builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0017 starttime: 1464301764.76 results: success (0) buildid: 20160526123752 builduid: f4dfe022ce674f02b5fabed132505126 revision: 6a6155e3afa6148ef93255e6d9e126c2974f0319 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:24.759361) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:24.759730) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:24.759997) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:24.781241) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:24.781511) ========= 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.jwrJw4zz3A/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.LaEM3u1eud/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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-26 15:29:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.86M=0.001s 2016-05-26 15:29:25 (7.86 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.736137 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:25.537175) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:25.537762) ========= 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.jwrJw4zz3A/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.LaEM3u1eud/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.020138 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:25.587608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-26 15:29:25.588093) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6a6155e3afa6148ef93255e6d9e126c2974f0319 --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 6a6155e3afa6148ef93255e6d9e126c2974f0319 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jwrJw4zz3A/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.LaEM3u1eud/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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-26 15:29:25,655 truncating revision to first 12 chars 2016-05-26 15:29:25,655 Setting DEBUG logging. 2016-05-26 15:29:25,655 attempt 1/10 2016-05-26 15:29:25,655 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6a6155e3afa6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 15:29:27,470 unpacking tar archive at: fx-team-6a6155e3afa6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.076877 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-26 15:29:27.689211) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:27.690004) ========= script_repo_revision: 6a6155e3afa6148ef93255e6d9e126c2974f0319 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:27.691018) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:27.691716) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 15:29:27.709687) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-05-26 15:29:27.710349) ========= /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.jwrJw4zz3A/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.LaEM3u1eud/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 15:29:27 INFO - MultiFileLogger online at 20160526 15:29:27 in /builds/slave/test 15:29:27 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 15:29:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:29:27 INFO - {'append_to_log': False, 15:29:27 INFO - 'base_work_dir': '/builds/slave/test', 15:29:27 INFO - 'blob_upload_branch': 'Fx-Team', 15:29:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:29:27 INFO - 'branch': 'Fx-Team', 15:29:27 INFO - 'buildbot_json_path': 'buildprops.json', 15:29:27 INFO - 'config_files': ('talos/mac_config.py',), 15:29:27 INFO - 'default_actions': ('clobber', 15:29:27 INFO - 'read-buildbot-config', 15:29:27 INFO - 'download-and-extract', 15:29:27 INFO - 'populate-webroot', 15:29:27 INFO - 'create-virtualenv', 15:29:27 INFO - 'install', 15:29:27 INFO - 'run-tests'), 15:29:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:29:27 INFO - 'download_minidump_stackwalk': True, 15:29:27 INFO - 'download_symbols': 'ondemand', 15:29:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:29:27 INFO - 'tooltool.py': '/tools/tooltool.py', 15:29:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:29:27 INFO - '/tools/misc-python/virtualenv.py')}, 15:29:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:29:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:29:27 INFO - 'installer_path': 'installer.exe', 15:29:27 INFO - 'log_level': 'info', 15:29:27 INFO - 'log_name': 'talos', 15:29:27 INFO - 'log_to_console': True, 15:29:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:29:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:29:27 INFO - 'opt_config_files': (), 15:29:27 INFO - 'pip_index': False, 15:29:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:29:27 INFO - 'cmd': ('bash', 15:29:27 INFO - '-c', 15:29:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:29:27 INFO - 'enabled': True, 15:29:27 INFO - 'halt_on_failure': False, 15:29:27 INFO - 'name': 'check_screen_resolution'},), 15:29:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:29:27 INFO - 'cmd': ('bash', 15:29:27 INFO - '-c', 15:29:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:29:27 INFO - 'enabled': True, 15:29:27 INFO - 'halt_on_failure': False, 15:29:27 INFO - 'name': 'check_screen_resolution'},), 15:29:27 INFO - 'run_cmd_checks_enabled': True, 15:29:27 INFO - 'sps_profile': False, 15:29:27 INFO - 'sps_profile_interval': 0, 15:29:27 INFO - 'suite': 'dromaeojs-e10s', 15:29:27 INFO - 'system_bits': '32', 15:29:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:29:27 INFO - 'title': 't-yosemite-r7-0017', 15:29:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:29:27 INFO - 'use_talos_json': True, 15:29:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:29:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:29:27 INFO - 'work_dir': 'build'} 15:29:27 INFO - ##### 15:29:27 INFO - ##### Running clobber step. 15:29:27 INFO - ##### 15:29:27 INFO - Running pre-action listener: _resource_record_pre_action 15:29:27 INFO - Running main action method: clobber 15:29:27 INFO - rmtree: /builds/slave/test/build 15:29:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:29:29 INFO - Running post-action listener: _resource_record_post_action 15:29:29 INFO - ##### 15:29:29 INFO - ##### Finished clobber step (success) 15:29:29 INFO - ##### 15:29:29 INFO - ##### 15:29:29 INFO - ##### Running read-buildbot-config step. 15:29:29 INFO - ##### 15:29:29 INFO - Running pre-action listener: _resource_record_pre_action 15:29:29 INFO - Running main action method: read_buildbot_config 15:29:29 INFO - Using buildbot properties: 15:29:29 INFO - { 15:29:29 INFO - "project": "", 15:29:29 INFO - "product": "firefox", 15:29:29 INFO - "script_repo_revision": "production", 15:29:29 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 15:29:29 INFO - "repository": "", 15:29:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 15:29:29 INFO - "buildid": "20160526123752", 15:29:29 INFO - "pgo_build": "False", 15:29:29 INFO - "basedir": "/builds/slave/test", 15:29:29 INFO - "buildnumber": 28, 15:29:29 INFO - "slavename": "t-yosemite-r7-0017", 15:29:29 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:29:29 INFO - "platform": "yosemite_r7", 15:29:29 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 15:29:29 INFO - "branch": "fx-team", 15:29:29 INFO - "repo_path": "integration/fx-team", 15:29:29 INFO - "revision": "6a6155e3afa6148ef93255e6d9e126c2974f0319", 15:29:29 INFO - "stage_platform": "macosx64", 15:29:29 INFO - "builduid": "f4dfe022ce674f02b5fabed132505126", 15:29:29 INFO - "slavebuilddir": "test" 15:29:29 INFO - } 15:29:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:29:29 INFO - Running post-action listener: _resource_record_post_action 15:29:29 INFO - ##### 15:29:29 INFO - ##### Finished read-buildbot-config step (success) 15:29:29 INFO - ##### 15:29:29 INFO - ##### 15:29:29 INFO - ##### Running download-and-extract step. 15:29:29 INFO - ##### 15:29:29 INFO - Running pre-action listener: _resource_record_pre_action 15:29:29 INFO - Running main action method: download_and_extract 15:29:29 INFO - mkdir: /builds/slave/test/build/tests 15:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:29 INFO - https://queue.taskcluster.net/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:29:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:29:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:29:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/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 15:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/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 15:29:39 INFO - Downloaded 1199 bytes. 15:29:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:29:39 INFO - Using the following test package requirements: 15:29:39 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:29:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:39 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:29:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:39 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:29:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:39 INFO - u'jsshell-mac.zip'], 15:29:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:39 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:29:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:29:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:39 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:29:39 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:39 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:29:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:39 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:29:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:39 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:29:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:39 INFO - https://queue.taskcluster.net/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:29:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:29:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:29:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/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 15:29:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/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 15:29:41 INFO - Downloaded 18622113 bytes. 15:29:41 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'] 15:29:41 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 15:29:44 INFO - Return code: 0 15:29:44 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 15:29: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')]} 15:29:44 INFO - https://queue.taskcluster.net/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:29:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:29:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:29:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/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 15:29:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/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 15:29:55 INFO - Downloaded 18622113 bytes. 15:29:55 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'] 15:29:55 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 15:29:58 INFO - Return code: 0 15:29:58 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')]} 15:29:58 INFO - https://queue.taskcluster.net/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:29:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:29:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:29:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/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 15:29:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/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 15:29:59 INFO - Downloaded 10834034 bytes. 15:29:59 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'] 15:29:59 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 15:29:59 INFO - Return code: 0 15:29:59 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')]} 15:29:59 INFO - https://queue.taskcluster.net/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:29:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:29:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:29:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:29:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jmtk3LAARIuFaeqxrdxnVg/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 15:30:03 INFO - Downloaded 110492662 bytes. 15:30:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:30:03 INFO - mkdir: /builds/slave/test/properties 15:30:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:30:03 INFO - Writing to file /builds/slave/test/properties/build_url 15:30:03 INFO - Contents: 15:30:03 INFO - build_url:https://queue.taskcluster.net/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:30:04 INFO - Running post-action listener: _resource_record_post_action 15:30:04 INFO - Running post-action listener: set_extra_try_arguments 15:30:04 INFO - ##### 15:30:04 INFO - ##### Finished download-and-extract step (success) 15:30:04 INFO - ##### 15:30:04 INFO - ##### 15:30:04 INFO - ##### Running populate-webroot step. 15:30:04 INFO - ##### 15:30:04 INFO - Running pre-action listener: _resource_record_pre_action 15:30:04 INFO - Running main action method: populate_webroot 15:30:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:30:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:30:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:30:04 INFO - '--tppagecycles', 15:30:04 INFO - '7'], 15:30:04 INFO - 'tests': ['tsvgm']}}, 15:30:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:30:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:30:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:30:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:30:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:30:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:30:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:04 INFO - 'talos_options': ['--disable-e10s'], 15:30:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:30:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:30:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:04 INFO - 'talos_options': ['--disable-e10s'], 15:30:04 INFO - 'tests': ['damp', 'tps']}, 15:30:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:04 INFO - 'tests': ['damp', 'tps']}, 15:30:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:30:04 INFO - 'tests': ['dromaeo_dom']}, 15:30:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:30:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:30:04 INFO - 'tests': ['a11yr', 15:30:04 INFO - 'ts_paint', 15:30:04 INFO - 'tpaint', 15:30:04 INFO - 'sessionrestore', 15:30:04 INFO - 'sessionrestore_no_auto_restore', 15:30:04 INFO - 'tabpaint']}, 15:30:04 INFO - 'other-e10s': {'tests': ['a11yr', 15:30:04 INFO - 'ts_paint', 15:30:04 INFO - 'tpaint', 15:30:04 INFO - 'sessionrestore', 15:30:04 INFO - 'sessionrestore_no_auto_restore', 15:30:04 INFO - 'tabpaint']}, 15:30:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:30:04 INFO - 'ts_paint', 15:30:04 INFO - 'tpaint', 15:30:04 INFO - 'sessionrestore', 15:30:04 INFO - 'sessionrestore_no_auto_restore', 15:30:04 INFO - 'tabpaint']}, 15:30:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:30:04 INFO - 'ts_paint', 15:30:04 INFO - 'tpaint', 15:30:04 INFO - 'sessionrestore', 15:30:04 INFO - 'sessionrestore_no_auto_restore', 15:30:04 INFO - 'tabpaint']}, 15:30:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:30:04 INFO - 'tests': ['a11yr', 15:30:04 INFO - 'ts_paint', 15:30:04 INFO - 'tpaint', 15:30:04 INFO - 'sessionrestore', 15:30:04 INFO - 'sessionrestore_no_auto_restore', 15:30:04 INFO - 'tabpaint']}, 15:30:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:30:04 INFO - 'tests': ['a11yr', 15:30:04 INFO - 'ts_paint', 15:30:04 INFO - 'tpaint', 15:30:04 INFO - 'sessionrestore', 15:30:04 INFO - 'sessionrestore_no_auto_restore', 15:30:04 INFO - 'tabpaint']}, 15:30:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:30:04 INFO - 'tests': ['tsvgx', 15:30:04 INFO - 'tsvgr_opacity', 15:30:04 INFO - 'tart', 15:30:04 INFO - 'tscrollx', 15:30:04 INFO - 'cart']}, 15:30:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:30:04 INFO - 'tsvgr_opacity', 15:30:04 INFO - 'tart', 15:30:04 INFO - 'tscrollx', 15:30:04 INFO - 'cart']}, 15:30:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:04 INFO - 'talos_options': ['--disable-e10s'], 15:30:04 INFO - 'tests': ['tp5o']}, 15:30:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:04 INFO - 'tests': ['tp5o']}, 15:30:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:04 INFO - 'talos_options': ['--disable-e10s', 15:30:04 INFO - '--xperf_path', 15:30:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:30:04 INFO - 'tests': ['tp5n']}, 15:30:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:04 INFO - 'talos_options': ['--xperf_path', 15:30:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:30:04 INFO - 'tests': ['tp5n']}}, 15:30:04 INFO - 'talos.zip': {'path': '', 15:30:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:30:04 INFO - Running post-action listener: _resource_record_post_action 15:30:04 INFO - ##### 15:30:04 INFO - ##### Finished populate-webroot step (success) 15:30:04 INFO - ##### 15:30:04 INFO - ##### 15:30:04 INFO - ##### Running create-virtualenv step. 15:30:04 INFO - ##### 15:30:04 INFO - Running pre-action listener: _resource_record_pre_action 15:30:04 INFO - Running main action method: create_virtualenv 15:30:04 INFO - Creating virtualenv /builds/slave/test/build/venv 15:30:04 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 15:30:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:30:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:30:04 INFO - Using real prefix '/tools/python27' 15:30:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:30:05 INFO - Installing distribute.............................................................................................................................................................................................done. 15:30:08 INFO - Installing pip.................done. 15:30:08 INFO - Return code: 0 15:30:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:08 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 0x10cbbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb496820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca62d30>, '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 0x10c886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca59b20>, '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.jwrJw4zz3A/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.LaEM3u1eud/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 15:30:08 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 15:30:08 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 15:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwrJw4zz3A/Render', 15:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:08 INFO - 'HOME': '/Users/cltbld', 15:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:08 INFO - 'LOGNAME': 'cltbld', 15:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:08 INFO - 'MOZ_NO_REMOTE': '1', 15:30:08 INFO - 'NO_EM_RESTART': '1', 15:30:08 INFO - 'PAGER': '/bin/cat', 15:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:08 INFO - 'PWD': '/builds/slave/test', 15:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:08 INFO - 'SHELL': '/bin/bash', 15:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LaEM3u1eud/Listeners', 15:30:08 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 15:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:08 INFO - 'USER': 'cltbld', 15:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:08 INFO - 'XPC_FLAGS': '0x0', 15:30:08 INFO - 'XPC_SERVICE_NAME': '0', 15:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:08 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 15:30:08 INFO - Cleaning up... 15:30:08 INFO - Return code: 0 15:30:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:08 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 0x10cbbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb496820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca62d30>, '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 0x10c886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca59b20>, '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.jwrJw4zz3A/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.LaEM3u1eud/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 15:30:08 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 15:30:08 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 15:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwrJw4zz3A/Render', 15:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:08 INFO - 'HOME': '/Users/cltbld', 15:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:08 INFO - 'LOGNAME': 'cltbld', 15:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:08 INFO - 'MOZ_NO_REMOTE': '1', 15:30:08 INFO - 'NO_EM_RESTART': '1', 15:30:08 INFO - 'PAGER': '/bin/cat', 15:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:08 INFO - 'PWD': '/builds/slave/test', 15:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:08 INFO - 'SHELL': '/bin/bash', 15:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LaEM3u1eud/Listeners', 15:30:08 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 15:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:08 INFO - 'USER': 'cltbld', 15:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:08 INFO - 'XPC_FLAGS': '0x0', 15:30:08 INFO - 'XPC_SERVICE_NAME': '0', 15:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:08 INFO - Downloading/unpacking psutil>=3.1.1 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:30:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:30:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:30:11 INFO - Installing collected packages: psutil 15:30:11 INFO - Running setup.py install for psutil 15:30:11 INFO - building 'psutil._psutil_osx' extension 15:30:11 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 15:30:12 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 15:30:12 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 15:30:12 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 15:30:12 INFO - building 'psutil._psutil_posix' extension 15:30:12 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 15:30:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:30:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:30:12 INFO - ^ 15:30:12 INFO - 1 warning generated. 15:30:12 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 15:30:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:30:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:30:12 INFO - Successfully installed psutil 15:30:12 INFO - Cleaning up... 15:30:12 INFO - Return code: 0 15:30:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb496820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca62d30>, '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 0x10c886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca59b20>, '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.jwrJw4zz3A/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.LaEM3u1eud/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 15:30:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:30:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:30:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwrJw4zz3A/Render', 15:30:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:12 INFO - 'HOME': '/Users/cltbld', 15:30:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:12 INFO - 'LOGNAME': 'cltbld', 15:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:12 INFO - 'MOZ_NO_REMOTE': '1', 15:30:12 INFO - 'NO_EM_RESTART': '1', 15:30:12 INFO - 'PAGER': '/bin/cat', 15:30:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:12 INFO - 'PWD': '/builds/slave/test', 15:30:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:12 INFO - 'SHELL': '/bin/bash', 15:30:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LaEM3u1eud/Listeners', 15:30:12 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 15:30:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:12 INFO - 'USER': 'cltbld', 15:30:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:12 INFO - 'XPC_FLAGS': '0x0', 15:30:12 INFO - 'XPC_SERVICE_NAME': '0', 15:30:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:30:15 INFO - Installing collected packages: mozsystemmonitor 15:30:15 INFO - Running setup.py install for mozsystemmonitor 15:30:15 INFO - Successfully installed mozsystemmonitor 15:30:15 INFO - Cleaning up... 15:30:15 INFO - Return code: 0 15:30:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cbbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb496820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca62d30>, '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 0x10c886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca59b20>, '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.jwrJw4zz3A/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.LaEM3u1eud/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 15:30:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:30:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwrJw4zz3A/Render', 15:30:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:15 INFO - 'HOME': '/Users/cltbld', 15:30:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:15 INFO - 'LOGNAME': 'cltbld', 15:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:15 INFO - 'MOZ_NO_REMOTE': '1', 15:30:15 INFO - 'NO_EM_RESTART': '1', 15:30:15 INFO - 'PAGER': '/bin/cat', 15:30:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:15 INFO - 'PWD': '/builds/slave/test', 15:30:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:15 INFO - 'SHELL': '/bin/bash', 15:30:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LaEM3u1eud/Listeners', 15:30:15 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 15:30:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:15 INFO - 'USER': 'cltbld', 15:30:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:15 INFO - 'XPC_FLAGS': '0x0', 15:30:15 INFO - 'XPC_SERVICE_NAME': '0', 15:30:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:15 INFO - Downloading/unpacking blobuploader==1.2.4 15:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:18 INFO - Downloading blobuploader-1.2.4.tar.gz 15:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:30:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:30:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:19 INFO - Downloading docopt-0.6.1.tar.gz 15:30:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:30:19 INFO - Installing collected packages: blobuploader, requests, docopt 15:30:19 INFO - Running setup.py install for blobuploader 15:30:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:30:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:30:19 INFO - Running setup.py install for requests 15:30:20 INFO - Running setup.py install for docopt 15:30:20 INFO - Successfully installed blobuploader requests docopt 15:30:20 INFO - Cleaning up... 15:30:20 INFO - Return code: 0 15:30:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:20 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')]} 15:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:20 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')]} 15:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:20 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 0x10cbbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb496820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca62d30>, '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 0x10c886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca59b20>, '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.jwrJw4zz3A/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.LaEM3u1eud/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 15:30:20 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 15:30:20 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 15:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwrJw4zz3A/Render', 15:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:20 INFO - 'HOME': '/Users/cltbld', 15:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:20 INFO - 'LOGNAME': 'cltbld', 15:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:20 INFO - 'MOZ_NO_REMOTE': '1', 15:30:20 INFO - 'NO_EM_RESTART': '1', 15:30:20 INFO - 'PAGER': '/bin/cat', 15:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:20 INFO - 'PWD': '/builds/slave/test', 15:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:20 INFO - 'SHELL': '/bin/bash', 15:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LaEM3u1eud/Listeners', 15:30:20 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 15:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:20 INFO - 'USER': 'cltbld', 15:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:20 INFO - 'XPC_FLAGS': '0x0', 15:30:20 INFO - 'XPC_SERVICE_NAME': '0', 15:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:20 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-tLVwJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:20 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-7YmKsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:20 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-aeoj9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:21 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-IxY904-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:21 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-PEx5e2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:21 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-F5rN8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:21 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-SDgROX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:21 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-zpDmMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:21 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-aEZgrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:21 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-E9Ryyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:21 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-xakyuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:21 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-u1TNBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:21 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-KL1Wm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:22 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-TUgvDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:22 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-lalvoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:22 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Dm6M73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:22 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-04bame-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:30:22 INFO - Running setup.py install for manifestparser 15:30:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:30:22 INFO - Running setup.py install for mozcrash 15:30:22 INFO - Running setup.py install for mozdebug 15:30:22 INFO - Running setup.py install for mozdevice 15:30:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:30:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:30:23 INFO - Running setup.py install for mozfile 15:30:23 INFO - Running setup.py install for mozhttpd 15:30:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:30:23 INFO - Running setup.py install for mozinfo 15:30:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:30:23 INFO - Running setup.py install for mozInstall 15:30:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:30:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:30:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:30:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:30:23 INFO - Running setup.py install for mozleak 15:30:23 INFO - Running setup.py install for mozlog 15:30:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:30:23 INFO - Running setup.py install for moznetwork 15:30:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:30:24 INFO - Running setup.py install for mozprocess 15:30:24 INFO - Running setup.py install for mozprofile 15:30:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:30:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:30:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:30:24 INFO - Running setup.py install for mozrunner 15:30:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:30:24 INFO - Running setup.py install for mozscreenshot 15:30:24 INFO - Running setup.py install for moztest 15:30:24 INFO - Running setup.py install for mozversion 15:30:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:30:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:30:24 INFO - Cleaning up... 15:30:25 INFO - Return code: 0 15:30:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:25 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')]} 15:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:25 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')]} 15:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:25 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 0x10cbbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb496820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca62d30>, '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 0x10c886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca59b20>, '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.jwrJw4zz3A/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.LaEM3u1eud/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 15:30:25 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 15:30:25 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 15:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwrJw4zz3A/Render', 15:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:25 INFO - 'HOME': '/Users/cltbld', 15:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:25 INFO - 'LOGNAME': 'cltbld', 15:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:25 INFO - 'MOZ_NO_REMOTE': '1', 15:30:25 INFO - 'NO_EM_RESTART': '1', 15:30:25 INFO - 'PAGER': '/bin/cat', 15:30:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:25 INFO - 'PWD': '/builds/slave/test', 15:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:25 INFO - 'SHELL': '/bin/bash', 15:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LaEM3u1eud/Listeners', 15:30:25 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 15:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:25 INFO - 'USER': 'cltbld', 15:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:25 INFO - 'XPC_FLAGS': '0x0', 15:30:25 INFO - 'XPC_SERVICE_NAME': '0', 15:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:25 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-8THF6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:25 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)) 15:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:25 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-gzJ0cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:25 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)) 15:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:25 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-56lTbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:25 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)) 15:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:25 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-0JKYeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:25 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)) 15:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:25 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-2swOCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:25 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)) 15:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:25 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-kWk4nB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:25 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)) 15:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:25 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Unq4li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:26 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)) 15:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-ESvyrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:26 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)) 15:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-h3_nr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:26 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)) 15:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-YTSddn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:26 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)) 15:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-jjGE9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:26 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)) 15:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Gf0zgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:26 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)) 15:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-MdcsyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:26 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)) 15:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Za3rVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:26 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)) 15:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-itPYt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:26 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)) 15:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-UYv_CF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:26 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)) 15:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-bg4BIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:27 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)) 15:30:27 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)) 15:30:27 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)) 15:30:27 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)) 15:30:27 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)) 15:30:27 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)) 15:30:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:29 INFO - Downloading blessings-1.6.tar.gz 15:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:30:29 INFO - Installing collected packages: blessings 15:30:29 INFO - Running setup.py install for blessings 15:30:29 INFO - Successfully installed blessings 15:30:29 INFO - Cleaning up... 15:30:29 INFO - Return code: 0 15:30:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:30:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:30 INFO - Reading from file tmpfile_stdout 15:30:30 INFO - Current package versions: 15:30:30 INFO - blessings == 1.6 15:30:30 INFO - blobuploader == 1.2.4 15:30:30 INFO - docopt == 0.6.1 15:30:30 INFO - manifestparser == 1.1 15:30:30 INFO - mozInstall == 1.12 15:30:30 INFO - mozcrash == 0.17 15:30:30 INFO - mozdebug == 0.1 15:30:30 INFO - mozdevice == 0.48 15:30:30 INFO - mozfile == 1.2 15:30:30 INFO - mozhttpd == 0.7 15:30:30 INFO - mozinfo == 0.9 15:30:30 INFO - mozleak == 0.1 15:30:30 INFO - mozlog == 3.2 15:30:30 INFO - moznetwork == 0.27 15:30:30 INFO - mozprocess == 0.22 15:30:30 INFO - mozprofile == 0.28 15:30:30 INFO - mozrunner == 6.11 15:30:30 INFO - mozscreenshot == 0.1 15:30:30 INFO - mozsystemmonitor == 0.3 15:30:30 INFO - moztest == 0.7 15:30:30 INFO - mozversion == 1.4 15:30:30 INFO - psutil == 3.1.1 15:30:30 INFO - requests == 1.2.3 15:30:30 INFO - wsgiref == 0.1.2 15:30:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:30 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 0x10cbbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb496820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca62d30>, '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 0x10c886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca59b20>, '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.jwrJw4zz3A/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.LaEM3u1eud/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 15:30:30 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 15:30:30 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 15:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwrJw4zz3A/Render', 15:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:30 INFO - 'HOME': '/Users/cltbld', 15:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:30 INFO - 'LOGNAME': 'cltbld', 15:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:30 INFO - 'MOZ_NO_REMOTE': '1', 15:30:30 INFO - 'NO_EM_RESTART': '1', 15:30:30 INFO - 'PAGER': '/bin/cat', 15:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:30 INFO - 'PWD': '/builds/slave/test', 15:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:30 INFO - 'SHELL': '/bin/bash', 15:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LaEM3u1eud/Listeners', 15:30:30 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 15:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:30 INFO - 'USER': 'cltbld', 15:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:30 INFO - 'XPC_FLAGS': '0x0', 15:30:30 INFO - 'XPC_SERVICE_NAME': '0', 15:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:30 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)) 15:30:30 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)) 15:30:30 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)) 15:30:30 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)) 15:30:30 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)) 15:30:30 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)) 15:30:30 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)) 15:30:30 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)) 15:30:30 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)) 15:30:30 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)) 15:30:30 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)) 15:30:30 INFO - Cleaning up... 15:30:30 INFO - Return code: 0 15:30:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb496820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca62d30>, '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 0x10c886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca59b20>, '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.jwrJw4zz3A/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.LaEM3u1eud/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 15:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwrJw4zz3A/Render', 15:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:30 INFO - 'HOME': '/Users/cltbld', 15:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:30 INFO - 'LOGNAME': 'cltbld', 15:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:30 INFO - 'MOZ_NO_REMOTE': '1', 15:30:30 INFO - 'NO_EM_RESTART': '1', 15:30:30 INFO - 'PAGER': '/bin/cat', 15:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:30 INFO - 'PWD': '/builds/slave/test', 15:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:30 INFO - 'SHELL': '/bin/bash', 15:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LaEM3u1eud/Listeners', 15:30:30 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 15:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:30 INFO - 'USER': 'cltbld', 15:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:30 INFO - 'XPC_FLAGS': '0x0', 15:30:30 INFO - 'XPC_SERVICE_NAME': '0', 15:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:31 INFO - Downloading/unpacking jsonschema 15:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:30:34 INFO - Downloading/unpacking functools32 (from jsonschema) 15:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:34 INFO - Downloading functools32-3.2.3-2.tar.gz 15:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:30:34 INFO - warning: no files found matching '*.txt' 15:30:34 INFO - no previously-included directories found matching 'build' 15:30:34 INFO - no previously-included directories found matching 'dist' 15:30:34 INFO - no previously-included directories found matching '.git*' 15:30:34 INFO - Installing collected packages: jsonschema, functools32 15:30:34 INFO - Running setup.py install for functools32 15:30:34 INFO - warning: no files found matching '*.txt' 15:30:34 INFO - no previously-included directories found matching 'build' 15:30:34 INFO - no previously-included directories found matching 'dist' 15:30:34 INFO - no previously-included directories found matching '.git*' 15:30:34 INFO - Successfully installed jsonschema functools32 15:30:34 INFO - Cleaning up... 15:30:34 INFO - Return code: 0 15:30:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:30:34 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')]} 15:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:34 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')]} 15:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:34 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 0x10cbbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb496820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca62d30>, '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 0x10c886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca59b20>, '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.jwrJw4zz3A/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.LaEM3u1eud/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 15:30:34 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 15:30:34 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 15:30:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwrJw4zz3A/Render', 15:30:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:34 INFO - 'HOME': '/Users/cltbld', 15:30:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:34 INFO - 'LOGNAME': 'cltbld', 15:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:34 INFO - 'MOZ_NO_REMOTE': '1', 15:30:34 INFO - 'NO_EM_RESTART': '1', 15:30:34 INFO - 'PAGER': '/bin/cat', 15:30:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:34 INFO - 'PWD': '/builds/slave/test', 15:30:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:34 INFO - 'SHELL': '/bin/bash', 15:30:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LaEM3u1eud/Listeners', 15:30:34 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 15:30:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:34 INFO - 'USER': 'cltbld', 15:30:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:34 INFO - 'XPC_FLAGS': '0x0', 15:30:34 INFO - 'XPC_SERVICE_NAME': '0', 15:30:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:35 INFO - Downloading/unpacking flake8 15:30:35 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 15:30:35 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 15:30:35 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 15:30:35 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 15:30:35 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 15:30:35 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 15:30:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:30:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:30:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:30:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:30:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:30:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:30:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:30:38 INFO - no previously-included directories found matching 'docs/_build' 15:30:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:30:38 INFO - Running setup.py install for pep8 15:30:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:30:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:30:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:30:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:30:38 INFO - no previously-included directories found matching 'docs/_build' 15:30:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:30:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:30:38 INFO - Cleaning up... 15:30:39 INFO - Return code: 0 15:30:39 INFO - Running post-action listener: _resource_record_post_action 15:30:39 INFO - Running post-action listener: _start_resource_monitoring 15:30:39 INFO - Starting resource monitoring. 15:30:39 INFO - ##### 15:30:39 INFO - ##### Finished create-virtualenv step (success) 15:30:39 INFO - ##### 15:30:39 INFO - ##### 15:30:39 INFO - ##### Running install step. 15:30:39 INFO - ##### 15:30:39 INFO - Running pre-action listener: _resource_record_pre_action 15:30:39 INFO - Running main action method: install 15:30:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:39 INFO - Reading from file tmpfile_stdout 15:30:39 INFO - Detecting whether we're running mozinstall >=1.0... 15:30:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:30:39 INFO - Reading from file tmpfile_stdout 15:30:39 INFO - Output received: 15:30:39 INFO - Usage: mozinstall [options] installer 15:30:39 INFO - Options: 15:30:39 INFO - -h, --help show this help message and exit 15:30:39 INFO - -d DEST, --destination=DEST 15:30:39 INFO - Directory to install application into. [default: 15:30:39 INFO - "/builds/slave/test"] 15:30:39 INFO - --app=APP Application being installed. [default: firefox] 15:30:39 INFO - mkdir: /builds/slave/test/build/application 15:30:39 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'] 15:30:39 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 15:31:13 INFO - Reading from file tmpfile_stdout 15:31:13 INFO - Output received: 15:31:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:31:13 INFO - Running post-action listener: _resource_record_post_action 15:31:13 INFO - ##### 15:31:13 INFO - ##### Finished install step (success) 15:31:13 INFO - ##### 15:31:13 INFO - ##### 15:31:13 INFO - ##### Running run-tests step. 15:31:13 INFO - ##### 15:31:13 INFO - Running pre-action listener: _resource_record_pre_action 15:31:13 INFO - Running main action method: run_tests 15:31:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:31:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:31:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:31:13 INFO - 2016-05-26 15:31:13.405 screenresolution[2192:29630] starting screenresolution argv=screenresolution get 15:31:13 INFO - 2016-05-26 15:31:13.408 screenresolution[2192:29630] Display 0: 1600x1200x32@50 15:31:13 INFO - 2016-05-26 15:31:13.414 screenresolution[2193:29632] starting screenresolution argv=screenresolution list 15:31:13 INFO - Available Modes on Display 0 15:31:13 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 15:31:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:31:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:31:13 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 15:31:13 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 15:31:13 INFO - Graphics/Displays: 15:31:13 INFO - Intel Iris: 15:31:13 INFO - Chipset Model: Intel Iris 15:31:13 INFO - Type: GPU 15:31:13 INFO - Bus: Built-In 15:31:13 INFO - VRAM (Dynamic, Max): 1536 MB 15:31:13 INFO - Vendor: Intel (0x8086) 15:31:13 INFO - Device ID: 0x0a2e 15:31:13 INFO - Revision ID: 0x0009 15:31:13 INFO - Displays: 15:31:13 INFO - Display: 15:31:13 INFO - Resolution: 1600 x 1200 @ 50 Hz 15:31:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:31:13 INFO - Display Serial Number: 1600x1200 50 15:31:13 INFO - Main Display: Yes 15:31:13 INFO - Mirror: Off 15:31:13 INFO - Online: Yes 15:31:13 INFO - Rotation: Supported 15:31:13 INFO - Return code: 0 15:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:31:13 INFO - Python 2.7.3 15:31:13 INFO - Return code: 0 15:31:13 INFO - grabbing minidump binary from tooltool 15:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:13 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 0x10ca62d30>, '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 0x10c886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca59b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:31:13 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 15:31:13 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 15:31:13 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 15:31:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:31:13 INFO - Return code: 0 15:31:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:31:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:31:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:31:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:31:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:31:14 INFO - Return code: 0 15:31:14 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/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 15:31:14 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/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0017 --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 15:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwrJw4zz3A/Render', 15:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:14 INFO - 'HOME': '/Users/cltbld', 15:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:14 INFO - 'LOGNAME': 'cltbld', 15:31:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:31:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:14 INFO - 'MOZ_NO_REMOTE': '1', 15:31:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:31:14 INFO - 'NO_EM_RESTART': '1', 15:31:14 INFO - 'PAGER': '/bin/cat', 15:31:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:14 INFO - 'PWD': '/builds/slave/test', 15:31:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:14 INFO - 'SHELL': '/bin/bash', 15:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LaEM3u1eud/Listeners', 15:31:14 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 15:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:14 INFO - 'USER': 'cltbld', 15:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:14 INFO - 'XPC_FLAGS': '0x0', 15:31:14 INFO - 'XPC_SERVICE_NAME': '0', 15:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:14 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/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 15:31:15 INFO - mozversion application_buildid: 20160526123752 15:31:15 INFO - mozversion application_changeset: 6a6155e3afa6148ef93255e6d9e126c2974f0319 15:31:15 INFO - mozversion application_display_name: Nightly 15:31:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:31:15 INFO - mozversion application_name: Firefox 15:31:15 INFO - mozversion application_remotingname: firefox 15:31:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:31:15 INFO - mozversion application_vendor: Mozilla 15:31:15 INFO - mozversion application_version: 49.0a1 15:31:15 INFO - mozversion platform_buildid: 20160526123752 15:31:15 INFO - mozversion platform_changeset: 6a6155e3afa6148ef93255e6d9e126c2974f0319 15:31:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:31:15 INFO - mozversion platform_version: 49.0a1 15:31:15 INFO - using testdate: 1464301875 15:31:15 INFO - actual date: 1464301875 15:31:15 INFO - starting webserver on 'localhost:58977' 15:31:15 INFO - SUITE-START | Running 2 tests 15:31:15 INFO - TEST-START | dromaeo_css 15:31:15 INFO - operating with platform_type : mac_ 15:31:15 INFO - Initialising browser for dromaeo_css test... 15:31:15 INFO - TEST-INFO | started process 2211 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpd_SDQR/profile http://localhost:58977/getInfo.html) 15:31:18 INFO - PROCESS | 2211 | __metrics Screen width/height:1600/1200 15:31:18 INFO - PROCESS | 2211 | colorDepth:24 15:31:18 INFO - PROCESS | 2211 | Browser inner width/height: 1024/689 15:31:18 INFO - PROCESS | 2211 | __metrics 15:31:18 ERROR - PROCESS | 2211 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 15:31:18 INFO - TEST-INFO | 2211: exit 0 15:31:18 INFO - Browser initialized. 15:31:18 INFO - Running cycle 1/1 for dromaeo_css test... 15:31:18 INFO - TEST-INFO | started process 2214 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpd_SDQR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:31:30 INFO - PROCESS | 2214 | RSS: Main: 252473344 15:31:30 INFO - PROCESS | 2214 | 15:32:20 INFO - PROCESS | 2214 | 1464301940575 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] 15:34:31 INFO - PROCESS | 2214 | Cycle 1(1): loaded http://localhost:58977/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58977/tests/dromaeo/cssquery-ext.html) 15:34:32 INFO - PROCESS | 2214 | RSS: Main: 264196096 15:34:32 INFO - PROCESS | 2214 | 15:37:34 INFO - PROCESS | 2214 | Cycle 1(1): loaded http://localhost:58977/tests/dromaeo/cssquery-ext.html (next: http://localhost:58977/tests/dromaeo/cssquery-jquery.html) 15:37:35 INFO - PROCESS | 2214 | RSS: Main: 276201472 15:37:35 INFO - PROCESS | 2214 | 15:40:37 INFO - PROCESS | 2214 | Cycle 1(1): loaded http://localhost:58977/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58977/tests/dromaeo/cssquery-mootools.html) 15:40:37 INFO - PROCESS | 2214 | RSS: Main: 277954560 15:40:37 INFO - PROCESS | 2214 | 15:43:39 INFO - PROCESS | 2214 | Cycle 1(1): loaded http://localhost:58977/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58977/tests/dromaeo/cssquery-prototype.html) 15:43:40 INFO - PROCESS | 2214 | RSS: Main: 279699456 15:43:40 INFO - PROCESS | 2214 | 15:46:43 INFO - PROCESS | 2214 | Cycle 1(1): loaded http://localhost:58977/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58977/tests/dromaeo/cssquery-yui.html) 15:46:43 INFO - PROCESS | 2214 | RSS: Main: 282591232 15:46:43 INFO - PROCESS | 2214 | 15:49:46 INFO - PROCESS | 2214 | Cycle 1(1): loaded http://localhost:58977/tests/dromaeo/cssquery-yui.html (next: http://localhost:58977/tests/dromaeo/cssquery-dojo.html) 15:49:46 INFO - PROCESS | 2214 | RSS: Main: 283574272 15:49:46 INFO - PROCESS | 2214 | 15:49:46 INFO - PROCESS | 2214 | __start_tp_report 15:49:46 INFO - PROCESS | 2214 | _x_x_mozilla_page_load 15:49:46 INFO - PROCESS | 2214 | _x_x_mozilla_page_load_details 15:49:46 INFO - PROCESS | 2214 | |i|pagename|runs| 15:49:46 INFO - PROCESS | 2214 | |0;dojo.html;2993.595864558284;3482.477628355745;3482.763618915285;3496.790192588445;3525.136341596309;5818.767249310028;5910.911336329958;5939.435753603406;5957.672328021957;6006.669633170174;5743.798967036155;5761.395053519383;5762.625429347089;5913.497352725023;5976;5128.99984503023;5176.404713457944;5239.895202095956;5261.76322065508;5279.313689220396;445.59366778441284;5323;5335.573154147678;5363.061464243761;5391.892162156754;5225.320708307933;5226.24219488174;5248.71132087735;5264.736763161845;5270.841874743725;5234.581233501349;5294.205869119624;5323.893522129574;5333.306670132897;5341.359036915556;5252.159654455307;5268.789248430058;5275.235090911835;5297.894042119136;5345.679259244458;4783.425988881322;5647.519960803295;5814.244148260699;5838.4453476919625;5886.881492516451;6120.081455037911;6168.568200225986;6229.221347331583;6258.937410625881;6270.905936410957;6219.222597175353;6344.080108384304;6349.396807303298;6351.745930162788;6375.266844312917;6036.034234522499;6063.969680151615;6081.300650425169;6082.087686846964;6100.328963813977;5363.356397232279;6274.403931626488;6329.68351582419;6460.418562329413;6475.417212450902;6156.6921653917125;6215.160953271341;6223.315435302204;6227.657478838707;6227.906581401283;6320.304766475684;6327.177466929362;6328.11406403104;6332.208473940757;6354;6118.588722523122;6135.44703110649;6160.168377269101;6190.195274614362;6219.564630475823;6137.652792202601;6150.630962142286;6178.752849885954;6192.628442293477;6198.6280823149855;4061.3095773718537;5063.569596584258;5077.416097148801;5087.364079490064;5109.87674544435;3787.791671458041;4501.909961800746;4507.143642707875;4587.403637527167;4626.56047675477;5232.319798426256;5993.4905533029305;6035.577509574287;6045.546584006129;6050.30421501533;6352.237731472253;6386.872262554816;6391.360863913572;6393.200849893763;6412.519061070345;5942.376050514634;6036.426539478743;6048.304444988795;6058.575899687068;6067.24159480065;5583.4137415571595;5593.608447408642;5594.636348637326;5608.186812912105;5620;6372.713227904848;6597.703103360361;6614.768483103068;6627.768028119089;6639.668016599151;6248.500119990481;6258.374162583706;6317.747290108528;6403.583767055126;6422.935770642233;6510.121133646897;6566;6576.210854697276;6611.041398997215;6611.801645950629;5424.484673955968;6147.9855823788575;6168.568200225941;6223.190985171898;6270.0908368287255;6162.845928851635;6293.653849038347;6306.148669929501;6317.526185536011;6328.924082905825;6147.938520614736;6182.783602574068;6193.752249910133;6209.689515524115;6217.347178546368;5059;5704.3154821421695;6118.43644382056;6143.354947730424;6196.256449226122;5920.200772895604;5987.251593550806;6002.489788368038;6061.969690151521;6168.074788781719;6095.725692343944;6153.29237137717;6178.468132816839;6197.504199664107;6200.162977998093;6034.849128771816;6135.754569817336;6152.738688568743;6166.876662466636;6203.379662033941 15:49:46 INFO - PROCESS | 2214 | |1;ext.html;477.39847791782364;518.5644058990448;523.3379774585151;525.4062908912928;565.5221337969415;36999;41315.58684413155;41512.79243603778;41514.792426037864;41581.376279355834;35597;42118.78940605293;42841.78579107108;42843.78578107113;42874.785626071825;8456.704015359464;9505;9750.561224744868;9766.121049105579;9771.95114024429;9634.75913102171;9699;9701.320907536476;9703.95148024259;9733.270004749636;9709.757256068584;9745.31782775206;9762;9762.463064531448;9772.609095636202;9648.276379271547;9698.806023879482;9701.51492425383;9704.417734935856;9722;9582.329236953416;9686.225101991824;9710.90289097107;9721.708348749478;9732.659356922473;31795.20511987219;39103.60896391028;39379.8031009846;39644;39857.80071099626;2874.6119273897966;3067.4785286501346;3071.43178511976;3081.6302043754667;3269.5749552558013;27852.025179118835;28919.277018074506;28953.42093158125;29748.70251297481;29748.70251297481;2659.8271112377633;2691.448253108108;2737.904173353923;2769.9169024929083;2777.264025033368;389744;394505;394929;395111;395535.99999999715;33746.49380259298;34555.82722086424;57538.42461575414;58712;60397.69801150966;32723.672763272538;35809.8209508956;35871.28257434837;35958;35958.74144404933;14831.258437078102;14947.626309342353;14978.251087445584;15002.69994600102;15023.774643380359;12651.17767345138;12702.491900324068;12731.872681273255;12742.980561555054;12756.80864787029;6670.799876003728;7818.569978651115;7871.291583757489;7873.448858579844;7904.486208396436;3332.8500217489786;3342.448495998182;3371.241470669129;3385.830708464567;3413.5903691556678;859.2181115185151;962.9277804164716;969.147150507563;1000.1498726082772;1138.3852719531526;28783;29000.7099928998;29446.705532944823;29587.964421245153;29592;931.9906800931903;1001.399160503692;1037.6108959140322;1058.777656692088;1081.6754973507916;12550.748985020431;13161.868381316064;14924.427325970159;14934.4772932949;14978;707430;712495;716586;718118;719521.4023929847;56914.430855690916;58668;58715.41284587185;58717.82564348689;58938.41061589415;1204.3857632607258;1412.7033323002286;1423.5729281215595;1441.2577522575882;1442.8701416872532;28202.85898570494;28592.714072859006;28623.570646440738;28943.565846511898;29024.564631530968;48934;49484.257736133266;49631.50368496413;49998;50015;586962;590718;592730;594492;594757;25902;25909;26179.345516362242;26261.868690656425;26368.077117300803;44297.55702442934;45291;45611.31583026191;45778.084438311715;46210;36656.45015324719;37250.8137459311;37663.435048473744;37785.62214377821;38141.618583813804;37093.443598345504;37213.81393093017;37476.73689553257;37764.43353349647;37783.81108094442;31858;35957.64042359647;37281.62718372782;37418.812905935294;37533.43699844559;25393.365165871;33040.50439243365;33638.32723345568;34187.829060854536;34230.486542702376;22736;24882.377940551632;25084.74915250824;25422.237332880042;25649.358766030997 15:49:46 INFO - PROCESS | 2214 | |2;jquery.html;381.3593163485344;389.3167491053202;390.1895206243031;390.7538250901931;412.65543271368426;13890.4443822247;18548.629027419443;19164.425067247987;20716.585668286625;20791.16835326587;20133.899330503362;20521.486962825937;20696.793032069676;21370;21453.463663408424;4520.050789334244;5033.2450132480235;5181.715005674687;5298.867528311785;5361.758720857571;4640.327152562876;5189.169732842746;5275.261463395128;7300.342969132777;7625;8007.639656215455;8017.078036025844;8133.593320333989;8171.101178870349;8180.9590952045155;6431;7157.747394206045;8113;8115.634796434145;8138.593070346488;6567.573107747981;6861.176658800976;7622.847543049108;7866.0560732711865;7896.091949425832;28859.42281154386;28990.420191596262;29105.85447072772;30108.397832043236;30307.242318941982;2202.9228976985887;2399.6400539918977;2402.5915594349003;2454.9631755523683;2456;14830.629234269121;15927;16242.350305987746;16843;17182.140892955304;1778.208697129765;1852.657258407199;1923.7595300587427;1928.9131989060527;1973.8124878136555;133635;140063.59936400608;140696;148386;149823.25088374596;17424.651506970044;18833.623327533373;22130.336089917328;22366;22446.887765561067;30217.244568885515;36624.08439788973;36704.81647591745;36790.44814327787;36817.63182368196;10925.344479331265;11160.16298777595;11178.552857885756;11192.104631629612;11309.09527237819;8171.79570510742;8571.400001999926;9100.453972761656;9135.77160570991;9215.63137474507;8232.382571307176;8401.95799021001;8696.652133914698;9636.903630963741;10178;1377.6624726941764;1388.3544151969384;1442.6609746709514;1681.377890180641;3801.581825999138;849.8555245608138;895.9865602015975;1005.0100650858898;1025.7794574166442;1047.5704960965968;16315.918420407823;19432.70850937237;20229.291974780812;20757.377278681663;21746.80392578392;898.2723993565103;947.1475671895349;947.6730527967978;969.2972594868804;985.0691096913491;5321.893562128813;11382.032527235113;11387.08903287735;11410.771784564262;11446.656600302003;567703.1614841899;597534;605398;621428;629512;122129.16806247913;135681;136993;137222;139751;1000;1046.1682962045193;1057.6245432871374;1072.3565860483648;1080.1412876762836;14612.707745845237;18307.816921831145;19206.039698015324;19792;20053.197872084955;48937.51062489329;51781.70545736387;52180.73909630428;52423.73788131035;52824.20763688471;72305.63847180731;72567.27432725605;72715.63642181756;73018.26981730114;74034.62982685052;6618.735250590115;7124.8218794528475;7823;7978.3218426434405;8329.375296852639;11520.135989800967;12898.871011290143;12979.480820767441;13069.411876465772;13422.194668319542;11123.388213648002;11194.496247669038;11408.657740267805;11441.885581144083;11612.883871161519;10575.206859485415;10898.673039808818;11203.943980280046;11405.486753095965;11696.239744416926;10047.447390393598;10749.81752007263;10763.300385474919;11672.066234701033;11680.357580333164;7422.369098626677;7502.662380192795;7508.174100848793;7513.849723005618;7635.389168866366;9584.808303834025;10526.736831578966;11238.494267758135;11600.18798684084;11656.06751459898 15:49:46 INFO - PROCESS | 2214 | |3;mootools.html;1533.1491022482526;1567.012781947373;1790.2749386498472;1894.535838719514;1920.4430715092624;7189.928100718991;7878.6060696965205;8429.831403371929;8914.286857051437;8950.910490895089;8493.830123397529;8534;8697.1302869713;8711.91288087119;8712.695055673054;2329.37106981115;2339.602267614501;2352.4236562042324;2360.0913648245464;2365.325882123595;2347.730011048734;2372.7508611595795;2390.8445951013214;2408.722996855358;2412.7949124324455;2363.1374548289837;2375.1212051540956;2379.7620237976153;2380.785729284364;2389.247387073075;2346.296111166643;2346.9061237550477;2354.1289722802585;2354.658574506704;2380.976190238093;2351.6002279612508;2358.516504116652;2372.5492156490386;2374.5250949810106;2375.180562705864;8660.43707159039;8729.519876406795;8760.211580957744;8771.342149338825;8785.297176225886;4501.639868810487;5130.358705161855;5157.303763991888;5297.8145764898045;5307.336582927134;2999.4900866852686;3318.2533929865826;3385.8984230473125;3402.1494626343415;3464.3937310970477;981.0777868803302;987.4322264697755;994.0854413939121;1004.9045340692622;1021.4943602916604;10929.836052459219;11334.376609286486;11336.433178341133;11417.54329826806;11654.883451165464;4506.729596224237;4556.3621093047;4584.839530616412;4590;4646.163690535668;9062.003179650234;9074.954625226963;9091.909080909238;9101.908980910239;9109.954450227706;5142.717150556755;5173.146430838945;5194.636375453682;5208.869778255574;5210.97394513025;4331.393604895317;4663.720176789404;4834.613230941516;4835.105505481497;4849.199882019498;3282.688144626257;3720.441933709911;3774.9056273592832;3821.3121638104853;3864.7101467390057;1173.8650055243756;1180.7815554122515;1183.8993685536825;1193.6061099837034;1195.2708847602955;850.3494826457764;852.4075767341762;892.4689809563299;900.342749792655;927.4713413354458;5695.515881150066;5720.885582288331;5851.531877449794;5905.911411328833;6044.395560443921;814.4787336104897;945.5130607737021;965.3483898368573;977.9755506112261;1004.1715584642553;3658.158623516606;3939.7242193046786;3972.7020473464604;3981.8009099544906;3992.800359982047;10311.278210525192;10515.84226236607;10663.466826658636;10664.306820056827;10677.466126693635;10518.368897866152;10533.736656583647;10618.787624247472;10641.361518308926;10656.680299591024;7708.306252437195;8149.981252343576;8231.259186673227;8232.753017409488;8241.587920603946;5583.944160558505;5646.604737668324;5671.347795003462;5716.399778023414;5850.707464626837;8600.182982616641;9720.611175552898;9763.85354219673;9923.156531694887;9945.950270248602;9903.504824758877;9904.801903962025;10046.397216167054;10051.849222261819;10074.042965918226;6077.2099627049965;6885.965570172117;7173.10336207974;7253.746118885813;7501.437392195498;8138.552379619177;8241.95879020601;8301.709440169565;8332.416730829027;8342.207490288412;7220.277972202738;7399.112106547249;7402.1117465904435;7487.176410594939;7522.8871566925445;6200.720967556561;7226.277372262942;7276.599787011765;7286.380657644159;7302.634868256672;5990.34106248321;6893.724251029903;6970.686319115754;6988.301169882971;6998.160220773336;4783.186858234039;4818.71087734737;4869.829555965524;4918.2376731606655;5062.265971434048;4858.46556878736;4885.560299573055;4913.778879950482;4992.975035124801;5107.974460127675 15:49:46 INFO - PROCESS | 2214 | |4;prototype.html;260.6676487478465;320.1452122832038;323.5971215836283;324.16365776297147;329.22139140931705;5343.679379237244;5708.486236238738;5745.138229265613;5746.798862039829;5762.3373312069125;5166.974165129169;5571.29792050856;5603.60774745768;5631.352394474637;5636.294578602274;3725.2735716535285;3993.9999999999927;4074.6740260779065;4124.381342798574;4231.259529582326;4170.833166673329;4222.880936551801;4225.774525387634;4244.554321796213;4250.766207858566;4201.39079833424;4226.51395089564;4227.809748561307;4231.576842315759;4252.829886804524;4029.5164580250266;4230.851920182809;4241.4698162729655;4242.512111107231;4252.9367658085475;3004.5042567976257;3617.927641447183;3639.253952939641;3680.35593771089;3689.893032090389;4722.380367362827;5109.463506331856;5370.194470829368;5427.267318911936;5449.6730196188355;4826.34844296019;4831.009643023207;4958.404991401019;4986.301917731521;4987;4749.9762501187615;5953.761849526057;5986.970065149646;5990.573817603878;5995.760169593211;3879.7090218233743;4079.4288799568085;4082.203970225807;4096.160287141159;4100.815463304159;4431.714956541611;5200.479952004769;6253.6247825130185;6298.685065746648;6368.726254749069;3721.776693398405;3845.903917383532;4134.772587507716;4151.4395556600075;4159.438475805728;4200.894977625584;4212.3681447782465;4216.156768646283;4228.70399072062;4232.343986682007;4149.564295748964;4758.214894542431;4787.257975013878;4811.061842940593;4967.7019378837385;5046.293518907356;5190.81832135881;5300.496452836975;5399.946000540023;5688.829335119953;1282.852471965736;1287.0990306785288;1295.8639342868862;1331.7003674173427;1381.2320230105481;897.7620930453435;908.7500937242179;909.7680091576606;927.1562877781187;975.5805003848413;5136.486351364833;5383.703896285742;5562.721863906789;5572.554195664337;5649.0396632572565;5196.791363670425;5677.403872593318;5707.543396528268;5770.46099716132;6025.8192254232445;4576.588107070313;4646.883827904262;5218.530332270114;5522.420145884625;5587.245721827582;2701.6217729517884;3108.9067327980197;3208.358328334296;3212.3414699986447;3259.0548740865356;6011.428914253139;6332.430081292706;6351.174347334817;6377.585456945284;6379.362063793584;5516.806911758069;5589.149533737823;6291.779787707408;6315.873682526324;6335.524835637346;4962.826301079445;5866.6480011199465;6349.650769207646;6378.842472975906;6402.775902843378;4783.067301876101;5899.38056504078;5935.901858156255;5963.4334738199805;6132.5707200495535;6034.336223015377;6050.522613266239;6092.817215483543;6119.552179128206;6158.291796443287;5907.40925907423;6024.487918526975;6320.101921011253;6341.968290158519;6450.580712253689;3358.32083958021;4116.670666346745;4130.586941305845;4138.558250632142;4143.6892233082035;5796.333421656394;6172.2901866284155;6177.660228687286;6185.494874575804;6198.72105755233;4933.777979990838;5542.196381524737;5830.921279563295;5971.7611295547695;6117.877642447215;5256.316678831728;5559.91581641576;6083.574149809648;6118.5009672630495;6151.907721384272;4935.531124543163;5468.863278417913;5649.977262164723;5651.660900345993;5953.434423729739;5159.587233021424;5379.67721936685;5855.560832937632;5985.815177460108;6062.151298818081;5546.473085056913;5968.731407086605;5978.970105149447;5984.9401505986125;5985.820425387245 15:49:46 INFO - PROCESS | 2214 | |5;yui.html;644.5423749138112;664.6477366995495;713.4043074033182;723.2803360656145;726.6403130450428;10250.231232657552;14857.331420086095;14959.626009349759;14997.625059373508;15030.924845375757;13849.307534623278;13870.653233669138;13877.861221387782;13884.305784710774;13893;708.893665950109;881.8765372847806;891.0821853490914;908.8727578139066;911.8541033434652;892.2415946445534;904.4076130134772;907.4328544659588;910.4309806371018;913.3606475467167;938.2869019545127;948.9335746497748;951.9286053545977;954.3081266082104;954.7088138117871;770.6647608290384;899.8110396816709;901.6708901251062;932.6176267730232;944.7118628818208;697.5256825358754;700.9404200642952;706.7561691216522;707.3421717802441;707.5012116457884;13199.20804751718;13359.465621375133;14462.92768536161;14463.638409039753;14528.346224419927;12260.570880019246;12328.32194229317;12363.381830908418;12435.875641243561;12568.811467827898;9678.806423871554;9970.551325190383;10124.240681948882;10147.746306342327;10260.333078349884;3406.9829650851834;3522.771019883725;3546.8935931922083;3620.6198349173483;3705.7961812100325;78726.21273787245;78766.21233787647;80355.59822200851;81167.5941620294;81348.77976830411;7733.226677332222;9308.48803315824;9431.48126853016;9511.429314241168;9522.476263805556;20515.589688206153;20689.58620827605;20801.479963001046;20805.583888322453;20843.687344689843;7189.245129261457;7267.127944646676;7295.817604559928;7384.963075184697;7627.580483073373;3925.646691797752;4025.879223623296;4045.211183819127;4046.3121269384274;4051.453053837753;3431.3137372525593;3483.2511010132944;3520.718342532591;3525;3526.982365088158;612.9019356902963;623.267660498921;628.6699482771581;631.8767840271103;635.7774778827373;545.2094463028625;578.9652620842763;588.7998080652599;600.1208229943066;605.9151718759377;7813.882791758128;7945.960270198612;8186.713465028695;8190.467619604707;8322.70870519529;530.655074201772;545.4191286280098;562.0781917655049;569.6610516742531;571.1803561888703;3540.504329393887;3588.8743893963588;3837.2901013312626;3908.3746600543777;4039.9798001010395;78352.21647783564;82863.17136828555;83098.58450707828;84164;84198.5790071058;73090.26909730835;73650;74925;75294;76336.61831690806;51314;66510;66585.00122498166;66947.33052669508;67411.66294168498;7530.887036694564;7570.848583028199;7710.11333696632;7786.883196752166;7912.604369781603;33644.66355336415;37429.064273392294;38780;40975.795121025396;42727.5727242736;56861.715691421276;57595.71202143963;57688;58406;58943;10837.729056773329;10872.130229581457;10935.17986151026;11236.213465057368;11740.178187526792;8655.48067115975;10299.897001030193;11505.884941150482;11748.823767643322;12162.513499459923;11329.830052549383;11385.772284554429;11570.710732231424;11706.824397634213;11728.179027467995;11568.595099171489;11752.353620550952;11755.823662645238;11899.702507437383;12323.44544495482;10908.29468320141;11051.944740276247;11251.887481125084;11269.436528173395;11289.322640641587;9377.953110234404;9638.614455421704;9716.805663886824;9730.513474326113;10586.153107751208;7373.594452305175;9504.61981520759;9679.032096790264;9679.564419600994;9704.2721795867 15:49:46 INFO - PROCESS | 2214 | __end_tp_report 15:49:46 INFO - PROCESS | 2214 | __start_cc_report 15:49:46 INFO - PROCESS | 2214 | _x_x_mozilla_cycle_collect,422 15:49:46 INFO - PROCESS | 2214 | __end_cc_report 15:49:46 INFO - PROCESS | 2214 | __startTimestamp1464302986681__endTimestamp 15:49:46 INFO - PROCESS | 2214 | 15:49:46 INFO - PROCESS | 2214 | ------- Summary: start ------- 15:49:46 INFO - PROCESS | 2214 | Number of tests: 6 15:49:46 INFO - PROCESS | 2214 | 15:49:46 INFO - PROCESS | 2214 | [#0] dojo.html Cycles:165 Average:5793.64 Median:6082.09 stddev:797.02 (13.1%) stddev-sans-first:768.60 15:49:46 INFO - PROCESS | 2214 | Values: 2993.6 3482.5 3482.8 3496.8 3525.1 5818.8 5910.9 5939.4 5957.7 6006.7 5743.8 5761.4 5762.6 5913.5 5976.0 5129.0 5176.4 5239.9 5261.8 5279.3 445.6 5323.0 5335.6 5363.1 5391.9 5225.3 5226.2 5248.7 5264.7 5270.8 5234.6 5294.2 5323.9 5333.3 5341.4 5252.2 5268.8 5275.2 5297.9 5345.7 4783.4 5647.5 5814.2 5838.4 5886.9 6120.1 6168.6 6229.2 6258.9 6270.9 6219.2 6344.1 6349.4 6351.7 6375.3 6036.0 6064.0 6081.3 6082.1 6100.3 5363.4 6274.4 6329.7 6460.4 6475.4 6156.7 6215.2 6223.3 6227.7 6227.9 6320.3 6327.2 6328.1 6332.2 6354.0 6118.6 6135.4 6160.2 6190.2 6219.6 6137.7 6150.6 6178.8 6192.6 6198.6 4061.3 5063.6 5077.4 5087.4 5109.9 3787.8 4501.9 4507.1 4587.4 4626.6 5232.3 5993.5 6035.6 6045.5 6050.3 6352.2 6386.9 6391.4 6393.2 6412.5 5942.4 6036.4 6048.3 6058.6 6067.2 5583.4 5593.6 5594.6 5608.2 5620.0 6372.7 6597.7 6614.8 6627.8 6639.7 6248.5 6258.4 6317.7 6403.6 6422.9 6510.1 6566.0 6576.2 6611.0 6611.8 5424.5 6148.0 6168.6 6223.2 6270.1 6162.8 6293.7 6306.1 6317.5 6328.9 6147.9 6182.8 6193.8 6209.7 6217.3 5059.0 5704.3 6118.4 6143.4 6196.3 5920.2 5987.3 6002.5 6062.0 6168.1 6095.7 6153.3 6178.5 6197.5 6200.2 6034.8 6135.8 6152.7 6166.9 6203.4 15:49:46 INFO - PROCESS | 2214 | 15:49:46 INFO - PROCESS | 2214 | [#1] ext.html Cycles:180 Average:68054.11 Median:37598.44 stddev:156474.22 (416.2%) stddev-sans-first:156830.91 15:49:46 INFO - PROCESS | 2214 | Values: 477.4 518.6 523.3 525.4 565.5 36999.0 41315.6 41512.8 41514.8 41581.4 35597.0 42118.8 42841.8 42843.8 42874.8 8456.7 9505.0 9750.6 9766.1 9772.0 9634.8 9699.0 9701.3 9704.0 9733.3 9709.8 9745.3 9762.0 9762.5 9772.6 9648.3 9698.8 9701.5 9704.4 9722.0 9582.3 9686.2 9710.9 9721.7 9732.7 31795.2 39103.6 39379.8 39644.0 39857.8 2874.6 3067.5 3071.4 3081.6 3269.6 27852.0 28919.3 28953.4 29748.7 29748.7 2659.8 2691.4 2737.9 2769.9 2777.3 389744.0 394505.0 394929.0 395111.0 395536.0 33746.5 34555.8 57538.4 58712.0 60397.7 32723.7 35809.8 35871.3 35958.0 35958.7 14831.3 14947.6 14978.3 15002.7 15023.8 12651.2 12702.5 12731.9 12743.0 12756.8 6670.8 7818.6 7871.3 7873.4 7904.5 3332.9 3342.4 3371.2 3385.8 3413.6 859.2 962.9 969.1 1000.1 1138.4 28783.0 29000.7 29446.7 29588.0 29592.0 932.0 1001.4 1037.6 1058.8 1081.7 12550.7 13161.9 14924.4 14934.5 14978.0 707430.0 712495.0 716586.0 718118.0 719521.4 56914.4 58668.0 58715.4 58717.8 58938.4 1204.4 1412.7 1423.6 1441.3 1442.9 28202.9 28592.7 28623.6 28943.6 29024.6 48934.0 49484.3 49631.5 49998.0 50015.0 586962.0 590718.0 592730.0 594492.0 594757.0 25902.0 25909.0 26179.3 26261.9 26368.1 44297.6 45291.0 45611.3 45778.1 46210.0 36656.5 37250.8 37663.4 37785.6 38141.6 37093.4 37213.8 37476.7 37764.4 37783.8 31858.0 35957.6 37281.6 37418.8 37533.4 25393.4 33040.5 33638.3 34187.8 34230.5 22736.0 24882.4 25084.7 25422.2 25649.4 15:49:46 INFO - PROCESS | 2214 | 15:49:46 INFO - PROCESS | 2214 | [#2] jquery.html Cycles:180 Average:37229.35 Median:22406.44 stddev:101381.37 (452.5%) stddev-sans-first:101628.02 15:49:46 INFO - PROCESS | 2214 | Values: 381.4 389.3 390.2 390.8 412.7 13890.4 18548.6 19164.4 20716.6 20791.2 20133.9 20521.5 20696.8 21370.0 21453.5 4520.1 5033.2 5181.7 5298.9 5361.8 4640.3 5189.2 5275.3 7300.3 7625.0 8007.6 8017.1 8133.6 8171.1 8181.0 6431.0 7157.7 8113.0 8115.6 8138.6 6567.6 6861.2 7622.8 7866.1 7896.1 28859.4 28990.4 29105.9 30108.4 30307.2 2202.9 2399.6 2402.6 2455.0 2456.0 14830.6 15927.0 16242.4 16843.0 17182.1 1778.2 1852.7 1923.8 1928.9 1973.8 133635.0 140063.6 140696.0 148386.0 149823.3 17424.7 18833.6 22130.3 22366.0 22446.9 30217.2 36624.1 36704.8 36790.4 36817.6 10925.3 11160.2 11178.6 11192.1 11309.1 8171.8 8571.4 9100.5 9135.8 9215.6 8232.4 8402.0 8696.7 9636.9 10178.0 1377.7 1388.4 1442.7 1681.4 3801.6 849.9 896.0 1005.0 1025.8 1047.6 16315.9 19432.7 20229.3 20757.4 21746.8 898.3 947.1 947.7 969.3 985.1 5321.9 11382.0 11387.1 11410.8 11446.7 567703.2 597534.0 605398.0 621428.0 629512.0 122129.2 135681.0 136993.0 137222.0 139751.0 1000.0 1046.2 1057.6 1072.4 1080.1 14612.7 18307.8 19206.0 19792.0 20053.2 48937.5 51781.7 52180.7 52423.7 52824.2 72305.6 72567.3 72715.6 73018.3 74034.6 6618.7 7124.8 7823.0 7978.3 8329.4 11520.1 12898.9 12979.5 13069.4 13422.2 11123.4 11194.5 11408.7 11441.9 11612.9 10575.2 10898.7 11203.9 11405.5 11696.2 10047.4 10749.8 10763.3 11672.1 11680.4 7422.4 7502.7 7508.2 7513.8 7635.4 9584.8 10526.7 11238.5 11600.2 11656.1 15:49:46 INFO - PROCESS | 2214 | 15:49:46 INFO - PROCESS | 2214 | [#3] mootools.html Cycles:180 Average:5578.79 Median:5085.12 stddev:3111.83 (61.2%) stddev-sans-first:3105.71 15:49:46 INFO - PROCESS | 2214 | Values: 1533.1 1567.0 1790.3 1894.5 1920.4 7189.9 7878.6 8429.8 8914.3 8950.9 8493.8 8534.0 8697.1 8711.9 8712.7 2329.4 2339.6 2352.4 2360.1 2365.3 2347.7 2372.8 2390.8 2408.7 2412.8 2363.1 2375.1 2379.8 2380.8 2389.2 2346.3 2346.9 2354.1 2354.7 2381.0 2351.6 2358.5 2372.5 2374.5 2375.2 8660.4 8729.5 8760.2 8771.3 8785.3 4501.6 5130.4 5157.3 5297.8 5307.3 2999.5 3318.3 3385.9 3402.1 3464.4 981.1 987.4 994.1 1004.9 1021.5 10929.8 11334.4 11336.4 11417.5 11654.9 4506.7 4556.4 4584.8 4590.0 4646.2 9062.0 9075.0 9091.9 9101.9 9110.0 5142.7 5173.1 5194.6 5208.9 5211.0 4331.4 4663.7 4834.6 4835.1 4849.2 3282.7 3720.4 3774.9 3821.3 3864.7 1173.9 1180.8 1183.9 1193.6 1195.3 850.3 852.4 892.5 900.3 927.5 5695.5 5720.9 5851.5 5905.9 6044.4 814.5 945.5 965.3 978.0 1004.2 3658.2 3939.7 3972.7 3981.8 3992.8 10311.3 10515.8 10663.5 10664.3 10677.5 10518.4 10533.7 10618.8 10641.4 10656.7 7708.3 8150.0 8231.3 8232.8 8241.6 5583.9 5646.6 5671.3 5716.4 5850.7 8600.2 9720.6 9763.9 9923.2 9946.0 9903.5 9904.8 10046.4 10051.8 10074.0 6077.2 6886.0 7173.1 7253.7 7501.4 8138.6 8242.0 8301.7 8332.4 8342.2 7220.3 7399.1 7402.1 7487.2 7522.9 6200.7 7226.3 7276.6 7286.4 7302.6 5990.3 6893.7 6970.7 6988.3 6998.2 4783.2 4818.7 4869.8 4918.2 5062.3 4858.5 4885.6 4913.8 4993.0 5108.0 15:49:46 INFO - PROCESS | 2214 | 15:49:46 INFO - PROCESS | 2214 | [#4] prototype.html Cycles:180 Average:4783.33 Median:5356.94 stddev:1486.70 (27.8%) stddev-sans-first:1451.60 15:49:46 INFO - PROCESS | 2214 | Values: 260.7 320.1 323.6 324.2 329.2 5343.7 5708.5 5745.1 5746.8 5762.3 5167.0 5571.3 5603.6 5631.4 5636.3 3725.3 3994.0 4074.7 4124.4 4231.3 4170.8 4222.9 4225.8 4244.6 4250.8 4201.4 4226.5 4227.8 4231.6 4252.8 4029.5 4230.9 4241.5 4242.5 4252.9 3004.5 3617.9 3639.3 3680.4 3689.9 4722.4 5109.5 5370.2 5427.3 5449.7 4826.3 4831.0 4958.4 4986.3 4987.0 4750.0 5953.8 5987.0 5990.6 5995.8 3879.7 4079.4 4082.2 4096.2 4100.8 4431.7 5200.5 6253.6 6298.7 6368.7 3721.8 3845.9 4134.8 4151.4 4159.4 4200.9 4212.4 4216.2 4228.7 4232.3 4149.6 4758.2 4787.3 4811.1 4967.7 5046.3 5190.8 5300.5 5399.9 5688.8 1282.9 1287.1 1295.9 1331.7 1381.2 897.8 908.8 909.8 927.2 975.6 5136.5 5383.7 5562.7 5572.6 5649.0 5196.8 5677.4 5707.5 5770.5 6025.8 4576.6 4646.9 5218.5 5522.4 5587.2 2701.6 3108.9 3208.4 3212.3 3259.1 6011.4 6332.4 6351.2 6377.6 6379.4 5516.8 5589.1 6291.8 6315.9 6335.5 4962.8 5866.6 6349.7 6378.8 6402.8 4783.1 5899.4 5935.9 5963.4 6132.6 6034.3 6050.5 6092.8 6119.6 6158.3 5907.4 6024.5 6320.1 6342.0 6450.6 3358.3 4116.7 4130.6 4138.6 4143.7 5796.3 6172.3 6177.7 6185.5 6198.7 4933.8 5542.2 5830.9 5971.8 6117.9 5256.3 5559.9 6083.6 6118.5 6151.9 4935.5 5468.9 5650.0 5651.7 5953.4 5159.6 5379.7 5855.6 5985.8 6062.2 5546.5 5968.7 5979.0 5984.9 5985.8 15:49:46 INFO - PROCESS | 2214 | 15:49:46 INFO - PROCESS | 2214 | [#5] yui.html Cycles:180 Average:17003.37 Median:29765.90 stddev:23506.38 (79.0%) stddev-sans-first:23540.22 15:49:46 INFO - PROCESS | 2214 | Values: 644.5 664.6 713.4 723.3 726.6 10250.2 14857.3 14959.6 14997.6 15030.9 13849.3 13870.7 13877.9 13884.3 13893.0 708.9 881.9 891.1 908.9 911.9 892.2 904.4 907.4 910.4 913.4 938.3 948.9 951.9 954.3 954.7 770.7 899.8 901.7 932.6 944.7 697.5 700.9 706.8 707.3 707.5 13199.2 13359.5 14462.9 14463.6 14528.3 12260.6 12328.3 12363.4 12435.9 12568.8 9678.8 9970.6 10124.2 10147.7 10260.3 3407.0 3522.8 3546.9 3620.6 3705.8 78726.2 78766.2 80355.6 81167.6 81348.8 7733.2 9308.5 9431.5 9511.4 9522.5 20515.6 20689.6 20801.5 20805.6 20843.7 7189.2 7267.1 7295.8 7385.0 7627.6 3925.6 4025.9 4045.2 4046.3 4051.5 3431.3 3483.3 3520.7 3525.0 3527.0 612.9 623.3 628.7 631.9 635.8 545.2 579.0 588.8 600.1 605.9 7813.9 7946.0 8186.7 8190.5 8322.7 530.7 545.4 562.1 569.7 571.2 3540.5 3588.9 3837.3 3908.4 4040.0 78352.2 82863.2 83098.6 84164.0 84198.6 73090.3 73650.0 74925.0 75294.0 76336.6 51314.0 66510.0 66585.0 66947.3 67411.7 7530.9 7570.8 7710.1 7786.9 7912.6 33644.7 37429.1 38780.0 40975.8 42727.6 56861.7 57595.7 57688.0 58406.0 58943.0 10837.7 10872.1 10935.2 11236.2 11740.2 8655.5 10299.9 11505.9 11748.8 12162.5 11329.8 11385.8 11570.7 11706.8 11728.2 11568.6 11752.4 11755.8 11899.7 12323.4 10908.3 11051.9 11251.9 11269.4 11289.3 9378.0 9638.6 9716.8 9730.5 10586.2 7373.6 9504.6 9679.0 9679.6 9704.3 15:49:46 INFO - PROCESS | 2214 | -------- Summary: end -------- 15:49:46 INFO - PROCESS | 2214 | 15:49:47 INFO - TEST-INFO | 2214: exit 0 15:49:47 INFO - TEST-OK | dromaeo_css | took 1112245ms 15:49:47 INFO - TEST-START | kraken 15:49:47 INFO - operating with platform_type : mac_ 15:49:47 INFO - Initialising browser for kraken test... 15:49:47 INFO - TEST-INFO | started process 2220 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpvA8fVz/profile http://localhost:58977/getInfo.html) 15:49:50 INFO - PROCESS | 2220 | __metrics Screen width/height:1600/1200 15:49:50 INFO - PROCESS | 2220 | colorDepth:24 15:49:50 INFO - PROCESS | 2220 | Browser inner width/height: 1024/689 15:49:50 INFO - PROCESS | 2220 | __metrics 15:49:50 ERROR - PROCESS | 2220 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 15:49:50 INFO - TEST-INFO | 2220: exit 0 15:49:50 INFO - Browser initialized. 15:49:50 INFO - Running cycle 1/1 for kraken test... 15:49:50 INFO - TEST-INFO | started process 2222 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpvA8fVz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:50:03 INFO - PROCESS | 2222 | RSS: Main: 252309504 15:50:03 INFO - PROCESS | 2222 | 15:50:05 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58977/tests/kraken/driver.html?testName=audio-beat-detection) 15:50:06 INFO - PROCESS | 2222 | RSS: Main: 264073216 15:50:06 INFO - PROCESS | 2222 | 15:50:09 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58977/tests/kraken/driver.html?testName=audio-dft) 15:50:10 INFO - PROCESS | 2222 | RSS: Main: 268947456 15:50:10 INFO - PROCESS | 2222 | 15:50:13 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58977/tests/kraken/driver.html?testName=audio-fft) 15:50:14 INFO - PROCESS | 2222 | RSS: Main: 269144064 15:50:14 INFO - PROCESS | 2222 | 15:50:16 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58977/tests/kraken/driver.html?testName=audio-oscillator) 15:50:16 INFO - PROCESS | 2222 | RSS: Main: 269864960 15:50:16 INFO - PROCESS | 2222 | 15:50:18 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58977/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:50:19 INFO - PROCESS | 2222 | RSS: Main: 270479360 15:50:19 INFO - PROCESS | 2222 | 15:50:22 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58977/tests/kraken/driver.html?testName=imaging-darkroom) 15:50:22 INFO - PROCESS | 2222 | RSS: Main: 271118336 15:50:22 INFO - PROCESS | 2222 | 15:50:25 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58977/tests/kraken/driver.html?testName=imaging-desaturate) 15:50:26 INFO - PROCESS | 2222 | RSS: Main: 271306752 15:50:26 INFO - PROCESS | 2222 | 15:50:29 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58977/tests/kraken/driver.html?testName=json-parse-financial) 15:50:30 INFO - PROCESS | 2222 | RSS: Main: 271495168 15:50:30 INFO - PROCESS | 2222 | 15:50:31 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58977/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:50:32 INFO - PROCESS | 2222 | RSS: Main: 270786560 15:50:32 INFO - PROCESS | 2222 | 15:50:34 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58977/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:50:35 INFO - PROCESS | 2222 | RSS: Main: 270852096 15:50:35 INFO - PROCESS | 2222 | 15:50:37 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58977/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:50:38 INFO - PROCESS | 2222 | RSS: Main: 271396864 15:50:38 INFO - PROCESS | 2222 | 15:50:40 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58977/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:50:41 INFO - PROCESS | 2222 | RSS: Main: 271372288 15:50:41 INFO - PROCESS | 2222 | 15:50:44 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58977/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:50:45 INFO - PROCESS | 2222 | RSS: Main: 271638528 15:50:45 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:58977/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58977/tests/kraken/driver.html?testName=ai-astar) 15:50:46 INFO - PROCESS | 2222 | RSS: Main: 273244160 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | __start_tp_report 15:50:46 INFO - PROCESS | 2222 | _x_x_mozilla_page_load 15:50:46 INFO - PROCESS | 2222 | _x_x_mozilla_page_load_details 15:50:46 INFO - PROCESS | 2222 | |i|pagename|runs| 15:50:46 INFO - PROCESS | 2222 | |0;ai-astar;113;111;115;117;119;116;130;167;120;137 15:50:46 INFO - PROCESS | 2222 | |1;audio-beat-detection;159;123;151;196;121;143;140;106;193;138 15:50:46 INFO - PROCESS | 2222 | |2;audio-dft;208;250;250;200;228;227;197;196;194;198 15:50:46 INFO - PROCESS | 2222 | |3;audio-fft;77;96;75;143;56;57;58;60;131;58 15:50:46 INFO - PROCESS | 2222 | |4;audio-oscillator;70;77;136;81;97;85;118;70;72;117 15:50:46 INFO - PROCESS | 2222 | |5;imaging-gaussian-blur;89;107;87;85;92;86;76;130;134;97 15:50:46 INFO - PROCESS | 2222 | |6;imaging-darkroom;107;136;96;99;93;118;154;96;95;95 15:50:46 INFO - PROCESS | 2222 | |7;imaging-desaturate;90;102;132;94;150;110;142;148;151;90 15:50:46 INFO - PROCESS | 2222 | |8;json-parse-financial;58;60;60;58;60;60;59;78;62;59 15:50:46 INFO - PROCESS | 2222 | |9;json-stringify-tinderbox;70;95;67;97;117;110;84;71;68;71 15:50:46 INFO - PROCESS | 2222 | |10;stanford-crypto-aes;99;91;86;99;111;107;85;88;88;89 15:50:46 INFO - PROCESS | 2222 | |11;stanford-crypto-ccm;138;141;133;171;143;126;178;131;182;149 15:50:46 INFO - PROCESS | 2222 | |12;stanford-crypto-pbkdf2;167;171;166;175;168;162;166;165;161;166 15:50:46 INFO - PROCESS | 2222 | |13;stanford-crypto-sha256-iterative;68;66;68;64;67;65;65;64;68;65 15:50:46 INFO - PROCESS | 2222 | __end_tp_report 15:50:46 INFO - PROCESS | 2222 | __start_cc_report 15:50:46 INFO - PROCESS | 2222 | _x_x_mozilla_cycle_collect,1078 15:50:46 INFO - PROCESS | 2222 | __end_cc_report 15:50:46 INFO - PROCESS | 2222 | __startTimestamp1464303046978__endTimestamp 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | ------- Summary: start ------- 15:50:46 INFO - PROCESS | 2222 | Number of tests: 14 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#0] ai-astar Cycles:10 Average:124.50 Median:119.50 stddev:16.92 (14.2%) stddev-sans-first:17.43 15:50:46 INFO - PROCESS | 2222 | Values: 113.0 111.0 115.0 117.0 119.0 116.0 130.0 167.0 120.0 137.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:147.00 stddev:29.36 (20.0%) stddev-sans-first:30.81 15:50:46 INFO - PROCESS | 2222 | Values: 159.0 123.0 151.0 196.0 121.0 143.0 140.0 106.0 193.0 138.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#2] audio-dft Cycles:10 Average:214.80 Median:217.50 stddev:22.24 (10.2%) stddev-sans-first:23.45 15:50:46 INFO - PROCESS | 2222 | Values: 208.0 250.0 250.0 200.0 228.0 227.0 197.0 196.0 194.0 198.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#3] audio-fft Cycles:10 Average:81.10 Median:59.00 stddev:32.18 (54.5%) stddev-sans-first:34.10 15:50:46 INFO - PROCESS | 2222 | Values: 77.0 96.0 75.0 143.0 56.0 57.0 58.0 60.0 131.0 58.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:74.50 stddev:23.63 (31.7%) stddev-sans-first:23.64 15:50:46 INFO - PROCESS | 2222 | Values: 70.0 77.0 136.0 81.0 97.0 85.0 118.0 70.0 72.0 117.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#5] imaging-gaussian-blur Cycles:10 Average:98.30 Median:86.50 stddev:19.54 (22.6%) stddev-sans-first:20.43 15:50:46 INFO - PROCESS | 2222 | Values: 89.0 107.0 87.0 85.0 92.0 86.0 76.0 130.0 134.0 97.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:95.00 stddev:20.88 (22.0%) stddev-sans-first:22.14 15:50:46 INFO - PROCESS | 2222 | Values: 107.0 136.0 96.0 99.0 93.0 118.0 154.0 96.0 95.0 95.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#7] imaging-desaturate Cycles:10 Average:120.90 Median:150.50 stddev:26.17 (17.4%) stddev-sans-first:25.26 15:50:46 INFO - PROCESS | 2222 | Values: 90.0 102.0 132.0 94.0 150.0 110.0 142.0 148.0 151.0 90.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:60.00 stddev:5.95 (9.9%) stddev-sans-first:6.18 15:50:46 INFO - PROCESS | 2222 | Values: 58.0 60.0 60.0 58.0 60.0 60.0 59.0 78.0 62.0 59.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#9] json-stringify-tinderbox Cycles:10 Average:85.00 Median:71.00 stddev:18.63 (26.2%) stddev-sans-first:18.95 15:50:46 INFO - PROCESS | 2222 | Values: 70.0 95.0 67.0 97.0 117.0 110.0 84.0 71.0 68.0 71.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#10] stanford-crypto-aes Cycles:10 Average:94.30 Median:88.50 stddev:9.18 (10.4%) stddev-sans-first:9.58 15:50:46 INFO - PROCESS | 2222 | Values: 99.0 91.0 86.0 99.0 111.0 107.0 85.0 88.0 88.0 89.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:146.00 stddev:20.39 (14.0%) stddev-sans-first:21.23 15:50:46 INFO - PROCESS | 2222 | Values: 138.0 141.0 133.0 171.0 143.0 126.0 178.0 131.0 182.0 149.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:166.50 stddev:4.06 (2.4%) stddev-sans-first:4.30 15:50:46 INFO - PROCESS | 2222 | Values: 167.0 171.0 166.0 175.0 168.0 162.0 166.0 165.0 161.0 166.0 15:50:46 INFO - PROCESS | 2222 | 15:50:46 INFO - PROCESS | 2222 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:1.63 (2.5%) stddev-sans-first:1.56 15:50:46 INFO - PROCESS | 2222 | Values: 68.0 66.0 68.0 64.0 67.0 65.0 65.0 64.0 68.0 65.0 15:50:46 INFO - PROCESS | 2222 | -------- Summary: end -------- 15:50:46 INFO - PROCESS | 2222 | 15:50:47 INFO - TEST-INFO | 2222: exit 0 15:50:47 INFO - TEST-OK | kraken | took 60430ms 15:50:47 INFO - SUITE-END | took 1172s 15:50:48 INFO - Completed test suite (00:19:33) 15:50:48 INFO - javascript benchmark 15:50:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7233.767051614937, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2993.595864558284, 3482.477628355745, 3482.763618915285, 3496.790192588445, 3525.136341596309, 5818.767249310028, 5910.911336329958, 5939.435753603406, 5957.672328021957, 6006.669633170174, 5743.798967036155, 5761.395053519383, 5762.625429347089, 5913.497352725023, 5976.0, 5128.99984503023, 5176.404713457944, 5239.895202095956, 5261.76322065508, 5279.313689220396, 445.59366778441284, 5323.0, 5335.573154147678, 5363.061464243761, 5391.892162156754, 5225.320708307933, 5226.24219488174, 5248.71132087735, 5264.736763161845, 5270.841874743725, 5234.581233501349, 5294.205869119624, 5323.893522129574, 5333.306670132897, 5341.359036915556, 5252.159654455307, 5268.789248430058, 5275.235090911835, 5297.894042119136, 5345.679259244458, 4783.425988881322, 5647.519960803295, 5814.244148260699, 5838.4453476919625, 5886.881492516451, 6120.081455037911, 6168.568200225986, 6229.221347331583, 6258.937410625881, 6270.905936410957, 6219.222597175353, 6344.080108384304, 6349.396807303298, 6351.745930162788, 6375.266844312917, 6036.034234522499, 6063.969680151615, 6081.300650425169, 6082.087686846964, 6100.328963813977, 5363.356397232279, 6274.403931626488, 6329.68351582419, 6460.418562329413, 6475.417212450902, 6156.6921653917125, 6215.160953271341, 6223.315435302204, 6227.657478838707, 6227.906581401283, 6320.304766475684, 6327.177466929362, 6328.11406403104, 6332.208473940757, 6354.0, 6118.588722523122, 6135.44703110649, 6160.168377269101, 6190.195274614362, 6219.564630475823, 6137.652792202601, 6150.630962142286, 6178.752849885954, 6192.628442293477, 6198.6280823149855, 4061.3095773718537, 5063.569596584258, 5077.416097148801, 5087.364079490064, 5109.87674544435, 3787.791671458041, 4501.909961800746, 4507.143642707875, 4587.403637527167, 4626.56047675477, 5232.319798426256, 5993.4905533029305, 6035.577509574287, 6045.546584006129, 6050.30421501533, 6352.237731472253, 6386.872262554816, 6391.360863913572, 6393.200849893763, 6412.519061070345, 5942.376050514634, 6036.426539478743, 6048.304444988795, 6058.575899687068, 6067.24159480065, 5583.4137415571595, 5593.608447408642, 5594.636348637326, 5608.186812912105, 5620.0, 6372.713227904848, 6597.703103360361, 6614.768483103068, 6627.768028119089, 6639.668016599151, 6248.500119990481, 6258.374162583706, 6317.747290108528, 6403.583767055126, 6422.935770642233, 6510.121133646897, 6566.0, 6576.210854697276, 6611.041398997215, 6611.801645950629, 5424.484673955968, 6147.9855823788575, 6168.568200225941, 6223.190985171898, 6270.0908368287255, 6162.845928851635, 6293.653849038347, 6306.148669929501, 6317.526185536011, 6328.924082905825, 6147.938520614736, 6182.783602574068, 6193.752249910133, 6209.689515524115, 6217.347178546368, 5059.0, 5704.3154821421695, 6118.43644382056, 6143.354947730424, 6196.256449226122, 5920.200772895604, 5987.251593550806, 6002.489788368038, 6061.969690151521, 6168.074788781719, 6095.725692343944, 6153.29237137717, 6178.468132816839, 6197.504199664107, 6200.162977998093, 6034.849128771816, 6135.754569817336, 6152.738688568743, 6166.876662466636, 6203.379662033941], "value": 5744.34960111769, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.39847791782364, 518.5644058990448, 523.3379774585151, 525.4062908912928, 565.5221337969415, 36999.0, 41315.58684413155, 41512.79243603778, 41514.792426037864, 41581.376279355834, 35597.0, 42118.78940605293, 42841.78579107108, 42843.78578107113, 42874.785626071825, 8456.704015359464, 9505.0, 9750.561224744868, 9766.121049105579, 9771.95114024429, 9634.75913102171, 9699.0, 9701.320907536476, 9703.95148024259, 9733.270004749636, 9709.757256068584, 9745.31782775206, 9762.0, 9762.463064531448, 9772.609095636202, 9648.276379271547, 9698.806023879482, 9701.51492425383, 9704.417734935856, 9722.0, 9582.329236953416, 9686.225101991824, 9710.90289097107, 9721.708348749478, 9732.659356922473, 31795.20511987219, 39103.60896391028, 39379.8031009846, 39644.0, 39857.80071099626, 2874.6119273897966, 3067.4785286501346, 3071.43178511976, 3081.6302043754667, 3269.5749552558013, 27852.025179118835, 28919.277018074506, 28953.42093158125, 29748.70251297481, 29748.70251297481, 2659.8271112377633, 2691.448253108108, 2737.904173353923, 2769.9169024929083, 2777.264025033368, 389744.0, 394505.0, 394929.0, 395111.0, 395535.99999999715, 33746.49380259298, 34555.82722086424, 57538.42461575414, 58712.0, 60397.69801150966, 32723.672763272538, 35809.8209508956, 35871.28257434837, 35958.0, 35958.74144404933, 14831.258437078102, 14947.626309342353, 14978.251087445584, 15002.69994600102, 15023.774643380359, 12651.17767345138, 12702.491900324068, 12731.872681273255, 12742.980561555054, 12756.80864787029, 6670.799876003728, 7818.569978651115, 7871.291583757489, 7873.448858579844, 7904.486208396436, 3332.8500217489786, 3342.448495998182, 3371.241470669129, 3385.830708464567, 3413.5903691556678, 859.2181115185151, 962.9277804164716, 969.147150507563, 1000.1498726082772, 1138.3852719531526, 28783.0, 29000.7099928998, 29446.705532944823, 29587.964421245153, 29592.0, 931.9906800931903, 1001.399160503692, 1037.6108959140322, 1058.777656692088, 1081.6754973507916, 12550.748985020431, 13161.868381316064, 14924.427325970159, 14934.4772932949, 14978.0, 707430.0, 712495.0, 716586.0, 718118.0, 719521.4023929847, 56914.430855690916, 58668.0, 58715.41284587185, 58717.82564348689, 58938.41061589415, 1204.3857632607258, 1412.7033323002286, 1423.5729281215595, 1441.2577522575882, 1442.8701416872532, 28202.85898570494, 28592.714072859006, 28623.570646440738, 28943.565846511898, 29024.564631530968, 48934.0, 49484.257736133266, 49631.50368496413, 49998.0, 50015.0, 586962.0, 590718.0, 592730.0, 594492.0, 594757.0, 25902.0, 25909.0, 26179.345516362242, 26261.868690656425, 26368.077117300803, 44297.55702442934, 45291.0, 45611.31583026191, 45778.084438311715, 46210.0, 36656.45015324719, 37250.8137459311, 37663.435048473744, 37785.62214377821, 38141.618583813804, 37093.443598345504, 37213.81393093017, 37476.73689553257, 37764.43353349647, 37783.81108094442, 31858.0, 35957.64042359647, 37281.62718372782, 37418.812905935294, 37533.43699844559, 25393.365165871, 33040.50439243365, 33638.32723345568, 34187.829060854536, 34230.486542702376, 22736.0, 24882.377940551632, 25084.74915250824, 25422.237332880042, 25649.358766030997], "value": 18174.599041067224, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.3593163485344, 389.3167491053202, 390.1895206243031, 390.7538250901931, 412.65543271368426, 13890.4443822247, 18548.629027419443, 19164.425067247987, 20716.585668286625, 20791.16835326587, 20133.899330503362, 20521.486962825937, 20696.793032069676, 21370.0, 21453.463663408424, 4520.050789334244, 5033.2450132480235, 5181.715005674687, 5298.867528311785, 5361.758720857571, 4640.327152562876, 5189.169732842746, 5275.261463395128, 7300.342969132777, 7625.0, 8007.639656215455, 8017.078036025844, 8133.593320333989, 8171.101178870349, 8180.9590952045155, 6431.0, 7157.747394206045, 8113.0, 8115.634796434145, 8138.593070346488, 6567.573107747981, 6861.176658800976, 7622.847543049108, 7866.0560732711865, 7896.091949425832, 28859.42281154386, 28990.420191596262, 29105.85447072772, 30108.397832043236, 30307.242318941982, 2202.9228976985887, 2399.6400539918977, 2402.5915594349003, 2454.9631755523683, 2456.0, 14830.629234269121, 15927.0, 16242.350305987746, 16843.0, 17182.140892955304, 1778.208697129765, 1852.657258407199, 1923.7595300587427, 1928.9131989060527, 1973.8124878136555, 133635.0, 140063.59936400608, 140696.0, 148386.0, 149823.25088374596, 17424.651506970044, 18833.623327533373, 22130.336089917328, 22366.0, 22446.887765561067, 30217.244568885515, 36624.08439788973, 36704.81647591745, 36790.44814327787, 36817.63182368196, 10925.344479331265, 11160.16298777595, 11178.552857885756, 11192.104631629612, 11309.09527237819, 8171.79570510742, 8571.400001999926, 9100.453972761656, 9135.77160570991, 9215.63137474507, 8232.382571307176, 8401.95799021001, 8696.652133914698, 9636.903630963741, 10178.0, 1377.6624726941764, 1388.3544151969384, 1442.6609746709514, 1681.377890180641, 3801.581825999138, 849.8555245608138, 895.9865602015975, 1005.0100650858898, 1025.7794574166442, 1047.5704960965968, 16315.918420407823, 19432.70850937237, 20229.291974780812, 20757.377278681663, 21746.80392578392, 898.2723993565103, 947.1475671895349, 947.6730527967978, 969.2972594868804, 985.0691096913491, 5321.893562128813, 11382.032527235113, 11387.08903287735, 11410.771784564262, 11446.656600302003, 567703.1614841899, 597534.0, 605398.0, 621428.0, 629512.0, 122129.16806247913, 135681.0, 136993.0, 137222.0, 139751.0, 1000.0, 1046.1682962045193, 1057.6245432871374, 1072.3565860483648, 1080.1412876762836, 14612.707745845237, 18307.816921831145, 19206.039698015324, 19792.0, 20053.197872084955, 48937.51062489329, 51781.70545736387, 52180.73909630428, 52423.73788131035, 52824.20763688471, 72305.63847180731, 72567.27432725605, 72715.63642181756, 73018.26981730114, 74034.62982685052, 6618.735250590115, 7124.8218794528475, 7823.0, 7978.3218426434405, 8329.375296852639, 11520.135989800967, 12898.871011290143, 12979.480820767441, 13069.411876465772, 13422.194668319542, 11123.388213648002, 11194.496247669038, 11408.657740267805, 11441.885581144083, 11612.883871161519, 10575.206859485415, 10898.673039808818, 11203.943980280046, 11405.486753095965, 11696.239744416926, 10047.447390393598, 10749.81752007263, 10763.300385474919, 11672.066234701033, 11680.357580333164, 7422.369098626677, 7502.662380192795, 7508.174100848793, 7513.849723005618, 7635.389168866366, 9584.808303834025, 10526.736831578966, 11238.494267758135, 11600.18798684084, 11656.06751459898], "value": 10656.176953212427, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1533.1491022482526, 1567.012781947373, 1790.2749386498472, 1894.535838719514, 1920.4430715092624, 7189.928100718991, 7878.6060696965205, 8429.831403371929, 8914.286857051437, 8950.910490895089, 8493.830123397529, 8534.0, 8697.1302869713, 8711.91288087119, 8712.695055673054, 2329.37106981115, 2339.602267614501, 2352.4236562042324, 2360.0913648245464, 2365.325882123595, 2347.730011048734, 2372.7508611595795, 2390.8445951013214, 2408.722996855358, 2412.7949124324455, 2363.1374548289837, 2375.1212051540956, 2379.7620237976153, 2380.785729284364, 2389.247387073075, 2346.296111166643, 2346.9061237550477, 2354.1289722802585, 2354.658574506704, 2380.976190238093, 2351.6002279612508, 2358.516504116652, 2372.5492156490386, 2374.5250949810106, 2375.180562705864, 8660.43707159039, 8729.519876406795, 8760.211580957744, 8771.342149338825, 8785.297176225886, 4501.639868810487, 5130.358705161855, 5157.303763991888, 5297.8145764898045, 5307.336582927134, 2999.4900866852686, 3318.2533929865826, 3385.8984230473125, 3402.1494626343415, 3464.3937310970477, 981.0777868803302, 987.4322264697755, 994.0854413939121, 1004.9045340692622, 1021.4943602916604, 10929.836052459219, 11334.376609286486, 11336.433178341133, 11417.54329826806, 11654.883451165464, 4506.729596224237, 4556.3621093047, 4584.839530616412, 4590.0, 4646.163690535668, 9062.003179650234, 9074.954625226963, 9091.909080909238, 9101.908980910239, 9109.954450227706, 5142.717150556755, 5173.146430838945, 5194.636375453682, 5208.869778255574, 5210.97394513025, 4331.393604895317, 4663.720176789404, 4834.613230941516, 4835.105505481497, 4849.199882019498, 3282.688144626257, 3720.441933709911, 3774.9056273592832, 3821.3121638104853, 3864.7101467390057, 1173.8650055243756, 1180.7815554122515, 1183.8993685536825, 1193.6061099837034, 1195.2708847602955, 850.3494826457764, 852.4075767341762, 892.4689809563299, 900.342749792655, 927.4713413354458, 5695.515881150066, 5720.885582288331, 5851.531877449794, 5905.911411328833, 6044.395560443921, 814.4787336104897, 945.5130607737021, 965.3483898368573, 977.9755506112261, 1004.1715584642553, 3658.158623516606, 3939.7242193046786, 3972.7020473464604, 3981.8009099544906, 3992.800359982047, 10311.278210525192, 10515.84226236607, 10663.466826658636, 10664.306820056827, 10677.466126693635, 10518.368897866152, 10533.736656583647, 10618.787624247472, 10641.361518308926, 10656.680299591024, 7708.306252437195, 8149.981252343576, 8231.259186673227, 8232.753017409488, 8241.587920603946, 5583.944160558505, 5646.604737668324, 5671.347795003462, 5716.399778023414, 5850.707464626837, 8600.182982616641, 9720.611175552898, 9763.85354219673, 9923.156531694887, 9945.950270248602, 9903.504824758877, 9904.801903962025, 10046.397216167054, 10051.849222261819, 10074.042965918226, 6077.2099627049965, 6885.965570172117, 7173.10336207974, 7253.746118885813, 7501.437392195498, 8138.552379619177, 8241.95879020601, 8301.709440169565, 8332.416730829027, 8342.207490288412, 7220.277972202738, 7399.112106547249, 7402.1117465904435, 7487.176410594939, 7522.8871566925445, 6200.720967556561, 7226.277372262942, 7276.599787011765, 7286.380657644159, 7302.634868256672, 5990.34106248321, 6893.724251029903, 6970.686319115754, 6988.301169882971, 6998.160220773336, 4783.186858234039, 4818.71087734737, 4869.829555965524, 4918.2376731606655, 5062.265971434048, 4858.46556878736, 4885.560299573055, 4913.778879950482, 4992.975035124801, 5107.974460127675], "value": 4496.763713129187, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.6676487478465, 320.1452122832038, 323.5971215836283, 324.16365776297147, 329.22139140931705, 5343.679379237244, 5708.486236238738, 5745.138229265613, 5746.798862039829, 5762.3373312069125, 5166.974165129169, 5571.29792050856, 5603.60774745768, 5631.352394474637, 5636.294578602274, 3725.2735716535285, 3993.9999999999927, 4074.6740260779065, 4124.381342798574, 4231.259529582326, 4170.833166673329, 4222.880936551801, 4225.774525387634, 4244.554321796213, 4250.766207858566, 4201.39079833424, 4226.51395089564, 4227.809748561307, 4231.576842315759, 4252.829886804524, 4029.5164580250266, 4230.851920182809, 4241.4698162729655, 4242.512111107231, 4252.9367658085475, 3004.5042567976257, 3617.927641447183, 3639.253952939641, 3680.35593771089, 3689.893032090389, 4722.380367362827, 5109.463506331856, 5370.194470829368, 5427.267318911936, 5449.6730196188355, 4826.34844296019, 4831.009643023207, 4958.404991401019, 4986.301917731521, 4987.0, 4749.9762501187615, 5953.761849526057, 5986.970065149646, 5990.573817603878, 5995.760169593211, 3879.7090218233743, 4079.4288799568085, 4082.203970225807, 4096.160287141159, 4100.815463304159, 4431.714956541611, 5200.479952004769, 6253.6247825130185, 6298.685065746648, 6368.726254749069, 3721.776693398405, 3845.903917383532, 4134.772587507716, 4151.4395556600075, 4159.438475805728, 4200.894977625584, 4212.3681447782465, 4216.156768646283, 4228.70399072062, 4232.343986682007, 4149.564295748964, 4758.214894542431, 4787.257975013878, 4811.061842940593, 4967.7019378837385, 5046.293518907356, 5190.81832135881, 5300.496452836975, 5399.946000540023, 5688.829335119953, 1282.852471965736, 1287.0990306785288, 1295.8639342868862, 1331.7003674173427, 1381.2320230105481, 897.7620930453435, 908.7500937242179, 909.7680091576606, 927.1562877781187, 975.5805003848413, 5136.486351364833, 5383.703896285742, 5562.721863906789, 5572.554195664337, 5649.0396632572565, 5196.791363670425, 5677.403872593318, 5707.543396528268, 5770.46099716132, 6025.8192254232445, 4576.588107070313, 4646.883827904262, 5218.530332270114, 5522.420145884625, 5587.245721827582, 2701.6217729517884, 3108.9067327980197, 3208.358328334296, 3212.3414699986447, 3259.0548740865356, 6011.428914253139, 6332.430081292706, 6351.174347334817, 6377.585456945284, 6379.362063793584, 5516.806911758069, 5589.149533737823, 6291.779787707408, 6315.873682526324, 6335.524835637346, 4962.826301079445, 5866.6480011199465, 6349.650769207646, 6378.842472975906, 6402.775902843378, 4783.067301876101, 5899.38056504078, 5935.901858156255, 5963.4334738199805, 6132.5707200495535, 6034.336223015377, 6050.522613266239, 6092.817215483543, 6119.552179128206, 6158.291796443287, 5907.40925907423, 6024.487918526975, 6320.101921011253, 6341.968290158519, 6450.580712253689, 3358.32083958021, 4116.670666346745, 4130.586941305845, 4138.558250632142, 4143.6892233082035, 5796.333421656394, 6172.2901866284155, 6177.660228687286, 6185.494874575804, 6198.72105755233, 4933.777979990838, 5542.196381524737, 5830.921279563295, 5971.7611295547695, 6117.877642447215, 5256.316678831728, 5559.91581641576, 6083.574149809648, 6118.5009672630495, 6151.907721384272, 4935.531124543163, 5468.863278417913, 5649.977262164723, 5651.660900345993, 5953.434423729739, 5159.587233021424, 5379.67721936685, 5855.560832937632, 5985.815177460108, 6062.151298818081, 5546.473085056913, 5968.731407086605, 5978.970105149447, 5984.9401505986125, 5985.820425387245], "value": 4303.040600688426, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.5423749138112, 664.6477366995495, 713.4043074033182, 723.2803360656145, 726.6403130450428, 10250.231232657552, 14857.331420086095, 14959.626009349759, 14997.625059373508, 15030.924845375757, 13849.307534623278, 13870.653233669138, 13877.861221387782, 13884.305784710774, 13893.0, 708.893665950109, 881.8765372847806, 891.0821853490914, 908.8727578139066, 911.8541033434652, 892.2415946445534, 904.4076130134772, 907.4328544659588, 910.4309806371018, 913.3606475467167, 938.2869019545127, 948.9335746497748, 951.9286053545977, 954.3081266082104, 954.7088138117871, 770.6647608290384, 899.8110396816709, 901.6708901251062, 932.6176267730232, 944.7118628818208, 697.5256825358754, 700.9404200642952, 706.7561691216522, 707.3421717802441, 707.5012116457884, 13199.20804751718, 13359.465621375133, 14462.92768536161, 14463.638409039753, 14528.346224419927, 12260.570880019246, 12328.32194229317, 12363.381830908418, 12435.875641243561, 12568.811467827898, 9678.806423871554, 9970.551325190383, 10124.240681948882, 10147.746306342327, 10260.333078349884, 3406.9829650851834, 3522.771019883725, 3546.8935931922083, 3620.6198349173483, 3705.7961812100325, 78726.21273787245, 78766.21233787647, 80355.59822200851, 81167.5941620294, 81348.77976830411, 7733.226677332222, 9308.48803315824, 9431.48126853016, 9511.429314241168, 9522.476263805556, 20515.589688206153, 20689.58620827605, 20801.479963001046, 20805.583888322453, 20843.687344689843, 7189.245129261457, 7267.127944646676, 7295.817604559928, 7384.963075184697, 7627.580483073373, 3925.646691797752, 4025.879223623296, 4045.211183819127, 4046.3121269384274, 4051.453053837753, 3431.3137372525593, 3483.2511010132944, 3520.718342532591, 3525.0, 3526.982365088158, 612.9019356902963, 623.267660498921, 628.6699482771581, 631.8767840271103, 635.7774778827373, 545.2094463028625, 578.9652620842763, 588.7998080652599, 600.1208229943066, 605.9151718759377, 7813.882791758128, 7945.960270198612, 8186.713465028695, 8190.467619604707, 8322.70870519529, 530.655074201772, 545.4191286280098, 562.0781917655049, 569.6610516742531, 571.1803561888703, 3540.504329393887, 3588.8743893963588, 3837.2901013312626, 3908.3746600543777, 4039.9798001010395, 78352.21647783564, 82863.17136828555, 83098.58450707828, 84164.0, 84198.5790071058, 73090.26909730835, 73650.0, 74925.0, 75294.0, 76336.61831690806, 51314.0, 66510.0, 66585.00122498166, 66947.33052669508, 67411.66294168498, 7530.887036694564, 7570.848583028199, 7710.11333696632, 7786.883196752166, 7912.604369781603, 33644.66355336415, 37429.064273392294, 38780.0, 40975.795121025396, 42727.5727242736, 56861.715691421276, 57595.71202143963, 57688.0, 58406.0, 58943.0, 10837.729056773329, 10872.130229581457, 10935.17986151026, 11236.213465057368, 11740.178187526792, 8655.48067115975, 10299.897001030193, 11505.884941150482, 11748.823767643322, 12162.513499459923, 11329.830052549383, 11385.772284554429, 11570.710732231424, 11706.824397634213, 11728.179027467995, 11568.595099171489, 11752.353620550952, 11755.823662645238, 11899.702507437383, 12323.44544495482, 10908.29468320141, 11051.944740276247, 11251.887481125084, 11269.436528173395, 11289.322640641587, 9377.953110234404, 9638.614455421704, 9716.805663886824, 9730.513474326113, 10586.153107751208, 7373.594452305175, 9504.61981520759, 9679.032096790264, 9679.564419600994, 9704.2721795867], "value": 6655.208935555061, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1610.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 115.0, 117.0, 119.0, 116.0, 130.0, 167.0, 120.0, 137.0], "value": 124.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 123.0, 151.0, 196.0, 121.0, 143.0, 140.0, 106.0, 193.0, 138.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 250.0, 250.0, 200.0, 228.0, 227.0, 197.0, 196.0, 194.0, 198.0], "value": 214.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 96.0, 75.0, 143.0, 56.0, 57.0, 58.0, 60.0, 131.0, 58.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 136.0, 81.0, 97.0, 85.0, 118.0, 70.0, 72.0, 117.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 107.0, 87.0, 85.0, 92.0, 86.0, 76.0, 130.0, 134.0, 97.0], "value": 98.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 136.0, 96.0, 99.0, 93.0, 118.0, 154.0, 96.0, 95.0, 95.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 102.0, 132.0, 94.0, 150.0, 110.0, 142.0, 148.0, 151.0, 90.0], "value": 120.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 60.0, 58.0, 60.0, 60.0, 59.0, 78.0, 62.0, 59.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 95.0, 67.0, 97.0, 117.0, 110.0, 84.0, 71.0, 68.0, 71.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 91.0, 86.0, 99.0, 111.0, 107.0, 85.0, 88.0, 88.0, 89.0], "value": 94.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 133.0, 171.0, 143.0, 126.0, 178.0, 131.0, 182.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 171.0, 166.0, 175.0, 168.0, 162.0, 166.0, 165.0, 161.0, 166.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 68.0, 64.0, 67.0, 65.0, 65.0, 64.0, 68.0, 65.0], "value": 66.0, "unit": "score"}]}]} 15:50:48 INFO - Return code: 0 15:50:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:50:48 ERROR - # TBPL SUCCESS # 15:50:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:50:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:50:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:50:48 INFO - 2016-05-26 15:50:48.291 screenresolution[2227:32135] starting screenresolution argv=screenresolution get 15:50:48 INFO - 2016-05-26 15:50:48.294 screenresolution[2227:32135] Display 0: 1600x1200x32@50 15:50:48 INFO - 2016-05-26 15:50:48.300 screenresolution[2228:32138] starting screenresolution argv=screenresolution list 15:50:48 INFO - Available Modes on Display 0 15:50:48 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 15:50:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:50:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:50:48 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 15:50:48 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 15:50:48 INFO - Graphics/Displays: 15:50:48 INFO - Intel Iris: 15:50:48 INFO - Chipset Model: Intel Iris 15:50:48 INFO - Type: GPU 15:50:48 INFO - Bus: Built-In 15:50:48 INFO - VRAM (Dynamic, Max): 1536 MB 15:50:48 INFO - Vendor: Intel (0x8086) 15:50:48 INFO - Device ID: 0x0a2e 15:50:48 INFO - Revision ID: 0x0009 15:50:48 INFO - Displays: 15:50:48 INFO - Display: 15:50:48 INFO - Resolution: 1600 x 1200 @ 50 Hz 15:50:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:50:48 INFO - Display Serial Number: 1600x1200 50 15:50:48 INFO - Main Display: Yes 15:50:48 INFO - Mirror: Off 15:50:48 INFO - Online: Yes 15:50:48 INFO - Rotation: Supported 15:50:48 INFO - Return code: 0 15:50:48 INFO - Running post-action listener: _resource_record_post_action 15:50:48 INFO - ##### 15:50:48 INFO - ##### Finished run-tests step (success) 15:50:48 INFO - ##### 15:50:48 INFO - Running post-run listener: _resource_record_post_run 15:50:48 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 892928; Write bytes: 694576128; Read time: 7; Write time: 2135 15:50:48 INFO - TinderboxPrint: CPU usage
54.5% 15:50:48 INFO - TinderboxPrint: I/O read bytes / time
892,928 / 7 15:50:48 INFO - TinderboxPrint: I/O write bytes / time
694,576,128 / 2,135 15:50:48 INFO - TinderboxPrint: CPU idle
2,199.9 (45.5%) 15:50:48 INFO - TinderboxPrint: CPU system
973.8 (20.1%) 15:50:48 INFO - TinderboxPrint: CPU user
1,659.9 (34.3%) 15:50:48 INFO - TinderboxPrint: Swap in / out
397,856,768 / 0 15:50:48 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 393052672; Write bytes: 387401728; Read time: 29683; Write time: 706 15:50:48 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 892928; Write bytes: 293968896; Read time: 7; Write time: 1389 15:50:49 INFO - Running post-run listener: _upload_blobber_files 15:50:49 INFO - Blob upload gear active. 15:50:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:50:49 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 15:50:49 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'] 15:50:49 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 15:50:49 INFO - (blobuploader) - INFO - Open directory for files ... 15:50:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:50:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:50:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:52 INFO - (blobuploader) - INFO - Done attempting. 15:50:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:50:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:50:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:53 INFO - (blobuploader) - INFO - Done attempting. 15:50:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:50:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:50:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:55 INFO - (blobuploader) - INFO - Done attempting. 15:50:55 INFO - (blobuploader) - INFO - Iteration through files over. 15:50:55 INFO - Return code: 0 15:50:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:50:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:50:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f9b952710c038f8e9da1e773768c6df4feec71fbc768a13f6cfc0ada5f930203e477df2efc4887281942a7bd19fc8742ddb21bb13c68c525303930eb34558cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae85d993490f9c42bd95bcb4f9c5a486b2a20d658f72e8d446db5f2b2709bac5af5b019f0ff7938a7e3be154ca09576308c21ad9243373d318c5dbc03c9ced35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2db8622918ed3e7856be00f5bb07cccdf6c2b02200d2fef3f64959ca7c3da2ac43a5be38d11de27a2958951882e4354aae65a2184edd0053f4f6a1c5cb977c7b"} 15:50:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:50:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:50:55 INFO - Contents: 15:50:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f9b952710c038f8e9da1e773768c6df4feec71fbc768a13f6cfc0ada5f930203e477df2efc4887281942a7bd19fc8742ddb21bb13c68c525303930eb34558cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae85d993490f9c42bd95bcb4f9c5a486b2a20d658f72e8d446db5f2b2709bac5af5b019f0ff7938a7e3be154ca09576308c21ad9243373d318c5dbc03c9ced35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2db8622918ed3e7856be00f5bb07cccdf6c2b02200d2fef3f64959ca7c3da2ac43a5be38d11de27a2958951882e4354aae65a2184edd0053f4f6a1c5cb977c7b"} 15:50:55 INFO - Running post-run listener: copy_logs_to_upload_dir 15:50:55 INFO - Copying logs to upload dir... 15:50:55 INFO - mkdir: /builds/slave/test/build/upload/logs 15:50:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.993915 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-05-26 15:50:55.747873) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 15:50:55.751031) ========= 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.jwrJw4zz3A/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.LaEM3u1eud/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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/7f9b952710c038f8e9da1e773768c6df4feec71fbc768a13f6cfc0ada5f930203e477df2efc4887281942a7bd19fc8742ddb21bb13c68c525303930eb34558cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae85d993490f9c42bd95bcb4f9c5a486b2a20d658f72e8d446db5f2b2709bac5af5b019f0ff7938a7e3be154ca09576308c21ad9243373d318c5dbc03c9ced35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2db8622918ed3e7856be00f5bb07cccdf6c2b02200d2fef3f64959ca7c3da2ac43a5be38d11de27a2958951882e4354aae65a2184edd0053f4f6a1c5cb977c7b"} build_url:https://queue.taskcluster.net/v1/task/Jmtk3LAARIuFaeqxrdxnVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011458 build_url: 'https://queue.taskcluster.net/v1/task/Jmtk3LAARIuFaeqxrdxnVg/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/7f9b952710c038f8e9da1e773768c6df4feec71fbc768a13f6cfc0ada5f930203e477df2efc4887281942a7bd19fc8742ddb21bb13c68c525303930eb34558cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae85d993490f9c42bd95bcb4f9c5a486b2a20d658f72e8d446db5f2b2709bac5af5b019f0ff7938a7e3be154ca09576308c21ad9243373d318c5dbc03c9ced35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2db8622918ed3e7856be00f5bb07cccdf6c2b02200d2fef3f64959ca7c3da2ac43a5be38d11de27a2958951882e4354aae65a2184edd0053f4f6a1c5cb977c7b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 15:50:55.786374) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 15:50:55.787144) ========= 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.jwrJw4zz3A/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.LaEM3u1eud/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.005369 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 15:50:55.840209) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 15:50:55.840933) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 15:50:55.841621) ========= ========= Total master_lag: 0.19 =========