builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0033 starttime: 1461220425.03 results: success (0) buildid: 20160420213010 builduid: 98b108a68777428c958cd52d13f6b17f revision: d9fdbad8f079c7801abdef70df2cad93efcce797 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 23:33:45.033275) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 23:33:45.033692) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 10 secs) (at 2016-04-20 23:33:45.033959) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 10 secs) (at 2016-04-20 23:33:55.705097) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 23:33:55.705345) ========= 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.bFuOKb7EDS/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.wfAP4OK62k/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-20 23:33:56-- 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% 41.5M=0s 2016-04-20 23:33:56 (41.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174760 ========= master_lag: 1.76 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 23:33:57.640499) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-04-20 23:33:57.640819) ========= 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.bFuOKb7EDS/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.wfAP4OK62k/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.032323 ========= master_lag: 3.77 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-04-20 23:34:01.438743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-20 23:34:01.439053) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d9fdbad8f079c7801abdef70df2cad93efcce797 --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 d9fdbad8f079c7801abdef70df2cad93efcce797 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bFuOKb7EDS/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.wfAP4OK62k/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-20 23:34:01,559 truncating revision to first 12 chars 2016-04-20 23:34:01,559 Setting DEBUG logging. 2016-04-20 23:34:01,559 attempt 1/10 2016-04-20 23:34:01,559 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d9fdbad8f079?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 23:34:02,916 unpacking tar archive at: mozilla-aurora-d9fdbad8f079/testing/mozharness/ program finished with exit code 0 elapsedTime=1.607848 ========= master_lag: 5.40 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-20 23:34:08.450325) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 23:34:08.450589) ========= script_repo_revision: d9fdbad8f079c7801abdef70df2cad93efcce797 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 23:34:08.450959) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 9 secs) (at 2016-04-20 23:34:08.451215) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 9 secs) (at 2016-04-20 23:34:18.034390) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-20 23:34:18.034663) ========= /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.bFuOKb7EDS/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.wfAP4OK62k/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 23:34:18 INFO - MultiFileLogger online at 20160420 23:34:18 in /builds/slave/test 23:34:18 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 23:34:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:34:18 INFO - {'append_to_log': False, 23:34:18 INFO - 'base_work_dir': '/builds/slave/test', 23:34:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:34:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:34:18 INFO - 'branch': 'Mozilla-Aurora', 23:34:18 INFO - 'buildbot_json_path': 'buildprops.json', 23:34:18 INFO - 'config_files': ('talos/mac_config.py',), 23:34:18 INFO - 'default_actions': ('clobber', 23:34:18 INFO - 'read-buildbot-config', 23:34:18 INFO - 'download-and-extract', 23:34:18 INFO - 'populate-webroot', 23:34:18 INFO - 'create-virtualenv', 23:34:18 INFO - 'install', 23:34:18 INFO - 'run-tests'), 23:34:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:34:18 INFO - 'download_minidump_stackwalk': True, 23:34:18 INFO - 'download_symbols': 'ondemand', 23:34:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:34:18 INFO - 'tooltool.py': '/tools/tooltool.py', 23:34:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:34:18 INFO - '/tools/misc-python/virtualenv.py')}, 23:34:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:34:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:34:18 INFO - 'installer_path': 'installer.exe', 23:34:18 INFO - 'log_level': 'info', 23:34:18 INFO - 'log_name': 'talos', 23:34:18 INFO - 'log_to_console': True, 23:34:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:34:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:34:18 INFO - 'opt_config_files': (), 23:34:18 INFO - 'pip_index': False, 23:34:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:34:18 INFO - 'cmd': ('bash', 23:34:18 INFO - '-c', 23:34:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:34:18 INFO - 'enabled': True, 23:34:18 INFO - 'halt_on_failure': False, 23:34:18 INFO - 'name': 'check_screen_resolution'},), 23:34:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:34:18 INFO - 'cmd': ('bash', 23:34:18 INFO - '-c', 23:34:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:34:18 INFO - 'enabled': True, 23:34:18 INFO - 'halt_on_failure': False, 23:34:18 INFO - 'name': 'check_screen_resolution'},), 23:34:18 INFO - 'run_cmd_checks_enabled': True, 23:34:18 INFO - 'sps_profile': False, 23:34:18 INFO - 'sps_profile_interval': 0, 23:34:18 INFO - 'suite': 'dromaeojs', 23:34:18 INFO - 'system_bits': '32', 23:34:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:34:18 INFO - 'title': 't-yosemite-r7-0033', 23:34:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:34:18 INFO - 'use_talos_json': True, 23:34:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:34:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:34:18 INFO - 'work_dir': 'build'} 23:34:18 INFO - ##### 23:34:18 INFO - ##### Running clobber step. 23:34:18 INFO - ##### 23:34:18 INFO - Running pre-action listener: _resource_record_pre_action 23:34:18 INFO - Running main action method: clobber 23:34:18 INFO - rmtree: /builds/slave/test/build 23:34:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:34:20 INFO - Running post-action listener: _resource_record_post_action 23:34:20 INFO - ##### 23:34:20 INFO - ##### Running read-buildbot-config step. 23:34:20 INFO - ##### 23:34:20 INFO - Running pre-action listener: _resource_record_pre_action 23:34:20 INFO - Running main action method: read_buildbot_config 23:34:20 INFO - Using buildbot properties: 23:34:20 INFO - { 23:34:20 INFO - "project": "", 23:34:20 INFO - "product": "firefox", 23:34:20 INFO - "script_repo_revision": "production", 23:34:20 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 23:34:20 INFO - "repository": "", 23:34:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 23:34:20 INFO - "buildid": "20160420213010", 23:34:20 INFO - "pgo_build": "False", 23:34:20 INFO - "basedir": "/builds/slave/test", 23:34:20 INFO - "buildnumber": 299, 23:34:20 INFO - "slavename": "t-yosemite-r7-0033", 23:34:20 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 23:34:20 INFO - "platform": "yosemite_r7", 23:34:20 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 23:34:20 INFO - "branch": "mozilla-aurora", 23:34:20 INFO - "repo_path": "releases/mozilla-aurora", 23:34:20 INFO - "revision": "d9fdbad8f079c7801abdef70df2cad93efcce797", 23:34:20 INFO - "stage_platform": "macosx64", 23:34:20 INFO - "builduid": "98b108a68777428c958cd52d13f6b17f", 23:34:20 INFO - "slavebuilddir": "test" 23:34:20 INFO - } 23:34:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 23:34:20 INFO - Running post-action listener: _resource_record_post_action 23:34:20 INFO - ##### 23:34:20 INFO - ##### Running download-and-extract step. 23:34:20 INFO - ##### 23:34:20 INFO - Running pre-action listener: _resource_record_pre_action 23:34:20 INFO - Running main action method: download_and_extract 23:34:20 INFO - mkdir: /builds/slave/test/build/tests 23:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:20 INFO - https://queue.taskcluster.net/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:34:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 23:34:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 23:34:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/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 23:34:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/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 23:34:22 INFO - Downloaded 1277 bytes. 23:34:22 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 23:34:22 INFO - Using the following test package requirements: 23:34:22 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 23:34:22 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 23:34:22 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 23:34:22 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 23:34:22 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 23:34:22 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 23:34:22 INFO - u'jsshell-mac.zip'], 23:34:22 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 23:34:22 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 23:34:22 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 23:34:22 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 23:34:22 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 23:34:22 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 23:34:22 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 23:34:22 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 23:34:22 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 23:34:22 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 23:34:22 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 23:34:22 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 23:34:22 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 23:34: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')]} 23:34:22 INFO - https://queue.taskcluster.net/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:34:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 23:34:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 23:34:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/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 23:34:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/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 23:34:25 INFO - Downloaded 17913854 bytes. 23:34:25 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'] 23:34:25 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 23:34:28 INFO - Return code: 0 23:34:28 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 23:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:28 INFO - https://queue.taskcluster.net/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:34:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 23:34:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 23:34:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/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 23:34:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/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 23:34:30 INFO - Downloaded 17913854 bytes. 23:34:30 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'] 23:34:30 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 23:34:34 INFO - Return code: 0 23:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:34 INFO - https://queue.taskcluster.net/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:34:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 23:34:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 23:34:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/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 23:34:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/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 23:34:35 INFO - Downloaded 10845198 bytes. 23:34:35 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'] 23:34:35 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 23:34:35 INFO - Return code: 0 23:34:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:35 INFO - https://queue.taskcluster.net/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 23:34:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 23:34:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 23:34:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 23:34:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V65yKZxOQ92AsFPy9kc9fA/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 23:34:40 INFO - Downloaded 115369710 bytes. 23:34:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 23:34:40 INFO - mkdir: /builds/slave/test/properties 23:34:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:34:40 INFO - Writing to file /builds/slave/test/properties/build_url 23:34:40 INFO - Contents: 23:34:40 INFO - build_url:https://queue.taskcluster.net/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 23:34:40 INFO - Running post-action listener: _resource_record_post_action 23:34:40 INFO - Running post-action listener: set_extra_try_arguments 23:34:40 INFO - ##### 23:34:40 INFO - ##### Running populate-webroot step. 23:34:40 INFO - ##### 23:34:40 INFO - Running pre-action listener: _resource_record_pre_action 23:34:40 INFO - Running main action method: populate_webroot 23:34:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:34:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:34:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:34:40 INFO - '--tppagecycles', 23:34:40 INFO - '7'], 23:34:40 INFO - 'tests': ['tsvgm']}}, 23:34:40 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 23:34:40 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 23:34:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:34:40 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 23:34:40 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 23:34:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:34:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:34:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:34:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:34:40 INFO - 'talos_options': ['--e10s'], 23:34:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:34:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:34:40 INFO - 'tests': ['damp', 'tps']}, 23:34:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:34:40 INFO - 'talos_options': ['--e10s'], 23:34:40 INFO - 'tests': ['damp', 'tps']}, 23:34:40 INFO - 'g3': {'tests': ['dromaeo_dom']}, 23:34:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:34:40 INFO - 'other': {'tests': ['a11yr', 23:34:40 INFO - 'ts_paint', 23:34:40 INFO - 'tpaint', 23:34:40 INFO - 'sessionrestore', 23:34:40 INFO - 'sessionrestore_no_auto_restore', 23:34:40 INFO - 'tabpaint']}, 23:34:40 INFO - 'other-e10s': {'talos_options': ['--e10s'], 23:34:40 INFO - 'tests': ['a11yr', 23:34:40 INFO - 'ts_paint', 23:34:40 INFO - 'tpaint', 23:34:40 INFO - 'sessionrestore', 23:34:40 INFO - 'sessionrestore_no_auto_restore', 23:34:40 INFO - 'tabpaint']}, 23:34:40 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 23:34:40 INFO - 'tests': ['a11yr', 23:34:40 INFO - 'ts_paint', 23:34:40 INFO - 'tpaint', 23:34:40 INFO - 'sessionrestore', 23:34:40 INFO - 'sessionrestore_no_auto_restore', 23:34:40 INFO - 'tabpaint']}, 23:34:40 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 23:34:40 INFO - 'tests': ['a11yr', 23:34:40 INFO - 'ts_paint', 23:34:40 INFO - 'tpaint', 23:34:40 INFO - 'sessionrestore', 23:34:40 INFO - 'sessionrestore_no_auto_restore', 23:34:40 INFO - 'tabpaint']}, 23:34:40 INFO - 'other_l64': {'tests': ['a11yr', 23:34:40 INFO - 'ts_paint', 23:34:40 INFO - 'tpaint', 23:34:40 INFO - 'sessionrestore', 23:34:40 INFO - 'sessionrestore_no_auto_restore', 23:34:40 INFO - 'tabpaint']}, 23:34:40 INFO - 'other_nol64': {'tests': ['a11yr', 23:34:40 INFO - 'ts_paint', 23:34:40 INFO - 'tpaint', 23:34:40 INFO - 'sessionrestore', 23:34:40 INFO - 'sessionrestore_no_auto_restore', 23:34:40 INFO - 'tabpaint']}, 23:34:40 INFO - 'svgr': {'tests': ['tsvgx', 23:34:40 INFO - 'tsvgr_opacity', 23:34:40 INFO - 'tart', 23:34:40 INFO - 'tscrollx', 23:34:40 INFO - 'cart']}, 23:34:40 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 23:34:40 INFO - 'tests': ['tsvgx', 23:34:40 INFO - 'tsvgr_opacity', 23:34:40 INFO - 'tart', 23:34:40 INFO - 'tscrollx', 23:34:40 INFO - 'cart']}, 23:34:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:34:40 INFO - 'tests': ['tp5o']}, 23:34:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:34:40 INFO - 'talos_options': ['--e10s'], 23:34:40 INFO - 'tests': ['tp5o']}, 23:34:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:34:40 INFO - 'talos_options': ['--xperf_path', 23:34:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:34:40 INFO - 'tests': ['tp5n']}, 23:34:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:34:40 INFO - 'talos_options': ['--e10s', 23:34:40 INFO - '--xperf_path', 23:34:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:34:40 INFO - 'tests': ['tp5n']}}, 23:34:40 INFO - 'talos.zip': {'path': '', 23:34:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:34:40 INFO - Running post-action listener: _resource_record_post_action 23:34:40 INFO - ##### 23:34:40 INFO - ##### Running create-virtualenv step. 23:34:40 INFO - ##### 23:34:40 INFO - Running pre-action listener: _resource_record_pre_action 23:34:40 INFO - Running main action method: create_virtualenv 23:34:40 INFO - Creating virtualenv /builds/slave/test/build/venv 23:34:40 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 23:34:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:34:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:34:40 INFO - Using real prefix '/tools/python27' 23:34:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:34:41 INFO - Installing distribute.............................................................................................................................................................................................done. 23:34:44 INFO - Installing pip.................done. 23:34:44 INFO - Return code: 0 23:34:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:44 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 0x10cde8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdef4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb9cab2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8cd30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc83b20>, '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.bFuOKb7EDS/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.wfAP4OK62k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 23:34:44 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 23:34:44 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 23:34:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bFuOKb7EDS/Render', 23:34:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:34:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:34:44 INFO - 'HOME': '/Users/cltbld', 23:34:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:34:44 INFO - 'LOGNAME': 'cltbld', 23:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:34:44 INFO - 'MOZ_NO_REMOTE': '1', 23:34:44 INFO - 'NO_EM_RESTART': '1', 23:34:44 INFO - 'PAGER': '/bin/cat', 23:34:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:34:44 INFO - 'PWD': '/builds/slave/test', 23:34:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:34:44 INFO - 'SHELL': '/bin/bash', 23:34:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wfAP4OK62k/Listeners', 23:34:44 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 23:34:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:34:44 INFO - 'USER': 'cltbld', 23:34:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:34:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:34:44 INFO - 'XPC_FLAGS': '0x0', 23:34:44 INFO - 'XPC_SERVICE_NAME': '0', 23:34:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:34:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:34:44 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 23:34:44 INFO - Cleaning up... 23:34:44 INFO - Return code: 0 23:34:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:44 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 0x10cde8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdef4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb9cab2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8cd30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc83b20>, '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.bFuOKb7EDS/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.wfAP4OK62k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 23:34:44 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 23:34:44 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 23:34:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bFuOKb7EDS/Render', 23:34:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:34:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:34:44 INFO - 'HOME': '/Users/cltbld', 23:34:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:34:44 INFO - 'LOGNAME': 'cltbld', 23:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:34:44 INFO - 'MOZ_NO_REMOTE': '1', 23:34:44 INFO - 'NO_EM_RESTART': '1', 23:34:44 INFO - 'PAGER': '/bin/cat', 23:34:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:34:44 INFO - 'PWD': '/builds/slave/test', 23:34:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:34:44 INFO - 'SHELL': '/bin/bash', 23:34:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wfAP4OK62k/Listeners', 23:34:44 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 23:34:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:34:44 INFO - 'USER': 'cltbld', 23:34:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:34:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:34:44 INFO - 'XPC_FLAGS': '0x0', 23:34:44 INFO - 'XPC_SERVICE_NAME': '0', 23:34:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:34:45 INFO - Downloading/unpacking psutil>=0.7.1 23:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:34:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:34:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:34:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:34:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:34:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:34:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:34:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:34:47 INFO - Installing collected packages: psutil 23:34:47 INFO - Running setup.py install for psutil 23:34:48 INFO - building 'psutil._psutil_osx' extension 23:34:48 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 23:34:48 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 23:34:48 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 23:34:48 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 23:34:48 INFO - building 'psutil._psutil_posix' extension 23:34:48 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 23:34:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:34:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:34:48 INFO - ^ 23:34:48 INFO - 1 warning generated. 23:34:48 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 23:34:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:34:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:34:49 INFO - Successfully installed psutil 23:34:49 INFO - Cleaning up... 23:34:49 INFO - Return code: 0 23:34:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:34:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:34:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:34:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cde8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdef4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb9cab2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8cd30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc83b20>, '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.bFuOKb7EDS/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.wfAP4OK62k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 23:34:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 23:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 23:34:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bFuOKb7EDS/Render', 23:34:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:34:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:34:49 INFO - 'HOME': '/Users/cltbld', 23:34:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:34:49 INFO - 'LOGNAME': 'cltbld', 23:34:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:34:49 INFO - 'MOZ_NO_REMOTE': '1', 23:34:49 INFO - 'NO_EM_RESTART': '1', 23:34:49 INFO - 'PAGER': '/bin/cat', 23:34:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:34:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:34:49 INFO - 'PWD': '/builds/slave/test', 23:34:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:34:49 INFO - 'SHELL': '/bin/bash', 23:34:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wfAP4OK62k/Listeners', 23:34:49 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 23:34:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:34:49 INFO - 'USER': 'cltbld', 23:34:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:34:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:34:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:34:49 INFO - 'XPC_FLAGS': '0x0', 23:34:49 INFO - 'XPC_SERVICE_NAME': '0', 23:34:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:34:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:34:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:34:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:34:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:34:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:34:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:34:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:34:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:34:52 INFO - Installing collected packages: mozsystemmonitor 23:34:52 INFO - Running setup.py install for mozsystemmonitor 23:34:52 INFO - Successfully installed mozsystemmonitor 23:34:52 INFO - Cleaning up... 23:34:52 INFO - Return code: 0 23:34:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:34:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:34:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cde8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdef4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb9cab2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8cd30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc83b20>, '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.bFuOKb7EDS/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.wfAP4OK62k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 23:34:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:34:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bFuOKb7EDS/Render', 23:34:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:34:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:34:52 INFO - 'HOME': '/Users/cltbld', 23:34:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:34:52 INFO - 'LOGNAME': 'cltbld', 23:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:34:52 INFO - 'MOZ_NO_REMOTE': '1', 23:34:52 INFO - 'NO_EM_RESTART': '1', 23:34:52 INFO - 'PAGER': '/bin/cat', 23:34:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:34:52 INFO - 'PWD': '/builds/slave/test', 23:34:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:34:52 INFO - 'SHELL': '/bin/bash', 23:34:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wfAP4OK62k/Listeners', 23:34:52 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 23:34:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:34:52 INFO - 'USER': 'cltbld', 23:34:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:34:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:34:52 INFO - 'XPC_FLAGS': '0x0', 23:34:52 INFO - 'XPC_SERVICE_NAME': '0', 23:34:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:34:52 INFO - Downloading/unpacking blobuploader==1.2.4 23:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:34:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:34:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:34:54 INFO - Downloading blobuploader-1.2.4.tar.gz 23:34:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:34:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:34:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:34:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:34:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:34:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:34:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:34:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:34:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:34:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:34:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:34:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:34:56 INFO - Downloading docopt-0.6.1.tar.gz 23:34:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:34:56 INFO - Installing collected packages: blobuploader, requests, docopt 23:34:56 INFO - Running setup.py install for blobuploader 23:34:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:34:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:34:56 INFO - Running setup.py install for requests 23:34:56 INFO - Running setup.py install for docopt 23:34:57 INFO - Successfully installed blobuploader requests docopt 23:34:57 INFO - Cleaning up... 23:34:57 INFO - Return code: 0 23:34:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:34:57 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')]} 23:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:34:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:57 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')]} 23:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:34:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:34:57 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 0x10cde8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdef4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb9cab2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8cd30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc83b20>, '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.bFuOKb7EDS/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.wfAP4OK62k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 23:34:57 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 23:34:57 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 23:34:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bFuOKb7EDS/Render', 23:34:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:34:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:34:57 INFO - 'HOME': '/Users/cltbld', 23:34:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:34:57 INFO - 'LOGNAME': 'cltbld', 23:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:34:57 INFO - 'MOZ_NO_REMOTE': '1', 23:34:57 INFO - 'NO_EM_RESTART': '1', 23:34:57 INFO - 'PAGER': '/bin/cat', 23:34:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:34:57 INFO - 'PWD': '/builds/slave/test', 23:34:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:34:57 INFO - 'SHELL': '/bin/bash', 23:34:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wfAP4OK62k/Listeners', 23:34:57 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 23:34:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:34:57 INFO - 'USER': 'cltbld', 23:34:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:34:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:34:57 INFO - 'XPC_FLAGS': '0x0', 23:34:57 INFO - 'XPC_SERVICE_NAME': '0', 23:34:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:34:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-uK9tya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:34:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-TiIPgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:34:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-hBXtZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:34:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-tp67Ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:34:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ZTpIn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:34:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-nbfFmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:34:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-6U3cK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:34:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-0FXCQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:34:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-1rTV1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:34:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GunO7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:34:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-PzDAob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:34:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Zy4RpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:34:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-AxSwrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:34:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-9k5qlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:34:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-MlMlWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:34:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-pRij44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:34:59 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-qOsb32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:34:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:34:59 INFO - Running setup.py install for manifestparser 23:34:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:34:59 INFO - Running setup.py install for mozcrash 23:34:59 INFO - Running setup.py install for mozdebug 23:34:59 INFO - Running setup.py install for mozdevice 23:34:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:34:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:34:59 INFO - Running setup.py install for mozfile 23:34:59 INFO - Running setup.py install for mozhttpd 23:35:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:35:00 INFO - Running setup.py install for mozinfo 23:35:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:35:00 INFO - Running setup.py install for mozInstall 23:35:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:35:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:35:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:35:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:35:00 INFO - Running setup.py install for mozleak 23:35:00 INFO - Running setup.py install for mozlog 23:35:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:35:00 INFO - Running setup.py install for moznetwork 23:35:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:35:00 INFO - Running setup.py install for mozprocess 23:35:00 INFO - Running setup.py install for mozprofile 23:35:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:35:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:35:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:35:01 INFO - Running setup.py install for mozrunner 23:35:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:35:01 INFO - Running setup.py install for mozscreenshot 23:35:01 INFO - Running setup.py install for moztest 23:35:01 INFO - Running setup.py install for mozversion 23:35:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:35:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:35:01 INFO - Cleaning up... 23:35:01 INFO - Return code: 0 23:35:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:35: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')]} 23:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:35:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35: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')]} 23:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:35:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:01 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 0x10cde8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdef4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb9cab2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8cd30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc83b20>, '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.bFuOKb7EDS/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.wfAP4OK62k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 23:35:01 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 23:35:01 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 23:35:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bFuOKb7EDS/Render', 23:35:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:35:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:35:01 INFO - 'HOME': '/Users/cltbld', 23:35:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:35:01 INFO - 'LOGNAME': 'cltbld', 23:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:35:01 INFO - 'MOZ_NO_REMOTE': '1', 23:35:01 INFO - 'NO_EM_RESTART': '1', 23:35:01 INFO - 'PAGER': '/bin/cat', 23:35:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:35:01 INFO - 'PWD': '/builds/slave/test', 23:35:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:35:01 INFO - 'SHELL': '/bin/bash', 23:35:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wfAP4OK62k/Listeners', 23:35:01 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 23:35:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:35:01 INFO - 'USER': 'cltbld', 23:35:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:35:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:35:01 INFO - 'XPC_FLAGS': '0x0', 23:35:01 INFO - 'XPC_SERVICE_NAME': '0', 23:35:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:35:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-3gs03A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:35:02 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)) 23:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:35:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-CZqOuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:35:02 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)) 23:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:35:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-cFng2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:35:02 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)) 23:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:35:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-5itdBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:35:02 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)) 23:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:35:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-vUkdHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:35:02 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)) 23:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:35:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-iQbIuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:35:02 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)) 23:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:35:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-u5jhwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:35:02 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)) 23:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:35:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-VrOcbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:35:02 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)) 23:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:35:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-_CWdkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:35:02 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)) 23:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:35:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-6_Xk5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:35:03 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)) 23:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:35:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-JdWlWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:35:03 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)) 23:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:35:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-8hPoJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:35:03 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)) 23:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:35:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-68bXhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:35:03 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)) 23:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:35:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-JZQSJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:35:03 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)) 23:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:35:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Hlfytb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:35:03 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)) 23:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:35:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ofHODq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:35:03 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)) 23:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:35:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-0YebWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:35:03 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)) 23:35:03 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)) 23:35:03 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)) 23:35:03 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)) 23:35:03 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)) 23:35:03 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)) 23:35:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:35:03 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 23:35:03 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 23:35:03 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 23:35:03 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 23:35:03 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 23:35:03 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 23:35:06 INFO - Downloading blessings-1.6.tar.gz 23:35:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:35:06 INFO - Installing collected packages: blessings 23:35:06 INFO - Running setup.py install for blessings 23:35:06 INFO - Successfully installed blessings 23:35:06 INFO - Cleaning up... 23:35:06 INFO - Return code: 0 23:35:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:35:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:35:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:35:06 INFO - Reading from file tmpfile_stdout 23:35:06 INFO - Current package versions: 23:35:06 INFO - blessings == 1.6 23:35:06 INFO - blobuploader == 1.2.4 23:35:06 INFO - docopt == 0.6.1 23:35:06 INFO - manifestparser == 1.1 23:35:06 INFO - mozInstall == 1.12 23:35:06 INFO - mozcrash == 0.17 23:35:06 INFO - mozdebug == 0.1 23:35:06 INFO - mozdevice == 0.48 23:35:06 INFO - mozfile == 1.2 23:35:06 INFO - mozhttpd == 0.7 23:35:06 INFO - mozinfo == 0.9 23:35:06 INFO - mozleak == 0.1 23:35:06 INFO - mozlog == 3.1 23:35:06 INFO - moznetwork == 0.27 23:35:06 INFO - mozprocess == 0.22 23:35:06 INFO - mozprofile == 0.28 23:35:06 INFO - mozrunner == 6.11 23:35:06 INFO - mozscreenshot == 0.1 23:35:06 INFO - mozsystemmonitor == 0.0 23:35:06 INFO - moztest == 0.7 23:35:06 INFO - mozversion == 1.4 23:35:06 INFO - psutil == 3.1.1 23:35:06 INFO - requests == 1.2.3 23:35:06 INFO - wsgiref == 0.1.2 23:35:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:35:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:35:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:35:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:06 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 0x10cde8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdef4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb9cab2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8cd30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc83b20>, '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.bFuOKb7EDS/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.wfAP4OK62k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 23:35:06 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 23:35:06 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 23:35:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bFuOKb7EDS/Render', 23:35:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:35:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:35:06 INFO - 'HOME': '/Users/cltbld', 23:35:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:35:06 INFO - 'LOGNAME': 'cltbld', 23:35:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:35:06 INFO - 'MOZ_NO_REMOTE': '1', 23:35:06 INFO - 'NO_EM_RESTART': '1', 23:35:06 INFO - 'PAGER': '/bin/cat', 23:35:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:35:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:35:06 INFO - 'PWD': '/builds/slave/test', 23:35:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:35:06 INFO - 'SHELL': '/bin/bash', 23:35:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wfAP4OK62k/Listeners', 23:35:06 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 23:35:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:35:06 INFO - 'USER': 'cltbld', 23:35:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:35:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:35:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:35:06 INFO - 'XPC_FLAGS': '0x0', 23:35:06 INFO - 'XPC_SERVICE_NAME': '0', 23:35:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:35:07 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)) 23:35:07 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)) 23:35:07 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)) 23:35:07 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)) 23:35:07 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)) 23:35:07 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)) 23:35:07 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)) 23:35:07 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)) 23:35:07 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)) 23:35:07 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)) 23:35:07 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)) 23:35:07 INFO - Cleaning up... 23:35:07 INFO - Return code: 0 23:35:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:35:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:35:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:07 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 0x10cde8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdef4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb9cab2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8cd30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc83b20>, '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.bFuOKb7EDS/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.wfAP4OK62k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 23:35:07 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 23:35:07 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 23:35:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bFuOKb7EDS/Render', 23:35:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:35:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:35:07 INFO - 'HOME': '/Users/cltbld', 23:35:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:35:07 INFO - 'LOGNAME': 'cltbld', 23:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:35:07 INFO - 'MOZ_NO_REMOTE': '1', 23:35:07 INFO - 'NO_EM_RESTART': '1', 23:35:07 INFO - 'PAGER': '/bin/cat', 23:35:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:35:07 INFO - 'PWD': '/builds/slave/test', 23:35:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:35:07 INFO - 'SHELL': '/bin/bash', 23:35:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wfAP4OK62k/Listeners', 23:35:07 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 23:35:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:35:07 INFO - 'USER': 'cltbld', 23:35:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:35:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:35:07 INFO - 'XPC_FLAGS': '0x0', 23:35:07 INFO - 'XPC_SERVICE_NAME': '0', 23:35:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:35:07 INFO - Downloading/unpacking jsonschema 23:35: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 23:35: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 23:35: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 23:35: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 23:35: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 23:35: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 23:35:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:35:09 INFO - Downloading/unpacking functools32 (from jsonschema) 23:35:09 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 23:35:09 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 23:35:09 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 23:35:09 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 23:35:09 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 23:35:09 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 23:35:10 INFO - Downloading functools32-3.2.3-2.tar.gz 23:35:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:35:10 INFO - warning: no files found matching '*.txt' 23:35:10 INFO - no previously-included directories found matching 'build' 23:35:10 INFO - no previously-included directories found matching 'dist' 23:35:10 INFO - no previously-included directories found matching '.git*' 23:35:10 INFO - Installing collected packages: jsonschema, functools32 23:35:10 INFO - Running setup.py install for functools32 23:35:10 INFO - warning: no files found matching '*.txt' 23:35:10 INFO - no previously-included directories found matching 'build' 23:35:10 INFO - no previously-included directories found matching 'dist' 23:35:10 INFO - no previously-included directories found matching '.git*' 23:35:10 INFO - Successfully installed jsonschema functools32 23:35:10 INFO - Cleaning up... 23:35:10 INFO - Return code: 0 23:35:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:35:10 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')]} 23:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:35:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:10 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')]} 23:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:35:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:10 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 0x10cde8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdef4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb9cab2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8cd30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc83b20>, '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.bFuOKb7EDS/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.wfAP4OK62k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 23:35:10 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 23:35:10 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 23:35:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bFuOKb7EDS/Render', 23:35:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:35:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:35:10 INFO - 'HOME': '/Users/cltbld', 23:35:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:35:10 INFO - 'LOGNAME': 'cltbld', 23:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:35:10 INFO - 'MOZ_NO_REMOTE': '1', 23:35:10 INFO - 'NO_EM_RESTART': '1', 23:35:10 INFO - 'PAGER': '/bin/cat', 23:35:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:35:10 INFO - 'PWD': '/builds/slave/test', 23:35:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:35:10 INFO - 'SHELL': '/bin/bash', 23:35:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wfAP4OK62k/Listeners', 23:35:10 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 23:35:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:35:10 INFO - 'USER': 'cltbld', 23:35:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:35:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:35:10 INFO - 'XPC_FLAGS': '0x0', 23:35:10 INFO - 'XPC_SERVICE_NAME': '0', 23:35:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:35:10 INFO - Downloading/unpacking flake8 23:35:10 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 23:35:10 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 23:35:10 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 23:35:10 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 23:35:10 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 23:35:10 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 23:35:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:35:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:35:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 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 23:35:13 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 23:35:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:35:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:35:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:35:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:35:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:35:14 INFO - no previously-included directories found matching 'docs/_build' 23:35:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:35:14 INFO - Running setup.py install for pep8 23:35:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:35:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:35:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:35:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:35:14 INFO - no previously-included directories found matching 'docs/_build' 23:35:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:35:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:35:14 INFO - Cleaning up... 23:35:14 INFO - Return code: 0 23:35:14 INFO - Running post-action listener: _resource_record_post_action 23:35:14 INFO - Running post-action listener: _start_resource_monitoring 23:35:14 INFO - Starting resource monitoring. 23:35:14 INFO - ##### 23:35:14 INFO - ##### Running install step. 23:35:14 INFO - ##### 23:35:14 INFO - Running pre-action listener: _resource_record_pre_action 23:35:14 INFO - Running main action method: install 23:35:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:35:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:35:15 INFO - Reading from file tmpfile_stdout 23:35:15 INFO - Detecting whether we're running mozinstall >=1.0... 23:35:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:35:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:35:15 INFO - Reading from file tmpfile_stdout 23:35:15 INFO - Output received: 23:35:15 INFO - Usage: mozinstall [options] installer 23:35:15 INFO - Options: 23:35:15 INFO - -h, --help show this help message and exit 23:35:15 INFO - -d DEST, --destination=DEST 23:35:15 INFO - Directory to install application into. [default: 23:35:15 INFO - "/builds/slave/test"] 23:35:15 INFO - --app=APP Application being installed. [default: firefox] 23:35:15 INFO - mkdir: /builds/slave/test/build/application 23:35:15 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'] 23:35:15 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 23:35:47 INFO - Reading from file tmpfile_stdout 23:35:47 INFO - Output received: 23:35:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 23:35:47 INFO - Running post-action listener: _resource_record_post_action 23:35:47 INFO - ##### 23:35:47 INFO - ##### Running run-tests step. 23:35:47 INFO - ##### 23:35:47 INFO - Running pre-action listener: _resource_record_pre_action 23:35:47 INFO - Running main action method: run_tests 23:35:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:35:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:35:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:35:47 INFO - 2016-04-20 23:35:47.686 screenresolution[1644:8118] starting screenresolution argv=screenresolution get 23:35:47 INFO - 2016-04-20 23:35:47.688 screenresolution[1644:8118] Display 0: 1600x1200x32@60 23:35:47 INFO - 2016-04-20 23:35:47.694 screenresolution[1645:8121] starting screenresolution argv=screenresolution list 23:35:47 INFO - Available Modes on Display 0 23:35:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:35:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:35:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:35:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:35:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:35:47 INFO - Intel Iris: 23:35:47 INFO - Chipset Model: Intel Iris 23:35:47 INFO - Type: GPU 23:35:47 INFO - Bus: Built-In 23:35:47 INFO - VRAM (Dynamic, Max): 1536 MB 23:35:47 INFO - Vendor: Intel (0x8086) 23:35:47 INFO - Device ID: 0x0a2e 23:35:47 INFO - Revision ID: 0x0009 23:35:47 INFO - Displays: 23:35:47 INFO - Display: 23:35:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:35:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:35:47 INFO - Display Serial Number: 1600x1200 60 23:35:47 INFO - Main Display: Yes 23:35:47 INFO - Mirror: Off 23:35:47 INFO - Online: Yes 23:35:47 INFO - Rotation: Supported 23:35:47 INFO - Return code: 0 23:35:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:35:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:35:47 INFO - Python 2.7.3 23:35:47 INFO - Return code: 0 23:35:47 INFO - grabbing minidump binary from tooltool 23:35: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')]} 23:35:47 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 0x10cc8cd30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc83b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:35:47 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 23:35:47 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 23:35:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:35:48 INFO - Return code: 0 23:35:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:35:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:35:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:35:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:35:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:35:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:35:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:35:49 INFO - Return code: 0 23:35:49 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/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 23:35:49 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/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --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 23:35:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bFuOKb7EDS/Render', 23:35:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:35:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:35:49 INFO - 'HOME': '/Users/cltbld', 23:35:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:35:49 INFO - 'LOGNAME': 'cltbld', 23:35:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:35:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:35:49 INFO - 'MOZ_NO_REMOTE': '1', 23:35:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:35:49 INFO - 'NO_EM_RESTART': '1', 23:35:49 INFO - 'PAGER': '/bin/cat', 23:35:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:35:49 INFO - 'PWD': '/builds/slave/test', 23:35:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:35:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:35:49 INFO - 'SHELL': '/bin/bash', 23:35:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wfAP4OK62k/Listeners', 23:35:49 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 23:35:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:35:49 INFO - 'USER': 'cltbld', 23:35:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:35:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:35:49 INFO - 'XPC_FLAGS': '0x0', 23:35:49 INFO - 'XPC_SERVICE_NAME': '0', 23:35:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:35:49 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/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 23:35:49 INFO - mozversion application_buildid: 20160420213010 23:35:49 INFO - mozversion application_changeset: d9fdbad8f079c7801abdef70df2cad93efcce797 23:35:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:35:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:35:49 INFO - mozversion application_name: Firefox 23:35:49 INFO - mozversion application_remotingname: firefox-dev 23:35:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:35:49 INFO - mozversion application_vendor: Mozilla 23:35:49 INFO - mozversion application_version: 47.0a2 23:35:49 INFO - mozversion platform_buildid: 20160420213010 23:35:49 INFO - mozversion platform_changeset: d9fdbad8f079c7801abdef70df2cad93efcce797 23:35:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:35:49 INFO - mozversion platform_version: 47.0a2 23:35:49 INFO - using testdate: 1461220549 23:35:49 INFO - actual date: 1461220549 23:35:49 INFO - starting webserver on 'localhost:49270' 23:35:49 INFO - SUITE-START | Running 2 tests 23:35:49 INFO - TEST-START | dromaeo_css 23:35:49 INFO - operating with platform_type : mac_ 23:35:49 INFO - Initialising browser for dromaeo_css test... 23:35:49 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpvxb1EG/profile http://localhost:49270/getInfo.html) 23:35:51 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 23:35:51 INFO - PROCESS | 1663 | colorDepth:24 23:35:51 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 23:35:51 INFO - PROCESS | 1663 | __metrics 23:35:51 INFO - PROCESS | 1663 | 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" 23:35:51 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:35:52 INFO - TEST-INFO | 1663: exit 0 23:35:52 INFO - Browser initialized. 23:35:52 INFO - Running cycle 1/1 for dromaeo_css test... 23:35:52 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpvxb1EG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:36:04 INFO - PROCESS | 1666 | RSS: Main: 207507456 23:36:04 INFO - PROCESS | 1666 | 23:36:55 INFO - PROCESS | 1666 | 1461220615526 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] 23:39:05 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 23:39:07 INFO - PROCESS | 1666 | RSS: Main: 359198720 23:39:07 INFO - PROCESS | 1666 | 23:42:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 23:42:10 INFO - PROCESS | 1666 | RSS: Main: 336986112 23:42:10 INFO - PROCESS | 1666 | 23:45:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 23:45:13 INFO - PROCESS | 1666 | RSS: Main: 344313856 23:45:13 INFO - PROCESS | 1666 | 23:48:15 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 23:48:15 INFO - PROCESS | 1666 | RSS: Main: 306524160 23:48:15 INFO - PROCESS | 1666 | 23:51:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 23:51:19 INFO - PROCESS | 1666 | RSS: Main: 303955968 23:51:19 INFO - PROCESS | 1666 | 23:54:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 23:54:22 INFO - PROCESS | 1666 | RSS: Main: 351752192 23:54:22 INFO - PROCESS | 1666 | 23:54:22 INFO - PROCESS | 1666 | __start_tp_report 23:54:22 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 23:54:22 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 23:54:22 INFO - PROCESS | 1666 | |i|pagename|runs| 23:54:22 INFO - PROCESS | 1666 | |0;dojo.html;3472.4096903526406;3525.048236976018;3530.2939412117585;3559.3949028665133;3604.0629436346544;4757.26262429324;5799.014167591509;5821.825345239639;5822.912656310159;5863.384344643815;4863.465018847924;5030.396352437712;5788.942110578893;5795.188673585691;5800.796972105977;4259.467566554181;5190.740462976836;5255.999999999981;5285.127953887613;5308.177232528945;5308.0710875596615;5309.654872433299;5310.70791106489;5310.8141215057285;5321.920171197435;5275.683458992473;5296.735163241822;5309.283246761695;5325.733713314338;5335.493128152819;5283.154695248761;5337.4662533746705;5342.2253773202865;5353;5370.060239458079;5104.132297509433;5147.0992576298995;5265.710385928773;5272.736363181825;5273.288106105665;5680.034394153004;5688.5733569982;5736.4550367715;5738.081906894919;5765.654060756368;6169.876602467925;6303.621782693053;6331.493480521547;6340.746370145189;6362.968185159091;6201.565890387675;6247.344028876993;6261.092141639482;6303.810885673392;6315.936840631581;5941.22764040672;5971.790987315423;5982.641041537521;5992.550558708114;6001.819945401645;6189;6278.748850045947;6297.779577714849;6384.074309225136;6403.64779937108;6136.325004249529;6144.9553575892205;6168.321484636686;6284.151639528605;6313.43179113882;6289.433950944437;6291.276503202191;6292.2134733158355;6302.747890084345;6334.556581039283;6252;6255.56211065221;6296.181496405438;6313.305536390993;6317.084022816666;6200.224971878515;6274.749010039639;6309.621422714652;6311.463525600376;6312.7790527332245;4908.337374454403;4967.254911763264;4981.103401387712;5007.7245751484015;5019.52314530119;4447.822087116544;4455.955440445554;4469.150861336336;4476.328550717353;4498.865034048984;5682.119271512922;5770.769169233184;5794.710264486759;5830.941690583039;5890.793822216202;6192;6265.185525881606;6270.0908368287255;6274.498040156868;6287.9056814147825;5834.533237341003;5839.240898683229;5854.8536286592325;5860.765569377178;5884.0585506318785;5336.226247194135;5445.782168713286;5446.183072539151;5462.399136095027;5467.9453205468235;6407.7436902524305;6502.057201705727;6512.804615861532;6512.804615861532;6543.018547217765;6223.3465486125115;6231.750729970931;6262.34245404226;6279.748810047547;6281.811545653638;6294.8426289341305;6363.6499992499;6388.744450222125;6442.194725659293;6465.709043093166;6208.255009398901;6213.2233470816145;6230.376962303914;6242.282137554058;6245.03202003672;5642.125470552071;6270.529710271839;6290.2766181889665;6292.811215663537;6309.652969086745;6053.727582258897;6135.44703110649;6200.193974783249;6201.906971395342;6231.688415579293;5054.747262636927;6109.070667812992;6204.5967012144065;6212.502999760099;6224.315325314303;6089.117078023573;6110.358412366638;6120.326764056039;6122.785702500391;6132.509399248139;4996.750162491788;6164.599301045418;6173.074038894202;6193.256809182927;6202.472789812917;5999.670018149044;6075.210222671025;6077.088436734525;6082.300535438304;6083.7870674526175 23:54:22 INFO - PROCESS | 1666 | |1;ext.html;485.5581420906975;551.4705882352941;586.6919867069788;612.6171143035602;661.4311691944927;27263.727362726368;32254;32280.67719322806;32337.67662323376;32424.02727918166;41352.58647413525;41830.58169418305;41904.37143442843;41931.58068419315;41951.79024104883;9617.663381781622;9861.852072218922;9869.358491698053;9876.30865839388;9884.406935583853;9717.708468745914;9828.459434731087;9841.212702983745;9844.06481384271;9873.308868379216;9799;9885.901140988606;9890.307678462474;9901.554430050664;9904.306698531105;9708.223342132613;9775;9794.90205097947;9848.06443387877;9848.60605575776;9741;9890.109890109852;9930.155936745385;10039;10058.89941100587;42634.934126646775;43058;43072.13855722868;43100.35349469761;43151.78424107891;2872.353720412911;3331.1505566080496;3502.702270307027;3519.454484554898;3801.733878628497;29546.26134346637;29602.25994350137;29634.407311853858;29930.95241666553;30110.54834177489;2779.4163225722623;2784.3595972926137;2819.6616406031203;2825.0818483992784;2826.7314605112483;466270;577064;583315;584395.0780246115;584545;71224.28775712177;71407.64296178486;71859;72014.27985720181;72219.63890180516;72369.27630723626;72655;72916;80031.59984200042;80062.59968700235;18219.362322318655;18406.447806565826;18430.815691843178;18440.446786596425;18487.260509579733;13989.30053497321;14062.718745625234;14077.225752583707;14095.859041409454;14108.647283817987;22926.08295668188;23339;23342.883285583463;23359;23373.065077396714;8979.685711000215;9306.86039709405;9320.766980825534;9321.72034838956;9338.95330523343;1007.5012868630075;1038.4288641247283;1052.8789189243182;1126.4367816091954;1158.9014933730728;23870;30239.395212095635;30394.696053039628;30777.538336924965;30986.845065774527;915.6566287642135;1159.7100724818795;1165.3532289579196;1167.486306025346;1205.8552973643043;11236.213465057368;15265.313060912285;15355.846441535665;15536.611584710472;15596.98619589723;548379;553241;559559;559887;560431;63273;64169.679151603945;64209.67895160494;64212;64266.35733642604;1410.189141243789;1538.8076490438696;1559.3216950626513;1588.4599236259728;1589.674116806075;27341.94405196397;28920.7107928918;29614.703852961196;30208.244793880327;30342.54486182665;51419.228711570104;51826.999999998494;51835;51937.22094168515;52073.7396313016;377150;381731.18268818065;383963;384297;384398;29990.70009299879;35051.29897402089;47314.52685473239;48651.540453786445;49232.507674922796;47298.527014729414;47402.52597474119;47513.762431189;49002;49393.50606493889;37490.62509374871;38309;38406.61593384142;38790.80604596959;39399.40900886546;37816.81091594634;38275.617243827204;39183.82448526549;39604.20791584095;40265.194696105325;39790.60209397985;39923;40263.79868100641;40290.597094028686;40578;34319.656803431644;35179.824100879334;35790.64209357873;35901.92294231177;36152.27695446129;24723.25830225096;26561.937522498883;26596.468070638093;26657;27161 23:54:22 INFO - PROCESS | 1666 | |2;jquery.html;370.91654377764996;371.47065431759734;377.3057574063724;381.22230649475057;385.6540673051053;20398.69401958972;21143.365699029033;21838.672419913713;22150.113995440162;22178.667319990174;19859.900700496513;19978.90010549945;20670.863102529354;20785.79214207861;20867.478313042142;5245.86885327866;5433.592480563953;5480;5484.561235101182;5509.862253443656;4037.2934736421157;4104.302268614343;6758.763443279487;6766.492513061516;6774.695138718769;6524.249711283216;6622.072909792633;6684.46524278059;6712;6726.562773419737;6419.871602567946;6645.667716614198;6653.866922661568;6661.433778128865;6666.300038495937;4006.3790112532606;5496.5053145216725;5736.827895163152;5750.511206547449;5750.856228594277;13296.335183240799;13620.387082581308;13735.519256825963;13749.587512374645;13872.4451021959;2250.5161390301164;2641.762241398264;2828.490871643103;2842.1899758568707;2947.4694554980097;10096.596136154545;10336.121429678486;10494.790104197873;10506;14275.357608907623;1749.7637818894414;1793.1392931393004;1795.9910200449044;1800.333876465716;1822.1891258390021;88823.55588222083;97639.53540696895;98148.0185198146;103322;106411.46794265979;11365.659030229106;11382.601608943813;11487.483063262172;11517.25137866036;11671.649850504498;14595.85404145945;14673.559793206221;14684;14692.926535367254;14703.264836758119;7741.845163096819;8505.511535481266;8983.640654373883;8989.41568798039;9064.728058158265;7342.48602597813;9219.907800921905;9228.584713687766;9234.261259099256;9263.166315031545;4570.588647021717;4586.174488592085;4607.9078418431445;4627.005193883331;4674.649401294849;1923.7018262169386;1975.3580086471954;1980.8019198080076;1982.3953694123143;1993.2226431691652;883.3639779358851;963.3401120232663;992.3500107429645;994.8308787506141;1015.7044290240783;18561.35035273759;20500.487487812625;20592.27927022577;20779.584408311748;20837.583248334948;1017.3387298256192;1023.902729240721;1029.6396261308632;1037.6938803053117;1122.03083588302;9337.972822989466;10616.097631701237;10970.616028438966;11043.226974111734;11055.557777688802;624030;656857;660983.6950815215;690539;692931;102813;110344.4482777581;117518.41240793912;120248;120512;1059.496739048961;1097.401915955806;1121.058311018748;1122.550979608147;1123.039800970175;17607.079186412488;17636.559086022953;17904.641907162044;18252.26990920348;18503.629927401107;50145;51092.2933190205;51560.74219628878;53076.73461632821;53195.13817016386;71799;73699;74069;74648.62675686803;74909.50180996458;7778.92221077782;8091.676332946617;8130.878036829334;8380.994280686356;8650;12492.687682808002;13156.947444204632;13451.663708407368;13576.86423135756;13855.23796191179;11341.546338146381;11772.352520611106;11890.881091188978;11972.58095966637;12068.034557235225;11623.302601843916;11752.412379381169;11897.583584574844;11909.106816988588;12073.154879158375;8852.778680533038;11913.940430297793;11966.521339146337;11973.339324496299;12113.030957523553;8236.629351679308;8258.669653213805;8297.917020829715;8421.07368189511;8431;8685.261752751087;11145.665630031112;12022.939885300517;12098.516059357878;12356 23:54:22 INFO - PROCESS | 1666 | |3;mootools.html;1387.3964825300995;1454.3092031285134;1618.401191559123;1861.3299212283584;2037.9184832606686;8121.51270923745;8733.519656418903;8902.376833621649;8913.286937045044;8930.508822014786;8589.226969572737;8866.733997980054;8880.511571863544;8887.20015198632;8904.732858014253;2507.0849140063883;2545.414554652431;2546.3888666719945;2547.350425641459;2547.3886267295807;2437.914672986446;2536.3912660961328;2537.086648806428;2537.644729737838;2539.0351666366755;2314.710661167354;2343.6641114564704;2534.1637259704253;2547.414094758207;2554.5274124286975;2521.0924067335745;2542.5423423783714;2550.795936325099;2555.09294200558;2557.1689201009744;2115.8942052897446;2514.2582938033324;2551.094361501677;2555.514452254066;2556.642070110186;8880.955595222047;9011.549422528913;9071.63713451461;9091.545422728903;9105.317101217435;5496.780128794843;5738.397468265814;5739.598228124033;5794.362620111827;5807.709614519299;3640.9635903641156;3684.6315368463206;3744.6817020553285;3789.3368660484307;3816.9427458588143;1073.366713638949;1092.8524649172418;1094.895984881435;1105.1766434006636;1125.0493333133488;12303.95416389599;12316.692082698004;12320.445579949013;12327.013838892866;12349.691257718629;4828.8792780180775;4910.926336104962;4972.701637901665;4991.326170966873;4996.100701873697;10395.94802025985;10427.895721042843;10430.269881108401;10439.530221140067;10443.268971172098;5541.5843811714285;5597.580181486404;5600.915986260209;5602.943970560324;5608.383077861432;4467.687261891701;4575.611073058761;4632.953670463319;4633.328167415706;5012.974935125374;3370.443876760356;3970.3845903884944;4010.4184893190322;4080.3471444568727;4092.202020606013;1348.4875747216192;1357.429879450633;1358.5245164192454;1360.8162898008643;1368.1244003837553;1378.7242551489742;1401.4184113592949;1417.3165605920128;1424.579748974055;1427.8215223097113;8412.453190542717;8520.318374530023;8520.977482702116;8526.445781024213;8526.488410695378;1058.6982709927788;1113.5267511307661;1219.591436868659;1223.975520489603;1237.8019516877257;6339.936600633934;6374.490040796725;6435.613863168225;6439.58142720731;6440.033994900708;9486;10713;10956.52086968265;11162.665120046411;11189;9751.951240243754;10625.628103016512;10980.780384392267;11040.613578524713;11066;7239.493235473572;8422.705205317907;8537.317014638815;8554.401191916506;8612.65549378018;7969.481983671043;8110.797230069295;8174.386920980831;8193.95903020505;8212.712555060543;9582.760430989281;9875.901240987498;9889.406635601887;9918.156956658764;9983.251256155963;9946.552405141641;10071.992800719869;10257.076863082359;10264.384136951807;10280.794384112425;6705.765298214539;7600.239976002355;7615.581143037186;7647.579383133759;7719.498232614862;7380.77857664271;7627.733029343946;7673.53958762476;7690;7698.653560589899;7163.534370265916;7330.157031941395;7341.4126869849215;7423.1834498206235;7444.255574442513;7163.534370266125;7243.746468873775;7275.417966562768;7331.230220826949;7388.519746216479;7566.470347075652;7636.08366995964;7710.190430004993;7750.147483776667;7761.883571746541;7011.3339232772805;7022.40309573692;7033.9296607033275;7035.788926332219;7042.084529011196;7103.467239957127;7142;7146.606936618536;7176.677049532679;7182.569045857265 23:54:22 INFO - PROCESS | 1666 | |4;prototype.html;334.4297971957812;337.809137837122;340.83639852870635;345.5404312264688;346.4197469239024;6534.934650653492;6543.05125756765;6594.340565943403;6604.372584604461;6615.966920165399;6491.188601424822;6491.902621460682;6503.902441463382;6514.837129071776;6522.739090436388;4623.352730617717;4760.523947605247;4793.08931303053;4879.756012199394;4880.8047678092835;3999.9400008999887;4832.395950506187;4861.902761944759;4880.7071575705395;4908.779104940269;4814.975925120387;4826.879328016793;4827.56551910328;4828.299896515004;4831.227003679412;4550.38569793077;4677.50886156952;4793.5925446337105;4825.131476334259;4833.564979151858;4162.146759914211;4162.604552567501;4167.729097608646;4181.393697913816;4196;6234.750609975596;6247.406496382836;6277.121203031579;6281.7173227204885;6286.182796236506;4646.279826626878;5799.3620701722775;5805.390434004453;5816.069428891357;5816.505597024258;6734.023566582867;6980.825479363006;6982.441404687613;6986.161660600709;7014.894776578355;4722.456917454468;4737.881552961204;4752.025834703912;4757.333973243749;4770.045990801819;6012.218411606463;7332.230115837813;7380.225076366958;7388.741394051182;7419;4809.495003024632;4819.204831202812;4823.2282834746275;4824.517548245148;4827.758612069382;4892.143874821892;4894.902101957941;4895;4904.2643603459055;4913.950860491421;5771.769129234784;5788;5796.62321949072;5801.622894511843;5812.157237200582;6767.560108593024;7024.1571011478945;7025.89461158083;7044.15470143586;7071.681774320168;1682.1337011439118;1693.3226709316373;1702.880798344104;1703.1399143432734;1703.557075160467;996.8454889492141;1031.4481752262504;1053.6513263023342;1067.3969207397795;1101.5483651702923;6368.172137622079;6486.318936511599;6564.6389448580785;6571.572847764975;6581.901271480931;7215.639218039077;7235.2764723527225;7238.52949558277;7252;7253.927460725431;6530.085787989685;6531.791618550488;6549.541532092803;6556;6563.146790917151;3680.871169509054;3779.4708740776314;3817.408301713239;3819.694424446038;3832.7317087803585;7558.319751222416;7563.621818909032;7598.8100297493;7614.809629759189;7634.236576342432;7408.99978502906;7494.5503269803985;7500;7503.09962804467;7512.887306690404;7574.5076570022775;7575.280348366897;7599.658015389321;7601.505902116234;7615.504992175491;7023.122109736283;7233.132024157135;7279.417646588367;7279.8544029120185;7289.343959043712;7111.715531378687;7168.211496735459;7178.102737157856;7180.030695855992;7193.424526038009;6407.679616019087;7357.337839594462;7565.2812982766545;7570.356519695888;7571.242875712384;3995.2409042281874;4832.009438065258;4854.878628034327;4856.587190088882;4860.562549370574;6972.477064220102;7230.783076507713;7248.782536523912;7253.56478611285;7257.383122434652;7031.964840175767;7070.611116388648;7084.326988936043;7089.964550177217;7103;5036.521530454747;7031.050808140835;7040.401565866959;7052.647367631495;7068.646567671493;6848.589084654936;6898.310168983062;6898.310168983262;6899.862002760017;6904.102466679299;6729.158855143107;6956.373926346653;7022.367986881205;7033;7043.506954513135;5714.7998920225555;6554.63949482783;6824.18109826824;6868.038474613658;6952.721891124501 23:54:22 INFO - PROCESS | 1666 | |5;yui.html;733.9009233753443;773.3697036914914;781.8003234351589;792.3700657976908;795.9403044771643;15010;15067.773983390245;15278.46525371611;15285.235738213101;15320.92753507255;14506.419743210285;14547.92726036371;14557.708845823103;14606.853931460682;14607.634809129777;822.9670813167467;831.1812864328631;985.1232403161192;1008.3848852200152;1014.2595904989362;1019.7858449725567;1024.4826362686833;1025.2925481417835;1027.3733022856088;1028.6245520385044;1068.4230515521608;1076.445630500293;1077.2136340471461;1077.3589714120087;1077.9407132607744;846.6951897316961;1054.2251445187742;1066.2056767708068;1089.7874914391696;1090.983635245472;786.8662327404297;791.2364568191665;791.6002418778537;792.374024520628;793.7499687598388;15221.695566088727;15287.312070956723;15305.693886122215;15312.387504499806;15320.004199726982;12485.75028499434;12602.495900164075;12697.682557936034;12900.419481123372;12909.67725806853;10084;10158.933312002282;10188.235882308782;10294.845577316346;10382.221333400024;3554.0581745837376;3611.963880361215;3630.473581330719;3713.4615480755137;3722.9627703722886;86986;87266.25467490614;87334.5633271836;88002.5599872003;89989.55005224998;9019.819603608023;9326.626934922662;9968.501574921225;9995.70012899614;10455.790884182426;23549.175778848;23747.287581372588;24039.27882163538;24047.398815029763;24134.517309653707;7628.466007379541;7714.537127772352;7817.257360550739;8005.279524842792;8010.759677209693;4055.513338399411;4066.2883995300704;4072.5927407259037;4074.2869997750868;4076.470058892325;3782.413725872494;3832.80835958201;3836.3478208704587;3840.9423858642144;3846.538415390115;674.652553934724;689.1076056506836;690.5994523176546;696.951213415056;718.1561665043657;545.38916413617;556.1463154058581;597.7071235094772;611.782817099932;621.1924498152384;7854.803629909184;7989.760307190793;8008.359331253486;8159.46963447374;8224.300934420522;549.6180154792461;596.7076132694948;604.9939500605026;609.1624016976657;609.3175643279556;3777.4985540198295;3806.8096595170687;3881.6312450317173;3895.2209558088493;3896.6103389660807;98958;100199.49900250598;101265;101588.98411015944;101818;82288;83411.58294208611;84361.73457398143;85101;88070;72361.6381918087;74512;74829.25170748332;74893;75948.62025689836;7686.154523002577;7847.607619619111;7890.408219383455;7900.446968712365;7921.762347129365;47690.52309476861;48669.026619466706;48805;51362.48637513577;51923.221151682;66904;68098.65950670216;68576.6571167141;69327;69815.30184698291;10221.642242521477;10478.161747060369;10516.369017858648;10634.680959571226;10821.242513024014;10381.27331086847;10628.096611787776;10826.971437713404;10827.783444330911;11142.331460112418;8776.551868941553;10154.796904062025;10212.693619191436;10427.217958653284;10435.269531132748;10097.646582369582;10126.645567405105;10371.377717336984;10377.740556486147;10512.42181680015;10125.64560240388;10185.949070254897;10294.794104118026;10992.835107473235;11297.322160670386;9434.528273586431;9546.379485333431;9619.04525235462;9741.853872191781;9857.605695772376;9588.904110958802;9631.133198012212;9762.267829912644;9772.413655180711;9797.951010245188 23:54:22 INFO - PROCESS | 1666 | __end_tp_report 23:54:22 INFO - PROCESS | 1666 | __start_cc_report 23:54:22 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1522 23:54:22 INFO - PROCESS | 1666 | __end_cc_report 23:54:22 INFO - PROCESS | 1666 | __startTimestamp1461221662205__endTimestamp 23:54:22 INFO - PROCESS | 1666 | 23:54:22 INFO - PROCESS | 1666 | ------- Summary: start ------- 23:54:22 INFO - PROCESS | 1666 | Number of tests: 6 23:54:22 INFO - PROCESS | 1666 | 23:54:22 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5789.75 Median:6082.30 stddev:655.21 (10.8%) stddev-sans-first:631.50 23:54:22 INFO - PROCESS | 1666 | Values: 3472.4 3525.0 3530.3 3559.4 3604.1 4757.3 5799.0 5821.8 5822.9 5863.4 4863.5 5030.4 5788.9 5795.2 5800.8 4259.5 5190.7 5256.0 5285.1 5308.2 5308.1 5309.7 5310.7 5310.8 5321.9 5275.7 5296.7 5309.3 5325.7 5335.5 5283.2 5337.5 5342.2 5353.0 5370.1 5104.1 5147.1 5265.7 5272.7 5273.3 5680.0 5688.6 5736.5 5738.1 5765.7 6169.9 6303.6 6331.5 6340.7 6363.0 6201.6 6247.3 6261.1 6303.8 6315.9 5941.2 5971.8 5982.6 5992.6 6001.8 6189.0 6278.7 6297.8 6384.1 6403.6 6136.3 6145.0 6168.3 6284.2 6313.4 6289.4 6291.3 6292.2 6302.7 6334.6 6252.0 6255.6 6296.2 6313.3 6317.1 6200.2 6274.7 6309.6 6311.5 6312.8 4908.3 4967.3 4981.1 5007.7 5019.5 4447.8 4456.0 4469.2 4476.3 4498.9 5682.1 5770.8 5794.7 5830.9 5890.8 6192.0 6265.2 6270.1 6274.5 6287.9 5834.5 5839.2 5854.9 5860.8 5884.1 5336.2 5445.8 5446.2 5462.4 5467.9 6407.7 6502.1 6512.8 6512.8 6543.0 6223.3 6231.8 6262.3 6279.7 6281.8 6294.8 6363.6 6388.7 6442.2 6465.7 6208.3 6213.2 6230.4 6242.3 6245.0 5642.1 6270.5 6290.3 6292.8 6309.7 6053.7 6135.4 6200.2 6201.9 6231.7 5054.7 6109.1 6204.6 6212.5 6224.3 6089.1 6110.4 6120.3 6122.8 6132.5 4996.8 6164.6 6173.1 6193.3 6202.5 5999.7 6075.2 6077.1 6082.3 6083.8 23:54:22 INFO - PROCESS | 1666 | 23:54:22 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:65954.17 Median:39501.81 stddev:134879.92 (341.5%) stddev-sans-first:135168.73 23:54:22 INFO - PROCESS | 1666 | Values: 485.6 551.5 586.7 612.6 661.4 27263.7 32254.0 32280.7 32337.7 32424.0 41352.6 41830.6 41904.4 41931.6 41951.8 9617.7 9861.9 9869.4 9876.3 9884.4 9717.7 9828.5 9841.2 9844.1 9873.3 9799.0 9885.9 9890.3 9901.6 9904.3 9708.2 9775.0 9794.9 9848.1 9848.6 9741.0 9890.1 9930.2 10039.0 10058.9 42634.9 43058.0 43072.1 43100.4 43151.8 2872.4 3331.2 3502.7 3519.5 3801.7 29546.3 29602.3 29634.4 29931.0 30110.5 2779.4 2784.4 2819.7 2825.1 2826.7 466270.0 577064.0 583315.0 584395.1 584545.0 71224.3 71407.6 71859.0 72014.3 72219.6 72369.3 72655.0 72916.0 80031.6 80062.6 18219.4 18406.4 18430.8 18440.4 18487.3 13989.3 14062.7 14077.2 14095.9 14108.6 22926.1 23339.0 23342.9 23359.0 23373.1 8979.7 9306.9 9320.8 9321.7 9339.0 1007.5 1038.4 1052.9 1126.4 1158.9 23870.0 30239.4 30394.7 30777.5 30986.8 915.7 1159.7 1165.4 1167.5 1205.9 11236.2 15265.3 15355.8 15536.6 15597.0 548379.0 553241.0 559559.0 559887.0 560431.0 63273.0 64169.7 64209.7 64212.0 64266.4 1410.2 1538.8 1559.3 1588.5 1589.7 27341.9 28920.7 29614.7 30208.2 30342.5 51419.2 51827.0 51835.0 51937.2 52073.7 377150.0 381731.2 383963.0 384297.0 384398.0 29990.7 35051.3 47314.5 48651.5 49232.5 47298.5 47402.5 47513.8 49002.0 49393.5 37490.6 38309.0 38406.6 38790.8 39399.4 37816.8 38275.6 39183.8 39604.2 40265.2 39790.6 39923.0 40263.8 40290.6 40578.0 34319.7 35179.8 35790.6 35901.9 36152.3 24723.3 26561.9 26596.5 26657.0 27161.0 23:54:22 INFO - PROCESS | 1666 | 23:54:22 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:35654.98 Median:21005.42 stddev:109832.51 (522.9%) stddev-sans-first:110108.66 23:54:22 INFO - PROCESS | 1666 | Values: 370.9 371.5 377.3 381.2 385.7 20398.7 21143.4 21838.7 22150.1 22178.7 19859.9 19978.9 20670.9 20785.8 20867.5 5245.9 5433.6 5480.0 5484.6 5509.9 4037.3 4104.3 6758.8 6766.5 6774.7 6524.2 6622.1 6684.5 6712.0 6726.6 6419.9 6645.7 6653.9 6661.4 6666.3 4006.4 5496.5 5736.8 5750.5 5750.9 13296.3 13620.4 13735.5 13749.6 13872.4 2250.5 2641.8 2828.5 2842.2 2947.5 10096.6 10336.1 10494.8 10506.0 14275.4 1749.8 1793.1 1796.0 1800.3 1822.2 88823.6 97639.5 98148.0 103322.0 106411.5 11365.7 11382.6 11487.5 11517.3 11671.6 14595.9 14673.6 14684.0 14692.9 14703.3 7741.8 8505.5 8983.6 8989.4 9064.7 7342.5 9219.9 9228.6 9234.3 9263.2 4570.6 4586.2 4607.9 4627.0 4674.6 1923.7 1975.4 1980.8 1982.4 1993.2 883.4 963.3 992.4 994.8 1015.7 18561.4 20500.5 20592.3 20779.6 20837.6 1017.3 1023.9 1029.6 1037.7 1122.0 9338.0 10616.1 10970.6 11043.2 11055.6 624030.0 656857.0 660983.7 690539.0 692931.0 102813.0 110344.4 117518.4 120248.0 120512.0 1059.5 1097.4 1121.1 1122.6 1123.0 17607.1 17636.6 17904.6 18252.3 18503.6 50145.0 51092.3 51560.7 53076.7 53195.1 71799.0 73699.0 74069.0 74648.6 74909.5 7778.9 8091.7 8130.9 8381.0 8650.0 12492.7 13156.9 13451.7 13576.9 13855.2 11341.5 11772.4 11890.9 11972.6 12068.0 11623.3 11752.4 11897.6 11909.1 12073.2 8852.8 11913.9 11966.5 11973.3 12113.0 8236.6 8258.7 8297.9 8421.1 8431.0 8685.3 11145.7 12022.9 12098.5 12356.0 23:54:22 INFO - PROCESS | 1666 | 23:54:22 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:6149.63 Median:5004.54 stddev:3228.55 (64.5%) stddev-sans-first:3217.76 23:54:22 INFO - PROCESS | 1666 | Values: 1387.4 1454.3 1618.4 1861.3 2037.9 8121.5 8733.5 8902.4 8913.3 8930.5 8589.2 8866.7 8880.5 8887.2 8904.7 2507.1 2545.4 2546.4 2547.4 2547.4 2437.9 2536.4 2537.1 2537.6 2539.0 2314.7 2343.7 2534.2 2547.4 2554.5 2521.1 2542.5 2550.8 2555.1 2557.2 2115.9 2514.3 2551.1 2555.5 2556.6 8881.0 9011.5 9071.6 9091.5 9105.3 5496.8 5738.4 5739.6 5794.4 5807.7 3641.0 3684.6 3744.7 3789.3 3816.9 1073.4 1092.9 1094.9 1105.2 1125.0 12304.0 12316.7 12320.4 12327.0 12349.7 4828.9 4910.9 4972.7 4991.3 4996.1 10395.9 10427.9 10430.3 10439.5 10443.3 5541.6 5597.6 5600.9 5602.9 5608.4 4467.7 4575.6 4633.0 4633.3 5013.0 3370.4 3970.4 4010.4 4080.3 4092.2 1348.5 1357.4 1358.5 1360.8 1368.1 1378.7 1401.4 1417.3 1424.6 1427.8 8412.5 8520.3 8521.0 8526.4 8526.5 1058.7 1113.5 1219.6 1224.0 1237.8 6339.9 6374.5 6435.6 6439.6 6440.0 9486.0 10713.0 10956.5 11162.7 11189.0 9752.0 10625.6 10980.8 11040.6 11066.0 7239.5 8422.7 8537.3 8554.4 8612.7 7969.5 8110.8 8174.4 8194.0 8212.7 9582.8 9875.9 9889.4 9918.2 9983.3 9946.6 10072.0 10257.1 10264.4 10280.8 6705.8 7600.2 7615.6 7647.6 7719.5 7380.8 7627.7 7673.5 7690.0 7698.7 7163.5 7330.2 7341.4 7423.2 7444.3 7163.5 7243.7 7275.4 7331.2 7388.5 7566.5 7636.1 7710.2 7750.1 7761.9 7011.3 7022.4 7033.9 7035.8 7042.1 7103.5 7142.0 7146.6 7176.7 7182.6 23:54:22 INFO - PROCESS | 1666 | 23:54:22 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5749.78 Median:6530.94 stddev:1814.76 (27.8%) stddev-sans-first:1773.75 23:54:22 INFO - PROCESS | 1666 | Values: 334.4 337.8 340.8 345.5 346.4 6534.9 6543.1 6594.3 6604.4 6616.0 6491.2 6491.9 6503.9 6514.8 6522.7 4623.4 4760.5 4793.1 4879.8 4880.8 3999.9 4832.4 4861.9 4880.7 4908.8 4815.0 4826.9 4827.6 4828.3 4831.2 4550.4 4677.5 4793.6 4825.1 4833.6 4162.1 4162.6 4167.7 4181.4 4196.0 6234.8 6247.4 6277.1 6281.7 6286.2 4646.3 5799.4 5805.4 5816.1 5816.5 6734.0 6980.8 6982.4 6986.2 7014.9 4722.5 4737.9 4752.0 4757.3 4770.0 6012.2 7332.2 7380.2 7388.7 7419.0 4809.5 4819.2 4823.2 4824.5 4827.8 4892.1 4894.9 4895.0 4904.3 4914.0 5771.8 5788.0 5796.6 5801.6 5812.2 6767.6 7024.2 7025.9 7044.2 7071.7 1682.1 1693.3 1702.9 1703.1 1703.6 996.8 1031.4 1053.7 1067.4 1101.5 6368.2 6486.3 6564.6 6571.6 6581.9 7215.6 7235.3 7238.5 7252.0 7253.9 6530.1 6531.8 6549.5 6556.0 6563.1 3680.9 3779.5 3817.4 3819.7 3832.7 7558.3 7563.6 7598.8 7614.8 7634.2 7409.0 7494.6 7500.0 7503.1 7512.9 7574.5 7575.3 7599.7 7601.5 7615.5 7023.1 7233.1 7279.4 7279.9 7289.3 7111.7 7168.2 7178.1 7180.0 7193.4 6407.7 7357.3 7565.3 7570.4 7571.2 3995.2 4832.0 4854.9 4856.6 4860.6 6972.5 7230.8 7248.8 7253.6 7257.4 7032.0 7070.6 7084.3 7090.0 7103.0 5036.5 7031.1 7040.4 7052.6 7068.6 6848.6 6898.3 6898.3 6899.9 6904.1 6729.2 6956.4 7022.4 7033.0 7043.5 5714.8 6554.6 6824.2 6868.0 6952.7 23:54:22 INFO - PROCESS | 1666 | 23:54:22 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:19051.96 Median:4060.90 stddev:27618.41 (680.1%) stddev-sans-first:27661.63 23:54:22 INFO - PROCESS | 1666 | Values: 733.9 773.4 781.8 792.4 795.9 15010.0 15067.8 15278.5 15285.2 15320.9 14506.4 14547.9 14557.7 14606.9 14607.6 823.0 831.2 985.1 1008.4 1014.3 1019.8 1024.5 1025.3 1027.4 1028.6 1068.4 1076.4 1077.2 1077.4 1077.9 846.7 1054.2 1066.2 1089.8 1091.0 786.9 791.2 791.6 792.4 793.7 15221.7 15287.3 15305.7 15312.4 15320.0 12485.8 12602.5 12697.7 12900.4 12909.7 10084.0 10158.9 10188.2 10294.8 10382.2 3554.1 3612.0 3630.5 3713.5 3723.0 86986.0 87266.3 87334.6 88002.6 89989.6 9019.8 9326.6 9968.5 9995.7 10455.8 23549.2 23747.3 24039.3 24047.4 24134.5 7628.5 7714.5 7817.3 8005.3 8010.8 4055.5 4066.3 4072.6 4074.3 4076.5 3782.4 3832.8 3836.3 3840.9 3846.5 674.7 689.1 690.6 697.0 718.2 545.4 556.1 597.7 611.8 621.2 7854.8 7989.8 8008.4 8159.5 8224.3 549.6 596.7 605.0 609.2 609.3 3777.5 3806.8 3881.6 3895.2 3896.6 98958.0 100199.5 101265.0 101589.0 101818.0 82288.0 83411.6 84361.7 85101.0 88070.0 72361.6 74512.0 74829.3 74893.0 75948.6 7686.2 7847.6 7890.4 7900.4 7921.8 47690.5 48669.0 48805.0 51362.5 51923.2 66904.0 68098.7 68576.7 69327.0 69815.3 10221.6 10478.2 10516.4 10634.7 10821.2 10381.3 10628.1 10827.0 10827.8 11142.3 8776.6 10154.8 10212.7 10427.2 10435.3 10097.6 10126.6 10371.4 10377.7 10512.4 10125.6 10185.9 10294.8 10992.8 11297.3 9434.5 9546.4 9619.0 9741.9 9857.6 9588.9 9631.1 9762.3 9772.4 9798.0 23:54:22 INFO - PROCESS | 1666 | -------- Summary: end -------- 23:54:22 INFO - PROCESS | 1666 | 23:54:22 INFO - TEST-INFO | 1666: exit 0 23:54:22 INFO - TEST-OK | dromaeo_css | took 1113431ms 23:54:22 INFO - TEST-START | kraken 23:54:22 INFO - operating with platform_type : mac_ 23:54:22 INFO - Initialising browser for kraken test... 23:54:22 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpjktDHP/profile http://localhost:49270/getInfo.html) 23:54:25 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 23:54:25 INFO - PROCESS | 1671 | colorDepth:24 23:54:25 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 23:54:25 INFO - PROCESS | 1671 | __metrics 23:54:25 INFO - PROCESS | 1671 | 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" 23:54:25 INFO - PROCESS | 1671 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:54:25 INFO - TEST-INFO | 1671: exit 0 23:54:25 INFO - Browser initialized. 23:54:25 INFO - Running cycle 1/1 for kraken test... 23:54:25 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpjktDHP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:54:37 INFO - PROCESS | 1673 | RSS: Main: 208134144 23:54:37 INFO - PROCESS | 1673 | 23:54:40 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 23:54:40 INFO - PROCESS | 1673 | RSS: Main: 262873088 23:54:40 INFO - PROCESS | 1673 | 23:54:43 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 23:54:43 INFO - PROCESS | 1673 | RSS: Main: 273125376 23:54:43 INFO - PROCESS | 1673 | 23:54:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 23:54:47 INFO - PROCESS | 1673 | RSS: Main: 262205440 23:54:47 INFO - PROCESS | 1673 | 23:54:49 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 23:54:50 INFO - PROCESS | 1673 | RSS: Main: 264769536 23:54:50 INFO - PROCESS | 1673 | 23:54:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:54:52 INFO - PROCESS | 1673 | RSS: Main: 264667136 23:54:52 INFO - PROCESS | 1673 | 23:54:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 23:54:56 INFO - PROCESS | 1673 | RSS: Main: 264663040 23:54:56 INFO - PROCESS | 1673 | 23:54:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 23:54:59 INFO - PROCESS | 1673 | RSS: Main: 264716288 23:54:59 INFO - PROCESS | 1673 | 23:55:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 23:55:03 INFO - PROCESS | 1673 | RSS: Main: 265089024 23:55:03 INFO - PROCESS | 1673 | 23:55:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:55:05 INFO - PROCESS | 1673 | RSS: Main: 298541056 23:55:05 INFO - PROCESS | 1673 | 23:55:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:55:07 INFO - PROCESS | 1673 | RSS: Main: 299749376 23:55:07 INFO - PROCESS | 1673 | 23:55:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:55:10 INFO - PROCESS | 1673 | RSS: Main: 305852416 23:55:10 INFO - PROCESS | 1673 | 23:55:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:55:13 INFO - PROCESS | 1673 | RSS: Main: 307580928 23:55:13 INFO - PROCESS | 1673 | 23:55:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:55:16 INFO - PROCESS | 1673 | RSS: Main: 307572736 23:55:16 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 23:55:18 INFO - PROCESS | 1673 | RSS: Main: 370696192 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | __start_tp_report 23:55:18 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 23:55:18 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 23:55:18 INFO - PROCESS | 1673 | |i|pagename|runs| 23:55:18 INFO - PROCESS | 1673 | |0;ai-astar;111;108;109;111;112;112;118;109;109;110 23:55:18 INFO - PROCESS | 1673 | |1;audio-beat-detection;153;120;152;148;118;138;118;148;151;114 23:55:18 INFO - PROCESS | 1673 | |2;audio-dft;183;184;184;184;184;182;199;265;205;182 23:55:18 INFO - PROCESS | 1673 | |3;audio-fft;73;72;75;113;55;56;60;77;132;57 23:55:18 INFO - PROCESS | 1673 | |4;audio-oscillator;87;94;134;79;82;81;117;77;80;117 23:55:18 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;89;86;89;87;88;85;90;90;135;131 23:55:18 INFO - PROCESS | 1673 | |6;imaging-darkroom;93;93;93;93;93;93;93;93;93;93 23:55:18 INFO - PROCESS | 1673 | |7;imaging-desaturate;125;135;100;86;85;86;86;86;86;84 23:55:18 INFO - PROCESS | 1673 | |8;json-parse-financial;57;58;57;57;58;58;59;68;50;52 23:55:18 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;62;62;68;77;71;67;66;66;66;66 23:55:18 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;80;80;81;81;81;80;79;80;81;83 23:55:18 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;122;131;119;130;118;117;117;128;141;116 23:55:18 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;133;120;132;131;133;119;134;134;132;123 23:55:18 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;65;66;68;65;66;65;67;67;67 23:55:18 INFO - PROCESS | 1673 | __end_tp_report 23:55:18 INFO - PROCESS | 1673 | __start_cc_report 23:55:18 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1235 23:55:18 INFO - PROCESS | 1673 | __end_cc_report 23:55:18 INFO - PROCESS | 1673 | __startTimestamp1461221718391__endTimestamp 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | ------- Summary: start ------- 23:55:18 INFO - PROCESS | 1673 | Number of tests: 14 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.00 stddev:2.85 (2.6%) stddev-sans-first:3.02 23:55:18 INFO - PROCESS | 1673 | Values: 111.0 108.0 109.0 111.0 112.0 112.0 118.0 109.0 109.0 110.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:148.00 stddev:16.50 (11.1%) stddev-sans-first:16.31 23:55:18 INFO - PROCESS | 1673 | Values: 153.0 120.0 152.0 148.0 118.0 138.0 118.0 148.0 151.0 114.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:195.20 Median:184.00 stddev:25.78 (14.0%) stddev-sans-first:26.96 23:55:18 INFO - PROCESS | 1673 | Values: 183.0 184.0 184.0 184.0 184.0 182.0 199.0 265.0 205.0 182.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:77.00 Median:66.00 stddev:25.78 (39.1%) stddev-sans-first:27.30 23:55:18 INFO - PROCESS | 1673 | Values: 73.0 72.0 75.0 113.0 55.0 56.0 60.0 77.0 132.0 57.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:80.50 stddev:20.34 (25.3%) stddev-sans-first:21.38 23:55:18 INFO - PROCESS | 1673 | Values: 87.0 94.0 134.0 79.0 82.0 81.0 117.0 77.0 80.0 117.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:88.50 stddev:19.07 (21.5%) stddev-sans-first:20.00 23:55:18 INFO - PROCESS | 1673 | Values: 89.0 86.0 89.0 87.0 88.0 85.0 90.0 90.0 135.0 131.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 23:55:18 INFO - PROCESS | 1673 | Values: 93.0 93.0 93.0 93.0 93.0 93.0 93.0 93.0 93.0 93.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:86.00 stddev:18.69 (21.7%) stddev-sans-first:16.59 23:55:18 INFO - PROCESS | 1673 | Values: 125.0 135.0 100.0 86.0 85.0 86.0 86.0 86.0 86.0 84.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:4.72 (8.1%) stddev-sans-first:5.00 23:55:18 INFO - PROCESS | 1673 | Values: 57.0 58.0 57.0 57.0 58.0 58.0 59.0 68.0 50.0 52.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:66.50 stddev:4.36 (6.6%) stddev-sans-first:4.21 23:55:18 INFO - PROCESS | 1673 | Values: 62.0 62.0 68.0 77.0 71.0 67.0 66.0 66.0 66.0 66.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:81.00 stddev:1.07 (1.3%) stddev-sans-first:1.12 23:55:18 INFO - PROCESS | 1673 | Values: 80.0 80.0 81.0 81.0 81.0 80.0 79.0 80.0 81.0 83.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:125.00 stddev:8.28 (6.6%) stddev-sans-first:8.75 23:55:18 INFO - PROCESS | 1673 | Values: 122.0 131.0 119.0 130.0 118.0 117.0 117.0 128.0 141.0 116.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:129.10 Median:132.50 stddev:5.97 (4.5%) stddev-sans-first:6.16 23:55:18 INFO - PROCESS | 1673 | Values: 133.0 120.0 132.0 131.0 133.0 119.0 134.0 134.0 132.0 123.0 23:55:18 INFO - PROCESS | 1673 | 23:55:18 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:1.03 (1.6%) stddev-sans-first:1.09 23:55:18 INFO - PROCESS | 1673 | Values: 66.0 65.0 66.0 68.0 65.0 66.0 65.0 67.0 67.0 67.0 23:55:18 INFO - PROCESS | 1673 | -------- Summary: end -------- 23:55:18 INFO - PROCESS | 1673 | 23:55:19 INFO - TEST-INFO | 1673: exit 0 23:55:19 INFO - TEST-OK | kraken | took 56247ms 23:55:19 INFO - SUITE-END | took 1169s 23:55:19 INFO - Completed test suite (00:19:30) 23:55:19 INFO - javascript benchmark 23:55:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3472.4096903526406, 3525.048236976018, 3530.2939412117585, 3559.3949028665133, 3604.0629436346544, 4757.26262429324, 5799.014167591509, 5821.825345239639, 5822.912656310159, 5863.384344643815, 4863.465018847924, 5030.396352437712, 5788.942110578893, 5795.188673585691, 5800.796972105977, 4259.467566554181, 5190.740462976836, 5255.999999999981, 5285.127953887613, 5308.177232528945, 5308.0710875596615, 5309.654872433299, 5310.70791106489, 5310.8141215057285, 5321.920171197435, 5275.683458992473, 5296.735163241822, 5309.283246761695, 5325.733713314338, 5335.493128152819, 5283.154695248761, 5337.4662533746705, 5342.2253773202865, 5353.0, 5370.060239458079, 5104.132297509433, 5147.0992576298995, 5265.710385928773, 5272.736363181825, 5273.288106105665, 5680.034394153004, 5688.5733569982, 5736.4550367715, 5738.081906894919, 5765.654060756368, 6169.876602467925, 6303.621782693053, 6331.493480521547, 6340.746370145189, 6362.968185159091, 6201.565890387675, 6247.344028876993, 6261.092141639482, 6303.810885673392, 6315.936840631581, 5941.22764040672, 5971.790987315423, 5982.641041537521, 5992.550558708114, 6001.819945401645, 6189.0, 6278.748850045947, 6297.779577714849, 6384.074309225136, 6403.64779937108, 6136.325004249529, 6144.9553575892205, 6168.321484636686, 6284.151639528605, 6313.43179113882, 6289.433950944437, 6291.276503202191, 6292.2134733158355, 6302.747890084345, 6334.556581039283, 6252.0, 6255.56211065221, 6296.181496405438, 6313.305536390993, 6317.084022816666, 6200.224971878515, 6274.749010039639, 6309.621422714652, 6311.463525600376, 6312.7790527332245, 4908.337374454403, 4967.254911763264, 4981.103401387712, 5007.7245751484015, 5019.52314530119, 4447.822087116544, 4455.955440445554, 4469.150861336336, 4476.328550717353, 4498.865034048984, 5682.119271512922, 5770.769169233184, 5794.710264486759, 5830.941690583039, 5890.793822216202, 6192.0, 6265.185525881606, 6270.0908368287255, 6274.498040156868, 6287.9056814147825, 5834.533237341003, 5839.240898683229, 5854.8536286592325, 5860.765569377178, 5884.0585506318785, 5336.226247194135, 5445.782168713286, 5446.183072539151, 5462.399136095027, 5467.9453205468235, 6407.7436902524305, 6502.057201705727, 6512.804615861532, 6512.804615861532, 6543.018547217765, 6223.3465486125115, 6231.750729970931, 6262.34245404226, 6279.748810047547, 6281.811545653638, 6294.8426289341305, 6363.6499992499, 6388.744450222125, 6442.194725659293, 6465.709043093166, 6208.255009398901, 6213.2233470816145, 6230.376962303914, 6242.282137554058, 6245.03202003672, 5642.125470552071, 6270.529710271839, 6290.2766181889665, 6292.811215663537, 6309.652969086745, 6053.727582258897, 6135.44703110649, 6200.193974783249, 6201.906971395342, 6231.688415579293, 5054.747262636927, 6109.070667812992, 6204.5967012144065, 6212.502999760099, 6224.315325314303, 6089.117078023573, 6110.358412366638, 6120.326764056039, 6122.785702500391, 6132.509399248139, 4996.750162491788, 6164.599301045418, 6173.074038894202, 6193.256809182927, 6202.472789812917, 5999.670018149044, 6075.210222671025, 6077.088436734525, 6082.300535438304, 6083.7870674526175], "unit": "score", "name": "dojo.html", "value": 5750.138152799699}, {"lowerIsBetter": false, "replicates": [485.5581420906975, 551.4705882352941, 586.6919867069788, 612.6171143035602, 661.4311691944927, 27263.727362726368, 32254.0, 32280.67719322806, 32337.67662323376, 32424.02727918166, 41352.58647413525, 41830.58169418305, 41904.37143442843, 41931.58068419315, 41951.79024104883, 9617.663381781622, 9861.852072218922, 9869.358491698053, 9876.30865839388, 9884.406935583853, 9717.708468745914, 9828.459434731087, 9841.212702983745, 9844.06481384271, 9873.308868379216, 9799.0, 9885.901140988606, 9890.307678462474, 9901.554430050664, 9904.306698531105, 9708.223342132613, 9775.0, 9794.90205097947, 9848.06443387877, 9848.60605575776, 9741.0, 9890.109890109852, 9930.155936745385, 10039.0, 10058.89941100587, 42634.934126646775, 43058.0, 43072.13855722868, 43100.35349469761, 43151.78424107891, 2872.353720412911, 3331.1505566080496, 3502.702270307027, 3519.454484554898, 3801.733878628497, 29546.26134346637, 29602.25994350137, 29634.407311853858, 29930.95241666553, 30110.54834177489, 2779.4163225722623, 2784.3595972926137, 2819.6616406031203, 2825.0818483992784, 2826.7314605112483, 466270.0, 577064.0, 583315.0, 584395.0780246115, 584545.0, 71224.28775712177, 71407.64296178486, 71859.0, 72014.27985720181, 72219.63890180516, 72369.27630723626, 72655.0, 72916.0, 80031.59984200042, 80062.59968700235, 18219.362322318655, 18406.447806565826, 18430.815691843178, 18440.446786596425, 18487.260509579733, 13989.30053497321, 14062.718745625234, 14077.225752583707, 14095.859041409454, 14108.647283817987, 22926.08295668188, 23339.0, 23342.883285583463, 23359.0, 23373.065077396714, 8979.685711000215, 9306.86039709405, 9320.766980825534, 9321.72034838956, 9338.95330523343, 1007.5012868630075, 1038.4288641247283, 1052.8789189243182, 1126.4367816091954, 1158.9014933730728, 23870.0, 30239.395212095635, 30394.696053039628, 30777.538336924965, 30986.845065774527, 915.6566287642135, 1159.7100724818795, 1165.3532289579196, 1167.486306025346, 1205.8552973643043, 11236.213465057368, 15265.313060912285, 15355.846441535665, 15536.611584710472, 15596.98619589723, 548379.0, 553241.0, 559559.0, 559887.0, 560431.0, 63273.0, 64169.679151603945, 64209.67895160494, 64212.0, 64266.35733642604, 1410.189141243789, 1538.8076490438696, 1559.3216950626513, 1588.4599236259728, 1589.674116806075, 27341.94405196397, 28920.7107928918, 29614.703852961196, 30208.244793880327, 30342.54486182665, 51419.228711570104, 51826.999999998494, 51835.0, 51937.22094168515, 52073.7396313016, 377150.0, 381731.18268818065, 383963.0, 384297.0, 384398.0, 29990.70009299879, 35051.29897402089, 47314.52685473239, 48651.540453786445, 49232.507674922796, 47298.527014729414, 47402.52597474119, 47513.762431189, 49002.0, 49393.50606493889, 37490.62509374871, 38309.0, 38406.61593384142, 38790.80604596959, 39399.40900886546, 37816.81091594634, 38275.617243827204, 39183.82448526549, 39604.20791584095, 40265.194696105325, 39790.60209397985, 39923.0, 40263.79868100641, 40290.597094028686, 40578.0, 34319.656803431644, 35179.824100879334, 35790.64209357873, 35901.92294231177, 36152.27695446129, 24723.25830225096, 26561.937522498883, 26596.468070638093, 26657.0, 27161.0], "unit": "score", "name": "ext.html", "value": 20781.373447269914}, {"lowerIsBetter": false, "replicates": [370.91654377764996, 371.47065431759734, 377.3057574063724, 381.22230649475057, 385.6540673051053, 20398.69401958972, 21143.365699029033, 21838.672419913713, 22150.113995440162, 22178.667319990174, 19859.900700496513, 19978.90010549945, 20670.863102529354, 20785.79214207861, 20867.478313042142, 5245.86885327866, 5433.592480563953, 5480.0, 5484.561235101182, 5509.862253443656, 4037.2934736421157, 4104.302268614343, 6758.763443279487, 6766.492513061516, 6774.695138718769, 6524.249711283216, 6622.072909792633, 6684.46524278059, 6712.0, 6726.562773419737, 6419.871602567946, 6645.667716614198, 6653.866922661568, 6661.433778128865, 6666.300038495937, 4006.3790112532606, 5496.5053145216725, 5736.827895163152, 5750.511206547449, 5750.856228594277, 13296.335183240799, 13620.387082581308, 13735.519256825963, 13749.587512374645, 13872.4451021959, 2250.5161390301164, 2641.762241398264, 2828.490871643103, 2842.1899758568707, 2947.4694554980097, 10096.596136154545, 10336.121429678486, 10494.790104197873, 10506.0, 14275.357608907623, 1749.7637818894414, 1793.1392931393004, 1795.9910200449044, 1800.333876465716, 1822.1891258390021, 88823.55588222083, 97639.53540696895, 98148.0185198146, 103322.0, 106411.46794265979, 11365.659030229106, 11382.601608943813, 11487.483063262172, 11517.25137866036, 11671.649850504498, 14595.85404145945, 14673.559793206221, 14684.0, 14692.926535367254, 14703.264836758119, 7741.845163096819, 8505.511535481266, 8983.640654373883, 8989.41568798039, 9064.728058158265, 7342.48602597813, 9219.907800921905, 9228.584713687766, 9234.261259099256, 9263.166315031545, 4570.588647021717, 4586.174488592085, 4607.9078418431445, 4627.005193883331, 4674.649401294849, 1923.7018262169386, 1975.3580086471954, 1980.8019198080076, 1982.3953694123143, 1993.2226431691652, 883.3639779358851, 963.3401120232663, 992.3500107429645, 994.8308787506141, 1015.7044290240783, 18561.35035273759, 20500.487487812625, 20592.27927022577, 20779.584408311748, 20837.583248334948, 1017.3387298256192, 1023.902729240721, 1029.6396261308632, 1037.6938803053117, 1122.03083588302, 9337.972822989466, 10616.097631701237, 10970.616028438966, 11043.226974111734, 11055.557777688802, 624030.0, 656857.0, 660983.6950815215, 690539.0, 692931.0, 102813.0, 110344.4482777581, 117518.41240793912, 120248.0, 120512.0, 1059.496739048961, 1097.401915955806, 1121.058311018748, 1122.550979608147, 1123.039800970175, 17607.079186412488, 17636.559086022953, 17904.641907162044, 18252.26990920348, 18503.629927401107, 50145.0, 51092.2933190205, 51560.74219628878, 53076.73461632821, 53195.13817016386, 71799.0, 73699.0, 74069.0, 74648.62675686803, 74909.50180996458, 7778.92221077782, 8091.676332946617, 8130.878036829334, 8380.994280686356, 8650.0, 12492.687682808002, 13156.947444204632, 13451.663708407368, 13576.86423135756, 13855.23796191179, 11341.546338146381, 11772.352520611106, 11890.881091188978, 11972.58095966637, 12068.034557235225, 11623.302601843916, 11752.412379381169, 11897.583584574844, 11909.106816988588, 12073.154879158375, 8852.778680533038, 11913.940430297793, 11966.521339146337, 11973.339324496299, 12113.030957523553, 8236.629351679308, 8258.669653213805, 8297.917020829715, 8421.07368189511, 8431.0, 8685.261752751087, 11145.665630031112, 12022.939885300517, 12098.516059357878, 12356.0], "unit": "score", "name": "jquery.html", "value": 9579.497987716806}, {"lowerIsBetter": false, "replicates": [1387.3964825300995, 1454.3092031285134, 1618.401191559123, 1861.3299212283584, 2037.9184832606686, 8121.51270923745, 8733.519656418903, 8902.376833621649, 8913.286937045044, 8930.508822014786, 8589.226969572737, 8866.733997980054, 8880.511571863544, 8887.20015198632, 8904.732858014253, 2507.0849140063883, 2545.414554652431, 2546.3888666719945, 2547.350425641459, 2547.3886267295807, 2437.914672986446, 2536.3912660961328, 2537.086648806428, 2537.644729737838, 2539.0351666366755, 2314.710661167354, 2343.6641114564704, 2534.1637259704253, 2547.414094758207, 2554.5274124286975, 2521.0924067335745, 2542.5423423783714, 2550.795936325099, 2555.09294200558, 2557.1689201009744, 2115.8942052897446, 2514.2582938033324, 2551.094361501677, 2555.514452254066, 2556.642070110186, 8880.955595222047, 9011.549422528913, 9071.63713451461, 9091.545422728903, 9105.317101217435, 5496.780128794843, 5738.397468265814, 5739.598228124033, 5794.362620111827, 5807.709614519299, 3640.9635903641156, 3684.6315368463206, 3744.6817020553285, 3789.3368660484307, 3816.9427458588143, 1073.366713638949, 1092.8524649172418, 1094.895984881435, 1105.1766434006636, 1125.0493333133488, 12303.95416389599, 12316.692082698004, 12320.445579949013, 12327.013838892866, 12349.691257718629, 4828.8792780180775, 4910.926336104962, 4972.701637901665, 4991.326170966873, 4996.100701873697, 10395.94802025985, 10427.895721042843, 10430.269881108401, 10439.530221140067, 10443.268971172098, 5541.5843811714285, 5597.580181486404, 5600.915986260209, 5602.943970560324, 5608.383077861432, 4467.687261891701, 4575.611073058761, 4632.953670463319, 4633.328167415706, 5012.974935125374, 3370.443876760356, 3970.3845903884944, 4010.4184893190322, 4080.3471444568727, 4092.202020606013, 1348.4875747216192, 1357.429879450633, 1358.5245164192454, 1360.8162898008643, 1368.1244003837553, 1378.7242551489742, 1401.4184113592949, 1417.3165605920128, 1424.579748974055, 1427.8215223097113, 8412.453190542717, 8520.318374530023, 8520.977482702116, 8526.445781024213, 8526.488410695378, 1058.6982709927788, 1113.5267511307661, 1219.591436868659, 1223.975520489603, 1237.8019516877257, 6339.936600633934, 6374.490040796725, 6435.613863168225, 6439.58142720731, 6440.033994900708, 9486.0, 10713.0, 10956.52086968265, 11162.665120046411, 11189.0, 9751.951240243754, 10625.628103016512, 10980.780384392267, 11040.613578524713, 11066.0, 7239.493235473572, 8422.705205317907, 8537.317014638815, 8554.401191916506, 8612.65549378018, 7969.481983671043, 8110.797230069295, 8174.386920980831, 8193.95903020505, 8212.712555060543, 9582.760430989281, 9875.901240987498, 9889.406635601887, 9918.156956658764, 9983.251256155963, 9946.552405141641, 10071.992800719869, 10257.076863082359, 10264.384136951807, 10280.794384112425, 6705.765298214539, 7600.239976002355, 7615.581143037186, 7647.579383133759, 7719.498232614862, 7380.77857664271, 7627.733029343946, 7673.53958762476, 7690.0, 7698.653560589899, 7163.534370265916, 7330.157031941395, 7341.4126869849215, 7423.1834498206235, 7444.255574442513, 7163.534370266125, 7243.746468873775, 7275.417966562768, 7331.230220826949, 7388.519746216479, 7566.470347075652, 7636.08366995964, 7710.190430004993, 7750.147483776667, 7761.883571746541, 7011.3339232772805, 7022.40309573692, 7033.9296607033275, 7035.788926332219, 7042.084529011196, 7103.467239957127, 7142.0, 7146.606936618536, 7176.677049532679, 7182.569045857265], "unit": "score", "name": "mootools.html", "value": 5038.352870689918}, {"lowerIsBetter": false, "replicates": [334.4297971957812, 337.809137837122, 340.83639852870635, 345.5404312264688, 346.4197469239024, 6534.934650653492, 6543.05125756765, 6594.340565943403, 6604.372584604461, 6615.966920165399, 6491.188601424822, 6491.902621460682, 6503.902441463382, 6514.837129071776, 6522.739090436388, 4623.352730617717, 4760.523947605247, 4793.08931303053, 4879.756012199394, 4880.8047678092835, 3999.9400008999887, 4832.395950506187, 4861.902761944759, 4880.7071575705395, 4908.779104940269, 4814.975925120387, 4826.879328016793, 4827.56551910328, 4828.299896515004, 4831.227003679412, 4550.38569793077, 4677.50886156952, 4793.5925446337105, 4825.131476334259, 4833.564979151858, 4162.146759914211, 4162.604552567501, 4167.729097608646, 4181.393697913816, 4196.0, 6234.750609975596, 6247.406496382836, 6277.121203031579, 6281.7173227204885, 6286.182796236506, 4646.279826626878, 5799.3620701722775, 5805.390434004453, 5816.069428891357, 5816.505597024258, 6734.023566582867, 6980.825479363006, 6982.441404687613, 6986.161660600709, 7014.894776578355, 4722.456917454468, 4737.881552961204, 4752.025834703912, 4757.333973243749, 4770.045990801819, 6012.218411606463, 7332.230115837813, 7380.225076366958, 7388.741394051182, 7419.0, 4809.495003024632, 4819.204831202812, 4823.2282834746275, 4824.517548245148, 4827.758612069382, 4892.143874821892, 4894.902101957941, 4895.0, 4904.2643603459055, 4913.950860491421, 5771.769129234784, 5788.0, 5796.62321949072, 5801.622894511843, 5812.157237200582, 6767.560108593024, 7024.1571011478945, 7025.89461158083, 7044.15470143586, 7071.681774320168, 1682.1337011439118, 1693.3226709316373, 1702.880798344104, 1703.1399143432734, 1703.557075160467, 996.8454889492141, 1031.4481752262504, 1053.6513263023342, 1067.3969207397795, 1101.5483651702923, 6368.172137622079, 6486.318936511599, 6564.6389448580785, 6571.572847764975, 6581.901271480931, 7215.639218039077, 7235.2764723527225, 7238.52949558277, 7252.0, 7253.927460725431, 6530.085787989685, 6531.791618550488, 6549.541532092803, 6556.0, 6563.146790917151, 3680.871169509054, 3779.4708740776314, 3817.408301713239, 3819.694424446038, 3832.7317087803585, 7558.319751222416, 7563.621818909032, 7598.8100297493, 7614.809629759189, 7634.236576342432, 7408.99978502906, 7494.5503269803985, 7500.0, 7503.09962804467, 7512.887306690404, 7574.5076570022775, 7575.280348366897, 7599.658015389321, 7601.505902116234, 7615.504992175491, 7023.122109736283, 7233.132024157135, 7279.417646588367, 7279.8544029120185, 7289.343959043712, 7111.715531378687, 7168.211496735459, 7178.102737157856, 7180.030695855992, 7193.424526038009, 6407.679616019087, 7357.337839594462, 7565.2812982766545, 7570.356519695888, 7571.242875712384, 3995.2409042281874, 4832.009438065258, 4854.878628034327, 4856.587190088882, 4860.562549370574, 6972.477064220102, 7230.783076507713, 7248.782536523912, 7253.56478611285, 7257.383122434652, 7031.964840175767, 7070.611116388648, 7084.326988936043, 7089.964550177217, 7103.0, 5036.521530454747, 7031.050808140835, 7040.401565866959, 7052.647367631495, 7068.646567671493, 6848.589084654936, 6898.310168983062, 6898.310168983262, 6899.862002760017, 6904.102466679299, 6729.158855143107, 6956.373926346653, 7022.367986881205, 7033.0, 7043.506954513135, 5714.7998920225555, 6554.63949482783, 6824.18109826824, 6868.038474613658, 6952.721891124501], "unit": "score", "name": "prototype.html", "value": 5148.1277279639}, {"lowerIsBetter": false, "replicates": [733.9009233753443, 773.3697036914914, 781.8003234351589, 792.3700657976908, 795.9403044771643, 15010.0, 15067.773983390245, 15278.46525371611, 15285.235738213101, 15320.92753507255, 14506.419743210285, 14547.92726036371, 14557.708845823103, 14606.853931460682, 14607.634809129777, 822.9670813167467, 831.1812864328631, 985.1232403161192, 1008.3848852200152, 1014.2595904989362, 1019.7858449725567, 1024.4826362686833, 1025.2925481417835, 1027.3733022856088, 1028.6245520385044, 1068.4230515521608, 1076.445630500293, 1077.2136340471461, 1077.3589714120087, 1077.9407132607744, 846.6951897316961, 1054.2251445187742, 1066.2056767708068, 1089.7874914391696, 1090.983635245472, 786.8662327404297, 791.2364568191665, 791.6002418778537, 792.374024520628, 793.7499687598388, 15221.695566088727, 15287.312070956723, 15305.693886122215, 15312.387504499806, 15320.004199726982, 12485.75028499434, 12602.495900164075, 12697.682557936034, 12900.419481123372, 12909.67725806853, 10084.0, 10158.933312002282, 10188.235882308782, 10294.845577316346, 10382.221333400024, 3554.0581745837376, 3611.963880361215, 3630.473581330719, 3713.4615480755137, 3722.9627703722886, 86986.0, 87266.25467490614, 87334.5633271836, 88002.5599872003, 89989.55005224998, 9019.819603608023, 9326.626934922662, 9968.501574921225, 9995.70012899614, 10455.790884182426, 23549.175778848, 23747.287581372588, 24039.27882163538, 24047.398815029763, 24134.517309653707, 7628.466007379541, 7714.537127772352, 7817.257360550739, 8005.279524842792, 8010.759677209693, 4055.513338399411, 4066.2883995300704, 4072.5927407259037, 4074.2869997750868, 4076.470058892325, 3782.413725872494, 3832.80835958201, 3836.3478208704587, 3840.9423858642144, 3846.538415390115, 674.652553934724, 689.1076056506836, 690.5994523176546, 696.951213415056, 718.1561665043657, 545.38916413617, 556.1463154058581, 597.7071235094772, 611.782817099932, 621.1924498152384, 7854.803629909184, 7989.760307190793, 8008.359331253486, 8159.46963447374, 8224.300934420522, 549.6180154792461, 596.7076132694948, 604.9939500605026, 609.1624016976657, 609.3175643279556, 3777.4985540198295, 3806.8096595170687, 3881.6312450317173, 3895.2209558088493, 3896.6103389660807, 98958.0, 100199.49900250598, 101265.0, 101588.98411015944, 101818.0, 82288.0, 83411.58294208611, 84361.73457398143, 85101.0, 88070.0, 72361.6381918087, 74512.0, 74829.25170748332, 74893.0, 75948.62025689836, 7686.154523002577, 7847.607619619111, 7890.408219383455, 7900.446968712365, 7921.762347129365, 47690.52309476861, 48669.026619466706, 48805.0, 51362.48637513577, 51923.221151682, 66904.0, 68098.65950670216, 68576.6571167141, 69327.0, 69815.30184698291, 10221.642242521477, 10478.161747060369, 10516.369017858648, 10634.680959571226, 10821.242513024014, 10381.27331086847, 10628.096611787776, 10826.971437713404, 10827.783444330911, 11142.331460112418, 8776.551868941553, 10154.796904062025, 10212.693619191436, 10427.217958653284, 10435.269531132748, 10097.646582369582, 10126.645567405105, 10371.377717336984, 10377.740556486147, 10512.42181680015, 10125.64560240388, 10185.949070254897, 10294.794104118026, 10992.835107473235, 11297.322160670386, 9434.528273586431, 9546.379485333431, 9619.04525235462, 9741.853872191781, 9857.605695772376, 9588.904110958802, 9631.133198012212, 9762.267829912644, 9772.413655180711, 9797.951010245188], "unit": "score", "name": "yui.html", "value": 7073.806761338086}], "name": "dromaeo_css", "value": 7709.987652071071}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 108.0, 109.0, 111.0, 112.0, 112.0, 118.0, 109.0, 109.0, 110.0], "unit": "score", "name": "ai-astar", "value": 110.9}, {"lowerIsBetter": true, "replicates": [153.0, 120.0, 152.0, 148.0, 118.0, 138.0, 118.0, 148.0, 151.0, 114.0], "unit": "score", "name": "audio-beat-detection", "value": 136.0}, {"lowerIsBetter": true, "replicates": [183.0, 184.0, 184.0, 184.0, 184.0, 182.0, 199.0, 265.0, 205.0, 182.0], "unit": "score", "name": "audio-dft", "value": 195.2}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 75.0, 113.0, 55.0, 56.0, 60.0, 77.0, 132.0, 57.0], "unit": "score", "name": "audio-fft", "value": 77.0}, {"lowerIsBetter": true, "replicates": [87.0, 94.0, 134.0, 79.0, 82.0, 81.0, 117.0, 77.0, 80.0, 117.0], "unit": "score", "name": "audio-oscillator", "value": 94.8}, {"lowerIsBetter": true, "replicates": [89.0, 86.0, 89.0, 87.0, 88.0, 85.0, 90.0, 90.0, 135.0, 131.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 97.0}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 93.0}, {"lowerIsBetter": true, "replicates": [125.0, 135.0, 100.0, 86.0, 85.0, 86.0, 86.0, 86.0, 86.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [57.0, 58.0, 57.0, 57.0, 58.0, 58.0, 59.0, 68.0, 50.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 68.0, 77.0, 71.0, 67.0, 66.0, 66.0, 66.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.1}, {"lowerIsBetter": true, "replicates": [80.0, 80.0, 81.0, 81.0, 81.0, 80.0, 79.0, 80.0, 81.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.6}, {"lowerIsBetter": true, "replicates": [122.0, 131.0, 119.0, 130.0, 118.0, 117.0, 117.0, 128.0, 141.0, 116.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.9}, {"lowerIsBetter": true, "replicates": [133.0, 120.0, 132.0, 131.0, 133.0, 119.0, 134.0, 134.0, 132.0, 123.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 129.1}, {"lowerIsBetter": true, "replicates": [66.0, 65.0, 66.0, 68.0, 65.0, 66.0, 65.0, 67.0, 67.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.2}], "name": "kraken", "value": 1424.1}]} 23:55:19 INFO - Return code: 0 23:55:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:55:19 INFO - # TBPL SUCCESS # 23:55:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:55:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:55:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:55:19 INFO - 2016-04-20 23:55:19.689 screenresolution[1677:10110] starting screenresolution argv=screenresolution get 23:55:19 INFO - 2016-04-20 23:55:19.692 screenresolution[1677:10110] Display 0: 1600x1200x32@60 23:55:19 INFO - 2016-04-20 23:55:19.698 screenresolution[1678:10113] starting screenresolution argv=screenresolution list 23:55:19 INFO - Available Modes on Display 0 23:55:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:55:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:55:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:55:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:55:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:55:19 INFO - Intel Iris: 23:55:19 INFO - Chipset Model: Intel Iris 23:55:19 INFO - Type: GPU 23:55:19 INFO - Bus: Built-In 23:55:19 INFO - VRAM (Dynamic, Max): 1536 MB 23:55:19 INFO - Vendor: Intel (0x8086) 23:55:19 INFO - Device ID: 0x0a2e 23:55:19 INFO - Revision ID: 0x0009 23:55:19 INFO - Displays: 23:55:19 INFO - Display: 23:55:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:55:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:55:19 INFO - Display Serial Number: 1600x1200 60 23:55:19 INFO - Main Display: Yes 23:55:19 INFO - Mirror: Off 23:55:19 INFO - Online: Yes 23:55:19 INFO - Rotation: Supported 23:55:19 INFO - Return code: 0 23:55:19 INFO - Running post-action listener: _resource_record_post_action 23:55:19 INFO - Running post-run listener: _resource_record_post_run 23:55:20 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 32587264; Write bytes: 663785472; Read time: 321; Write time: 1781 23:55:20 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 353550848; Write bytes: 354043392; Read time: 28062; Write time: 627 23:55:20 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 31653376; Write bytes: 299399680; Read time: 307; Write time: 1120 23:55:21 INFO - Running post-run listener: _upload_blobber_files 23:55:21 INFO - Blob upload gear active. 23:55:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:55:21 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 23:55:21 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'] 23:55:21 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 23:55:21 INFO - (blobuploader) - INFO - Open directory for files ... 23:55:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:55:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:55:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:55:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:55:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:55:22 INFO - (blobuploader) - INFO - Done attempting. 23:55:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:55:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:55:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:55:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:55:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:55:23 INFO - (blobuploader) - INFO - Done attempting. 23:55:23 INFO - (blobuploader) - INFO - Iteration through files over. 23:55:23 INFO - Return code: 0 23:55:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:55:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:55:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ec37b84d636696abcb7b9404646a247cb0ff20b6dd94dfa563527a3dfeef41c1b12cafece8e95645eb4350350428d5ac50788d012fd2e5660e85057bb9e3c44", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10ecf8d2b4f1af30fd60b773c233cdd0ffba1a5d6d08289c9f2914cc3fbcbb1560c28fdd373d55db4a3b68cddb0844195b72f6b0931f49be3f3418a1ca6ea1fc"} 23:55:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:55:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:55:23 INFO - Contents: 23:55:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ec37b84d636696abcb7b9404646a247cb0ff20b6dd94dfa563527a3dfeef41c1b12cafece8e95645eb4350350428d5ac50788d012fd2e5660e85057bb9e3c44", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10ecf8d2b4f1af30fd60b773c233cdd0ffba1a5d6d08289c9f2914cc3fbcbb1560c28fdd373d55db4a3b68cddb0844195b72f6b0931f49be3f3418a1ca6ea1fc"} 23:55:23 INFO - Running post-run listener: copy_logs_to_upload_dir 23:55:23 INFO - Copying logs to upload dir... 23:55:23 INFO - mkdir: /builds/slave/test/build/upload/logs 23:55:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.725142 ========= master_lag: 4.54 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-20 23:55:27.298318) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-04-20 23:55:27.303021) ========= 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.bFuOKb7EDS/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.wfAP4OK62k/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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/5ec37b84d636696abcb7b9404646a247cb0ff20b6dd94dfa563527a3dfeef41c1b12cafece8e95645eb4350350428d5ac50788d012fd2e5660e85057bb9e3c44", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10ecf8d2b4f1af30fd60b773c233cdd0ffba1a5d6d08289c9f2914cc3fbcbb1560c28fdd373d55db4a3b68cddb0844195b72f6b0931f49be3f3418a1ca6ea1fc"} build_url:https://queue.taskcluster.net/v1/task/V65yKZxOQ92AsFPy9kc9fA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010901 build_url: 'https://queue.taskcluster.net/v1/task/V65yKZxOQ92AsFPy9kc9fA/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/5ec37b84d636696abcb7b9404646a247cb0ff20b6dd94dfa563527a3dfeef41c1b12cafece8e95645eb4350350428d5ac50788d012fd2e5660e85057bb9e3c44", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10ecf8d2b4f1af30fd60b773c233cdd0ffba1a5d6d08289c9f2914cc3fbcbb1560c28fdd373d55db4a3b68cddb0844195b72f6b0931f49be3f3418a1ca6ea1fc"}' ========= master_lag: 7.00 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-04-20 23:55:34.315118) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-20 23:55:34.315414) ========= 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.bFuOKb7EDS/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.wfAP4OK62k/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005136 ========= master_lag: 3.71 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-20 23:55:38.031189) ========= ========= Started reboot skipped (results: 3, elapsed: 8 secs) (at 2016-04-20 23:55:38.031456) ========= ========= Finished reboot skipped (results: 3, elapsed: 8 secs) (at 2016-04-20 23:55:46.278902) ========= ========= Total master_lag: 26.18 =========