builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0272 starttime: 1481864884.73 results: success (0) buildid: 20161215192549 builduid: b8ffb2c6dc114a1aa896e5640d203fb3 revision: ebfe8f2907a38844076d9040d3b5df49cbae46d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:04.730756) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:04.731230) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:04.731519) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:04.759092) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:04.759376) ========= 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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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-12-15 21:08:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.9M=0s 2016-12-15 21:08:04 (28.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103578 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:04.886480) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:04.886813) ========= 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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.024945 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:04.938777) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-15 21:08:04.942174) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ebfe8f2907a38844076d9040d3b5df49cbae46d9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ebfe8f2907a38844076d9040d3b5df49cbae46d9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lGcAb1Py2s/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.wKOYbC0iwN/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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-12-15 21:08:05,041 truncating revision to first 12 chars 2016-12-15 21:08:05,041 Setting DEBUG logging. 2016-12-15 21:08:05,041 attempt 1/10 2016-12-15 21:08:05,041 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ebfe8f2907a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-15 21:08:05,967 unpacking tar archive at: mozilla-aurora-ebfe8f2907a3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.194002 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-15 21:08:06.159628) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:06.162998) ========= script_repo_revision: ebfe8f2907a38844076d9040d3b5df49cbae46d9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:06.163406) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:06.163670) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 21:08:06.183041) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-15 21:08:06.183358) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lGcAb1Py2s/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.wKOYbC0iwN/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:08:06 INFO - MultiFileLogger online at 20161215 21:08:06 in /builds/slave/test 21:08:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 21:08:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:08:06 INFO - {'append_to_log': False, 21:08:06 INFO - 'base_work_dir': '/builds/slave/test', 21:08:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:08:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:08:06 INFO - 'branch': 'Mozilla-Aurora', 21:08:06 INFO - 'buildbot_json_path': 'buildprops.json', 21:08:06 INFO - 'config_files': ('talos/mac_config.py',), 21:08:06 INFO - 'default_actions': ('clobber', 21:08:06 INFO - 'read-buildbot-config', 21:08:06 INFO - 'download-and-extract', 21:08:06 INFO - 'populate-webroot', 21:08:06 INFO - 'create-virtualenv', 21:08:06 INFO - 'install', 21:08:06 INFO - 'run-tests'), 21:08:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:08:06 INFO - 'download_minidump_stackwalk': True, 21:08:06 INFO - 'download_symbols': 'ondemand', 21:08:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:08:06 INFO - 'tooltool.py': '/tools/tooltool.py', 21:08:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:08:06 INFO - '/tools/misc-python/virtualenv.py')}, 21:08:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:08:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:08:06 INFO - 'installer_path': 'installer.exe', 21:08:06 INFO - 'log_level': 'info', 21:08:06 INFO - 'log_name': 'talos', 21:08:06 INFO - 'log_to_console': True, 21:08:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:08:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:08:06 INFO - 'opt_config_files': (), 21:08:06 INFO - 'pip_index': False, 21:08:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:08:06 INFO - 'cmd': ('bash', 21:08:06 INFO - '-c', 21:08:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:08:06 INFO - 'enabled': True, 21:08:06 INFO - 'halt_on_failure': False, 21:08:06 INFO - 'name': 'check_screen_resolution'},), 21:08:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:08:06 INFO - 'cmd': ('bash', 21:08:06 INFO - '-c', 21:08:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:08:06 INFO - 'enabled': True, 21:08:06 INFO - 'halt_on_failure': False, 21:08:06 INFO - 'name': 'check_screen_resolution'},), 21:08:06 INFO - 'run_cmd_checks_enabled': True, 21:08:06 INFO - 'sps_profile': False, 21:08:06 INFO - 'sps_profile_interval': 0, 21:08:06 INFO - 'suite': 'dromaeojs-e10s', 21:08:06 INFO - 'system_bits': '32', 21:08:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:08:06 INFO - 'title': 't-yosemite-r7-0272', 21:08:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:08:06 INFO - 'use_talos_json': True, 21:08:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:08:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:08:06 INFO - 'work_dir': 'build'} 21:08:06 INFO - [mozharness: 2016-12-16 05:08:06.390037Z] Running clobber step. 21:08:06 INFO - Running pre-action listener: _resource_record_pre_action 21:08:06 INFO - Running main action method: clobber 21:08:06 INFO - rmtree: /builds/slave/test/build 21:08:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:08:08 INFO - Running post-action listener: _resource_record_post_action 21:08:08 INFO - [mozharness: 2016-12-16 05:08:08.693332Z] Finished clobber step (success) 21:08:08 INFO - [mozharness: 2016-12-16 05:08:08.693493Z] Running read-buildbot-config step. 21:08:08 INFO - Running pre-action listener: _resource_record_pre_action 21:08:08 INFO - Running main action method: read_buildbot_config 21:08:08 INFO - Using buildbot properties: 21:08:08 INFO - { 21:08:08 INFO - "project": "", 21:08:08 INFO - "product": "firefox", 21:08:08 INFO - "script_repo_revision": "production", 21:08:08 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 21:08:08 INFO - "repository": "", 21:08:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 21:08:08 INFO - "buildid": "20161215192549", 21:08:08 INFO - "pgo_build": "False", 21:08:08 INFO - "basedir": "/builds/slave/test", 21:08:08 INFO - "buildnumber": 9, 21:08:08 INFO - "slavename": "t-yosemite-r7-0272", 21:08:08 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:08:08 INFO - "platform": "yosemite_r7", 21:08:08 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 21:08:08 INFO - "branch": "mozilla-aurora", 21:08:08 INFO - "repo_path": "releases/mozilla-aurora", 21:08:08 INFO - "revision": "ebfe8f2907a38844076d9040d3b5df49cbae46d9", 21:08:08 INFO - "stage_platform": "macosx64", 21:08:08 INFO - "builduid": "b8ffb2c6dc114a1aa896e5640d203fb3", 21:08:08 INFO - "slavebuilddir": "test" 21:08:08 INFO - } 21:08:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 21:08:08 INFO - Running post-action listener: _resource_record_post_action 21:08:08 INFO - [mozharness: 2016-12-16 05:08:08.696778Z] Finished read-buildbot-config step (success) 21:08:08 INFO - [mozharness: 2016-12-16 05:08:08.696893Z] Running download-and-extract step. 21:08:08 INFO - Running pre-action listener: _resource_record_pre_action 21:08:08 INFO - Running main action method: download_and_extract 21:08:08 INFO - mkdir: /builds/slave/test/build/tests 21:08:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:08 INFO - trying https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 21:08:08 INFO - Downloading https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 21:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 21:08:09 INFO - Downloaded 1199 bytes. 21:08:09 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 21:08:09 INFO - Using the following test package requirements: 21:08:09 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 21:08:09 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:08:09 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 21:08:09 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:08:09 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 21:08:09 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:08:09 INFO - u'jsshell-mac.zip'], 21:08:09 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:08:09 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 21:08:09 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 21:08:09 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:08:09 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 21:08:09 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:08:09 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 21:08:09 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:08:09 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 21:08:09 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:08:09 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 21:08:09 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 21:08:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 21:08:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:08:09 INFO - Fetch https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 21:08:10 INFO - Http code: 200 21:08:10 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 21:08:10 INFO - x-amz-cf-id: O_Cew9HxHxLqeJkrDnKDzDLar-2BAGFoT_IgXhAQeXScre7KApuxFQ== 21:08:10 INFO - x-amz-version-id: ZUcQcKU28vxCNrhyqnDuRWURXLIRsech 21:08:10 INFO - Expected file size: 16280324 21:08:10 INFO - Obtained file size: 16280324 21:08:17 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 21:08:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 21:08:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:08:17 INFO - Fetch https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 21:08:17 INFO - Http code: 200 21:08:17 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 21:08:17 INFO - x-amz-cf-id: USwvsTm1kk5dRAq_zIM-CVHZr--ahp3QZHNEXagum6nhc3pNW2UxGw== 21:08:17 INFO - x-amz-version-id: ZUcQcKU28vxCNrhyqnDuRWURXLIRsech 21:08:18 INFO - Expected file size: 16280324 21:08:18 INFO - Obtained file size: 16280324 21:08:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 21:08:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 21:08:24 INFO - Fetch https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 21:08:25 INFO - Http code: 200 21:08:25 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 21:08:25 INFO - x-amz-cf-id: 5fk5eEu4pcroRaA56kTy65WPNFkY6LbjuioikdsIhhwlpZuxio-r5Q== 21:08:25 INFO - x-amz-version-id: kmmeugKn6OHPkVWm8TgfyAuEIOCKJtV8 21:08:25 INFO - Expected file size: 14409038 21:08:25 INFO - Obtained file size: 14409038 21:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:26 INFO - trying https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 21:08:26 INFO - Downloading https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 21:08:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 21:08:28 INFO - Downloaded 109723611 bytes. 21:08:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 21:08:28 INFO - mkdir: /builds/slave/test/properties 21:08:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:08:28 INFO - Writing to file /builds/slave/test/properties/build_url 21:08:28 INFO - Contents: 21:08:28 INFO - build_url:https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 21:08:30 INFO - Running post-action listener: _resource_record_post_action 21:08:30 INFO - Running post-action listener: set_extra_try_arguments 21:08:30 INFO - [mozharness: 2016-12-16 05:08:30.025436Z] Finished download-and-extract step (success) 21:08:30 INFO - [mozharness: 2016-12-16 05:08:30.025598Z] Running populate-webroot step. 21:08:30 INFO - Running pre-action listener: _resource_record_pre_action 21:08:30 INFO - Running main action method: populate_webroot 21:08:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:08:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:08:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:08:30 INFO - '--tppagecycles', 21:08:30 INFO - '7'], 21:08:30 INFO - 'tests': ['tsvgm']}}, 21:08:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:08:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:08:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:08:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:08:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:30 INFO - 'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:30 INFO - 'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['damp', 'tps']}, 21:08:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:30 INFO - 'tests': ['damp', 'tps']}, 21:08:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['dromaeo_dom']}, 21:08:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:08:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['basic_compositor_video']}, 21:08:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:08:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['a11yr', 21:08:30 INFO - 'ts_paint', 21:08:30 INFO - 'tpaint', 21:08:30 INFO - 'sessionrestore', 21:08:30 INFO - 'sessionrestore_no_auto_restore', 21:08:30 INFO - 'tabpaint']}, 21:08:30 INFO - 'other-e10s': {'tests': ['a11yr', 21:08:30 INFO - 'ts_paint', 21:08:30 INFO - 'tpaint', 21:08:30 INFO - 'sessionrestore', 21:08:30 INFO - 'sessionrestore_no_auto_restore', 21:08:30 INFO - 'tabpaint']}, 21:08:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:08:30 INFO - 'ts_paint', 21:08:30 INFO - 'tpaint', 21:08:30 INFO - 'sessionrestore', 21:08:30 INFO - 'sessionrestore_no_auto_restore', 21:08:30 INFO - 'tabpaint']}, 21:08:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:08:30 INFO - 'ts_paint', 21:08:30 INFO - 'tpaint', 21:08:30 INFO - 'sessionrestore', 21:08:30 INFO - 'sessionrestore_no_auto_restore', 21:08:30 INFO - 'tabpaint']}, 21:08:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['a11yr', 21:08:30 INFO - 'ts_paint', 21:08:30 INFO - 'tpaint', 21:08:30 INFO - 'sessionrestore', 21:08:30 INFO - 'sessionrestore_no_auto_restore', 21:08:30 INFO - 'tabpaint']}, 21:08:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['a11yr', 21:08:30 INFO - 'ts_paint', 21:08:30 INFO - 'tpaint', 21:08:30 INFO - 'sessionrestore', 21:08:30 INFO - 'sessionrestore_no_auto_restore', 21:08:30 INFO - 'tabpaint']}, 21:08:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['tsvgx', 21:08:30 INFO - 'tsvgr_opacity', 21:08:30 INFO - 'tart', 21:08:30 INFO - 'tscrollx', 21:08:30 INFO - 'cart']}, 21:08:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:08:30 INFO - 'tsvgr_opacity', 21:08:30 INFO - 'tart', 21:08:30 INFO - 'tscrollx', 21:08:30 INFO - 'cart']}, 21:08:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:30 INFO - 'talos_options': ['--disable-e10s'], 21:08:30 INFO - 'tests': ['tp5o']}, 21:08:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:30 INFO - 'tests': ['tp5o']}, 21:08:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:30 INFO - 'talos_options': ['--disable-e10s', 21:08:30 INFO - '--xperf_path', 21:08:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:30 INFO - 'tests': ['tp5n']}, 21:08:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:30 INFO - 'talos_options': ['--xperf_path', 21:08:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:30 INFO - 'tests': ['tp5n']}}, 21:08:30 INFO - 'talos.zip': {'path': '', 21:08:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:08:30 INFO - Running post-action listener: _resource_record_post_action 21:08:30 INFO - [mozharness: 2016-12-16 05:08:30.044650Z] Finished populate-webroot step (success) 21:08:30 INFO - [mozharness: 2016-12-16 05:08:30.044784Z] Running create-virtualenv step. 21:08:30 INFO - Running pre-action listener: _resource_record_pre_action 21:08:30 INFO - Running main action method: create_virtualenv 21:08:30 INFO - Creating virtualenv /builds/slave/test/build/venv 21:08:30 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 21:08:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:08:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:08:30 INFO - Using real prefix '/tools/python27' 21:08:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:08:30 INFO - Installing distribute.............................................................................................................................................................................................done. 21:08:33 INFO - Installing pip.................done. 21:08:33 INFO - Return code: 0 21:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:08:34 INFO - Reading from file tmpfile_stdout 21:08:34 INFO - Output received: 21:08:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:08:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10209a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102010570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe0b4bf250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, '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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:08:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:08:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:34 INFO - 'HOME': '/Users/cltbld', 21:08:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:34 INFO - 'LOGNAME': 'cltbld', 21:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:34 INFO - 'MOZ_NO_REMOTE': '1', 21:08:34 INFO - 'NO_EM_RESTART': '1', 21:08:34 INFO - 'PAGER': '/bin/cat', 21:08:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:34 INFO - 'PWD': '/builds/slave/test', 21:08:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:34 INFO - 'SHELL': '/bin/bash', 21:08:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:08:34 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:08:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:34 INFO - 'USER': 'cltbld', 21:08:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:34 INFO - 'XPC_FLAGS': '0x0', 21:08:34 INFO - 'XPC_SERVICE_NAME': '0', 21:08:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:34 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 21:08:34 INFO - Cleaning up... 21:08:34 INFO - Return code: 0 21:08:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10209a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102010570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe0b4bf250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, '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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:08:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:08:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:34 INFO - 'HOME': '/Users/cltbld', 21:08:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:34 INFO - 'LOGNAME': 'cltbld', 21:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:34 INFO - 'MOZ_NO_REMOTE': '1', 21:08:34 INFO - 'NO_EM_RESTART': '1', 21:08:34 INFO - 'PAGER': '/bin/cat', 21:08:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:34 INFO - 'PWD': '/builds/slave/test', 21:08:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:34 INFO - 'SHELL': '/bin/bash', 21:08:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:08:34 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:08:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:34 INFO - 'USER': 'cltbld', 21:08:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:34 INFO - 'XPC_FLAGS': '0x0', 21:08:34 INFO - 'XPC_SERVICE_NAME': '0', 21:08:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:34 INFO - Downloading/unpacking psutil>=3.1.1 21:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:08:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:37 INFO - Installing collected packages: psutil 21:08:37 INFO - Running setup.py install for psutil 21:08:38 INFO - building 'psutil._psutil_osx' extension 21:08:38 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 21:08:38 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 21:08:38 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 21:08:38 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 21:08:38 INFO - building 'psutil._psutil_posix' extension 21:08:38 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 21:08:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:08:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:08:38 INFO - ^ 21:08:38 INFO - 1 warning generated. 21:08:38 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 21:08:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:39 INFO - Successfully installed psutil 21:08:39 INFO - Cleaning up... 21:08:39 INFO - Return code: 0 21:08:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:39 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 0x10209a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102010570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe0b4bf250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, '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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:08:39 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 21:08:39 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 21:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:39 INFO - 'HOME': '/Users/cltbld', 21:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:39 INFO - 'LOGNAME': 'cltbld', 21:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:39 INFO - 'MOZ_NO_REMOTE': '1', 21:08:39 INFO - 'NO_EM_RESTART': '1', 21:08:39 INFO - 'PAGER': '/bin/cat', 21:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:39 INFO - 'PWD': '/builds/slave/test', 21:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:39 INFO - 'SHELL': '/bin/bash', 21:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:08:39 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:39 INFO - 'USER': 'cltbld', 21:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:39 INFO - 'XPC_FLAGS': '0x0', 21:08:39 INFO - 'XPC_SERVICE_NAME': '0', 21:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:08:39 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 21:08:39 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 21:08:39 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 21:08:39 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 21:08:39 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 21:08:39 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 21:08:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:08:41 INFO - Installing collected packages: mozsystemmonitor 21:08:41 INFO - Running setup.py install for mozsystemmonitor 21:08:42 INFO - Successfully installed mozsystemmonitor 21:08:42 INFO - Cleaning up... 21:08:42 INFO - Return code: 0 21:08:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:42 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 0x10209a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102010570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe0b4bf250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, '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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:08:42 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 21:08:42 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 21:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:42 INFO - 'HOME': '/Users/cltbld', 21:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:42 INFO - 'LOGNAME': 'cltbld', 21:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:42 INFO - 'MOZ_NO_REMOTE': '1', 21:08:42 INFO - 'NO_EM_RESTART': '1', 21:08:42 INFO - 'PAGER': '/bin/cat', 21:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:42 INFO - 'PWD': '/builds/slave/test', 21:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:42 INFO - 'SHELL': '/bin/bash', 21:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:08:42 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:42 INFO - 'USER': 'cltbld', 21:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:42 INFO - 'XPC_FLAGS': '0x0', 21:08:42 INFO - 'XPC_SERVICE_NAME': '0', 21:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:42 INFO - Downloading/unpacking jsonschema==2.5.1 21:08:42 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 21:08:42 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 21:08:42 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 21:08:42 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 21:08:42 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 21:08:42 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 21:08:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:08:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:45 INFO - Downloading functools32-3.2.3-2.tar.gz 21:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:08:45 INFO - warning: no files found matching '*.txt' 21:08:45 INFO - no previously-included directories found matching 'build' 21:08:45 INFO - no previously-included directories found matching 'dist' 21:08:45 INFO - no previously-included directories found matching '.git*' 21:08:45 INFO - Installing collected packages: jsonschema, functools32 21:08:45 INFO - Running setup.py install for functools32 21:08:45 INFO - warning: no files found matching '*.txt' 21:08:45 INFO - no previously-included directories found matching 'build' 21:08:45 INFO - no previously-included directories found matching 'dist' 21:08:45 INFO - no previously-included directories found matching '.git*' 21:08:45 INFO - Successfully installed jsonschema functools32 21:08:45 INFO - Cleaning up... 21:08:45 INFO - Return code: 0 21:08:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:08:45 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:45 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:45 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10209a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102010570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe0b4bf250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, '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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:08:45 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:08:45 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 functools32==3.2.3-2 21:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:45 INFO - 'HOME': '/Users/cltbld', 21:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:45 INFO - 'LOGNAME': 'cltbld', 21:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:45 INFO - 'MOZ_NO_REMOTE': '1', 21:08:45 INFO - 'NO_EM_RESTART': '1', 21:08:45 INFO - 'PAGER': '/bin/cat', 21:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:45 INFO - 'PWD': '/builds/slave/test', 21:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:45 INFO - 'SHELL': '/bin/bash', 21:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:08:45 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:45 INFO - 'USER': 'cltbld', 21:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:45 INFO - 'XPC_FLAGS': '0x0', 21:08:45 INFO - 'XPC_SERVICE_NAME': '0', 21:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:08:46 INFO - Cleaning up... 21:08:46 INFO - Return code: 0 21:08:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10209a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102010570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe0b4bf250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, '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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:08:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:08:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:08:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:46 INFO - 'HOME': '/Users/cltbld', 21:08:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:46 INFO - 'LOGNAME': 'cltbld', 21:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:46 INFO - 'MOZ_NO_REMOTE': '1', 21:08:46 INFO - 'NO_EM_RESTART': '1', 21:08:46 INFO - 'PAGER': '/bin/cat', 21:08:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:46 INFO - 'PWD': '/builds/slave/test', 21:08:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:46 INFO - 'SHELL': '/bin/bash', 21:08:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:08:46 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:08:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:46 INFO - 'USER': 'cltbld', 21:08:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:46 INFO - 'XPC_FLAGS': '0x0', 21:08:46 INFO - 'XPC_SERVICE_NAME': '0', 21:08:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:46 INFO - Downloading/unpacking blobuploader==1.2.4 21:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:48 INFO - Downloading blobuploader-1.2.4.tar.gz 21:08:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:08:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:08:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:50 INFO - Downloading docopt-0.6.1.tar.gz 21:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:08:50 INFO - Installing collected packages: blobuploader, requests, docopt 21:08:50 INFO - Running setup.py install for blobuploader 21:08:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:08:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:08:50 INFO - Running setup.py install for requests 21:08:50 INFO - Running setup.py install for docopt 21:08:51 INFO - Successfully installed blobuploader requests docopt 21:08:51 INFO - Cleaning up... 21:08:51 INFO - Return code: 0 21:08:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10209a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102010570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe0b4bf250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, '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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:51 INFO - 'HOME': '/Users/cltbld', 21:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:51 INFO - 'LOGNAME': 'cltbld', 21:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:51 INFO - 'MOZ_NO_REMOTE': '1', 21:08:51 INFO - 'NO_EM_RESTART': '1', 21:08:51 INFO - 'PAGER': '/bin/cat', 21:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:51 INFO - 'PWD': '/builds/slave/test', 21:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:51 INFO - 'SHELL': '/bin/bash', 21:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:08:51 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:51 INFO - 'USER': 'cltbld', 21:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:51 INFO - 'XPC_FLAGS': '0x0', 21:08:51 INFO - 'XPC_SERVICE_NAME': '0', 21:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:08:51 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-kl0G_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:08:51 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-jNohRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:08:51 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-b1SyDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:08:51 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-10oqWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:08:51 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-_vnaI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:08:51 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-g2PdxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:08:52 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-oqE50z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:08:52 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-bS3_qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:08:52 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-frKdR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:08:52 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-ZJhm9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:08:52 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-IB3GAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:08:52 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-C0Kt47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:08:52 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-h35ZHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:08:52 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-RYpoKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:52 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-fORmwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:08:52 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-JOVEUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:08:53 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-P0sVUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:08:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:08:53 INFO - Running setup.py install for manifestparser 21:08:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:08:53 INFO - Running setup.py install for mozcrash 21:08:53 INFO - Running setup.py install for mozdebug 21:08:53 INFO - Running setup.py install for mozdevice 21:08:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:08:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:08:53 INFO - Running setup.py install for mozfile 21:08:53 INFO - Running setup.py install for mozhttpd 21:08:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:08:54 INFO - Running setup.py install for mozinfo 21:08:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:08:54 INFO - Running setup.py install for mozInstall 21:08:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:08:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:08:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:08:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:08:54 INFO - Running setup.py install for mozleak 21:08:54 INFO - Running setup.py install for mozlog 21:08:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:08:54 INFO - Running setup.py install for moznetwork 21:08:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:08:54 INFO - Running setup.py install for mozprocess 21:08:54 INFO - Running setup.py install for mozprofile 21:08:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:08:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:08:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:08:55 INFO - Running setup.py install for mozrunner 21:08:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:08:55 INFO - Running setup.py install for mozscreenshot 21:08:55 INFO - Running setup.py install for moztest 21:08:55 INFO - Running setup.py install for mozversion 21:08:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:08:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:08:55 INFO - Cleaning up... 21:08:55 INFO - Return code: 0 21:08:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10209a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102010570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe0b4bf250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, '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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:08:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:55 INFO - 'HOME': '/Users/cltbld', 21:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:55 INFO - 'LOGNAME': 'cltbld', 21:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:55 INFO - 'MOZ_NO_REMOTE': '1', 21:08:55 INFO - 'NO_EM_RESTART': '1', 21:08:55 INFO - 'PAGER': '/bin/cat', 21:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:55 INFO - 'PWD': '/builds/slave/test', 21:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:55 INFO - 'SHELL': '/bin/bash', 21:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:08:55 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:55 INFO - 'USER': 'cltbld', 21:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:55 INFO - 'XPC_FLAGS': '0x0', 21:08:55 INFO - 'XPC_SERVICE_NAME': '0', 21:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:08:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-P7JLYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:08:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-IhXq95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 21:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:08:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-MSpq1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:08:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-4W7ZmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:08:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-aZ2UKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:08:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-IYMBXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:08:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-c5MHEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:08:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-HO9oDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:08:56 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)) 21:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:08:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-O24F3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:08:56 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)) 21:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:08:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-dcJybh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 21:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:08:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-nQrmxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:08:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-EM6L9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:08:57 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)) 21:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:08:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-b0nZmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:08:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-0lvd6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 21:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-PFnQ_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:08:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Mc2FnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:08:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-_ZIqOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:08:57 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)) 21:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:08:57 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)) 21:08:57 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)) 21:08:57 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)) 21:08:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:08:57 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 21:08:57 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 21:08:57 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 21:08:57 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 21:08:57 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 21:08:57 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 21:09:00 INFO - Downloading blessings-1.6.tar.gz 21:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:09:00 INFO - Installing collected packages: blessings 21:09:00 INFO - Running setup.py install for blessings 21:09:00 INFO - Successfully installed blessings 21:09:00 INFO - Cleaning up... 21:09:00 INFO - Return code: 0 21:09:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:09:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:09:01 INFO - Reading from file tmpfile_stdout 21:09:01 INFO - Current package versions: 21:09:01 INFO - blessings == 1.6 21:09:01 INFO - blobuploader == 1.2.4 21:09:01 INFO - docopt == 0.6.1 21:09:01 INFO - functools32 == 3.2.3-2 21:09:01 INFO - jsonschema == 2.5.1 21:09:01 INFO - manifestparser == 1.1 21:09:01 INFO - mozInstall == 1.12 21:09:01 INFO - mozcrash == 1.0 21:09:01 INFO - mozdebug == 0.1 21:09:01 INFO - mozdevice == 0.48 21:09:01 INFO - mozfile == 1.2 21:09:01 INFO - mozhttpd == 0.7 21:09:01 INFO - mozinfo == 0.9 21:09:01 INFO - mozleak == 0.1 21:09:01 INFO - mozlog == 3.4 21:09:01 INFO - moznetwork == 0.27 21:09:01 INFO - mozprocess == 0.23 21:09:01 INFO - mozprofile == 0.28 21:09:01 INFO - mozrunner == 6.13 21:09:01 INFO - mozscreenshot == 0.1 21:09:01 INFO - mozsystemmonitor == 0.3 21:09:01 INFO - moztest == 0.7 21:09:01 INFO - mozversion == 1.4 21:09:01 INFO - psutil == 3.1.1 21:09:01 INFO - requests == 1.2.3 21:09:01 INFO - wsgiref == 0.1.2 21:09:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10209a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102010570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe0b4bf250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, '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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:01 INFO - 'HOME': '/Users/cltbld', 21:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:01 INFO - 'LOGNAME': 'cltbld', 21:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:01 INFO - 'MOZ_NO_REMOTE': '1', 21:09:01 INFO - 'NO_EM_RESTART': '1', 21:09:01 INFO - 'PAGER': '/bin/cat', 21:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:01 INFO - 'PWD': '/builds/slave/test', 21:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:01 INFO - 'SHELL': '/bin/bash', 21:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:09:01 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:01 INFO - 'USER': 'cltbld', 21:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:01 INFO - 'XPC_FLAGS': '0x0', 21:09:01 INFO - 'XPC_SERVICE_NAME': '0', 21:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:02 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)) 21:09:02 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)) 21:09:02 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)) 21:09:02 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)) 21:09:02 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)) 21:09:02 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)) 21:09:02 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)) 21:09:02 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)) 21:09:02 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)) 21:09:02 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)) 21:09:02 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)) 21:09:02 INFO - Cleaning up... 21:09:02 INFO - Return code: 0 21:09:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:09:02 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:02 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:02 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 0x10209a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102010570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe0b4bf250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, '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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 21:09:02 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 21:09:02 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 21:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:02 INFO - 'HOME': '/Users/cltbld', 21:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:02 INFO - 'LOGNAME': 'cltbld', 21:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:02 INFO - 'MOZ_NO_REMOTE': '1', 21:09:02 INFO - 'NO_EM_RESTART': '1', 21:09:02 INFO - 'PAGER': '/bin/cat', 21:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:02 INFO - 'PWD': '/builds/slave/test', 21:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:02 INFO - 'SHELL': '/bin/bash', 21:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:09:02 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:02 INFO - 'USER': 'cltbld', 21:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:02 INFO - 'XPC_FLAGS': '0x0', 21:09:02 INFO - 'XPC_SERVICE_NAME': '0', 21:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:09:02 INFO - Cleaning up... 21:09:02 INFO - Return code: 0 21:09:02 INFO - Running post-action listener: _resource_record_post_action 21:09:02 INFO - Running post-action listener: _start_resource_monitoring 21:09:02 INFO - Starting resource monitoring. 21:09:02 INFO - [mozharness: 2016-12-16 05:09:02.861517Z] Finished create-virtualenv step (success) 21:09:02 INFO - [mozharness: 2016-12-16 05:09:02.862413Z] Running install step. 21:09:02 INFO - Running pre-action listener: _resource_record_pre_action 21:09:02 INFO - Running main action method: install 21:09:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:09:03 INFO - Reading from file tmpfile_stdout 21:09:03 INFO - Detecting whether we're running mozinstall >=1.0... 21:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:09:03 INFO - Reading from file tmpfile_stdout 21:09:03 INFO - Output received: 21:09:03 INFO - Usage: mozinstall [options] installer 21:09:03 INFO - Options: 21:09:03 INFO - -h, --help show this help message and exit 21:09:03 INFO - -d DEST, --destination=DEST 21:09:03 INFO - Directory to install application into. [default: 21:09:03 INFO - "/builds/slave/test"] 21:09:03 INFO - --app=APP Application being installed. [default: firefox] 21:09:03 INFO - mkdir: /builds/slave/test/build/application 21:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 21:09:35 INFO - Reading from file tmpfile_stdout 21:09:35 INFO - Output received: 21:09:35 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 21:09:35 INFO - Running post-action listener: _resource_record_post_action 21:09:35 INFO - [mozharness: 2016-12-16 05:09:35.081382Z] Finished install step (success) 21:09:35 INFO - [mozharness: 2016-12-16 05:09:35.081534Z] Running run-tests step. 21:09:35 INFO - Running pre-action listener: _resource_record_pre_action 21:09:35 INFO - Running main action method: run_tests 21:09:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:09:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:09:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:09:35 INFO - 2016-12-15 21:09:35.096 screenresolution[1624:7516] starting screenresolution argv=screenresolution get 21:09:35 INFO - 2016-12-15 21:09:35.098 screenresolution[1624:7516] Display 0: 1600x1200x32@60 21:09:35 INFO - 2016-12-15 21:09:35.104 screenresolution[1625:7518] starting screenresolution argv=screenresolution list 21:09:35 INFO - Available Modes on Display 0 21:09:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:09:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:09:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:09:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:09:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:09:35 INFO - Intel Iris: 21:09:35 INFO - Chipset Model: Intel Iris 21:09:35 INFO - Type: GPU 21:09:35 INFO - Bus: Built-In 21:09:35 INFO - VRAM (Dynamic, Max): 1536 MB 21:09:35 INFO - Vendor: Intel (0x8086) 21:09:35 INFO - Device ID: 0x0a2e 21:09:35 INFO - Revision ID: 0x0009 21:09:35 INFO - Displays: 21:09:35 INFO - Display: 21:09:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:09:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:09:35 INFO - Display Serial Number: 1600x1200 60 21:09:35 INFO - Main Display: Yes 21:09:35 INFO - Mirror: Off 21:09:35 INFO - Online: Yes 21:09:35 INFO - Rotation: Supported 21:09:35 INFO - Return code: 0 21:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:09:35 INFO - Python 2.7.3 21:09:35 INFO - Return code: 0 21:09:35 INFO - grabbing minidump binary from tooltool 21:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:35 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 0x101eaed30>, '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 0x101d14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:09:35 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 21:09:35 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 21:09:35 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 21:09:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:09:35 INFO - Return code: 0 21:09:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:09:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:09:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:09:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:09:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0272', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0272 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:09:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lGcAb1Py2s/Render', 21:09:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:35 INFO - 'HOME': '/Users/cltbld', 21:09:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:35 INFO - 'LOGNAME': 'cltbld', 21:09:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:09:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:35 INFO - 'MOZ_NO_REMOTE': '1', 21:09:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:09:35 INFO - 'NO_EM_RESTART': '1', 21:09:35 INFO - 'PAGER': '/bin/cat', 21:09:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:35 INFO - 'PWD': '/builds/slave/test', 21:09:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:09:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:35 INFO - 'SHELL': '/bin/bash', 21:09:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wKOYbC0iwN/Listeners', 21:09:35 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 21:09:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:35 INFO - 'USER': 'cltbld', 21:09:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:35 INFO - 'XPC_FLAGS': '0x0', 21:09:35 INFO - 'XPC_SERVICE_NAME': '0', 21:09:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0272', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:09:35 INFO - mozversion application_buildid: 20161215192549 21:09:35 INFO - mozversion application_changeset: ebfe8f2907a38844076d9040d3b5df49cbae46d9 21:09:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:09:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:09:35 INFO - mozversion application_name: Firefox 21:09:35 INFO - mozversion application_remotingname: firefox-dev 21:09:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:09:35 INFO - mozversion application_vendor: Mozilla 21:09:35 INFO - mozversion application_version: 52.0a2 21:09:35 INFO - mozversion platform_buildid: 20161215192549 21:09:35 INFO - mozversion platform_changeset: ebfe8f2907a38844076d9040d3b5df49cbae46d9 21:09:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:09:35 INFO - mozversion platform_version: 52.0a2 21:09:35 INFO - using testdate: 1481864975 21:09:35 INFO - actual date: 1481864975 21:09:35 INFO - starting webserver on 'localhost:49251' 21:09:35 INFO - SUITE-START | Running 2 tests 21:09:35 INFO - TEST-START | dromaeo_css 21:09:35 INFO - operating with platform_type : mac_ 21:09:35 INFO - Initialising browser for dromaeo_css test... 21:09:35 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmpa9bLnP/profile http://localhost:49251/getInfo.html) 21:09:38 INFO - PROCESS | 1635 | 2016-12-15 21:09:38.692 plugin-container[1637:7660] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 21:09:38 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:09:38 INFO - PROCESS | 1635 | 2016-12-15 21:09:38.696 plugin-container[1637:7660] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 21:09:38 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:09:38 INFO - PROCESS | 1635 | 2016-12-15 21:09:38.696 plugin-container[1637:7660] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:09:38 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 21:09:38 INFO - PROCESS | 1635 | colorDepth:24 21:09:38 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 21:09:38 INFO - PROCESS | 1635 | __metrics 21:09:38 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 21:09:39 INFO - TEST-INFO | 1635: exit 0 21:09:39 INFO - Browser initialized. 21:09:39 INFO - Running cycle 1/1 for dromaeo_css test... 21:09:39 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmpa9bLnP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:09:42 INFO - PROCESS | 1638 | 2016-12-15 21:09:42.436 plugin-container[1639:7785] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 21:09:42 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:09:42 INFO - PROCESS | 1638 | 2016-12-15 21:09:42.437 plugin-container[1639:7785] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 21:09:42 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:09:42 INFO - PROCESS | 1638 | 2016-12-15 21:09:42.437 plugin-container[1639:7785] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:09:51 INFO - PROCESS | 1638 | RSS: Main: 229416960 21:09:51 INFO - PROCESS | 1638 | 21:10:41 INFO - PROCESS | 1638 | 1481865041588 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] 21:12:56 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 21:12:58 INFO - PROCESS | 1638 | RSS: Main: 231936000 21:12:58 INFO - PROCESS | 1638 | 21:16:00 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 21:16:00 INFO - PROCESS | 1638 | RSS: Main: 243150848 21:16:00 INFO - PROCESS | 1638 | 21:19:02 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 21:19:03 INFO - PROCESS | 1638 | RSS: Main: 245997568 21:19:03 INFO - PROCESS | 1638 | 21:22:05 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 21:22:05 INFO - PROCESS | 1638 | RSS: Main: 245260288 21:22:05 INFO - PROCESS | 1638 | 21:25:08 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 21:25:09 INFO - PROCESS | 1638 | RSS: Main: 248729600 21:25:09 INFO - PROCESS | 1638 | 21:28:12 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 21:28:12 INFO - PROCESS | 1638 | RSS: Main: 250417152 21:28:12 INFO - PROCESS | 1638 | 21:28:12 INFO - PROCESS | 1638 | __start_tp_report 21:28:12 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 21:28:12 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 21:28:12 INFO - PROCESS | 1638 | |i|pagename|runs| 21:28:12 INFO - PROCESS | 1638 | |0;dojo.html;2767.944641107179;3313.5692359993204;3347.978866445736;3364.1085112445207;3370.6629337066283;5141.485851414856;5249.763760630772;5617.241672374228;5659.6321239119425;5661.745221465034;5647.971760141194;5707.686077265748;5724.313082430104;5739.569532285074;5740.540756739461;5205.505476979691;5210.322658054448;5230.320058392404;5237.633365664405;5252.8686782830355;5243.10867152585;5257.185136303879;5261.316028916235;5277.3139491866195;5300.761465734032;5244.763985620656;5245.318108645871;5269.0252303324005;5275.261463395128;5300.337457817773;4992.001599680042;5070.442251352349;5075.593952483792;5154.098032844237;5170.741462926876;5038.697678139323;5157.6647517911215;5178.352705911761;5214.556762675139;5293.523582877559;5769.423057694239;5814.709264536757;5826.621269617462;5831.329397119301;5871.941280587225;6006.879862402728;6033.09503574463;6048.213732214828;6203.565750397474;6236.906446403307;6116.174316467264;6164.722587483574;6170.568060235785;6176.81469555914;6198.132261483396;5691.544676425876;5692.8861422771315;5702.942970570324;5709.685967271798;5757.366689664133;6135.44703110649;6183.536234782319;6193.597416167935;6200.472959798468;6221.626702397871;6071.696415179224;6110.315755985069;6124.777645458507;6133.815985520441;6141.785037523755;4825.324454576362;5948.553858460633;6152.138700581922;6157.076438534166;6209.192804935329;4776.450708168536;5996;6154.5076393888385;6183.659898705614;6203.844903877349;6066.848328791815;6088.8782224356155;6110.710646019186;6116.244470650255;6120.081455037911;4628.97685511577;4717.952820471752;4864.8783780405065;4927.507249275044;4934.605231581465;4353.782310884443;4384.0135969407265;4407.625351845129;4412.117576484716;4437.467503899553;4883.829065982744;5612.102063669793;5656.85857853557;5900.321463031806;5903.085021821625;6098.390160983955;6110.224634763485;6122.398155112068;6130.092410195535;6148.631082134997;5630.915536266959;5677.37548869624;5689;5760.8271751847515;5889.734961926723;5336.879255356341;5347.385050719217;5437.15724062763;5445.401005889349;5465.262189604353;5306.575473962151;6305.747770089145;6313.6843157841;6327.240731112296;6349.14286571307;6068.575199735976;6137.468918203611;6140.350877192904;6170.197874276315;6198.72105755233;5110.97444512775;6120.812855101315;6169.136320915165;6189.876202476015;6213.285472170758;5279.891222843093;6020.096985452042;6026.879462410815;6047.576669633084;6055.485283750931;4639.791209395653;5047.520485553867;5262.210668399771;6042.305134909541;6122.663253521099;5312.681239125664;5958.582899197015;5959.284885813557;6024.87950240984;6027.246594175728;6061.060535616986;6085.604435711664;6104.307579233734;6104.938950610437;6139.8465038372615;5879.470847623735;5910.083936989773;6013.128096426081;6014.72933717975;6015.39846015395;5863.648181109147;5907.202527658883;5982.102684597346;6114.266288045463;6125.785597504066;5807.419258074159;5821.7089145543405;5890.381509941394;5919.378465261086;5992.520598352208 21:28:12 INFO - PROCESS | 1638 | |1;ext.html;473.3964195650543;479.37441638661545;520.8801077682981;523.3013926408244;528.8519214619905;37966.810165949166;39601.81194564164;39625.8018709906;39722.00694982627;39776.60223397769;40930.38604420936;41502.7924860376;41541.37687934676;41611;41701.58298417015;9258.25933925286;9414.811703765921;9420.576074076684;9437.905620943806;9462.716118516455;8846.823063538757;8870.201681848632;8871.733847984571;8886.46681199127;8984.416012959136;9492.572834222477;9500.192483638899;9521.761955951088;9527.809443811155;9528.665476958155;9291.210247129002;9374.578143983503;9435.19800816928;9462.763430914214;9489.857652135224;9315.347925645208;9367.812643747087;9376.343655944087;9420.152186303198;9421.623135074657;38771;38935;39053.41419878704;39167;39178.608213918065;3281.1961069538115;3297.4559197732347;3338.432466480704;3397.184675677844;3569.464580312974;27804;28494;29175.270618234503;30202.9428969985;30328.848355758302;3166.255929856482;3190.888318908827;3202.071399294202;3233.8868139615;3243.5783348164587;459860;463934;469904;471667;472461;57064.71467642635;57340.713296434085;57372;57383.713081434325;57498.13752793711;60048.399516005156;60346.793064138474;60558.09162862472;60879.695601522595;61059.38940610626;15448.845511544741;15599.766003509956;15687.058776473219;15718.371265149266;15726.977746446673;11982.520699171935;12194.512219511298;12202.938985305016;12208.938955305344;12242.571509997108;15261.847381526264;16297.185140742915;16316.836831631768;16327;16341.754873676904;5866.384029676908;5868.2664666916635;5953.107033944856;5999.970000149971;6017.849553761191;802.5866678660494;955.6655170690203;967.8886928003229;993.2997236947934;1043.2019505078622;29728;29886.953956611414;30013.549796753065;30295.545566816516;30409.239769005508;1026.7535791410007;1043.7077618266896;1059.597353005853;1077.6066735641434;1102.3551222534907;14905;15058;15506;15807.209639518209;15921.442749503713;193387;193468;194051;194683;194903;56084.439155608736;56472.15291770627;56690.716546417;56798.43201567931;57076;1308.7905935050346;1340.1356125299128;1345.401296423102;1347.2051489621172;1362.7138300956713;23179.420514487272;28611.713882860906;29170.854145729136;29511.852440737657;29595.704042959296;49358.75320623374;50023.24965125599;50082;50162.49837501724;50392.74803625958;575301;575739;578414;579938;582616;21329.253476128262;23459.178928737412;25450.472971621763;25861;26548.734512654628;35559.644403555634;44650.330245047;45001.77499112483;45089.54910450854;45142.32286515639;37603.81198093992;37668.4769682071;38225.80887095658;38285;38918.02704932286;34056.829715851265;35638.465423019195;36345.90962271136;38070.61929380671;38763.612363876004;33549;36746.26507469889;36839.07902302464;38115.42826857655;39142.60857391504;32791.8360408206;33853.984380468624;34388.48417273793;34468.48297275493;34668;25700.8714956424;25705;25789.742102578733;25841.612375814002;26121 21:28:12 INFO - PROCESS | 1638 | |2;jquery.html;363.62546577025665;365.1838141753181;375.2363939383355;382.17259632894786;388.3727779635889;11083.889161108396;11502.964733174013;11618.244814087078;11639.534418623267;18736.156872940715;20408.979551022465;20771.27300544481;20984.685229721526;21498.785012149874;21782.346529604096;6275.090111933779;6710.362515561014;6715.630640314781;6721.193456785192;6721.294264102272;5606.6636001839815;7035.36681698647;7401.962990185041;7439.776806695808;7517.436192285573;7245.420366370704;7400.925990740105;7472.813179670497;7490.400767938552;7525.134609519893;7281.854362912712;7527.661255243527;7575.9621201893915;7588.810279742996;7619.80950476237;6452.903206451907;6792.845216313239;6835.692393842289;7357.558546487228;7391.445641576904;22076.88961555198;25685.74314256852;26212.21363359102;27198.1840544784;27860.860695696596;2363.586372384843;2564.8332858364147;2612.5166844133705;2780.3466185446405;2807.6069350290977;14762.926185369111;16010.359585616678;16477.58806029847;16647.583810404714;16985.83014169855;1889.7354370388157;1904.6857268550673;1944.8152425519554;2019.1519561784087;2020.8686435381653;117411.4129429356;137920.31039844838;142105.289473553;144594;145839.99999999895;21198;21711.674324885138;21805.890970545046;21897.562048758937;21958.780412195993;34764.652353476646;34838.9548313551;35174.64825351714;35361.82319088388;35464.82267588697;8618.655253789904;9049.230815380635;9797.118259356692;10244.795104097877;10589.835118136998;7301.598412087386;8635.006974197922;8682.565871706389;8691.304695624334;8797.38418310712;9592.616295348107;9660.855087173699;9691.806163876823;9751.853722194173;9768.95115524432;3275.3776782411264;3285.819279939626;3722.9813850930573;3821.38857782759;3837.1558257183374;760.6653072648018;795.5584650518902;840.82342708032;902.9593668284942;958.7459323279337;19489.317873874345;20429.795702043088;20545.48636284105;20770.376888693365;20783.726953227782;833.4957350802798;886.8980067292215;920.6363486422825;940.6190492850407;995.0546980368679;8545.359098067669;9809.11717945374;10202.030807073315;10686;10722.839157412645;549346;560901;581791;590357;623537;93162.53418732862;114296.85703142862;116719;126437;130588;948.4878165790534;959.4627008875052;976.72651657536;989.7030890732751;996.1670526231624;14132.929335353258;14897.925510372379;16215.18924053769;18680.87914725121;18844.246230150642;40041.799791000856;42422.15155696931;52770.73614632056;54014.45985540094;55491.72254138703;56912.71543642255;71881.6405917988;79236.60381698054;81491.18508814836;84492.04277850245;4773.594244489257;4777.976110119427;7566.394688424803;7766.378689704923;7770.883436748566;10036.297459177787;11913.927746502857;12217.144799863925;12314.938425307817;12399.938000309941;7989.400794940287;9025.548722563713;10406.647135872288;10646.20153488507;10709.892901070889;10462.267641265038;10611.681649550526;10668.466576671291;10809.189310801565;10812.243142979916;10694.946525267324;11043;11066.612668556561;11145.72135696614;11447.942760286145;6863.553868998499;7291.890621640574;7443.590602516913;7966.721164759206;8083.272505474535;8734.257588104829;8783.341249406449;10279.794404111726;10294.485275736033;10427.009434103747 21:28:12 INFO - PROCESS | 1638 | |3;mootools.html;1445.457953267525;1479.1051413894593;1636.7872176617038;1722.1733567887422;1784.3754685859958;7416.962915185417;7692.000039994807;7964.402669799766;8397.286230670392;8413.242808147274;6281.309056003836;8112.31045361145;8128.471649342789;8274.91725082749;8289;2306.1697788796064;2336.427575244059;2344.3318654183563;2344.3787396339985;2355.105060077168;2322.3613506285737;2351.2593533037125;2359.6814430051895;2372.3713215997773;2376.4534157143867;2302.240260713969;2322.605157123293;2337.0651739304244;2338.707661542307;2340.8361414700976;2295.908163673451;2299.4251437140715;2307.849989750669;2321.872297023663;2333.3349995251356;2318.733345665253;2354.4231663242444;2354.658574506704;2364.1725396111397;2370.0638247891984;8146.348292136615;8321.417500774949;8322.16778322169;8323.12607176244;8336.458130221577;4925.21196608541;5004.149294619887;5165.664231824957;5174;5179.14544100223;2934.9853250733822;3239.1416274687226;3379.290349026707;3386.7459940504314;3392.796432214075;1020.6886899495726;1033.3489901362168;1046.837740150278;1053.8419237114417;1068.172166570906;10297.691069267934;10426.270161088727;10436.37381757097;10441.68674939753;10466.68599942003;4491.146682130385;4569.908601827945;4717.905641887143;4745.477997420281;4765.7140571565815;9266.305027122859;9341.252699784001;9351.205147562398;9366.344355895159;9368.4378937264;5129.384473863086;5129.563987061067;5133.589312854962;5137.383513978347;5211.947880521222;4209.2423363794815;4221.197972385237;4661.277501987197;4690.601298889633;4730.2431610942085;3770.6606405423645;3793.430985352164;3858.8456461741216;3875.5930627284292;3895.8831235062994;1277.3677029870273;1283.101828719881;1287.9098463107493;1297.5977446991462;1298.5909438526835;783.3419927261128;808.2967817998261;811.2374368093973;873.2010210657178;891.3136884598823;4483.977580112079;4647.604953578983;4695.3426520287185;5563.805266815642;5577.9163312550345;816.93056090233;825.6532256452305;902.8149229407956;916.702071826659;937.1144268666181;3757.1922036762226;3762.1159027628487;3764.8305826237884;3796.1838204786104;3810.942835857464;9818.607255709834;10046.849297260547;10166.898331016742;10183.083522482864;10200.795984080276;10153.340032897839;10160.288779785344;10180.185585153318;10196.184305255561;10462.738431539121;6931.514793964374;7247.710091596277;7767.02912135983;7949.801254968556;8011.038675358877;5634.464725851038;5701.173329867063;5821.79623713168;5835.241418615553;5909.645421274738;9235.584398702209;9254.213391861767;9304.395214311318;9320.300977426856;9377.859332109887;9314.767130821783;9445.527723613654;9531.046895310414;9532.428054316762;9545.427274363561;6606.141201643755;6850.725970961105;7016.894746578908;7111.53775004623;7288.08898887639;7823.921760782319;7935.2064793520185;7985.680572777024;8023.799405014688;8040.718574849852;6998.475114366341;7133.357997780225;7146.9285307146265;7156.177039640508;7232.819179520554;6865.828354291183;6969.512134150561;6992;7013.158420989514;7049.259827718016;6658.43403310724;6661.300563440769;6716;6716.83207919806;6727.562708423937;4536.319552067216;4814.446338671098;4821.228603423436;4852.8301509447;4883.681406020426;4736.8578942631775;4813.78337974785;4815.133276010351;4922.483139270325;5022.84931452057 21:28:12 INFO - PROCESS | 1638 | |4;prototype.html;289.57432574116046;290.57721015921834;307.2380496369004;309.0558344258293;310.3529141739473;4860.465348811628;5346.117890548055;5486.341639003315;5564.914841605889;5648.887022259558;4346.695731298811;4765.714057156564;4892.755362231892;5249.107651699211;5492.395836457997;3514.912127196815;4190.518090419595;4197.559256278085;4230.153969206156;4233.618974292313;4049.311617025113;4188.659628918747;4201.810918508674;4217.45173127493;4226.387173859788;3655.981720091409;3694.5935947045805;4141.792910354486;4145.80272455599;4178.456800615916;4026.979865100685;4149.2531344358;4158.7296825706535;4172.436721042651;4177.7284476509085;3084.306031142997;3600.6939410150167;3645.7812531247946;3663.8275751759447;3667.2665466906724;4549.340345649863;5197.54468748751;5334.186056969285;5437.293151890229;5454.290942177531;3591.5690117185845;4684.5549672781035;4686.812527498896;4717.386739723848;4722.858314250578;4385.184889146463;5564;5657.660540367591;5778.6243894147165;5834.416558344132;3317.9004629861142;3407.693307602303;3645.489631451572;3890.2025084857537;3949.032487040665;5076.6192535559985;6158.72285747138;6192.9380706192815;6216.2851272103835;6241.843953901189;3352.513885486591;3998.0209796151094;4017.417474466186;4030.1133750574827;4036;3440.7935523868646;3757.4363845423404;3989.9800500997308;4005.098852758106;4061.147159096564;3999.860039888618;4708.976455117702;4731.313959475857;4737.3841400618385;4743.217369134123;4937.35814344133;5813.244278243801;5848.941510584925;5862.70686465675;5880.852978675567;1216.6958260434892;1253.7931241345075;1256.874312568754;1289.4970961325091;1295.7732396830518;769.9422543309274;834.8956380452444;870.5821205821242;877.3814460805245;877.6046086722015;4461.769115442279;4794.232922732346;5242.18746094356;5264.92102618461;5324.653897496651;5613.298337707786;5635.6618602883955;5668.064769313017;5691.772329106872;5762.337331206881;4282.892927676833;4479.328100784908;4919.794650310697;5142.665726727751;5183.222516622537;2585.1985884375904;2767.8861281058807;2879.006742673774;2914.81053731511;2924.5613158026467;5228.503292187236;5572.777088916398;5812.505936995318;5812.953668339739;5844.240248767718;5259.526642602184;5436.320459942507;5744.070524032773;5752.827415177468;5847.649141051551;4914.32169467641;4947.441555910021;5600.535095688274;5783.624064435799;5847.38602446737;4814.614830813456;5372.501786669218;5425.945740542702;5592;5648.293963254593;5096.43939166684;5200.713960732196;5251.844594189272;5376.838694839161;5682.715864206691;5052.635788337202;5415.864603384947;5464.453554644504;5846.988800767523;5870.180244124335;3217.0463741207122;3338.0987133474314;3601.4249811384984;3719.7681391166166;3779.3764028934916;4415.933760993652;5109.565686916505;5232.293640358503;5529.585281103853;5664;4606.861794146181;4998.575121114746;5201.803585175507;5310.522053015247;5761.942380576141;4769.642276829182;5087.567556757717;5582.413846546055;5890.319904443382;5914.34942156371;4903.387076615423;5558.360788509373;5618.612089861874;5731.8853622928145;5799.652020878761;5325.627206095536;5795.797596913146;5841.620294680833;5867.383924687846;5907.527397808251;5670.659760414388;5681.431856814451;5700.771969121354;5723.456271654186;5779.682117483579 21:28:12 INFO - PROCESS | 1638 | |5;yui.html;629.9023651334041;687.6699184391492;742.3615690506167;765.1506827421562;768.7885831396366;13963.860361396382;14742.705145897076;14750.55748327552;14781.482648107318;14833.332500037497;12582.748345033095;13160;13166.341682915863;13213.537526186585;13280.07039507235;625.337142628815;649.7043845050491;773.7059917231472;793.7063286583943;809.7894547417654;787.582581231948;797.3103265675207;801.5992003998001;802.4062193976474;802.4423025996934;857.0743596915347;857.6440777077505;860.0496451421184;861.0399404663817;861.2593169873935;821.5563595658338;839.5466448117978;852.8763329317214;853.159637756809;853.7865533616596;607.9483243924271;612.727336335331;630.2342653675806;630.6752022708309;631.5736877607596;13288.60134195966;13596.32018399086;13603.795943060863;13610.59168224955;14251.64370890736;13400.530981415604;13652.385642646006;13675.247861367621;13908.58274251764;14061.367238474293;10974.286671366335;11147.609833655782;11252.774944501147;11399;11455.312681239153;3462.117160124177;3605.675489205984;3625.8187090645624;3627.2201476682376;3634.1641422472717;79564;86539.99999999937;88894.99972501253;92040.6193907092;94461.52769236178;9884.604615815431;10284.228682848667;10554.102901253325;10582.629607963838;10723.356598604109;18405.355812546495;20398.082086306153;20406.591868162555;20471.590568188552;20475;6421.935780642228;6437.645929473831;6614.305497922745;7199.964000179965;7880.172581878935;4257.936130958038;4280.892977675583;4377.78111094444;4410.985473341149;4443;3429.8285085745615;3542.5217595624263;3549.538559987185;3559.519464872261;3581.283743251308;650.7657243392375;660.8942569188906;663.9634820084942;695.4366962760181;710.3855165282097;548.1667864845413;591.0100581525927;616.5006344860677;620.2804746494045;620.2835724737935;8861.335399845037;8917.821643567222;8975.77560560978;8997.460152390877;9114.453132812052;650.4698670583459;654.1561385812249;657.139147716493;668.1414382518537;686.7150132694976;4199.223143718422;4204.306289462265;4337.587929146726;4371.606555410029;4387.561243875587;76345;80138;104486.47756761167;113483;117611.41194293974;95132.52433737786;97110.0288997101;99418;99650.00349996408;100346;86022.56988715017;87454.56272718469;87884.56057719671;89610.55194724115;92204.07795921955;8163.083236684825;8471.830563388574;8474.74575762728;8601.655933762579;8666.176713212235;56311.43688563226;56399.71800140973;56835.43164568301;57737.13394299173;58712.412875870694;52430.73784631053;81023.78464322921;82883;83359.5832020836;83485;11108.777824443627;11203.43982800873;11383.146264030065;11463.942680286878;11614.6515604532;11664.76670466569;11732.354720490455;12355.25868447896;12402;12414.317212553397;11129.833052504056;11285.943570282096;11401.771964560829;11449.427528623703;11763.529458821551;11550.59572914944;11763.647090587296;11884.405779711153;11964.700882478008;12332.630021099381;11012.284201526874;11419.37193454368;11536.653900383002;11815.704607384883;11874.940625296818;7810.258025487569;9254.12085851843;9770;9978;10606.363618182933;7516.436267279866;8171.83656326882;8691.435056721293;8863.689770858247;9947.900520994697 21:28:12 INFO - PROCESS | 1638 | __end_tp_report 21:28:12 INFO - PROCESS | 1638 | __start_cc_report 21:28:12 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,373 21:28:12 INFO - PROCESS | 1638 | __end_cc_report 21:28:12 INFO - PROCESS | 1638 | __startTimestamp1481866092359__endTimestamp 21:28:12 INFO - PROCESS | 1638 | 21:28:12 INFO - PROCESS | 1638 | ------- Summary: start ------- 21:28:12 INFO - PROCESS | 1638 | Number of tests: 6 21:28:12 INFO - PROCESS | 1638 | 21:28:12 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5629.56 Median:5863.65 stddev:645.37 (11.0%) stddev-sans-first:607.06 21:28:12 INFO - PROCESS | 1638 | Values: 2767.9 3313.6 3348.0 3364.1 3370.7 5141.5 5249.8 5617.2 5659.6 5661.7 5648.0 5707.7 5724.3 5739.6 5740.5 5205.5 5210.3 5230.3 5237.6 5252.9 5243.1 5257.2 5261.3 5277.3 5300.8 5244.8 5245.3 5269.0 5275.3 5300.3 4992.0 5070.4 5075.6 5154.1 5170.7 5038.7 5157.7 5178.4 5214.6 5293.5 5769.4 5814.7 5826.6 5831.3 5871.9 6006.9 6033.1 6048.2 6203.6 6236.9 6116.2 6164.7 6170.6 6176.8 6198.1 5691.5 5692.9 5702.9 5709.7 5757.4 6135.4 6183.5 6193.6 6200.5 6221.6 6071.7 6110.3 6124.8 6133.8 6141.8 4825.3 5948.6 6152.1 6157.1 6209.2 4776.5 5996.0 6154.5 6183.7 6203.8 6066.8 6088.9 6110.7 6116.2 6120.1 4629.0 4718.0 4864.9 4927.5 4934.6 4353.8 4384.0 4407.6 4412.1 4437.5 4883.8 5612.1 5656.9 5900.3 5903.1 6098.4 6110.2 6122.4 6130.1 6148.6 5630.9 5677.4 5689.0 5760.8 5889.7 5336.9 5347.4 5437.2 5445.4 5465.3 5306.6 6305.7 6313.7 6327.2 6349.1 6068.6 6137.5 6140.4 6170.2 6198.7 5111.0 6120.8 6169.1 6189.9 6213.3 5279.9 6020.1 6026.9 6047.6 6055.5 4639.8 5047.5 5262.2 6042.3 6122.7 5312.7 5958.6 5959.3 6024.9 6027.2 6061.1 6085.6 6104.3 6104.9 6139.8 5879.5 5910.1 6013.1 6014.7 6015.4 5863.6 5907.2 5982.1 6114.3 6125.8 5807.4 5821.7 5890.4 5919.4 5992.5 21:28:12 INFO - PROCESS | 1638 | 21:28:12 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:56475.36 Median:38767.31 stddev:118875.07 (306.6%) stddev-sans-first:119134.18 21:28:12 INFO - PROCESS | 1638 | Values: 473.4 479.4 520.9 523.3 528.9 37966.8 39601.8 39625.8 39722.0 39776.6 40930.4 41502.8 41541.4 41611.0 41701.6 9258.3 9414.8 9420.6 9437.9 9462.7 8846.8 8870.2 8871.7 8886.5 8984.4 9492.6 9500.2 9521.8 9527.8 9528.7 9291.2 9374.6 9435.2 9462.8 9489.9 9315.3 9367.8 9376.3 9420.2 9421.6 38771.0 38935.0 39053.4 39167.0 39178.6 3281.2 3297.5 3338.4 3397.2 3569.5 27804.0 28494.0 29175.3 30202.9 30328.8 3166.3 3190.9 3202.1 3233.9 3243.6 459860.0 463934.0 469904.0 471667.0 472461.0 57064.7 57340.7 57372.0 57383.7 57498.1 60048.4 60346.8 60558.1 60879.7 61059.4 15448.8 15599.8 15687.1 15718.4 15727.0 11982.5 12194.5 12202.9 12208.9 12242.6 15261.8 16297.2 16316.8 16327.0 16341.8 5866.4 5868.3 5953.1 6000.0 6017.8 802.6 955.7 967.9 993.3 1043.2 29728.0 29887.0 30013.5 30295.5 30409.2 1026.8 1043.7 1059.6 1077.6 1102.4 14905.0 15058.0 15506.0 15807.2 15921.4 193387.0 193468.0 194051.0 194683.0 194903.0 56084.4 56472.2 56690.7 56798.4 57076.0 1308.8 1340.1 1345.4 1347.2 1362.7 23179.4 28611.7 29170.9 29511.9 29595.7 49358.8 50023.2 50082.0 50162.5 50392.7 575301.0 575739.0 578414.0 579938.0 582616.0 21329.3 23459.2 25450.5 25861.0 26548.7 35559.6 44650.3 45001.8 45089.5 45142.3 37603.8 37668.5 38225.8 38285.0 38918.0 34056.8 35638.5 36345.9 38070.6 38763.6 33549.0 36746.3 36839.1 38115.4 39142.6 32791.8 33854.0 34388.5 34468.5 34668.0 25700.9 25705.0 25789.7 25841.6 26121.0 21:28:12 INFO - PROCESS | 1638 | 21:28:12 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:35560.52 Median:378.70 stddev:97313.33 (25696.4%) stddev-sans-first:97550.43 21:28:12 INFO - PROCESS | 1638 | Values: 363.6 365.2 375.2 382.2 388.4 11083.9 11503.0 11618.2 11639.5 18736.2 20409.0 20771.3 20984.7 21498.8 21782.3 6275.1 6710.4 6715.6 6721.2 6721.3 5606.7 7035.4 7402.0 7439.8 7517.4 7245.4 7400.9 7472.8 7490.4 7525.1 7281.9 7527.7 7576.0 7588.8 7619.8 6452.9 6792.8 6835.7 7357.6 7391.4 22076.9 25685.7 26212.2 27198.2 27860.9 2363.6 2564.8 2612.5 2780.3 2807.6 14762.9 16010.4 16477.6 16647.6 16985.8 1889.7 1904.7 1944.8 2019.2 2020.9 117411.4 137920.3 142105.3 144594.0 145840.0 21198.0 21711.7 21805.9 21897.6 21958.8 34764.7 34839.0 35174.6 35361.8 35464.8 8618.7 9049.2 9797.1 10244.8 10589.8 7301.6 8635.0 8682.6 8691.3 8797.4 9592.6 9660.9 9691.8 9751.9 9769.0 3275.4 3285.8 3723.0 3821.4 3837.2 760.7 795.6 840.8 903.0 958.7 19489.3 20429.8 20545.5 20770.4 20783.7 833.5 886.9 920.6 940.6 995.1 8545.4 9809.1 10202.0 10686.0 10722.8 549346.0 560901.0 581791.0 590357.0 623537.0 93162.5 114296.9 116719.0 126437.0 130588.0 948.5 959.5 976.7 989.7 996.2 14132.9 14897.9 16215.2 18680.9 18844.2 40041.8 42422.2 52770.7 54014.5 55491.7 56912.7 71881.6 79236.6 81491.2 84492.0 4773.6 4778.0 7566.4 7766.4 7770.9 10036.3 11913.9 12217.1 12314.9 12399.9 7989.4 9025.5 10406.6 10646.2 10709.9 10462.3 10611.7 10668.5 10809.2 10812.2 10694.9 11043.0 11066.6 11145.7 11447.9 6863.6 7291.9 7443.6 7966.7 8083.3 8734.3 8783.3 10279.8 10294.5 10427.0 21:28:12 INFO - PROCESS | 1638 | 21:28:12 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5397.08 Median:4923.85 stddev:2949.69 (59.9%) stddev-sans-first:2943.01 21:28:12 INFO - PROCESS | 1638 | Values: 1445.5 1479.1 1636.8 1722.2 1784.4 7417.0 7692.0 7964.4 8397.3 8413.2 6281.3 8112.3 8128.5 8274.9 8289.0 2306.2 2336.4 2344.3 2344.4 2355.1 2322.4 2351.3 2359.7 2372.4 2376.5 2302.2 2322.6 2337.1 2338.7 2340.8 2295.9 2299.4 2307.8 2321.9 2333.3 2318.7 2354.4 2354.7 2364.2 2370.1 8146.3 8321.4 8322.2 8323.1 8336.5 4925.2 5004.1 5165.7 5174.0 5179.1 2935.0 3239.1 3379.3 3386.7 3392.8 1020.7 1033.3 1046.8 1053.8 1068.2 10297.7 10426.3 10436.4 10441.7 10466.7 4491.1 4569.9 4717.9 4745.5 4765.7 9266.3 9341.3 9351.2 9366.3 9368.4 5129.4 5129.6 5133.6 5137.4 5211.9 4209.2 4221.2 4661.3 4690.6 4730.2 3770.7 3793.4 3858.8 3875.6 3895.9 1277.4 1283.1 1287.9 1297.6 1298.6 783.3 808.3 811.2 873.2 891.3 4484.0 4647.6 4695.3 5563.8 5577.9 816.9 825.7 902.8 916.7 937.1 3757.2 3762.1 3764.8 3796.2 3810.9 9818.6 10046.8 10166.9 10183.1 10200.8 10153.3 10160.3 10180.2 10196.2 10462.7 6931.5 7247.7 7767.0 7949.8 8011.0 5634.5 5701.2 5821.8 5835.2 5909.6 9235.6 9254.2 9304.4 9320.3 9377.9 9314.8 9445.5 9531.0 9532.4 9545.4 6606.1 6850.7 7016.9 7111.5 7288.1 7823.9 7935.2 7985.7 8023.8 8040.7 6998.5 7133.4 7146.9 7156.2 7232.8 6865.8 6969.5 6992.0 7013.2 7049.3 6658.4 6661.3 6716.0 6716.8 6727.6 4536.3 4814.4 4821.2 4852.8 4883.7 4736.9 4813.8 4815.1 4922.5 5022.8 21:28:12 INFO - PROCESS | 1638 | 21:28:12 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4524.74 Median:5064.63 stddev:1407.95 (27.8%) stddev-sans-first:1375.55 21:28:12 INFO - PROCESS | 1638 | Values: 289.6 290.6 307.2 309.1 310.4 4860.5 5346.1 5486.3 5564.9 5648.9 4346.7 4765.7 4892.8 5249.1 5492.4 3514.9 4190.5 4197.6 4230.2 4233.6 4049.3 4188.7 4201.8 4217.5 4226.4 3656.0 3694.6 4141.8 4145.8 4178.5 4027.0 4149.3 4158.7 4172.4 4177.7 3084.3 3600.7 3645.8 3663.8 3667.3 4549.3 5197.5 5334.2 5437.3 5454.3 3591.6 4684.6 4686.8 4717.4 4722.9 4385.2 5564.0 5657.7 5778.6 5834.4 3317.9 3407.7 3645.5 3890.2 3949.0 5076.6 6158.7 6192.9 6216.3 6241.8 3352.5 3998.0 4017.4 4030.1 4036.0 3440.8 3757.4 3990.0 4005.1 4061.1 3999.9 4709.0 4731.3 4737.4 4743.2 4937.4 5813.2 5848.9 5862.7 5880.9 1216.7 1253.8 1256.9 1289.5 1295.8 769.9 834.9 870.6 877.4 877.6 4461.8 4794.2 5242.2 5264.9 5324.7 5613.3 5635.7 5668.1 5691.8 5762.3 4282.9 4479.3 4919.8 5142.7 5183.2 2585.2 2767.9 2879.0 2914.8 2924.6 5228.5 5572.8 5812.5 5813.0 5844.2 5259.5 5436.3 5744.1 5752.8 5847.6 4914.3 4947.4 5600.5 5783.6 5847.4 4814.6 5372.5 5425.9 5592.0 5648.3 5096.4 5200.7 5251.8 5376.8 5682.7 5052.6 5415.9 5464.5 5847.0 5870.2 3217.0 3338.1 3601.4 3719.8 3779.4 4415.9 5109.6 5232.3 5529.6 5664.0 4606.9 4998.6 5201.8 5310.5 5761.9 4769.6 5087.6 5582.4 5890.3 5914.3 4903.4 5558.4 5618.6 5731.9 5799.7 5325.6 5795.8 5841.6 5867.4 5907.5 5670.7 5681.4 5700.8 5723.5 5779.7 21:28:12 INFO - PROCESS | 1638 | 21:28:12 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20205.01 Median:29651.71 stddev:30102.77 (101.5%) stddev-sans-first:30151.34 21:28:12 INFO - PROCESS | 1638 | Values: 629.9 687.7 742.4 765.2 768.8 13963.9 14742.7 14750.6 14781.5 14833.3 12582.7 13160.0 13166.3 13213.5 13280.1 625.3 649.7 773.7 793.7 809.8 787.6 797.3 801.6 802.4 802.4 857.1 857.6 860.0 861.0 861.3 821.6 839.5 852.9 853.2 853.8 607.9 612.7 630.2 630.7 631.6 13288.6 13596.3 13603.8 13610.6 14251.6 13400.5 13652.4 13675.2 13908.6 14061.4 10974.3 11147.6 11252.8 11399.0 11455.3 3462.1 3605.7 3625.8 3627.2 3634.2 79564.0 86540.0 88895.0 92040.6 94461.5 9884.6 10284.2 10554.1 10582.6 10723.4 18405.4 20398.1 20406.6 20471.6 20475.0 6421.9 6437.6 6614.3 7200.0 7880.2 4257.9 4280.9 4377.8 4411.0 4443.0 3429.8 3542.5 3549.5 3559.5 3581.3 650.8 660.9 664.0 695.4 710.4 548.2 591.0 616.5 620.3 620.3 8861.3 8917.8 8975.8 8997.5 9114.5 650.5 654.2 657.1 668.1 686.7 4199.2 4204.3 4337.6 4371.6 4387.6 76345.0 80138.0 104486.5 113483.0 117611.4 95132.5 97110.0 99418.0 99650.0 100346.0 86022.6 87454.6 87884.6 89610.6 92204.1 8163.1 8471.8 8474.7 8601.7 8666.2 56311.4 56399.7 56835.4 57737.1 58712.4 52430.7 81023.8 82883.0 83359.6 83485.0 11108.8 11203.4 11383.1 11463.9 11614.7 11664.8 11732.4 12355.3 12402.0 12414.3 11129.8 11285.9 11401.8 11449.4 11763.5 11550.6 11763.6 11884.4 11964.7 12332.6 11012.3 11419.4 11536.7 11815.7 11874.9 7810.3 9254.1 9770.0 9978.0 10606.4 7516.4 8171.8 8691.4 8863.7 9947.9 21:28:12 INFO - PROCESS | 1638 | -------- Summary: end -------- 21:28:12 INFO - PROCESS | 1638 | 21:28:12 INFO - TEST-INFO | 1638: exit 0 21:28:13 INFO - TEST-OK | dromaeo_css | took 1117340ms 21:28:13 INFO - TEST-START | kraken 21:28:13 INFO - operating with platform_type : mac_ 21:28:13 INFO - Initialising browser for kraken test... 21:28:13 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmpqqH3T8/profile http://localhost:49251/getInfo.html) 21:28:16 INFO - PROCESS | 1649 | 2016-12-15 21:28:16.046 plugin-container[1650:10339] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x862f, name = 'com.apple.tsm.portname' 21:28:16 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:28:16 INFO - PROCESS | 1649 | 2016-12-15 21:28:16.051 plugin-container[1650:10339] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 21:28:16 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:28:16 INFO - PROCESS | 1649 | 2016-12-15 21:28:16.051 plugin-container[1650:10339] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:28:16 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 21:28:16 INFO - PROCESS | 1649 | colorDepth:24 21:28:16 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 21:28:16 INFO - PROCESS | 1649 | __metrics 21:28:16 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 21:28:16 INFO - TEST-INFO | 1649: exit 0 21:28:16 INFO - Browser initialized. 21:28:16 INFO - Running cycle 1/1 for kraken test... 21:28:16 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmpqqH3T8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:28:19 INFO - PROCESS | 1651 | 2016-12-15 21:28:19.804 plugin-container[1652:10457] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 21:28:19 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:28:19 INFO - PROCESS | 1651 | 2016-12-15 21:28:19.804 plugin-container[1652:10457] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 21:28:19 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:28:19 INFO - PROCESS | 1651 | 2016-12-15 21:28:19.804 plugin-container[1652:10457] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:28:29 INFO - PROCESS | 1651 | RSS: Main: 229421056 21:28:29 INFO - PROCESS | 1651 | 21:28:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 21:28:33 INFO - PROCESS | 1651 | RSS: Main: 241340416 21:28:33 INFO - PROCESS | 1651 | 21:28:36 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 21:28:36 INFO - PROCESS | 1651 | RSS: Main: 248967168 21:28:36 INFO - PROCESS | 1651 | 21:28:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 21:28:40 INFO - PROCESS | 1651 | RSS: Main: 252436480 21:28:40 INFO - PROCESS | 1651 | 21:28:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 21:28:43 INFO - PROCESS | 1651 | RSS: Main: 254005248 21:28:43 INFO - PROCESS | 1651 | 21:28:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:28:45 INFO - PROCESS | 1651 | RSS: Main: 254001152 21:28:45 INFO - PROCESS | 1651 | 21:28:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 21:28:49 INFO - PROCESS | 1651 | RSS: Main: 258162688 21:28:49 INFO - PROCESS | 1651 | 21:28:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 21:28:53 INFO - PROCESS | 1651 | RSS: Main: 258039808 21:28:53 INFO - PROCESS | 1651 | 21:28:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 21:28:56 INFO - PROCESS | 1651 | RSS: Main: 258301952 21:28:56 INFO - PROCESS | 1651 | 21:28:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:28:59 INFO - PROCESS | 1651 | RSS: Main: 257388544 21:28:59 INFO - PROCESS | 1651 | 21:29:01 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:29:01 INFO - PROCESS | 1651 | RSS: Main: 257335296 21:29:01 INFO - PROCESS | 1651 | 21:29:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:29:04 INFO - PROCESS | 1651 | RSS: Main: 257404928 21:29:04 INFO - PROCESS | 1651 | 21:29:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:29:08 INFO - PROCESS | 1651 | RSS: Main: 257818624 21:29:08 INFO - PROCESS | 1651 | 21:29:11 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:29:12 INFO - PROCESS | 1651 | RSS: Main: 257654784 21:29:12 INFO - PROCESS | 1651 | 21:29:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 21:29:14 INFO - PROCESS | 1651 | RSS: Main: 259432448 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | __start_tp_report 21:29:14 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 21:29:14 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 21:29:14 INFO - PROCESS | 1651 | |i|pagename|runs| 21:29:14 INFO - PROCESS | 1651 | |0;ai-astar;137;170;126;127;118;117;117;119;130;171 21:29:14 INFO - PROCESS | 1651 | |1;audio-beat-detection;143;112;117;146;111;113;113;114;139;132 21:29:14 INFO - PROCESS | 1651 | |2;audio-dft;201;199;191;202;234;198;196;194;199;195 21:29:14 INFO - PROCESS | 1651 | |3;audio-fft;83;58;63;88;61;60;61;58;80;60 21:29:14 INFO - PROCESS | 1651 | |4;audio-oscillator;94;70;92;74;70;71;87;70;71;89 21:29:14 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;77;128;94;94;106;92;85;74;83;117 21:29:14 INFO - PROCESS | 1651 | |6;imaging-darkroom;166;164;117;97;161;104;100;99;151;147 21:29:14 INFO - PROCESS | 1651 | |7;imaging-desaturate;85;129;77;136;135;124;92;108;119;87 21:29:14 INFO - PROCESS | 1651 | |8;json-parse-financial;58;58;57;59;60;60;58;61;59;58 21:29:14 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;72;71;67;103;103;98;88;69;100;105 21:29:14 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;78;103;103;94;98;102;102;90;88;85 21:29:14 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;154;157;145;139;143;146;144;144;189;145 21:29:14 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;167;169;167;170;168;170;172;169;199;230 21:29:14 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;68;71;68;67;68;66;79;90;85;86 21:29:14 INFO - PROCESS | 1651 | __end_tp_report 21:29:14 INFO - PROCESS | 1651 | __start_cc_report 21:29:14 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,990 21:29:14 INFO - PROCESS | 1651 | __end_cc_report 21:29:14 INFO - PROCESS | 1651 | __startTimestamp1481866154110__endTimestamp 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | ------- Summary: start ------- 21:29:14 INFO - PROCESS | 1651 | Number of tests: 14 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:133.20 Median:128.50 stddev:20.70 (16.1%) stddev-sans-first:21.91 21:29:14 INFO - PROCESS | 1651 | Values: 137.0 170.0 126.0 127.0 118.0 117.0 117.0 119.0 130.0 171.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:124.00 Median:124.50 stddev:14.29 (11.5%) stddev-sans-first:13.40 21:29:14 INFO - PROCESS | 1651 | Values: 143.0 112.0 117.0 146.0 111.0 113.0 113.0 114.0 139.0 132.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:200.90 Median:199.00 stddev:12.10 (6.1%) stddev-sans-first:12.83 21:29:14 INFO - PROCESS | 1651 | Values: 201.0 199.0 191.0 202.0 234.0 198.0 196.0 194.0 199.0 195.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.20 Median:62.00 stddev:11.61 (18.7%) stddev-sans-first:10.82 21:29:14 INFO - PROCESS | 1651 | Values: 83.0 58.0 63.0 88.0 61.0 60.0 61.0 58.0 80.0 60.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:80.50 stddev:10.29 (12.8%) stddev-sans-first:9.33 21:29:14 INFO - PROCESS | 1651 | Values: 94.0 70.0 92.0 74.0 70.0 71.0 87.0 70.0 71.0 89.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:95.00 Median:84.00 stddev:17.37 (20.7%) stddev-sans-first:17.15 21:29:14 INFO - PROCESS | 1651 | Values: 77.0 128.0 94.0 94.0 106.0 92.0 85.0 74.0 83.0 117.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:130.60 Median:162.50 stddev:29.69 (18.3%) stddev-sans-first:28.60 21:29:14 INFO - PROCESS | 1651 | Values: 166.0 164.0 117.0 97.0 161.0 104.0 100.0 99.0 151.0 147.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:109.20 Median:106.50 stddev:22.37 (21.0%) stddev-sans-first:21.95 21:29:14 INFO - PROCESS | 1651 | Values: 85.0 129.0 77.0 136.0 135.0 124.0 92.0 108.0 119.0 87.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:59.00 stddev:1.23 (2.1%) stddev-sans-first:1.27 21:29:14 INFO - PROCESS | 1651 | Values: 58.0 58.0 57.0 59.0 60.0 60.0 58.0 61.0 59.0 58.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:87.60 Median:70.00 stddev:16.08 (23.0%) stddev-sans-first:16.04 21:29:14 INFO - PROCESS | 1651 | Values: 72.0 71.0 67.0 103.0 103.0 98.0 88.0 69.0 100.0 105.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:94.30 Median:86.50 stddev:8.78 (10.2%) stddev-sans-first:7.06 21:29:14 INFO - PROCESS | 1651 | Values: 78.0 103.0 103.0 94.0 98.0 102.0 102.0 90.0 88.0 85.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:145.50 stddev:14.49 (10.0%) stddev-sans-first:15.32 21:29:14 INFO - PROCESS | 1651 | Values: 154.0 157.0 145.0 139.0 143.0 146.0 144.0 144.0 189.0 145.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.10 Median:170.00 stddev:20.58 (12.1%) stddev-sans-first:21.44 21:29:14 INFO - PROCESS | 1651 | Values: 167.0 169.0 167.0 170.0 168.0 170.0 172.0 169.0 199.0 230.0 21:29:14 INFO - PROCESS | 1651 | 21:29:14 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:75.00 stddev:9.25 (12.3%) stddev-sans-first:9.48 21:29:14 INFO - PROCESS | 1651 | Values: 68.0 71.0 68.0 67.0 68.0 66.0 79.0 90.0 85.0 86.0 21:29:14 INFO - PROCESS | 1651 | -------- Summary: end -------- 21:29:14 INFO - PROCESS | 1651 | 21:29:15 INFO - TEST-INFO | 1651: exit 0 21:29:15 INFO - TEST-OK | kraken | took 62135ms 21:29:15 INFO - SUITE-END | took 1179s 21:29:15 INFO - Completed test suite (00:19:39) 21:29:15 INFO - javascript benchmark 21:29:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7177.959658733916, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2767.944641107179, 3313.5692359993204, 3347.978866445736, 3364.1085112445207, 3370.6629337066283, 5141.485851414856, 5249.763760630772, 5617.241672374228, 5659.6321239119425, 5661.745221465034, 5647.971760141194, 5707.686077265748, 5724.313082430104, 5739.569532285074, 5740.540756739461, 5205.505476979691, 5210.322658054448, 5230.320058392404, 5237.633365664405, 5252.8686782830355, 5243.10867152585, 5257.185136303879, 5261.316028916235, 5277.3139491866195, 5300.761465734032, 5244.763985620656, 5245.318108645871, 5269.0252303324005, 5275.261463395128, 5300.337457817773, 4992.001599680042, 5070.442251352349, 5075.593952483792, 5154.098032844237, 5170.741462926876, 5038.697678139323, 5157.6647517911215, 5178.352705911761, 5214.556762675139, 5293.523582877559, 5769.423057694239, 5814.709264536757, 5826.621269617462, 5831.329397119301, 5871.941280587225, 6006.879862402728, 6033.09503574463, 6048.213732214828, 6203.565750397474, 6236.906446403307, 6116.174316467264, 6164.722587483574, 6170.568060235785, 6176.81469555914, 6198.132261483396, 5691.544676425876, 5692.8861422771315, 5702.942970570324, 5709.685967271798, 5757.366689664133, 6135.44703110649, 6183.536234782319, 6193.597416167935, 6200.472959798468, 6221.626702397871, 6071.696415179224, 6110.315755985069, 6124.777645458507, 6133.815985520441, 6141.785037523755, 4825.324454576362, 5948.553858460633, 6152.138700581922, 6157.076438534166, 6209.192804935329, 4776.450708168536, 5996.0, 6154.5076393888385, 6183.659898705614, 6203.844903877349, 6066.848328791815, 6088.8782224356155, 6110.710646019186, 6116.244470650255, 6120.081455037911, 4628.97685511577, 4717.952820471752, 4864.8783780405065, 4927.507249275044, 4934.605231581465, 4353.782310884443, 4384.0135969407265, 4407.625351845129, 4412.117576484716, 4437.467503899553, 4883.829065982744, 5612.102063669793, 5656.85857853557, 5900.321463031806, 5903.085021821625, 6098.390160983955, 6110.224634763485, 6122.398155112068, 6130.092410195535, 6148.631082134997, 5630.915536266959, 5677.37548869624, 5689.0, 5760.8271751847515, 5889.734961926723, 5336.879255356341, 5347.385050719217, 5437.15724062763, 5445.401005889349, 5465.262189604353, 5306.575473962151, 6305.747770089145, 6313.6843157841, 6327.240731112296, 6349.14286571307, 6068.575199735976, 6137.468918203611, 6140.350877192904, 6170.197874276315, 6198.72105755233, 5110.97444512775, 6120.812855101315, 6169.136320915165, 6189.876202476015, 6213.285472170758, 5279.891222843093, 6020.096985452042, 6026.879462410815, 6047.576669633084, 6055.485283750931, 4639.791209395653, 5047.520485553867, 5262.210668399771, 6042.305134909541, 6122.663253521099, 5312.681239125664, 5958.582899197015, 5959.284885813557, 6024.87950240984, 6027.246594175728, 6061.060535616986, 6085.604435711664, 6104.307579233734, 6104.938950610437, 6139.8465038372615, 5879.470847623735, 5910.083936989773, 6013.128096426081, 6014.72933717975, 6015.39846015395, 5863.648181109147, 5907.202527658883, 5982.102684597346, 6114.266288045463, 6125.785597504066, 5807.419258074159, 5821.7089145543405, 5890.381509941394, 5919.378465261086, 5992.520598352208], "value": 5590.434354401218, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.3964195650543, 479.37441638661545, 520.8801077682981, 523.3013926408244, 528.8519214619905, 37966.810165949166, 39601.81194564164, 39625.8018709906, 39722.00694982627, 39776.60223397769, 40930.38604420936, 41502.7924860376, 41541.37687934676, 41611.0, 41701.58298417015, 9258.25933925286, 9414.811703765921, 9420.576074076684, 9437.905620943806, 9462.716118516455, 8846.823063538757, 8870.201681848632, 8871.733847984571, 8886.46681199127, 8984.416012959136, 9492.572834222477, 9500.192483638899, 9521.761955951088, 9527.809443811155, 9528.665476958155, 9291.210247129002, 9374.578143983503, 9435.19800816928, 9462.763430914214, 9489.857652135224, 9315.347925645208, 9367.812643747087, 9376.343655944087, 9420.152186303198, 9421.623135074657, 38771.0, 38935.0, 39053.41419878704, 39167.0, 39178.608213918065, 3281.1961069538115, 3297.4559197732347, 3338.432466480704, 3397.184675677844, 3569.464580312974, 27804.0, 28494.0, 29175.270618234503, 30202.9428969985, 30328.848355758302, 3166.255929856482, 3190.888318908827, 3202.071399294202, 3233.8868139615, 3243.5783348164587, 459860.0, 463934.0, 469904.0, 471667.0, 472461.0, 57064.71467642635, 57340.713296434085, 57372.0, 57383.713081434325, 57498.13752793711, 60048.399516005156, 60346.793064138474, 60558.09162862472, 60879.695601522595, 61059.38940610626, 15448.845511544741, 15599.766003509956, 15687.058776473219, 15718.371265149266, 15726.977746446673, 11982.520699171935, 12194.512219511298, 12202.938985305016, 12208.938955305344, 12242.571509997108, 15261.847381526264, 16297.185140742915, 16316.836831631768, 16327.0, 16341.754873676904, 5866.384029676908, 5868.2664666916635, 5953.107033944856, 5999.970000149971, 6017.849553761191, 802.5866678660494, 955.6655170690203, 967.8886928003229, 993.2997236947934, 1043.2019505078622, 29728.0, 29886.953956611414, 30013.549796753065, 30295.545566816516, 30409.239769005508, 1026.7535791410007, 1043.7077618266896, 1059.597353005853, 1077.6066735641434, 1102.3551222534907, 14905.0, 15058.0, 15506.0, 15807.209639518209, 15921.442749503713, 193387.0, 193468.0, 194051.0, 194683.0, 194903.0, 56084.439155608736, 56472.15291770627, 56690.716546417, 56798.43201567931, 57076.0, 1308.7905935050346, 1340.1356125299128, 1345.401296423102, 1347.2051489621172, 1362.7138300956713, 23179.420514487272, 28611.713882860906, 29170.854145729136, 29511.852440737657, 29595.704042959296, 49358.75320623374, 50023.24965125599, 50082.0, 50162.49837501724, 50392.74803625958, 575301.0, 575739.0, 578414.0, 579938.0, 582616.0, 21329.253476128262, 23459.178928737412, 25450.472971621763, 25861.0, 26548.734512654628, 35559.644403555634, 44650.330245047, 45001.77499112483, 45089.54910450854, 45142.32286515639, 37603.81198093992, 37668.4769682071, 38225.80887095658, 38285.0, 38918.02704932286, 34056.829715851265, 35638.465423019195, 36345.90962271136, 38070.61929380671, 38763.612363876004, 33549.0, 36746.26507469889, 36839.07902302464, 38115.42826857655, 39142.60857391504, 32791.8360408206, 33853.984380468624, 34388.48417273793, 34468.48297275493, 34668.0, 25700.8714956424, 25705.0, 25789.742102578733, 25841.612375814002, 26121.0], "value": 18662.072083338997, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.62546577025665, 365.1838141753181, 375.2363939383355, 382.17259632894786, 388.3727779635889, 11083.889161108396, 11502.964733174013, 11618.244814087078, 11639.534418623267, 18736.156872940715, 20408.979551022465, 20771.27300544481, 20984.685229721526, 21498.785012149874, 21782.346529604096, 6275.090111933779, 6710.362515561014, 6715.630640314781, 6721.193456785192, 6721.294264102272, 5606.6636001839815, 7035.36681698647, 7401.962990185041, 7439.776806695808, 7517.436192285573, 7245.420366370704, 7400.925990740105, 7472.813179670497, 7490.400767938552, 7525.134609519893, 7281.854362912712, 7527.661255243527, 7575.9621201893915, 7588.810279742996, 7619.80950476237, 6452.903206451907, 6792.845216313239, 6835.692393842289, 7357.558546487228, 7391.445641576904, 22076.88961555198, 25685.74314256852, 26212.21363359102, 27198.1840544784, 27860.860695696596, 2363.586372384843, 2564.8332858364147, 2612.5166844133705, 2780.3466185446405, 2807.6069350290977, 14762.926185369111, 16010.359585616678, 16477.58806029847, 16647.583810404714, 16985.83014169855, 1889.7354370388157, 1904.6857268550673, 1944.8152425519554, 2019.1519561784087, 2020.8686435381653, 117411.4129429356, 137920.31039844838, 142105.289473553, 144594.0, 145839.99999999895, 21198.0, 21711.674324885138, 21805.890970545046, 21897.562048758937, 21958.780412195993, 34764.652353476646, 34838.9548313551, 35174.64825351714, 35361.82319088388, 35464.82267588697, 8618.655253789904, 9049.230815380635, 9797.118259356692, 10244.795104097877, 10589.835118136998, 7301.598412087386, 8635.006974197922, 8682.565871706389, 8691.304695624334, 8797.38418310712, 9592.616295348107, 9660.855087173699, 9691.806163876823, 9751.853722194173, 9768.95115524432, 3275.3776782411264, 3285.819279939626, 3722.9813850930573, 3821.38857782759, 3837.1558257183374, 760.6653072648018, 795.5584650518902, 840.82342708032, 902.9593668284942, 958.7459323279337, 19489.317873874345, 20429.795702043088, 20545.48636284105, 20770.376888693365, 20783.726953227782, 833.4957350802798, 886.8980067292215, 920.6363486422825, 940.6190492850407, 995.0546980368679, 8545.359098067669, 9809.11717945374, 10202.030807073315, 10686.0, 10722.839157412645, 549346.0, 560901.0, 581791.0, 590357.0, 623537.0, 93162.53418732862, 114296.85703142862, 116719.0, 126437.0, 130588.0, 948.4878165790534, 959.4627008875052, 976.72651657536, 989.7030890732751, 996.1670526231624, 14132.929335353258, 14897.925510372379, 16215.18924053769, 18680.87914725121, 18844.246230150642, 40041.799791000856, 42422.15155696931, 52770.73614632056, 54014.45985540094, 55491.72254138703, 56912.71543642255, 71881.6405917988, 79236.60381698054, 81491.18508814836, 84492.04277850245, 4773.594244489257, 4777.976110119427, 7566.394688424803, 7766.378689704923, 7770.883436748566, 10036.297459177787, 11913.927746502857, 12217.144799863925, 12314.938425307817, 12399.938000309941, 7989.400794940287, 9025.548722563713, 10406.647135872288, 10646.20153488507, 10709.892901070889, 10462.267641265038, 10611.681649550526, 10668.466576671291, 10809.189310801565, 10812.243142979916, 10694.946525267324, 11043.0, 11066.612668556561, 11145.72135696614, 11447.942760286145, 6863.553868998499, 7291.890621640574, 7443.590602516913, 7966.721164759206, 8083.272505474535, 8734.257588104829, 8783.341249406449, 10279.794404111726, 10294.485275736033, 10427.009434103747], "value": 10448.53304656125, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.457953267525, 1479.1051413894593, 1636.7872176617038, 1722.1733567887422, 1784.3754685859958, 7416.962915185417, 7692.000039994807, 7964.402669799766, 8397.286230670392, 8413.242808147274, 6281.309056003836, 8112.31045361145, 8128.471649342789, 8274.91725082749, 8289.0, 2306.1697788796064, 2336.427575244059, 2344.3318654183563, 2344.3787396339985, 2355.105060077168, 2322.3613506285737, 2351.2593533037125, 2359.6814430051895, 2372.3713215997773, 2376.4534157143867, 2302.240260713969, 2322.605157123293, 2337.0651739304244, 2338.707661542307, 2340.8361414700976, 2295.908163673451, 2299.4251437140715, 2307.849989750669, 2321.872297023663, 2333.3349995251356, 2318.733345665253, 2354.4231663242444, 2354.658574506704, 2364.1725396111397, 2370.0638247891984, 8146.348292136615, 8321.417500774949, 8322.16778322169, 8323.12607176244, 8336.458130221577, 4925.21196608541, 5004.149294619887, 5165.664231824957, 5174.0, 5179.14544100223, 2934.9853250733822, 3239.1416274687226, 3379.290349026707, 3386.7459940504314, 3392.796432214075, 1020.6886899495726, 1033.3489901362168, 1046.837740150278, 1053.8419237114417, 1068.172166570906, 10297.691069267934, 10426.270161088727, 10436.37381757097, 10441.68674939753, 10466.68599942003, 4491.146682130385, 4569.908601827945, 4717.905641887143, 4745.477997420281, 4765.7140571565815, 9266.305027122859, 9341.252699784001, 9351.205147562398, 9366.344355895159, 9368.4378937264, 5129.384473863086, 5129.563987061067, 5133.589312854962, 5137.383513978347, 5211.947880521222, 4209.2423363794815, 4221.197972385237, 4661.277501987197, 4690.601298889633, 4730.2431610942085, 3770.6606405423645, 3793.430985352164, 3858.8456461741216, 3875.5930627284292, 3895.8831235062994, 1277.3677029870273, 1283.101828719881, 1287.9098463107493, 1297.5977446991462, 1298.5909438526835, 783.3419927261128, 808.2967817998261, 811.2374368093973, 873.2010210657178, 891.3136884598823, 4483.977580112079, 4647.604953578983, 4695.3426520287185, 5563.805266815642, 5577.9163312550345, 816.93056090233, 825.6532256452305, 902.8149229407956, 916.702071826659, 937.1144268666181, 3757.1922036762226, 3762.1159027628487, 3764.8305826237884, 3796.1838204786104, 3810.942835857464, 9818.607255709834, 10046.849297260547, 10166.898331016742, 10183.083522482864, 10200.795984080276, 10153.340032897839, 10160.288779785344, 10180.185585153318, 10196.184305255561, 10462.738431539121, 6931.514793964374, 7247.710091596277, 7767.02912135983, 7949.801254968556, 8011.038675358877, 5634.464725851038, 5701.173329867063, 5821.79623713168, 5835.241418615553, 5909.645421274738, 9235.584398702209, 9254.213391861767, 9304.395214311318, 9320.300977426856, 9377.859332109887, 9314.767130821783, 9445.527723613654, 9531.046895310414, 9532.428054316762, 9545.427274363561, 6606.141201643755, 6850.725970961105, 7016.894746578908, 7111.53775004623, 7288.08898887639, 7823.921760782319, 7935.2064793520185, 7985.680572777024, 8023.799405014688, 8040.718574849852, 6998.475114366341, 7133.357997780225, 7146.9285307146265, 7156.177039640508, 7232.819179520554, 6865.828354291183, 6969.512134150561, 6992.0, 7013.158420989514, 7049.259827718016, 6658.43403310724, 6661.300563440769, 6716.0, 6716.83207919806, 6727.562708423937, 4536.319552067216, 4814.446338671098, 4821.228603423436, 4852.8301509447, 4883.681406020426, 4736.8578942631775, 4813.78337974785, 4815.133276010351, 4922.483139270325, 5022.84931452057], "value": 4380.8929588230385, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.57432574116046, 290.57721015921834, 307.2380496369004, 309.0558344258293, 310.3529141739473, 4860.465348811628, 5346.117890548055, 5486.341639003315, 5564.914841605889, 5648.887022259558, 4346.695731298811, 4765.714057156564, 4892.755362231892, 5249.107651699211, 5492.395836457997, 3514.912127196815, 4190.518090419595, 4197.559256278085, 4230.153969206156, 4233.618974292313, 4049.311617025113, 4188.659628918747, 4201.810918508674, 4217.45173127493, 4226.387173859788, 3655.981720091409, 3694.5935947045805, 4141.792910354486, 4145.80272455599, 4178.456800615916, 4026.979865100685, 4149.2531344358, 4158.7296825706535, 4172.436721042651, 4177.7284476509085, 3084.306031142997, 3600.6939410150167, 3645.7812531247946, 3663.8275751759447, 3667.2665466906724, 4549.340345649863, 5197.54468748751, 5334.186056969285, 5437.293151890229, 5454.290942177531, 3591.5690117185845, 4684.5549672781035, 4686.812527498896, 4717.386739723848, 4722.858314250578, 4385.184889146463, 5564.0, 5657.660540367591, 5778.6243894147165, 5834.416558344132, 3317.9004629861142, 3407.693307602303, 3645.489631451572, 3890.2025084857537, 3949.032487040665, 5076.6192535559985, 6158.72285747138, 6192.9380706192815, 6216.2851272103835, 6241.843953901189, 3352.513885486591, 3998.0209796151094, 4017.417474466186, 4030.1133750574827, 4036.0, 3440.7935523868646, 3757.4363845423404, 3989.9800500997308, 4005.098852758106, 4061.147159096564, 3999.860039888618, 4708.976455117702, 4731.313959475857, 4737.3841400618385, 4743.217369134123, 4937.35814344133, 5813.244278243801, 5848.941510584925, 5862.70686465675, 5880.852978675567, 1216.6958260434892, 1253.7931241345075, 1256.874312568754, 1289.4970961325091, 1295.7732396830518, 769.9422543309274, 834.8956380452444, 870.5821205821242, 877.3814460805245, 877.6046086722015, 4461.769115442279, 4794.232922732346, 5242.18746094356, 5264.92102618461, 5324.653897496651, 5613.298337707786, 5635.6618602883955, 5668.064769313017, 5691.772329106872, 5762.337331206881, 4282.892927676833, 4479.328100784908, 4919.794650310697, 5142.665726727751, 5183.222516622537, 2585.1985884375904, 2767.8861281058807, 2879.006742673774, 2914.81053731511, 2924.5613158026467, 5228.503292187236, 5572.777088916398, 5812.505936995318, 5812.953668339739, 5844.240248767718, 5259.526642602184, 5436.320459942507, 5744.070524032773, 5752.827415177468, 5847.649141051551, 4914.32169467641, 4947.441555910021, 5600.535095688274, 5783.624064435799, 5847.38602446737, 4814.614830813456, 5372.501786669218, 5425.945740542702, 5592.0, 5648.293963254593, 5096.43939166684, 5200.713960732196, 5251.844594189272, 5376.838694839161, 5682.715864206691, 5052.635788337202, 5415.864603384947, 5464.453554644504, 5846.988800767523, 5870.180244124335, 3217.0463741207122, 3338.0987133474314, 3601.4249811384984, 3719.7681391166166, 3779.3764028934916, 4415.933760993652, 5109.565686916505, 5232.293640358503, 5529.585281103853, 5664.0, 4606.861794146181, 4998.575121114746, 5201.803585175507, 5310.522053015247, 5761.942380576141, 4769.642276829182, 5087.567556757717, 5582.413846546055, 5890.319904443382, 5914.34942156371, 4903.387076615423, 5558.360788509373, 5618.612089861874, 5731.8853622928145, 5799.652020878761, 5325.627206095536, 5795.797596913146, 5841.620294680833, 5867.383924687846, 5907.527397808251, 5670.659760414388, 5681.431856814451, 5700.771969121354, 5723.456271654186, 5779.682117483579], "value": 4071.094492385141, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.9023651334041, 687.6699184391492, 742.3615690506167, 765.1506827421562, 768.7885831396366, 13963.860361396382, 14742.705145897076, 14750.55748327552, 14781.482648107318, 14833.332500037497, 12582.748345033095, 13160.0, 13166.341682915863, 13213.537526186585, 13280.07039507235, 625.337142628815, 649.7043845050491, 773.7059917231472, 793.7063286583943, 809.7894547417654, 787.582581231948, 797.3103265675207, 801.5992003998001, 802.4062193976474, 802.4423025996934, 857.0743596915347, 857.6440777077505, 860.0496451421184, 861.0399404663817, 861.2593169873935, 821.5563595658338, 839.5466448117978, 852.8763329317214, 853.159637756809, 853.7865533616596, 607.9483243924271, 612.727336335331, 630.2342653675806, 630.6752022708309, 631.5736877607596, 13288.60134195966, 13596.32018399086, 13603.795943060863, 13610.59168224955, 14251.64370890736, 13400.530981415604, 13652.385642646006, 13675.247861367621, 13908.58274251764, 14061.367238474293, 10974.286671366335, 11147.609833655782, 11252.774944501147, 11399.0, 11455.312681239153, 3462.117160124177, 3605.675489205984, 3625.8187090645624, 3627.2201476682376, 3634.1641422472717, 79564.0, 86539.99999999937, 88894.99972501253, 92040.6193907092, 94461.52769236178, 9884.604615815431, 10284.228682848667, 10554.102901253325, 10582.629607963838, 10723.356598604109, 18405.355812546495, 20398.082086306153, 20406.591868162555, 20471.590568188552, 20475.0, 6421.935780642228, 6437.645929473831, 6614.305497922745, 7199.964000179965, 7880.172581878935, 4257.936130958038, 4280.892977675583, 4377.78111094444, 4410.985473341149, 4443.0, 3429.8285085745615, 3542.5217595624263, 3549.538559987185, 3559.519464872261, 3581.283743251308, 650.7657243392375, 660.8942569188906, 663.9634820084942, 695.4366962760181, 710.3855165282097, 548.1667864845413, 591.0100581525927, 616.5006344860677, 620.2804746494045, 620.2835724737935, 8861.335399845037, 8917.821643567222, 8975.77560560978, 8997.460152390877, 9114.453132812052, 650.4698670583459, 654.1561385812249, 657.139147716493, 668.1414382518537, 686.7150132694976, 4199.223143718422, 4204.306289462265, 4337.587929146726, 4371.606555410029, 4387.561243875587, 76345.0, 80138.0, 104486.47756761167, 113483.0, 117611.41194293974, 95132.52433737786, 97110.0288997101, 99418.0, 99650.00349996408, 100346.0, 86022.56988715017, 87454.56272718469, 87884.56057719671, 89610.55194724115, 92204.07795921955, 8163.083236684825, 8471.830563388574, 8474.74575762728, 8601.655933762579, 8666.176713212235, 56311.43688563226, 56399.71800140973, 56835.43164568301, 57737.13394299173, 58712.412875870694, 52430.73784631053, 81023.78464322921, 82883.0, 83359.5832020836, 83485.0, 11108.777824443627, 11203.43982800873, 11383.146264030065, 11463.942680286878, 11614.6515604532, 11664.76670466569, 11732.354720490455, 12355.25868447896, 12402.0, 12414.317212553397, 11129.833052504056, 11285.943570282096, 11401.771964560829, 11449.427528623703, 11763.529458821551, 11550.59572914944, 11763.647090587296, 11884.405779711153, 11964.700882478008, 12332.630021099381, 11012.284201526874, 11419.37193454368, 11536.653900383002, 11815.704607384883, 11874.940625296818, 7810.258025487569, 9254.12085851843, 9770.0, 9978.0, 10606.363618182933, 7516.436267279866, 8171.83656326882, 8691.435056721293, 8863.689770858247, 9947.900520994697], "value": 7034.3582627066835, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 170.0, 126.0, 127.0, 118.0, 117.0, 117.0, 119.0, 130.0, 171.0], "value": 133.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 117.0, 146.0, 111.0, 113.0, 113.0, 114.0, 139.0, 132.0], "value": 124.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 199.0, 191.0, 202.0, 234.0, 198.0, 196.0, 194.0, 199.0, 195.0], "value": 200.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 63.0, 88.0, 61.0, 60.0, 61.0, 58.0, 80.0, 60.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 70.0, 92.0, 74.0, 70.0, 71.0, 87.0, 70.0, 71.0, 89.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 128.0, 94.0, 94.0, 106.0, 92.0, 85.0, 74.0, 83.0, 117.0], "value": 95.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 117.0, 97.0, 161.0, 104.0, 100.0, 99.0, 151.0, 147.0], "value": 130.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 129.0, 77.0, 136.0, 135.0, 124.0, 92.0, 108.0, 119.0, 87.0], "value": 109.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 59.0, 60.0, 60.0, 58.0, 61.0, 59.0, 58.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 67.0, 103.0, 103.0, 98.0, 88.0, 69.0, 100.0, 105.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 103.0, 103.0, 94.0, 98.0, 102.0, 102.0, 90.0, 88.0, 85.0], "value": 94.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 157.0, 145.0, 139.0, 143.0, 146.0, 144.0, 144.0, 189.0, 145.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 167.0, 170.0, 168.0, 170.0, 172.0, 169.0, 199.0, 230.0], "value": 178.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 68.0, 67.0, 68.0, 66.0, 79.0, 90.0, 85.0, 86.0], "value": 74.8, "unit": "score"}]}]} 21:29:15 INFO - Return code: 0 21:29:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:29:15 ERROR - # TBPL SUCCESS # 21:29:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:29:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:29:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:29:15 INFO - 2016-12-15 21:29:15.424 screenresolution[1656:10840] starting screenresolution argv=screenresolution get 21:29:15 INFO - 2016-12-15 21:29:15.427 screenresolution[1656:10840] Display 0: 1600x1200x32@60 21:29:15 INFO - 2016-12-15 21:29:15.433 screenresolution[1657:10843] starting screenresolution argv=screenresolution list 21:29:15 INFO - Available Modes on Display 0 21:29:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:29:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:29:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:29:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:29:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:29:15 INFO - Intel Iris: 21:29:15 INFO - Chipset Model: Intel Iris 21:29:15 INFO - Type: GPU 21:29:15 INFO - Bus: Built-In 21:29:15 INFO - VRAM (Dynamic, Max): 1536 MB 21:29:15 INFO - Vendor: Intel (0x8086) 21:29:15 INFO - Device ID: 0x0a2e 21:29:15 INFO - Revision ID: 0x0009 21:29:15 INFO - Displays: 21:29:15 INFO - Display: 21:29:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:29:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:29:15 INFO - Display Serial Number: 1600x1200 60 21:29:15 INFO - Main Display: Yes 21:29:15 INFO - Mirror: Off 21:29:15 INFO - Online: Yes 21:29:15 INFO - Rotation: Supported 21:29:15 INFO - Return code: 0 21:29:15 INFO - Running post-action listener: _resource_record_post_action 21:29:15 INFO - [mozharness: 2016-12-16 05:29:15.683100Z] Finished run-tests step (success) 21:29:15 INFO - Running post-run listener: _resource_record_post_run 21:29:15 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38558208; Write bytes: 660815360; Read time: 246; Write time: 1954 21:29:15 INFO - TinderboxPrint: CPU usage
54.4% 21:29:15 INFO - TinderboxPrint: I/O read bytes / time
38,558,208 / 246 21:29:15 INFO - TinderboxPrint: I/O write bytes / time
660,815,360 / 1,954 21:29:15 INFO - TinderboxPrint: CPU idle
2,213.4 (45.6%) 21:29:15 INFO - TinderboxPrint: CPU system
979.8 (20.2%) 21:29:15 INFO - TinderboxPrint: CPU user
1,656.5 (34.2%) 21:29:15 INFO - TinderboxPrint: Swap in / out
387,137,536 / 0 21:29:15 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 347988480; Write bytes: 345217024; Read time: 28435; Write time: 726 21:29:15 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35293696; Write bytes: 308315648; Read time: 220; Write time: 1198 21:29:16 INFO - Running post-run listener: _upload_blobber_files 21:29:16 INFO - Blob upload gear active. 21:29:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:29:16 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 21:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:29:16 INFO - (blobuploader) - INFO - Open directory for files ... 21:29:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:29:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:17 INFO - (blobuploader) - INFO - Done attempting. 21:29:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:29:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:29:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:18 INFO - (blobuploader) - INFO - Done attempting. 21:29:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:29:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:29:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:20 INFO - (blobuploader) - INFO - Done attempting. 21:29:20 INFO - (blobuploader) - INFO - Iteration through files over. 21:29:20 INFO - Return code: 0 21:29:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:29:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:29:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6c1ddbe6e69e5e6fac211af25c8df6820e96968d7bb51dab34322eefbaf7f302297c6223672e4254466d9be795c2f0ebd61ffc793dcac4069fe03833a5c60582", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/430da4d4b6f3e9038fc1a4e767592f3b30c0bf0b06f7787f946a897726b47be6ebad1988b2445e54ca7a543fc45cf9f1c3bf19c48eea4bb1a66091eb8fd7bce5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e054608c413092f18e84c7dfc54f3e09fe40a2543ad38833517e9cd732a692097d9b38a23ce2b60dfcc0adad8ba0d8fdc78b6bb763cada42ef15099cf661cb60"} 21:29:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:29:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:29:20 INFO - Contents: 21:29:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6c1ddbe6e69e5e6fac211af25c8df6820e96968d7bb51dab34322eefbaf7f302297c6223672e4254466d9be795c2f0ebd61ffc793dcac4069fe03833a5c60582", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/430da4d4b6f3e9038fc1a4e767592f3b30c0bf0b06f7787f946a897726b47be6ebad1988b2445e54ca7a543fc45cf9f1c3bf19c48eea4bb1a66091eb8fd7bce5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e054608c413092f18e84c7dfc54f3e09fe40a2543ad38833517e9cd732a692097d9b38a23ce2b60dfcc0adad8ba0d8fdc78b6bb763cada42ef15099cf661cb60"} 21:29:20 INFO - Running post-run listener: copy_logs_to_upload_dir 21:29:20 INFO - Copying logs to upload dir... 21:29:20 INFO - mkdir: /builds/slave/test/build/upload/logs 21:29:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.635468 ========= master_lag: 2.89 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-15 21:29:23.705898) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 21:29:23.709926) ========= 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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6c1ddbe6e69e5e6fac211af25c8df6820e96968d7bb51dab34322eefbaf7f302297c6223672e4254466d9be795c2f0ebd61ffc793dcac4069fe03833a5c60582", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/430da4d4b6f3e9038fc1a4e767592f3b30c0bf0b06f7787f946a897726b47be6ebad1988b2445e54ca7a543fc45cf9f1c3bf19c48eea4bb1a66091eb8fd7bce5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e054608c413092f18e84c7dfc54f3e09fe40a2543ad38833517e9cd732a692097d9b38a23ce2b60dfcc0adad8ba0d8fdc78b6bb763cada42ef15099cf661cb60"} build_url:https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010391 build_url: 'https://queue.taskcluster.net/v1/task/DwKKpR-NSMePJPwlenrZxg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6c1ddbe6e69e5e6fac211af25c8df6820e96968d7bb51dab34322eefbaf7f302297c6223672e4254466d9be795c2f0ebd61ffc793dcac4069fe03833a5c60582", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/430da4d4b6f3e9038fc1a4e767592f3b30c0bf0b06f7787f946a897726b47be6ebad1988b2445e54ca7a543fc45cf9f1c3bf19c48eea4bb1a66091eb8fd7bce5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e054608c413092f18e84c7dfc54f3e09fe40a2543ad38833517e9cd732a692097d9b38a23ce2b60dfcc0adad8ba0d8fdc78b6bb763cada42ef15099cf661cb60"}' ========= master_lag: 0.51 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 21:29:24.228534) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 21:29:24.228875) ========= 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.lGcAb1Py2s/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.wKOYbC0iwN/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.005091 ========= master_lag: 0.20 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 21:29:24.436943) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 21:29:24.437254) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 21:29:24.437612) ========= ========= Total master_lag: 3.67 =========