builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0375 starttime: 1467830072.07 results: success (0) buildid: 20160706092723 builduid: 936cb087f11646afbd1246200aa27a2c revision: 4e0a4baa176e8e560883a1baef88e710837bd9c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 11:34:32.065653) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 11:34:32.066122) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 11:34:32.066410) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 11:34:32.948349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-07-06 11:34:32.948680) ========= 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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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-07-06 11:34:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.9M=0.001s 2016-07-06 11:34:33 (17.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103994 ========= master_lag: 3.70 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-07-06 11:34:36.748064) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 11:34:36.748395) ========= 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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.026828 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 11:34:36.797179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 11:34:36.797520) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4e0a4baa176e8e560883a1baef88e710837bd9c7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 4e0a4baa176e8e560883a1baef88e710837bd9c7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aXxJRVbqcB/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.y9w9RgoFQh/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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-07-06 11:34:36,877 truncating revision to first 12 chars 2016-07-06 11:34:36,877 Setting DEBUG logging. 2016-07-06 11:34:36,877 attempt 1/10 2016-07-06 11:34:36,877 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4e0a4baa176e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 11:34:38,233 unpacking tar archive at: autoland-4e0a4baa176e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.628891 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 11:34:38.447667) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 11:34:38.447986) ========= script_repo_revision: 4e0a4baa176e8e560883a1baef88e710837bd9c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 11:34:38.448397) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 11:34:38.448658) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 11:34:38.466710) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-06 11:34:38.467020) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aXxJRVbqcB/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.y9w9RgoFQh/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:34:38 INFO - MultiFileLogger online at 20160706 11:34:38 in /builds/slave/test 11:34:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 11:34:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:34:38 INFO - {'append_to_log': False, 11:34:38 INFO - 'base_work_dir': '/builds/slave/test', 11:34:38 INFO - 'blob_upload_branch': 'Autoland', 11:34:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:34:38 INFO - 'branch': 'Autoland', 11:34:38 INFO - 'buildbot_json_path': 'buildprops.json', 11:34:38 INFO - 'config_files': ('talos/mac_config.py',), 11:34:38 INFO - 'default_actions': ('clobber', 11:34:38 INFO - 'read-buildbot-config', 11:34:38 INFO - 'download-and-extract', 11:34:38 INFO - 'populate-webroot', 11:34:38 INFO - 'create-virtualenv', 11:34:38 INFO - 'install', 11:34:38 INFO - 'run-tests'), 11:34:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:34:38 INFO - 'download_minidump_stackwalk': True, 11:34:38 INFO - 'download_symbols': 'ondemand', 11:34:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:34:38 INFO - 'tooltool.py': '/tools/tooltool.py', 11:34:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:34:38 INFO - '/tools/misc-python/virtualenv.py')}, 11:34:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:34:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:34:38 INFO - 'installer_path': 'installer.exe', 11:34:38 INFO - 'log_level': 'info', 11:34:38 INFO - 'log_name': 'talos', 11:34:38 INFO - 'log_to_console': True, 11:34:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:34:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:34:38 INFO - 'opt_config_files': (), 11:34:38 INFO - 'pip_index': False, 11:34:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:34:38 INFO - 'cmd': ('bash', 11:34:38 INFO - '-c', 11:34:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:34:38 INFO - 'enabled': True, 11:34:38 INFO - 'halt_on_failure': False, 11:34:38 INFO - 'name': 'check_screen_resolution'},), 11:34:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:34:38 INFO - 'cmd': ('bash', 11:34:38 INFO - '-c', 11:34:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:34:38 INFO - 'enabled': True, 11:34:38 INFO - 'halt_on_failure': False, 11:34:38 INFO - 'name': 'check_screen_resolution'},), 11:34:38 INFO - 'run_cmd_checks_enabled': True, 11:34:38 INFO - 'sps_profile': False, 11:34:38 INFO - 'sps_profile_interval': 0, 11:34:38 INFO - 'suite': 'dromaeojs', 11:34:38 INFO - 'system_bits': '32', 11:34:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:34:38 INFO - 'title': 't-yosemite-r7-0375', 11:34:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:34:38 INFO - 'use_talos_json': True, 11:34:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:34:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:34:38 INFO - 'work_dir': 'build'} 11:34:38 INFO - ##### 11:34:38 INFO - ##### Running clobber step. 11:34:38 INFO - ##### 11:34:38 INFO - Running pre-action listener: _resource_record_pre_action 11:34:38 INFO - Running main action method: clobber 11:34:38 INFO - rmtree: /builds/slave/test/build 11:34:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:34:40 INFO - Running post-action listener: _resource_record_post_action 11:34:40 INFO - ##### 11:34:40 INFO - ##### Finished clobber step (success) 11:34:40 INFO - ##### 11:34:40 INFO - ##### 11:34:40 INFO - ##### Running read-buildbot-config step. 11:34:40 INFO - ##### 11:34:40 INFO - Running pre-action listener: _resource_record_pre_action 11:34:40 INFO - Running main action method: read_buildbot_config 11:34:40 INFO - Using buildbot properties: 11:34:40 INFO - { 11:34:40 INFO - "project": "", 11:34:40 INFO - "product": "firefox", 11:34:40 INFO - "script_repo_revision": "production", 11:34:40 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 11:34:40 INFO - "repository": "", 11:34:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 11:34:40 INFO - "buildid": "20160706092723", 11:34:40 INFO - "pgo_build": "False", 11:34:40 INFO - "basedir": "/builds/slave/test", 11:34:40 INFO - "buildnumber": 20, 11:34:40 INFO - "slavename": "t-yosemite-r7-0375", 11:34:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:34:40 INFO - "platform": "yosemite_r7", 11:34:40 INFO - "scheduler": "tests-autoland-macosx64-talos", 11:34:40 INFO - "branch": "autoland", 11:34:40 INFO - "repo_path": "integration/autoland", 11:34:40 INFO - "revision": "4e0a4baa176e8e560883a1baef88e710837bd9c7", 11:34:40 INFO - "stage_platform": "macosx64", 11:34:40 INFO - "builduid": "936cb087f11646afbd1246200aa27a2c", 11:34:40 INFO - "slavebuilddir": "test" 11:34:40 INFO - } 11:34:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 11:34:40 INFO - Running post-action listener: _resource_record_post_action 11:34:40 INFO - ##### 11:34:40 INFO - ##### Finished read-buildbot-config step (success) 11:34:40 INFO - ##### 11:34:40 INFO - ##### 11:34:40 INFO - ##### Running download-and-extract step. 11:34:40 INFO - ##### 11:34:40 INFO - Running pre-action listener: _resource_record_pre_action 11:34:40 INFO - Running main action method: download_and_extract 11:34:40 INFO - mkdir: /builds/slave/test/build/tests 11:34:40 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')]} 11:34:40 INFO - https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:34:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:34:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:34:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:34:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 11:34:42 INFO - Downloaded 1199 bytes. 11:34:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:34:42 INFO - Using the following test package requirements: 11:34:42 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:34:42 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:34:42 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 11:34:42 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:34:42 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 11:34:42 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:34:42 INFO - u'jsshell-mac.zip'], 11:34:42 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:34:42 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 11:34:42 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:34:42 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:34:42 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 11:34:42 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:34:42 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 11:34:42 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:34:42 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 11:34:42 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:34:42 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 11:34:42 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:34:42 INFO - https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:34:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:34:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:34:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:34:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:34:43 INFO - Downloaded 18804793 bytes. 11:34:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:34:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:34:46 INFO - Return code: 0 11:34:46 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:34:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:34:46 INFO - https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:34:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:34:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:34:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:34:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:34:47 INFO - Downloaded 18804793 bytes. 11:34:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:34:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:34:51 INFO - Return code: 0 11:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:34:51 INFO - https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:34:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:34:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:34:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:34:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:34:52 INFO - Downloaded 14403333 bytes. 11:34:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:34:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:34:52 INFO - Return code: 0 11: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')]} 11:34:52 INFO - https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:34:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:34:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:34:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 11:34:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 11:34:55 INFO - Downloaded 112406045 bytes. 11:34:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:34:55 INFO - mkdir: /builds/slave/test/properties 11:34:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:34:55 INFO - Writing to file /builds/slave/test/properties/build_url 11:34:55 INFO - Contents: 11:34:55 INFO - build_url:https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:34:56 INFO - Running post-action listener: _resource_record_post_action 11:34:56 INFO - Running post-action listener: set_extra_try_arguments 11:34:56 INFO - ##### 11:34:56 INFO - ##### Finished download-and-extract step (success) 11:34:56 INFO - ##### 11:34:56 INFO - ##### 11:34:56 INFO - ##### Running populate-webroot step. 11:34:56 INFO - ##### 11:34:56 INFO - Running pre-action listener: _resource_record_pre_action 11:34:56 INFO - Running main action method: populate_webroot 11:34:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:34:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:34:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:34:56 INFO - '--tppagecycles', 11:34:56 INFO - '7'], 11:34:56 INFO - 'tests': ['tsvgm']}}, 11:34:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:34:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:34:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:34:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:34:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:34:56 INFO - 'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:34:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:34:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:34:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:34:56 INFO - 'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['damp', 'tps']}, 11:34:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:34:56 INFO - 'tests': ['damp', 'tps']}, 11:34:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['dromaeo_dom']}, 11:34:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:34:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['basic_compositor_video']}, 11:34:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:34:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['a11yr', 11:34:56 INFO - 'ts_paint', 11:34:56 INFO - 'tpaint', 11:34:56 INFO - 'sessionrestore', 11:34:56 INFO - 'sessionrestore_no_auto_restore', 11:34:56 INFO - 'tabpaint']}, 11:34:56 INFO - 'other-e10s': {'tests': ['a11yr', 11:34:56 INFO - 'ts_paint', 11:34:56 INFO - 'tpaint', 11:34:56 INFO - 'sessionrestore', 11:34:56 INFO - 'sessionrestore_no_auto_restore', 11:34:56 INFO - 'tabpaint']}, 11:34:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:34:56 INFO - 'ts_paint', 11:34:56 INFO - 'tpaint', 11:34:56 INFO - 'sessionrestore', 11:34:56 INFO - 'sessionrestore_no_auto_restore', 11:34:56 INFO - 'tabpaint']}, 11:34:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:34:56 INFO - 'ts_paint', 11:34:56 INFO - 'tpaint', 11:34:56 INFO - 'sessionrestore', 11:34:56 INFO - 'sessionrestore_no_auto_restore', 11:34:56 INFO - 'tabpaint']}, 11:34:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['a11yr', 11:34:56 INFO - 'ts_paint', 11:34:56 INFO - 'tpaint', 11:34:56 INFO - 'sessionrestore', 11:34:56 INFO - 'sessionrestore_no_auto_restore', 11:34:56 INFO - 'tabpaint']}, 11:34:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['a11yr', 11:34:56 INFO - 'ts_paint', 11:34:56 INFO - 'tpaint', 11:34:56 INFO - 'sessionrestore', 11:34:56 INFO - 'sessionrestore_no_auto_restore', 11:34:56 INFO - 'tabpaint']}, 11:34:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['tsvgx', 11:34:56 INFO - 'tsvgr_opacity', 11:34:56 INFO - 'tart', 11:34:56 INFO - 'tscrollx', 11:34:56 INFO - 'cart']}, 11:34:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:34:56 INFO - 'tsvgr_opacity', 11:34:56 INFO - 'tart', 11:34:56 INFO - 'tscrollx', 11:34:56 INFO - 'cart']}, 11:34:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:34:56 INFO - 'talos_options': ['--disable-e10s'], 11:34:56 INFO - 'tests': ['tp5o']}, 11:34:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:34:56 INFO - 'tests': ['tp5o']}, 11:34:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:34:56 INFO - 'talos_options': ['--disable-e10s', 11:34:56 INFO - '--xperf_path', 11:34:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:34:56 INFO - 'tests': ['tp5n']}, 11:34:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:34:56 INFO - 'talos_options': ['--xperf_path', 11:34:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:34:56 INFO - 'tests': ['tp5n']}}, 11:34:56 INFO - 'talos.zip': {'path': '', 11:34:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:34:56 INFO - Running post-action listener: _resource_record_post_action 11:34:56 INFO - ##### 11:34:56 INFO - ##### Finished populate-webroot step (success) 11:34:56 INFO - ##### 11:34:56 INFO - ##### 11:34:56 INFO - ##### Running create-virtualenv step. 11:34:56 INFO - ##### 11:34:56 INFO - Running pre-action listener: _resource_record_pre_action 11:34:56 INFO - Running main action method: create_virtualenv 11:34:56 INFO - Creating virtualenv /builds/slave/test/build/venv 11:34:56 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 11:34:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:34:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:34:56 INFO - Using real prefix '/tools/python27' 11:34:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:34:57 INFO - Installing distribute.............................................................................................................................................................................................done. 11:35:00 INFO - Installing pip.................done. 11:35:00 INFO - Return code: 0 11:35:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:35:00 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')]} 11:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:00 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')]} 11:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:00 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 0x1092c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc02a4a1ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10922cd30>, '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 0x108f91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109224b20>, '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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:35:00 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 11:35:00 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 11:35:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aXxJRVbqcB/Render', 11:35:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:00 INFO - 'HOME': '/Users/cltbld', 11:35:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:00 INFO - 'LOGNAME': 'cltbld', 11:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:00 INFO - 'MOZ_NO_REMOTE': '1', 11:35:00 INFO - 'NO_EM_RESTART': '1', 11:35:00 INFO - 'PAGER': '/bin/cat', 11:35:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:00 INFO - 'PWD': '/builds/slave/test', 11:35:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:00 INFO - 'SHELL': '/bin/bash', 11:35:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y9w9RgoFQh/Listeners', 11:35:00 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:35:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:00 INFO - 'USER': 'cltbld', 11:35:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:00 INFO - 'XPC_FLAGS': '0x0', 11:35:00 INFO - 'XPC_SERVICE_NAME': '0', 11:35:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:00 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 11:35:00 INFO - Cleaning up... 11:35:00 INFO - Return code: 0 11:35:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:35:00 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')]} 11:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:00 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')]} 11:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1092c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc02a4a1ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10922cd30>, '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 0x108f91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109224b20>, '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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:35:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:35:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aXxJRVbqcB/Render', 11:35:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:00 INFO - 'HOME': '/Users/cltbld', 11:35:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:00 INFO - 'LOGNAME': 'cltbld', 11:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:00 INFO - 'MOZ_NO_REMOTE': '1', 11:35:00 INFO - 'NO_EM_RESTART': '1', 11:35:00 INFO - 'PAGER': '/bin/cat', 11:35:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:00 INFO - 'PWD': '/builds/slave/test', 11:35:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:00 INFO - 'SHELL': '/bin/bash', 11:35:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y9w9RgoFQh/Listeners', 11:35:00 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:35:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:00 INFO - 'USER': 'cltbld', 11:35:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:00 INFO - 'XPC_FLAGS': '0x0', 11:35:00 INFO - 'XPC_SERVICE_NAME': '0', 11:35:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:00 INFO - Downloading/unpacking psutil>=3.1.1 11:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:35:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:35:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:35:03 INFO - Installing collected packages: psutil 11:35:03 INFO - Running setup.py install for psutil 11:35:03 INFO - building 'psutil._psutil_osx' extension 11:35:03 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 11:35:04 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 11:35:04 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 11:35:04 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 11:35:04 INFO - building 'psutil._psutil_posix' extension 11:35:04 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 11:35:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:35:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:35:04 INFO - ^ 11:35:04 INFO - 1 warning generated. 11:35:04 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 11:35:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:35:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:35:04 INFO - Successfully installed psutil 11:35:04 INFO - Cleaning up... 11:35:04 INFO - Return code: 0 11:35:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1092c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc02a4a1ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10922cd30>, '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 0x108f91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109224b20>, '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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:35:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:35:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aXxJRVbqcB/Render', 11:35:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:04 INFO - 'HOME': '/Users/cltbld', 11:35:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:04 INFO - 'LOGNAME': 'cltbld', 11:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:04 INFO - 'MOZ_NO_REMOTE': '1', 11:35:04 INFO - 'NO_EM_RESTART': '1', 11:35:04 INFO - 'PAGER': '/bin/cat', 11:35:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:04 INFO - 'PWD': '/builds/slave/test', 11:35:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:04 INFO - 'SHELL': '/bin/bash', 11:35:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y9w9RgoFQh/Listeners', 11:35:04 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:35:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:04 INFO - 'USER': 'cltbld', 11:35:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:04 INFO - 'XPC_FLAGS': '0x0', 11:35:04 INFO - 'XPC_SERVICE_NAME': '0', 11:35:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:35:07 INFO - Installing collected packages: mozsystemmonitor 11:35:07 INFO - Running setup.py install for mozsystemmonitor 11:35:07 INFO - Successfully installed mozsystemmonitor 11:35:07 INFO - Cleaning up... 11:35:07 INFO - Return code: 0 11:35:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11: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')]} 11:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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')]} 11:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1092c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc02a4a1ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10922cd30>, '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 0x108f91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109224b20>, '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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11: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==2.5.1'] in /builds/slave/test/build 11: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==2.5.1 11:35:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aXxJRVbqcB/Render', 11:35:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:07 INFO - 'HOME': '/Users/cltbld', 11:35:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:07 INFO - 'LOGNAME': 'cltbld', 11:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:07 INFO - 'MOZ_NO_REMOTE': '1', 11:35:07 INFO - 'NO_EM_RESTART': '1', 11:35:07 INFO - 'PAGER': '/bin/cat', 11:35:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:07 INFO - 'PWD': '/builds/slave/test', 11:35:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:07 INFO - 'SHELL': '/bin/bash', 11:35:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y9w9RgoFQh/Listeners', 11:35:07 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:35:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:07 INFO - 'USER': 'cltbld', 11:35:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:07 INFO - 'XPC_FLAGS': '0x0', 11:35:07 INFO - 'XPC_SERVICE_NAME': '0', 11:35:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:08 INFO - Downloading/unpacking jsonschema==2.5.1 11:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:35:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:35:11 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 11:35:11 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 11:35:11 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 11:35:11 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 11:35:11 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 11:35:11 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 11:35:12 INFO - Downloading functools32-3.2.3-2.tar.gz 11:35:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:35:12 INFO - warning: no files found matching '*.txt' 11:35:12 INFO - no previously-included directories found matching 'build' 11:35:12 INFO - no previously-included directories found matching 'dist' 11:35:12 INFO - no previously-included directories found matching '.git*' 11:35:12 INFO - Installing collected packages: jsonschema, functools32 11:35:12 INFO - Running setup.py install for functools32 11:35:12 INFO - warning: no files found matching '*.txt' 11:35:12 INFO - no previously-included directories found matching 'build' 11:35:12 INFO - no previously-included directories found matching 'dist' 11:35:12 INFO - no previously-included directories found matching '.git*' 11:35:12 INFO - Successfully installed jsonschema functools32 11:35:12 INFO - Cleaning up... 11:35:12 INFO - Return code: 0 11:35:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1092c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc02a4a1ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10922cd30>, '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 0x108f91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109224b20>, '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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:35:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:35:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aXxJRVbqcB/Render', 11:35:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:12 INFO - 'HOME': '/Users/cltbld', 11:35:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:12 INFO - 'LOGNAME': 'cltbld', 11:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:12 INFO - 'MOZ_NO_REMOTE': '1', 11:35:12 INFO - 'NO_EM_RESTART': '1', 11:35:12 INFO - 'PAGER': '/bin/cat', 11:35:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:12 INFO - 'PWD': '/builds/slave/test', 11:35:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:12 INFO - 'SHELL': '/bin/bash', 11:35:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y9w9RgoFQh/Listeners', 11:35:12 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:35:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:12 INFO - 'USER': 'cltbld', 11:35:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:12 INFO - 'XPC_FLAGS': '0x0', 11:35:12 INFO - 'XPC_SERVICE_NAME': '0', 11:35:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:12 INFO - Downloading/unpacking blobuploader==1.2.4 11:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:15 INFO - Downloading blobuploader-1.2.4.tar.gz 11:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:35:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:35:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:16 INFO - Downloading docopt-0.6.1.tar.gz 11:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:35:16 INFO - Installing collected packages: blobuploader, requests, docopt 11:35:16 INFO - Running setup.py install for blobuploader 11:35:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:35:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:35:16 INFO - Running setup.py install for requests 11:35:17 INFO - Running setup.py install for docopt 11:35:17 INFO - Successfully installed blobuploader requests docopt 11:35:17 INFO - Cleaning up... 11:35:17 INFO - Return code: 0 11:35:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:35:17 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')]} 11:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:17 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')]} 11:35:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:17 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 0x1092c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc02a4a1ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10922cd30>, '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 0x108f91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109224b20>, '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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:35:17 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 11:35:17 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 11:35:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aXxJRVbqcB/Render', 11:35:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:17 INFO - 'HOME': '/Users/cltbld', 11:35:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:17 INFO - 'LOGNAME': 'cltbld', 11:35:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:17 INFO - 'MOZ_NO_REMOTE': '1', 11:35:17 INFO - 'NO_EM_RESTART': '1', 11:35:17 INFO - 'PAGER': '/bin/cat', 11:35:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:17 INFO - 'PWD': '/builds/slave/test', 11:35:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:17 INFO - 'SHELL': '/bin/bash', 11:35:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y9w9RgoFQh/Listeners', 11:35:17 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:35:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:17 INFO - 'USER': 'cltbld', 11:35:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:17 INFO - 'XPC_FLAGS': '0x0', 11:35:17 INFO - 'XPC_SERVICE_NAME': '0', 11:35:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:35:17 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-TftfgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:35:17 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-l6IM6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:35:17 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-uk0ICV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:35:17 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-9YaRQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:35:18 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-nAYwzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:35:18 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-1Er5My-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:35:18 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-LrxID0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:35:18 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-v2Q6x4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:35:18 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-rdvMzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:35:18 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-KKlXKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:35:18 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-TqEJJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:35:18 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-XQzSY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:35:18 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Ai2JUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:35:18 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-N8RCNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:35:19 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-USH0XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:35:19 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-oqYa1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:35:19 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-h9SLO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:35:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:35:19 INFO - Running setup.py install for manifestparser 11:35:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:35:19 INFO - Running setup.py install for mozcrash 11:35:19 INFO - Running setup.py install for mozdebug 11:35:19 INFO - Running setup.py install for mozdevice 11:35:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:35:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:35:20 INFO - Running setup.py install for mozfile 11:35:20 INFO - Running setup.py install for mozhttpd 11:35:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:35:20 INFO - Running setup.py install for mozinfo 11:35:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:35:20 INFO - Running setup.py install for mozInstall 11:35:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:35:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:35:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:35:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:35:20 INFO - Running setup.py install for mozleak 11:35:20 INFO - Running setup.py install for mozlog 11:35:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:35:20 INFO - Running setup.py install for moznetwork 11:35:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:35:21 INFO - Running setup.py install for mozprocess 11:35:21 INFO - Running setup.py install for mozprofile 11:35:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:35:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:35:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:35:21 INFO - Running setup.py install for mozrunner 11:35:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:35:21 INFO - Running setup.py install for mozscreenshot 11:35:21 INFO - Running setup.py install for moztest 11:35:21 INFO - Running setup.py install for mozversion 11:35:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:35:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:35:21 INFO - Cleaning up... 11:35:21 INFO - Return code: 0 11:35:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:35:21 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')]} 11:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:21 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')]} 11:35:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:21 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 0x1092c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc02a4a1ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10922cd30>, '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 0x108f91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109224b20>, '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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:35:21 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 11:35:21 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 11:35:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aXxJRVbqcB/Render', 11:35:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:21 INFO - 'HOME': '/Users/cltbld', 11:35:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:21 INFO - 'LOGNAME': 'cltbld', 11:35:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:21 INFO - 'MOZ_NO_REMOTE': '1', 11:35:21 INFO - 'NO_EM_RESTART': '1', 11:35:21 INFO - 'PAGER': '/bin/cat', 11:35:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:21 INFO - 'PWD': '/builds/slave/test', 11:35:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:21 INFO - 'SHELL': '/bin/bash', 11:35:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y9w9RgoFQh/Listeners', 11:35:21 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:35:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:21 INFO - 'USER': 'cltbld', 11:35:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:21 INFO - 'XPC_FLAGS': '0x0', 11:35:21 INFO - 'XPC_SERVICE_NAME': '0', 11:35:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:35:22 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-F1gEHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:35:22 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)) 11:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:35:22 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-b1c2PE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:35:22 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)) 11:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:35:22 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-OmARFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:35:22 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)) 11:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:35:22 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-KHYZJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:35:22 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)) 11:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:35:22 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-RDjGgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:35:22 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)) 11:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:35:22 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-7jruTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:35:22 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)) 11:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:35:22 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ouolcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:35:22 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)) 11:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:35:22 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-H23NNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:35:23 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)) 11:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:35:23 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-tX2Fpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:35:23 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)) 11:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:35:23 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-i0bNlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:35:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:35:23 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-fODQGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:35:23 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)) 11:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:35:23 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-SBt7BV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:35:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 11:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:35:23 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-BJYGMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:35:23 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)) 11:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:35:23 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ekiduH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:35:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 11:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:35:23 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-gKRMlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:35:23 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)) 11:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:35:23 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Rdl12Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:35:23 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)) 11:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:35:23 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-uOF8IN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:35:24 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)) 11:35:24 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)) 11:35:24 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)) 11:35:24 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)) 11:35:24 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)) 11:35:24 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)) 11:35:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:35:24 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 11:35:24 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 11:35:24 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 11:35:24 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 11:35:24 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 11:35:24 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 11:35:26 INFO - Downloading blessings-1.6.tar.gz 11:35:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:35:26 INFO - Installing collected packages: blessings 11:35:26 INFO - Running setup.py install for blessings 11:35:26 INFO - Successfully installed blessings 11:35:26 INFO - Cleaning up... 11:35:26 INFO - Return code: 0 11:35:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:35:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:35:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:35:27 INFO - Reading from file tmpfile_stdout 11:35:27 INFO - Current package versions: 11:35:27 INFO - blessings == 1.6 11:35:27 INFO - blobuploader == 1.2.4 11:35:27 INFO - docopt == 0.6.1 11:35:27 INFO - functools32 == 3.2.3-2 11:35:27 INFO - jsonschema == 2.5.1 11:35:27 INFO - manifestparser == 1.1 11:35:27 INFO - mozInstall == 1.12 11:35:27 INFO - mozcrash == 0.17 11:35:27 INFO - mozdebug == 0.1 11:35:27 INFO - mozdevice == 0.48 11:35:27 INFO - mozfile == 1.2 11:35:27 INFO - mozhttpd == 0.7 11:35:27 INFO - mozinfo == 0.9 11:35:27 INFO - mozleak == 0.1 11:35:27 INFO - mozlog == 3.2 11:35:27 INFO - moznetwork == 0.27 11:35:27 INFO - mozprocess == 0.23 11:35:27 INFO - mozprofile == 0.28 11:35:27 INFO - mozrunner == 6.12 11:35:27 INFO - mozscreenshot == 0.1 11:35:27 INFO - mozsystemmonitor == 0.3 11:35:27 INFO - moztest == 0.7 11:35:27 INFO - mozversion == 1.4 11:35:27 INFO - psutil == 3.1.1 11:35:27 INFO - requests == 1.2.3 11:35:27 INFO - wsgiref == 0.1.2 11:35:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:27 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 0x1092c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc02a4a1ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10922cd30>, '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 0x108f91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109224b20>, '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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:35:27 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 11:35:27 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 11:35:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aXxJRVbqcB/Render', 11:35:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:27 INFO - 'HOME': '/Users/cltbld', 11:35:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:27 INFO - 'LOGNAME': 'cltbld', 11:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:27 INFO - 'MOZ_NO_REMOTE': '1', 11:35:27 INFO - 'NO_EM_RESTART': '1', 11:35:27 INFO - 'PAGER': '/bin/cat', 11:35:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:27 INFO - 'PWD': '/builds/slave/test', 11:35:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:27 INFO - 'SHELL': '/bin/bash', 11:35:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y9w9RgoFQh/Listeners', 11:35:27 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:35:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:27 INFO - 'USER': 'cltbld', 11:35:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:27 INFO - 'XPC_FLAGS': '0x0', 11:35:27 INFO - 'XPC_SERVICE_NAME': '0', 11:35:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:27 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)) 11:35:27 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)) 11:35:27 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)) 11:35:27 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)) 11:35:27 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)) 11:35:27 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)) 11:35:27 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)) 11:35:27 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)) 11:35:27 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)) 11:35:27 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)) 11:35:27 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)) 11:35:27 INFO - Cleaning up... 11:35:27 INFO - Return code: 0 11:35:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:27 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 0x1092c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc02a4a1ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10922cd30>, '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 0x108f91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109224b20>, '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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:35:27 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 11:35:27 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 11:35:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aXxJRVbqcB/Render', 11:35:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:27 INFO - 'HOME': '/Users/cltbld', 11:35:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:27 INFO - 'LOGNAME': 'cltbld', 11:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:27 INFO - 'MOZ_NO_REMOTE': '1', 11:35:27 INFO - 'NO_EM_RESTART': '1', 11:35:27 INFO - 'PAGER': '/bin/cat', 11:35:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:27 INFO - 'PWD': '/builds/slave/test', 11:35:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:27 INFO - 'SHELL': '/bin/bash', 11:35:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y9w9RgoFQh/Listeners', 11:35:27 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:35:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:27 INFO - 'USER': 'cltbld', 11:35:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:27 INFO - 'XPC_FLAGS': '0x0', 11:35:27 INFO - 'XPC_SERVICE_NAME': '0', 11:35:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:35:28 INFO - Cleaning up... 11:35:28 INFO - Return code: 0 11:35:28 INFO - Running post-action listener: _resource_record_post_action 11:35:28 INFO - Running post-action listener: _start_resource_monitoring 11:35:28 INFO - Starting resource monitoring. 11:35:28 INFO - ##### 11:35:28 INFO - ##### Finished create-virtualenv step (success) 11:35:28 INFO - ##### 11:35:28 INFO - ##### 11:35:28 INFO - ##### Running install step. 11:35:28 INFO - ##### 11:35:28 INFO - Running pre-action listener: _resource_record_pre_action 11:35:28 INFO - Running main action method: install 11:35:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:35:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:35:28 INFO - Reading from file tmpfile_stdout 11:35:28 INFO - Detecting whether we're running mozinstall >=1.0... 11:35:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:35:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:35:28 INFO - Reading from file tmpfile_stdout 11:35:28 INFO - Output received: 11:35:28 INFO - Usage: mozinstall [options] installer 11:35:28 INFO - Options: 11:35:28 INFO - -h, --help show this help message and exit 11:35:28 INFO - -d DEST, --destination=DEST 11:35:28 INFO - Directory to install application into. [default: 11:35:28 INFO - "/builds/slave/test"] 11:35:28 INFO - --app=APP Application being installed. [default: firefox] 11:35:28 INFO - mkdir: /builds/slave/test/build/application 11:35:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:35:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:36:02 INFO - Reading from file tmpfile_stdout 11:36:02 INFO - Output received: 11:36:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:36:02 INFO - Running post-action listener: _resource_record_post_action 11:36:02 INFO - ##### 11:36:02 INFO - ##### Finished install step (success) 11:36:02 INFO - ##### 11:36:02 INFO - ##### 11:36:02 INFO - ##### Running run-tests step. 11:36:02 INFO - ##### 11:36:02 INFO - Running pre-action listener: _resource_record_pre_action 11:36:02 INFO - Running main action method: run_tests 11:36:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:36:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:36:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:36:02 INFO - 2016-07-06 11:36:02.792 screenresolution[1638:7437] starting screenresolution argv=screenresolution get 11:36:02 INFO - 2016-07-06 11:36:02.794 screenresolution[1638:7437] Display 0: 1600x1200x32@60 11:36:02 INFO - 2016-07-06 11:36:02.801 screenresolution[1639:7439] starting screenresolution argv=screenresolution list 11:36:02 INFO - Available Modes on Display 0 11:36:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:36:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:36:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:36:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:36:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:36:03 INFO - Intel Iris: 11:36:03 INFO - Chipset Model: Intel Iris 11:36:03 INFO - Type: GPU 11:36:03 INFO - Bus: Built-In 11:36:03 INFO - VRAM (Dynamic, Max): 1536 MB 11:36:03 INFO - Vendor: Intel (0x8086) 11:36:03 INFO - Device ID: 0x0a2e 11:36:03 INFO - Revision ID: 0x0009 11:36:03 INFO - Displays: 11:36:03 INFO - Display: 11:36:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:36:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:36:03 INFO - Display Serial Number: 1600x1200 60 11:36:03 INFO - Main Display: Yes 11:36:03 INFO - Mirror: Off 11:36:03 INFO - Online: Yes 11:36:03 INFO - Rotation: Supported 11:36:03 INFO - Return code: 0 11:36:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:36:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:36:03 INFO - Python 2.7.3 11:36:03 INFO - Return code: 0 11:36:03 INFO - grabbing minidump binary from tooltool 11:36:03 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')]} 11:36:03 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10922cd30>, '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 0x108f91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109224b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:36:03 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 11:36:03 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 11:36:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:36:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:36:03 INFO - Return code: 0 11:36:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:36:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:36:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:36:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:36:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:36:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 11:36:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0375 --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 11:36:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aXxJRVbqcB/Render', 11:36:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:03 INFO - 'HOME': '/Users/cltbld', 11:36:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:03 INFO - 'LOGNAME': 'cltbld', 11:36:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:36:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:36:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:03 INFO - 'MOZ_NO_REMOTE': '1', 11:36:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:36:03 INFO - 'NO_EM_RESTART': '1', 11:36:03 INFO - 'PAGER': '/bin/cat', 11:36:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:03 INFO - 'PWD': '/builds/slave/test', 11:36:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:36:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:03 INFO - 'SHELL': '/bin/bash', 11:36:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y9w9RgoFQh/Listeners', 11:36:03 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:36:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:03 INFO - 'USER': 'cltbld', 11:36:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:03 INFO - 'XPC_FLAGS': '0x0', 11:36:03 INFO - 'XPC_SERVICE_NAME': '0', 11:36:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 11:36:03 INFO - mozversion application_buildid: 20160706092723 11:36:03 INFO - mozversion application_changeset: 4e0a4baa176e8e560883a1baef88e710837bd9c7 11:36:03 INFO - mozversion application_display_name: Nightly 11:36:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:36:03 INFO - mozversion application_name: Firefox 11:36:03 INFO - mozversion application_remotingname: firefox 11:36:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:36:03 INFO - mozversion application_vendor: Mozilla 11:36:03 INFO - mozversion application_version: 50.0a1 11:36:03 INFO - mozversion platform_buildid: 20160706092723 11:36:03 INFO - mozversion platform_changeset: 4e0a4baa176e8e560883a1baef88e710837bd9c7 11:36:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:36:03 INFO - mozversion platform_version: 50.0a1 11:36:03 INFO - using testdate: 1467830163 11:36:03 INFO - actual date: 1467830163 11:36:03 INFO - starting webserver on 'localhost:49274' 11:36:03 INFO - SUITE-START | Running 2 tests 11:36:03 INFO - TEST-START | dromaeo_css 11:36:03 INFO - operating with platform_type : mac_ 11:36:03 INFO - Initialising browser for dromaeo_css test... 11:36:03 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpJigauE/profile http://localhost:49274/getInfo.html) 11:36:05 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 11:36:05 INFO - PROCESS | 1649 | colorDepth:24 11:36:05 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 11:36:05 INFO - PROCESS | 1649 | __metrics 11:36:05 INFO - PROCESS | 1649 | 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" 11:36:05 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:36:06 INFO - TEST-INFO | 1649: exit 0 11:36:06 INFO - Browser initialized. 11:36:06 INFO - Running cycle 1/1 for dromaeo_css test... 11:36:06 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpJigauE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:36:18 INFO - PROCESS | 1651 | RSS: Main: 243961856 11:36:18 INFO - PROCESS | 1651 | 11:37:10 INFO - PROCESS | 1651 | 1467830230212 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] 11:39:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 11:39:22 INFO - PROCESS | 1651 | RSS: Main: 377454592 11:39:22 INFO - PROCESS | 1651 | 11:42:25 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 11:42:26 INFO - PROCESS | 1651 | RSS: Main: 385880064 11:42:26 INFO - PROCESS | 1651 | 11:45:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 11:45:29 INFO - PROCESS | 1651 | RSS: Main: 373641216 11:45:29 INFO - PROCESS | 1651 | 11:48:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 11:48:32 INFO - PROCESS | 1651 | RSS: Main: 373968896 11:48:32 INFO - PROCESS | 1651 | 11:51:35 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 11:51:36 INFO - PROCESS | 1651 | RSS: Main: 345604096 11:51:36 INFO - PROCESS | 1651 | 11:54:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 11:54:39 INFO - PROCESS | 1651 | RSS: Main: 435732480 11:54:39 INFO - PROCESS | 1651 | 11:54:39 INFO - PROCESS | 1651 | __start_tp_report 11:54:39 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 11:54:39 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 11:54:39 INFO - PROCESS | 1651 | |i|pagename|runs| 11:54:39 INFO - PROCESS | 1651 | |0;dojo.html;3268.82021488818;3317.2536179359645;3327.8003319800805;3350.045237107425;3387.4918762185653;5624.606277560572;5670.404607516213;5684.37471878093;5807.361190269073;5823.097419899912;5700.344460387057;5749.683767392792;5753.223314852494;5753.913691294634;5771.961047011536;4285.957140428587;5141.974290128554;5180.3006594109875;5212.087884620177;5274.4461831507715;4690.319903613974;4928.531789479994;5146.794128234885;5203.245529398235;5253.448387919251;5189.766460509267;5198.090334191501;5228.111221092405;5230.555402790748;5244.160934250521;5288.894222115556;5297.231901374279;5302.655327403706;5310.575153987672;5350.678959262418;5120.539151476348;5124.590032797368;5150.253213284052;5195.792168313263;5235.450277720861;5689.487946084831;5700.885982280372;5741.081426971665;5781.364049954502;5858.648481091148;6301.842453938642;6312.210973628296;6324.7470101195895;6333.113364129025;6344.968275158641;5896.410358964112;6194;6254.343293954114;6299.6850157491945;6324.7470101195895;5782.421757824226;5814.418558144152;5817.389174136697;5854.531637468992;5860.472557469848;6278.560500764903;6295.496360291166;6297.149884765589;6303.149074874925;6304.432601065926;6013.338532761481;6229.190205273376;6231.657258850807;6238.1266622672865;6240.251169859555;6219.973704338733;6285.151504546919;6288.779892703734;6312.021636646327;6347.809565713036;6265.091561723524;6294.9685251574365;6295.087212354183;6303.369663033752;6328.588641738273;6118.694065296718;6127.754889804358;6165.383461653799;6279.277883043508;6338.873222535523;4369.650427965755;4797.232442809134;4913.1893237615395;4921.384826896638;4938.308636790852;4385.7149285296355;4462.933056004163;4465.665075119379;4472.172648060119;4480.4623445186135;5760.164776107441;5771.076627739541;5772.480476757112;5772.740226689809;5773.24947756789;5586.050371436865;5934.6142500737315;6214.161088253119;6238.594491358047;6281.120643109968;5863.589548731547;5870.53035757147;5870.970645146747;5877.9118313225335;5880.558958078162;5362.678239305654;5395.406505284415;5413.485718856702;5465;5472.179173123984;6214.782482613177;6264.467520260738;6276.184096067482;6283.151774510383;6625.966870165618;6312.337204593451;6323.272823625158;6327.17746692927;6329.303776584664;6333.1766870306565;6335.968320158369;6345.873082538231;6349.142865713255;6352.491800655844;6375.968120159369;5957.523398128226;6097.282711138064;6121.788327166137;6185;6236.532260080603;6161;6172.629642221481;6175.166352542349;6177.413145751147;6244.65654389013;6141.07883817431;6164.291106522807;6173.536984726074;6214.2853571838;6230.626162430269;5854.824355269348;5973.940260597338;6014.338422773404;6015.72929218195;6103.7253323601435;5898.4101589842385;5935.940640593539;6046.244219472566;6080.290116415274;6086.7789383501795;6175.011998080286;6178.783742568989;6181.690915454119;6190.380961903954;6210.5652604317265;5494.203340515683;5590.4626227787685;5796.449337312949;5932.2584676915385;5967.492763115184 11:54:39 INFO - PROCESS | 1651 | |1;ext.html;445.467666138964;504.08256972310363;537.7284471341969;540.9864753381165;555.744357595506;29667.813287468503;30588.235294117658;31968.840155799247;32042.67957320426;41248.968775780624;34031.65968340316;44535;44566.331505027454;44583.777081114546;44685.32972005422;9703.175230105431;9854.704358869245;9857.457839818808;9866.45734484603;9890;8712.869306960401;8904.955475222614;9656.179224765903;9760.804783904317;11796.941015294911;11462.312261264351;11781.234219775688;11794.174407791457;11795.587154449597;11806.645800625953;10305.330153540071;11943.34311612861;12001.819972700416;12080.275183488931;12083.275003499773;8625.353098517573;11061.612843550438;11784.88215117855;11808.468618912084;12136.029117670654;41845.581544184475;42246.57753422457;42282;42342.57657423417;42376.57623423788;3577.928441431157;3632.4188129899353;3726.31063253299;3772.2455508898056;3918.666913312372;32080.03759887207;32106.83946580252;32361.838190809132;32891;33189;3278.3115545735423;3316.303576248991;3325.7173140283103;3326.8835590754206;3401.9319613607836;475870;479128;479147;481520;482235.5888220572;58833.41166588365;59140.40859591349;59487.70256148691;59569.702151488964;59631.80736385248;32711.182220444203;32977.83511082477;32994.67005329964;33029.50455743166;33079.50380744291;17089.23098460572;17216.31134754621;17249.56876078108;17263.3957811476;17279.222434990457;11940.98501627354;13006.414711338013;13102.279374634487;13143.671408214872;13159.802602960963;17002.06488643112;19448.611027779647;19531.773904318106;19655.508612284808;20073.698894516594;7451.552906825608;8129.715459958873;8203.876941845878;8246.463979841292;8246.752597422086;863.7605036772252;897.4660077254024;1009.5507499162802;1014.5383850348074;1032.5611615063567;30448.54327185094;30745;30984.53523197154;31482.527762083588;31551;1045.1586472889246;1121.0751130317358;1133.2633788037842;1156.6356597671706;1246.3705828556522;16733.665326693397;16751.246193921303;16845.83154168467;17244.396446124327;17354.392596259324;715844;719466;720474;721075;721486;59734;61732.76534469285;61750.69124654348;61759.691201543705;61915.452113697516;1285.929273889945;1320;1321.062045947388;1432.7421064208334;1474.2997076388629;25220.74779252184;26135;31710.68289317077;31716.731330746512;31882;51609.74195129;51843.96312073813;52304.73847630737;52423;52623.47376526186;559641;563909;564417;565684;570430;25955.96176152933;26503.734962650127;26838.329041774112;26864.194074177805;26890.731092688824;47887.04225915532;48365.27452088292;48886.26670600015;49009;49180.77048073827;39761.80119099386;39894.40158397685;40322.39516407315;40741;41006.17987640171;35634.93095207148;38986.80506597449;39937.800310999424;40246.19507609773;41067.383989239584;38598.842034739006;39128.21743565056;39208.411873821344;39472.802635987784;39491.605083948794;28983.420331592828;30803.691963080084;31324.06027819169;33101.834490827394;33498.832505837316;23555.764442355357;25359.239222823344;25360.74639253584;25804.612930805677;26029.609555857056 11:54:39 INFO - PROCESS | 1651 | |2;jquery.html;355.2539666699929;358.8439029022376;385.458430904579;386.48597365503883;404.76321352009074;15965.521034368958;16364.59088522786;21276.89361553194;21309.36071917841;21322.89338553307;20792.168313267488;21181;21558.245461408853;21755.34733957984;21790.45523861904;5164.328637277149;5762.884742305162;5953.1070339449;5990.640561566298;6004.609700369484;4147;4384.627306678936;5874.1776151338845;6346.365363463663;6650.102236198124;6311.337309582496;6637.46900247979;6639.701213445383;6644.93355066448;6658.76694315699;6501.869962600746;6550.9344906550805;6563.967180164069;6582.80251592453;6590.769323073718;3628.782273063598;5370.919436208421;5682;5684.857878553028;5723.370429252779;26141.477170456485;26170.6074408884;26185.73814261871;26842.731572684414;27193.728062719318;2205.514786746929;2211.7124773779465;2214.247155966979;2246.539459410817;2295.494991101955;15441.5367538974;15528;15801.762973555406;15823.287952042187;16327.18364081803;1614;1900.8764430311985;1911.8279354857991;1918.2422942937462;1990.601879624081;119360;122023.77976220213;122604.77395226022;123009.38495307555;124486;10559.577616895393;10784.784304314027;11008.064314533354;11035.503402346752;11176.217664763552;13732.107413018122;13797.310134493235;13825.308734563232;13826.101303415448;13839.723205536035;7365.079365079365;8639.265662418766;8848.203661670352;8856.38005339633;8930.687425940061;7499.475036747375;8943.284537237005;8943.329250306253;8980.955095224483;9067.410618309788;6717.1267735194115;6844.1444819397575;6849.897251541231;6860.725570977105;6867.450603951672;1655.8509734123745;1773.955651108707;1775.2189036823756;1776.600264940378;1780.2078075256386;782.8982232309877;923.1460898668705;940.7177846645978;954.055485069776;1043.6555936540924;21807.563848723254;22247.777522224893;22388.32835014952;22405.319601029987;22419.10323587038;932.698885055344;1003.7540802503287;1005.3716427232979;1006.285537268533;1058.6241884131177;11326.546938122383;11419.257748246337;11915.940420298017;11933.522659093713;11970.461329240205;607113;607480;607570;624100;624433;69780;115482;116851;127383;129445;999.0808456220295;999.3554157568328;1013.0780989299702;1021.3667526133696;1021.6271061062658;14184.313144774998;15772.60568485797;19846.801531984496;20229.393118206477;20383.388498345073;54422.183667245816;54909.62725931883;55131.44868551424;55314;55790.72104639451;72650;73005.6349718248;73312;73684.15789605303;74078.25921740929;6717.193936727428;7414.851702966019;7461.552306861606;7556.962215188889;7725.304722574995;12798.16811907223;13359.666008349868;13402.26287554194;13494.73010539764;13528.729425411633;7518.962405187939;8768.350138261954;11412.714682133013;11617.6514704559;11869.703257418289;11640.767184656428;11711.941440292743;11739.882601173878;11964.222325548812;12109.576164834189;11552.537898483966;11955.462004210005;12074.698132546406;12094.75810483803;12149.1495595311;7896.802579935318;8312.168783121639;8455.534945578052;8472.534010629475;8567.014793298851;9801.607935682492;11628.244164129304;11921.582744603897;11937.761244775229;11943.522259109886 11:54:39 INFO - PROCESS | 1651 | |3;mootools.html;1564.022485946284;1597.2333280025587;1747;1821.5628249220183;1939.2824654877697;8572.057073721886;8801.735947921557;8989.325800564968;9010.053944335841;9058;7797.4931629444045;8065.596720163983;8421.663133474654;8681.609327580258;8786.12138786121;2347.671326014355;2390.1873363056557;2394.150076722756;2395.2215529952746;2396.6045602475656;2364.113457453455;2367.9052837886466;2417;2417.2506522977933;2418.4316685578874;2032.2379107834563;2125.2774056820676;2198.230619283246;2321.8839058047115;2405.1341517053847;2379.7501262367377;2382.3805810489225;2405.9157929472385;2411.5659181347264;2419.7701218384227;2375.928722138339;2392.114917480526;2396.352984694123;2399.7240317363553;2407.0492155598618;9015.41399809017;9022.548872556412;9034.638614455414;9035.909640903572;9049.592768325376;5216.374035115772;5344.011357898801;5393.5954803389905;5412.431694672042;5414.377346605152;3503.3343664703625;3639.5086663300644;3653.378925582631;3717.126475278307;3719.3119272934323;1061.3737894642197;1087.8694556653172;1092.071739021833;1098.9010989010947;1104.1222228328497;11667.766644667145;11705.414729263503;11718.648440546796;11730.002949749281;11738.647840564796;4565.3836732040745;4616.1229366420275;4818.084563932842;4823.855284341475;4834.444038935497;9912.405255684538;9947.403155810674;9954.950225248827;9961.55173017216;9967.601295948225;5363;5371.57027437804;5374.946250537445;5384.892302154013;5388.434214407509;4808.543188397097;4853.95146048535;4911.214205727067;4916.459189489154;4918.286848407032;3885.2812229737588;3952.308346039489;3960.2277555876326;4019.3569028955226;4077.4903137107863;980.4166520920041;1076.5478499030426;1240.3364200152876;1290.7741145299535;1294.1587967820803;743.6802175064666;928.9163975242126;928.9163975242126;934.3412893909777;953.9284553658504;5908.645481271137;5944.375840536768;6009.819705408845;6056.939430605726;6170.10533472644;923.35826600512;940.7742141886035;960.6397600899662;984.1241295247092;1036.2849633752687;3955.861544845917;4055.0876052887866;4131.710780245354;4139;4146.896327591834;10322.690319290434;10457.581696732197;10726.302790318605;10728.463576821128;10972.780544389068;10012;10734.677959661223;10881.83677244842;10901.182411319183;10904.890951090387;7750.534967901831;8244.381671374671;8256.050554186346;8342.791430214173;8367.037790654153;5885.73514191871;5968.671723055274;6005.609635373687;6121.47967422774;6202.906956395567;9712.562934667816;10268.845967310646;10269.948650256702;10285.382877027403;10300.690979270634;10231.28381013322;10334.638287659896;10380.117689996436;10382.948085259526;10386.221033422373;7370.44721645868;7550.622468876424;7582.317591416799;7743.806404839699;7761.262680045386;8549.700760473354;8597.097304782908;8665.913340866511;8666.696665616926;8683.565821708762;7444.478886478111;7588.696452142073;7708.190639982721;7865.528068315919;7874.960625196837;7321.633918303957;7462.179160292255;7499.700011999459;7587.165411804586;7616.961915190389;7080.398166155728;7115.75094871677;7145.785626431215;7163.176234733072;7164.247753985965;4850.587700045395;5114.693118412907;5118.462561430996;5123.436421993652;5151.5106064923775;5003.774075351562;5031.471695471922;5104;5128.487151284841;5271.551918086852 11:54:39 INFO - PROCESS | 1651 | |4;prototype.html;299.5342242812426;307.30702266389295;312.38460233340317;317.9395914776192;321.87768647913816;4401.581849724279;4835.178019736644;5446.210299506569;5451.781928722851;5463.508284254416;4472.373867658531;4918.22120501917;5427.213054107152;5433.347998240207;5484.204790305414;3568.947160587633;4047.575004624516;4050.8379664813374;4130.834766609332;4202.2435961526835;3818.3890577507605;3896.5129358830145;3926.254011737775;4158.521769996444;4185.7560390697145;3403.1662242750435;3427;3801.061458656078;3974.602539746031;4121.505419349683;4094.3858421236905;4110.77390743509;4116.629503344683;4139.441175441337;4161.334186530171;3601.7478776485655;3601.8559257629663;3604.279144171176;3605.8461292386446;3617.5297211362617;4566.789800702783;4659.157913672657;4883.19427294496;4886.924876527159;5279.683219006872;3913.25648126855;4193.727407718488;4341.175176716445;4680.297955306697;4707.741074240916;4431.8670439886855;4962.528559786814;5289.074411977888;5715.714214289311;5780.924448263964;3726.496922915426;3953.604639536052;3957.28768821612;3979.602039796026;4005.339119045354;4773.260144677581;5369.221462887859;5902.498287645555;5923.555733320019;5975.446383940163;3879.359905615598;4010.7393019453643;4011.217812526529;4055.918881622351;4082.795860206978;3913.2760439318818;4028.140020585359;4076.205139997672;4095.3037983542868;4107.794610269475;4200.87397378073;4513.345564893072;4691.859244222679;4725.10223057618;4729.597984171315;4533.750643714628;5238.528532432099;5566.610337276352;5753.827385178452;5756.740946657326;1187.625897842177;1279.8144269081117;1319.6955001028687;1360.6598350412396;1369.8904087672963;845.691322667222;856.1267507142679;889.8620713789373;916.3951791817368;921.6129225725211;4457.866264012085;5297.390800057966;5310.309659744208;5328.600354973392;5353.732313384316;4966.453690094087;5869.354371019184;5898.14476900847;5901.793437229762;5913.586048976616;4760.64295177863;5036.370453693288;5453;5458.208559758813;5485.451454854562;2596.5070084701374;2934.7652187824924;3130.906072817819;3140.795848269855;3144.7326977206735;5661.46216109469;5747.971260143672;5847.44449277318;6159.536185525736;6204.193454850841;6047.758089676452;6071.878562428727;6077.301110372364;6106.1606353046045;6125;5442.836714898559;6101.298350689639;6149.2313460817395;6169.2288463942;6337.2395312562785;4973.761807186708;5093.740006395984;5531.834044978657;5989.8203053908455;6027.36712645166;5157.994191132693;5859.882802344015;5899.557533184942;5917.556183286185;5917.970410147922;5274.920876186783;6148.508119350352;6174.536909731878;6191.659458729813;6213.37866213375;3126.671699471614;3924.3328634131694;3934.5212516114666;3950.427188057773;3985.48551550409;5384.542313903362;5819.592628515963;5853.385394533512;5853.9121913172185;5865;4565.680402371802;5250.369955605352;5543.972280138573;5582;5712.657525481592;5709.65742055478;5736.68448235351;5749.051406517878;5768.7404066817935;5800.433882851691;5605.439456054362;5616.1014237721765;5624.35319938196;5639.2668953035845;5708;5356.812511562077;5819.388964158702;5864.853378665397;5869.178315035813;5871.007714754009;5038.319826823332;5074.949250507595;5717.371089180104;5736.856578585569;5759.683217423082 11:54:39 INFO - PROCESS | 1651 | |5;yui.html;658.2627457247881;756.2362014365489;789.6209819286743;808.4381354958302;823.4770920465503;14816.185109818969;15928.522144335673;15942.282597283122;15986.200689965499;15991;11904.821427678591;14522.636934076627;14558.05372650777;14561.92719036406;14856.702865942674;670.51722759613;884.8849649545582;886.7029545102382;893.499640201486;895.3463971300954;735.224338323069;885.9689910853122;901.2789768185426;918.2149262380635;921.5668635741191;958.9856152157723;959.7120863740885;960.0255740423476;960.7502049467118;962.1436921140191;752.9774106776805;929.665320484625;952.1573407534313;953.2659851914023;955.8709574207462;703.4161645833946;706.5407485134652;708.550070705106;710.8578284343153;714.4534431160166;14991.775123373158;15051.849481505154;15052.172130532816;15079;15095.47165849201;13900.096493727875;14091.365888534938;14091.788623170558;14321.641958951006;14371.568852934428;12721.745565088646;12742.936285318514;12789.936050319782;13001.284929328884;13330.533431329857;4566.954330456686;4591.8392856249875;4685.437747470325;4738.620910327166;4749.860033591913;109284;111220;111308.33037504443;111468;111540.32689509664;9466.526673666289;11030.44847757625;11047.944760276308;11457.312561246352;11556.133290003283;21888.12447502114;21889.233876814553;21891.343259702233;21903.890480547496;21913.890430548065;7683.308502234826;7817.8827317590285;8002.8799568006525;8039.598020098972;8086.272235498834;4458.197524445566;4484.484284307281;4495.662825288116;4583.175028494903;4624.12141693084;3441.9655803441647;3544.929101418009;3554.91112722184;3559.2703495783303;3563.376409128392;659.3274859643137;684.4455990647591;685.4242436353487;732.9670164842595;738.4572339330588;538.7898719499399;675.9256481786999;697.8778713725179;709.7154150986628;714.4641518860855;7683.846323073619;8665.480071195749;9281.72154835356;9505.619775208914;9565.569549370295;532.4941305759544;609.9939000610026;639.4085470939362;666.5933780393956;686.3376841348023;4521.525239849834;4680.759598706346;4708.457987438799;4734.73958932262;4775.355327030807;106368;108910;142244;160635;166153;106728.46635766771;138457;138931;139134.30432847975;141583.29208353892;115873.841261588;116515.41742291235;117801;117997.41001294939;120914.79085209212;8915.375923685539;8938.50838203905;8938.50838203905;8939.150780675825;9088.63645454201;47396;53081;54587;55058.44941550533;56195;68211;79952;91354;95026.76817411516;98937;11339.716507087389;13419.731605367642;13504.797428038784;13651.79522307186;13732.588022359345;15127.830714020542;15139.243037848284;15174.393024278905;15183.316750746022;15569.76645350298;14094.083884547472;14107.012509124263;14132.78800817968;14139.43442262298;14172.858271417153;13875.514356997457;13952.511662091778;13973.930130349283;14132.434702612192;14610.926945365205;13260.867391325963;13370.799438008244;13429.529966451128;13434.462621495031;13536.79694804559;9713.320067595201;11021.779564408827;11178.217524773188;11464.049231136352;11718.781119299148;10790.170659338508;11318.151138664469;11381.772364552828;11606.941965290458;11648.301101933912 11:54:39 INFO - PROCESS | 1651 | __end_tp_report 11:54:39 INFO - PROCESS | 1651 | __start_cc_report 11:54:39 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1208 11:54:39 INFO - PROCESS | 1651 | __end_cc_report 11:54:39 INFO - PROCESS | 1651 | __startTimestamp1467831279819__endTimestamp 11:54:39 INFO - PROCESS | 1651 | 11:54:39 INFO - PROCESS | 1651 | ------- Summary: start ------- 11:54:39 INFO - PROCESS | 1651 | Number of tests: 6 11:54:39 INFO - PROCESS | 1651 | 11:54:39 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5749.56 Median:5932.26 stddev:668.38 (11.3%) stddev-sans-first:641.47 11:54:39 INFO - PROCESS | 1651 | Values: 3268.8 3317.3 3327.8 3350.0 3387.5 5624.6 5670.4 5684.4 5807.4 5823.1 5700.3 5749.7 5753.2 5753.9 5772.0 4286.0 5142.0 5180.3 5212.1 5274.4 4690.3 4928.5 5146.8 5203.2 5253.4 5189.8 5198.1 5228.1 5230.6 5244.2 5288.9 5297.2 5302.7 5310.6 5350.7 5120.5 5124.6 5150.3 5195.8 5235.5 5689.5 5700.9 5741.1 5781.4 5858.6 6301.8 6312.2 6324.7 6333.1 6345.0 5896.4 6194.0 6254.3 6299.7 6324.7 5782.4 5814.4 5817.4 5854.5 5860.5 6278.6 6295.5 6297.1 6303.1 6304.4 6013.3 6229.2 6231.7 6238.1 6240.3 6220.0 6285.2 6288.8 6312.0 6347.8 6265.1 6295.0 6295.1 6303.4 6328.6 6118.7 6127.8 6165.4 6279.3 6338.9 4369.7 4797.2 4913.2 4921.4 4938.3 4385.7 4462.9 4465.7 4472.2 4480.5 5760.2 5771.1 5772.5 5772.7 5773.2 5586.1 5934.6 6214.2 6238.6 6281.1 5863.6 5870.5 5871.0 5877.9 5880.6 5362.7 5395.4 5413.5 5465.0 5472.2 6214.8 6264.5 6276.2 6283.2 6626.0 6312.3 6323.3 6327.2 6329.3 6333.2 6336.0 6345.9 6349.1 6352.5 6376.0 5957.5 6097.3 6121.8 6185.0 6236.5 6161.0 6172.6 6175.2 6177.4 6244.7 6141.1 6164.3 6173.5 6214.3 6230.6 5854.8 5973.9 6014.3 6015.7 6103.7 5898.4 5935.9 6046.2 6080.3 6086.8 6175.0 6178.8 6181.7 6190.4 6210.6 5494.2 5590.5 5796.4 5932.3 5967.5 11:54:39 INFO - PROCESS | 1651 | 11:54:39 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:71409.77 Median:18450.38 stddev:159722.63 (865.7%) stddev-sans-first:160081.83 11:54:39 INFO - PROCESS | 1651 | Values: 445.5 504.1 537.7 541.0 555.7 29667.8 30588.2 31968.8 32042.7 41249.0 34031.7 44535.0 44566.3 44583.8 44685.3 9703.2 9854.7 9857.5 9866.5 9890.0 8712.9 8905.0 9656.2 9760.8 11796.9 11462.3 11781.2 11794.2 11795.6 11806.6 10305.3 11943.3 12001.8 12080.3 12083.3 8625.4 11061.6 11784.9 11808.5 12136.0 41845.6 42246.6 42282.0 42342.6 42376.6 3577.9 3632.4 3726.3 3772.2 3918.7 32080.0 32106.8 32361.8 32891.0 33189.0 3278.3 3316.3 3325.7 3326.9 3401.9 475870.0 479128.0 479147.0 481520.0 482235.6 58833.4 59140.4 59487.7 59569.7 59631.8 32711.2 32977.8 32994.7 33029.5 33079.5 17089.2 17216.3 17249.6 17263.4 17279.2 11941.0 13006.4 13102.3 13143.7 13159.8 17002.1 19448.6 19531.8 19655.5 20073.7 7451.6 8129.7 8203.9 8246.5 8246.8 863.8 897.5 1009.6 1014.5 1032.6 30448.5 30745.0 30984.5 31482.5 31551.0 1045.2 1121.1 1133.3 1156.6 1246.4 16733.7 16751.2 16845.8 17244.4 17354.4 715844.0 719466.0 720474.0 721075.0 721486.0 59734.0 61732.8 61750.7 61759.7 61915.5 1285.9 1320.0 1321.1 1432.7 1474.3 25220.7 26135.0 31710.7 31716.7 31882.0 51609.7 51844.0 52304.7 52423.0 52623.5 559641.0 563909.0 564417.0 565684.0 570430.0 25956.0 26503.7 26838.3 26864.2 26890.7 47887.0 48365.3 48886.3 49009.0 49180.8 39761.8 39894.4 40322.4 40741.0 41006.2 35634.9 38986.8 39937.8 40246.2 41067.4 38598.8 39128.2 39208.4 39472.8 39491.6 28983.4 30803.7 31324.1 33101.8 33498.8 23555.8 25359.2 25360.7 25804.6 26029.6 11:54:39 INFO - PROCESS | 1651 | 11:54:39 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:35381.86 Median:22412.21 stddev:102044.74 (455.3%) stddev-sans-first:102297.11 11:54:39 INFO - PROCESS | 1651 | Values: 355.3 358.8 385.5 386.5 404.8 15965.5 16364.6 21276.9 21309.4 21322.9 20792.2 21181.0 21558.2 21755.3 21790.5 5164.3 5762.9 5953.1 5990.6 6004.6 4147.0 4384.6 5874.2 6346.4 6650.1 6311.3 6637.5 6639.7 6644.9 6658.8 6501.9 6550.9 6564.0 6582.8 6590.8 3628.8 5370.9 5682.0 5684.9 5723.4 26141.5 26170.6 26185.7 26842.7 27193.7 2205.5 2211.7 2214.2 2246.5 2295.5 15441.5 15528.0 15801.8 15823.3 16327.2 1614.0 1900.9 1911.8 1918.2 1990.6 119360.0 122023.8 122604.8 123009.4 124486.0 10559.6 10784.8 11008.1 11035.5 11176.2 13732.1 13797.3 13825.3 13826.1 13839.7 7365.1 8639.3 8848.2 8856.4 8930.7 7499.5 8943.3 8943.3 8981.0 9067.4 6717.1 6844.1 6849.9 6860.7 6867.5 1655.9 1774.0 1775.2 1776.6 1780.2 782.9 923.1 940.7 954.1 1043.7 21807.6 22247.8 22388.3 22405.3 22419.1 932.7 1003.8 1005.4 1006.3 1058.6 11326.5 11419.3 11915.9 11933.5 11970.5 607113.0 607480.0 607570.0 624100.0 624433.0 69780.0 115482.0 116851.0 127383.0 129445.0 999.1 999.4 1013.1 1021.4 1021.6 14184.3 15772.6 19846.8 20229.4 20383.4 54422.2 54909.6 55131.4 55314.0 55790.7 72650.0 73005.6 73312.0 73684.2 74078.3 6717.2 7414.9 7461.6 7557.0 7725.3 12798.2 13359.7 13402.3 13494.7 13528.7 7519.0 8768.4 11412.7 11617.7 11869.7 11640.8 11711.9 11739.9 11964.2 12109.6 11552.5 11955.5 12074.7 12094.8 12149.1 7896.8 8312.2 8455.5 8472.5 8567.0 9801.6 11628.2 11921.6 11937.8 11943.5 11:54:39 INFO - PROCESS | 1651 | 11:54:39 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5790.90 Median:5017.62 stddev:3226.37 (64.3%) stddev-sans-first:3219.79 11:54:39 INFO - PROCESS | 1651 | Values: 1564.0 1597.2 1747.0 1821.6 1939.3 8572.1 8801.7 8989.3 9010.1 9058.0 7797.5 8065.6 8421.7 8681.6 8786.1 2347.7 2390.2 2394.2 2395.2 2396.6 2364.1 2367.9 2417.0 2417.3 2418.4 2032.2 2125.3 2198.2 2321.9 2405.1 2379.8 2382.4 2405.9 2411.6 2419.8 2375.9 2392.1 2396.4 2399.7 2407.0 9015.4 9022.5 9034.6 9035.9 9049.6 5216.4 5344.0 5393.6 5412.4 5414.4 3503.3 3639.5 3653.4 3717.1 3719.3 1061.4 1087.9 1092.1 1098.9 1104.1 11667.8 11705.4 11718.6 11730.0 11738.6 4565.4 4616.1 4818.1 4823.9 4834.4 9912.4 9947.4 9955.0 9961.6 9967.6 5363.0 5371.6 5374.9 5384.9 5388.4 4808.5 4854.0 4911.2 4916.5 4918.3 3885.3 3952.3 3960.2 4019.4 4077.5 980.4 1076.5 1240.3 1290.8 1294.2 743.7 928.9 928.9 934.3 953.9 5908.6 5944.4 6009.8 6056.9 6170.1 923.4 940.8 960.6 984.1 1036.3 3955.9 4055.1 4131.7 4139.0 4146.9 10322.7 10457.6 10726.3 10728.5 10972.8 10012.0 10734.7 10881.8 10901.2 10904.9 7750.5 8244.4 8256.1 8342.8 8367.0 5885.7 5968.7 6005.6 6121.5 6202.9 9712.6 10268.8 10269.9 10285.4 10300.7 10231.3 10334.6 10380.1 10382.9 10386.2 7370.4 7550.6 7582.3 7743.8 7761.3 8549.7 8597.1 8665.9 8666.7 8683.6 7444.5 7588.7 7708.2 7865.5 7875.0 7321.6 7462.2 7499.7 7587.2 7617.0 7080.4 7115.8 7145.8 7163.2 7164.2 4850.6 5114.7 5118.5 5123.4 5151.5 5003.8 5031.5 5104.0 5128.5 5271.6 11:54:39 INFO - PROCESS | 1651 | 11:54:39 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4613.97 Median:5125.87 stddev:1437.98 (28.1%) stddev-sans-first:1405.08 11:54:39 INFO - PROCESS | 1651 | Values: 299.5 307.3 312.4 317.9 321.9 4401.6 4835.2 5446.2 5451.8 5463.5 4472.4 4918.2 5427.2 5433.3 5484.2 3568.9 4047.6 4050.8 4130.8 4202.2 3818.4 3896.5 3926.3 4158.5 4185.8 3403.2 3427.0 3801.1 3974.6 4121.5 4094.4 4110.8 4116.6 4139.4 4161.3 3601.7 3601.9 3604.3 3605.8 3617.5 4566.8 4659.2 4883.2 4886.9 5279.7 3913.3 4193.7 4341.2 4680.3 4707.7 4431.9 4962.5 5289.1 5715.7 5780.9 3726.5 3953.6 3957.3 3979.6 4005.3 4773.3 5369.2 5902.5 5923.6 5975.4 3879.4 4010.7 4011.2 4055.9 4082.8 3913.3 4028.1 4076.2 4095.3 4107.8 4200.9 4513.3 4691.9 4725.1 4729.6 4533.8 5238.5 5566.6 5753.8 5756.7 1187.6 1279.8 1319.7 1360.7 1369.9 845.7 856.1 889.9 916.4 921.6 4457.9 5297.4 5310.3 5328.6 5353.7 4966.5 5869.4 5898.1 5901.8 5913.6 4760.6 5036.4 5453.0 5458.2 5485.5 2596.5 2934.8 3130.9 3140.8 3144.7 5661.5 5748.0 5847.4 6159.5 6204.2 6047.8 6071.9 6077.3 6106.2 6125.0 5442.8 6101.3 6149.2 6169.2 6337.2 4973.8 5093.7 5531.8 5989.8 6027.4 5158.0 5859.9 5899.6 5917.6 5918.0 5274.9 6148.5 6174.5 6191.7 6213.4 3126.7 3924.3 3934.5 3950.4 3985.5 5384.5 5819.6 5853.4 5853.9 5865.0 4565.7 5250.4 5544.0 5582.0 5712.7 5709.7 5736.7 5749.1 5768.7 5800.4 5605.4 5616.1 5624.4 5639.3 5708.0 5356.8 5819.4 5864.9 5869.2 5871.0 5038.3 5074.9 5717.4 5736.9 5759.7 11:54:39 INFO - PROCESS | 1651 | 11:54:39 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:24611.26 Median:3557.09 stddev:39295.32 (1104.7%) stddev-sans-first:39364.40 11:54:39 INFO - PROCESS | 1651 | Values: 658.3 756.2 789.6 808.4 823.5 14816.2 15928.5 15942.3 15986.2 15991.0 11904.8 14522.6 14558.1 14561.9 14856.7 670.5 884.9 886.7 893.5 895.3 735.2 886.0 901.3 918.2 921.6 959.0 959.7 960.0 960.8 962.1 753.0 929.7 952.2 953.3 955.9 703.4 706.5 708.6 710.9 714.5 14991.8 15051.8 15052.2 15079.0 15095.5 13900.1 14091.4 14091.8 14321.6 14371.6 12721.7 12742.9 12789.9 13001.3 13330.5 4567.0 4591.8 4685.4 4738.6 4749.9 109284.0 111220.0 111308.3 111468.0 111540.3 9466.5 11030.4 11047.9 11457.3 11556.1 21888.1 21889.2 21891.3 21903.9 21913.9 7683.3 7817.9 8002.9 8039.6 8086.3 4458.2 4484.5 4495.7 4583.2 4624.1 3442.0 3544.9 3554.9 3559.3 3563.4 659.3 684.4 685.4 733.0 738.5 538.8 675.9 697.9 709.7 714.5 7683.8 8665.5 9281.7 9505.6 9565.6 532.5 610.0 639.4 666.6 686.3 4521.5 4680.8 4708.5 4734.7 4775.4 106368.0 108910.0 142244.0 160635.0 166153.0 106728.5 138457.0 138931.0 139134.3 141583.3 115873.8 116515.4 117801.0 117997.4 120914.8 8915.4 8938.5 8938.5 8939.2 9088.6 47396.0 53081.0 54587.0 55058.4 56195.0 68211.0 79952.0 91354.0 95026.8 98937.0 11339.7 13419.7 13504.8 13651.8 13732.6 15127.8 15139.2 15174.4 15183.3 15569.8 14094.1 14107.0 14132.8 14139.4 14172.9 13875.5 13952.5 13973.9 14132.4 14610.9 13260.9 13370.8 13429.5 13434.5 13536.8 9713.3 11021.8 11178.2 11464.0 11718.8 10790.2 11318.2 11381.8 11606.9 11648.3 11:54:39 INFO - PROCESS | 1651 | -------- Summary: end -------- 11:54:39 INFO - PROCESS | 1651 | 11:54:40 INFO - TEST-INFO | 1651: exit 0 11:54:40 INFO - TEST-OK | dromaeo_css | took 1117000ms 11:54:40 INFO - TEST-START | kraken 11:54:40 INFO - operating with platform_type : mac_ 11:54:40 INFO - Initialising browser for kraken test... 11:54:40 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmp0Uh33G/profile http://localhost:49274/getInfo.html) 11:54:42 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 11:54:42 INFO - PROCESS | 1660 | colorDepth:24 11:54:42 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 11:54:42 INFO - PROCESS | 1660 | __metrics 11:54:42 INFO - PROCESS | 1660 | 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" 11:54:42 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:54:43 INFO - TEST-INFO | 1660: exit 0 11:54:43 INFO - Browser initialized. 11:54:43 INFO - Running cycle 1/1 for kraken test... 11:54:43 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmp0Uh33G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:54:55 INFO - PROCESS | 1661 | RSS: Main: 243814400 11:54:55 INFO - PROCESS | 1661 | 11:54:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 11:54:59 INFO - PROCESS | 1661 | RSS: Main: 293924864 11:54:59 INFO - PROCESS | 1661 | 11:55:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 11:55:02 INFO - PROCESS | 1661 | RSS: Main: 305197056 11:55:02 INFO - PROCESS | 1661 | 11:55:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 11:55:06 INFO - PROCESS | 1661 | RSS: Main: 391487488 11:55:06 INFO - PROCESS | 1661 | 11:55:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 11:55:09 INFO - PROCESS | 1661 | RSS: Main: 292720640 11:55:09 INFO - PROCESS | 1661 | 11:55:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:55:11 INFO - PROCESS | 1661 | RSS: Main: 296353792 11:55:11 INFO - PROCESS | 1661 | 11:55:14 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 11:55:15 INFO - PROCESS | 1661 | RSS: Main: 332042240 11:55:15 INFO - PROCESS | 1661 | 11:55:18 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 11:55:18 INFO - PROCESS | 1661 | RSS: Main: 334839808 11:55:18 INFO - PROCESS | 1661 | 11:55:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 11:55:22 INFO - PROCESS | 1661 | RSS: Main: 332234752 11:55:22 INFO - PROCESS | 1661 | 11:55:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:55:24 INFO - PROCESS | 1661 | RSS: Main: 313110528 11:55:24 INFO - PROCESS | 1661 | 11:55:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:55:26 INFO - PROCESS | 1661 | RSS: Main: 320200704 11:55:26 INFO - PROCESS | 1661 | 11:55:28 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:55:29 INFO - PROCESS | 1661 | RSS: Main: 319643648 11:55:29 INFO - PROCESS | 1661 | 11:55:32 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:55:33 INFO - PROCESS | 1661 | RSS: Main: 325877760 11:55:33 INFO - PROCESS | 1661 | 11:55:35 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:55:36 INFO - PROCESS | 1661 | RSS: Main: 329539584 11:55:36 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 11:55:38 INFO - PROCESS | 1661 | RSS: Main: 372600832 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | __start_tp_report 11:55:38 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 11:55:38 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 11:55:38 INFO - PROCESS | 1661 | |i|pagename|runs| 11:55:38 INFO - PROCESS | 1661 | |0;ai-astar;102;115;113;113;113;113;113;112;113;111 11:55:38 INFO - PROCESS | 1661 | |1;audio-beat-detection;142;110;102;158;112;107;128;106;139;108 11:55:38 INFO - PROCESS | 1661 | |2;audio-dft;182;186;183;181;182;185;179;182;182;184 11:55:38 INFO - PROCESS | 1661 | |3;audio-fft;83;78;56;104;57;57;59;66;94;56 11:55:38 INFO - PROCESS | 1661 | |4;audio-oscillator;83;90;91;71;72;68;98;70;68;101 11:55:38 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;93;87;89;87;91;88;94;91;91;87 11:55:38 INFO - PROCESS | 1661 | |6;imaging-darkroom;93;93;94;93;93;94;94;94;94;92 11:55:38 INFO - PROCESS | 1661 | |7;imaging-desaturate;84;84;84;86;86;85;82;84;84;83 11:55:38 INFO - PROCESS | 1661 | |8;json-parse-financial;55;53;53;55;54;55;53;53;55;54 11:55:38 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;65;71;72;77;72;69;70;68;68;70 11:55:38 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;83;84;84;83;82;84;77;82;86;86 11:55:38 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;137;141;146;150;142;139;154;121;234;158 11:55:38 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;163;171;164;161;162;164;161;162;161;163 11:55:38 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;64;67;66;67;65;70;85;85;71;66 11:55:38 INFO - PROCESS | 1661 | __end_tp_report 11:55:38 INFO - PROCESS | 1661 | __start_cc_report 11:55:38 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1991 11:55:38 INFO - PROCESS | 1661 | __end_cc_report 11:55:38 INFO - PROCESS | 1661 | __startTimestamp1467831338574__endTimestamp 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | ------- Summary: start ------- 11:55:38 INFO - PROCESS | 1661 | Number of tests: 14 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.58 (3.2%) stddev-sans-first:1.05 11:55:38 INFO - PROCESS | 1661 | Values: 102.0 115.0 113.0 113.0 113.0 113.0 113.0 112.0 113.0 111.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:120.00 stddev:19.25 (16.0%) stddev-sans-first:18.89 11:55:38 INFO - PROCESS | 1661 | Values: 142.0 110.0 102.0 158.0 112.0 107.0 128.0 106.0 139.0 108.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:182.60 Median:182.50 stddev:2.01 (1.1%) stddev-sans-first:2.12 11:55:38 INFO - PROCESS | 1661 | Values: 182.0 186.0 183.0 181.0 182.0 185.0 179.0 182.0 182.0 184.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:71.00 Median:62.50 stddev:17.71 (28.3%) stddev-sans-first:18.24 11:55:38 INFO - PROCESS | 1661 | Values: 83.0 78.0 56.0 104.0 57.0 57.0 59.0 66.0 94.0 56.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:77.50 stddev:12.97 (16.7%) stddev-sans-first:13.74 11:55:38 INFO - PROCESS | 1661 | Values: 83.0 90.0 91.0 71.0 72.0 68.0 98.0 70.0 68.0 101.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:91.00 stddev:2.57 (2.8%) stddev-sans-first:2.46 11:55:38 INFO - PROCESS | 1661 | Values: 93.0 87.0 89.0 87.0 91.0 88.0 94.0 91.0 91.0 87.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 11:55:38 INFO - PROCESS | 1661 | Values: 93.0 93.0 94.0 93.0 93.0 94.0 94.0 94.0 94.0 92.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:1.23 (1.5%) stddev-sans-first:1.30 11:55:38 INFO - PROCESS | 1661 | Values: 84.0 84.0 84.0 86.0 86.0 85.0 82.0 84.0 84.0 83.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:0.94 (1.7%) stddev-sans-first:0.93 11:55:38 INFO - PROCESS | 1661 | Values: 55.0 53.0 53.0 55.0 54.0 55.0 53.0 53.0 55.0 54.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.50 stddev:3.19 (4.5%) stddev-sans-first:2.77 11:55:38 INFO - PROCESS | 1661 | Values: 65.0 71.0 72.0 77.0 72.0 69.0 70.0 68.0 68.0 70.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:84.00 stddev:2.56 (3.0%) stddev-sans-first:2.71 11:55:38 INFO - PROCESS | 1661 | Values: 83.0 84.0 84.0 83.0 82.0 84.0 77.0 82.0 86.0 86.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:148.00 stddev:30.51 (20.6%) stddev-sans-first:31.86 11:55:38 INFO - PROCESS | 1661 | Values: 137.0 141.0 146.0 150.0 142.0 139.0 154.0 121.0 234.0 158.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.20 Median:163.00 stddev:2.97 (1.8%) stddev-sans-first:3.15 11:55:38 INFO - PROCESS | 1661 | Values: 163.0 171.0 164.0 161.0 162.0 164.0 161.0 162.0 161.0 163.0 11:55:38 INFO - PROCESS | 1661 | 11:55:38 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:68.50 stddev:7.88 (11.5%) stddev-sans-first:7.98 11:55:38 INFO - PROCESS | 1661 | Values: 64.0 67.0 66.0 67.0 65.0 70.0 85.0 85.0 71.0 66.0 11:55:38 INFO - PROCESS | 1661 | -------- Summary: end -------- 11:55:38 INFO - PROCESS | 1661 | 11:55:39 INFO - TEST-INFO | 1661: exit 0 11:55:39 INFO - TEST-OK | kraken | took 59031ms 11:55:39 INFO - SUITE-END | took 1176s 11:55:39 INFO - Completed test suite (00:19:36) 11:55:39 INFO - javascript benchmark 11:55:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3268.82021488818, 3317.2536179359645, 3327.8003319800805, 3350.045237107425, 3387.4918762185653, 5624.606277560572, 5670.404607516213, 5684.37471878093, 5807.361190269073, 5823.097419899912, 5700.344460387057, 5749.683767392792, 5753.223314852494, 5753.913691294634, 5771.961047011536, 4285.957140428587, 5141.974290128554, 5180.3006594109875, 5212.087884620177, 5274.4461831507715, 4690.319903613974, 4928.531789479994, 5146.794128234885, 5203.245529398235, 5253.448387919251, 5189.766460509267, 5198.090334191501, 5228.111221092405, 5230.555402790748, 5244.160934250521, 5288.894222115556, 5297.231901374279, 5302.655327403706, 5310.575153987672, 5350.678959262418, 5120.539151476348, 5124.590032797368, 5150.253213284052, 5195.792168313263, 5235.450277720861, 5689.487946084831, 5700.885982280372, 5741.081426971665, 5781.364049954502, 5858.648481091148, 6301.842453938642, 6312.210973628296, 6324.7470101195895, 6333.113364129025, 6344.968275158641, 5896.410358964112, 6194.0, 6254.343293954114, 6299.6850157491945, 6324.7470101195895, 5782.421757824226, 5814.418558144152, 5817.389174136697, 5854.531637468992, 5860.472557469848, 6278.560500764903, 6295.496360291166, 6297.149884765589, 6303.149074874925, 6304.432601065926, 6013.338532761481, 6229.190205273376, 6231.657258850807, 6238.1266622672865, 6240.251169859555, 6219.973704338733, 6285.151504546919, 6288.779892703734, 6312.021636646327, 6347.809565713036, 6265.091561723524, 6294.9685251574365, 6295.087212354183, 6303.369663033752, 6328.588641738273, 6118.694065296718, 6127.754889804358, 6165.383461653799, 6279.277883043508, 6338.873222535523, 4369.650427965755, 4797.232442809134, 4913.1893237615395, 4921.384826896638, 4938.308636790852, 4385.7149285296355, 4462.933056004163, 4465.665075119379, 4472.172648060119, 4480.4623445186135, 5760.164776107441, 5771.076627739541, 5772.480476757112, 5772.740226689809, 5773.24947756789, 5586.050371436865, 5934.6142500737315, 6214.161088253119, 6238.594491358047, 6281.120643109968, 5863.589548731547, 5870.53035757147, 5870.970645146747, 5877.9118313225335, 5880.558958078162, 5362.678239305654, 5395.406505284415, 5413.485718856702, 5465.0, 5472.179173123984, 6214.782482613177, 6264.467520260738, 6276.184096067482, 6283.151774510383, 6625.966870165618, 6312.337204593451, 6323.272823625158, 6327.17746692927, 6329.303776584664, 6333.1766870306565, 6335.968320158369, 6345.873082538231, 6349.142865713255, 6352.491800655844, 6375.968120159369, 5957.523398128226, 6097.282711138064, 6121.788327166137, 6185.0, 6236.532260080603, 6161.0, 6172.629642221481, 6175.166352542349, 6177.413145751147, 6244.65654389013, 6141.07883817431, 6164.291106522807, 6173.536984726074, 6214.2853571838, 6230.626162430269, 5854.824355269348, 5973.940260597338, 6014.338422773404, 6015.72929218195, 6103.7253323601435, 5898.4101589842385, 5935.940640593539, 6046.244219472566, 6080.290116415274, 6086.7789383501795, 6175.011998080286, 6178.783742568989, 6181.690915454119, 6190.380961903954, 6210.5652604317265, 5494.203340515683, 5590.4626227787685, 5796.449337312949, 5932.2584676915385, 5967.492763115184], "value": 5704.900219717265, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.467666138964, 504.08256972310363, 537.7284471341969, 540.9864753381165, 555.744357595506, 29667.813287468503, 30588.235294117658, 31968.840155799247, 32042.67957320426, 41248.968775780624, 34031.65968340316, 44535.0, 44566.331505027454, 44583.777081114546, 44685.32972005422, 9703.175230105431, 9854.704358869245, 9857.457839818808, 9866.45734484603, 9890.0, 8712.869306960401, 8904.955475222614, 9656.179224765903, 9760.804783904317, 11796.941015294911, 11462.312261264351, 11781.234219775688, 11794.174407791457, 11795.587154449597, 11806.645800625953, 10305.330153540071, 11943.34311612861, 12001.819972700416, 12080.275183488931, 12083.275003499773, 8625.353098517573, 11061.612843550438, 11784.88215117855, 11808.468618912084, 12136.029117670654, 41845.581544184475, 42246.57753422457, 42282.0, 42342.57657423417, 42376.57623423788, 3577.928441431157, 3632.4188129899353, 3726.31063253299, 3772.2455508898056, 3918.666913312372, 32080.03759887207, 32106.83946580252, 32361.838190809132, 32891.0, 33189.0, 3278.3115545735423, 3316.303576248991, 3325.7173140283103, 3326.8835590754206, 3401.9319613607836, 475870.0, 479128.0, 479147.0, 481520.0, 482235.5888220572, 58833.41166588365, 59140.40859591349, 59487.70256148691, 59569.702151488964, 59631.80736385248, 32711.182220444203, 32977.83511082477, 32994.67005329964, 33029.50455743166, 33079.50380744291, 17089.23098460572, 17216.31134754621, 17249.56876078108, 17263.3957811476, 17279.222434990457, 11940.98501627354, 13006.414711338013, 13102.279374634487, 13143.671408214872, 13159.802602960963, 17002.06488643112, 19448.611027779647, 19531.773904318106, 19655.508612284808, 20073.698894516594, 7451.552906825608, 8129.715459958873, 8203.876941845878, 8246.463979841292, 8246.752597422086, 863.7605036772252, 897.4660077254024, 1009.5507499162802, 1014.5383850348074, 1032.5611615063567, 30448.54327185094, 30745.0, 30984.53523197154, 31482.527762083588, 31551.0, 1045.1586472889246, 1121.0751130317358, 1133.2633788037842, 1156.6356597671706, 1246.3705828556522, 16733.665326693397, 16751.246193921303, 16845.83154168467, 17244.396446124327, 17354.392596259324, 715844.0, 719466.0, 720474.0, 721075.0, 721486.0, 59734.0, 61732.76534469285, 61750.69124654348, 61759.691201543705, 61915.452113697516, 1285.929273889945, 1320.0, 1321.062045947388, 1432.7421064208334, 1474.2997076388629, 25220.74779252184, 26135.0, 31710.68289317077, 31716.731330746512, 31882.0, 51609.74195129, 51843.96312073813, 52304.73847630737, 52423.0, 52623.47376526186, 559641.0, 563909.0, 564417.0, 565684.0, 570430.0, 25955.96176152933, 26503.734962650127, 26838.329041774112, 26864.194074177805, 26890.731092688824, 47887.04225915532, 48365.27452088292, 48886.26670600015, 49009.0, 49180.77048073827, 39761.80119099386, 39894.40158397685, 40322.39516407315, 40741.0, 41006.17987640171, 35634.93095207148, 38986.80506597449, 39937.800310999424, 40246.19507609773, 41067.383989239584, 38598.842034739006, 39128.21743565056, 39208.411873821344, 39472.802635987784, 39491.605083948794, 28983.420331592828, 30803.691963080084, 31324.06027819169, 33101.834490827394, 33498.832505837316, 23555.764442355357, 25359.239222823344, 25360.74639253584, 25804.612930805677, 26029.609555857056], "value": 20273.99229335182, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.2539666699929, 358.8439029022376, 385.458430904579, 386.48597365503883, 404.76321352009074, 15965.521034368958, 16364.59088522786, 21276.89361553194, 21309.36071917841, 21322.89338553307, 20792.168313267488, 21181.0, 21558.245461408853, 21755.34733957984, 21790.45523861904, 5164.328637277149, 5762.884742305162, 5953.1070339449, 5990.640561566298, 6004.609700369484, 4147.0, 4384.627306678936, 5874.1776151338845, 6346.365363463663, 6650.102236198124, 6311.337309582496, 6637.46900247979, 6639.701213445383, 6644.93355066448, 6658.76694315699, 6501.869962600746, 6550.9344906550805, 6563.967180164069, 6582.80251592453, 6590.769323073718, 3628.782273063598, 5370.919436208421, 5682.0, 5684.857878553028, 5723.370429252779, 26141.477170456485, 26170.6074408884, 26185.73814261871, 26842.731572684414, 27193.728062719318, 2205.514786746929, 2211.7124773779465, 2214.247155966979, 2246.539459410817, 2295.494991101955, 15441.5367538974, 15528.0, 15801.762973555406, 15823.287952042187, 16327.18364081803, 1614.0, 1900.8764430311985, 1911.8279354857991, 1918.2422942937462, 1990.601879624081, 119360.0, 122023.77976220213, 122604.77395226022, 123009.38495307555, 124486.0, 10559.577616895393, 10784.784304314027, 11008.064314533354, 11035.503402346752, 11176.217664763552, 13732.107413018122, 13797.310134493235, 13825.308734563232, 13826.101303415448, 13839.723205536035, 7365.079365079365, 8639.265662418766, 8848.203661670352, 8856.38005339633, 8930.687425940061, 7499.475036747375, 8943.284537237005, 8943.329250306253, 8980.955095224483, 9067.410618309788, 6717.1267735194115, 6844.1444819397575, 6849.897251541231, 6860.725570977105, 6867.450603951672, 1655.8509734123745, 1773.955651108707, 1775.2189036823756, 1776.600264940378, 1780.2078075256386, 782.8982232309877, 923.1460898668705, 940.7177846645978, 954.055485069776, 1043.6555936540924, 21807.563848723254, 22247.777522224893, 22388.32835014952, 22405.319601029987, 22419.10323587038, 932.698885055344, 1003.7540802503287, 1005.3716427232979, 1006.285537268533, 1058.6241884131177, 11326.546938122383, 11419.257748246337, 11915.940420298017, 11933.522659093713, 11970.461329240205, 607113.0, 607480.0, 607570.0, 624100.0, 624433.0, 69780.0, 115482.0, 116851.0, 127383.0, 129445.0, 999.0808456220295, 999.3554157568328, 1013.0780989299702, 1021.3667526133696, 1021.6271061062658, 14184.313144774998, 15772.60568485797, 19846.801531984496, 20229.393118206477, 20383.388498345073, 54422.183667245816, 54909.62725931883, 55131.44868551424, 55314.0, 55790.72104639451, 72650.0, 73005.6349718248, 73312.0, 73684.15789605303, 74078.25921740929, 6717.193936727428, 7414.851702966019, 7461.552306861606, 7556.962215188889, 7725.304722574995, 12798.16811907223, 13359.666008349868, 13402.26287554194, 13494.73010539764, 13528.729425411633, 7518.962405187939, 8768.350138261954, 11412.714682133013, 11617.6514704559, 11869.703257418289, 11640.767184656428, 11711.941440292743, 11739.882601173878, 11964.222325548812, 12109.576164834189, 11552.537898483966, 11955.462004210005, 12074.698132546406, 12094.75810483803, 12149.1495595311, 7896.802579935318, 8312.168783121639, 8455.534945578052, 8472.534010629475, 8567.014793298851, 9801.607935682492, 11628.244164129304, 11921.582744603897, 11937.761244775229, 11943.522259109886], "value": 9826.497494807025, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1564.022485946284, 1597.2333280025587, 1747.0, 1821.5628249220183, 1939.2824654877697, 8572.057073721886, 8801.735947921557, 8989.325800564968, 9010.053944335841, 9058.0, 7797.4931629444045, 8065.596720163983, 8421.663133474654, 8681.609327580258, 8786.12138786121, 2347.671326014355, 2390.1873363056557, 2394.150076722756, 2395.2215529952746, 2396.6045602475656, 2364.113457453455, 2367.9052837886466, 2417.0, 2417.2506522977933, 2418.4316685578874, 2032.2379107834563, 2125.2774056820676, 2198.230619283246, 2321.8839058047115, 2405.1341517053847, 2379.7501262367377, 2382.3805810489225, 2405.9157929472385, 2411.5659181347264, 2419.7701218384227, 2375.928722138339, 2392.114917480526, 2396.352984694123, 2399.7240317363553, 2407.0492155598618, 9015.41399809017, 9022.548872556412, 9034.638614455414, 9035.909640903572, 9049.592768325376, 5216.374035115772, 5344.011357898801, 5393.5954803389905, 5412.431694672042, 5414.377346605152, 3503.3343664703625, 3639.5086663300644, 3653.378925582631, 3717.126475278307, 3719.3119272934323, 1061.3737894642197, 1087.8694556653172, 1092.071739021833, 1098.9010989010947, 1104.1222228328497, 11667.766644667145, 11705.414729263503, 11718.648440546796, 11730.002949749281, 11738.647840564796, 4565.3836732040745, 4616.1229366420275, 4818.084563932842, 4823.855284341475, 4834.444038935497, 9912.405255684538, 9947.403155810674, 9954.950225248827, 9961.55173017216, 9967.601295948225, 5363.0, 5371.57027437804, 5374.946250537445, 5384.892302154013, 5388.434214407509, 4808.543188397097, 4853.95146048535, 4911.214205727067, 4916.459189489154, 4918.286848407032, 3885.2812229737588, 3952.308346039489, 3960.2277555876326, 4019.3569028955226, 4077.4903137107863, 980.4166520920041, 1076.5478499030426, 1240.3364200152876, 1290.7741145299535, 1294.1587967820803, 743.6802175064666, 928.9163975242126, 928.9163975242126, 934.3412893909777, 953.9284553658504, 5908.645481271137, 5944.375840536768, 6009.819705408845, 6056.939430605726, 6170.10533472644, 923.35826600512, 940.7742141886035, 960.6397600899662, 984.1241295247092, 1036.2849633752687, 3955.861544845917, 4055.0876052887866, 4131.710780245354, 4139.0, 4146.896327591834, 10322.690319290434, 10457.581696732197, 10726.302790318605, 10728.463576821128, 10972.780544389068, 10012.0, 10734.677959661223, 10881.83677244842, 10901.182411319183, 10904.890951090387, 7750.534967901831, 8244.381671374671, 8256.050554186346, 8342.791430214173, 8367.037790654153, 5885.73514191871, 5968.671723055274, 6005.609635373687, 6121.47967422774, 6202.906956395567, 9712.562934667816, 10268.845967310646, 10269.948650256702, 10285.382877027403, 10300.690979270634, 10231.28381013322, 10334.638287659896, 10380.117689996436, 10382.948085259526, 10386.221033422373, 7370.44721645868, 7550.622468876424, 7582.317591416799, 7743.806404839699, 7761.262680045386, 8549.700760473354, 8597.097304782908, 8665.913340866511, 8666.696665616926, 8683.565821708762, 7444.478886478111, 7588.696452142073, 7708.190639982721, 7865.528068315919, 7874.960625196837, 7321.633918303957, 7462.179160292255, 7499.700011999459, 7587.165411804586, 7616.961915190389, 7080.398166155728, 7115.75094871677, 7145.785626431215, 7163.176234733072, 7164.247753985965, 4850.587700045395, 5114.693118412907, 5118.462561430996, 5123.436421993652, 5151.5106064923775, 5003.774075351562, 5031.471695471922, 5104.0, 5128.487151284841, 5271.551918086852], "value": 4651.790207511531, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.5342242812426, 307.30702266389295, 312.38460233340317, 317.9395914776192, 321.87768647913816, 4401.581849724279, 4835.178019736644, 5446.210299506569, 5451.781928722851, 5463.508284254416, 4472.373867658531, 4918.22120501917, 5427.213054107152, 5433.347998240207, 5484.204790305414, 3568.947160587633, 4047.575004624516, 4050.8379664813374, 4130.834766609332, 4202.2435961526835, 3818.3890577507605, 3896.5129358830145, 3926.254011737775, 4158.521769996444, 4185.7560390697145, 3403.1662242750435, 3427.0, 3801.061458656078, 3974.602539746031, 4121.505419349683, 4094.3858421236905, 4110.77390743509, 4116.629503344683, 4139.441175441337, 4161.334186530171, 3601.7478776485655, 3601.8559257629663, 3604.279144171176, 3605.8461292386446, 3617.5297211362617, 4566.789800702783, 4659.157913672657, 4883.19427294496, 4886.924876527159, 5279.683219006872, 3913.25648126855, 4193.727407718488, 4341.175176716445, 4680.297955306697, 4707.741074240916, 4431.8670439886855, 4962.528559786814, 5289.074411977888, 5715.714214289311, 5780.924448263964, 3726.496922915426, 3953.604639536052, 3957.28768821612, 3979.602039796026, 4005.339119045354, 4773.260144677581, 5369.221462887859, 5902.498287645555, 5923.555733320019, 5975.446383940163, 3879.359905615598, 4010.7393019453643, 4011.217812526529, 4055.918881622351, 4082.795860206978, 3913.2760439318818, 4028.140020585359, 4076.205139997672, 4095.3037983542868, 4107.794610269475, 4200.87397378073, 4513.345564893072, 4691.859244222679, 4725.10223057618, 4729.597984171315, 4533.750643714628, 5238.528532432099, 5566.610337276352, 5753.827385178452, 5756.740946657326, 1187.625897842177, 1279.8144269081117, 1319.6955001028687, 1360.6598350412396, 1369.8904087672963, 845.691322667222, 856.1267507142679, 889.8620713789373, 916.3951791817368, 921.6129225725211, 4457.866264012085, 5297.390800057966, 5310.309659744208, 5328.600354973392, 5353.732313384316, 4966.453690094087, 5869.354371019184, 5898.14476900847, 5901.793437229762, 5913.586048976616, 4760.64295177863, 5036.370453693288, 5453.0, 5458.208559758813, 5485.451454854562, 2596.5070084701374, 2934.7652187824924, 3130.906072817819, 3140.795848269855, 3144.7326977206735, 5661.46216109469, 5747.971260143672, 5847.44449277318, 6159.536185525736, 6204.193454850841, 6047.758089676452, 6071.878562428727, 6077.301110372364, 6106.1606353046045, 6125.0, 5442.836714898559, 6101.298350689639, 6149.2313460817395, 6169.2288463942, 6337.2395312562785, 4973.761807186708, 5093.740006395984, 5531.834044978657, 5989.8203053908455, 6027.36712645166, 5157.994191132693, 5859.882802344015, 5899.557533184942, 5917.556183286185, 5917.970410147922, 5274.920876186783, 6148.508119350352, 6174.536909731878, 6191.659458729813, 6213.37866213375, 3126.671699471614, 3924.3328634131694, 3934.5212516114666, 3950.427188057773, 3985.48551550409, 5384.542313903362, 5819.592628515963, 5853.385394533512, 5853.9121913172185, 5865.0, 4565.680402371802, 5250.369955605352, 5543.972280138573, 5582.0, 5712.657525481592, 5709.65742055478, 5736.68448235351, 5749.051406517878, 5768.7404066817935, 5800.433882851691, 5605.439456054362, 5616.1014237721765, 5624.35319938196, 5639.2668953035845, 5708.0, 5356.812511562077, 5819.388964158702, 5864.853378665397, 5869.178315035813, 5871.007714754009, 5038.319826823332, 5074.949250507595, 5717.371089180104, 5736.856578585569, 5759.683217423082], "value": 4155.3131872111735, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.2627457247881, 756.2362014365489, 789.6209819286743, 808.4381354958302, 823.4770920465503, 14816.185109818969, 15928.522144335673, 15942.282597283122, 15986.200689965499, 15991.0, 11904.821427678591, 14522.636934076627, 14558.05372650777, 14561.92719036406, 14856.702865942674, 670.51722759613, 884.8849649545582, 886.7029545102382, 893.499640201486, 895.3463971300954, 735.224338323069, 885.9689910853122, 901.2789768185426, 918.2149262380635, 921.5668635741191, 958.9856152157723, 959.7120863740885, 960.0255740423476, 960.7502049467118, 962.1436921140191, 752.9774106776805, 929.665320484625, 952.1573407534313, 953.2659851914023, 955.8709574207462, 703.4161645833946, 706.5407485134652, 708.550070705106, 710.8578284343153, 714.4534431160166, 14991.775123373158, 15051.849481505154, 15052.172130532816, 15079.0, 15095.47165849201, 13900.096493727875, 14091.365888534938, 14091.788623170558, 14321.641958951006, 14371.568852934428, 12721.745565088646, 12742.936285318514, 12789.936050319782, 13001.284929328884, 13330.533431329857, 4566.954330456686, 4591.8392856249875, 4685.437747470325, 4738.620910327166, 4749.860033591913, 109284.0, 111220.0, 111308.33037504443, 111468.0, 111540.32689509664, 9466.526673666289, 11030.44847757625, 11047.944760276308, 11457.312561246352, 11556.133290003283, 21888.12447502114, 21889.233876814553, 21891.343259702233, 21903.890480547496, 21913.890430548065, 7683.308502234826, 7817.8827317590285, 8002.8799568006525, 8039.598020098972, 8086.272235498834, 4458.197524445566, 4484.484284307281, 4495.662825288116, 4583.175028494903, 4624.12141693084, 3441.9655803441647, 3544.929101418009, 3554.91112722184, 3559.2703495783303, 3563.376409128392, 659.3274859643137, 684.4455990647591, 685.4242436353487, 732.9670164842595, 738.4572339330588, 538.7898719499399, 675.9256481786999, 697.8778713725179, 709.7154150986628, 714.4641518860855, 7683.846323073619, 8665.480071195749, 9281.72154835356, 9505.619775208914, 9565.569549370295, 532.4941305759544, 609.9939000610026, 639.4085470939362, 666.5933780393956, 686.3376841348023, 4521.525239849834, 4680.759598706346, 4708.457987438799, 4734.73958932262, 4775.355327030807, 106368.0, 108910.0, 142244.0, 160635.0, 166153.0, 106728.46635766771, 138457.0, 138931.0, 139134.30432847975, 141583.29208353892, 115873.841261588, 116515.41742291235, 117801.0, 117997.41001294939, 120914.79085209212, 8915.375923685539, 8938.50838203905, 8938.50838203905, 8939.150780675825, 9088.63645454201, 47396.0, 53081.0, 54587.0, 55058.44941550533, 56195.0, 68211.0, 79952.0, 91354.0, 95026.76817411516, 98937.0, 11339.716507087389, 13419.731605367642, 13504.797428038784, 13651.79522307186, 13732.588022359345, 15127.830714020542, 15139.243037848284, 15174.393024278905, 15183.316750746022, 15569.76645350298, 14094.083884547472, 14107.012509124263, 14132.78800817968, 14139.43442262298, 14172.858271417153, 13875.514356997457, 13952.511662091778, 13973.930130349283, 14132.434702612192, 14610.926945365205, 13260.867391325963, 13370.799438008244, 13429.529966451128, 13434.462621495031, 13536.79694804559, 9713.320067595201, 11021.779564408827, 11178.217524773188, 11464.049231136352, 11718.781119299148, 10790.170659338508, 11318.151138664469, 11381.772364552828, 11606.941965290458, 11648.301101933912], "value": 7939.055839024556, "unit": "score"}], "name": "dromaeo_css", "value": 7474.877633631426, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 111.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 102.0, 158.0, 112.0, 107.0, 128.0, 106.0, 139.0, 108.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 183.0, 181.0, 182.0, 185.0, 179.0, 182.0, 182.0, 184.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 56.0, 104.0, 57.0, 57.0, 59.0, 66.0, 94.0, 56.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 90.0, 91.0, 71.0, 72.0, 68.0, 98.0, 70.0, 68.0, 101.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 89.0, 87.0, 91.0, 88.0, 94.0, 91.0, 91.0, 87.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 92.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 86.0, 86.0, 85.0, 82.0, 84.0, 84.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 55.0, 54.0, 55.0, 53.0, 53.0, 55.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 72.0, 77.0, 72.0, 69.0, 70.0, 68.0, 68.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 83.0, 82.0, 84.0, 77.0, 82.0, 86.0, 86.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 141.0, 146.0, 150.0, 142.0, 139.0, 154.0, 121.0, 234.0, 158.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 171.0, 164.0, 161.0, 162.0, 164.0, 161.0, 162.0, 161.0, 163.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 66.0, 67.0, 65.0, 70.0, 85.0, 85.0, 71.0, 66.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1428.5, "alertThreshold": 2.0}]} 11:55:39 INFO - Return code: 0 11:55:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:55:39 INFO - # TBPL SUCCESS # 11:55:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:55:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:55:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:55:39 INFO - 2016-07-06 11:55:39.896 screenresolution[1665:9594] starting screenresolution argv=screenresolution get 11:55:39 INFO - 2016-07-06 11:55:39.899 screenresolution[1665:9594] Display 0: 1600x1200x32@60 11:55:39 INFO - 2016-07-06 11:55:39.905 screenresolution[1666:9597] starting screenresolution argv=screenresolution list 11:55:39 INFO - Available Modes on Display 0 11:55:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:55:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:55:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:55:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:55:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:55:40 INFO - Intel Iris: 11:55:40 INFO - Chipset Model: Intel Iris 11:55:40 INFO - Type: GPU 11:55:40 INFO - Bus: Built-In 11:55:40 INFO - VRAM (Dynamic, Max): 1536 MB 11:55:40 INFO - Vendor: Intel (0x8086) 11:55:40 INFO - Device ID: 0x0a2e 11:55:40 INFO - Revision ID: 0x0009 11:55:40 INFO - Displays: 11:55:40 INFO - Display: 11:55:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:55:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:55:40 INFO - Display Serial Number: 1600x1200 60 11:55:40 INFO - Main Display: Yes 11:55:40 INFO - Mirror: Off 11:55:40 INFO - Online: Yes 11:55:40 INFO - Rotation: Supported 11:55:40 INFO - Return code: 0 11:55:40 INFO - Running post-action listener: _resource_record_post_action 11:55:40 INFO - ##### 11:55:40 INFO - ##### Finished run-tests step (success) 11:55:40 INFO - ##### 11:55:40 INFO - Running post-run listener: _resource_record_post_run 11:55:40 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36984320; Write bytes: 706123264; Read time: 239; Write time: 1945 11:55:40 INFO - TinderboxPrint: CPU usage
53.6% 11:55:40 INFO - TinderboxPrint: I/O read bytes / time
36,984,320 / 239 11:55:40 INFO - TinderboxPrint: I/O write bytes / time
706,123,264 / 1,945 11:55:40 INFO - TinderboxPrint: CPU idle
2,247.7 (46.4%) 11:55:40 INFO - TinderboxPrint: CPU system
969.5 (20.0%) 11:55:40 INFO - TinderboxPrint: CPU user
1,628.4 (33.6%) 11:55:40 INFO - TinderboxPrint: Swap in / out
443,420,672 / 0 11:55:40 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392165888; Write bytes: 387745280; Read time: 29386; Write time: 797 11:55:40 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36029952; Write bytes: 295346176; Read time: 227; Write time: 1093 11:55:40 INFO - Running post-run listener: _upload_blobber_files 11:55:40 INFO - Blob upload gear active. 11:55:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:55:40 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 11:55:40 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:55:40 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:55:41 INFO - (blobuploader) - INFO - Open directory for files ... 11:55:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:55:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:55:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:55:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:55:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:55:41 INFO - (blobuploader) - INFO - Done attempting. 11:55:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:55:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:55:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:55:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:55:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:55:42 INFO - (blobuploader) - INFO - Done attempting. 11:55:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:55:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:55:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:55:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:55:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:55:45 INFO - (blobuploader) - INFO - Done attempting. 11:55:45 INFO - (blobuploader) - INFO - Iteration through files over. 11:55:45 INFO - Return code: 0 11:55:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:55:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:55:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fff0445afc0059474728d903ef056af86bad508d72267c8e745ed112d4d9f4f893b73f6861c0ff0d95f3821dc5e63a2699d81b7bb8d55a1d163cdb61072ad513", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21d7322179496d5cfb2534cc45a612d982ce8dc6c8a476f54454607af4c1adc9ae33407130a7181d447242d44eed74ae8f4101acd54a9d06f8085e99fead87f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d734c54eca93d1afc12609da0a4ffd4111d87e397bb0713aef5eef2146bd8f554d42d55422904eb9b04977ad53b84d0457c06639b43d1df0a31dc8cb19e0b745"} 11:55:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:55:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:55:45 INFO - Contents: 11:55:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fff0445afc0059474728d903ef056af86bad508d72267c8e745ed112d4d9f4f893b73f6861c0ff0d95f3821dc5e63a2699d81b7bb8d55a1d163cdb61072ad513", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21d7322179496d5cfb2534cc45a612d982ce8dc6c8a476f54454607af4c1adc9ae33407130a7181d447242d44eed74ae8f4101acd54a9d06f8085e99fead87f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d734c54eca93d1afc12609da0a4ffd4111d87e397bb0713aef5eef2146bd8f554d42d55422904eb9b04977ad53b84d0457c06639b43d1df0a31dc8cb19e0b745"} 11:55:45 INFO - Running post-run listener: copy_logs_to_upload_dir 11:55:45 INFO - Copying logs to upload dir... 11:55:45 INFO - mkdir: /builds/slave/test/build/upload/logs 11:55:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.732328 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-06 11:55:45.236428) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 11:55:45.240410) ========= 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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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/Autoland/sha512/fff0445afc0059474728d903ef056af86bad508d72267c8e745ed112d4d9f4f893b73f6861c0ff0d95f3821dc5e63a2699d81b7bb8d55a1d163cdb61072ad513", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21d7322179496d5cfb2534cc45a612d982ce8dc6c8a476f54454607af4c1adc9ae33407130a7181d447242d44eed74ae8f4101acd54a9d06f8085e99fead87f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d734c54eca93d1afc12609da0a4ffd4111d87e397bb0713aef5eef2146bd8f554d42d55422904eb9b04977ad53b84d0457c06639b43d1df0a31dc8cb19e0b745"} build_url:https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011041 build_url: 'https://queue.taskcluster.net/v1/task/Dl7C7DtsSeWiPGw3TtRPvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fff0445afc0059474728d903ef056af86bad508d72267c8e745ed112d4d9f4f893b73f6861c0ff0d95f3821dc5e63a2699d81b7bb8d55a1d163cdb61072ad513", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21d7322179496d5cfb2534cc45a612d982ce8dc6c8a476f54454607af4c1adc9ae33407130a7181d447242d44eed74ae8f4101acd54a9d06f8085e99fead87f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d734c54eca93d1afc12609da0a4ffd4111d87e397bb0713aef5eef2146bd8f554d42d55422904eb9b04977ad53b84d0457c06639b43d1df0a31dc8cb19e0b745"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 11:55:45.272482) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 11:55:45.272792) ========= 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.aXxJRVbqcB/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.y9w9RgoFQh/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.004847 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 11:55:45.320714) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 11:55:45.321011) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 11:55:45.321342) ========= ========= Total master_lag: 3.84 =========