builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0006 starttime: 1463644004.32 results: success (0) buildid: 20160518214151 builduid: 87bb5bad325047f9a40681931a6169f5 revision: aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:44.321833) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:44.322244) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:44.322525) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:44.344188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:44.344468) ========= 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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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-19 00:46:44-- 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% 170M=0s 2016-05-19 00:46:44 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.450692 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:44.812878) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:44.813201) ========= 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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.029314 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:44.861480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:44.861806) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 --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 aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nY3CKWrxzh/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.nqoAPmkWBq/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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-19 00:46:44,939 truncating revision to first 12 chars 2016-05-19 00:46:44,939 Setting DEBUG logging. 2016-05-19 00:46:44,939 attempt 1/10 2016-05-19 00:46:44,939 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/aa7ea6cc0792?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 00:46:45,658 unpacking tar archive at: fx-team-aa7ea6cc0792/testing/mozharness/ program finished with exit code 0 elapsedTime=0.965019 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:45.844086) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:45.844397) ========= script_repo_revision: aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:45.844776) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:45.845066) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 00:46:45.859182) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 41 secs) (at 2016-05-19 00:46:45.859557) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nY3CKWrxzh/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.nqoAPmkWBq/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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:46:46 INFO - MultiFileLogger online at 20160519 00:46:46 in /builds/slave/test 00:46:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 00:46:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:46:46 INFO - {'append_to_log': False, 00:46:46 INFO - 'base_work_dir': '/builds/slave/test', 00:46:46 INFO - 'blob_upload_branch': 'Fx-Team', 00:46:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:46:46 INFO - 'branch': 'Fx-Team', 00:46:46 INFO - 'buildbot_json_path': 'buildprops.json', 00:46:46 INFO - 'config_files': ('talos/mac_config.py',), 00:46:46 INFO - 'default_actions': ('clobber', 00:46:46 INFO - 'read-buildbot-config', 00:46:46 INFO - 'download-and-extract', 00:46:46 INFO - 'populate-webroot', 00:46:46 INFO - 'create-virtualenv', 00:46:46 INFO - 'install', 00:46:46 INFO - 'run-tests'), 00:46:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:46:46 INFO - 'download_minidump_stackwalk': True, 00:46:46 INFO - 'download_symbols': 'ondemand', 00:46:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:46:46 INFO - 'tooltool.py': '/tools/tooltool.py', 00:46:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:46:46 INFO - '/tools/misc-python/virtualenv.py')}, 00:46:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:46:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:46:46 INFO - 'installer_path': 'installer.exe', 00:46:46 INFO - 'log_level': 'info', 00:46:46 INFO - 'log_name': 'talos', 00:46:46 INFO - 'log_to_console': True, 00:46:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:46:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:46:46 INFO - 'opt_config_files': (), 00:46:46 INFO - 'pip_index': False, 00:46:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:46:46 INFO - 'cmd': ('bash', 00:46:46 INFO - '-c', 00:46:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:46:46 INFO - 'enabled': True, 00:46:46 INFO - 'halt_on_failure': False, 00:46:46 INFO - 'name': 'check_screen_resolution'},), 00:46:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:46:46 INFO - 'cmd': ('bash', 00:46:46 INFO - '-c', 00:46:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:46:46 INFO - 'enabled': True, 00:46:46 INFO - 'halt_on_failure': False, 00:46:46 INFO - 'name': 'check_screen_resolution'},), 00:46:46 INFO - 'run_cmd_checks_enabled': True, 00:46:46 INFO - 'sps_profile': False, 00:46:46 INFO - 'sps_profile_interval': 0, 00:46:46 INFO - 'suite': 'dromaeojs-e10s', 00:46:46 INFO - 'system_bits': '32', 00:46:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:46:46 INFO - 'title': 't-yosemite-r7-0006', 00:46:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:46:46 INFO - 'use_talos_json': True, 00:46:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:46:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:46:46 INFO - 'work_dir': 'build'} 00:46:46 INFO - ##### 00:46:46 INFO - ##### Running clobber step. 00:46:46 INFO - ##### 00:46:46 INFO - Running pre-action listener: _resource_record_pre_action 00:46:46 INFO - Running main action method: clobber 00:46:46 INFO - rmtree: /builds/slave/test/build 00:46:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:46:47 INFO - Running post-action listener: _resource_record_post_action 00:46:47 INFO - ##### 00:46:47 INFO - ##### Finished clobber step (success) 00:46:47 INFO - ##### 00:46:47 INFO - ##### 00:46:47 INFO - ##### Running read-buildbot-config step. 00:46:47 INFO - ##### 00:46:47 INFO - Running pre-action listener: _resource_record_pre_action 00:46:47 INFO - Running main action method: read_buildbot_config 00:46:47 INFO - Using buildbot properties: 00:46:47 INFO - { 00:46:47 INFO - "project": "", 00:46:47 INFO - "product": "firefox", 00:46:47 INFO - "script_repo_revision": "production", 00:46:47 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 00:46:47 INFO - "repository": "", 00:46:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 00:46:47 INFO - "buildid": "20160518214151", 00:46:47 INFO - "pgo_build": "False", 00:46:47 INFO - "basedir": "/builds/slave/test", 00:46:47 INFO - "buildnumber": 9, 00:46:47 INFO - "slavename": "t-yosemite-r7-0006", 00:46:47 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 00:46:47 INFO - "platform": "yosemite_r7", 00:46:47 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 00:46:47 INFO - "branch": "fx-team", 00:46:47 INFO - "repo_path": "integration/fx-team", 00:46:47 INFO - "revision": "aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2", 00:46:47 INFO - "stage_platform": "macosx64", 00:46:47 INFO - "builduid": "87bb5bad325047f9a40681931a6169f5", 00:46:47 INFO - "slavebuilddir": "test" 00:46:47 INFO - } 00:46:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 00:46:47 INFO - Running post-action listener: _resource_record_post_action 00:46:47 INFO - ##### 00:46:47 INFO - ##### Finished read-buildbot-config step (success) 00:46:47 INFO - ##### 00:46:47 INFO - ##### 00:46:47 INFO - ##### Running download-and-extract step. 00:46:47 INFO - ##### 00:46:47 INFO - Running pre-action listener: _resource_record_pre_action 00:46:47 INFO - Running main action method: download_and_extract 00:46:47 INFO - mkdir: /builds/slave/test/build/tests 00:46:47 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:46:47 INFO - https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 00:46:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 00:46:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 00:46:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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:46:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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:47:09 INFO - Downloaded 1199 bytes. 00:47:09 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 00:47:09 INFO - Using the following test package requirements: 00:47:09 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 00:47:09 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:47:09 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 00:47:09 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:47:09 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 00:47:09 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:47:09 INFO - u'jsshell-mac.zip'], 00:47:09 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:47:09 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 00:47:09 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 00:47:09 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:47:09 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 00:47:09 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:47:09 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 00:47:09 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:47:09 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 00:47:09 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:47:09 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 00:47:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:09 INFO - https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:47:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:47:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:47:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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:47:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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:47:15 INFO - Downloaded 18629060 bytes. 00:47:15 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:47:15 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:47:18 INFO - Return code: 0 00:47:18 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:47:18 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:47:18 INFO - https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:47:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:47:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:47:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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:47:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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:47:22 INFO - Downloaded 18629060 bytes. 00:47:22 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:47:22 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:47:26 INFO - Return code: 0 00:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:26 INFO - https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 00:47:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 00:47:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 00:47:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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:47:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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:47:27 INFO - Downloaded 10840194 bytes. 00:47:27 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:47:27 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:47:27 INFO - Return code: 0 00:47:27 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:47:27 INFO - https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 00:47:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:47:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:47:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 00:47:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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:47:33 INFO - Downloaded 110312343 bytes. 00:47:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:47:33 INFO - mkdir: /builds/slave/test/properties 00:47:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:47:33 INFO - Writing to file /builds/slave/test/properties/build_url 00:47:33 INFO - Contents: 00:47:33 INFO - build_url:https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:47:34 INFO - Running post-action listener: _resource_record_post_action 00:47:34 INFO - Running post-action listener: set_extra_try_arguments 00:47:34 INFO - ##### 00:47:34 INFO - ##### Finished download-and-extract step (success) 00:47:34 INFO - ##### 00:47:34 INFO - ##### 00:47:34 INFO - ##### Running populate-webroot step. 00:47:34 INFO - ##### 00:47:34 INFO - Running pre-action listener: _resource_record_pre_action 00:47:34 INFO - Running main action method: populate_webroot 00:47:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:47:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:47:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:47:34 INFO - '--tppagecycles', 00:47:34 INFO - '7'], 00:47:34 INFO - 'tests': ['tsvgm']}}, 00:47:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:47:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:47:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:47:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:47:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:47:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:47:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:34 INFO - 'talos_options': ['--disable-e10s'], 00:47:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:47:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:47:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:34 INFO - 'talos_options': ['--disable-e10s'], 00:47:34 INFO - 'tests': ['damp', 'tps']}, 00:47:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:34 INFO - 'tests': ['damp', 'tps']}, 00:47:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:47:34 INFO - 'tests': ['dromaeo_dom']}, 00:47:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:47:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:47:34 INFO - 'tests': ['a11yr', 00:47:34 INFO - 'ts_paint', 00:47:34 INFO - 'tpaint', 00:47:34 INFO - 'sessionrestore', 00:47:34 INFO - 'sessionrestore_no_auto_restore', 00:47:34 INFO - 'tabpaint']}, 00:47:34 INFO - 'other-e10s': {'tests': ['a11yr', 00:47:34 INFO - 'ts_paint', 00:47:34 INFO - 'tpaint', 00:47:34 INFO - 'sessionrestore', 00:47:34 INFO - 'sessionrestore_no_auto_restore', 00:47:34 INFO - 'tabpaint']}, 00:47:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:47:34 INFO - 'ts_paint', 00:47:34 INFO - 'tpaint', 00:47:34 INFO - 'sessionrestore', 00:47:34 INFO - 'sessionrestore_no_auto_restore', 00:47:34 INFO - 'tabpaint']}, 00:47:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:47:34 INFO - 'ts_paint', 00:47:34 INFO - 'tpaint', 00:47:34 INFO - 'sessionrestore', 00:47:34 INFO - 'sessionrestore_no_auto_restore', 00:47:34 INFO - 'tabpaint']}, 00:47:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:47:34 INFO - 'tests': ['a11yr', 00:47:34 INFO - 'ts_paint', 00:47:34 INFO - 'tpaint', 00:47:34 INFO - 'sessionrestore', 00:47:34 INFO - 'sessionrestore_no_auto_restore', 00:47:34 INFO - 'tabpaint']}, 00:47:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:47:34 INFO - 'tests': ['a11yr', 00:47:34 INFO - 'ts_paint', 00:47:34 INFO - 'tpaint', 00:47:34 INFO - 'sessionrestore', 00:47:34 INFO - 'sessionrestore_no_auto_restore', 00:47:34 INFO - 'tabpaint']}, 00:47:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:47:34 INFO - 'tests': ['tsvgx', 00:47:34 INFO - 'tsvgr_opacity', 00:47:34 INFO - 'tart', 00:47:34 INFO - 'tscrollx', 00:47:34 INFO - 'cart']}, 00:47:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:47:34 INFO - 'tsvgr_opacity', 00:47:34 INFO - 'tart', 00:47:34 INFO - 'tscrollx', 00:47:34 INFO - 'cart']}, 00:47:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:34 INFO - 'talos_options': ['--disable-e10s'], 00:47:34 INFO - 'tests': ['tp5o']}, 00:47:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:34 INFO - 'tests': ['tp5o']}, 00:47:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:34 INFO - 'talos_options': ['--disable-e10s', 00:47:34 INFO - '--xperf_path', 00:47:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:47:34 INFO - 'tests': ['tp5n']}, 00:47:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:34 INFO - 'talos_options': ['--xperf_path', 00:47:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:47:34 INFO - 'tests': ['tp5n']}}, 00:47:34 INFO - 'talos.zip': {'path': '', 00:47:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:47:34 INFO - Running post-action listener: _resource_record_post_action 00:47:34 INFO - ##### 00:47:34 INFO - ##### Finished populate-webroot step (success) 00:47:34 INFO - ##### 00:47:34 INFO - ##### 00:47:34 INFO - ##### Running create-virtualenv step. 00:47:34 INFO - ##### 00:47:34 INFO - Running pre-action listener: _resource_record_pre_action 00:47:34 INFO - Running main action method: create_virtualenv 00:47:34 INFO - Creating virtualenv /builds/slave/test/build/venv 00:47:34 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:47:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:47:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:47:34 INFO - Using real prefix '/tools/python27' 00:47:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:47:35 INFO - Installing distribute.............................................................................................................................................................................................done. 00:47:38 INFO - Installing pip.................done. 00:47:38 INFO - Return code: 0 00:47:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104541a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104547570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd891589bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043e5d30>, '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 0x104209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043dcb20>, '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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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:47:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 00:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:47:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nY3CKWrxzh/Render', 00:47:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:38 INFO - 'HOME': '/Users/cltbld', 00:47:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:38 INFO - 'LOGNAME': 'cltbld', 00:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:38 INFO - 'MOZ_NO_REMOTE': '1', 00:47:38 INFO - 'NO_EM_RESTART': '1', 00:47:38 INFO - 'PAGER': '/bin/cat', 00:47:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:38 INFO - 'PWD': '/builds/slave/test', 00:47:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:38 INFO - 'SHELL': '/bin/bash', 00:47:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqoAPmkWBq/Listeners', 00:47:38 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 00:47:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:38 INFO - 'USER': 'cltbld', 00:47:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:38 INFO - 'XPC_FLAGS': '0x0', 00:47:38 INFO - 'XPC_SERVICE_NAME': '0', 00:47:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:38 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:47:38 INFO - Cleaning up... 00:47:38 INFO - Return code: 0 00:47:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104541a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104547570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd891589bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043e5d30>, '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 0x104209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043dcb20>, '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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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:47:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 00:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 00:47:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nY3CKWrxzh/Render', 00:47:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:38 INFO - 'HOME': '/Users/cltbld', 00:47:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:38 INFO - 'LOGNAME': 'cltbld', 00:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:38 INFO - 'MOZ_NO_REMOTE': '1', 00:47:38 INFO - 'NO_EM_RESTART': '1', 00:47:38 INFO - 'PAGER': '/bin/cat', 00:47:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:38 INFO - 'PWD': '/builds/slave/test', 00:47:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:38 INFO - 'SHELL': '/bin/bash', 00:47:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqoAPmkWBq/Listeners', 00:47:38 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 00:47:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:38 INFO - 'USER': 'cltbld', 00:47:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:38 INFO - 'XPC_FLAGS': '0x0', 00:47:38 INFO - 'XPC_SERVICE_NAME': '0', 00:47:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:38 INFO - Downloading/unpacking psutil>=3.1.1 00:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:47:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:47:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:47:41 INFO - Installing collected packages: psutil 00:47:41 INFO - Running setup.py install for psutil 00:47:41 INFO - building 'psutil._psutil_osx' extension 00:47:41 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:47:42 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:47:42 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:47:42 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:47:42 INFO - building 'psutil._psutil_posix' extension 00:47:42 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:47:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:47:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:47:42 INFO - ^ 00:47:42 INFO - 1 warning generated. 00:47:42 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:47:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:47:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:47:43 INFO - Successfully installed psutil 00:47:43 INFO - Cleaning up... 00:47:43 INFO - Return code: 0 00:47:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104541a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104547570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd891589bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043e5d30>, '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 0x104209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043dcb20>, '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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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:47:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:47:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 00:47:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nY3CKWrxzh/Render', 00:47:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:43 INFO - 'HOME': '/Users/cltbld', 00:47:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:43 INFO - 'LOGNAME': 'cltbld', 00:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:43 INFO - 'MOZ_NO_REMOTE': '1', 00:47:43 INFO - 'NO_EM_RESTART': '1', 00:47:43 INFO - 'PAGER': '/bin/cat', 00:47:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:43 INFO - 'PWD': '/builds/slave/test', 00:47:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:43 INFO - 'SHELL': '/bin/bash', 00:47:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqoAPmkWBq/Listeners', 00:47:43 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 00:47:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:43 INFO - 'USER': 'cltbld', 00:47:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:43 INFO - 'XPC_FLAGS': '0x0', 00:47:43 INFO - 'XPC_SERVICE_NAME': '0', 00:47:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:47:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:47:45 INFO - Installing collected packages: mozsystemmonitor 00:47:45 INFO - Running setup.py install for mozsystemmonitor 00:47:46 INFO - Successfully installed mozsystemmonitor 00:47:46 INFO - Cleaning up... 00:47:46 INFO - Return code: 0 00:47:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104541a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104547570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd891589bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043e5d30>, '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 0x104209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043dcb20>, '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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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:47:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:47:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nY3CKWrxzh/Render', 00:47:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:46 INFO - 'HOME': '/Users/cltbld', 00:47:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:46 INFO - 'LOGNAME': 'cltbld', 00:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:46 INFO - 'MOZ_NO_REMOTE': '1', 00:47:46 INFO - 'NO_EM_RESTART': '1', 00:47:46 INFO - 'PAGER': '/bin/cat', 00:47:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:46 INFO - 'PWD': '/builds/slave/test', 00:47:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:46 INFO - 'SHELL': '/bin/bash', 00:47:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqoAPmkWBq/Listeners', 00:47:46 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 00:47:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:46 INFO - 'USER': 'cltbld', 00:47:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:46 INFO - 'XPC_FLAGS': '0x0', 00:47:46 INFO - 'XPC_SERVICE_NAME': '0', 00:47:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:46 INFO - Downloading/unpacking blobuploader==1.2.4 00:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:48 INFO - Downloading blobuploader-1.2.4.tar.gz 00:47:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:47:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:47:48 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:47:48 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:47:48 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:47:48 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:47:48 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:47:48 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:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:47:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:49 INFO - Downloading docopt-0.6.1.tar.gz 00:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:47:50 INFO - Installing collected packages: blobuploader, requests, docopt 00:47:50 INFO - Running setup.py install for blobuploader 00:47:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:47:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:47:50 INFO - Running setup.py install for requests 00:47:50 INFO - Running setup.py install for docopt 00:47:50 INFO - Successfully installed blobuploader requests docopt 00:47:50 INFO - Cleaning up... 00:47:50 INFO - Return code: 0 00:47:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:51 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 0x104541a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104547570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd891589bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043e5d30>, '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 0x104209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043dcb20>, '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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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:47:51 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:47:51 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:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nY3CKWrxzh/Render', 00:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:51 INFO - 'HOME': '/Users/cltbld', 00:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:51 INFO - 'LOGNAME': 'cltbld', 00:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:51 INFO - 'MOZ_NO_REMOTE': '1', 00:47:51 INFO - 'NO_EM_RESTART': '1', 00:47:51 INFO - 'PAGER': '/bin/cat', 00:47:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:51 INFO - 'PWD': '/builds/slave/test', 00:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:51 INFO - 'SHELL': '/bin/bash', 00:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqoAPmkWBq/Listeners', 00:47:51 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 00:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:51 INFO - 'USER': 'cltbld', 00:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:51 INFO - 'XPC_FLAGS': '0x0', 00:47:51 INFO - 'XPC_SERVICE_NAME': '0', 00:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:47:51 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-c47XVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:47:51 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-hWibPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:47:51 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-VMEbTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:47:51 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-GKT3fY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:47:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-VW0CP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:47:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-bErF_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:47:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-emOEta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:47:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-LzewAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:47:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-t5Gsg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:47:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-e6g_mE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:47:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-dsROxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:47:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-f_PtKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:47:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-0qhdE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:47:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-gh2esW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:47:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Vks8t4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:47:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-bDHyzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:47:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-M3PUjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:47:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:47:53 INFO - Running setup.py install for manifestparser 00:47:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:47:53 INFO - Running setup.py install for mozcrash 00:47:53 INFO - Running setup.py install for mozdebug 00:47:53 INFO - Running setup.py install for mozdevice 00:47:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:47:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:47:54 INFO - Running setup.py install for mozfile 00:47:54 INFO - Running setup.py install for mozhttpd 00:47:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:47:54 INFO - Running setup.py install for mozinfo 00:47:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:47:54 INFO - Running setup.py install for mozInstall 00:47:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:47:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:47:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:47:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:47:54 INFO - Running setup.py install for mozleak 00:47:54 INFO - Running setup.py install for mozlog 00:47:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:47:54 INFO - Running setup.py install for moznetwork 00:47:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:47:55 INFO - Running setup.py install for mozprocess 00:47:55 INFO - Running setup.py install for mozprofile 00:47:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:47:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:47:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:47:55 INFO - Running setup.py install for mozrunner 00:47:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:47:55 INFO - Running setup.py install for mozscreenshot 00:47:55 INFO - Running setup.py install for moztest 00:47:55 INFO - Running setup.py install for mozversion 00:47:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:47:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:47:55 INFO - Cleaning up... 00:47:55 INFO - Return code: 0 00:47:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:47:55 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:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:55 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:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:55 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 0x104541a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104547570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd891589bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043e5d30>, '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 0x104209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043dcb20>, '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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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:47:55 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:47:55 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:47:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nY3CKWrxzh/Render', 00:47:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:55 INFO - 'HOME': '/Users/cltbld', 00:47:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:55 INFO - 'LOGNAME': 'cltbld', 00:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:55 INFO - 'MOZ_NO_REMOTE': '1', 00:47:55 INFO - 'NO_EM_RESTART': '1', 00:47:55 INFO - 'PAGER': '/bin/cat', 00:47:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:55 INFO - 'PWD': '/builds/slave/test', 00:47:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:55 INFO - 'SHELL': '/bin/bash', 00:47:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqoAPmkWBq/Listeners', 00:47:55 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 00:47:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:55 INFO - 'USER': 'cltbld', 00:47:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:55 INFO - 'XPC_FLAGS': '0x0', 00:47:55 INFO - 'XPC_SERVICE_NAME': '0', 00:47:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:47:56 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-OnrNnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:47:56 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-YEsPtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:47:56 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-_MyzMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:47:56 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-CHrs5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:47:56 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-mRLkf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:47:56 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-hrY8Au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:47:56 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-B2ucvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:47:56 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ByREF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:47:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-FIf9sO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:47:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-TPa5CL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:47:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:47:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-GUhxFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:47:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-pyWS2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:47:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-YYXFLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:47:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-sNxgsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:47:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-nB5Bcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:47:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-TV592x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:47:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-DQR32h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:47:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:48:00 INFO - Downloading blessings-1.6.tar.gz 00:48:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:48:01 INFO - Installing collected packages: blessings 00:48:01 INFO - Running setup.py install for blessings 00:48:01 INFO - Successfully installed blessings 00:48:01 INFO - Cleaning up... 00:48:01 INFO - Return code: 0 00:48:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:48:01 INFO - Reading from file tmpfile_stdout 00:48:01 INFO - Current package versions: 00:48:01 INFO - blessings == 1.6 00:48:01 INFO - blobuploader == 1.2.4 00:48:01 INFO - docopt == 0.6.1 00:48:01 INFO - manifestparser == 1.1 00:48:01 INFO - mozInstall == 1.12 00:48:01 INFO - mozcrash == 0.17 00:48:01 INFO - mozdebug == 0.1 00:48:01 INFO - mozdevice == 0.48 00:48:01 INFO - mozfile == 1.2 00:48:01 INFO - mozhttpd == 0.7 00:48:01 INFO - mozinfo == 0.9 00:48:01 INFO - mozleak == 0.1 00:48:01 INFO - mozlog == 3.2 00:48:01 INFO - moznetwork == 0.27 00:48:01 INFO - mozprocess == 0.22 00:48:01 INFO - mozprofile == 0.28 00:48:01 INFO - mozrunner == 6.11 00:48:01 INFO - mozscreenshot == 0.1 00:48:01 INFO - mozsystemmonitor == 0.3 00:48:01 INFO - moztest == 0.7 00:48:01 INFO - mozversion == 1.4 00:48:01 INFO - psutil == 3.1.1 00:48:01 INFO - requests == 1.2.3 00:48:01 INFO - wsgiref == 0.1.2 00:48:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:48: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:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:48: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:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:48:01 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 0x104541a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104547570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd891589bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043e5d30>, '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 0x104209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043dcb20>, '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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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:48:01 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:48:01 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:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nY3CKWrxzh/Render', 00:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:48:01 INFO - 'HOME': '/Users/cltbld', 00:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:48:01 INFO - 'LOGNAME': 'cltbld', 00:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:48:01 INFO - 'MOZ_NO_REMOTE': '1', 00:48:01 INFO - 'NO_EM_RESTART': '1', 00:48:01 INFO - 'PAGER': '/bin/cat', 00:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:48:01 INFO - 'PWD': '/builds/slave/test', 00:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:48:01 INFO - 'SHELL': '/bin/bash', 00:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqoAPmkWBq/Listeners', 00:48:01 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 00:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:48:01 INFO - 'USER': 'cltbld', 00:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:48:01 INFO - 'XPC_FLAGS': '0x0', 00:48:01 INFO - 'XPC_SERVICE_NAME': '0', 00:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 INFO - Cleaning up... 00:48:01 INFO - Return code: 0 00:48:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:48: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:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:48: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:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:48:01 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 0x104541a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104547570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd891589bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043e5d30>, '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 0x104209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043dcb20>, '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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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:48:01 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:48:01 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:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nY3CKWrxzh/Render', 00:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:48:01 INFO - 'HOME': '/Users/cltbld', 00:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:48:01 INFO - 'LOGNAME': 'cltbld', 00:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:48:01 INFO - 'MOZ_NO_REMOTE': '1', 00:48:01 INFO - 'NO_EM_RESTART': '1', 00:48:01 INFO - 'PAGER': '/bin/cat', 00:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:48:01 INFO - 'PWD': '/builds/slave/test', 00:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:48:01 INFO - 'SHELL': '/bin/bash', 00:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqoAPmkWBq/Listeners', 00:48:01 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 00:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:48:01 INFO - 'USER': 'cltbld', 00:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:48:01 INFO - 'XPC_FLAGS': '0x0', 00:48:01 INFO - 'XPC_SERVICE_NAME': '0', 00:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:48:02 INFO - Downloading/unpacking jsonschema 00:48:02 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:48:02 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:48:02 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:48:02 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:48:02 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:48:02 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:48:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:48:04 INFO - Downloading/unpacking functools32 (from jsonschema) 00:48:04 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:48:04 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:48:04 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:48:04 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:48:04 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:48:04 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:48:04 INFO - Downloading functools32-3.2.3-2.tar.gz 00:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:48:04 INFO - warning: no files found matching '*.txt' 00:48:04 INFO - no previously-included directories found matching 'build' 00:48:04 INFO - no previously-included directories found matching 'dist' 00:48:04 INFO - no previously-included directories found matching '.git*' 00:48:05 INFO - Installing collected packages: jsonschema, functools32 00:48:05 INFO - Running setup.py install for functools32 00:48:05 INFO - warning: no files found matching '*.txt' 00:48:05 INFO - no previously-included directories found matching 'build' 00:48:05 INFO - no previously-included directories found matching 'dist' 00:48:05 INFO - no previously-included directories found matching '.git*' 00:48:05 INFO - Successfully installed jsonschema functools32 00:48:05 INFO - Cleaning up... 00:48:05 INFO - Return code: 0 00:48:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 00:48:05 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:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:48:05 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:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:48:05 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 0x104541a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104547570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd891589bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043e5d30>, '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 0x104209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043dcb20>, '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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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:48:05 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:48:05 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:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nY3CKWrxzh/Render', 00:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:48:05 INFO - 'HOME': '/Users/cltbld', 00:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:48:05 INFO - 'LOGNAME': 'cltbld', 00:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:48:05 INFO - 'MOZ_NO_REMOTE': '1', 00:48:05 INFO - 'NO_EM_RESTART': '1', 00:48:05 INFO - 'PAGER': '/bin/cat', 00:48:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:48:05 INFO - 'PWD': '/builds/slave/test', 00:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:48:05 INFO - 'SHELL': '/bin/bash', 00:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqoAPmkWBq/Listeners', 00:48:05 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 00:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:48:05 INFO - 'USER': 'cltbld', 00:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:48:05 INFO - 'XPC_FLAGS': '0x0', 00:48:05 INFO - 'XPC_SERVICE_NAME': '0', 00:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:48:05 INFO - Downloading/unpacking flake8 00:48:05 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:48:05 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:48:05 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:48:05 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:48:05 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:48:05 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:48:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 00:48:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 00:48:07 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:48:07 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:48:07 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:48:07 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:48:07 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:48:07 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:48:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 00:48:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 00:48: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:48: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:48: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:48: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:48: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:48: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:48:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 00:48: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:48: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:48: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:48: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:48: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:48: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:48:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 00:48:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:48:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:48:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:48:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:48:09 INFO - no previously-included directories found matching 'docs/_build' 00:48:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 00:48:09 INFO - Running setup.py install for pep8 00:48:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:48:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:48:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:48:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:48:09 INFO - no previously-included directories found matching 'docs/_build' 00:48:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 00:48:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 00:48:09 INFO - Cleaning up... 00:48:09 INFO - Return code: 0 00:48:09 INFO - Running post-action listener: _resource_record_post_action 00:48:09 INFO - Running post-action listener: _start_resource_monitoring 00:48:09 INFO - Starting resource monitoring. 00:48:09 INFO - ##### 00:48:09 INFO - ##### Finished create-virtualenv step (success) 00:48:09 INFO - ##### 00:48:09 INFO - ##### 00:48:09 INFO - ##### Running install step. 00:48:09 INFO - ##### 00:48:09 INFO - Running pre-action listener: _resource_record_pre_action 00:48:09 INFO - Running main action method: install 00:48:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:48:10 INFO - Reading from file tmpfile_stdout 00:48:10 INFO - Detecting whether we're running mozinstall >=1.0... 00:48:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:48:10 INFO - Reading from file tmpfile_stdout 00:48:10 INFO - Output received: 00:48:10 INFO - Usage: mozinstall [options] installer 00:48:10 INFO - Options: 00:48:10 INFO - -h, --help show this help message and exit 00:48:10 INFO - -d DEST, --destination=DEST 00:48:10 INFO - Directory to install application into. [default: 00:48:10 INFO - "/builds/slave/test"] 00:48:10 INFO - --app=APP Application being installed. [default: firefox] 00:48:10 INFO - mkdir: /builds/slave/test/build/application 00:48:10 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:48:10 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:48:43 INFO - Reading from file tmpfile_stdout 00:48:43 INFO - Output received: 00:48:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:48:43 INFO - Running post-action listener: _resource_record_post_action 00:48:43 INFO - ##### 00:48:43 INFO - ##### Finished install step (success) 00:48:43 INFO - ##### 00:48:43 INFO - ##### 00:48:43 INFO - ##### Running run-tests step. 00:48:43 INFO - ##### 00:48:43 INFO - Running pre-action listener: _resource_record_pre_action 00:48:43 INFO - Running main action method: run_tests 00:48:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:48:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:48:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:48:43 INFO - 2016-05-19 00:48:43.798 screenresolution[1654:9803] starting screenresolution argv=screenresolution get 00:48:43 INFO - 2016-05-19 00:48:43.800 screenresolution[1654:9803] Display 0: 1600x1200x32@60 00:48:43 INFO - 2016-05-19 00:48:43.806 screenresolution[1655:9805] starting screenresolution argv=screenresolution list 00:48:43 INFO - Available Modes on Display 0 00:48:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:48:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:48:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:48:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:48:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:48:44 INFO - Intel Iris: 00:48:44 INFO - Chipset Model: Intel Iris 00:48:44 INFO - Type: GPU 00:48:44 INFO - Bus: Built-In 00:48:44 INFO - VRAM (Dynamic, Max): 1536 MB 00:48:44 INFO - Vendor: Intel (0x8086) 00:48:44 INFO - Device ID: 0x0a2e 00:48:44 INFO - Revision ID: 0x0009 00:48:44 INFO - Displays: 00:48:44 INFO - Display: 00:48:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:48:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:48:44 INFO - Display Serial Number: 1600x1200 60 00:48:44 INFO - Main Display: Yes 00:48:44 INFO - Mirror: Off 00:48:44 INFO - Online: Yes 00:48:44 INFO - Rotation: Supported 00:48:44 INFO - Return code: 0 00:48:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:48:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:48:44 INFO - Python 2.7.3 00:48:44 INFO - Return code: 0 00:48:44 INFO - grabbing minidump binary from tooltool 00:48:44 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:48:44 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043e5d30>, '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 0x104209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043dcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:48:44 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:48:44 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:48:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 00:48:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:48:44 INFO - Return code: 0 00:48:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:48:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:48:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:48:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:48:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:48:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 00:48:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 00:48:45 INFO - Return code: 0 00:48:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 00:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 00:48:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nY3CKWrxzh/Render', 00:48:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:48:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:48:45 INFO - 'HOME': '/Users/cltbld', 00:48:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:48:45 INFO - 'LOGNAME': 'cltbld', 00:48:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:48:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:48:45 INFO - 'MOZ_NO_REMOTE': '1', 00:48:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:48:45 INFO - 'NO_EM_RESTART': '1', 00:48:45 INFO - 'PAGER': '/bin/cat', 00:48:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:48:45 INFO - 'PWD': '/builds/slave/test', 00:48:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:48:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:48:45 INFO - 'SHELL': '/bin/bash', 00:48:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqoAPmkWBq/Listeners', 00:48:45 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 00:48:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:48:45 INFO - 'USER': 'cltbld', 00:48:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:48:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:48:45 INFO - 'XPC_FLAGS': '0x0', 00:48:45 INFO - 'XPC_SERVICE_NAME': '0', 00:48:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:48:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 00:48:45 INFO - mozversion application_buildid: 20160518214151 00:48:45 INFO - mozversion application_changeset: aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 00:48:45 INFO - mozversion application_display_name: Nightly 00:48:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:48:45 INFO - mozversion application_name: Firefox 00:48:45 INFO - mozversion application_remotingname: firefox 00:48:45 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 00:48:45 INFO - mozversion application_vendor: Mozilla 00:48:45 INFO - mozversion application_version: 49.0a1 00:48:45 INFO - mozversion platform_buildid: 20160518214151 00:48:45 INFO - mozversion platform_changeset: aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 00:48:45 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 00:48:45 INFO - mozversion platform_version: 49.0a1 00:48:45 INFO - using testdate: 1463644125 00:48:45 INFO - actual date: 1463644125 00:48:45 INFO - starting webserver on 'localhost:49273' 00:48:45 INFO - SUITE-START | Running 2 tests 00:48:45 INFO - TEST-START | dromaeo_css 00:48:45 INFO - operating with platform_type : mac_ 00:48:45 INFO - Initialising browser for dromaeo_css test... 00:48:45 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpcvXTd9/profile http://localhost:49273/getInfo.html) 00:48:48 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 00:48:48 INFO - PROCESS | 1673 | colorDepth:24 00:48:48 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 00:48:48 INFO - PROCESS | 1673 | __metrics 00:48:48 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 00:48:49 INFO - TEST-INFO | 1673: exit 0 00:48:49 INFO - Browser initialized. 00:48:49 INFO - Running cycle 1/1 for dromaeo_css test... 00:48:49 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpcvXTd9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:49:01 INFO - PROCESS | 1677 | RSS: Main: 249741312 00:49:01 INFO - PROCESS | 1677 | 00:49:50 INFO - PROCESS | 1677 | 1463644190698 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:52:02 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 00:52:03 INFO - PROCESS | 1677 | RSS: Main: 261672960 00:52:03 INFO - PROCESS | 1677 | 00:55:05 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 00:55:06 INFO - PROCESS | 1677 | RSS: Main: 273649664 00:55:06 INFO - PROCESS | 1677 | 00:58:08 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 00:58:08 INFO - PROCESS | 1677 | RSS: Main: 275562496 00:58:08 INFO - PROCESS | 1677 | 01:01:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 01:01:11 INFO - PROCESS | 1677 | RSS: Main: 277114880 01:01:11 INFO - PROCESS | 1677 | 01:04:14 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 01:04:14 INFO - PROCESS | 1677 | RSS: Main: 279982080 01:04:14 INFO - PROCESS | 1677 | 01:07:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 01:07:17 INFO - PROCESS | 1677 | RSS: Main: 281157632 01:07:17 INFO - PROCESS | 1677 | 01:07:17 INFO - PROCESS | 1677 | __start_tp_report 01:07:17 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 01:07:17 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 01:07:17 INFO - PROCESS | 1677 | |i|pagename|runs| 01:07:17 INFO - PROCESS | 1677 | |0;dojo.html;3491.668291512306;3497.038314463522;3520.436730123181;3531.752777305588;3547.237343971046;4571.3600095986585;5733.9713301433485;5765.625234359763;5849.385814489476;5850.561207909403;5524.502794748471;5736.856578585537;5811.389804070575;5843.831233753256;5856.560757943161;5141.537261646451;5161.509656582637;5245.79016839326;5259.500347467004;5300.01949639316;5292.073887069748;5304.336957880265;5330.973345133269;5332.813351532697;5364.275822763916;3905.863294784683;4372.8032238549185;5200.427952925175;5229.111051121318;5236.73816309183;5262.079136151158;5264.684118952875;5291.86770330741;5293.9735301323635;5294.311739473882;4527.750973696446;5261.394939581921;5261.736913154327;5301.443348448396;5368.731563421813;5008.899822003539;5487.122060470346;5638.35158956717;5725.198472213893;5768.625039372428;6180.165677633507;6258.78094266703;6278.434940855299;6286.968565157191;6305.117283580303;6182.721777520022;6267.341929097471;6315.336889626569;6332.176817013805;6333.8416539586415;5102.260172275037;5888.911666325009;5933.19901813254;5962.463378295887;5965.224520812267;6347.079673447324;6373.649449280241;6405.967970160213;6431.067495213168;6459.903101453482;6138.202033735675;6151.938480615226;6270.9372906270355;6285.685715714196;6287;6190.659513726699;6218.347073557211;6303.275123360872;6311.96844015777;6325.588836725598;6186.4741496972365;6218.067289906459;6281.623102613858;6297.748090076438;6317.273513545909;6166.907496387557;6167.044108163242;6217.937820621826;6315.305316415191;6325;4479.372887795711;5038.143515602356;5046.495350464924;5063.240513922868;5109.540141387219;4003.4795476587856;4523.457185137739;4565.246734288871;4568.634509239253;4590.265557510782;5825.7087145642545;5829.213056237439;5835.737391817378;5946.67293298864;5997.55018373615;5529.889402211933;5772.365039845614;6248.875022499525;6269.561130720897;6281.246250449975;4873.878153046202;5872.471477566954;5880.117982302603;5894.0274854648505;5909.881802364014;5403.297571315782;5431;5474.479924407175;5492.313460817398;5523.143912693539;5442.292501974718;6482.611043337321;6498.805035848837;6516.2506311773805;6553.148090748172;6253.343398943045;6284.811455656338;6291.213598300213;6292.465140463112;6305.936940630535;6282.340354262919;6382.6170429774365;6392.744290228339;6458.22501299847;6519.152510173647;6241.78153764616;6286.52851036165;6293.087502312231;6294.685265736786;6331.461825744679;5371.865703357291;6188.257409110935;6195.659238741912;6263.530235232284;6305.337939516285;6138.907916381168;6145.095020543646;6146.754129834757;6172.660503672161;6207.565470417208;6199.690015499117;6225.439710426083;6270.9372906270355;6293.874122517433;6305.211848518935;4959.9832034433575;5699.686517241592;6083;6108.08378743192;6166.321704612399;6113.628337950651;6178.62928224308;6200.720967556381;6227.782027629011;6245.500359971101;6153.969230153821;6172.22847144107;6204.875902482016;6206.503479721521;6228.22147231596 01:07:17 INFO - PROCESS | 1677 | |1;ext.html;494.6487993524596;500.04990518015774;518.9117849965505;519.0993626058787;540.8945255675145;32090.518642220355;42274.15451690972;42470.36294455582;42494.36258456122;42500;42628.36057459133;43302.61835599041;43335.56664433355;43344.66553344664;43431.78284108575;9563.999999999982;9700.563474643657;9805.31362804604;9808.901910980905;9810.754731131743;9606.90393096067;9672.129508344247;9744.951275243613;9745.976672449397;9751.75620609487;9692.418454892693;9730.708078757649;9779.70660880171;9796.804063918717;9811.313208075437;9709.61161553537;9719.902800972006;9750.024997500264;9753.170980466668;9764.121229089416;8409.579521023925;8856.202941735275;8869.11308869108;9649.951750241275;9742.805143897152;36799.4480082799;40139;40197.39703904444;40220.597794021975;40251.7987410064;3161.573187619665;3252.4796032634663;3395.8471868765964;3500.352434799545;3558.217192217734;27852.442951141067;29918.07353496792;30059.398812023857;30531.542026869392;30611.846940765154;2543.097200493835;2636.380450594109;2658.6809582850183;2674.224474902276;2675.1974407677812;484469;516676;526585.3670731622;529580;529596;63660;63825.36174638287;64549.354506454336;64561.677191613744;64595.67702161459;35967.10082247912;37018.62981370206;37060.44409333862;68725.65637171883;69274;16547;16575.91712041432;16695.49913502597;16754.99470031802;16759.162041897856;12609.93695031519;12693.873061269454;12699.746005079847;12703.872961270268;12730.490780368867;15859.920700396424;15912;15937.442189523312;15954.601134971486;15956.601084972968;7553.811154721176;7715.148364392506;7845.921540784633;8160.591970401575;8179.427440079137;852.86780549015;1049.333673117576;1052.0689190066846;1076.8223243164946;1107.341132026443;28632.713672863003;30103.548446773315;30153.944611938477;30575.624096915693;30946.690533094832;1074.2856000759407;1134.5178299222798;1137.8805225451374;1161.976760464803;1217.8843009914044;15589.674877635302;15745.921270393575;16010.679786404207;16106.838931610533;16323.836761632469;696303;712820;714221;714569;716201;60130.69934650299;60763;60846.0873086904;60954;60971.08543371853;1429.6068581140062;1441.0633088492355;1479.7484427647325;1494.559105063987;1496.745553255949;29253;29613.555796662637;29679.703202967696;29902.401951960404;30553;52859;53526.1971070442;53748.46251537591;53761.46238537564;54493;593684;594661;594742;599514;600617;26968.460630787667;27122.72877271202;27199.04803331874;27606;27645.447091058468;39973;47086.119695147354;47144.29283560818;47272;48387.5161248383;30208.395832083675;36881;38138;38157.61842381541;38234.42648360221;37610.8119459401;37884.86345409642;38319.42520862134;38568.035799104124;38726.6127338723;37709.434358485196;38070;38854.640087596796;39158.80420597879;39416.2116757669;31889;34009.829950851075;34478.31043379169;34600;34678.82660586681;20066.799332006492;24915.501689965735;25345.49309013846;25547.87226063932;27117.50853703065 01:07:17 INFO - PROCESS | 1677 | |2;jquery.html;317.48725807820375;388.25842640556533;390.2487711156024;391.61817228202506;418.91621675664857;15866.841331586695;20396;20860.165593376245;21382.25162119326;21388.89305553474;11874.584389546368;11998.94000529996;12139.453724582392;12482.750344993095;12519.186252893556;5093.363329583802;5445.264889239961;5645.745941432645;5653.434656534355;5665.461781130786;3945.743526670757;5898.911516327237;6178.258608966908;6196.721147548348;6239.937600624004;5974.312954010279;6222.844428889269;6229.159063526411;6230;6230.221222347207;6173.351798061229;6206.37936206378;6207.131001659771;6217.942949698562;6228.813135605939;5693.914591281134;6039.78860739872;6055.5761096723245;6057;6080.087986801971;13533;13985.58043258704;13989.720205595933;14076.296185190802;14162.433502659882;2040.6734922412343;2070.5030792609814;2093.3196711068963;2096.758872729625;2123.1401282480465;10038.648647297383;10109.898901010969;10140.03669651382;10416.218783591185;10586.20603454744;1543.3440787665309;1709.7948246210535;1752.815954324803;1804.7744031996;1930.8165724256314;93199;125835;126969;127018.36490817579;128202.35898820446;11829;11900.583479578174;11936.343501107522;12020.338881361435;12166.57416990401;15260.084394936339;15269.923650381677;15376.923115384576;15380.15409152485;15396.999195052285;7424.48028638001;8499.640057590756;8638.222559969632;8692.913070869337;8795.428297160664;8500.44747091437;8512.40413171072;8513.616887240089;8569.871451928226;8611.913880861237;3833;3945.763254204757;4293.012607100384;4707.388039554836;4768.403949506312;1873.7657792775904;1998.2007197121268;2003.6994450832492;2003.9699404508945;2044.3355909329528;822.5270469480073;937.2876613773581;944.2493217891792;995.1640621877658;1023.150784848574;19635.018249087487;19784.802151978583;20646.793532064486;20761.273355432488;20864.582708345748;793.4525177627419;938.9718308450757;962.1340793285958;973.8782652168479;1001.0590045357341;8361;10850.240483166102;10986.890131098588;10993.340399576051;11036.834447483294;547642;553673;557316;630336;635518;119287;126705.36647316704;130087;131164.3441782785;134133.65866341407;1015.746063484129;1036.8081904847627;1067.0556557446716;1072.753266748652;1072.9678109656722;18185.909070454563;18470.538236544195;18604.44186674402;18672.719909201645;18836.434906952814;52640.7367963173;53630.46369536411;53651.19523207233;53672;53688;73856.63071684608;76135;79706.40587188108;87527.56236218777;90806;5904.143899134688;9289.953550232205;10983.121350291798;11646.825297620373;12156.817647735113;13060.281684507443;13241.073124881166;13349.532766353132;13461.663458413226;13521.323933803074;10062.893081760854;10952.835707464554;11361.715957100809;11531.88468115308;11617.60588729358;10259.922708115839;10496;11289.153313501356;11342.943285283522;11592.246503977214;10521.894781052397;11809.05527557776;11809.232399894323;11901.464434100313;11943.581974630846;12077.758444830879;12095.274283543016;12266.632001039983;12326.383680816103;12334.444949977453;11531;11535.826962595736;11626.476808543466;11832.34922079294;11886.524539018688 01:07:17 INFO - PROCESS | 1677 | |3;mootools.html;1611.7098922194004;1650.711125553028;1919.1843466526723;1974.160981582827;2017.6065667194903;7958.840823183533;8373.455725377846;8893.866592001117;8937.55312234389;9399.577019034143;9068.909310906889;9171.082891710825;9209.12513311236;9241.676541321056;9273.582688779004;2487.868020050877;2509.0089414681283;2518.0179729905353;2528.88620012099;2535.404180017694;2134.4343748906554;2318.258157389636;2338.9064437422485;2469.1851688942697;2537.061287323693;2497.3506888209104;2513.40934880303;2530.6457096006575;2537.3783423061377;2542.83471574348;2499.5875680512786;2503.2740505253455;2512.849229046254;2520.1053625962886;2521;2458.2502336787275;2459.963100553493;2472.097684345219;2503.161440917295;2505.08564374004;9102.408343457653;9120.635174593008;9251.953740231256;9260.490673012982;9292;5509.972450137724;5663.716814159276;5667.603267771257;5681.204631351573;5693.77224911003;3612.8554857805657;3709.0727318170457;3741.981290093532;3788.04532464678;3789.2610940866543;1030.1141018723893;1047.3454091193005;1054.3357684658615;1062.6493257225097;1085.159001773624;12228.694282643006;12357.567485138066;12396.81404778929;12407.945324647326;12426.005919526417;4969.552740253394;4989.052080104769;5046.949530504648;5113.309703190096;5128.8717782055;10324.535395907202;10380.948095259626;10420.218483613759;10465.47672616366;10466.895331046744;5588.524975377057;5595.832125036255;5669.007923613433;5674.716264186856;5680.943190568124;4594.563516465931;4967.006598680278;5082.440931497532;5172.560332371715;5177.58579313654;4157.584241575879;4162.83348666056;4203.579642035772;4234.618884300427;4310.181065597527;1378.600205940266;1400.8949328800381;1402.9929850350684;1406.4796025470641;1410.3230449384353;762.5424745152864;939.2532936315646;945.5650400815564;950.5959967013991;951.2009911674227;6074.240719910012;6170.475509581735;6192.938070619326;6201.782937597252;6214.471769899519;464.6422254863736;473.5051870795027;480.1860845866293;484.4670862051715;489.5593965431069;4051.7974101294817;4076.7961601919787;4133.359329303963;4175.603317684815;4177.624013838769;10891.83662245067;11059;11282.379469129113;11288.435578221057;11291.774164516828;11100.222984391177;11104.944475277573;11233.045191158679;11235.382053987109;11244.325340479598;8121.634526446206;8498.277646400125;8505;8536.615852286537;8600.39797214189;6095.177151084724;6125.785597504244;6164.753409863734;6252.843678908063;6273.686315684106;10036.247281454067;10205.387676739123;10410.323328983592;10474.68575942723;10494.580216791242;10235.437050962268;10588.152947764007;10596.417197053928;10607.787844243227;10683.679489615324;6870.141232345957;7196.532225405332;7625.275598818103;7793.181715919747;8015.799605009688;8764.91235087641;8782.121787821166;8861;8871.689490868046;8923.598438070432;6598.1422415085735;7575.924240757522;7712.614369281401;7824.491408058458;7840.921590784019;7612.543247404952;7667.693292268247;7710.498817576839;7740.26467485588;7783.805404864924;7210.459215558749;7258.201597824341;7269.491135620456;7288.854222915618;7296.817579560554;4874.341963834979;5015.3480047593575;5081.822136225214;5110.565601923878;5121.923171152361;4401.625861801783;5049.419316778617;5092.668976516515;5111.591072714248;5358.437364076716 01:07:17 INFO - PROCESS | 1677 | |4;prototype.html;286.4771791480548;326.0902083187904;328.44328862577925;329.10319379690344;332.97003269705755;4749.643776716747;4999.575121090481;5034.27003084553;5696.174054762057;5776.624519406236;4510.413646225994;5206.8698282542955;5404.108801791042;5553.278073850393;5623.550115990721;3678.5769636491746;4056.1747213753856;4230.788460576974;4244.893877653052;4257.40396344512;3568.750187486877;4230.830766769341;4250.192463431938;4275.631797824697;4302.268614335555;3497.52783374245;3551.4140166872435;4239.7668128252935;4243.299855523835;4279.379489973952;3870.7677539347446;3891.9805400973096;4181.540030596647;4185.769782661952;4242.618164365222;3196.9769673704423;3399.8130102844334;3591.1919818040733;3596.6762991330907;3618.6562276583945;5348.876735885468;5416.214648875891;5429.511343979061;5458.2904222451225;5553.861153471155;4232.603240930516;4268.396278270253;4510.661700372485;4673.766311684402;4875.268709693574;5157.690538567698;5215.608829337815;5583.441655834425;5951.4291425145;5981.910271345933;3548.183917698944;3574.803385813779;4001.599840015975;4032.2258206389097;4054.175620970575;5456.945430545684;6209.59637623553;6216.502679785652;6290.308066112724;6329.303776584572;3547.237343971007;3978.264021156095;4065.4918135233097;4074.595988366878;4077.6330130288416;3979.2837289288204;4120.896977575585;4133.834646614102;4155.0027993282;4155.836365817576;4617.229662202719;4806.927896081562;4856.611471082305;4870.512948705101;4874.341963834837;4947.084789313988;5258.447862974409;5948.137520059654;6008.3390827009;6031.492126968258;1112.7496313329598;1157.4386422585208;1199.964001079969;1349.2713934475469;1409.5559898631898;781.5896654256561;824.9793755156169;877.635781150828;883.8630012348096;927.638221093774;4872.317875497433;5396.838094857082;5408.188256933892;5445.591580631469;5497.39528651848;4847.345608342899;4870.9269360959615;4889.65772395936;5605.691686957257;5666.744996475169;4849.629507492259;4995.875103122451;5220.9638241086;5401.405845357008;5675.5459563234845;2865.8423786691515;2891.898783542566;2901.8113822601463;3206.246532064963;3247.902562923116;5546.223528705985;6366.395192456709;6372.808815735535;6476.417122459001;6477.514186436036;5758.424157584208;5962.850928726816;6354;6404.391582799627;6449.838754031187;5669;6477.5465717400275;6556.033190043064;6556.5738227016045;6620.735170593122;5197.584193264605;5600;6143.96928015357;6183.690815459119;6205.665781856923;5143.3570803649545;5460.60754507598;5881.706024674565;5998.220231369894;6078.270607527125;5082.390113186441;5427.538659214011;5972.462478376967;6122.234720659918;6162.336169234192;3595.532580764484;3902.52189859447;3904.4143378493454;3969.9206015878945;3979.9801000994808;6136.907946380718;6159.507239420746;6176.258848938156;6241.438270555672;6286.151369565233;4869.683470574401;6019.729112189874;6037.63774173551;6080.574359794772;6107.786227482017;4926.48271931442;5308.628396012242;5868.471837534472;6059.3334733178435;6127.387261274015;5660.047283687286;5964.105384192406;5976.312724036791;5990.430909063632;5991.430814072831;5318.57451403879;5365.490278423544;5937.91093133612;6053.727582258897;6068.4538391544975;5435.152048303596;5702.116171993348;5892.7642894283745;5942.910856337072;5964.522838173022 01:07:17 INFO - PROCESS | 1677 | |5;yui.html;659.7954634063441;686.3239708886749;714.6248219684662;719.0221299033313;740.4187712645574;13183.143095698773;13473.326333683326;13505.932470337635;13522.661933451656;14324.427022919097;13961.581152565414;14287.428502859899;14289.356978935946;14331.996760226788;14343.92828035861;722.9132504099515;904.0597778310549;905.8097799462121;929.2194556572512;931.1852129386787;923.8060007398454;924.2975338742536;927.4806108579184;931.0875342164683;932.5523748600676;949.5584553182769;967.1537404770826;981.793823297105;983.1544871410581;983.7884854756226;954.9283803714715;965.4979410706393;965.715114041356;975.1272611013139;982.6658935961807;725.437785716069;726.117766913203;727.5452841973766;729;729.5440349781387;15185.468508602145;15285.388584456608;15311.540653780294;15341;15344.386224551004;10723.83914241287;12852.22886626796;12946.223226606433;13027.153235039787;13383.263920484369;10059.949700251525;10247.07776300129;10254.077133058061;10261.69214923546;10469.528871200815;3462.8614855405754;3479.1302174456387;3529.170644898447;3554.8400321985314;3629.764065335771;87226.12773872244;87836.56081719615;89618.5519072407;89628;90192;8315;8358.540280284522;9462.716118516455;9585.808283834423;9880.703578892644;22939.541209175724;22979;23203.88398057999;23239.651405228935;23312.883435582713;7170;7449.180590135081;7488.812779680552;7507.436942229246;7700.499467534592;4003.6396724294955;4025.8590949316635;4027.838886444509;4035.0921042765144;4069.2675318442966;3499.3701133795653;3618.656227658368;3623.9094022649647;3629.7459177857822;3635.327464419091;643.0547095769211;645.570695487505;646.424682032991;647.7052940911968;653.9346065393422;549.1707521642269;549.9422560631156;551.8399664097448;579.2498714165166;582.7785441532191;6644.235912869985;7493.85012299751;7527.172011078777;7876.684932602746;7939.325157361689;524.2477045439806;535.344203350892;537.6800803521899;579.0763731847696;581.3924448950856;3237.1583388319204;3535.080878971486;3649.94525082124;3711.387621042498;3890.8054597270025;60207.69896150491;85481;92027.15945681183;92765;96696;30949.0715278542;69459.3054069463;80525.59737201393;80748.57754267381;81704;72350.63824680843;72941.6352918232;72958;73195;73299.63350183216;6163.722632481459;6238.625682459067;7320.853582928418;7340.596267205141;7471.21552237029;45155.32267016063;46090.769546152056;46881;47185.820354491414;47592;61483.69258153681;63254.36745632485;63362.366376335645;63484.60380867678;64042;9819.607215711292;9866.408015519091;10237.334573252714;10369.014943580349;10501.579936802442;10404.58381664725;10407.323523970917;10614;10701.946490267499;11131.387773672526;9664.710058697969;9808.509574521388;9930.354526955725;10054.245931555017;10070.597176112873;9963.302568820112;10080.24398170155;10220.846687299547;10222.897771022195;10303.587856485956;9809.705708828747;9813.313068085166;9928.801423971625;10022.448765317977;10430.895691043295;8575.3997220194;8672.78318042054;8826.42628229163;8942.37403381757;9026.007139214813;8362.247397734232;8423.578821059045;8454.57727113654;8486;8655.437396569203 01:07:17 INFO - PROCESS | 1677 | __end_tp_report 01:07:17 INFO - PROCESS | 1677 | __start_cc_report 01:07:17 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,424 01:07:17 INFO - PROCESS | 1677 | __end_cc_report 01:07:17 INFO - PROCESS | 1677 | __startTimestamp1463645237602__endTimestamp 01:07:17 INFO - PROCESS | 1677 | 01:07:17 INFO - PROCESS | 1677 | ------- Summary: start ------- 01:07:17 INFO - PROCESS | 1677 | Number of tests: 6 01:07:17 INFO - PROCESS | 1677 | 01:07:17 INFO - PROCESS | 1677 | [#0] dojo.html Cycles:165 Average:5764.95 Median:6108.08 stddev:687.99 (11.3%) stddev-sans-first:666.58 01:07:17 INFO - PROCESS | 1677 | Values: 3491.7 3497.0 3520.4 3531.8 3547.2 4571.4 5734.0 5765.6 5849.4 5850.6 5524.5 5736.9 5811.4 5843.8 5856.6 5141.5 5161.5 5245.8 5259.5 5300.0 5292.1 5304.3 5331.0 5332.8 5364.3 3905.9 4372.8 5200.4 5229.1 5236.7 5262.1 5264.7 5291.9 5294.0 5294.3 4527.8 5261.4 5261.7 5301.4 5368.7 5008.9 5487.1 5638.4 5725.2 5768.6 6180.2 6258.8 6278.4 6287.0 6305.1 6182.7 6267.3 6315.3 6332.2 6333.8 5102.3 5888.9 5933.2 5962.5 5965.2 6347.1 6373.6 6406.0 6431.1 6459.9 6138.2 6151.9 6270.9 6285.7 6287.0 6190.7 6218.3 6303.3 6312.0 6325.6 6186.5 6218.1 6281.6 6297.7 6317.3 6166.9 6167.0 6217.9 6315.3 6325.0 4479.4 5038.1 5046.5 5063.2 5109.5 4003.5 4523.5 4565.2 4568.6 4590.3 5825.7 5829.2 5835.7 5946.7 5997.6 5529.9 5772.4 6248.9 6269.6 6281.2 4873.9 5872.5 5880.1 5894.0 5909.9 5403.3 5431.0 5474.5 5492.3 5523.1 5442.3 6482.6 6498.8 6516.3 6553.1 6253.3 6284.8 6291.2 6292.5 6305.9 6282.3 6382.6 6392.7 6458.2 6519.2 6241.8 6286.5 6293.1 6294.7 6331.5 5371.9 6188.3 6195.7 6263.5 6305.3 6138.9 6145.1 6146.8 6172.7 6207.6 6199.7 6225.4 6270.9 6293.9 6305.2 4960.0 5699.7 6083.0 6108.1 6166.3 6113.6 6178.6 6200.7 6227.8 6245.5 6154.0 6172.2 6204.9 6206.5 6228.2 01:07:17 INFO - PROCESS | 1677 | 01:07:17 INFO - PROCESS | 1677 | [#1] ext.html Cycles:180 Average:73283.86 Median:38790.63 stddev:164395.92 (423.8%) stddev-sans-first:164766.25 01:07:17 INFO - PROCESS | 1677 | Values: 494.6 500.0 518.9 519.1 540.9 32090.5 42274.2 42470.4 42494.4 42500.0 42628.4 43302.6 43335.6 43344.7 43431.8 9564.0 9700.6 9805.3 9808.9 9810.8 9606.9 9672.1 9745.0 9746.0 9751.8 9692.4 9730.7 9779.7 9796.8 9811.3 9709.6 9719.9 9750.0 9753.2 9764.1 8409.6 8856.2 8869.1 9650.0 9742.8 36799.4 40139.0 40197.4 40220.6 40251.8 3161.6 3252.5 3395.8 3500.4 3558.2 27852.4 29918.1 30059.4 30531.5 30611.8 2543.1 2636.4 2658.7 2674.2 2675.2 484469.0 516676.0 526585.4 529580.0 529596.0 63660.0 63825.4 64549.4 64561.7 64595.7 35967.1 37018.6 37060.4 68725.7 69274.0 16547.0 16575.9 16695.5 16755.0 16759.2 12609.9 12693.9 12699.7 12703.9 12730.5 15859.9 15912.0 15937.4 15954.6 15956.6 7553.8 7715.1 7845.9 8160.6 8179.4 852.9 1049.3 1052.1 1076.8 1107.3 28632.7 30103.5 30153.9 30575.6 30946.7 1074.3 1134.5 1137.9 1162.0 1217.9 15589.7 15745.9 16010.7 16106.8 16323.8 696303.0 712820.0 714221.0 714569.0 716201.0 60130.7 60763.0 60846.1 60954.0 60971.1 1429.6 1441.1 1479.7 1494.6 1496.7 29253.0 29613.6 29679.7 29902.4 30553.0 52859.0 53526.2 53748.5 53761.5 54493.0 593684.0 594661.0 594742.0 599514.0 600617.0 26968.5 27122.7 27199.0 27606.0 27645.4 39973.0 47086.1 47144.3 47272.0 48387.5 30208.4 36881.0 38138.0 38157.6 38234.4 37610.8 37884.9 38319.4 38568.0 38726.6 37709.4 38070.0 38854.6 39158.8 39416.2 31889.0 34009.8 34478.3 34600.0 34678.8 20066.8 24915.5 25345.5 25547.9 27117.5 01:07:17 INFO - PROCESS | 1677 | 01:07:17 INFO - PROCESS | 1677 | [#2] jquery.html Cycles:180 Average:34510.91 Median:19709.91 stddev:98147.90 (498.0%) stddev-sans-first:98389.65 01:07:17 INFO - PROCESS | 1677 | Values: 317.5 388.3 390.2 391.6 418.9 15866.8 20396.0 20860.2 21382.3 21388.9 11874.6 11998.9 12139.5 12482.8 12519.2 5093.4 5445.3 5645.7 5653.4 5665.5 3945.7 5898.9 6178.3 6196.7 6239.9 5974.3 6222.8 6229.2 6230.0 6230.2 6173.4 6206.4 6207.1 6217.9 6228.8 5693.9 6039.8 6055.6 6057.0 6080.1 13533.0 13985.6 13989.7 14076.3 14162.4 2040.7 2070.5 2093.3 2096.8 2123.1 10038.6 10109.9 10140.0 10416.2 10586.2 1543.3 1709.8 1752.8 1804.8 1930.8 93199.0 125835.0 126969.0 127018.4 128202.4 11829.0 11900.6 11936.3 12020.3 12166.6 15260.1 15269.9 15376.9 15380.2 15397.0 7424.5 8499.6 8638.2 8692.9 8795.4 8500.4 8512.4 8513.6 8569.9 8611.9 3833.0 3945.8 4293.0 4707.4 4768.4 1873.8 1998.2 2003.7 2004.0 2044.3 822.5 937.3 944.2 995.2 1023.2 19635.0 19784.8 20646.8 20761.3 20864.6 793.5 939.0 962.1 973.9 1001.1 8361.0 10850.2 10986.9 10993.3 11036.8 547642.0 553673.0 557316.0 630336.0 635518.0 119287.0 126705.4 130087.0 131164.3 134133.7 1015.7 1036.8 1067.1 1072.8 1073.0 18185.9 18470.5 18604.4 18672.7 18836.4 52640.7 53630.5 53651.2 53672.0 53688.0 73856.6 76135.0 79706.4 87527.6 90806.0 5904.1 9290.0 10983.1 11646.8 12156.8 13060.3 13241.1 13349.5 13461.7 13521.3 10062.9 10952.8 11361.7 11531.9 11617.6 10259.9 10496.0 11289.2 11342.9 11592.2 10521.9 11809.1 11809.2 11901.5 11943.6 12077.8 12095.3 12266.6 12326.4 12334.4 11531.0 11535.8 11626.5 11832.3 11886.5 01:07:17 INFO - PROCESS | 1677 | 01:07:17 INFO - PROCESS | 1677 | [#3] mootools.html Cycles:180 Average:5950.97 Median:2679.40 stddev:3351.33 (125.1%) stddev-sans-first:3344.87 01:07:17 INFO - PROCESS | 1677 | Values: 1611.7 1650.7 1919.2 1974.2 2017.6 7958.8 8373.5 8893.9 8937.6 9399.6 9068.9 9171.1 9209.1 9241.7 9273.6 2487.9 2509.0 2518.0 2528.9 2535.4 2134.4 2318.3 2338.9 2469.2 2537.1 2497.4 2513.4 2530.6 2537.4 2542.8 2499.6 2503.3 2512.8 2520.1 2521.0 2458.3 2460.0 2472.1 2503.2 2505.1 9102.4 9120.6 9252.0 9260.5 9292.0 5510.0 5663.7 5667.6 5681.2 5693.8 3612.9 3709.1 3742.0 3788.0 3789.3 1030.1 1047.3 1054.3 1062.6 1085.2 12228.7 12357.6 12396.8 12407.9 12426.0 4969.6 4989.1 5046.9 5113.3 5128.9 10324.5 10380.9 10420.2 10465.5 10466.9 5588.5 5595.8 5669.0 5674.7 5680.9 4594.6 4967.0 5082.4 5172.6 5177.6 4157.6 4162.8 4203.6 4234.6 4310.2 1378.6 1400.9 1403.0 1406.5 1410.3 762.5 939.3 945.6 950.6 951.2 6074.2 6170.5 6192.9 6201.8 6214.5 464.6 473.5 480.2 484.5 489.6 4051.8 4076.8 4133.4 4175.6 4177.6 10891.8 11059.0 11282.4 11288.4 11291.8 11100.2 11104.9 11233.0 11235.4 11244.3 8121.6 8498.3 8505.0 8536.6 8600.4 6095.2 6125.8 6164.8 6252.8 6273.7 10036.2 10205.4 10410.3 10474.7 10494.6 10235.4 10588.2 10596.4 10607.8 10683.7 6870.1 7196.5 7625.3 7793.2 8015.8 8764.9 8782.1 8861.0 8871.7 8923.6 6598.1 7575.9 7712.6 7824.5 7840.9 7612.5 7667.7 7710.5 7740.3 7783.8 7210.5 7258.2 7269.5 7288.9 7296.8 4874.3 5015.3 5081.8 5110.6 5121.9 4401.6 5049.4 5092.7 5111.6 5358.4 01:07:17 INFO - PROCESS | 1677 | 01:07:17 INFO - PROCESS | 1677 | [#4] prototype.html Cycles:180 Average:4737.40 Median:5283.54 stddev:1512.51 (28.6%) stddev-sans-first:1479.39 01:07:17 INFO - PROCESS | 1677 | Values: 286.5 326.1 328.4 329.1 333.0 4749.6 4999.6 5034.3 5696.2 5776.6 4510.4 5206.9 5404.1 5553.3 5623.6 3678.6 4056.2 4230.8 4244.9 4257.4 3568.8 4230.8 4250.2 4275.6 4302.3 3497.5 3551.4 4239.8 4243.3 4279.4 3870.8 3892.0 4181.5 4185.8 4242.6 3197.0 3399.8 3591.2 3596.7 3618.7 5348.9 5416.2 5429.5 5458.3 5553.9 4232.6 4268.4 4510.7 4673.8 4875.3 5157.7 5215.6 5583.4 5951.4 5981.9 3548.2 3574.8 4001.6 4032.2 4054.2 5456.9 6209.6 6216.5 6290.3 6329.3 3547.2 3978.3 4065.5 4074.6 4077.6 3979.3 4120.9 4133.8 4155.0 4155.8 4617.2 4806.9 4856.6 4870.5 4874.3 4947.1 5258.4 5948.1 6008.3 6031.5 1112.7 1157.4 1200.0 1349.3 1409.6 781.6 825.0 877.6 883.9 927.6 4872.3 5396.8 5408.2 5445.6 5497.4 4847.3 4870.9 4889.7 5605.7 5666.7 4849.6 4995.9 5221.0 5401.4 5675.5 2865.8 2891.9 2901.8 3206.2 3247.9 5546.2 6366.4 6372.8 6476.4 6477.5 5758.4 5962.9 6354.0 6404.4 6449.8 5669.0 6477.5 6556.0 6556.6 6620.7 5197.6 5600.0 6144.0 6183.7 6205.7 5143.4 5460.6 5881.7 5998.2 6078.3 5082.4 5427.5 5972.5 6122.2 6162.3 3595.5 3902.5 3904.4 3969.9 3980.0 6136.9 6159.5 6176.3 6241.4 6286.2 4869.7 6019.7 6037.6 6080.6 6107.8 4926.5 5308.6 5868.5 6059.3 6127.4 5660.0 5964.1 5976.3 5990.4 5991.4 5318.6 5365.5 5937.9 6053.7 6068.5 5435.2 5702.1 5892.8 5942.9 5964.5 01:07:17 INFO - PROCESS | 1677 | 01:07:17 INFO - PROCESS | 1677 | [#5] yui.html Cycles:180 Average:17656.81 Median:644.31 stddev:25410.76 (3943.9%) stddev-sans-first:25449.99 01:07:17 INFO - PROCESS | 1677 | Values: 659.8 686.3 714.6 719.0 740.4 13183.1 13473.3 13505.9 13522.7 14324.4 13961.6 14287.4 14289.4 14332.0 14343.9 722.9 904.1 905.8 929.2 931.2 923.8 924.3 927.5 931.1 932.6 949.6 967.2 981.8 983.2 983.8 954.9 965.5 965.7 975.1 982.7 725.4 726.1 727.5 729.0 729.5 15185.5 15285.4 15311.5 15341.0 15344.4 10723.8 12852.2 12946.2 13027.2 13383.3 10059.9 10247.1 10254.1 10261.7 10469.5 3462.9 3479.1 3529.2 3554.8 3629.8 87226.1 87836.6 89618.6 89628.0 90192.0 8315.0 8358.5 9462.7 9585.8 9880.7 22939.5 22979.0 23203.9 23239.7 23312.9 7170.0 7449.2 7488.8 7507.4 7700.5 4003.6 4025.9 4027.8 4035.1 4069.3 3499.4 3618.7 3623.9 3629.7 3635.3 643.1 645.6 646.4 647.7 653.9 549.2 549.9 551.8 579.2 582.8 6644.2 7493.9 7527.2 7876.7 7939.3 524.2 535.3 537.7 579.1 581.4 3237.2 3535.1 3649.9 3711.4 3890.8 60207.7 85481.0 92027.2 92765.0 96696.0 30949.1 69459.3 80525.6 80748.6 81704.0 72350.6 72941.6 72958.0 73195.0 73299.6 6163.7 6238.6 7320.9 7340.6 7471.2 45155.3 46090.8 46881.0 47185.8 47592.0 61483.7 63254.4 63362.4 63484.6 64042.0 9819.6 9866.4 10237.3 10369.0 10501.6 10404.6 10407.3 10614.0 10701.9 11131.4 9664.7 9808.5 9930.4 10054.2 10070.6 9963.3 10080.2 10220.8 10222.9 10303.6 9809.7 9813.3 9928.8 10022.4 10430.9 8575.4 8672.8 8826.4 8942.4 9026.0 8362.2 8423.6 8454.6 8486.0 8655.4 01:07:17 INFO - PROCESS | 1677 | -------- Summary: end -------- 01:07:17 INFO - PROCESS | 1677 | 01:07:18 INFO - TEST-INFO | 1677: exit 0 01:07:18 INFO - TEST-OK | dromaeo_css | took 1112671ms 01:07:18 INFO - TEST-START | kraken 01:07:18 INFO - operating with platform_type : mac_ 01:07:18 INFO - Initialising browser for kraken test... 01:07:18 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmp9nriNe/profile http://localhost:49273/getInfo.html) 01:07:21 INFO - PROCESS | 1685 | __metrics Screen width/height:1600/1200 01:07:21 INFO - PROCESS | 1685 | colorDepth:24 01:07:21 INFO - PROCESS | 1685 | Browser inner width/height: 1024/689 01:07:21 INFO - PROCESS | 1685 | __metrics 01:07:21 ERROR - PROCESS | 1685 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 01:07:22 INFO - TEST-INFO | 1685: exit 0 01:07:22 INFO - Browser initialized. 01:07:22 INFO - Running cycle 1/1 for kraken test... 01:07:22 INFO - TEST-INFO | started process 1688 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmp9nriNe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:07:33 INFO - PROCESS | 1688 | RSS: Main: 249720832 01:07:33 INFO - PROCESS | 1688 | 01:07:36 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 01:07:37 INFO - PROCESS | 1688 | RSS: Main: 261562368 01:07:37 INFO - PROCESS | 1688 | 01:07:40 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 01:07:41 INFO - PROCESS | 1688 | RSS: Main: 266256384 01:07:41 INFO - PROCESS | 1688 | 01:07:44 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 01:07:45 INFO - PROCESS | 1688 | RSS: Main: 267104256 01:07:45 INFO - PROCESS | 1688 | 01:07:47 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 01:07:47 INFO - PROCESS | 1688 | RSS: Main: 267325440 01:07:47 INFO - PROCESS | 1688 | 01:07:50 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:07:50 INFO - PROCESS | 1688 | RSS: Main: 267792384 01:07:50 INFO - PROCESS | 1688 | 01:07:53 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 01:07:54 INFO - PROCESS | 1688 | RSS: Main: 268144640 01:07:54 INFO - PROCESS | 1688 | 01:07:57 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 01:07:58 INFO - PROCESS | 1688 | RSS: Main: 269500416 01:07:58 INFO - PROCESS | 1688 | 01:08:01 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 01:08:01 INFO - PROCESS | 1688 | RSS: Main: 270438400 01:08:01 INFO - PROCESS | 1688 | 01:08:03 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:08:04 INFO - PROCESS | 1688 | RSS: Main: 269930496 01:08:04 INFO - PROCESS | 1688 | 01:08:06 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:08:06 INFO - PROCESS | 1688 | RSS: Main: 270389248 01:08:06 INFO - PROCESS | 1688 | 01:08:09 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:08:09 INFO - PROCESS | 1688 | RSS: Main: 270360576 01:08:09 INFO - PROCESS | 1688 | 01:08:12 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:08:13 INFO - PROCESS | 1688 | RSS: Main: 270422016 01:08:13 INFO - PROCESS | 1688 | 01:08:16 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:08:16 INFO - PROCESS | 1688 | RSS: Main: 270381056 01:08:16 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 01:08:18 INFO - PROCESS | 1688 | RSS: Main: 272216064 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | __start_tp_report 01:08:18 INFO - PROCESS | 1688 | _x_x_mozilla_page_load 01:08:18 INFO - PROCESS | 1688 | _x_x_mozilla_page_load_details 01:08:18 INFO - PROCESS | 1688 | |i|pagename|runs| 01:08:18 INFO - PROCESS | 1688 | |0;ai-astar;112;117;110;115;116;116;110;116;117;146 01:08:18 INFO - PROCESS | 1688 | |1;audio-beat-detection;158;128;141;196;122;169;168;154;165;157 01:08:18 INFO - PROCESS | 1688 | |2;audio-dft;197;197;209;224;246;194;202;193;196;208 01:08:18 INFO - PROCESS | 1688 | |3;audio-fft;75;83;91;199;83;60;60;67;141;56 01:08:18 INFO - PROCESS | 1688 | |4;audio-oscillator;82;81;152;79;82;88;127;81;81;125 01:08:18 INFO - PROCESS | 1688 | |5;imaging-gaussian-blur;91;88;89;88;90;87;91;87;91;87 01:08:18 INFO - PROCESS | 1688 | |6;imaging-darkroom;96;97;96;130;107;151;143;99;96;96 01:08:18 INFO - PROCESS | 1688 | |7;imaging-desaturate;92;94;91;155;94;93;95;93;93;92 01:08:18 INFO - PROCESS | 1688 | |8;json-parse-financial;58;59;57;59;59;59;56;129;51;58 01:08:18 INFO - PROCESS | 1688 | |9;json-stringify-tinderbox;63;67;104;101;71;67;72;82;103;100 01:08:18 INFO - PROCESS | 1688 | |10;stanford-crypto-aes;86;86;87;84;92;85;86;98;112;106 01:08:18 INFO - PROCESS | 1688 | |11;stanford-crypto-ccm;137;138;174;138;125;122;148;149;248;158 01:08:18 INFO - PROCESS | 1688 | |12;stanford-crypto-pbkdf2;167;162;164;165;165;166;166;164;164;165 01:08:18 INFO - PROCESS | 1688 | |13;stanford-crypto-sha256-iterative;68;64;68;71;83;67;66;64;60;67 01:08:18 INFO - PROCESS | 1688 | __end_tp_report 01:08:18 INFO - PROCESS | 1688 | __start_cc_report 01:08:18 INFO - PROCESS | 1688 | _x_x_mozilla_cycle_collect,1088 01:08:18 INFO - PROCESS | 1688 | __end_cc_report 01:08:18 INFO - PROCESS | 1688 | __startTimestamp1463645298902__endTimestamp 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | ------- Summary: start ------- 01:08:18 INFO - PROCESS | 1688 | Number of tests: 14 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#0] ai-astar Cycles:10 Average:117.50 Median:116.00 stddev:10.37 (8.9%) stddev-sans-first:10.81 01:08:18 INFO - PROCESS | 1688 | Values: 112.0 117.0 110.0 115.0 116.0 116.0 110.0 116.0 117.0 146.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#1] audio-beat-detection Cycles:10 Average:155.80 Median:161.50 stddev:21.52 (13.3%) stddev-sans-first:22.81 01:08:18 INFO - PROCESS | 1688 | Values: 158.0 128.0 141.0 196.0 122.0 169.0 168.0 154.0 165.0 157.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#2] audio-dft Cycles:10 Average:206.60 Median:205.00 stddev:16.75 (8.2%) stddev-sans-first:17.40 01:08:18 INFO - PROCESS | 1688 | Values: 197.0 197.0 209.0 224.0 246.0 194.0 202.0 193.0 196.0 208.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#3] audio-fft Cycles:10 Average:91.50 Median:71.00 stddev:45.05 (63.5%) stddev-sans-first:47.39 01:08:18 INFO - PROCESS | 1688 | Values: 75.0 83.0 91.0 199.0 83.0 60.0 60.0 67.0 141.0 56.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:81.00 stddev:26.51 (32.7%) stddev-sans-first:27.50 01:08:18 INFO - PROCESS | 1688 | Values: 82.0 81.0 152.0 79.0 82.0 88.0 127.0 81.0 81.0 125.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:89.50 stddev:1.73 (1.9%) stddev-sans-first:1.66 01:08:18 INFO - PROCESS | 1688 | Values: 91.0 88.0 89.0 88.0 90.0 87.0 91.0 87.0 91.0 87.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#6] imaging-darkroom Cycles:10 Average:111.10 Median:96.00 stddev:21.71 (22.6%) stddev-sans-first:22.33 01:08:18 INFO - PROCESS | 1688 | Values: 96.0 97.0 96.0 130.0 107.0 151.0 143.0 99.0 96.0 96.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:93.00 stddev:19.64 (21.1%) stddev-sans-first:20.66 01:08:18 INFO - PROCESS | 1688 | Values: 92.0 94.0 91.0 155.0 94.0 93.0 95.0 93.0 93.0 92.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:58.50 stddev:22.79 (39.0%) stddev-sans-first:24.06 01:08:18 INFO - PROCESS | 1688 | Values: 58.0 59.0 57.0 59.0 59.0 59.0 56.0 129.0 51.0 58.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#9] json-stringify-tinderbox Cycles:10 Average:83.00 Median:67.00 stddev:17.10 (25.5%) stddev-sans-first:16.54 01:08:18 INFO - PROCESS | 1688 | Values: 63.0 67.0 104.0 101.0 71.0 67.0 72.0 82.0 103.0 100.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:86.00 stddev:9.87 (11.5%) stddev-sans-first:10.22 01:08:18 INFO - PROCESS | 1688 | Values: 86.0 86.0 87.0 84.0 92.0 85.0 86.0 98.0 112.0 106.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:148.50 stddev:36.48 (24.6%) stddev-sans-first:38.19 01:08:18 INFO - PROCESS | 1688 | Values: 137.0 138.0 174.0 138.0 125.0 122.0 148.0 149.0 248.0 158.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.80 Median:165.00 stddev:1.40 (0.8%) stddev-sans-first:1.24 01:08:18 INFO - PROCESS | 1688 | Values: 167.0 162.0 164.0 165.0 165.0 166.0 166.0 164.0 164.0 165.0 01:08:18 INFO - PROCESS | 1688 | 01:08:18 INFO - PROCESS | 1688 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:6.11 (9.0%) stddev-sans-first:6.48 01:08:18 INFO - PROCESS | 1688 | Values: 68.0 64.0 68.0 71.0 83.0 67.0 66.0 64.0 60.0 67.0 01:08:18 INFO - PROCESS | 1688 | -------- Summary: end -------- 01:08:18 INFO - PROCESS | 1688 | 01:08:19 INFO - TEST-INFO | 1688: exit 0 01:08:19 INFO - TEST-OK | kraken | took 61533ms 01:08:19 INFO - SUITE-END | took 1174s 01:08:20 INFO - Completed test suite (00:19:34) 01:08:20 INFO - javascript benchmark 01:08:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7242.539850240592, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3491.668291512306, 3497.038314463522, 3520.436730123181, 3531.752777305588, 3547.237343971046, 4571.3600095986585, 5733.9713301433485, 5765.625234359763, 5849.385814489476, 5850.561207909403, 5524.502794748471, 5736.856578585537, 5811.389804070575, 5843.831233753256, 5856.560757943161, 5141.537261646451, 5161.509656582637, 5245.79016839326, 5259.500347467004, 5300.01949639316, 5292.073887069748, 5304.336957880265, 5330.973345133269, 5332.813351532697, 5364.275822763916, 3905.863294784683, 4372.8032238549185, 5200.427952925175, 5229.111051121318, 5236.73816309183, 5262.079136151158, 5264.684118952875, 5291.86770330741, 5293.9735301323635, 5294.311739473882, 4527.750973696446, 5261.394939581921, 5261.736913154327, 5301.443348448396, 5368.731563421813, 5008.899822003539, 5487.122060470346, 5638.35158956717, 5725.198472213893, 5768.625039372428, 6180.165677633507, 6258.78094266703, 6278.434940855299, 6286.968565157191, 6305.117283580303, 6182.721777520022, 6267.341929097471, 6315.336889626569, 6332.176817013805, 6333.8416539586415, 5102.260172275037, 5888.911666325009, 5933.19901813254, 5962.463378295887, 5965.224520812267, 6347.079673447324, 6373.649449280241, 6405.967970160213, 6431.067495213168, 6459.903101453482, 6138.202033735675, 6151.938480615226, 6270.9372906270355, 6285.685715714196, 6287.0, 6190.659513726699, 6218.347073557211, 6303.275123360872, 6311.96844015777, 6325.588836725598, 6186.4741496972365, 6218.067289906459, 6281.623102613858, 6297.748090076438, 6317.273513545909, 6166.907496387557, 6167.044108163242, 6217.937820621826, 6315.305316415191, 6325.0, 4479.372887795711, 5038.143515602356, 5046.495350464924, 5063.240513922868, 5109.540141387219, 4003.4795476587856, 4523.457185137739, 4565.246734288871, 4568.634509239253, 4590.265557510782, 5825.7087145642545, 5829.213056237439, 5835.737391817378, 5946.67293298864, 5997.55018373615, 5529.889402211933, 5772.365039845614, 6248.875022499525, 6269.561130720897, 6281.246250449975, 4873.878153046202, 5872.471477566954, 5880.117982302603, 5894.0274854648505, 5909.881802364014, 5403.297571315782, 5431.0, 5474.479924407175, 5492.313460817398, 5523.143912693539, 5442.292501974718, 6482.611043337321, 6498.805035848837, 6516.2506311773805, 6553.148090748172, 6253.343398943045, 6284.811455656338, 6291.213598300213, 6292.465140463112, 6305.936940630535, 6282.340354262919, 6382.6170429774365, 6392.744290228339, 6458.22501299847, 6519.152510173647, 6241.78153764616, 6286.52851036165, 6293.087502312231, 6294.685265736786, 6331.461825744679, 5371.865703357291, 6188.257409110935, 6195.659238741912, 6263.530235232284, 6305.337939516285, 6138.907916381168, 6145.095020543646, 6146.754129834757, 6172.660503672161, 6207.565470417208, 6199.690015499117, 6225.439710426083, 6270.9372906270355, 6293.874122517433, 6305.211848518935, 4959.9832034433575, 5699.686517241592, 6083.0, 6108.08378743192, 6166.321704612399, 6113.628337950651, 6178.62928224308, 6200.720967556381, 6227.782027629011, 6245.500359971101, 6153.969230153821, 6172.22847144107, 6204.875902482016, 6206.503479721521, 6228.22147231596], "value": 5723.065853019148, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.6487993524596, 500.04990518015774, 518.9117849965505, 519.0993626058787, 540.8945255675145, 32090.518642220355, 42274.15451690972, 42470.36294455582, 42494.36258456122, 42500.0, 42628.36057459133, 43302.61835599041, 43335.56664433355, 43344.66553344664, 43431.78284108575, 9563.999999999982, 9700.563474643657, 9805.31362804604, 9808.901910980905, 9810.754731131743, 9606.90393096067, 9672.129508344247, 9744.951275243613, 9745.976672449397, 9751.75620609487, 9692.418454892693, 9730.708078757649, 9779.70660880171, 9796.804063918717, 9811.313208075437, 9709.61161553537, 9719.902800972006, 9750.024997500264, 9753.170980466668, 9764.121229089416, 8409.579521023925, 8856.202941735275, 8869.11308869108, 9649.951750241275, 9742.805143897152, 36799.4480082799, 40139.0, 40197.39703904444, 40220.597794021975, 40251.7987410064, 3161.573187619665, 3252.4796032634663, 3395.8471868765964, 3500.352434799545, 3558.217192217734, 27852.442951141067, 29918.07353496792, 30059.398812023857, 30531.542026869392, 30611.846940765154, 2543.097200493835, 2636.380450594109, 2658.6809582850183, 2674.224474902276, 2675.1974407677812, 484469.0, 516676.0, 526585.3670731622, 529580.0, 529596.0, 63660.0, 63825.36174638287, 64549.354506454336, 64561.677191613744, 64595.67702161459, 35967.10082247912, 37018.62981370206, 37060.44409333862, 68725.65637171883, 69274.0, 16547.0, 16575.91712041432, 16695.49913502597, 16754.99470031802, 16759.162041897856, 12609.93695031519, 12693.873061269454, 12699.746005079847, 12703.872961270268, 12730.490780368867, 15859.920700396424, 15912.0, 15937.442189523312, 15954.601134971486, 15956.601084972968, 7553.811154721176, 7715.148364392506, 7845.921540784633, 8160.591970401575, 8179.427440079137, 852.86780549015, 1049.333673117576, 1052.0689190066846, 1076.8223243164946, 1107.341132026443, 28632.713672863003, 30103.548446773315, 30153.944611938477, 30575.624096915693, 30946.690533094832, 1074.2856000759407, 1134.5178299222798, 1137.8805225451374, 1161.976760464803, 1217.8843009914044, 15589.674877635302, 15745.921270393575, 16010.679786404207, 16106.838931610533, 16323.836761632469, 696303.0, 712820.0, 714221.0, 714569.0, 716201.0, 60130.69934650299, 60763.0, 60846.0873086904, 60954.0, 60971.08543371853, 1429.6068581140062, 1441.0633088492355, 1479.7484427647325, 1494.559105063987, 1496.745553255949, 29253.0, 29613.555796662637, 29679.703202967696, 29902.401951960404, 30553.0, 52859.0, 53526.1971070442, 53748.46251537591, 53761.46238537564, 54493.0, 593684.0, 594661.0, 594742.0, 599514.0, 600617.0, 26968.460630787667, 27122.72877271202, 27199.04803331874, 27606.0, 27645.447091058468, 39973.0, 47086.119695147354, 47144.29283560818, 47272.0, 48387.5161248383, 30208.395832083675, 36881.0, 38138.0, 38157.61842381541, 38234.42648360221, 37610.8119459401, 37884.86345409642, 38319.42520862134, 38568.035799104124, 38726.6127338723, 37709.434358485196, 38070.0, 38854.640087596796, 39158.80420597879, 39416.2116757669, 31889.0, 34009.829950851075, 34478.31043379169, 34600.0, 34678.82660586681, 20066.799332006492, 24915.501689965735, 25345.49309013846, 25547.87226063932, 27117.50853703065], "value": 19969.837723658307, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.48725807820375, 388.25842640556533, 390.2487711156024, 391.61817228202506, 418.91621675664857, 15866.841331586695, 20396.0, 20860.165593376245, 21382.25162119326, 21388.89305553474, 11874.584389546368, 11998.94000529996, 12139.453724582392, 12482.750344993095, 12519.186252893556, 5093.363329583802, 5445.264889239961, 5645.745941432645, 5653.434656534355, 5665.461781130786, 3945.743526670757, 5898.911516327237, 6178.258608966908, 6196.721147548348, 6239.937600624004, 5974.312954010279, 6222.844428889269, 6229.159063526411, 6230.0, 6230.221222347207, 6173.351798061229, 6206.37936206378, 6207.131001659771, 6217.942949698562, 6228.813135605939, 5693.914591281134, 6039.78860739872, 6055.5761096723245, 6057.0, 6080.087986801971, 13533.0, 13985.58043258704, 13989.720205595933, 14076.296185190802, 14162.433502659882, 2040.6734922412343, 2070.5030792609814, 2093.3196711068963, 2096.758872729625, 2123.1401282480465, 10038.648647297383, 10109.898901010969, 10140.03669651382, 10416.218783591185, 10586.20603454744, 1543.3440787665309, 1709.7948246210535, 1752.815954324803, 1804.7744031996, 1930.8165724256314, 93199.0, 125835.0, 126969.0, 127018.36490817579, 128202.35898820446, 11829.0, 11900.583479578174, 11936.343501107522, 12020.338881361435, 12166.57416990401, 15260.084394936339, 15269.923650381677, 15376.923115384576, 15380.15409152485, 15396.999195052285, 7424.48028638001, 8499.640057590756, 8638.222559969632, 8692.913070869337, 8795.428297160664, 8500.44747091437, 8512.40413171072, 8513.616887240089, 8569.871451928226, 8611.913880861237, 3833.0, 3945.763254204757, 4293.012607100384, 4707.388039554836, 4768.403949506312, 1873.7657792775904, 1998.2007197121268, 2003.6994450832492, 2003.9699404508945, 2044.3355909329528, 822.5270469480073, 937.2876613773581, 944.2493217891792, 995.1640621877658, 1023.150784848574, 19635.018249087487, 19784.802151978583, 20646.793532064486, 20761.273355432488, 20864.582708345748, 793.4525177627419, 938.9718308450757, 962.1340793285958, 973.8782652168479, 1001.0590045357341, 8361.0, 10850.240483166102, 10986.890131098588, 10993.340399576051, 11036.834447483294, 547642.0, 553673.0, 557316.0, 630336.0, 635518.0, 119287.0, 126705.36647316704, 130087.0, 131164.3441782785, 134133.65866341407, 1015.746063484129, 1036.8081904847627, 1067.0556557446716, 1072.753266748652, 1072.9678109656722, 18185.909070454563, 18470.538236544195, 18604.44186674402, 18672.719909201645, 18836.434906952814, 52640.7367963173, 53630.46369536411, 53651.19523207233, 53672.0, 53688.0, 73856.63071684608, 76135.0, 79706.40587188108, 87527.56236218777, 90806.0, 5904.143899134688, 9289.953550232205, 10983.121350291798, 11646.825297620373, 12156.817647735113, 13060.281684507443, 13241.073124881166, 13349.532766353132, 13461.663458413226, 13521.323933803074, 10062.893081760854, 10952.835707464554, 11361.715957100809, 11531.88468115308, 11617.60588729358, 10259.922708115839, 10496.0, 11289.153313501356, 11342.943285283522, 11592.246503977214, 10521.894781052397, 11809.05527557776, 11809.232399894323, 11901.464434100313, 11943.581974630846, 12077.758444830879, 12095.274283543016, 12266.632001039983, 12326.383680816103, 12334.444949977453, 11531.0, 11535.826962595736, 11626.476808543466, 11832.34922079294, 11886.524539018688], "value": 9492.66340687212, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1611.7098922194004, 1650.711125553028, 1919.1843466526723, 1974.160981582827, 2017.6065667194903, 7958.840823183533, 8373.455725377846, 8893.866592001117, 8937.55312234389, 9399.577019034143, 9068.909310906889, 9171.082891710825, 9209.12513311236, 9241.676541321056, 9273.582688779004, 2487.868020050877, 2509.0089414681283, 2518.0179729905353, 2528.88620012099, 2535.404180017694, 2134.4343748906554, 2318.258157389636, 2338.9064437422485, 2469.1851688942697, 2537.061287323693, 2497.3506888209104, 2513.40934880303, 2530.6457096006575, 2537.3783423061377, 2542.83471574348, 2499.5875680512786, 2503.2740505253455, 2512.849229046254, 2520.1053625962886, 2521.0, 2458.2502336787275, 2459.963100553493, 2472.097684345219, 2503.161440917295, 2505.08564374004, 9102.408343457653, 9120.635174593008, 9251.953740231256, 9260.490673012982, 9292.0, 5509.972450137724, 5663.716814159276, 5667.603267771257, 5681.204631351573, 5693.77224911003, 3612.8554857805657, 3709.0727318170457, 3741.981290093532, 3788.04532464678, 3789.2610940866543, 1030.1141018723893, 1047.3454091193005, 1054.3357684658615, 1062.6493257225097, 1085.159001773624, 12228.694282643006, 12357.567485138066, 12396.81404778929, 12407.945324647326, 12426.005919526417, 4969.552740253394, 4989.052080104769, 5046.949530504648, 5113.309703190096, 5128.8717782055, 10324.535395907202, 10380.948095259626, 10420.218483613759, 10465.47672616366, 10466.895331046744, 5588.524975377057, 5595.832125036255, 5669.007923613433, 5674.716264186856, 5680.943190568124, 4594.563516465931, 4967.006598680278, 5082.440931497532, 5172.560332371715, 5177.58579313654, 4157.584241575879, 4162.83348666056, 4203.579642035772, 4234.618884300427, 4310.181065597527, 1378.600205940266, 1400.8949328800381, 1402.9929850350684, 1406.4796025470641, 1410.3230449384353, 762.5424745152864, 939.2532936315646, 945.5650400815564, 950.5959967013991, 951.2009911674227, 6074.240719910012, 6170.475509581735, 6192.938070619326, 6201.782937597252, 6214.471769899519, 464.6422254863736, 473.5051870795027, 480.1860845866293, 484.4670862051715, 489.5593965431069, 4051.7974101294817, 4076.7961601919787, 4133.359329303963, 4175.603317684815, 4177.624013838769, 10891.83662245067, 11059.0, 11282.379469129113, 11288.435578221057, 11291.774164516828, 11100.222984391177, 11104.944475277573, 11233.045191158679, 11235.382053987109, 11244.325340479598, 8121.634526446206, 8498.277646400125, 8505.0, 8536.615852286537, 8600.39797214189, 6095.177151084724, 6125.785597504244, 6164.753409863734, 6252.843678908063, 6273.686315684106, 10036.247281454067, 10205.387676739123, 10410.323328983592, 10474.68575942723, 10494.580216791242, 10235.437050962268, 10588.152947764007, 10596.417197053928, 10607.787844243227, 10683.679489615324, 6870.141232345957, 7196.532225405332, 7625.275598818103, 7793.181715919747, 8015.799605009688, 8764.91235087641, 8782.121787821166, 8861.0, 8871.689490868046, 8923.598438070432, 6598.1422415085735, 7575.924240757522, 7712.614369281401, 7824.491408058458, 7840.921590784019, 7612.543247404952, 7667.693292268247, 7710.498817576839, 7740.26467485588, 7783.805404864924, 7210.459215558749, 7258.201597824341, 7269.491135620456, 7288.854222915618, 7296.817579560554, 4874.341963834979, 5015.3480047593575, 5081.822136225214, 5110.565601923878, 5121.923171152361, 4401.625861801783, 5049.419316778617, 5092.668976516515, 5111.591072714248, 5358.437364076716], "value": 4709.122036387835, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.4771791480548, 326.0902083187904, 328.44328862577925, 329.10319379690344, 332.97003269705755, 4749.643776716747, 4999.575121090481, 5034.27003084553, 5696.174054762057, 5776.624519406236, 4510.413646225994, 5206.8698282542955, 5404.108801791042, 5553.278073850393, 5623.550115990721, 3678.5769636491746, 4056.1747213753856, 4230.788460576974, 4244.893877653052, 4257.40396344512, 3568.750187486877, 4230.830766769341, 4250.192463431938, 4275.631797824697, 4302.268614335555, 3497.52783374245, 3551.4140166872435, 4239.7668128252935, 4243.299855523835, 4279.379489973952, 3870.7677539347446, 3891.9805400973096, 4181.540030596647, 4185.769782661952, 4242.618164365222, 3196.9769673704423, 3399.8130102844334, 3591.1919818040733, 3596.6762991330907, 3618.6562276583945, 5348.876735885468, 5416.214648875891, 5429.511343979061, 5458.2904222451225, 5553.861153471155, 4232.603240930516, 4268.396278270253, 4510.661700372485, 4673.766311684402, 4875.268709693574, 5157.690538567698, 5215.608829337815, 5583.441655834425, 5951.4291425145, 5981.910271345933, 3548.183917698944, 3574.803385813779, 4001.599840015975, 4032.2258206389097, 4054.175620970575, 5456.945430545684, 6209.59637623553, 6216.502679785652, 6290.308066112724, 6329.303776584572, 3547.237343971007, 3978.264021156095, 4065.4918135233097, 4074.595988366878, 4077.6330130288416, 3979.2837289288204, 4120.896977575585, 4133.834646614102, 4155.0027993282, 4155.836365817576, 4617.229662202719, 4806.927896081562, 4856.611471082305, 4870.512948705101, 4874.341963834837, 4947.084789313988, 5258.447862974409, 5948.137520059654, 6008.3390827009, 6031.492126968258, 1112.7496313329598, 1157.4386422585208, 1199.964001079969, 1349.2713934475469, 1409.5559898631898, 781.5896654256561, 824.9793755156169, 877.635781150828, 883.8630012348096, 927.638221093774, 4872.317875497433, 5396.838094857082, 5408.188256933892, 5445.591580631469, 5497.39528651848, 4847.345608342899, 4870.9269360959615, 4889.65772395936, 5605.691686957257, 5666.744996475169, 4849.629507492259, 4995.875103122451, 5220.9638241086, 5401.405845357008, 5675.5459563234845, 2865.8423786691515, 2891.898783542566, 2901.8113822601463, 3206.246532064963, 3247.902562923116, 5546.223528705985, 6366.395192456709, 6372.808815735535, 6476.417122459001, 6477.514186436036, 5758.424157584208, 5962.850928726816, 6354.0, 6404.391582799627, 6449.838754031187, 5669.0, 6477.5465717400275, 6556.033190043064, 6556.5738227016045, 6620.735170593122, 5197.584193264605, 5600.0, 6143.96928015357, 6183.690815459119, 6205.665781856923, 5143.3570803649545, 5460.60754507598, 5881.706024674565, 5998.220231369894, 6078.270607527125, 5082.390113186441, 5427.538659214011, 5972.462478376967, 6122.234720659918, 6162.336169234192, 3595.532580764484, 3902.52189859447, 3904.4143378493454, 3969.9206015878945, 3979.9801000994808, 6136.907946380718, 6159.507239420746, 6176.258848938156, 6241.438270555672, 6286.151369565233, 4869.683470574401, 6019.729112189874, 6037.63774173551, 6080.574359794772, 6107.786227482017, 4926.48271931442, 5308.628396012242, 5868.471837534472, 6059.3334733178435, 6127.387261274015, 5660.047283687286, 5964.105384192406, 5976.312724036791, 5990.430909063632, 5991.430814072831, 5318.57451403879, 5365.490278423544, 5937.91093133612, 6053.727582258897, 6068.4538391544975, 5435.152048303596, 5702.116171993348, 5892.7642894283745, 5942.910856337072, 5964.522838173022], "value": 4247.538887593977, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.7954634063441, 686.3239708886749, 714.6248219684662, 719.0221299033313, 740.4187712645574, 13183.143095698773, 13473.326333683326, 13505.932470337635, 13522.661933451656, 14324.427022919097, 13961.581152565414, 14287.428502859899, 14289.356978935946, 14331.996760226788, 14343.92828035861, 722.9132504099515, 904.0597778310549, 905.8097799462121, 929.2194556572512, 931.1852129386787, 923.8060007398454, 924.2975338742536, 927.4806108579184, 931.0875342164683, 932.5523748600676, 949.5584553182769, 967.1537404770826, 981.793823297105, 983.1544871410581, 983.7884854756226, 954.9283803714715, 965.4979410706393, 965.715114041356, 975.1272611013139, 982.6658935961807, 725.437785716069, 726.117766913203, 727.5452841973766, 729.0, 729.5440349781387, 15185.468508602145, 15285.388584456608, 15311.540653780294, 15341.0, 15344.386224551004, 10723.83914241287, 12852.22886626796, 12946.223226606433, 13027.153235039787, 13383.263920484369, 10059.949700251525, 10247.07776300129, 10254.077133058061, 10261.69214923546, 10469.528871200815, 3462.8614855405754, 3479.1302174456387, 3529.170644898447, 3554.8400321985314, 3629.764065335771, 87226.12773872244, 87836.56081719615, 89618.5519072407, 89628.0, 90192.0, 8315.0, 8358.540280284522, 9462.716118516455, 9585.808283834423, 9880.703578892644, 22939.541209175724, 22979.0, 23203.88398057999, 23239.651405228935, 23312.883435582713, 7170.0, 7449.180590135081, 7488.812779680552, 7507.436942229246, 7700.499467534592, 4003.6396724294955, 4025.8590949316635, 4027.838886444509, 4035.0921042765144, 4069.2675318442966, 3499.3701133795653, 3618.656227658368, 3623.9094022649647, 3629.7459177857822, 3635.327464419091, 643.0547095769211, 645.570695487505, 646.424682032991, 647.7052940911968, 653.9346065393422, 549.1707521642269, 549.9422560631156, 551.8399664097448, 579.2498714165166, 582.7785441532191, 6644.235912869985, 7493.85012299751, 7527.172011078777, 7876.684932602746, 7939.325157361689, 524.2477045439806, 535.344203350892, 537.6800803521899, 579.0763731847696, 581.3924448950856, 3237.1583388319204, 3535.080878971486, 3649.94525082124, 3711.387621042498, 3890.8054597270025, 60207.69896150491, 85481.0, 92027.15945681183, 92765.0, 96696.0, 30949.0715278542, 69459.3054069463, 80525.59737201393, 80748.57754267381, 81704.0, 72350.63824680843, 72941.6352918232, 72958.0, 73195.0, 73299.63350183216, 6163.722632481459, 6238.625682459067, 7320.853582928418, 7340.596267205141, 7471.21552237029, 45155.32267016063, 46090.769546152056, 46881.0, 47185.820354491414, 47592.0, 61483.69258153681, 63254.36745632485, 63362.366376335645, 63484.60380867678, 64042.0, 9819.607215711292, 9866.408015519091, 10237.334573252714, 10369.014943580349, 10501.579936802442, 10404.58381664725, 10407.323523970917, 10614.0, 10701.946490267499, 11131.387773672526, 9664.710058697969, 9808.509574521388, 9930.354526955725, 10054.245931555017, 10070.597176112873, 9963.302568820112, 10080.24398170155, 10220.846687299547, 10222.897771022195, 10303.587856485956, 9809.705708828747, 9813.313068085166, 9928.801423971625, 10022.448765317977, 10430.895691043295, 8575.3997220194, 8672.78318042054, 8826.42628229163, 8942.37403381757, 9026.007139214813, 8362.247397734232, 8423.578821059045, 8454.57727113654, 8486.0, 8655.437396569203], "value": 6650.178921128966, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 110.0, 115.0, 116.0, 116.0, 110.0, 116.0, 117.0, 146.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 128.0, 141.0, 196.0, 122.0, 169.0, 168.0, 154.0, 165.0, 157.0], "value": 155.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 197.0, 209.0, 224.0, 246.0, 194.0, 202.0, 193.0, 196.0, 208.0], "value": 206.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 83.0, 91.0, 199.0, 83.0, 60.0, 60.0, 67.0, 141.0, 56.0], "value": 91.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 152.0, 79.0, 82.0, 88.0, 127.0, 81.0, 81.0, 125.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 89.0, 88.0, 90.0, 87.0, 91.0, 87.0, 91.0, 87.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 130.0, 107.0, 151.0, 143.0, 99.0, 96.0, 96.0], "value": 111.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 91.0, 155.0, 94.0, 93.0, 95.0, 93.0, 93.0, 92.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 57.0, 59.0, 59.0, 59.0, 56.0, 129.0, 51.0, 58.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 104.0, 101.0, 71.0, 67.0, 72.0, 82.0, 103.0, 100.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 84.0, 92.0, 85.0, 86.0, 98.0, 112.0, 106.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 174.0, 138.0, 125.0, 122.0, 148.0, 149.0, 248.0, 158.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 164.0, 165.0, 165.0, 166.0, 166.0, 164.0, 164.0, 165.0], "value": 164.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 68.0, 71.0, 83.0, 67.0, 66.0, 64.0, 60.0, 67.0], "value": 67.8, "unit": "score"}]}]} 01:08:20 INFO - Return code: 0 01:08:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:08:20 ERROR - # TBPL SUCCESS # 01:08:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:08:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:08:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:08:20 INFO - 2016-05-19 01:08:20.259 screenresolution[1693:12684] starting screenresolution argv=screenresolution get 01:08:20 INFO - 2016-05-19 01:08:20.262 screenresolution[1693:12684] Display 0: 1600x1200x32@60 01:08:20 INFO - 2016-05-19 01:08:20.267 screenresolution[1694:12687] starting screenresolution argv=screenresolution list 01:08:20 INFO - Available Modes on Display 0 01:08:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:08:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:08:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:08:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:08:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:08:20 INFO - Intel Iris: 01:08:20 INFO - Chipset Model: Intel Iris 01:08:20 INFO - Type: GPU 01:08:20 INFO - Bus: Built-In 01:08:20 INFO - VRAM (Dynamic, Max): 1536 MB 01:08:20 INFO - Vendor: Intel (0x8086) 01:08:20 INFO - Device ID: 0x0a2e 01:08:20 INFO - Revision ID: 0x0009 01:08:20 INFO - Displays: 01:08:20 INFO - Display: 01:08:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:08:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:08:20 INFO - Display Serial Number: 1600x1200 60 01:08:20 INFO - Main Display: Yes 01:08:20 INFO - Mirror: Off 01:08:20 INFO - Online: Yes 01:08:20 INFO - Rotation: Supported 01:08:20 INFO - Return code: 0 01:08:20 INFO - Running post-action listener: _resource_record_post_action 01:08:20 INFO - ##### 01:08:20 INFO - ##### Finished run-tests step (success) 01:08:20 INFO - ##### 01:08:20 INFO - Running post-run listener: _resource_record_post_run 01:08:20 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 32570880; Write bytes: 699308544; Read time: 323; Write time: 2264 01:08:20 INFO - TinderboxPrint: CPU usage
54.3% 01:08:20 INFO - TinderboxPrint: I/O read bytes / time
32,570,880 / 323 01:08:20 INFO - TinderboxPrint: I/O write bytes / time
699,308,544 / 2,264 01:08:20 INFO - TinderboxPrint: CPU idle
2,211.5 (45.7%) 01:08:20 INFO - TinderboxPrint: CPU system
970.7 (20.0%) 01:08:20 INFO - TinderboxPrint: CPU user
1,659.4 (34.3%) 01:08:20 INFO - TinderboxPrint: Swap in / out
424,402,944 / 0 01:08:20 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 396556800; Write bytes: 398067712; Read time: 29686; Write time: 775 01:08:20 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30776832; Write bytes: 300392960; Read time: 304; Write time: 1487 01:08:20 INFO - Running post-run listener: _upload_blobber_files 01:08:20 INFO - Blob upload gear active. 01:08:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:08:20 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 01:08:20 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'] 01:08:20 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 01:08:21 INFO - (blobuploader) - INFO - Open directory for files ... 01:08:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:08:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:08:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:08:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:08:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:08:22 INFO - (blobuploader) - INFO - Done attempting. 01:08:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:08:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:08:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:08:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:08:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:08:23 INFO - (blobuploader) - INFO - Done attempting. 01:08:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:08:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:08:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:08:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:08:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:08:25 INFO - (blobuploader) - INFO - Done attempting. 01:08:25 INFO - (blobuploader) - INFO - Iteration through files over. 01:08:26 INFO - Return code: 0 01:08:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:08:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:08:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fca287995f4b005c2c162de56fe620b49a3adf574d3de0ac5d95d93488cb102613cb2f67f10f3f7aa71e2efbb8a0ff70164f7d1220830ab8fcfaf4f497819859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ff8eedf4e41f6dd7696d90740cc2b90bc8571e4b3e89407758609a7803198e2356458bdbbb29554a4dcaf308497fc0f899584f9639bf13282fb76113dd6e8e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9fa7b59e5d7a01e21ce465105234a8b30dbab00f4a3867d98e58b1e9f6141a6de220a366326b483bec74793b838f9c5a9b61ee76328553520498551feabc7d11"} 01:08:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:08:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:08:26 INFO - Contents: 01:08:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fca287995f4b005c2c162de56fe620b49a3adf574d3de0ac5d95d93488cb102613cb2f67f10f3f7aa71e2efbb8a0ff70164f7d1220830ab8fcfaf4f497819859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ff8eedf4e41f6dd7696d90740cc2b90bc8571e4b3e89407758609a7803198e2356458bdbbb29554a4dcaf308497fc0f899584f9639bf13282fb76113dd6e8e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9fa7b59e5d7a01e21ce465105234a8b30dbab00f4a3867d98e58b1e9f6141a6de220a366326b483bec74793b838f9c5a9b61ee76328553520498551feabc7d11"} 01:08:26 INFO - Running post-run listener: copy_logs_to_upload_dir 01:08:26 INFO - Copying logs to upload dir... 01:08:26 INFO - mkdir: /builds/slave/test/build/upload/logs 01:08:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1300.167973 ========= master_lag: 0.89 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 41 secs) (at 2016-05-19 01:08:26.922106) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 01:08:26.924515) ========= 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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fca287995f4b005c2c162de56fe620b49a3adf574d3de0ac5d95d93488cb102613cb2f67f10f3f7aa71e2efbb8a0ff70164f7d1220830ab8fcfaf4f497819859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ff8eedf4e41f6dd7696d90740cc2b90bc8571e4b3e89407758609a7803198e2356458bdbbb29554a4dcaf308497fc0f899584f9639bf13282fb76113dd6e8e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9fa7b59e5d7a01e21ce465105234a8b30dbab00f4a3867d98e58b1e9f6141a6de220a366326b483bec74793b838f9c5a9b61ee76328553520498551feabc7d11"} build_url:https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010988 build_url: 'https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fca287995f4b005c2c162de56fe620b49a3adf574d3de0ac5d95d93488cb102613cb2f67f10f3f7aa71e2efbb8a0ff70164f7d1220830ab8fcfaf4f497819859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ff8eedf4e41f6dd7696d90740cc2b90bc8571e4b3e89407758609a7803198e2356458bdbbb29554a4dcaf308497fc0f899584f9639bf13282fb76113dd6e8e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9fa7b59e5d7a01e21ce465105234a8b30dbab00f4a3867d98e58b1e9f6141a6de220a366326b483bec74793b838f9c5a9b61ee76328553520498551feabc7d11"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 01:08:26.974397) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-05-19 01:08:26.974754) ========= 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.nY3CKWrxzh/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.nqoAPmkWBq/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.005106 ========= master_lag: 3.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-05-19 01:08:30.079550) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 01:08:30.079929) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 01:08:30.080721) ========= ========= Total master_lag: 4.09 =========