builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0158 starttime: 1460646812.95 results: success (0) buildid: 20160414071051 builduid: 84c751fb1d734afa955c68997472d991 revision: 7bb53c007a2ecde84e470b86ee3ec39fccea9518 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:32.951364) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:32.951870) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:32.952196) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:32.973626) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:32.974350) ========= 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.ixvROmn1mt/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.gx35koOfms/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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-04-14 08:13:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-04-14 08:13:33 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112203 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:33.109383) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:33.110031) ========= 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.ixvROmn1mt/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.gx35koOfms/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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.029107 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:33.161316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-14 08:13:33.161702) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7bb53c007a2ecde84e470b86ee3ec39fccea9518 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7bb53c007a2ecde84e470b86ee3ec39fccea9518 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ixvROmn1mt/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.gx35koOfms/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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-04-14 08:13:33,236 truncating revision to first 12 chars 2016-04-14 08:13:33,237 Setting DEBUG logging. 2016-04-14 08:13:33,237 attempt 1/10 2016-04-14 08:13:33,237 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7bb53c007a2e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-14 08:13:34,171 unpacking tar archive at: mozilla-aurora-7bb53c007a2e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.183949 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-14 08:13:34.365967) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:34.366743) ========= script_repo_revision: 7bb53c007a2ecde84e470b86ee3ec39fccea9518 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:34.367780) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:34.368515) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 08:13:34.383493) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-04-14 08:13:34.384241) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ixvROmn1mt/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.gx35koOfms/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 08:13:34 INFO - MultiFileLogger online at 20160414 08:13:34 in /builds/slave/test 08:13:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:13:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:13:34 INFO - {'append_to_log': False, 08:13:34 INFO - 'base_work_dir': '/builds/slave/test', 08:13:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:13:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:13:34 INFO - 'branch': 'Mozilla-Aurora', 08:13:34 INFO - 'buildbot_json_path': 'buildprops.json', 08:13:34 INFO - 'config_files': ('talos/mac_config.py',), 08:13:34 INFO - 'default_actions': ('clobber', 08:13:34 INFO - 'read-buildbot-config', 08:13:34 INFO - 'download-and-extract', 08:13:34 INFO - 'populate-webroot', 08:13:34 INFO - 'create-virtualenv', 08:13:34 INFO - 'install', 08:13:34 INFO - 'run-tests'), 08:13:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:13:34 INFO - 'download_minidump_stackwalk': True, 08:13:34 INFO - 'download_symbols': 'ondemand', 08:13:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:13:34 INFO - 'tooltool.py': '/tools/tooltool.py', 08:13:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:13:34 INFO - '/tools/misc-python/virtualenv.py')}, 08:13:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:13:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:13:34 INFO - 'installer_path': 'installer.exe', 08:13:34 INFO - 'log_level': 'info', 08:13:34 INFO - 'log_name': 'talos', 08:13:34 INFO - 'log_to_console': True, 08:13:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:13:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:13:34 INFO - 'opt_config_files': (), 08:13:34 INFO - 'pip_index': False, 08:13:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:13:34 INFO - 'cmd': ('bash', 08:13:34 INFO - '-c', 08:13:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:13:34 INFO - 'enabled': True, 08:13:34 INFO - 'halt_on_failure': False, 08:13:34 INFO - 'name': 'check_screen_resolution'},), 08:13:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:13:34 INFO - 'cmd': ('bash', 08:13:34 INFO - '-c', 08:13:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:13:34 INFO - 'enabled': True, 08:13:34 INFO - 'halt_on_failure': False, 08:13:34 INFO - 'name': 'check_screen_resolution'},), 08:13:34 INFO - 'run_cmd_checks_enabled': True, 08:13:34 INFO - 'sps_profile': False, 08:13:34 INFO - 'sps_profile_interval': 0, 08:13:34 INFO - 'suite': 'dromaeojs', 08:13:34 INFO - 'system_bits': '32', 08:13:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:13:34 INFO - 'title': 't-yosemite-r7-0158', 08:13:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:13:34 INFO - 'use_talos_json': True, 08:13:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:13:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:13:34 INFO - 'work_dir': 'build'} 08:13:34 INFO - ##### 08:13:34 INFO - ##### Running clobber step. 08:13:34 INFO - ##### 08:13:34 INFO - Running pre-action listener: _resource_record_pre_action 08:13:34 INFO - Running main action method: clobber 08:13:34 INFO - rmtree: /builds/slave/test/build 08:13:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:13:36 INFO - Running post-action listener: _resource_record_post_action 08:13:36 INFO - ##### 08:13:36 INFO - ##### Running read-buildbot-config step. 08:13:36 INFO - ##### 08:13:36 INFO - Running pre-action listener: _resource_record_pre_action 08:13:36 INFO - Running main action method: read_buildbot_config 08:13:36 INFO - Using buildbot properties: 08:13:36 INFO - { 08:13:36 INFO - "project": "", 08:13:36 INFO - "product": "firefox", 08:13:36 INFO - "script_repo_revision": "production", 08:13:36 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:13:36 INFO - "repository": "", 08:13:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:13:36 INFO - "buildid": "20160414071051", 08:13:36 INFO - "pgo_build": "False", 08:13:36 INFO - "basedir": "/builds/slave/test", 08:13:36 INFO - "buildnumber": 179, 08:13:36 INFO - "slavename": "t-yosemite-r7-0158", 08:13:36 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:13:36 INFO - "platform": "yosemite_r7", 08:13:36 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:13:36 INFO - "branch": "mozilla-aurora", 08:13:36 INFO - "repo_path": "releases/mozilla-aurora", 08:13:36 INFO - "revision": "7bb53c007a2ecde84e470b86ee3ec39fccea9518", 08:13:36 INFO - "stage_platform": "macosx64", 08:13:36 INFO - "builduid": "84c751fb1d734afa955c68997472d991", 08:13:36 INFO - "slavebuilddir": "test" 08:13:36 INFO - } 08:13:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 08:13:36 INFO - Running post-action listener: _resource_record_post_action 08:13:36 INFO - ##### 08:13:36 INFO - ##### Running download-and-extract step. 08:13:36 INFO - ##### 08:13:36 INFO - Running pre-action listener: _resource_record_pre_action 08:13:36 INFO - Running main action method: download_and_extract 08:13:36 INFO - mkdir: /builds/slave/test/build/tests 08:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:13:36 INFO - https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:13:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 08:13:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 08:13:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 08:13:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 08:13:37 INFO - Downloaded 1277 bytes. 08:13:37 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 08:13:37 INFO - Using the following test package requirements: 08:13:37 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 08:13:37 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 08:13:37 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 08:13:37 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 08:13:37 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 08:13:37 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 08:13:37 INFO - u'jsshell-mac.zip'], 08:13:37 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 08:13:37 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 08:13:37 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 08:13:37 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 08:13:37 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 08:13:37 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 08:13:37 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 08:13:37 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 08:13:37 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 08:13:37 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 08:13:37 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 08:13:37 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 08:13:37 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:13:37 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')]} 08:13:37 INFO - https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:13:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 08:13:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 08:13:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 08:13:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:13:39 INFO - Downloaded 17910943 bytes. 08:13:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:13:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:13:42 INFO - Return code: 0 08:13:42 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:13:42 INFO - https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:13:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 08:13:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 08:13:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 08:13:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:13:43 INFO - Downloaded 17910943 bytes. 08:13:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:13:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:13:47 INFO - Return code: 0 08:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:13:47 INFO - https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:13:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 08:13:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 08:13:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 08:13:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:13:48 INFO - Downloaded 10845168 bytes. 08:13:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:13:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:13:48 INFO - Return code: 0 08:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:13:48 INFO - https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 08:13:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 08:13:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 08:13:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 08:13:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 08:13:51 INFO - Downloaded 115198029 bytes. 08:13:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 08:13:51 INFO - mkdir: /builds/slave/test/properties 08:13:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:13:51 INFO - Writing to file /builds/slave/test/properties/build_url 08:13:51 INFO - Contents: 08:13:51 INFO - build_url:https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 08:13:52 INFO - Running post-action listener: _resource_record_post_action 08:13:52 INFO - Running post-action listener: set_extra_try_arguments 08:13:52 INFO - ##### 08:13:52 INFO - ##### Running populate-webroot step. 08:13:52 INFO - ##### 08:13:52 INFO - Running pre-action listener: _resource_record_pre_action 08:13:52 INFO - Running main action method: populate_webroot 08:13:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:13:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:13:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:13:52 INFO - '--tppagecycles', 08:13:52 INFO - '7'], 08:13:52 INFO - 'tests': ['tsvgm']}}, 08:13:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:13:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:13:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:13:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 08:13:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:13:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:13:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:13:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:52 INFO - 'talos_options': ['--e10s'], 08:13:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:13:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:52 INFO - 'tests': ['damp', 'tps']}, 08:13:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:52 INFO - 'talos_options': ['--e10s'], 08:13:52 INFO - 'tests': ['damp', 'tps']}, 08:13:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:13:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:13:52 INFO - 'other': {'tests': ['a11yr', 08:13:52 INFO - 'ts_paint', 08:13:52 INFO - 'tpaint', 08:13:52 INFO - 'sessionrestore', 08:13:52 INFO - 'sessionrestore_no_auto_restore', 08:13:52 INFO - 'tabpaint']}, 08:13:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:13:52 INFO - 'tests': ['a11yr', 08:13:52 INFO - 'ts_paint', 08:13:52 INFO - 'tpaint', 08:13:52 INFO - 'sessionrestore', 08:13:52 INFO - 'sessionrestore_no_auto_restore', 08:13:52 INFO - 'tabpaint']}, 08:13:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:13:52 INFO - 'tests': ['a11yr', 08:13:52 INFO - 'ts_paint', 08:13:52 INFO - 'tpaint', 08:13:52 INFO - 'sessionrestore', 08:13:52 INFO - 'sessionrestore_no_auto_restore', 08:13:52 INFO - 'tabpaint']}, 08:13:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:13:52 INFO - 'tests': ['a11yr', 08:13:52 INFO - 'ts_paint', 08:13:52 INFO - 'tpaint', 08:13:52 INFO - 'sessionrestore', 08:13:52 INFO - 'sessionrestore_no_auto_restore', 08:13:52 INFO - 'tabpaint']}, 08:13:52 INFO - 'other_l64': {'tests': ['a11yr', 08:13:52 INFO - 'ts_paint', 08:13:52 INFO - 'tpaint', 08:13:52 INFO - 'sessionrestore', 08:13:52 INFO - 'sessionrestore_no_auto_restore', 08:13:52 INFO - 'tabpaint']}, 08:13:52 INFO - 'other_nol64': {'tests': ['a11yr', 08:13:52 INFO - 'ts_paint', 08:13:52 INFO - 'tpaint', 08:13:52 INFO - 'sessionrestore', 08:13:52 INFO - 'sessionrestore_no_auto_restore', 08:13:52 INFO - 'tabpaint']}, 08:13:52 INFO - 'svgr': {'tests': ['tsvgx', 08:13:52 INFO - 'tsvgr_opacity', 08:13:52 INFO - 'tart', 08:13:52 INFO - 'tscrollx', 08:13:52 INFO - 'cart']}, 08:13:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:13:52 INFO - 'tests': ['tsvgx', 08:13:52 INFO - 'tsvgr_opacity', 08:13:52 INFO - 'tart', 08:13:52 INFO - 'tscrollx', 08:13:52 INFO - 'cart']}, 08:13:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:52 INFO - 'tests': ['tp5o']}, 08:13:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:52 INFO - 'talos_options': ['--e10s'], 08:13:52 INFO - 'tests': ['tp5o']}, 08:13:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:52 INFO - 'talos_options': ['--xperf_path', 08:13:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:13:52 INFO - 'tests': ['tp5n']}, 08:13:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:52 INFO - 'talos_options': ['--e10s', 08:13:52 INFO - '--xperf_path', 08:13:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:13:52 INFO - 'tests': ['tp5n']}}, 08:13:52 INFO - 'talos.zip': {'path': '', 08:13:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:13:52 INFO - Running post-action listener: _resource_record_post_action 08:13:52 INFO - ##### 08:13:52 INFO - ##### Running create-virtualenv step. 08:13:52 INFO - ##### 08:13:52 INFO - Running pre-action listener: _resource_record_pre_action 08:13:52 INFO - Running main action method: create_virtualenv 08:13:52 INFO - Creating virtualenv /builds/slave/test/build/venv 08:13:52 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 08:13:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:13:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:13:52 INFO - Using real prefix '/tools/python27' 08:13:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:13:53 INFO - Installing distribute.............................................................................................................................................................................................done. 08:13:56 INFO - Installing pip.................done. 08:13:56 INFO - Return code: 0 08:13:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c206a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccb84c80f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0aad30>, '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 0x10bf0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0a1b20>, '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.ixvROmn1mt/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.gx35koOfms/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 08:13:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:13:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixvROmn1mt/Render', 08:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:13:56 INFO - 'HOME': '/Users/cltbld', 08:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:13:56 INFO - 'LOGNAME': 'cltbld', 08:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:56 INFO - 'MOZ_NO_REMOTE': '1', 08:13:56 INFO - 'NO_EM_RESTART': '1', 08:13:56 INFO - 'PAGER': '/bin/cat', 08:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:56 INFO - 'PWD': '/builds/slave/test', 08:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:13:56 INFO - 'SHELL': '/bin/bash', 08:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gx35koOfms/Listeners', 08:13:56 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 08:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:13:56 INFO - 'USER': 'cltbld', 08:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:56 INFO - 'XPC_FLAGS': '0x0', 08:13:56 INFO - 'XPC_SERVICE_NAME': '0', 08:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:56 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 08:13:56 INFO - Cleaning up... 08:13:56 INFO - Return code: 0 08:13:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c206a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccb84c80f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0aad30>, '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 0x10bf0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0a1b20>, '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.ixvROmn1mt/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.gx35koOfms/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 08:13:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 08:13:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixvROmn1mt/Render', 08:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:13:56 INFO - 'HOME': '/Users/cltbld', 08:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:13:56 INFO - 'LOGNAME': 'cltbld', 08:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:56 INFO - 'MOZ_NO_REMOTE': '1', 08:13:56 INFO - 'NO_EM_RESTART': '1', 08:13:56 INFO - 'PAGER': '/bin/cat', 08:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:56 INFO - 'PWD': '/builds/slave/test', 08:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:13:56 INFO - 'SHELL': '/bin/bash', 08:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gx35koOfms/Listeners', 08:13:56 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 08:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:13:56 INFO - 'USER': 'cltbld', 08:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:56 INFO - 'XPC_FLAGS': '0x0', 08:13:56 INFO - 'XPC_SERVICE_NAME': '0', 08:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:56 INFO - Downloading/unpacking psutil>=0.7.1 08:13:56 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 08:13:56 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 08:13:56 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 08:13:56 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 08:13:56 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 08:13:56 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 08:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:13:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:13:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:13:59 INFO - Installing collected packages: psutil 08:14:00 INFO - Running setup.py install for psutil 08:14:00 INFO - building 'psutil._psutil_osx' extension 08:14:00 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 08:14:00 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 08:14:00 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 08:14:00 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 08:14:00 INFO - building 'psutil._psutil_posix' extension 08:14:00 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 08:14:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:14:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:14:01 INFO - ^ 08:14:01 INFO - 1 warning generated. 08:14:01 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 08:14:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:14:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:14:01 INFO - Successfully installed psutil 08:14:01 INFO - Cleaning up... 08:14:01 INFO - Return code: 0 08:14:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c206a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccb84c80f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0aad30>, '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 0x10bf0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0a1b20>, '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.ixvROmn1mt/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.gx35koOfms/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 08:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixvROmn1mt/Render', 08:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:01 INFO - 'HOME': '/Users/cltbld', 08:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:01 INFO - 'LOGNAME': 'cltbld', 08:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:01 INFO - 'MOZ_NO_REMOTE': '1', 08:14:01 INFO - 'NO_EM_RESTART': '1', 08:14:01 INFO - 'PAGER': '/bin/cat', 08:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:01 INFO - 'PWD': '/builds/slave/test', 08:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:01 INFO - 'SHELL': '/bin/bash', 08:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gx35koOfms/Listeners', 08:14:01 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 08:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:01 INFO - 'USER': 'cltbld', 08:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:01 INFO - 'XPC_FLAGS': '0x0', 08:14:01 INFO - 'XPC_SERVICE_NAME': '0', 08:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:14:01 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 08:14:01 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 08:14:01 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 08:14:01 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 08:14:01 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 08:14:01 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 08:14:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:14:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:14:04 INFO - Installing collected packages: mozsystemmonitor 08:14:04 INFO - Running setup.py install for mozsystemmonitor 08:14:04 INFO - Successfully installed mozsystemmonitor 08:14:04 INFO - Cleaning up... 08:14:04 INFO - Return code: 0 08:14:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:04 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 0x10c206a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccb84c80f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0aad30>, '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 0x10bf0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0a1b20>, '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.ixvROmn1mt/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.gx35koOfms/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 08:14:04 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 08:14:04 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 08:14:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixvROmn1mt/Render', 08:14:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:04 INFO - 'HOME': '/Users/cltbld', 08:14:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:04 INFO - 'LOGNAME': 'cltbld', 08:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:04 INFO - 'MOZ_NO_REMOTE': '1', 08:14:04 INFO - 'NO_EM_RESTART': '1', 08:14:04 INFO - 'PAGER': '/bin/cat', 08:14:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:04 INFO - 'PWD': '/builds/slave/test', 08:14:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:04 INFO - 'SHELL': '/bin/bash', 08:14:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gx35koOfms/Listeners', 08:14:04 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 08:14:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:04 INFO - 'USER': 'cltbld', 08:14:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:04 INFO - 'XPC_FLAGS': '0x0', 08:14:04 INFO - 'XPC_SERVICE_NAME': '0', 08:14:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:04 INFO - Downloading/unpacking blobuploader==1.2.4 08:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:06 INFO - Downloading blobuploader-1.2.4.tar.gz 08:14:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:14:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:14:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:08 INFO - Downloading docopt-0.6.1.tar.gz 08:14:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:14:08 INFO - Installing collected packages: blobuploader, requests, docopt 08:14:08 INFO - Running setup.py install for blobuploader 08:14:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:14:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:14:08 INFO - Running setup.py install for requests 08:14:08 INFO - Running setup.py install for docopt 08:14:09 INFO - Successfully installed blobuploader requests docopt 08:14:09 INFO - Cleaning up... 08:14:09 INFO - Return code: 0 08:14:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:09 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 0x10c206a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccb84c80f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0aad30>, '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 0x10bf0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0a1b20>, '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.ixvROmn1mt/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.gx35koOfms/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 08:14:09 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 08:14:09 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 08:14:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixvROmn1mt/Render', 08:14:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:09 INFO - 'HOME': '/Users/cltbld', 08:14:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:09 INFO - 'LOGNAME': 'cltbld', 08:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:09 INFO - 'MOZ_NO_REMOTE': '1', 08:14:09 INFO - 'NO_EM_RESTART': '1', 08:14:09 INFO - 'PAGER': '/bin/cat', 08:14:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:09 INFO - 'PWD': '/builds/slave/test', 08:14:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:09 INFO - 'SHELL': '/bin/bash', 08:14:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gx35koOfms/Listeners', 08:14:09 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 08:14:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:09 INFO - 'USER': 'cltbld', 08:14:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:09 INFO - 'XPC_FLAGS': '0x0', 08:14:09 INFO - 'XPC_SERVICE_NAME': '0', 08:14:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:14:09 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-yzdWQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:14:09 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-LkEZUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:14:09 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-S9P4Za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:14:09 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-5kuMgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:14:09 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-T8ReLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:14:09 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-JWCBzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:14:10 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-lzDcrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:14:10 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-9AUPGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:14:10 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-c0cKFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:14:10 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-B2pmjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:14:10 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-pBOPk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:14:10 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-ngCS9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:14:10 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-jIdktO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:14:10 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-wbcN6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:10 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-Cmd_R1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:14:10 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-3gbWPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:14:11 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-MEIWeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:14:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:14:11 INFO - Running setup.py install for manifestparser 08:14:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:14:11 INFO - Running setup.py install for mozcrash 08:14:11 INFO - Running setup.py install for mozdebug 08:14:11 INFO - Running setup.py install for mozdevice 08:14:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:14:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:14:11 INFO - Running setup.py install for mozfile 08:14:11 INFO - Running setup.py install for mozhttpd 08:14:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:14:12 INFO - Running setup.py install for mozinfo 08:14:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:14:12 INFO - Running setup.py install for mozInstall 08:14:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:14:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:14:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:14:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:14:12 INFO - Running setup.py install for mozleak 08:14:12 INFO - Running setup.py install for mozlog 08:14:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:14:12 INFO - Running setup.py install for moznetwork 08:14:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:14:12 INFO - Running setup.py install for mozprocess 08:14:12 INFO - Running setup.py install for mozprofile 08:14:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:14:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:14:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:14:13 INFO - Running setup.py install for mozrunner 08:14:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:14:13 INFO - Running setup.py install for mozscreenshot 08:14:13 INFO - Running setup.py install for moztest 08:14:13 INFO - Running setup.py install for mozversion 08:14:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:14:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:14:13 INFO - Cleaning up... 08:14:13 INFO - Return code: 0 08:14:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:14: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')]} 08:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14: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')]} 08:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:13 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 0x10c206a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccb84c80f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0aad30>, '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 0x10bf0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0a1b20>, '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.ixvROmn1mt/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.gx35koOfms/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 08:14:13 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 08:14:13 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 08:14:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixvROmn1mt/Render', 08:14:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:13 INFO - 'HOME': '/Users/cltbld', 08:14:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:13 INFO - 'LOGNAME': 'cltbld', 08:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:13 INFO - 'MOZ_NO_REMOTE': '1', 08:14:13 INFO - 'NO_EM_RESTART': '1', 08:14:13 INFO - 'PAGER': '/bin/cat', 08:14:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:13 INFO - 'PWD': '/builds/slave/test', 08:14:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:13 INFO - 'SHELL': '/bin/bash', 08:14:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gx35koOfms/Listeners', 08:14:13 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 08:14:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:13 INFO - 'USER': 'cltbld', 08:14:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:13 INFO - 'XPC_FLAGS': '0x0', 08:14:13 INFO - 'XPC_SERVICE_NAME': '0', 08:14:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:14:14 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-SPcqeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:14:14 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)) 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:14:14 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-oSmqAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:14:14 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)) 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:14:14 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-Xgzg35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:14:14 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)) 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:14:14 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-oGFMxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:14:14 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)) 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:14:14 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-ORy8hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:14:14 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)) 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:14:14 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-NMrndU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:14:14 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)) 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:14:14 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-PbQkv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:14:14 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)) 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:14:14 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-6qNs6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:14:14 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)) 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:14:14 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-5J3Ae9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:14:14 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)) 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:14:15 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-9hmK6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:14:15 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-LVi5gf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:14:15 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)) 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:14:15 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-baJCoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:14:15 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)) 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:14:15 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-HU3dRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:14:15 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)) 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:14:15 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-rCEPMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:14:15 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)) 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:15 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-Tb6bsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:15 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)) 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:14:15 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-bXYYg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:14:15 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)) 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:14:15 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-asadJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:14:15 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)) 08:14:15 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)) 08:14:15 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)) 08:14:15 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)) 08:14:15 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)) 08:14:15 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)) 08:14:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:14: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 08:14: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 08:14: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 08:14: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 08:14: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 08:14: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 08:14:18 INFO - Downloading blessings-1.6.tar.gz 08:14:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:14:18 INFO - Installing collected packages: blessings 08:14:18 INFO - Running setup.py install for blessings 08:14:18 INFO - Successfully installed blessings 08:14:18 INFO - Cleaning up... 08:14:18 INFO - Return code: 0 08:14:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:14:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:14:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:14:18 INFO - Reading from file tmpfile_stdout 08:14:18 INFO - Current package versions: 08:14:18 INFO - blessings == 1.6 08:14:18 INFO - blobuploader == 1.2.4 08:14:18 INFO - docopt == 0.6.1 08:14:18 INFO - manifestparser == 1.1 08:14:18 INFO - mozInstall == 1.12 08:14:18 INFO - mozcrash == 0.17 08:14:18 INFO - mozdebug == 0.1 08:14:18 INFO - mozdevice == 0.48 08:14:18 INFO - mozfile == 1.2 08:14:18 INFO - mozhttpd == 0.7 08:14:18 INFO - mozinfo == 0.9 08:14:18 INFO - mozleak == 0.1 08:14:18 INFO - mozlog == 3.1 08:14:18 INFO - moznetwork == 0.27 08:14:18 INFO - mozprocess == 0.22 08:14:18 INFO - mozprofile == 0.28 08:14:18 INFO - mozrunner == 6.11 08:14:18 INFO - mozscreenshot == 0.1 08:14:18 INFO - mozsystemmonitor == 0.0 08:14:18 INFO - moztest == 0.7 08:14:18 INFO - mozversion == 1.4 08:14:18 INFO - psutil == 3.1.1 08:14:18 INFO - requests == 1.2.3 08:14:18 INFO - wsgiref == 0.1.2 08:14:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:18 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 0x10c206a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccb84c80f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0aad30>, '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 0x10bf0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0a1b20>, '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.ixvROmn1mt/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.gx35koOfms/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 08:14:18 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 08:14:18 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 08:14:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixvROmn1mt/Render', 08:14:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:18 INFO - 'HOME': '/Users/cltbld', 08:14:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:18 INFO - 'LOGNAME': 'cltbld', 08:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:18 INFO - 'MOZ_NO_REMOTE': '1', 08:14:18 INFO - 'NO_EM_RESTART': '1', 08:14:18 INFO - 'PAGER': '/bin/cat', 08:14:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:18 INFO - 'PWD': '/builds/slave/test', 08:14:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:18 INFO - 'SHELL': '/bin/bash', 08:14:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gx35koOfms/Listeners', 08:14:18 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 08:14:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:18 INFO - 'USER': 'cltbld', 08:14:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:18 INFO - 'XPC_FLAGS': '0x0', 08:14:18 INFO - 'XPC_SERVICE_NAME': '0', 08:14:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:19 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)) 08:14:19 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)) 08:14:19 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)) 08:14:19 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)) 08:14:19 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)) 08:14:19 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)) 08:14:19 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)) 08:14:19 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)) 08:14:19 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)) 08:14:19 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)) 08:14:19 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)) 08:14:19 INFO - Cleaning up... 08:14:19 INFO - Return code: 0 08:14:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:19 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 0x10c206a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccb84c80f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0aad30>, '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 0x10bf0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0a1b20>, '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.ixvROmn1mt/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.gx35koOfms/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 08:14:19 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 08:14:19 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 08:14:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixvROmn1mt/Render', 08:14:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:19 INFO - 'HOME': '/Users/cltbld', 08:14:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:19 INFO - 'LOGNAME': 'cltbld', 08:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:19 INFO - 'MOZ_NO_REMOTE': '1', 08:14:19 INFO - 'NO_EM_RESTART': '1', 08:14:19 INFO - 'PAGER': '/bin/cat', 08:14:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:19 INFO - 'PWD': '/builds/slave/test', 08:14:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:19 INFO - 'SHELL': '/bin/bash', 08:14:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gx35koOfms/Listeners', 08:14:19 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 08:14:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:19 INFO - 'USER': 'cltbld', 08:14:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:19 INFO - 'XPC_FLAGS': '0x0', 08:14:19 INFO - 'XPC_SERVICE_NAME': '0', 08:14:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:19 INFO - Downloading/unpacking jsonschema 08:14: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 08:14: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 08:14: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 08:14: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 08:14: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 08:14: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 08:14:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:14:21 INFO - Downloading/unpacking functools32 (from jsonschema) 08:14:21 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 08:14:21 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 08:14:21 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 08:14:21 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 08:14:21 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 08:14:21 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 08:14:22 INFO - Downloading functools32-3.2.3-2.tar.gz 08:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:14:22 INFO - warning: no files found matching '*.txt' 08:14:22 INFO - no previously-included directories found matching 'build' 08:14:22 INFO - no previously-included directories found matching 'dist' 08:14:22 INFO - no previously-included directories found matching '.git*' 08:14:22 INFO - Installing collected packages: jsonschema, functools32 08:14:22 INFO - Running setup.py install for functools32 08:14:22 INFO - warning: no files found matching '*.txt' 08:14:22 INFO - no previously-included directories found matching 'build' 08:14:22 INFO - no previously-included directories found matching 'dist' 08:14:22 INFO - no previously-included directories found matching '.git*' 08:14:22 INFO - Successfully installed jsonschema functools32 08:14:22 INFO - Cleaning up... 08:14:22 INFO - Return code: 0 08:14:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:14:22 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')]} 08:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:22 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')]} 08:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:22 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 0x10c206a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccb84c80f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0aad30>, '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 0x10bf0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0a1b20>, '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.ixvROmn1mt/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.gx35koOfms/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 08:14:22 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 08:14:22 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 08:14:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixvROmn1mt/Render', 08:14:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:22 INFO - 'HOME': '/Users/cltbld', 08:14:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:22 INFO - 'LOGNAME': 'cltbld', 08:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:22 INFO - 'MOZ_NO_REMOTE': '1', 08:14:22 INFO - 'NO_EM_RESTART': '1', 08:14:22 INFO - 'PAGER': '/bin/cat', 08:14:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:22 INFO - 'PWD': '/builds/slave/test', 08:14:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:22 INFO - 'SHELL': '/bin/bash', 08:14:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gx35koOfms/Listeners', 08:14:22 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 08:14:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:22 INFO - 'USER': 'cltbld', 08:14:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:22 INFO - 'XPC_FLAGS': '0x0', 08:14:22 INFO - 'XPC_SERVICE_NAME': '0', 08:14:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:22 INFO - Downloading/unpacking flake8 08:14:22 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 08:14:22 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 08:14:22 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 08:14:22 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 08:14:22 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 08:14:22 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 08:14:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:14:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:14:25 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 08:14:25 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 08:14:25 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 08:14:25 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 08:14:25 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 08:14:25 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 08:14:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:14:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:14:25 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 08:14:25 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 08:14:25 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 08:14:25 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 08:14:25 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 08:14:25 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 08:14:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:14:26 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 08:14:26 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 08:14:26 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 08:14:26 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 08:14:26 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 08:14:26 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 08:14:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:14:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:14:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:14:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:14:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:14:26 INFO - no previously-included directories found matching 'docs/_build' 08:14:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:14:26 INFO - Running setup.py install for pep8 08:14:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:14:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:14:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:14:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:14:26 INFO - no previously-included directories found matching 'docs/_build' 08:14:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:14:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:14:26 INFO - Cleaning up... 08:14:26 INFO - Return code: 0 08:14:26 INFO - Running post-action listener: _resource_record_post_action 08:14:26 INFO - Running post-action listener: _start_resource_monitoring 08:14:26 INFO - Starting resource monitoring. 08:14:26 INFO - ##### 08:14:26 INFO - ##### Running install step. 08:14:26 INFO - ##### 08:14:26 INFO - Running pre-action listener: _resource_record_pre_action 08:14:26 INFO - Running main action method: install 08:14:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:14:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:14:27 INFO - Reading from file tmpfile_stdout 08:14:27 INFO - Detecting whether we're running mozinstall >=1.0... 08:14:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:14:27 INFO - Reading from file tmpfile_stdout 08:14:27 INFO - Output received: 08:14:27 INFO - Usage: mozinstall [options] installer 08:14:27 INFO - Options: 08:14:27 INFO - -h, --help show this help message and exit 08:14:27 INFO - -d DEST, --destination=DEST 08:14:27 INFO - Directory to install application into. [default: 08:14:27 INFO - "/builds/slave/test"] 08:14:27 INFO - --app=APP Application being installed. [default: firefox] 08:14:27 INFO - mkdir: /builds/slave/test/build/application 08:14:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:14:58 INFO - Reading from file tmpfile_stdout 08:14:58 INFO - Output received: 08:14:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:14:58 INFO - Running post-action listener: _resource_record_post_action 08:14:58 INFO - ##### 08:14:58 INFO - ##### Running run-tests step. 08:14:58 INFO - ##### 08:14:58 INFO - Running pre-action listener: _resource_record_pre_action 08:14:58 INFO - Running main action method: run_tests 08:14:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:14:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:14:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:14:58 INFO - 2016-04-14 08:14:58.973 screenresolution[1638:7492] starting screenresolution argv=screenresolution get 08:14:58 INFO - 2016-04-14 08:14:58.976 screenresolution[1638:7492] Display 0: 1600x1200x32@60 08:14:58 INFO - 2016-04-14 08:14:58.982 screenresolution[1639:7495] starting screenresolution argv=screenresolution list 08:14:58 INFO - Available Modes on Display 0 08:14:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:14:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:14:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:14:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:14:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:14:59 INFO - Intel Iris: 08:14:59 INFO - Chipset Model: Intel Iris 08:14:59 INFO - Type: GPU 08:14:59 INFO - Bus: Built-In 08:14:59 INFO - VRAM (Dynamic, Max): 1536 MB 08:14:59 INFO - Vendor: Intel (0x8086) 08:14:59 INFO - Device ID: 0x0a2e 08:14:59 INFO - Revision ID: 0x0009 08:14:59 INFO - Displays: 08:14:59 INFO - Display: 08:14:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:14:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:14:59 INFO - Display Serial Number: 1600x1200 60 08:14:59 INFO - Main Display: Yes 08:14:59 INFO - Mirror: Off 08:14:59 INFO - Online: Yes 08:14:59 INFO - Rotation: Supported 08:14:59 INFO - Return code: 0 08:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:14:59 INFO - Python 2.7.3 08:14:59 INFO - Return code: 0 08:14:59 INFO - grabbing minidump binary from tooltool 08:14: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')]} 08:14:59 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0aad30>, '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 0x10bf0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0a1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:14:59 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 08:14:59 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 08:14:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:14:59 INFO - Return code: 0 08:14:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:14:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:14:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:14:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:14:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:15:00 INFO - Return code: 0 08:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0158', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0158 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:15:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixvROmn1mt/Render', 08:15:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:15:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:15:00 INFO - 'HOME': '/Users/cltbld', 08:15:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:15:00 INFO - 'LOGNAME': 'cltbld', 08:15:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:15:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:00 INFO - 'MOZ_NO_REMOTE': '1', 08:15:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:15:00 INFO - 'NO_EM_RESTART': '1', 08:15:00 INFO - 'PAGER': '/bin/cat', 08:15:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:00 INFO - 'PWD': '/builds/slave/test', 08:15:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:15:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:15:00 INFO - 'SHELL': '/bin/bash', 08:15:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gx35koOfms/Listeners', 08:15:00 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 08:15:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:15:00 INFO - 'USER': 'cltbld', 08:15:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:15:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:00 INFO - 'XPC_FLAGS': '0x0', 08:15:00 INFO - 'XPC_SERVICE_NAME': '0', 08:15:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:15:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0158', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:15:00 INFO - mozversion application_buildid: 20160414071051 08:15:00 INFO - mozversion application_changeset: 7bb53c007a2ecde84e470b86ee3ec39fccea9518 08:15:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:15:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:15:00 INFO - mozversion application_name: Firefox 08:15:00 INFO - mozversion application_remotingname: firefox-dev 08:15:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:15:00 INFO - mozversion application_vendor: Mozilla 08:15:00 INFO - mozversion application_version: 47.0a2 08:15:00 INFO - mozversion platform_buildid: 20160414071051 08:15:00 INFO - mozversion platform_changeset: 7bb53c007a2ecde84e470b86ee3ec39fccea9518 08:15:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:15:00 INFO - mozversion platform_version: 47.0a2 08:15:00 INFO - using testdate: 1460646900 08:15:00 INFO - actual date: 1460646900 08:15:00 INFO - starting webserver on 'localhost:49276' 08:15:00 INFO - SUITE-START | Running 2 tests 08:15:00 INFO - TEST-START | dromaeo_css 08:15:00 INFO - operating with platform_type : mac_ 08:15:00 INFO - Initialising browser for dromaeo_css test... 08:15:00 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpWIazwz/profile http://localhost:49276/getInfo.html) 08:15:02 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 08:15:02 INFO - PROCESS | 1657 | colorDepth:24 08:15:02 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 08:15:02 INFO - PROCESS | 1657 | __metrics 08:15:03 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:15:03 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:15:03 INFO - TEST-INFO | 1657: exit 0 08:15:03 INFO - Browser initialized. 08:15:03 INFO - Running cycle 1/1 for dromaeo_css test... 08:15:03 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpWIazwz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:15:15 INFO - PROCESS | 1659 | RSS: Main: 207515648 08:15:15 INFO - PROCESS | 1659 | 08:16:06 INFO - PROCESS | 1659 | 1460646966813 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] 08:18:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 08:18:18 INFO - PROCESS | 1659 | RSS: Main: 326070272 08:18:18 INFO - PROCESS | 1659 | 08:21:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 08:21:21 INFO - PROCESS | 1659 | RSS: Main: 338440192 08:21:21 INFO - PROCESS | 1659 | 08:24:23 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 08:24:23 INFO - PROCESS | 1659 | RSS: Main: 336244736 08:24:23 INFO - PROCESS | 1659 | 08:27:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 08:27:26 INFO - PROCESS | 1659 | RSS: Main: 302120960 08:27:26 INFO - PROCESS | 1659 | 08:30:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 08:30:30 INFO - PROCESS | 1659 | RSS: Main: 305205248 08:30:30 INFO - PROCESS | 1659 | 08:33:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 08:33:33 INFO - PROCESS | 1659 | RSS: Main: 353267712 08:33:33 INFO - PROCESS | 1659 | 08:33:33 INFO - PROCESS | 1659 | __start_tp_report 08:33:33 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 08:33:33 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 08:33:33 INFO - PROCESS | 1659 | |i|pagename|runs| 08:33:33 INFO - PROCESS | 1659 | |0;dojo.html;3400.880969166079;3493.4235851084572;3494.8252587370625;3505.2638945821386;3560.145565064384;5694.088945768678;5700.458456446636;5738.885222295562;5748.137779333102;5787.045137552304;5714.628549144302;5734.885302293962;5748.655080695151;5755.395683453229;5785.623934444268;5169.457206993268;5180.197069454241;5184.066867963756;5225.712585807779;5309.601779866525;5273.973630131844;5291.999999999981;5292.391374991867;5292.576593872481;5300.840974770763;5247.711375874325;5253.106971814801;5277.788888444457;5279.155335146377;5284.947150528484;5297.073012222865;5320.361556613192;5321.148616221386;5333.519983201491;5369.114096174166;5092.032513822362;5244.79020839166;5255.868603284948;5263.26314315996;5266.605004624668;5613.80351687693;5625.240592519998;5638.943610563924;5647.830565083054;5647.8588035299035;6151.89265932132;6294.905576416358;6296.527760417987;6315.147455093549;6326.778562750282;6267.4986001119805;6297.527685423612;6304.401081897213;6335.303116657164;6354.237491501003;5838.562107841886;5850.946829570676;5878.323992740804;5880.592846741479;5933;6229.034499652561;6258.812235632939;6281;6325.272593651789;6338.651374174372;5246.658967167122;6196.783112591038;6197.225346831646;6201.503879689614;6214.658193799294;6295.055741638791;6308.337624549356;6314.463270621956;6316.652584107918;6319.747210111544;6071.301800292935;6180.845478863064;6248.9062664060075;6271.96864015677;6302.401271879165;6270.749170033238;6274.372562743689;6282.2147231596055;6303.149074874925;6311.652859092702;4892.2661600760175;4927.457979622239;4932.778024988884;4938.2839488274;4942;4402.537733537996;4417.933730994037;4477.059817438309;4481.126180394857;4512.842050528216;5735.11105778605;5737.196792449061;5745;5751.3673495916255;5753.021986262345;6302.684865756693;6306.684665766693;6307.4954003679595;6325.05124231369;6340.651264180422;5617.522510586646;5671.489565939085;5705.22979397776;5764.942350576441;5795.1886735857015;5321.973390133025;5360.517553420211;5365.409804921455;5375.3012108425655;5392.433794451605;6297.716602752978;6317.778877739349;6336.968315158487;6338.873222535523;6340.651264180515;6177.84555386119;6233.750649974132;6324.620522768649;6333.65164915934;6335.4931605472375;6317.431431171217;6344.365563443804;6358.268799088164;6363.93636063652;6364.713587888648;6130.428711073822;6273.52948528853;6290.874182516415;6312.5581209314905;6325.683715814283;6229.4393504584805;6272.6236425814595;6307.526935479766;6308.306086330408;6321.905171422341;5947.316058653138;6122.755089796537;6130.172426722335;6175.9073613894925;6193.473554747717;5994.850128746816;6111.419415155553;6119.44924956756;6130.908036379368;6149.41580549847;5976.521878249642;5995.070764031582;6013.338532761305;6028.09578563219;6064.878702426015;6117.084826762281;6125.877482450415;6127.111568822406;6129.938700612936;6131.141640170291;5872.647641141545;5924.52603791687;5964.015937370285;5998.0103282957825;6016.278046634432 08:33:33 INFO - PROCESS | 1659 | |1;ext.html;493.28719999600594;536.8550491367333;622.9906551401727;670.1823774994507;670.4971271546339;29155.562666560018;39105.413418798744;39230.60769392312;39323.42706291745;39541.604583954155;43523.6942891713;44092.3386149208;44102.33846492305;44189.77905110478;44273;9681.467519286454;9937.503124843764;9941.204703623729;9949.005099490028;9994.950025249864;9652.662156824512;9816.361936474143;9826.213902887752;9830.852537211947;9934.801303973916;9893.604255829758;9986.750331241705;9996.700098997042;9999.000099990015;9999.2000639949;9892.15916647086;9957.253206009578;9968.900310996869;9973.70078897627;9976.501174941224;9729.513524323755;9874.210063194927;9881.851772223423;9890.456024918627;9893.4558599277;42216;42527.7873610633;42544.574554254374;42578;42697.224680395986;3288.3094550144497;3476.026712520498;3574.4995700601935;3849.615038496156;4032.2741906456827;28439.71560284412;29441.969531066523;29619.111426657233;29963.850180749174;30120;2752.518309295865;2830.7169283071735;2834.7873909456875;2915.110891178211;2958.8224706517676;493110;504310;505958;508051;508688.4565577186;42050;51205.48794512007;51243.23135152976;78828.81756773654;79530.60234698905;52095.73952130291;52213.7389313051;52492.53813425902;52586.73706631519;52648.21027684588;17471.388501402645;17696.734548981774;17696.734548981774;17703.822961770475;17705.291788328323;13976.580702578938;14081.225532595601;14104.647383815487;14105.294735263402;14123.576292711236;16336.75494867578;16362.509124726277;16634.75047874283;16661.91669041647;16717.83282167187;7063;7085.362317391459;7139.607321597258;7139.714411423589;7181.389581885598;851.642310229705;923.7806393874529;962.6005207838668;1037.7561273100816;1098.319042193845;27043;28748.712512875023;29327.97352092709;29969.850150749106;30678;1140.9201355905006;1146.9942650286862;1153.8384626152344;1168.2815068732668;1202.212550779241;15059;15242.923785381225;15643.530694079196;15756.369745210293;16518.587035324214;515484;517698;520499;520812;521905;62178;62458.750824984156;62530;62571.374286256556;62870.0569491458;1334.9733005339838;1373.924434156111;1554.3005647458688;1618.7248167811501;1673.514680742571;29297.121086367442;29490.967816126333;29501.852490738267;30140.84929575338;30340;48151;48492;48607.5139248603;48666.75666621644;48707.75646121747;278402;327550;329589;330536;333427;33460.49809252912;45047.549524505645;46369;48208.27687584619;48297.27554086762;37906.620933790306;44609.77695111504;44985.32522012107;45258;45680;32230.516542252353;36788.63211367852;37331;38782.224355513295;39439.408408874464;37670.62329376671;37995.70365189567;38284;38398.616013839506;39136;38509.614903850605;38706.8387948362;38935.805320974345;39233.019174520865;39670.60329396785;29980.650870710433;32585.34829303448;34313.828430857684;34389.484157737155;34430.967070987914;22143.55712885701;23960.88019559891;24960.251192464257;25352.366190845376;25740 08:33:33 INFO - PROCESS | 1659 | |2;jquery.html;375.0847931048242;380.8057890475857;382.38627003659127;385.76853887667403;387.2526024772192;20092.072019892345;20731.481712957182;21265;21308.573828523422;21384.679229811565;20592.073356698947;20943.16227350908;21446.678299825475;21467.892660536716;21918.794466304345;4329.891752706176;5669.149627555858;5856.970715146418;5879.412058794129;5887.8233652990475;4563.315502674593;6229.345918678541;6407.775727849526;6410.615363078207;6428.967855160718;5594.104943209088;6329.778457753957;6379.106925030499;6462.709178086998;6462.933615953362;6194.442500174983;6372.362763723637;6376.904346434807;6399.488040956712;6404.48764098871;4880.219164933594;5464.371597266286;5538.806141785019;5554.555635549146;5561.416051314594;25996.480070398487;26000;26109.477810443685;26415.20754377372;26502.93988240468;2178.7603363629987;2200.141944641591;2243.6522339037474;2243.9102435902546;2245.65192395179;14572.562823115217;14648.560543183721;14706.926465367605;15086.717629001549;15295.311710972921;1689.569159864239;1773.042557019208;1787.8659100567509;1824.9817501824944;1844.5019844642006;105587;106427;110089;111119.44440277827;111912.88087119105;9459.668911588062;11226.494807733672;11570.42147892602;11613.070954323633;11717;14474.131552106697;14519.491817786327;14567;14598.34307456167;14617.415303387745;8953.28373730113;8980.236679882153;9022.188003079755;9034.412763170372;9124.726258212264;9227.123423274777;9265.444073355486;9284.164425201765;9324.95337523308;9435.150836424755;4577.130345234395;4593.701409408378;4596.448426188878;4605.631549476034;4637.420322459692;1669.3406104588862;1930.903454827253;1944.9691663418412;1956.3544030469911;1985.0868600443669;933.7665583604099;944.2163004706077;1015.4313584392763;1029.2332212501585;1046.0951277145214;19569.412917612495;20138;20474.590508189754;20495;20565.829712295694;964.4502633498838;978.515634760798;982.7101005203482;994.4282037828277;1012.3571532077184;10873.782524349468;11052.447377631086;11092.057175140042;11128.666140015812;11161.107111431065;539541;555281;655663;668416;670329;105175;115691.42154289343;121642;131441.99999999808;137997.31001344929;978.9167920726818;993.4238141877694;999.6651121874108;1001.67946257197;1029.9691009269884;15384.84615153834;18117.91292522453;19668.803311966698;19681.507962301057;19713.70429443531;53500;54037;54212.45787542074;54296;54939.175912360544;75417.24582754102;80036;87381.68927465966;88927.55536222536;90948.72628184348;7200.531965422231;8249.340052795882;8365.456245344034;8416.24253817135;8542.78643033929;11038;12865.678358041125;13344.466221351036;13651.726965460834;13719.588412347646;9691.806163876541;10550.788984220426;11750.823737644114;11751.059915206934;11884.584039558575;8471.66113355459;10789.759177694352;11602.70993225176;11823.172377933462;11963;11805.291682499077;11824.46789894474;11931.522739090338;12131.939340303243;12213;7547.811304717426;8232.135625759449;8233.752987410388;8272.420930535045;8672.13278672153;11651.650450486499;11812.881871181522;11868.881311187122;12031.15781895259;12060.457279422271 08:33:33 INFO - PROCESS | 1659 | |3;mootools.html;1407.2541552976925;1623.1235133028165;1658.8424099710528;1981.4253866378751;2052.127845665592;8137.308328792053;8809.823803523934;8988.415752976052;9005.954970225155;9007.72976810695;8650.437721548094;8858.202761751441;8870.37907346486;8884;8891.02198758136;2253.2902135827244;2511.2717311979504;2543.4277287610325;2558.09187738353;2569.409035921739;2552.2853600991753;2595.390083330416;2600.1549496413645;2600.45390468001;2602.544554702929;2369.5853225685437;2425.490646964131;2430.829841911067;2436.622323539845;2444.2667199840066;2519.3701574606257;2553.808464365171;2569.357660584854;2570.4859028194437;2573.4724381501746;2529.557327467704;2559.1938539360044;2567.281161274846;2568.653231813719;2577.1624222127884;8657.783555411102;8745.825083498294;8822.514761688104;8836.337274704423;8859;5515.806946756844;5544.972275138639;5629.746661400247;5672.631278966854;5681.801136960186;3586.1214002569277;3653.5798383186007;3746.3818469952425;3750.324941510527;3755.248950209969;1043.6608102366686;1071.0521188748014;1082.8917108289186;1089.4716062709579;1113.3765091548678;11914.880851191463;12016.939915300367;12050.457729402198;12073.45669444877;12091.911727944527;4329.956700433018;4962.751862406865;5057.241413787888;5058.114829904752;5068;10398.896011039944;10433.895661043443;10436.5825366986;10442.738931526772;10443.895561044445;5625.8593535161945;5634.661920284796;5641.887162256732;5643.5203007743985;5645.125005624135;4455.9554404456185;4619.0530941156985;4865.416150062015;4966.553010229097;5033.993201359743;3988.3618621020496;4068.1253530490494;4071.6742660587083;4100.052475074375;4121.361189015647;1334.9933250333881;1359.734851703928;1362.5435479114608;1368.555219553649;1369.3495589594863;1334.4662135145825;1352.0468070010618;1368.0628769293066;1406.0719924849755;1420.8010878476814;8109.229623185788;8271.17288271168;8277.337812974947;8361.498310101413;8376.874346884802;528.7673423693562;533.1842281309525;543.0035884152625;550.4770468055283;551.1044552602021;6230.283517395557;6286.717097730613;6290.716917738713;6353.078803573456;6355.046742988588;10436.739081523023;10910.399928003877;10926.125909927347;10939.617113400993;10950.123990080774;10282.691519254435;10478.842817357745;10625.256232063835;10687.572497100184;10729.731756705989;7029;8404.07555168931;8422.705205317785;8456.365772567082;8493.745187644381;7791.766247012599;7948.125706172432;7977.680892764224;8071.394645401501;8083.797905052421;9421.528923553931;9658.420494770337;9763.218942484726;9801.656942007281;9828.705138845846;9754.658586949423;9943.353682010646;10044.849327259952;10144.847827282449;10172.13536849376;6763.560368576027;7295.781126566211;7509.924900751141;7550.244975502405;7582.696692132253;8340.749777506684;8358.749237522885;8373.371997100363;8403.117672644285;8418.915810842058;7280.96359518199;7320.231375705474;7403.8149046274275;7514.211007844097;7586.886196706943;7263.4915555910575;7285.417166626551;7365.410767138509;7374.705011799468;7468.402527797654;7785.766427007199;7804.219578042151;7832.099308579586;7840.3335716464735;7860.0567931848545;6093.847653808691;7002.824929376807;7045.401140903081;7066.752663656747;7092.751753688803;7110.288971102848;7165.140183178052;7175.569465832067;7199.496035277481;7242.167150777728 08:33:33 INFO - PROCESS | 1659 | |4;prototype.html;304.4975789946588;308.6897667843817;344.3973047167457;349.4269398186974;353.1013570463171;6658.23430305515;6667.699953502092;6669.766558170465;6701.731930722772;6726.730930762764;6609.942409214526;6641.169853768279;6661.26726060133;6663.900041499369;6665.400113989752;4779.235322348425;4868.05073010763;4909.288153217782;4918.778654960535;5013.072581572412;4712.316714076457;4930.383702037246;4939.728314942677;4972.801087956477;4974.726390048546;4880.072786170634;4903.460619331889;4907.779149938244;4910.1652719037675;4915.655904086698;4719.244920812671;4790.993891282835;4814.831480898151;4835.05716385305;4842.346283251769;4206.831726730927;4211.7262377945335;4229.048464095585;4234.6823988201;4261.4673165854265;6318.905216421757;6329.145565348711;6367.42693157618;6389.393007664265;6405.071264666597;5810.622309549908;5811.244538210048;5832.6500409975115;5841.357450680422;5844.620099693549;6742.325767423267;7059.399951004119;7079.221285658574;7079.964600176966;7087.255838136973;4603.884902877455;4619.792109355087;4635;4639.907201855944;4647.697899636512;7404.703811847519;7482.363999060087;7484.32641062307;7501.099868015818;7506.774796756106;4671.6262698984;4676.5791078802395;4679.040796636681;4681.133990211753;4683.7892294846815;3941.724079314478;4732.810687572527;4742.264948932921;4752.52474752522;4760.143544017836;4657.417822772153;5647.29408823897;5648.576356773258;5654.802081927195;5657.264555607745;6806;7052.717891284291;7065.258147894499;7072.434205263669;7079.575225486487;1609.4688752711577;1647.744599587066;1649.8432648898336;1652.314289569839;1652.8512433881008;994.2046362909643;1037.8131936251548;1047.471027131309;1061.0344586426315;1061.2040969273046;5456.672599644034;5484.808031718871;6361.650109243943;6392.073149393312;6516.2506311773805;7380.409567234608;7393.371563417169;7409.998600755532;7413.851722965618;7418.888716669254;6608.933910660832;6618.503612229102;6618.569792963442;6622.536422450478;6636.568623039583;3828.9617103829164;3869.6323849234277;3870.554886188068;3874.1670540833857;3876.069743261597;7364.153122390993;7688.154303026663;7707.884381734167;7727;7742.4580279381025;6118.877622447526;6931.653417329113;7597.430192735456;7606.581638009962;7623.35201507867;7719;7723.189065148079;7724.575148366781;7726.806829829299;7731.38148948094;7000.544964577286;7373.77878663641;7383.040204773345;7394.112706475043;7397.889031664422;7206.351428371472;7245.420366370572;7265.527740696838;7276.090488688914;7276.489611084167;7676.692932282646;7679.616019199129;7700.075990881131;7702.576358300347;7708.229177082247;3917.2557214129224;4704.694194877322;4705.411823522059;4715.033418149203;4725.669203155883;6020.277566692025;6561.786069577143;6760.695768690519;7287.635618219174;7319.231480694663;6763.188417389944;6982.825429364307;7000.894986575104;7025.683844227125;7025.929740702528;5993.730282137227;6239.720857224167;6945.305469453015;6990.8951365728535;7000.789976300719;6837.453003759588;6849.1096157499205;6901.896471553031;6908.585484870924;6917.377436030782;6754.088198093391;7040.577565346096;7061.2232654409;7068.540544864567;7118.822029449098;6627.9337206627315;6741.393274605309;6839;6853.691583878638;6858.622775747382 08:33:33 INFO - PROCESS | 1659 | |5;yui.html;679.2222904774034;774.5120574038358;779.1740754799911;781.4764108047608;795.7254747112248;14954.476593319236;15280.465183718572;15333.003354781931;15378.769318460232;17781.911090444562;16147.757783633255;16207.594810129753;16212.83787162131;16217.351305947748;16237.756433653474;733.3986131372277;741.0810594862359;987.9604815807377;1020.2857999400412;1021.6832781837617;998.3261298623437;1009.6970908727352;1016.2835201183185;1016.298753859838;1016.3901659004575;1041.8593489878883;1059.5761695321855;1062.1661995333664;1062.3254233561668;1062.952167152476;746.0860445953717;758.3743411685326;761.5811303782953;1048.5229220704562;1056.8361903904868;741.8738814401564;777.8949841771401;786.0724345272577;788.467784245632;789.5539020453425;14621.415143394252;14650.853491465055;14656.560303190814;14663.70672586553;14692.191929443876;12527.56153534631;12651.557195498113;12722.173058751245;12873.163244389178;12916.160449570747;10229.18166546682;10259.948700256451;10335.328203666737;10385.480725963747;10426.791464170674;3457.9308413831836;3463;3507.368673638744;3604.53141091659;3714.0714821294678;86589.56705216496;86709.56645216797;87663;87758.12241877563;87761.56119219426;9199.218066464291;9381.5309234538;9543.665971690958;9617;10210.744731381776;21748.34754957322;21857.344279671634;21896.452588685093;21911.67132493014;22250.666240006412;7298.2701729826595;7343.703260544357;7386.815329616695;7443.590602516913;7568.583727894908;4025.1547175092974;4107.424960505472;4116.444280022219;4151.982764222785;4290.570942905684;3746.775193488345;3766.7928263945746;3778.3576791945434;3791.0901383668274;3793.203427280302;669.3574168797911;670.7585269303047;677.123125552401;692.1279188222876;695.1449716848305;544.8038706065813;574.9712514374264;626.6083697688945;648.7567162314132;649.8927676933348;7123.358897699129;7987.201279871966;8518;8565.229129378262;8662.740117796477;553.4299671338528;603.7434090511515;621.8289970258215;631.5200447659812;640.9361854827448;3788.1855401088897;3846.7307288490097;3884.4950156479476;3938.9212215755524;3988.461557689675;99589;99942.5002874981;100878.49560752296;101307.98692013133;102190;80821.59589202135;81103;84945;84969.57515212384;87625;68189;73018;74530;74560.25439745642;77263.45473090507;8420.073791883011;8681.2186903179;8687.218150366498;8737.91262087371;8871.911280887109;45884.54115458803;47911;49683.503164967886;51772.74113629558;52172.739136305594;65247.04258872241;67479;67519.3248067513;68137;75272;9667.758306042406;11225;11440.485178166837;11566.884331156582;11632;11903;12011.159218854596;12208.32854193028;12221.38893055326;12430.316332601433;11774.705632359259;11792.29246245228;11995.1003674723;12265.448054837376;12388.69918658564;11786.46960886745;12022.579209727968;12069.27584344942;12106.394680265776;12273.07951903593;11370.20408571392;11581.710457238636;11630.476628551567;11953.880461195276;12025.037996960396;8377.49735015901;9795.51022448889;9813.313068085166;9944.502774861083;9954.352967057119;9781.51092445389;9980.051895069957;9984.737088874106;10028.247881408777;10107.797844043225 08:33:33 INFO - PROCESS | 1659 | __end_tp_report 08:33:33 INFO - PROCESS | 1659 | __start_cc_report 08:33:33 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1016 08:33:33 INFO - PROCESS | 1659 | __end_cc_report 08:33:33 INFO - PROCESS | 1659 | __startTimestamp1460648013097__endTimestamp 08:33:33 INFO - PROCESS | 1659 | 08:33:33 INFO - PROCESS | 1659 | ------- Summary: start ------- 08:33:33 INFO - PROCESS | 1659 | Number of tests: 6 08:33:33 INFO - PROCESS | 1659 | 08:33:33 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5783.44 Median:5995.07 stddev:632.34 (10.5%) stddev-sans-first:606.03 08:33:33 INFO - PROCESS | 1659 | Values: 3400.9 3493.4 3494.8 3505.3 3560.1 5694.1 5700.5 5738.9 5748.1 5787.0 5714.6 5734.9 5748.7 5755.4 5785.6 5169.5 5180.2 5184.1 5225.7 5309.6 5274.0 5292.0 5292.4 5292.6 5300.8 5247.7 5253.1 5277.8 5279.2 5284.9 5297.1 5320.4 5321.1 5333.5 5369.1 5092.0 5244.8 5255.9 5263.3 5266.6 5613.8 5625.2 5638.9 5647.8 5647.9 6151.9 6294.9 6296.5 6315.1 6326.8 6267.5 6297.5 6304.4 6335.3 6354.2 5838.6 5850.9 5878.3 5880.6 5933.0 6229.0 6258.8 6281.0 6325.3 6338.7 5246.7 6196.8 6197.2 6201.5 6214.7 6295.1 6308.3 6314.5 6316.7 6319.7 6071.3 6180.8 6248.9 6272.0 6302.4 6270.7 6274.4 6282.2 6303.1 6311.7 4892.3 4927.5 4932.8 4938.3 4942.0 4402.5 4417.9 4477.1 4481.1 4512.8 5735.1 5737.2 5745.0 5751.4 5753.0 6302.7 6306.7 6307.5 6325.1 6340.7 5617.5 5671.5 5705.2 5764.9 5795.2 5322.0 5360.5 5365.4 5375.3 5392.4 6297.7 6317.8 6337.0 6338.9 6340.7 6177.8 6233.8 6324.6 6333.7 6335.5 6317.4 6344.4 6358.3 6363.9 6364.7 6130.4 6273.5 6290.9 6312.6 6325.7 6229.4 6272.6 6307.5 6308.3 6321.9 5947.3 6122.8 6130.2 6175.9 6193.5 5994.9 6111.4 6119.4 6130.9 6149.4 5976.5 5995.1 6013.3 6028.1 6064.9 6117.1 6125.9 6127.1 6129.9 6131.1 5872.6 5924.5 5964.0 5998.0 6016.3 08:33:33 INFO - PROCESS | 1659 | 08:33:33 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:60292.22 Median:39490.51 stddev:121429.78 (307.5%) stddev-sans-first:121687.42 08:33:33 INFO - PROCESS | 1659 | Values: 493.3 536.9 623.0 670.2 670.5 29155.6 39105.4 39230.6 39323.4 39541.6 43523.7 44092.3 44102.3 44189.8 44273.0 9681.5 9937.5 9941.2 9949.0 9995.0 9652.7 9816.4 9826.2 9830.9 9934.8 9893.6 9986.8 9996.7 9999.0 9999.2 9892.2 9957.3 9968.9 9973.7 9976.5 9729.5 9874.2 9881.9 9890.5 9893.5 42216.0 42527.8 42544.6 42578.0 42697.2 3288.3 3476.0 3574.5 3849.6 4032.3 28439.7 29442.0 29619.1 29963.9 30120.0 2752.5 2830.7 2834.8 2915.1 2958.8 493110.0 504310.0 505958.0 508051.0 508688.5 42050.0 51205.5 51243.2 78828.8 79530.6 52095.7 52213.7 52492.5 52586.7 52648.2 17471.4 17696.7 17696.7 17703.8 17705.3 13976.6 14081.2 14104.6 14105.3 14123.6 16336.8 16362.5 16634.8 16661.9 16717.8 7063.0 7085.4 7139.6 7139.7 7181.4 851.6 923.8 962.6 1037.8 1098.3 27043.0 28748.7 29328.0 29969.9 30678.0 1140.9 1147.0 1153.8 1168.3 1202.2 15059.0 15242.9 15643.5 15756.4 16518.6 515484.0 517698.0 520499.0 520812.0 521905.0 62178.0 62458.8 62530.0 62571.4 62870.1 1335.0 1373.9 1554.3 1618.7 1673.5 29297.1 29491.0 29501.9 30140.8 30340.0 48151.0 48492.0 48607.5 48666.8 48707.8 278402.0 327550.0 329589.0 330536.0 333427.0 33460.5 45047.5 46369.0 48208.3 48297.3 37906.6 44609.8 44985.3 45258.0 45680.0 32230.5 36788.6 37331.0 38782.2 39439.4 37670.6 37995.7 38284.0 38398.6 39136.0 38509.6 38706.8 38935.8 39233.0 39670.6 29980.7 32585.3 34313.8 34389.5 34431.0 22143.6 23960.9 24960.3 25352.4 25740.0 08:33:33 INFO - PROCESS | 1659 | 08:33:33 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35658.74 Median:2243.78 stddev:103117.32 (4595.7%) stddev-sans-first:103372.55 08:33:33 INFO - PROCESS | 1659 | Values: 375.1 380.8 382.4 385.8 387.3 20092.1 20731.5 21265.0 21308.6 21384.7 20592.1 20943.2 21446.7 21467.9 21918.8 4329.9 5669.1 5857.0 5879.4 5887.8 4563.3 6229.3 6407.8 6410.6 6429.0 5594.1 6329.8 6379.1 6462.7 6462.9 6194.4 6372.4 6376.9 6399.5 6404.5 4880.2 5464.4 5538.8 5554.6 5561.4 25996.5 26000.0 26109.5 26415.2 26502.9 2178.8 2200.1 2243.7 2243.9 2245.7 14572.6 14648.6 14706.9 15086.7 15295.3 1689.6 1773.0 1787.9 1825.0 1844.5 105587.0 106427.0 110089.0 111119.4 111912.9 9459.7 11226.5 11570.4 11613.1 11717.0 14474.1 14519.5 14567.0 14598.3 14617.4 8953.3 8980.2 9022.2 9034.4 9124.7 9227.1 9265.4 9284.2 9325.0 9435.2 4577.1 4593.7 4596.4 4605.6 4637.4 1669.3 1930.9 1945.0 1956.4 1985.1 933.8 944.2 1015.4 1029.2 1046.1 19569.4 20138.0 20474.6 20495.0 20565.8 964.5 978.5 982.7 994.4 1012.4 10873.8 11052.4 11092.1 11128.7 11161.1 539541.0 555281.0 655663.0 668416.0 670329.0 105175.0 115691.4 121642.0 131442.0 137997.3 978.9 993.4 999.7 1001.7 1030.0 15384.8 18117.9 19668.8 19681.5 19713.7 53500.0 54037.0 54212.5 54296.0 54939.2 75417.2 80036.0 87381.7 88927.6 90948.7 7200.5 8249.3 8365.5 8416.2 8542.8 11038.0 12865.7 13344.5 13651.7 13719.6 9691.8 10550.8 11750.8 11751.1 11884.6 8471.7 10789.8 11602.7 11823.2 11963.0 11805.3 11824.5 11931.5 12131.9 12213.0 7547.8 8232.1 8233.8 8272.4 8672.1 11651.7 11812.9 11868.9 12031.2 12060.5 08:33:33 INFO - PROCESS | 1659 | 08:33:33 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:6123.77 Median:546.74 stddev:3223.74 (589.6%) stddev-sans-first:3213.29 08:33:33 INFO - PROCESS | 1659 | Values: 1407.3 1623.1 1658.8 1981.4 2052.1 8137.3 8809.8 8988.4 9006.0 9007.7 8650.4 8858.2 8870.4 8884.0 8891.0 2253.3 2511.3 2543.4 2558.1 2569.4 2552.3 2595.4 2600.2 2600.5 2602.5 2369.6 2425.5 2430.8 2436.6 2444.3 2519.4 2553.8 2569.4 2570.5 2573.5 2529.6 2559.2 2567.3 2568.7 2577.2 8657.8 8745.8 8822.5 8836.3 8859.0 5515.8 5545.0 5629.7 5672.6 5681.8 3586.1 3653.6 3746.4 3750.3 3755.2 1043.7 1071.1 1082.9 1089.5 1113.4 11914.9 12016.9 12050.5 12073.5 12091.9 4330.0 4962.8 5057.2 5058.1 5068.0 10398.9 10433.9 10436.6 10442.7 10443.9 5625.9 5634.7 5641.9 5643.5 5645.1 4456.0 4619.1 4865.4 4966.6 5034.0 3988.4 4068.1 4071.7 4100.1 4121.4 1335.0 1359.7 1362.5 1368.6 1369.3 1334.5 1352.0 1368.1 1406.1 1420.8 8109.2 8271.2 8277.3 8361.5 8376.9 528.8 533.2 543.0 550.5 551.1 6230.3 6286.7 6290.7 6353.1 6355.0 10436.7 10910.4 10926.1 10939.6 10950.1 10282.7 10478.8 10625.3 10687.6 10729.7 7029.0 8404.1 8422.7 8456.4 8493.7 7791.8 7948.1 7977.7 8071.4 8083.8 9421.5 9658.4 9763.2 9801.7 9828.7 9754.7 9943.4 10044.8 10144.8 10172.1 6763.6 7295.8 7509.9 7550.2 7582.7 8340.7 8358.7 8373.4 8403.1 8418.9 7281.0 7320.2 7403.8 7514.2 7586.9 7263.5 7285.4 7365.4 7374.7 7468.4 7785.8 7804.2 7832.1 7840.3 7860.1 6093.8 7002.8 7045.4 7066.8 7092.8 7110.3 7165.1 7175.6 7199.5 7242.2 08:33:33 INFO - PROCESS | 1659 | 08:33:33 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:5763.03 Median:6614.22 stddev:1839.68 (27.8%) stddev-sans-first:1798.64 08:33:33 INFO - PROCESS | 1659 | Values: 304.5 308.7 344.4 349.4 353.1 6658.2 6667.7 6669.8 6701.7 6726.7 6609.9 6641.2 6661.3 6663.9 6665.4 4779.2 4868.1 4909.3 4918.8 5013.1 4712.3 4930.4 4939.7 4972.8 4974.7 4880.1 4903.5 4907.8 4910.2 4915.7 4719.2 4791.0 4814.8 4835.1 4842.3 4206.8 4211.7 4229.0 4234.7 4261.5 6318.9 6329.1 6367.4 6389.4 6405.1 5810.6 5811.2 5832.7 5841.4 5844.6 6742.3 7059.4 7079.2 7080.0 7087.3 4603.9 4619.8 4635.0 4639.9 4647.7 7404.7 7482.4 7484.3 7501.1 7506.8 4671.6 4676.6 4679.0 4681.1 4683.8 3941.7 4732.8 4742.3 4752.5 4760.1 4657.4 5647.3 5648.6 5654.8 5657.3 6806.0 7052.7 7065.3 7072.4 7079.6 1609.5 1647.7 1649.8 1652.3 1652.9 994.2 1037.8 1047.5 1061.0 1061.2 5456.7 5484.8 6361.7 6392.1 6516.3 7380.4 7393.4 7410.0 7413.9 7418.9 6608.9 6618.5 6618.6 6622.5 6636.6 3829.0 3869.6 3870.6 3874.2 3876.1 7364.2 7688.2 7707.9 7727.0 7742.5 6118.9 6931.7 7597.4 7606.6 7623.4 7719.0 7723.2 7724.6 7726.8 7731.4 7000.5 7373.8 7383.0 7394.1 7397.9 7206.4 7245.4 7265.5 7276.1 7276.5 7676.7 7679.6 7700.1 7702.6 7708.2 3917.3 4704.7 4705.4 4715.0 4725.7 6020.3 6561.8 6760.7 7287.6 7319.2 6763.2 6982.8 7000.9 7025.7 7025.9 5993.7 6239.7 6945.3 6990.9 7000.8 6837.5 6849.1 6901.9 6908.6 6917.4 6754.1 7040.6 7061.2 7068.5 7118.8 6627.9 6741.4 6839.0 6853.7 6858.6 08:33:33 INFO - PROCESS | 1659 | 08:33:33 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:19178.10 Median:4134.21 stddev:27415.50 (663.1%) stddev-sans-first:27457.22 08:33:33 INFO - PROCESS | 1659 | Values: 679.2 774.5 779.2 781.5 795.7 14954.5 15280.5 15333.0 15378.8 17781.9 16147.8 16207.6 16212.8 16217.4 16237.8 733.4 741.1 988.0 1020.3 1021.7 998.3 1009.7 1016.3 1016.3 1016.4 1041.9 1059.6 1062.2 1062.3 1063.0 746.1 758.4 761.6 1048.5 1056.8 741.9 777.9 786.1 788.5 789.6 14621.4 14650.9 14656.6 14663.7 14692.2 12527.6 12651.6 12722.2 12873.2 12916.2 10229.2 10259.9 10335.3 10385.5 10426.8 3457.9 3463.0 3507.4 3604.5 3714.1 86589.6 86709.6 87663.0 87758.1 87761.6 9199.2 9381.5 9543.7 9617.0 10210.7 21748.3 21857.3 21896.5 21911.7 22250.7 7298.3 7343.7 7386.8 7443.6 7568.6 4025.2 4107.4 4116.4 4152.0 4290.6 3746.8 3766.8 3778.4 3791.1 3793.2 669.4 670.8 677.1 692.1 695.1 544.8 575.0 626.6 648.8 649.9 7123.4 7987.2 8518.0 8565.2 8662.7 553.4 603.7 621.8 631.5 640.9 3788.2 3846.7 3884.5 3938.9 3988.5 99589.0 99942.5 100878.5 101308.0 102190.0 80821.6 81103.0 84945.0 84969.6 87625.0 68189.0 73018.0 74530.0 74560.3 77263.5 8420.1 8681.2 8687.2 8737.9 8871.9 45884.5 47911.0 49683.5 51772.7 52172.7 65247.0 67479.0 67519.3 68137.0 75272.0 9667.8 11225.0 11440.5 11566.9 11632.0 11903.0 12011.2 12208.3 12221.4 12430.3 11774.7 11792.3 11995.1 12265.4 12388.7 11786.5 12022.6 12069.3 12106.4 12273.1 11370.2 11581.7 11630.5 11953.9 12025.0 8377.5 9795.5 9813.3 9944.5 9954.4 9781.5 9980.1 9984.7 10028.2 10107.8 08:33:33 INFO - PROCESS | 1659 | -------- Summary: end -------- 08:33:33 INFO - PROCESS | 1659 | 08:33:33 INFO - TEST-INFO | 1659: exit 0 08:33:33 INFO - TEST-OK | dromaeo_css | took 1112872ms 08:33:33 INFO - TEST-START | kraken 08:33:33 INFO - operating with platform_type : mac_ 08:33:33 INFO - Initialising browser for kraken test... 08:33:33 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmp5ekgY5/profile http://localhost:49276/getInfo.html) 08:33:35 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 08:33:35 INFO - PROCESS | 1670 | colorDepth:24 08:33:35 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 08:33:35 INFO - PROCESS | 1670 | __metrics 08:33:35 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:33:35 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:33:36 INFO - TEST-INFO | 1670: exit 0 08:33:36 INFO - Browser initialized. 08:33:36 INFO - Running cycle 1/1 for kraken test... 08:33:36 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmp5ekgY5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:33:48 INFO - PROCESS | 1672 | RSS: Main: 207708160 08:33:48 INFO - PROCESS | 1672 | 08:33:51 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 08:33:51 INFO - PROCESS | 1672 | RSS: Main: 262799360 08:33:51 INFO - PROCESS | 1672 | 08:33:54 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 08:33:54 INFO - PROCESS | 1672 | RSS: Main: 272850944 08:33:54 INFO - PROCESS | 1672 | 08:33:58 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 08:33:58 INFO - PROCESS | 1672 | RSS: Main: 263524352 08:33:58 INFO - PROCESS | 1672 | 08:34:00 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 08:34:01 INFO - PROCESS | 1672 | RSS: Main: 264564736 08:34:01 INFO - PROCESS | 1672 | 08:34:03 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:34:03 INFO - PROCESS | 1672 | RSS: Main: 264314880 08:34:03 INFO - PROCESS | 1672 | 08:34:06 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 08:34:07 INFO - PROCESS | 1672 | RSS: Main: 265502720 08:34:07 INFO - PROCESS | 1672 | 08:34:09 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 08:34:10 INFO - PROCESS | 1672 | RSS: Main: 265531392 08:34:10 INFO - PROCESS | 1672 | 08:34:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 08:34:14 INFO - PROCESS | 1672 | RSS: Main: 265850880 08:34:14 INFO - PROCESS | 1672 | 08:34:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:34:16 INFO - PROCESS | 1672 | RSS: Main: 298496000 08:34:16 INFO - PROCESS | 1672 | 08:34:18 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:34:18 INFO - PROCESS | 1672 | RSS: Main: 299528192 08:34:18 INFO - PROCESS | 1672 | 08:34:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:34:21 INFO - PROCESS | 1672 | RSS: Main: 305717248 08:34:21 INFO - PROCESS | 1672 | 08:34:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:34:24 INFO - PROCESS | 1672 | RSS: Main: 307175424 08:34:24 INFO - PROCESS | 1672 | 08:34:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:34:27 INFO - PROCESS | 1672 | RSS: Main: 307220480 08:34:27 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 08:34:29 INFO - PROCESS | 1672 | RSS: Main: 369627136 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | __start_tp_report 08:34:29 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 08:34:29 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 08:34:29 INFO - PROCESS | 1672 | |i|pagename|runs| 08:34:29 INFO - PROCESS | 1672 | |0;ai-astar;112;109;112;109;112;113;117;111;111;110 08:34:29 INFO - PROCESS | 1672 | |1;audio-beat-detection;155;119;157;145;117;139;117;151;159;147 08:34:29 INFO - PROCESS | 1672 | |2;audio-dft;182;184;182;184;184;187;187;184;184;185 08:34:29 INFO - PROCESS | 1672 | |3;audio-fft;71;72;77;117;55;55;58;57;115;56 08:34:29 INFO - PROCESS | 1672 | |4;audio-oscillator;93;97;130;79;77;85;119;75;80;117 08:34:29 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;87;85;88;85;87;83;88;84;87;83 08:34:29 INFO - PROCESS | 1672 | |6;imaging-darkroom;100;93;91;92;101;92;92;93;93;93 08:34:29 INFO - PROCESS | 1672 | |7;imaging-desaturate;81;84;70;124;71;123;132;132;85;88 08:34:29 INFO - PROCESS | 1672 | |8;json-parse-financial;58;59;59;57;59;58;60;71;51;55 08:34:29 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;62;67;63;72;73;69;67;67;65;66 08:34:29 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;81;82;81;80;80;81;80;81;80;89 08:34:29 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;132;130;128;119;117;117;129;119;117;120 08:34:29 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;133;126;134;134;130;123;134;133;132;135 08:34:29 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;66;66;67;66;65;66;65;66;67;65 08:34:29 INFO - PROCESS | 1672 | __end_tp_report 08:34:29 INFO - PROCESS | 1672 | __start_cc_report 08:34:29 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1241 08:34:29 INFO - PROCESS | 1672 | __end_cc_report 08:34:29 INFO - PROCESS | 1672 | __startTimestamp1460648069522__endTimestamp 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | ------- Summary: start ------- 08:34:29 INFO - PROCESS | 1672 | Number of tests: 14 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:2.32 (2.1%) stddev-sans-first:2.46 08:34:29 INFO - PROCESS | 1672 | Values: 112.0 109.0 112.0 109.0 112.0 113.0 117.0 111.0 111.0 110.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:149.00 stddev:16.89 (11.3%) stddev-sans-first:17.09 08:34:29 INFO - PROCESS | 1672 | Values: 155.0 119.0 157.0 145.0 117.0 139.0 117.0 151.0 159.0 147.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:184.30 Median:184.00 stddev:1.70 (0.9%) stddev-sans-first:1.59 08:34:29 INFO - PROCESS | 1672 | Values: 182.0 184.0 182.0 184.0 184.0 187.0 187.0 184.0 184.0 185.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:73.30 Median:57.50 stddev:23.89 (41.6%) stddev-sans-first:25.33 08:34:29 INFO - PROCESS | 1672 | Values: 71.0 72.0 77.0 117.0 55.0 55.0 58.0 57.0 115.0 56.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:79.50 stddev:19.99 (25.1%) stddev-sans-first:21.19 08:34:29 INFO - PROCESS | 1672 | Values: 93.0 97.0 130.0 79.0 77.0 85.0 119.0 75.0 80.0 117.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:87.00 stddev:1.95 (2.2%) stddev-sans-first:2.01 08:34:29 INFO - PROCESS | 1672 | Values: 87.0 85.0 88.0 85.0 87.0 83.0 88.0 84.0 87.0 83.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:92.50 stddev:3.50 (3.8%) stddev-sans-first:2.96 08:34:29 INFO - PROCESS | 1672 | Values: 100.0 93.0 91.0 92.0 101.0 92.0 92.0 93.0 93.0 93.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:76.00 stddev:25.54 (33.6%) stddev-sans-first:26.24 08:34:29 INFO - PROCESS | 1672 | Values: 81.0 84.0 70.0 124.0 71.0 123.0 132.0 132.0 85.0 88.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:59.00 stddev:5.06 (8.6%) stddev-sans-first:5.36 08:34:29 INFO - PROCESS | 1672 | Values: 58.0 59.0 59.0 57.0 59.0 58.0 60.0 71.0 51.0 55.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:3.51 (5.2%) stddev-sans-first:3.20 08:34:29 INFO - PROCESS | 1672 | Values: 62.0 67.0 63.0 72.0 73.0 69.0 67.0 67.0 65.0 66.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:81.00 stddev:2.72 (3.4%) stddev-sans-first:2.88 08:34:29 INFO - PROCESS | 1672 | Values: 81.0 82.0 81.0 80.0 80.0 81.0 80.0 81.0 80.0 89.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:122.80 Median:124.00 stddev:6.14 (5.0%) stddev-sans-first:5.54 08:34:29 INFO - PROCESS | 1672 | Values: 132.0 130.0 128.0 119.0 117.0 117.0 129.0 119.0 117.0 120.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.40 Median:133.50 stddev:3.95 (3.0%) stddev-sans-first:4.15 08:34:29 INFO - PROCESS | 1672 | Values: 133.0 126.0 134.0 134.0 130.0 123.0 134.0 133.0 132.0 135.0 08:34:29 INFO - PROCESS | 1672 | 08:34:29 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 08:34:29 INFO - PROCESS | 1672 | Values: 66.0 66.0 67.0 66.0 65.0 66.0 65.0 66.0 67.0 65.0 08:34:29 INFO - PROCESS | 1672 | -------- Summary: end -------- 08:34:29 INFO - PROCESS | 1672 | 08:34:30 INFO - TEST-INFO | 1672: exit 0 08:34:30 INFO - TEST-OK | kraken | took 56653ms 08:34:30 INFO - SUITE-END | took 1169s 08:34:30 INFO - Completed test suite (00:19:29) 08:34:30 INFO - javascript benchmark 08:34:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3400.880969166079, 3493.4235851084572, 3494.8252587370625, 3505.2638945821386, 3560.145565064384, 5694.088945768678, 5700.458456446636, 5738.885222295562, 5748.137779333102, 5787.045137552304, 5714.628549144302, 5734.885302293962, 5748.655080695151, 5755.395683453229, 5785.623934444268, 5169.457206993268, 5180.197069454241, 5184.066867963756, 5225.712585807779, 5309.601779866525, 5273.973630131844, 5291.999999999981, 5292.391374991867, 5292.576593872481, 5300.840974770763, 5247.711375874325, 5253.106971814801, 5277.788888444457, 5279.155335146377, 5284.947150528484, 5297.073012222865, 5320.361556613192, 5321.148616221386, 5333.519983201491, 5369.114096174166, 5092.032513822362, 5244.79020839166, 5255.868603284948, 5263.26314315996, 5266.605004624668, 5613.80351687693, 5625.240592519998, 5638.943610563924, 5647.830565083054, 5647.8588035299035, 6151.89265932132, 6294.905576416358, 6296.527760417987, 6315.147455093549, 6326.778562750282, 6267.4986001119805, 6297.527685423612, 6304.401081897213, 6335.303116657164, 6354.237491501003, 5838.562107841886, 5850.946829570676, 5878.323992740804, 5880.592846741479, 5933.0, 6229.034499652561, 6258.812235632939, 6281.0, 6325.272593651789, 6338.651374174372, 5246.658967167122, 6196.783112591038, 6197.225346831646, 6201.503879689614, 6214.658193799294, 6295.055741638791, 6308.337624549356, 6314.463270621956, 6316.652584107918, 6319.747210111544, 6071.301800292935, 6180.845478863064, 6248.9062664060075, 6271.96864015677, 6302.401271879165, 6270.749170033238, 6274.372562743689, 6282.2147231596055, 6303.149074874925, 6311.652859092702, 4892.2661600760175, 4927.457979622239, 4932.778024988884, 4938.2839488274, 4942.0, 4402.537733537996, 4417.933730994037, 4477.059817438309, 4481.126180394857, 4512.842050528216, 5735.11105778605, 5737.196792449061, 5745.0, 5751.3673495916255, 5753.021986262345, 6302.684865756693, 6306.684665766693, 6307.4954003679595, 6325.05124231369, 6340.651264180422, 5617.522510586646, 5671.489565939085, 5705.22979397776, 5764.942350576441, 5795.1886735857015, 5321.973390133025, 5360.517553420211, 5365.409804921455, 5375.3012108425655, 5392.433794451605, 6297.716602752978, 6317.778877739349, 6336.968315158487, 6338.873222535523, 6340.651264180515, 6177.84555386119, 6233.750649974132, 6324.620522768649, 6333.65164915934, 6335.4931605472375, 6317.431431171217, 6344.365563443804, 6358.268799088164, 6363.93636063652, 6364.713587888648, 6130.428711073822, 6273.52948528853, 6290.874182516415, 6312.5581209314905, 6325.683715814283, 6229.4393504584805, 6272.6236425814595, 6307.526935479766, 6308.306086330408, 6321.905171422341, 5947.316058653138, 6122.755089796537, 6130.172426722335, 6175.9073613894925, 6193.473554747717, 5994.850128746816, 6111.419415155553, 6119.44924956756, 6130.908036379368, 6149.41580549847, 5976.521878249642, 5995.070764031582, 6013.338532761305, 6028.09578563219, 6064.878702426015, 6117.084826762281, 6125.877482450415, 6127.111568822406, 6129.938700612936, 6131.141640170291, 5872.647641141545, 5924.52603791687, 5964.015937370285, 5998.0103282957825, 6016.278046634432], "unit": "score", "name": "dojo.html", "value": 5743.706572557288}, {"lowerIsBetter": false, "replicates": [493.28719999600594, 536.8550491367333, 622.9906551401727, 670.1823774994507, 670.4971271546339, 29155.562666560018, 39105.413418798744, 39230.60769392312, 39323.42706291745, 39541.604583954155, 43523.6942891713, 44092.3386149208, 44102.33846492305, 44189.77905110478, 44273.0, 9681.467519286454, 9937.503124843764, 9941.204703623729, 9949.005099490028, 9994.950025249864, 9652.662156824512, 9816.361936474143, 9826.213902887752, 9830.852537211947, 9934.801303973916, 9893.604255829758, 9986.750331241705, 9996.700098997042, 9999.000099990015, 9999.2000639949, 9892.15916647086, 9957.253206009578, 9968.900310996869, 9973.70078897627, 9976.501174941224, 9729.513524323755, 9874.210063194927, 9881.851772223423, 9890.456024918627, 9893.4558599277, 42216.0, 42527.7873610633, 42544.574554254374, 42578.0, 42697.224680395986, 3288.3094550144497, 3476.026712520498, 3574.4995700601935, 3849.615038496156, 4032.2741906456827, 28439.71560284412, 29441.969531066523, 29619.111426657233, 29963.850180749174, 30120.0, 2752.518309295865, 2830.7169283071735, 2834.7873909456875, 2915.110891178211, 2958.8224706517676, 493110.0, 504310.0, 505958.0, 508051.0, 508688.4565577186, 42050.0, 51205.48794512007, 51243.23135152976, 78828.81756773654, 79530.60234698905, 52095.73952130291, 52213.7389313051, 52492.53813425902, 52586.73706631519, 52648.21027684588, 17471.388501402645, 17696.734548981774, 17696.734548981774, 17703.822961770475, 17705.291788328323, 13976.580702578938, 14081.225532595601, 14104.647383815487, 14105.294735263402, 14123.576292711236, 16336.75494867578, 16362.509124726277, 16634.75047874283, 16661.91669041647, 16717.83282167187, 7063.0, 7085.362317391459, 7139.607321597258, 7139.714411423589, 7181.389581885598, 851.642310229705, 923.7806393874529, 962.6005207838668, 1037.7561273100816, 1098.319042193845, 27043.0, 28748.712512875023, 29327.97352092709, 29969.850150749106, 30678.0, 1140.9201355905006, 1146.9942650286862, 1153.8384626152344, 1168.2815068732668, 1202.212550779241, 15059.0, 15242.923785381225, 15643.530694079196, 15756.369745210293, 16518.587035324214, 515484.0, 517698.0, 520499.0, 520812.0, 521905.0, 62178.0, 62458.750824984156, 62530.0, 62571.374286256556, 62870.0569491458, 1334.9733005339838, 1373.924434156111, 1554.3005647458688, 1618.7248167811501, 1673.514680742571, 29297.121086367442, 29490.967816126333, 29501.852490738267, 30140.84929575338, 30340.0, 48151.0, 48492.0, 48607.5139248603, 48666.75666621644, 48707.75646121747, 278402.0, 327550.0, 329589.0, 330536.0, 333427.0, 33460.49809252912, 45047.549524505645, 46369.0, 48208.27687584619, 48297.27554086762, 37906.620933790306, 44609.77695111504, 44985.32522012107, 45258.0, 45680.0, 32230.516542252353, 36788.63211367852, 37331.0, 38782.224355513295, 39439.408408874464, 37670.62329376671, 37995.70365189567, 38284.0, 38398.616013839506, 39136.0, 38509.614903850605, 38706.8387948362, 38935.805320974345, 39233.019174520865, 39670.60329396785, 29980.650870710433, 32585.34829303448, 34313.828430857684, 34389.484157737155, 34430.967070987914, 22143.55712885701, 23960.88019559891, 24960.251192464257, 25352.366190845376, 25740.0], "unit": "score", "name": "ext.html", "value": 19907.103649885372}, {"lowerIsBetter": false, "replicates": [375.0847931048242, 380.8057890475857, 382.38627003659127, 385.76853887667403, 387.2526024772192, 20092.072019892345, 20731.481712957182, 21265.0, 21308.573828523422, 21384.679229811565, 20592.073356698947, 20943.16227350908, 21446.678299825475, 21467.892660536716, 21918.794466304345, 4329.891752706176, 5669.149627555858, 5856.970715146418, 5879.412058794129, 5887.8233652990475, 4563.315502674593, 6229.345918678541, 6407.775727849526, 6410.615363078207, 6428.967855160718, 5594.104943209088, 6329.778457753957, 6379.106925030499, 6462.709178086998, 6462.933615953362, 6194.442500174983, 6372.362763723637, 6376.904346434807, 6399.488040956712, 6404.48764098871, 4880.219164933594, 5464.371597266286, 5538.806141785019, 5554.555635549146, 5561.416051314594, 25996.480070398487, 26000.0, 26109.477810443685, 26415.20754377372, 26502.93988240468, 2178.7603363629987, 2200.141944641591, 2243.6522339037474, 2243.9102435902546, 2245.65192395179, 14572.562823115217, 14648.560543183721, 14706.926465367605, 15086.717629001549, 15295.311710972921, 1689.569159864239, 1773.042557019208, 1787.8659100567509, 1824.9817501824944, 1844.5019844642006, 105587.0, 106427.0, 110089.0, 111119.44440277827, 111912.88087119105, 9459.668911588062, 11226.494807733672, 11570.42147892602, 11613.070954323633, 11717.0, 14474.131552106697, 14519.491817786327, 14567.0, 14598.34307456167, 14617.415303387745, 8953.28373730113, 8980.236679882153, 9022.188003079755, 9034.412763170372, 9124.726258212264, 9227.123423274777, 9265.444073355486, 9284.164425201765, 9324.95337523308, 9435.150836424755, 4577.130345234395, 4593.701409408378, 4596.448426188878, 4605.631549476034, 4637.420322459692, 1669.3406104588862, 1930.903454827253, 1944.9691663418412, 1956.3544030469911, 1985.0868600443669, 933.7665583604099, 944.2163004706077, 1015.4313584392763, 1029.2332212501585, 1046.0951277145214, 19569.412917612495, 20138.0, 20474.590508189754, 20495.0, 20565.829712295694, 964.4502633498838, 978.515634760798, 982.7101005203482, 994.4282037828277, 1012.3571532077184, 10873.782524349468, 11052.447377631086, 11092.057175140042, 11128.666140015812, 11161.107111431065, 539541.0, 555281.0, 655663.0, 668416.0, 670329.0, 105175.0, 115691.42154289343, 121642.0, 131441.99999999808, 137997.31001344929, 978.9167920726818, 993.4238141877694, 999.6651121874108, 1001.67946257197, 1029.9691009269884, 15384.84615153834, 18117.91292522453, 19668.803311966698, 19681.507962301057, 19713.70429443531, 53500.0, 54037.0, 54212.45787542074, 54296.0, 54939.175912360544, 75417.24582754102, 80036.0, 87381.68927465966, 88927.55536222536, 90948.72628184348, 7200.531965422231, 8249.340052795882, 8365.456245344034, 8416.24253817135, 8542.78643033929, 11038.0, 12865.678358041125, 13344.466221351036, 13651.726965460834, 13719.588412347646, 9691.806163876541, 10550.788984220426, 11750.823737644114, 11751.059915206934, 11884.584039558575, 8471.66113355459, 10789.759177694352, 11602.70993225176, 11823.172377933462, 11963.0, 11805.291682499077, 11824.46789894474, 11931.522739090338, 12131.939340303243, 12213.0, 7547.811304717426, 8232.135625759449, 8233.752987410388, 8272.420930535045, 8672.13278672153, 11651.650450486499, 11812.881871181522, 11868.881311187122, 12031.15781895259, 12060.457279422271], "unit": "score", "name": "jquery.html", "value": 9816.283124017591}, {"lowerIsBetter": false, "replicates": [1407.2541552976925, 1623.1235133028165, 1658.8424099710528, 1981.4253866378751, 2052.127845665592, 8137.308328792053, 8809.823803523934, 8988.415752976052, 9005.954970225155, 9007.72976810695, 8650.437721548094, 8858.202761751441, 8870.37907346486, 8884.0, 8891.02198758136, 2253.2902135827244, 2511.2717311979504, 2543.4277287610325, 2558.09187738353, 2569.409035921739, 2552.2853600991753, 2595.390083330416, 2600.1549496413645, 2600.45390468001, 2602.544554702929, 2369.5853225685437, 2425.490646964131, 2430.829841911067, 2436.622323539845, 2444.2667199840066, 2519.3701574606257, 2553.808464365171, 2569.357660584854, 2570.4859028194437, 2573.4724381501746, 2529.557327467704, 2559.1938539360044, 2567.281161274846, 2568.653231813719, 2577.1624222127884, 8657.783555411102, 8745.825083498294, 8822.514761688104, 8836.337274704423, 8859.0, 5515.806946756844, 5544.972275138639, 5629.746661400247, 5672.631278966854, 5681.801136960186, 3586.1214002569277, 3653.5798383186007, 3746.3818469952425, 3750.324941510527, 3755.248950209969, 1043.6608102366686, 1071.0521188748014, 1082.8917108289186, 1089.4716062709579, 1113.3765091548678, 11914.880851191463, 12016.939915300367, 12050.457729402198, 12073.45669444877, 12091.911727944527, 4329.956700433018, 4962.751862406865, 5057.241413787888, 5058.114829904752, 5068.0, 10398.896011039944, 10433.895661043443, 10436.5825366986, 10442.738931526772, 10443.895561044445, 5625.8593535161945, 5634.661920284796, 5641.887162256732, 5643.5203007743985, 5645.125005624135, 4455.9554404456185, 4619.0530941156985, 4865.416150062015, 4966.553010229097, 5033.993201359743, 3988.3618621020496, 4068.1253530490494, 4071.6742660587083, 4100.052475074375, 4121.361189015647, 1334.9933250333881, 1359.734851703928, 1362.5435479114608, 1368.555219553649, 1369.3495589594863, 1334.4662135145825, 1352.0468070010618, 1368.0628769293066, 1406.0719924849755, 1420.8010878476814, 8109.229623185788, 8271.17288271168, 8277.337812974947, 8361.498310101413, 8376.874346884802, 528.7673423693562, 533.1842281309525, 543.0035884152625, 550.4770468055283, 551.1044552602021, 6230.283517395557, 6286.717097730613, 6290.716917738713, 6353.078803573456, 6355.046742988588, 10436.739081523023, 10910.399928003877, 10926.125909927347, 10939.617113400993, 10950.123990080774, 10282.691519254435, 10478.842817357745, 10625.256232063835, 10687.572497100184, 10729.731756705989, 7029.0, 8404.07555168931, 8422.705205317785, 8456.365772567082, 8493.745187644381, 7791.766247012599, 7948.125706172432, 7977.680892764224, 8071.394645401501, 8083.797905052421, 9421.528923553931, 9658.420494770337, 9763.218942484726, 9801.656942007281, 9828.705138845846, 9754.658586949423, 9943.353682010646, 10044.849327259952, 10144.847827282449, 10172.13536849376, 6763.560368576027, 7295.781126566211, 7509.924900751141, 7550.244975502405, 7582.696692132253, 8340.749777506684, 8358.749237522885, 8373.371997100363, 8403.117672644285, 8418.915810842058, 7280.96359518199, 7320.231375705474, 7403.8149046274275, 7514.211007844097, 7586.886196706943, 7263.4915555910575, 7285.417166626551, 7365.410767138509, 7374.705011799468, 7468.402527797654, 7785.766427007199, 7804.219578042151, 7832.099308579586, 7840.3335716464735, 7860.0567931848545, 6093.847653808691, 7002.824929376807, 7045.401140903081, 7066.752663656747, 7092.751753688803, 7110.288971102848, 7165.140183178052, 7175.569465832067, 7199.496035277481, 7242.167150777728], "unit": "score", "name": "mootools.html", "value": 4935.205236967248}, {"lowerIsBetter": false, "replicates": [304.4975789946588, 308.6897667843817, 344.3973047167457, 349.4269398186974, 353.1013570463171, 6658.23430305515, 6667.699953502092, 6669.766558170465, 6701.731930722772, 6726.730930762764, 6609.942409214526, 6641.169853768279, 6661.26726060133, 6663.900041499369, 6665.400113989752, 4779.235322348425, 4868.05073010763, 4909.288153217782, 4918.778654960535, 5013.072581572412, 4712.316714076457, 4930.383702037246, 4939.728314942677, 4972.801087956477, 4974.726390048546, 4880.072786170634, 4903.460619331889, 4907.779149938244, 4910.1652719037675, 4915.655904086698, 4719.244920812671, 4790.993891282835, 4814.831480898151, 4835.05716385305, 4842.346283251769, 4206.831726730927, 4211.7262377945335, 4229.048464095585, 4234.6823988201, 4261.4673165854265, 6318.905216421757, 6329.145565348711, 6367.42693157618, 6389.393007664265, 6405.071264666597, 5810.622309549908, 5811.244538210048, 5832.6500409975115, 5841.357450680422, 5844.620099693549, 6742.325767423267, 7059.399951004119, 7079.221285658574, 7079.964600176966, 7087.255838136973, 4603.884902877455, 4619.792109355087, 4635.0, 4639.907201855944, 4647.697899636512, 7404.703811847519, 7482.363999060087, 7484.32641062307, 7501.099868015818, 7506.774796756106, 4671.6262698984, 4676.5791078802395, 4679.040796636681, 4681.133990211753, 4683.7892294846815, 3941.724079314478, 4732.810687572527, 4742.264948932921, 4752.52474752522, 4760.143544017836, 4657.417822772153, 5647.29408823897, 5648.576356773258, 5654.802081927195, 5657.264555607745, 6806.0, 7052.717891284291, 7065.258147894499, 7072.434205263669, 7079.575225486487, 1609.4688752711577, 1647.744599587066, 1649.8432648898336, 1652.314289569839, 1652.8512433881008, 994.2046362909643, 1037.8131936251548, 1047.471027131309, 1061.0344586426315, 1061.2040969273046, 5456.672599644034, 5484.808031718871, 6361.650109243943, 6392.073149393312, 6516.2506311773805, 7380.409567234608, 7393.371563417169, 7409.998600755532, 7413.851722965618, 7418.888716669254, 6608.933910660832, 6618.503612229102, 6618.569792963442, 6622.536422450478, 6636.568623039583, 3828.9617103829164, 3869.6323849234277, 3870.554886188068, 3874.1670540833857, 3876.069743261597, 7364.153122390993, 7688.154303026663, 7707.884381734167, 7727.0, 7742.4580279381025, 6118.877622447526, 6931.653417329113, 7597.430192735456, 7606.581638009962, 7623.35201507867, 7719.0, 7723.189065148079, 7724.575148366781, 7726.806829829299, 7731.38148948094, 7000.544964577286, 7373.77878663641, 7383.040204773345, 7394.112706475043, 7397.889031664422, 7206.351428371472, 7245.420366370572, 7265.527740696838, 7276.090488688914, 7276.489611084167, 7676.692932282646, 7679.616019199129, 7700.075990881131, 7702.576358300347, 7708.229177082247, 3917.2557214129224, 4704.694194877322, 4705.411823522059, 4715.033418149203, 4725.669203155883, 6020.277566692025, 6561.786069577143, 6760.695768690519, 7287.635618219174, 7319.231480694663, 6763.188417389944, 6982.825429364307, 7000.894986575104, 7025.683844227125, 7025.929740702528, 5993.730282137227, 6239.720857224167, 6945.305469453015, 6990.8951365728535, 7000.789976300719, 6837.453003759588, 6849.1096157499205, 6901.896471553031, 6908.585484870924, 6917.377436030782, 6754.088198093391, 7040.577565346096, 7061.2232654409, 7068.540544864567, 7118.822029449098, 6627.9337206627315, 6741.393274605309, 6839.0, 6853.691583878638, 6858.622775747382], "unit": "score", "name": "prototype.html", "value": 5146.939378050147}, {"lowerIsBetter": false, "replicates": [679.2222904774034, 774.5120574038358, 779.1740754799911, 781.4764108047608, 795.7254747112248, 14954.476593319236, 15280.465183718572, 15333.003354781931, 15378.769318460232, 17781.911090444562, 16147.757783633255, 16207.594810129753, 16212.83787162131, 16217.351305947748, 16237.756433653474, 733.3986131372277, 741.0810594862359, 987.9604815807377, 1020.2857999400412, 1021.6832781837617, 998.3261298623437, 1009.6970908727352, 1016.2835201183185, 1016.298753859838, 1016.3901659004575, 1041.8593489878883, 1059.5761695321855, 1062.1661995333664, 1062.3254233561668, 1062.952167152476, 746.0860445953717, 758.3743411685326, 761.5811303782953, 1048.5229220704562, 1056.8361903904868, 741.8738814401564, 777.8949841771401, 786.0724345272577, 788.467784245632, 789.5539020453425, 14621.415143394252, 14650.853491465055, 14656.560303190814, 14663.70672586553, 14692.191929443876, 12527.56153534631, 12651.557195498113, 12722.173058751245, 12873.163244389178, 12916.160449570747, 10229.18166546682, 10259.948700256451, 10335.328203666737, 10385.480725963747, 10426.791464170674, 3457.9308413831836, 3463.0, 3507.368673638744, 3604.53141091659, 3714.0714821294678, 86589.56705216496, 86709.56645216797, 87663.0, 87758.12241877563, 87761.56119219426, 9199.218066464291, 9381.5309234538, 9543.665971690958, 9617.0, 10210.744731381776, 21748.34754957322, 21857.344279671634, 21896.452588685093, 21911.67132493014, 22250.666240006412, 7298.2701729826595, 7343.703260544357, 7386.815329616695, 7443.590602516913, 7568.583727894908, 4025.1547175092974, 4107.424960505472, 4116.444280022219, 4151.982764222785, 4290.570942905684, 3746.775193488345, 3766.7928263945746, 3778.3576791945434, 3791.0901383668274, 3793.203427280302, 669.3574168797911, 670.7585269303047, 677.123125552401, 692.1279188222876, 695.1449716848305, 544.8038706065813, 574.9712514374264, 626.6083697688945, 648.7567162314132, 649.8927676933348, 7123.358897699129, 7987.201279871966, 8518.0, 8565.229129378262, 8662.740117796477, 553.4299671338528, 603.7434090511515, 621.8289970258215, 631.5200447659812, 640.9361854827448, 3788.1855401088897, 3846.7307288490097, 3884.4950156479476, 3938.9212215755524, 3988.461557689675, 99589.0, 99942.5002874981, 100878.49560752296, 101307.98692013133, 102190.0, 80821.59589202135, 81103.0, 84945.0, 84969.57515212384, 87625.0, 68189.0, 73018.0, 74530.0, 74560.25439745642, 77263.45473090507, 8420.073791883011, 8681.2186903179, 8687.218150366498, 8737.91262087371, 8871.911280887109, 45884.54115458803, 47911.0, 49683.503164967886, 51772.74113629558, 52172.739136305594, 65247.04258872241, 67479.0, 67519.3248067513, 68137.0, 75272.0, 9667.758306042406, 11225.0, 11440.485178166837, 11566.884331156582, 11632.0, 11903.0, 12011.159218854596, 12208.32854193028, 12221.38893055326, 12430.316332601433, 11774.705632359259, 11792.29246245228, 11995.1003674723, 12265.448054837376, 12388.69918658564, 11786.46960886745, 12022.579209727968, 12069.27584344942, 12106.394680265776, 12273.07951903593, 11370.20408571392, 11581.710457238636, 11630.476628551567, 11953.880461195276, 12025.037996960396, 8377.49735015901, 9795.51022448889, 9813.313068085166, 9944.502774861083, 9954.352967057119, 9781.51092445389, 9980.051895069957, 9984.737088874106, 10028.247881408777, 10107.797844043225], "unit": "score", "name": "yui.html", "value": 7162.047659510308}], "name": "dromaeo_css", "value": 7673.832786789445}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 109.0, 112.0, 109.0, 112.0, 113.0, 117.0, 111.0, 111.0, 110.0], "unit": "score", "name": "ai-astar", "value": 111.6}, {"lowerIsBetter": true, "replicates": [155.0, 119.0, 157.0, 145.0, 117.0, 139.0, 117.0, 151.0, 159.0, 147.0], "unit": "score", "name": "audio-beat-detection", "value": 140.6}, {"lowerIsBetter": true, "replicates": [182.0, 184.0, 182.0, 184.0, 184.0, 187.0, 187.0, 184.0, 184.0, 185.0], "unit": "score", "name": "audio-dft", "value": 184.3}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 77.0, 117.0, 55.0, 55.0, 58.0, 57.0, 115.0, 56.0], "unit": "score", "name": "audio-fft", "value": 73.3}, {"lowerIsBetter": true, "replicates": [93.0, 97.0, 130.0, 79.0, 77.0, 85.0, 119.0, 75.0, 80.0, 117.0], "unit": "score", "name": "audio-oscillator", "value": 95.2}, {"lowerIsBetter": true, "replicates": [87.0, 85.0, 88.0, 85.0, 87.0, 83.0, 88.0, 84.0, 87.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 85.7}, {"lowerIsBetter": true, "replicates": [100.0, 93.0, 91.0, 92.0, 101.0, 92.0, 92.0, 93.0, 93.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 94.0}, {"lowerIsBetter": true, "replicates": [81.0, 84.0, 70.0, 124.0, 71.0, 123.0, 132.0, 132.0, 85.0, 88.0], "unit": "score", "name": "imaging-desaturate", "value": 99.0}, {"lowerIsBetter": true, "replicates": [58.0, 59.0, 59.0, 57.0, 59.0, 58.0, 60.0, 71.0, 51.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 58.7}, {"lowerIsBetter": true, "replicates": [62.0, 67.0, 63.0, 72.0, 73.0, 69.0, 67.0, 67.0, 65.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.1}, {"lowerIsBetter": true, "replicates": [81.0, 82.0, 81.0, 80.0, 80.0, 81.0, 80.0, 81.0, 80.0, 89.0], "unit": "score", "name": "stanford-crypto-aes", "value": 81.5}, {"lowerIsBetter": true, "replicates": [132.0, 130.0, 128.0, 119.0, 117.0, 117.0, 129.0, 119.0, 117.0, 120.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.8}, {"lowerIsBetter": true, "replicates": [133.0, 126.0, 134.0, 134.0, 130.0, 123.0, 134.0, 133.0, 132.0, 135.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.4}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 67.0, 66.0, 65.0, 66.0, 65.0, 66.0, 67.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.9}], "name": "kraken", "value": 1411.1000000000001}]} 08:34:30 INFO - Return code: 0 08:34:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:34:30 INFO - # TBPL SUCCESS # 08:34:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:34:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:34:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:34:30 INFO - 2016-04-14 08:34:30.846 screenresolution[1676:9762] starting screenresolution argv=screenresolution get 08:34:30 INFO - 2016-04-14 08:34:30.849 screenresolution[1676:9762] Display 0: 1600x1200x32@60 08:34:30 INFO - 2016-04-14 08:34:30.855 screenresolution[1677:9765] starting screenresolution argv=screenresolution list 08:34:30 INFO - Available Modes on Display 0 08:34:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:34:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:34:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:34:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:34:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:34:31 INFO - Intel Iris: 08:34:31 INFO - Chipset Model: Intel Iris 08:34:31 INFO - Type: GPU 08:34:31 INFO - Bus: Built-In 08:34:31 INFO - VRAM (Dynamic, Max): 1536 MB 08:34:31 INFO - Vendor: Intel (0x8086) 08:34:31 INFO - Device ID: 0x0a2e 08:34:31 INFO - Revision ID: 0x0009 08:34:31 INFO - Displays: 08:34:31 INFO - Display: 08:34:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:34:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:34:31 INFO - Display Serial Number: 1600x1200 60 08:34:31 INFO - Main Display: Yes 08:34:31 INFO - Mirror: Off 08:34:31 INFO - Online: Yes 08:34:31 INFO - Rotation: Supported 08:34:31 INFO - Return code: 0 08:34:31 INFO - Running post-action listener: _resource_record_post_action 08:34:31 INFO - Running post-run listener: _resource_record_post_run 08:34:32 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 37275136; Write bytes: 663371264; Read time: 392; Write time: 1888 08:34:32 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 351584768; Write bytes: 352085504; Read time: 27044; Write time: 601 08:34:32 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 36320768; Write bytes: 299812864; Read time: 377; Write time: 1252 08:34:32 INFO - Running post-run listener: _upload_blobber_files 08:34:32 INFO - Blob upload gear active. 08:34:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:34:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:34:32 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:34:32 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:34:32 INFO - (blobuploader) - INFO - Open directory for files ... 08:34:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:34:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:34:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:34:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:34:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:34:33 INFO - (blobuploader) - INFO - Done attempting. 08:34:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:34:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:34:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:34:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:34:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:34:34 INFO - (blobuploader) - INFO - Done attempting. 08:34:34 INFO - (blobuploader) - INFO - Iteration through files over. 08:34:34 INFO - Return code: 0 08:34:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:34:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:34:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f01e8ecbae42f105573e48ccade8c7d041f37cf4c60b3050384e1ca9c7ee4644d464960aca66929220ae842f2131ef43c0f87ba4a2f3641e0fdd50f647008bcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f0c17cfb5de62b10f70ed7411e51379a6d8d174670a99c2b17eccaf31b25d1c8eddd5dfb261bfd9be5ce9fa07c58f7c447fdabdedb4e5a9d98823dccbd024b1"} 08:34:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:34:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:34:34 INFO - Contents: 08:34:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f01e8ecbae42f105573e48ccade8c7d041f37cf4c60b3050384e1ca9c7ee4644d464960aca66929220ae842f2131ef43c0f87ba4a2f3641e0fdd50f647008bcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f0c17cfb5de62b10f70ed7411e51379a6d8d174670a99c2b17eccaf31b25d1c8eddd5dfb261bfd9be5ce9fa07c58f7c447fdabdedb4e5a9d98823dccbd024b1"} 08:34:34 INFO - Running post-run listener: copy_logs_to_upload_dir 08:34:34 INFO - Copying logs to upload dir... 08:34:34 INFO - mkdir: /builds/slave/test/build/upload/logs 08:34:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.283840 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-04-14 08:34:34.710242) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 08:34:34.713316) ========= 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.ixvROmn1mt/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.gx35koOfms/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f01e8ecbae42f105573e48ccade8c7d041f37cf4c60b3050384e1ca9c7ee4644d464960aca66929220ae842f2131ef43c0f87ba4a2f3641e0fdd50f647008bcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f0c17cfb5de62b10f70ed7411e51379a6d8d174670a99c2b17eccaf31b25d1c8eddd5dfb261bfd9be5ce9fa07c58f7c447fdabdedb4e5a9d98823dccbd024b1"} build_url:https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010926 build_url: 'https://queue.taskcluster.net/v1/task/CUvoU-9xR3OeMErfAOHa3g/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f01e8ecbae42f105573e48ccade8c7d041f37cf4c60b3050384e1ca9c7ee4644d464960aca66929220ae842f2131ef43c0f87ba4a2f3641e0fdd50f647008bcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f0c17cfb5de62b10f70ed7411e51379a6d8d174670a99c2b17eccaf31b25d1c8eddd5dfb261bfd9be5ce9fa07c58f7c447fdabdedb4e5a9d98823dccbd024b1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 08:34:34.751232) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 08:34:34.751992) ========= 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.ixvROmn1mt/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.gx35koOfms/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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.005188 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 08:34:34.804926) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 08:34:34.805600) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 08:34:34.806956) ========= ========= Total master_lag: 0.18 =========