builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0084 starttime: 1482040046.55 results: success (0) buildid: 20161217201235 builduid: d61100b0b8f94ab2a50f3e31796cc1d0 revision: 5baae61449c43851c961a83babe18adaa28e3c6b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:26.552932) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:26.553309) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:26.553591) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:26.753865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:26.754191) ========= 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.b7BItHYavl/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.tQh5wupGZF/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-17 21:47:26-- 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% 7.80M=0.001s 2016-12-17 21:47:27 (7.80 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.502835 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:27.354221) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:27.354543) ========= 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.b7BItHYavl/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.tQh5wupGZF/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.018138 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:27.418892) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 21:47:27.419200) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5baae61449c43851c961a83babe18adaa28e3c6b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5baae61449c43851c961a83babe18adaa28e3c6b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b7BItHYavl/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.tQh5wupGZF/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-17 21:47:27,491 truncating revision to first 12 chars 2016-12-17 21:47:27,491 Setting DEBUG logging. 2016-12-17 21:47:27,491 attempt 1/10 2016-12-17 21:47:27,491 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5baae61449c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-17 21:47:28,266 unpacking tar archive at: mozilla-central-5baae61449c4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.299145 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 21:47:28.758640) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:28.758996) ========= script_repo_revision: 5baae61449c43851c961a83babe18adaa28e3c6b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:28.759449) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:28.759753) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 21:47:28.785921) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-17 21:47:28.789782) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b7BItHYavl/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.tQh5wupGZF/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:47:28 INFO - MultiFileLogger online at 20161217 21:47:28 in /builds/slave/test 21:47:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:47:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:47:28 INFO - {'append_to_log': False, 21:47:28 INFO - 'base_work_dir': '/builds/slave/test', 21:47:28 INFO - 'blob_upload_branch': 'Firefox', 21:47:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:47:28 INFO - 'branch': 'Firefox', 21:47:28 INFO - 'buildbot_json_path': 'buildprops.json', 21:47:28 INFO - 'config_files': ('talos/mac_config.py',), 21:47:28 INFO - 'default_actions': ('clobber', 21:47:28 INFO - 'read-buildbot-config', 21:47:28 INFO - 'download-and-extract', 21:47:28 INFO - 'populate-webroot', 21:47:28 INFO - 'create-virtualenv', 21:47:28 INFO - 'install', 21:47:28 INFO - 'run-tests'), 21:47:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:47:28 INFO - 'download_minidump_stackwalk': True, 21:47:28 INFO - 'download_symbols': 'ondemand', 21:47:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:47:28 INFO - 'tooltool.py': '/tools/tooltool.py', 21:47:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:47:28 INFO - '/tools/misc-python/virtualenv.py')}, 21:47:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:47:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:47:28 INFO - 'installer_path': 'installer.exe', 21:47:28 INFO - 'log_level': 'info', 21:47:28 INFO - 'log_name': 'talos', 21:47:28 INFO - 'log_to_console': True, 21:47:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:47:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:47:28 INFO - 'opt_config_files': (), 21:47:28 INFO - 'pip_index': False, 21:47:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:47:28 INFO - 'cmd': ('bash', 21:47:28 INFO - '-c', 21:47:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:47:28 INFO - 'enabled': True, 21:47:28 INFO - 'halt_on_failure': False, 21:47:28 INFO - 'name': 'check_screen_resolution'},), 21:47:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:47:28 INFO - 'cmd': ('bash', 21:47:28 INFO - '-c', 21:47:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:47:28 INFO - 'enabled': True, 21:47:28 INFO - 'halt_on_failure': False, 21:47:28 INFO - 'name': 'check_screen_resolution'},), 21:47:28 INFO - 'run_cmd_checks_enabled': True, 21:47:28 INFO - 'sps_profile': False, 21:47:28 INFO - 'sps_profile_interval': 0, 21:47:28 INFO - 'suite': 'dromaeojs-e10s', 21:47:28 INFO - 'system_bits': '32', 21:47:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:47:28 INFO - 'title': 't-yosemite-r7-0084', 21:47:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:47:28 INFO - 'use_talos_json': True, 21:47:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:47:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:47:28 INFO - 'work_dir': 'build'} 21:47:28 INFO - [mozharness: 2016-12-18 05:47:28.991265Z] Running clobber step. 21:47:28 INFO - Running pre-action listener: _resource_record_pre_action 21:47:28 INFO - Running main action method: clobber 21:47:28 INFO - rmtree: /builds/slave/test/build 21:47:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:47:30 INFO - Running post-action listener: _resource_record_post_action 21:47:30 INFO - [mozharness: 2016-12-18 05:47:30.468898Z] Finished clobber step (success) 21:47:30 INFO - [mozharness: 2016-12-18 05:47:30.469056Z] Running read-buildbot-config step. 21:47:30 INFO - Running pre-action listener: _resource_record_pre_action 21:47:30 INFO - Running main action method: read_buildbot_config 21:47:30 INFO - Using buildbot properties: 21:47:30 INFO - { 21:47:30 INFO - "project": "", 21:47:30 INFO - "product": "firefox", 21:47:30 INFO - "script_repo_revision": "production", 21:47:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:47:30 INFO - "repository": "", 21:47:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:47:30 INFO - "buildid": "20161217201235", 21:47:30 INFO - "pgo_build": "False", 21:47:30 INFO - "basedir": "/builds/slave/test", 21:47:30 INFO - "buildnumber": 12, 21:47:30 INFO - "slavename": "t-yosemite-r7-0084", 21:47:30 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 21:47:30 INFO - "platform": "yosemite_r7", 21:47:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:47:30 INFO - "branch": "mozilla-central", 21:47:30 INFO - "repo_path": "mozilla-central", 21:47:30 INFO - "revision": "5baae61449c43851c961a83babe18adaa28e3c6b", 21:47:30 INFO - "stage_platform": "macosx64", 21:47:30 INFO - "builduid": "d61100b0b8f94ab2a50f3e31796cc1d0", 21:47:30 INFO - "slavebuilddir": "test" 21:47:30 INFO - } 21:47:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 21:47:30 INFO - Running post-action listener: _resource_record_post_action 21:47:30 INFO - [mozharness: 2016-12-18 05:47:30.474649Z] Finished read-buildbot-config step (success) 21:47:30 INFO - [mozharness: 2016-12-18 05:47:30.474825Z] Running download-and-extract step. 21:47:30 INFO - Running pre-action listener: _resource_record_pre_action 21:47:30 INFO - Running main action method: download_and_extract 21:47:30 INFO - mkdir: /builds/slave/test/build/tests 21:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:30 INFO - trying https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 21:47:30 INFO - Downloading https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:47:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 21:47:32 INFO - Downloaded 1199 bytes. 21:47:32 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:47:32 INFO - Using the following test package requirements: 21:47:32 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:47:32 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:47:32 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 21:47:32 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:47:32 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 21:47:32 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:47:32 INFO - u'jsshell-mac.zip'], 21:47:32 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:47:32 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 21:47:32 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:47:32 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:47:32 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 21:47:32 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:47:32 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 21:47:32 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:47:32 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 21:47:32 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:47:32 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 21:47:32 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:47:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:47:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:47:32 INFO - Fetch https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:47:33 INFO - Expected file size: 16275318 21:47:33 INFO - Obtained file size: 16275318 21:47:39 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:47:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:47:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:47:39 INFO - Fetch https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:47:41 INFO - Expected file size: 16275318 21:47:41 INFO - Obtained file size: 16275318 21:47:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 21:47:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:47:47 INFO - Fetch https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 21:47:48 INFO - Expected file size: 14409734 21:47:48 INFO - Obtained file size: 14409734 21:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:49 INFO - trying https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:47:49 INFO - Downloading https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 21:47:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 21:47:53 INFO - Downloaded 69562714 bytes. 21:47:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:47:53 INFO - mkdir: /builds/slave/test/properties 21:47:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:47:53 INFO - Writing to file /builds/slave/test/properties/build_url 21:47:53 INFO - Contents: 21:47:53 INFO - build_url:https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:47:54 INFO - Running post-action listener: _resource_record_post_action 21:47:54 INFO - Running post-action listener: set_extra_try_arguments 21:47:54 INFO - [mozharness: 2016-12-18 05:47:54.366496Z] Finished download-and-extract step (success) 21:47:54 INFO - [mozharness: 2016-12-18 05:47:54.366617Z] Running populate-webroot step. 21:47:54 INFO - Running pre-action listener: _resource_record_pre_action 21:47:54 INFO - Running main action method: populate_webroot 21:47:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:47:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:47:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:47:54 INFO - '--tppagecycles', 21:47:54 INFO - '7'], 21:47:54 INFO - 'tests': ['tsvgm']}}, 21:47:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:47:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:47:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:47:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:47:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:47:54 INFO - 'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:47:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:47:54 INFO - 'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['damp', 'tps']}, 21:47:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:47:54 INFO - 'tests': ['damp', 'tps']}, 21:47:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['dromaeo_dom']}, 21:47:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:47:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['basic_compositor_video']}, 21:47:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:47:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['a11yr', 21:47:54 INFO - 'ts_paint', 21:47:54 INFO - 'tpaint', 21:47:54 INFO - 'sessionrestore', 21:47:54 INFO - 'sessionrestore_no_auto_restore', 21:47:54 INFO - 'tabpaint']}, 21:47:54 INFO - 'other-e10s': {'tests': ['a11yr', 21:47:54 INFO - 'ts_paint', 21:47:54 INFO - 'tpaint', 21:47:54 INFO - 'sessionrestore', 21:47:54 INFO - 'sessionrestore_no_auto_restore', 21:47:54 INFO - 'tabpaint']}, 21:47:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:47:54 INFO - 'ts_paint', 21:47:54 INFO - 'tpaint', 21:47:54 INFO - 'sessionrestore', 21:47:54 INFO - 'sessionrestore_no_auto_restore', 21:47:54 INFO - 'tabpaint']}, 21:47:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:47:54 INFO - 'ts_paint', 21:47:54 INFO - 'tpaint', 21:47:54 INFO - 'sessionrestore', 21:47:54 INFO - 'sessionrestore_no_auto_restore', 21:47:54 INFO - 'tabpaint']}, 21:47:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['a11yr', 21:47:54 INFO - 'ts_paint', 21:47:54 INFO - 'tpaint', 21:47:54 INFO - 'sessionrestore', 21:47:54 INFO - 'sessionrestore_no_auto_restore', 21:47:54 INFO - 'tabpaint']}, 21:47:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['a11yr', 21:47:54 INFO - 'ts_paint', 21:47:54 INFO - 'tpaint', 21:47:54 INFO - 'sessionrestore', 21:47:54 INFO - 'sessionrestore_no_auto_restore', 21:47:54 INFO - 'tabpaint']}, 21:47:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['tsvgx', 21:47:54 INFO - 'tsvgr_opacity', 21:47:54 INFO - 'tart', 21:47:54 INFO - 'tscrollx', 21:47:54 INFO - 'cart', 21:47:54 INFO - 'tsvg_static']}, 21:47:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:47:54 INFO - 'tsvgr_opacity', 21:47:54 INFO - 'tart', 21:47:54 INFO - 'tscrollx', 21:47:54 INFO - 'cart', 21:47:54 INFO - 'tsvg_static']}, 21:47:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:47:54 INFO - 'talos_options': ['--disable-e10s'], 21:47:54 INFO - 'tests': ['tp5o']}, 21:47:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:47:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:47:54 INFO - 'talos_options': ['--disable-e10s', 21:47:54 INFO - '--xperf_path', 21:47:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:54 INFO - 'tests': ['tp5n']}, 21:47:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:47:54 INFO - 'talos_options': ['--xperf_path', 21:47:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:54 INFO - 'tests': ['tp5n']}}, 21:47:54 INFO - 'talos.zip': {'path': '', 21:47:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:47:54 INFO - Running post-action listener: _resource_record_post_action 21:47:54 INFO - [mozharness: 2016-12-18 05:47:54.385045Z] Finished populate-webroot step (success) 21:47:54 INFO - [mozharness: 2016-12-18 05:47:54.385176Z] Running create-virtualenv step. 21:47:54 INFO - Running pre-action listener: _resource_record_pre_action 21:47:54 INFO - Running main action method: create_virtualenv 21:47:54 INFO - Creating virtualenv /builds/slave/test/build/venv 21:47:54 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:47:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:47:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:47:54 INFO - Using real prefix '/tools/python27' 21:47:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:47:55 INFO - Installing distribute.............................................................................................................................................................................................done. 21:47:58 INFO - Installing pip.................done. 21:47:58 INFO - Return code: 0 21:47:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:47:58 INFO - Reading from file tmpfile_stdout 21:47:58 INFO - Output received: 21:47:58 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:47:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:58 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 0x10e8c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd078dcef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, '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.b7BItHYavl/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.tQh5wupGZF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:47:58 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:47:58 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:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:58 INFO - 'HOME': '/Users/cltbld', 21:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:58 INFO - 'LOGNAME': 'cltbld', 21:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:58 INFO - 'MOZ_NO_REMOTE': '1', 21:47:58 INFO - 'NO_EM_RESTART': '1', 21:47:58 INFO - 'PAGER': '/bin/cat', 21:47:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:58 INFO - 'PWD': '/builds/slave/test', 21:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:58 INFO - 'SHELL': '/bin/bash', 21:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:47:58 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:58 INFO - 'USER': 'cltbld', 21:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:58 INFO - 'XPC_FLAGS': '0x0', 21:47:58 INFO - 'XPC_SERVICE_NAME': '0', 21:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:58 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:47:58 INFO - Cleaning up... 21:47:58 INFO - Return code: 0 21:47:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:58 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 0x10e8c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd078dcef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, '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.b7BItHYavl/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.tQh5wupGZF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:47:58 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:47:58 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:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:58 INFO - 'HOME': '/Users/cltbld', 21:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:58 INFO - 'LOGNAME': 'cltbld', 21:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:58 INFO - 'MOZ_NO_REMOTE': '1', 21:47:58 INFO - 'NO_EM_RESTART': '1', 21:47:58 INFO - 'PAGER': '/bin/cat', 21:47:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:58 INFO - 'PWD': '/builds/slave/test', 21:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:58 INFO - 'SHELL': '/bin/bash', 21:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:47:58 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:58 INFO - 'USER': 'cltbld', 21:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:58 INFO - 'XPC_FLAGS': '0x0', 21:47:58 INFO - 'XPC_SERVICE_NAME': '0', 21:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:58 INFO - Downloading/unpacking psutil>=3.1.1 21:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:48:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:02 INFO - Installing collected packages: psutil 21:48:02 INFO - Running setup.py install for psutil 21:48:02 INFO - building 'psutil._psutil_osx' extension 21:48:02 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:48:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 21:48:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 21:48:03 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:48:03 INFO - building 'psutil._psutil_posix' extension 21:48:03 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:48:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:48:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:48:03 INFO - ^ 21:48:03 INFO - 1 warning generated. 21:48:03 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:48:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:03 INFO - Successfully installed psutil 21:48:03 INFO - Cleaning up... 21:48:03 INFO - Return code: 0 21:48:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:03 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 0x10e8c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd078dcef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, '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.b7BItHYavl/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.tQh5wupGZF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:48:03 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:48:03 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:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:03 INFO - 'HOME': '/Users/cltbld', 21:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:03 INFO - 'LOGNAME': 'cltbld', 21:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:03 INFO - 'MOZ_NO_REMOTE': '1', 21:48:03 INFO - 'NO_EM_RESTART': '1', 21:48:03 INFO - 'PAGER': '/bin/cat', 21:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:03 INFO - 'PWD': '/builds/slave/test', 21:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:03 INFO - 'SHELL': '/bin/bash', 21:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:48:03 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:03 INFO - 'USER': 'cltbld', 21:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:03 INFO - 'XPC_FLAGS': '0x0', 21:48:03 INFO - 'XPC_SERVICE_NAME': '0', 21:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:48:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:48:06 INFO - Installing collected packages: mozsystemmonitor 21:48:06 INFO - Running setup.py install for mozsystemmonitor 21:48:06 INFO - Successfully installed mozsystemmonitor 21:48:06 INFO - Cleaning up... 21:48:06 INFO - Return code: 0 21:48:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:06 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 0x10e8c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd078dcef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, '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.b7BItHYavl/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.tQh5wupGZF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:48:06 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:48:06 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:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:06 INFO - 'HOME': '/Users/cltbld', 21:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:06 INFO - 'LOGNAME': 'cltbld', 21:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:06 INFO - 'MOZ_NO_REMOTE': '1', 21:48:06 INFO - 'NO_EM_RESTART': '1', 21:48:06 INFO - 'PAGER': '/bin/cat', 21:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:06 INFO - 'PWD': '/builds/slave/test', 21:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:06 INFO - 'SHELL': '/bin/bash', 21:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:48:06 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:06 INFO - 'USER': 'cltbld', 21:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:06 INFO - 'XPC_FLAGS': '0x0', 21:48:06 INFO - 'XPC_SERVICE_NAME': '0', 21:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:07 INFO - Downloading/unpacking jsonschema==2.5.1 21:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:48:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:10 INFO - Downloading functools32-3.2.3-2.tar.gz 21:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:48:10 INFO - warning: no files found matching '*.txt' 21:48:10 INFO - no previously-included directories found matching 'build' 21:48:10 INFO - no previously-included directories found matching 'dist' 21:48:10 INFO - no previously-included directories found matching '.git*' 21:48:10 INFO - Installing collected packages: jsonschema, functools32 21:48:10 INFO - Running setup.py install for functools32 21:48:10 INFO - warning: no files found matching '*.txt' 21:48:10 INFO - no previously-included directories found matching 'build' 21:48:10 INFO - no previously-included directories found matching 'dist' 21:48:10 INFO - no previously-included directories found matching '.git*' 21:48:10 INFO - Successfully installed jsonschema functools32 21:48:10 INFO - Cleaning up... 21:48:10 INFO - Return code: 0 21:48:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e8c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd078dcef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, '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.b7BItHYavl/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.tQh5wupGZF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:48:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:48:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:48:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:10 INFO - 'HOME': '/Users/cltbld', 21:48:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:10 INFO - 'LOGNAME': 'cltbld', 21:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:10 INFO - 'MOZ_NO_REMOTE': '1', 21:48:10 INFO - 'NO_EM_RESTART': '1', 21:48:10 INFO - 'PAGER': '/bin/cat', 21:48:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:10 INFO - 'PWD': '/builds/slave/test', 21:48:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:10 INFO - 'SHELL': '/bin/bash', 21:48:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:48:10 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:48:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:10 INFO - 'USER': 'cltbld', 21:48:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:10 INFO - 'XPC_FLAGS': '0x0', 21:48:10 INFO - 'XPC_SERVICE_NAME': '0', 21:48:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:48:10 INFO - Cleaning up... 21:48:10 INFO - Return code: 0 21:48:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e8c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd078dcef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, '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.b7BItHYavl/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.tQh5wupGZF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:48:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:48:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:48:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:10 INFO - 'HOME': '/Users/cltbld', 21:48:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:10 INFO - 'LOGNAME': 'cltbld', 21:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:10 INFO - 'MOZ_NO_REMOTE': '1', 21:48:10 INFO - 'NO_EM_RESTART': '1', 21:48:10 INFO - 'PAGER': '/bin/cat', 21:48:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:10 INFO - 'PWD': '/builds/slave/test', 21:48:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:10 INFO - 'SHELL': '/bin/bash', 21:48:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:48:10 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:48:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:10 INFO - 'USER': 'cltbld', 21:48:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:10 INFO - 'XPC_FLAGS': '0x0', 21:48:10 INFO - 'XPC_SERVICE_NAME': '0', 21:48:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:10 INFO - Downloading/unpacking blobuploader==1.2.4 21:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:13 INFO - Downloading blobuploader-1.2.4.tar.gz 21:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:48:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:48:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:48:14 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:48:14 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:48:14 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:48:14 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:48:14 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:48:14 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:48:14 INFO - Downloading docopt-0.6.1.tar.gz 21:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:48:14 INFO - Installing collected packages: blobuploader, requests, docopt 21:48:14 INFO - Running setup.py install for blobuploader 21:48:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:48:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:48:14 INFO - Running setup.py install for requests 21:48:15 INFO - Running setup.py install for docopt 21:48:15 INFO - Successfully installed blobuploader requests docopt 21:48:15 INFO - Cleaning up... 21:48:15 INFO - Return code: 0 21:48:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:15 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 0x10e8c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd078dcef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, '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.b7BItHYavl/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.tQh5wupGZF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:48:15 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:48:15 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:48:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:48:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:15 INFO - 'HOME': '/Users/cltbld', 21:48:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:15 INFO - 'LOGNAME': 'cltbld', 21:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:15 INFO - 'MOZ_NO_REMOTE': '1', 21:48:15 INFO - 'NO_EM_RESTART': '1', 21:48:15 INFO - 'PAGER': '/bin/cat', 21:48:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:15 INFO - 'PWD': '/builds/slave/test', 21:48:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:15 INFO - 'SHELL': '/bin/bash', 21:48:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:48:15 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:48:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:15 INFO - 'USER': 'cltbld', 21:48:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:15 INFO - 'XPC_FLAGS': '0x0', 21:48:15 INFO - 'XPC_SERVICE_NAME': '0', 21:48:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:15 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-McyAir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ncWx7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-3hoD4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-uK2Rs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-_ILlbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-bYWjjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-e7ONVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Q97mMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-9wQqNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-7Vfy_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-lKFIY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-eJXv9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-R6J0x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-2QCIm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-nPOPGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-m5WK62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ykIEuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:48:17 INFO - Running setup.py install for manifestparser 21:48:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:48:17 INFO - Running setup.py install for mozcrash 21:48:17 INFO - Running setup.py install for mozdebug 21:48:18 INFO - Running setup.py install for mozdevice 21:48:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:48:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:48:18 INFO - Running setup.py install for mozfile 21:48:18 INFO - Running setup.py install for mozhttpd 21:48:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:48:18 INFO - Running setup.py install for mozinfo 21:48:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:48:18 INFO - Running setup.py install for mozInstall 21:48:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:48:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:48:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:48:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:48:18 INFO - Running setup.py install for mozleak 21:48:19 INFO - Running setup.py install for mozlog 21:48:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:48:19 INFO - Running setup.py install for moznetwork 21:48:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:48:19 INFO - Running setup.py install for mozprocess 21:48:19 INFO - Running setup.py install for mozprofile 21:48:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:48:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:48:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:48:19 INFO - Running setup.py install for mozrunner 21:48:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:48:19 INFO - Running setup.py install for mozscreenshot 21:48:20 INFO - Running setup.py install for moztest 21:48:20 INFO - Running setup.py install for mozversion 21:48:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:48:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:48:20 INFO - Cleaning up... 21:48:20 INFO - Return code: 0 21:48:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:20 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 0x10e8c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd078dcef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, '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.b7BItHYavl/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.tQh5wupGZF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:48:20 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:48:20 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:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:20 INFO - 'HOME': '/Users/cltbld', 21:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:20 INFO - 'LOGNAME': 'cltbld', 21:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:20 INFO - 'MOZ_NO_REMOTE': '1', 21:48:20 INFO - 'NO_EM_RESTART': '1', 21:48:20 INFO - 'PAGER': '/bin/cat', 21:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:20 INFO - 'PWD': '/builds/slave/test', 21:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:20 INFO - 'SHELL': '/bin/bash', 21:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:48:20 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:20 INFO - 'USER': 'cltbld', 21:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:20 INFO - 'XPC_FLAGS': '0x0', 21:48:20 INFO - 'XPC_SERVICE_NAME': '0', 21:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-i9TvSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:20 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:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-uy_QhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:20 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:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Z52Zl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:20 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:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-kMzTKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-259mws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:21 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:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-qBOxrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:21 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:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ZJGcpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:21 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:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-NhMXoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:21 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:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-aEVMFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:21 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:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-37oGj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:21 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:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-0k9uvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:21 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:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-g43W2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:21 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:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-dz_703-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:21 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:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-taUbpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:22 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:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-JTYVpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:22 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:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-DuRnbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:22 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:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-o0VQri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:22 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:48:22 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:48:22 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:48:22 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:48:22 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:48:22 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:48:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:25 INFO - Downloading blessings-1.6.tar.gz 21:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:48:25 INFO - Installing collected packages: blessings 21:48:25 INFO - Running setup.py install for blessings 21:48:25 INFO - Successfully installed blessings 21:48:25 INFO - Cleaning up... 21:48:25 INFO - Return code: 0 21:48:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:25 INFO - Reading from file tmpfile_stdout 21:48:25 INFO - Current package versions: 21:48:25 INFO - blessings == 1.6 21:48:25 INFO - blobuploader == 1.2.4 21:48:25 INFO - docopt == 0.6.1 21:48:25 INFO - functools32 == 3.2.3-2 21:48:25 INFO - jsonschema == 2.5.1 21:48:25 INFO - manifestparser == 1.1 21:48:25 INFO - mozInstall == 1.12 21:48:25 INFO - mozcrash == 1.0 21:48:25 INFO - mozdebug == 0.1 21:48:25 INFO - mozdevice == 0.49 21:48:25 INFO - mozfile == 1.2 21:48:25 INFO - mozhttpd == 0.7 21:48:25 INFO - mozinfo == 0.9 21:48:25 INFO - mozleak == 0.1 21:48:25 INFO - mozlog == 3.4 21:48:25 INFO - moznetwork == 0.27 21:48:25 INFO - mozprocess == 0.23 21:48:25 INFO - mozprofile == 0.28 21:48:25 INFO - mozrunner == 6.13 21:48:25 INFO - mozscreenshot == 0.1 21:48:25 INFO - mozsystemmonitor == 0.3 21:48:25 INFO - moztest == 0.7 21:48:25 INFO - mozversion == 1.4 21:48:25 INFO - psutil == 3.1.1 21:48:25 INFO - requests == 1.2.3 21:48:25 INFO - wsgiref == 0.1.2 21:48:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:25 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 0x10e8c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd078dcef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, '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.b7BItHYavl/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.tQh5wupGZF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:48:25 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:48:25 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:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:25 INFO - 'HOME': '/Users/cltbld', 21:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:25 INFO - 'LOGNAME': 'cltbld', 21:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:25 INFO - 'MOZ_NO_REMOTE': '1', 21:48:25 INFO - 'NO_EM_RESTART': '1', 21:48:25 INFO - 'PAGER': '/bin/cat', 21:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:25 INFO - 'PWD': '/builds/slave/test', 21:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:25 INFO - 'SHELL': '/bin/bash', 21:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:48:25 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:25 INFO - 'USER': 'cltbld', 21:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:25 INFO - 'XPC_FLAGS': '0x0', 21:48:25 INFO - 'XPC_SERVICE_NAME': '0', 21:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:25 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:48:25 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:48:25 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:48:25 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:48:25 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:48:25 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:48:25 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:48:25 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:48:25 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:48:25 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:48:25 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:48:25 INFO - Cleaning up... 21:48:25 INFO - Return code: 0 21:48:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:25 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 0x10e8c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd078dcef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, '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.b7BItHYavl/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.tQh5wupGZF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:48:25 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:48:25 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:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:25 INFO - 'HOME': '/Users/cltbld', 21:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:25 INFO - 'LOGNAME': 'cltbld', 21:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:25 INFO - 'MOZ_NO_REMOTE': '1', 21:48:25 INFO - 'NO_EM_RESTART': '1', 21:48:25 INFO - 'PAGER': '/bin/cat', 21:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:25 INFO - 'PWD': '/builds/slave/test', 21:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:25 INFO - 'SHELL': '/bin/bash', 21:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:48:25 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:25 INFO - 'USER': 'cltbld', 21:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:25 INFO - 'XPC_FLAGS': '0x0', 21:48:25 INFO - 'XPC_SERVICE_NAME': '0', 21:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:48:26 INFO - Cleaning up... 21:48:26 INFO - Return code: 0 21:48:26 INFO - Running post-action listener: _resource_record_post_action 21:48:26 INFO - Running post-action listener: _start_resource_monitoring 21:48:26 INFO - Starting resource monitoring. 21:48:26 INFO - [mozharness: 2016-12-18 05:48:26.258613Z] Finished create-virtualenv step (success) 21:48:26 INFO - [mozharness: 2016-12-18 05:48:26.259626Z] Running install step. 21:48:26 INFO - Running pre-action listener: _resource_record_pre_action 21:48:26 INFO - Running main action method: install 21:48:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:26 INFO - Reading from file tmpfile_stdout 21:48:26 INFO - Detecting whether we're running mozinstall >=1.0... 21:48:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:48:26 INFO - Reading from file tmpfile_stdout 21:48:26 INFO - Output received: 21:48:26 INFO - Usage: mozinstall [options] installer 21:48:26 INFO - Options: 21:48:26 INFO - -h, --help show this help message and exit 21:48:26 INFO - -d DEST, --destination=DEST 21:48:26 INFO - Directory to install application into. [default: 21:48:26 INFO - "/builds/slave/test"] 21:48:26 INFO - --app=APP Application being installed. [default: firefox] 21:48:26 INFO - mkdir: /builds/slave/test/build/application 21:48:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:48:48 INFO - Reading from file tmpfile_stdout 21:48:48 INFO - Output received: 21:48:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:48:48 INFO - Running post-action listener: _resource_record_post_action 21:48:48 INFO - [mozharness: 2016-12-18 05:48:48.421414Z] Finished install step (success) 21:48:48 INFO - [mozharness: 2016-12-18 05:48:48.421552Z] Running run-tests step. 21:48:48 INFO - Running pre-action listener: _resource_record_pre_action 21:48:48 INFO - Running main action method: run_tests 21:48:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:48:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:48:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:48:48 INFO - 2016-12-17 21:48:48.436 screenresolution[1914:24310] starting screenresolution argv=screenresolution get 21:48:48 INFO - 2016-12-17 21:48:48.438 screenresolution[1914:24310] Display 0: 1600x1200x32@60 21:48:48 INFO - 2016-12-17 21:48:48.444 screenresolution[1915:24312] starting screenresolution argv=screenresolution list 21:48:48 INFO - Available Modes on Display 0 21:48:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:48:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:48:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:48:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:48:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:48:48 INFO - Intel Iris: 21:48:48 INFO - Chipset Model: Intel Iris 21:48:48 INFO - Type: GPU 21:48:48 INFO - Bus: Built-In 21:48:48 INFO - VRAM (Dynamic, Max): 1536 MB 21:48:48 INFO - Vendor: Intel (0x8086) 21:48:48 INFO - Device ID: 0x0a2e 21:48:48 INFO - Revision ID: 0x0009 21:48:48 INFO - Displays: 21:48:48 INFO - Display: 21:48:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:48:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:48:48 INFO - Display Serial Number: 1600x1200 60 21:48:48 INFO - Main Display: Yes 21:48:48 INFO - Mirror: Off 21:48:48 INFO - Online: Yes 21:48:48 INFO - Rotation: Supported 21:48:48 INFO - Return code: 0 21:48:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:48:48 INFO - Python 2.7.3 21:48:48 INFO - Return code: 0 21:48:48 INFO - grabbing minidump binary from tooltool 21:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:48 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 0x10e6a1d30>, '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 0x10e5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e696be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:48:48 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:48:48 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:48:48 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:48:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:48:48 INFO - Return code: 0 21:48:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:48:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:48:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:48:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:48:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:48:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0084 --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:48:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7BItHYavl/Render', 21:48:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:48 INFO - 'HOME': '/Users/cltbld', 21:48:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:48 INFO - 'LOGNAME': 'cltbld', 21:48:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:48:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:48:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:48 INFO - 'MOZ_NO_REMOTE': '1', 21:48:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:48:48 INFO - 'NO_EM_RESTART': '1', 21:48:48 INFO - 'PAGER': '/bin/cat', 21:48:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:48 INFO - 'PWD': '/builds/slave/test', 21:48:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:48:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:48 INFO - 'SHELL': '/bin/bash', 21:48:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQh5wupGZF/Listeners', 21:48:48 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 21:48:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:48 INFO - 'USER': 'cltbld', 21:48:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:48 INFO - 'XPC_FLAGS': '0x0', 21:48:48 INFO - 'XPC_SERVICE_NAME': '0', 21:48:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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:48:49 INFO - mozversion application_buildid: 20161217201235 21:48:49 INFO - mozversion application_changeset: 5baae61449c43851c961a83babe18adaa28e3c6b 21:48:49 INFO - mozversion application_display_name: Nightly 21:48:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:48:49 INFO - mozversion application_name: Firefox 21:48:49 INFO - mozversion application_remotingname: firefox 21:48:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:48:49 INFO - mozversion application_vendor: Mozilla 21:48:49 INFO - mozversion application_version: 53.0a1 21:48:49 INFO - mozversion platform_buildid: 20161217201235 21:48:49 INFO - mozversion platform_changeset: 5baae61449c43851c961a83babe18adaa28e3c6b 21:48:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:48:49 INFO - mozversion platform_version: 53.0a1 21:48:49 INFO - using testdate: 1482040129 21:48:49 INFO - actual date: 1482040129 21:48:49 INFO - starting webserver on 'localhost:56177' 21:48:49 INFO - SUITE-START | Running 2 tests 21:48:49 INFO - TEST-START | dromaeo_css 21:48:49 INFO - operating with platform_type : mac_ 21:48:49 INFO - Initialising browser for dromaeo_css test... 21:48:49 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmp3YSmfd/profile http://localhost:56177/getInfo.html) 21:48:51 INFO - PROCESS | 1925 | 2016-12-17 21:48:51.643 plugin-container[1927:24468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 21:48:51 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:48:51 INFO - PROCESS | 1925 | 2016-12-17 21:48:51.645 plugin-container[1927:24468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 21:48:51 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:48:51 INFO - PROCESS | 1925 | 2016-12-17 21:48:51.645 plugin-container[1927:24468] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:48:51 INFO - PROCESS | 1925 | __metrics Screen width/height:1600/1200 21:48:51 INFO - PROCESS | 1925 | colorDepth:24 21:48:51 INFO - PROCESS | 1925 | Browser inner width/height: 1024/689 21:48:51 INFO - PROCESS | 1925 | __metrics 21:48:51 ERROR - PROCESS | 1925 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 21:48:52 INFO - TEST-INFO | 1925: exit 0 21:48:52 INFO - Browser initialized. 21:48:52 INFO - Running cycle 1/1 for dromaeo_css test... 21:48:52 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmp3YSmfd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:48:54 INFO - PROCESS | 1928 | 2016-12-17 21:48:54.717 plugin-container[1929:24590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 21:48:54 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:48:54 INFO - PROCESS | 1928 | 2016-12-17 21:48:54.718 plugin-container[1929:24590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 21:48:54 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:48:54 INFO - PROCESS | 1928 | 2016-12-17 21:48:54.718 plugin-container[1929:24590] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:49:04 INFO - PROCESS | 1928 | RSS: Main: 237076480 21:49:04 INFO - PROCESS | 1928 | 21:49:54 INFO - PROCESS | 1928 | 1482040194074 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:52:08 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56177/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56177/tests/dromaeo/cssquery-ext.html) 21:52:10 INFO - PROCESS | 1928 | RSS: Main: 251707392 21:52:10 INFO - PROCESS | 1928 | 21:55:12 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56177/tests/dromaeo/cssquery-ext.html (next: http://localhost:56177/tests/dromaeo/cssquery-jquery.html) 21:55:13 INFO - PROCESS | 1928 | RSS: Main: 262639616 21:55:13 INFO - PROCESS | 1928 | 21:58:15 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56177/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56177/tests/dromaeo/cssquery-mootools.html) 21:58:16 INFO - PROCESS | 1928 | RSS: Main: 269934592 21:58:16 INFO - PROCESS | 1928 | 22:01:17 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56177/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56177/tests/dromaeo/cssquery-prototype.html) 22:01:18 INFO - PROCESS | 1928 | RSS: Main: 269037568 22:01:18 INFO - PROCESS | 1928 | 22:04:21 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56177/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56177/tests/dromaeo/cssquery-yui.html) 22:04:22 INFO - PROCESS | 1928 | RSS: Main: 273080320 22:04:22 INFO - PROCESS | 1928 | 22:07:25 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56177/tests/dromaeo/cssquery-yui.html (next: http://localhost:56177/tests/dromaeo/cssquery-dojo.html) 22:07:25 INFO - PROCESS | 1928 | RSS: Main: 275374080 22:07:25 INFO - PROCESS | 1928 | 22:07:25 INFO - PROCESS | 1928 | __start_tp_report 22:07:25 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 22:07:25 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 22:07:25 INFO - PROCESS | 1928 | |i|pagename|runs| 22:07:25 INFO - PROCESS | 1928 | |0;dojo.html;2852.871620777065;3359.1266270769606;3369.4271973764457;3399.881004164853;3409.4544872820347;4486.551344865512;4532.932006019908;5590.16147577863;5638.097904335307;5669.291338582677;5558.416366281543;5617.9157312640245;5678.148277758328;5695.117256825189;5719.685417302057;4462.709923854939;4937.654364194508;5148.330717006784;5264.341957255343;5285.947140528603;5082.567981721558;5190.532852043315;5213.322268105141;5268.025415298182;5279.076161671711;5115.693058416469;5150.356205474316;5246.711430871301;5257.789688412459;5266.210068489719;5127.717975511346;5149.253358263069;5162.97418512905;5181.5077567631015;5238;5111.718855462948;5169.689818610857;5178.637495375326;5181.844544663666;5185.611079169077;5500.42245564218;5672.40439753824;5672.4894759471845;5681.517071048966;5712.285964254468;5943.821685349403;6015.699215039231;6030.155778191057;6127.234095738033;6198.690065496708;4466.776661166961;5715.542756579505;6117.553376797881;6173;6237.251529816405;5751.194832723422;5753;5755.510781583613;5769.365369809359;5771.624844385144;6218.782342618078;6330.715117819709;6348.079528468346;6355.650439225887;6363.268224154189;5888.587798854039;5960.135780311831;5965.761369545169;6008.09878518217;6048.939510604838;6062.878742425215;6122.977604208209;6144.832481828527;6145.955187618116;6147.770445910835;6052.515798736091;6091.451769340781;6117.614269788186;6122.032243765697;6127.938720612826;4683.718976861399;6038.762053778964;6177.083021988384;6233.657148856766;6240.438360547572;4751.00228951924;4823.541763532458;4864.635152363516;4871.293662419001;4924.4583095859425;4227.00665343644;4233.724807887477;4319.97840010798;4350.521442641307;4358.455193100863;5692.57305702074;5694.4590263924865;5700.743466544015;5704.286964129484;5763.221965034667;6070.2412198475195;6073.848153796191;6078.270607527036;6085.604435711664;6094.8171554853425;5671.319441667027;5696.1455781633085;5732.828015159552;5751.194832723422;5775.566832487496;5292.788288468495;5303.7878484860175;5309.973450132725;5332.200169974535;5333.333333333333;6392.8400191785495;6416.422521973045;6418.26189988148;6421.775237866652;6431.6462594556815;5190;5377.193420986883;6071.72493776312;6208;6285.277193122666;6192.318844927145;6251.218597675291;6279.748810047547;6319.58922670025;6325.335839736761;6135.998322733915;6141.946315431794;6143.784967526115;6157.476614487819;6170.876582468236;6170.876582468236;6178.721957511832;6227.96886015567;6235.96882015587;6280.152179455715;5702.6863522506665;5865.4134586541;5866.325372582208;5933.347331793586;6059.939400605937;5919.200907877382;5931.9406805931385;5939.07944268639;5958.553108516965;5967.134765459071;4682.274247491644;5770.5095066919785;5874.324452687824;5885.882282354414;5900.675462849585;6076.787312444043;6089.817305480843;6090.086487026982;6098.786542470993;6108.797423689751;5826.970865145647;5847.590668653153;5855.824325270249;5860.794872179454;5877.85381850535 22:07:25 INFO - PROCESS | 1928 | |1;ext.html;395.93269144245477;409.20205599081777;451.52138732943075;461.59149152999595;480.13815201712924;36858.44712329314;39084.63203787865;39207.21585568287;41325.380119298236;41409.792951035204;40689.389659155066;41293.79353103238;41585;41609.75170744883;41727.16545669093;8313.043999939993;9258.58336374863;9516.762080947963;9529.761755956088;9555.856662150074;8361.83276334473;9054.909450905505;11329.660110196708;11345.149113816455;11471.59849405271;11346.20576559641;11386.259893106962;11388.886111138907;11395.487203075882;11413;11307.434628268553;11372.431378431045;11380.430978451044;11396.145289103268;11412.31526108436;11199.66401007971;11246.775064498746;11268.887311126866;11269.60556380531;11276.661700149009;38877.41683874744;39156.80421597902;39182.60817391818;39319;39361.40957885634;3503.404421248368;3534.4344904815107;3627.873024444158;3721.237146385012;4041.090754580225;29260.8536957316;29302.99851342144;29570;30244.69755302441;30676.386472270653;3157.763167762427;3164.7309978651842;3165.3036332006923;3171.3181665941934;3274.5415641810164;473769;475120;478980;479281.60359197983;480479.59760201327;57851.710741446026;58271;58502;58616.706916466;58662.12006819901;62225.68887155535;62311.688441558406;62395;62552;62610.68694656497;14714.264286785618;15055.096694198382;15095.622609434633;15132.546023619308;15143.621409464631;10382.480875956171;10531.368117912949;11264.267822591506;11624.186306958432;11624.593139240254;13277.93361033208;13282.933585332012;13958.930205348908;16076.75884861728;16560.66878662444;5803.390643982406;5889.204957330791;6202.627842329474;6230.937690623035;6689;746.4476287547176;856.6402111113347;986.2849434160177;992.4839083676447;1040.9895901041043;25117.87441062783;27786.722132778817;28996;29602.851985739933;29742;1053.1732589916878;1065.8454524093963;1081.599808071003;1107.4186052322434;1127.9436028198722;14589.927050364893;15038.774418383733;15272.770908436383;15584.688306233811;15603.921980390025;569837;722815;731819;735418;735986;48900.5109948896;49457.010859782604;49609;49702.005959881324;49766.751166243936;1329.0829327763813;1350.8581598932167;1359.9116057456233;1364.34511434512;1376.3049659921876;27266.454670906867;27343.72656273412;27730.029448969188;27889.157708327813;27908.720912790614;42114.57885421107;42168.78915605525;42552;42572.7228183155;42580.36129457998;438650;441992;442563;442843;444112;22105.77894221037;22702;23379.88310058439;23432.88283558571;23706.762932370457;40137.996550085314;40864.18271634491;41088.58911410967;42060.57939420567;43056.56943430525;27241.45517089687;31888.521672174473;33699.66300336965;34753.47869782006;35273;33413.832930836164;33731.15672108217;33783.662163379035;34534;34541.82729086439;32044.51933220957;32162.678373215967;32829.17927051843;32885.50671739878;33169.83415082909;27548;30185.849070754506;30419.391612168074;30597.694023059485;30622.693773061983;19144.425667230007;22914.198003069814;23462.061517539107;23469.178578749663;23539.529209415374 22:07:25 INFO - PROCESS | 1928 | |2;jquery.html;371.4113130687858;375.4968342421155;380.78675941472767;387.9786611736357;405.0784465341348;17844.73232901506;19041.619167616675;19563.804361956376;19773.110210040548;19884.60230795383;15699.843001569981;18627.534311642215;19342.03289835506;19775.703364449546;19861.40415787525;4493.9775301123445;5117.104506711329;5298.682079075248;5306.946930530684;5314.627976041678;5586.972065139689;5654.830355089333;5733.627314224583;5733.913991290134;5739.512141467981;5515.448455154493;5681.375048744635;5717;5720.370759216483;5742.6841523716175;5628.774849006035;5685.061964775807;5685.744141513621;5737.254156959611;5749.913751293713;5332;5504.889902201933;5531.612787104904;5565.220869078333;5567.2762540869835;10986.450677466095;12535.122541422104;12614.558490452882;12646.177998430072;20838.27066052696;2091.362134548962;2641.7583735644216;2657.2161212442375;2922.1379692990618;2930.692277310873;9757.609695612167;10019.947905469966;10264.74338141545;11902.702432439171;15093.773593396108;1577.250805867204;1662.742274947373;1671.2980548169799;1800.5842955661042;1896.8577356698304;86025;95291.66479173265;96692;98514.50742746312;102468;8462.788430289293;11309.773804523864;11457.197996140358;11650.708732281591;11653.53385864556;11124.61063862761;14033.929830350782;14248.287585620677;14251.857481425053;14276.214808185441;6402.135711678957;7934.127246002935;7984.441089123822;8047.436679432501;8138.552379619059;7581.886271705929;7641.541507509567;7741;7864.134945156028;7883.842323153504;3827.7559793067367;4286.5284818669925;4459.888502787391;4478.641708663299;4555.316702494586;1761.9559511012328;1967.7933816949014;1988.5625162464235;1997.7402937618017;1998.4704053425853;805.399977017126;872.581161042703;936.0405584276035;951.6288647427509;996.4768496539375;16496.17519124039;18476.465215633583;19169.32907348236;20454.795452045288;20560.485987850425;799.9120096789349;810.2302812328194;924.0851556958563;929.256594724218;957.7318350861769;10325.173986081245;10665.84001239982;10705.732356690989;10715.571377144983;10717.678469645924;531842.4302420408;533415;537685;540996;542613;116101;119986;123310;127033.02636709955;127337;975.7463059604554;993.9552720127467;1035.7980193862275;1037.8806437259661;1039.142707266508;18291.63416731631;18341.266349345875;18650.34723784661;18733.250669973593;18851;28891.85554072216;43977.78011110052;49640.021598704196;50935.49064509456;50960.74519627378;69515.95726064012;70869.64565177141;71016.64491677508;71630.28369716444;72192.63903680448;7928.365730741642;7981.92018079835;8074.475159114639;8087.3125784308995;8138.9186108138165;8464;11012.889871101186;12130.150889437655;12188.268703877795;12410.131290809557;10634.521446534769;10683.03852653265;10784.245102842726;10948.835767463335;11031;10161.593536258466;10503.527341269508;10625.681229562817;10648.680539583826;10690.075786358218;9142;10345.068943794793;10889.618863340062;11379.829302560634;11460;7981.321587665114;7991.2807847294025;8167.224113709188;8230.506169629842;8240.093589705008;10799.514021868878;10828.133749300194;11013.559457621606;11161.386123763272;11188.216824822184 22:07:25 INFO - PROCESS | 1928 | |3;mootools.html;1522.5660686704289;1524.2074121456842;1750.903700296484;1828.1133650179652;1858.962820743586;7560.735374261902;7822.491538050022;7832.334251588614;8211.958940205299;8230.547319897403;7956.323712484431;8280.296174825133;8304.626291816867;8314.085450600429;8330.875036874451;1978.3570339639602;2291.278247352079;2318.678353338589;2351.988240058797;2355.9293221203393;1910.4650697804636;1920.2990908318502;2085.384811480617;2277.646964720471;2385.940351491218;1930.671785796418;2269.7503274639776;2322.6051571232847;2351.6354964980455;2359.8112151027963;2270.4323919020244;2288.2677543186187;2300.3903965449167;2310.8151347892126;2320.1995311617466;2306.342692332677;2324.32594547581;2329.3827135809024;2331.1724337859987;2342.5900467418305;7786.844263114706;7838;8173.8365232695605;8312.625931833081;8332.375071869634;5074.873128171788;5251.86870328241;5252.474752524717;5261.736913154327;5280.524752772269;3279.39331223722;3405.6083550391772;3491.3540994915766;3510.6840384365273;3518.7009104225917;1043.4730461117163;1062.606835470873;1083.3175099687146;1086.3699054548422;1096.961606343774;10548.630797922113;10601.310914790514;10693.091087257593;10749.032587067202;10784.4607769612;4485.618722408566;4643.976780116078;4721.716696998191;4739.905201895943;4743.6916600421555;9435.150836424618;9472.289578281656;9477.95742468328;9479.90520094804;9484.19384352324;5240.554552862973;5246.396664383568;5249.475052494797;5253.50091741284;5255.316808814906;3725.8882233533036;4739.957209413923;4743.003969166513;4767.4517430495935;4830.275458681226;3831.9042023949623;3851;3900.414937759359;3940.763554186758;3949.5260568731937;1277.6486466221677;1296.9935150324188;1300.5058077930325;1305.5039085147773;1312.7571399291162;793.5238856686058;808.8301456694043;831.4285999461024;896.7400801873379;932.9953350233341;5402.35171779389;5510.476504732044;5701.372848986608;5726;5806.129080637938;878.0911756332166;890.4479222881855;908.9181973622406;966.8308046091905;988.5172241637543;3678.8528458861347;3799.0502374406396;3846.576876543578;3847.115163512408;3850.9229815404096;9713.660021899199;9819.16537094355;9925.30522863407;10036.24728145392;10069.144122749649;9728.659496917573;9983.351082179635;10012.849807252896;10019.549120289605;10041.849372259421;6936.132983377078;7590.506617069983;7691.769246922601;7953.204679532;7961.402894782915;5446;5504.22940788282;5529.751161197836;5541.861453463534;5574;9017.684381046633;9280.443173409618;9302.627894884128;9305.929818071008;9331;9071.369302902085;9337.253019758267;9512.667056652985;9629.422234665943;9630.711078667651;6586.769463068769;6813.386795188457;6959.269276726073;7059.082319298458;7236.638168091469;6569.9343006569325;7915.366770658218;7937.523748575104;7960.601969901598;7971.202879712215;7013.579185248901;7130.180029296697;7199.280071992759;7211.711531538889;7268.345848873627;6956.2000369958105;6971.195579386975;6998.16022077354;7110.075690160246;7126.28737126304;6623.768168114093;6653.933460665525;6719.630420326929;6720.092787473628;6727.688100820293;4089.550149483614;4813.302071199727;4820.204666230173;4888.780004899717;4908.509149085063;4750.643701722453;4780.1873681473535;4854.660173787801;4941.085899108676;5044.39467263916 22:07:25 INFO - PROCESS | 1928 | |4;prototype.html;318.9362127574485;338.237274944999;351.66943073510896;356.4973387523592;362.82806534892296;4982.900341993162;5077.136886729256;5081.865616441094;5084.923726144101;5250.737463126848;4486.8653940381755;5220.556252718514;5221.660592061523;5243.973780131104;5322.893542129164;3932.744371615836;4177.227212965611;4179;4179.498460184782;4194.53860075393;3558.395072837624;3578.4274516077435;4181.205570941526;4202.768847713374;4213.43118678979;3118.469860123773;3797.6012518685557;3969.880903572883;3982.574985512457;4026.1947610477873;3121.82829944353;3439.7592168548213;3658.396364599838;3658.5426821647293;4030.9116538534727;3474.498361179946;3483.756137070406;3501.9824900875333;3513.9809455257946;3531.205478767257;4477.954990606387;5089.898202035975;5127.897621938748;5146.073706732786;5214.739263036833;4360.149770794696;4533.753217865081;4537.863864084083;4563.110193512233;4593.494715581283;4657.510961349078;4752.979983506995;5245.213218017289;5528.087865502187;5550.833474995757;3567.571891373026;3777.338965680995;3778.659920607131;3794.335991201556;3799.3731034379293;5201.491567445436;5275.57795376369;5708.514776244025;5821.970890145564;5841.108651217632;3839.827207775657;3857.5949525300002;3863.9034024149623;3871.864484743077;3890.9299942515468;3570.803605801706;3597.6582224689137;3915.432262322004;3945.743526670757;3954.3079961007284;4289.828406863753;4484.529124441952;4536.818527258873;4542.772861356919;4548.818047278071;4776.737279449663;5505.009098362253;5596.110703061415;5629.802956896489;5642.435756424325;1154.6132045764764;1161.73280145567;1174.4890972427022;1192.9821052684217;1198.898093662031;823.6211342782387;829.672279449614;852.884860543831;855.4952577978897;872.358816270041;4406.647468202536;4423.955760442419;4590.816367345269;5032.748362581856;5152.819651312261;5286.198314893113;5412.5940554458575;5431.972840135774;5450.809221677222;5471.480209380103;4623.722576645412;4709.576138147583;4818.325434439182;4885.4366602687505;5051.015052064812;2685.9328516787236;2772.819766715157;2856.300206449433;2872.224499385154;2879.8704058317426;5175.964807038607;5290.761915713802;5419.512243898068;5755.920582488348;5768.26951914424;5267.288915996368;5570;5584.972075139598;5606.831795046155;5625.662460252398;5038.513638476658;5580.581456390706;5590.608657393944;5762.88474230513;5766.567507436959;4629.375034370317;4904.877378065434;4986.825461108844;5350.5184533392185;5473.644213126134;4738.076075165448;5053.797848086036;5071.066851783496;5326.97336513315;5421.674699518042;4960.404751429852;5161.612879034163;5334.54610879;5611.326640802967;5920.200772895604;3087.5368694695976;3093.3967876263914;3164.240582260287;3538.699845100602;3668.761530500509;4605.953940460552;4869.342638743724;4943.292530602049;5335.946640533545;5415.296011518478;4898.804047838046;5401.432849550741;5470;5518.944810551843;5550.916736249041;5098.974505127599;5206.7350702444555;5560.388357280777;5586.636868603528;5593.168679454424;5019.7239151847;5197.0905091609575;5432.619716619799;5441.29263195782;5447.564194864322;5480.808171713971;5500.074973759312;5536.944630553643;5576.721163941867;5577;4591.747453890068;4794.328793968777;5437.972810135924;5471.72641367938;5474.3704473986 22:07:25 INFO - PROCESS | 1928 | |5;yui.html;671.278375746073;687.9105716256886;742.8551432470667;780.426386605845;816.9101398846128;14333.569992900204;15351.692966140698;15392.692146157071;15443;15482.845171548295;13874.167549947011;14015.649608759786;14021.228832414214;14067.507637232673;14088.78866816996;679.9864002719955;702.0417130616697;846.9026062002856;874.6851133591902;881.3345923827534;867.7266661001764;880.1638443478689;881.7002219245455;881.7310720230328;882.7969566999562;916.5188276155005;922.3912217936163;925.1765928323797;925.7361651929201;930.1117432851644;910.0853642089724;913.1827014821697;922.5617831530003;923.1138107416888;925.8611208318806;672.6132473827569;682.8975653651943;684.090160087088;688.4870771275382;691.1878542712293;13613.795793063113;13839.377228024763;14059.297035148202;14399;14460;12077.516899324017;13714.862851371458;14069.2965351732;14165.645858853508;14170.433182672681;10716.196285278558;11206.607768728056;11406.315621062762;11473.368964706939;11533.826992595117;4368.257132178896;4431.091626216617;4540.591346778772;4562.5665561771575;4632.444106707183;97805;99525.5023724884;101378.99999999926;103021.96978030198;103921;10320.277580569287;10361.585536578603;10486.213533984828;10584.629537966135;10597.523111460003;19577.314793982143;19675.803241967682;19688.70466942997;19721.605567888848;20023.195592837314;7613;7643.808904777425;7649.273319034683;7829.765107046797;7971.880421793678;3411.573553305837;4290.334998075304;4329.610335069859;4390.517043125254;4424.823007079681;3548.591911930161;3612.3678356287546;3643.8178091095338;3649.61679023704;3656.5794933582447;645.1709553224179;651.3844417025865;655.3741177175785;655.4003087175181;664.9567778094505;570.7146426786607;641.7721708793405;663.7554585152839;664.2726214794792;687.924328323884;7607.744722120899;8256.545889975989;9078;9248.398854074598;9463.242940564738;600.5315853634172;641.9329758178658;677.109600874848;698.5319835710086;708.1643660480682;3750.9062273443383;4288;4557.772111394417;4605.447346318396;4676.462206846256;99352;99733;102701.48649256706;105903.4704826471;105944.47027764811;73706.26293736995;88125;89644;89679;90035;76449.61775191089;77763.61118194372;78048.60975695199;78527;81498;8533.658653653783;8647.048824629412;8793.384463087523;8806.559672016501;8923.286137109146;45371.54628453673;50312.49687503078;50358.24462633137;52919.47080529299;52999.470005300995;61550.69224653848;64727.67636161789;66177.00734489082;66629;71017.6449117751;10216;10808.675739727822;12063.879361206627;13050.608481745563;13226;14085.295455340603;14442.422303107758;14547.563573092824;14814.703705926036;15189.924050379677;13118.147320424141;13903.443862245396;13926.955478339367;14057;14394.640133996734;12050.457729402022;12870.678233043875;13684;13895.643439301106;14267.072640278764;12937.741245175232;13365.866341336463;13374;13445.865541344461;13550.322483875963;11064.114870810155;11177.329360238411;11584.710382240512;11739.706507337385;11772.234804737665;10378.481075946022;11274.605388811353;11468.942655287005;11559.942200288944;11599.536018559163 22:07:25 INFO - PROCESS | 1928 | __end_tp_report 22:07:25 INFO - PROCESS | 1928 | __start_cc_report 22:07:25 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,378 22:07:25 INFO - PROCESS | 1928 | __end_cc_report 22:07:25 INFO - PROCESS | 1928 | __startTimestamp1482041245515__endTimestamp 22:07:25 INFO - PROCESS | 1928 | 22:07:25 INFO - PROCESS | 1928 | ------- Summary: start ------- 22:07:25 INFO - PROCESS | 1928 | Number of tests: 6 22:07:25 INFO - PROCESS | 1928 | 22:07:25 INFO - PROCESS | 1928 | [#0] dojo.html Cycles:165 Average:5629.05 Median:5775.57 stddev:671.39 (11.6%) stddev-sans-first:637.15 22:07:25 INFO - PROCESS | 1928 | Values: 2852.9 3359.1 3369.4 3399.9 3409.5 4486.6 4532.9 5590.2 5638.1 5669.3 5558.4 5617.9 5678.1 5695.1 5719.7 4462.7 4937.7 5148.3 5264.3 5285.9 5082.6 5190.5 5213.3 5268.0 5279.1 5115.7 5150.4 5246.7 5257.8 5266.2 5127.7 5149.3 5163.0 5181.5 5238.0 5111.7 5169.7 5178.6 5181.8 5185.6 5500.4 5672.4 5672.5 5681.5 5712.3 5943.8 6015.7 6030.2 6127.2 6198.7 4466.8 5715.5 6117.6 6173.0 6237.3 5751.2 5753.0 5755.5 5769.4 5771.6 6218.8 6330.7 6348.1 6355.7 6363.3 5888.6 5960.1 5965.8 6008.1 6048.9 6062.9 6123.0 6144.8 6146.0 6147.8 6052.5 6091.5 6117.6 6122.0 6127.9 4683.7 6038.8 6177.1 6233.7 6240.4 4751.0 4823.5 4864.6 4871.3 4924.5 4227.0 4233.7 4320.0 4350.5 4358.5 5692.6 5694.5 5700.7 5704.3 5763.2 6070.2 6073.8 6078.3 6085.6 6094.8 5671.3 5696.1 5732.8 5751.2 5775.6 5292.8 5303.8 5310.0 5332.2 5333.3 6392.8 6416.4 6418.3 6421.8 6431.6 5190.0 5377.2 6071.7 6208.0 6285.3 6192.3 6251.2 6279.7 6319.6 6325.3 6136.0 6141.9 6143.8 6157.5 6170.9 6170.9 6178.7 6228.0 6236.0 6280.2 5702.7 5865.4 5866.3 5933.3 6059.9 5919.2 5931.9 5939.1 5958.6 5967.1 4682.3 5770.5 5874.3 5885.9 5900.7 6076.8 6089.8 6090.1 6098.8 6108.8 5827.0 5847.6 5855.8 5860.8 5877.9 22:07:25 INFO - PROCESS | 1928 | 22:07:25 INFO - PROCESS | 1928 | [#1] ext.html Cycles:180 Average:66102.40 Median:33291.83 stddev:148209.77 (445.2%) stddev-sans-first:148543.43 22:07:25 INFO - PROCESS | 1928 | Values: 395.9 409.2 451.5 461.6 480.1 36858.4 39084.6 39207.2 41325.4 41409.8 40689.4 41293.8 41585.0 41609.8 41727.2 8313.0 9258.6 9516.8 9529.8 9555.9 8361.8 9054.9 11329.7 11345.1 11471.6 11346.2 11386.3 11388.9 11395.5 11413.0 11307.4 11372.4 11380.4 11396.1 11412.3 11199.7 11246.8 11268.9 11269.6 11276.7 38877.4 39156.8 39182.6 39319.0 39361.4 3503.4 3534.4 3627.9 3721.2 4041.1 29260.9 29303.0 29570.0 30244.7 30676.4 3157.8 3164.7 3165.3 3171.3 3274.5 473769.0 475120.0 478980.0 479281.6 480479.6 57851.7 58271.0 58502.0 58616.7 58662.1 62225.7 62311.7 62395.0 62552.0 62610.7 14714.3 15055.1 15095.6 15132.5 15143.6 10382.5 10531.4 11264.3 11624.2 11624.6 13277.9 13282.9 13958.9 16076.8 16560.7 5803.4 5889.2 6202.6 6230.9 6689.0 746.4 856.6 986.3 992.5 1041.0 25117.9 27786.7 28996.0 29602.9 29742.0 1053.2 1065.8 1081.6 1107.4 1127.9 14589.9 15038.8 15272.8 15584.7 15603.9 569837.0 722815.0 731819.0 735418.0 735986.0 48900.5 49457.0 49609.0 49702.0 49766.8 1329.1 1350.9 1359.9 1364.3 1376.3 27266.5 27343.7 27730.0 27889.2 27908.7 42114.6 42168.8 42552.0 42572.7 42580.4 438650.0 441992.0 442563.0 442843.0 444112.0 22105.8 22702.0 23379.9 23432.9 23706.8 40138.0 40864.2 41088.6 42060.6 43056.6 27241.5 31888.5 33699.7 34753.5 35273.0 33413.8 33731.2 33783.7 34534.0 34541.8 32044.5 32162.7 32829.2 32885.5 33169.8 27548.0 30185.8 30419.4 30597.7 30622.7 19144.4 22914.2 23462.1 23469.2 23539.5 22:07:25 INFO - PROCESS | 1928 | 22:07:25 INFO - PROCESS | 1928 | [#2] jquery.html Cycles:180 Average:31529.49 Median:1651.05 stddev:89626.65 (5428.5%) stddev-sans-first:89847.55 22:07:25 INFO - PROCESS | 1928 | Values: 371.4 375.5 380.8 388.0 405.1 17844.7 19041.6 19563.8 19773.1 19884.6 15699.8 18627.5 19342.0 19775.7 19861.4 4494.0 5117.1 5298.7 5306.9 5314.6 5587.0 5654.8 5733.6 5733.9 5739.5 5515.4 5681.4 5717.0 5720.4 5742.7 5628.8 5685.1 5685.7 5737.3 5749.9 5332.0 5504.9 5531.6 5565.2 5567.3 10986.5 12535.1 12614.6 12646.2 20838.3 2091.4 2641.8 2657.2 2922.1 2930.7 9757.6 10019.9 10264.7 11902.7 15093.8 1577.3 1662.7 1671.3 1800.6 1896.9 86025.0 95291.7 96692.0 98514.5 102468.0 8462.8 11309.8 11457.2 11650.7 11653.5 11124.6 14033.9 14248.3 14251.9 14276.2 6402.1 7934.1 7984.4 8047.4 8138.6 7581.9 7641.5 7741.0 7864.1 7883.8 3827.8 4286.5 4459.9 4478.6 4555.3 1762.0 1967.8 1988.6 1997.7 1998.5 805.4 872.6 936.0 951.6 996.5 16496.2 18476.5 19169.3 20454.8 20560.5 799.9 810.2 924.1 929.3 957.7 10325.2 10665.8 10705.7 10715.6 10717.7 531842.4 533415.0 537685.0 540996.0 542613.0 116101.0 119986.0 123310.0 127033.0 127337.0 975.7 994.0 1035.8 1037.9 1039.1 18291.6 18341.3 18650.3 18733.3 18851.0 28891.9 43977.8 49640.0 50935.5 50960.7 69516.0 70869.6 71016.6 71630.3 72192.6 7928.4 7981.9 8074.5 8087.3 8138.9 8464.0 11012.9 12130.2 12188.3 12410.1 10634.5 10683.0 10784.2 10948.8 11031.0 10161.6 10503.5 10625.7 10648.7 10690.1 9142.0 10345.1 10889.6 11379.8 11460.0 7981.3 7991.3 8167.2 8230.5 8240.1 10799.5 10828.1 11013.6 11161.4 11188.2 22:07:25 INFO - PROCESS | 1928 | 22:07:25 INFO - PROCESS | 1928 | [#3] mootools.html Cycles:180 Average:5409.34 Median:5252.99 stddev:2941.50 (56.0%) stddev-sans-first:2935.25 22:07:25 INFO - PROCESS | 1928 | Values: 1522.6 1524.2 1750.9 1828.1 1859.0 7560.7 7822.5 7832.3 8212.0 8230.5 7956.3 8280.3 8304.6 8314.1 8330.9 1978.4 2291.3 2318.7 2352.0 2355.9 1910.5 1920.3 2085.4 2277.6 2385.9 1930.7 2269.8 2322.6 2351.6 2359.8 2270.4 2288.3 2300.4 2310.8 2320.2 2306.3 2324.3 2329.4 2331.2 2342.6 7786.8 7838.0 8173.8 8312.6 8332.4 5074.9 5251.9 5252.5 5261.7 5280.5 3279.4 3405.6 3491.4 3510.7 3518.7 1043.5 1062.6 1083.3 1086.4 1097.0 10548.6 10601.3 10693.1 10749.0 10784.5 4485.6 4644.0 4721.7 4739.9 4743.7 9435.2 9472.3 9478.0 9479.9 9484.2 5240.6 5246.4 5249.5 5253.5 5255.3 3725.9 4740.0 4743.0 4767.5 4830.3 3831.9 3851.0 3900.4 3940.8 3949.5 1277.6 1297.0 1300.5 1305.5 1312.8 793.5 808.8 831.4 896.7 933.0 5402.4 5510.5 5701.4 5726.0 5806.1 878.1 890.4 908.9 966.8 988.5 3678.9 3799.1 3846.6 3847.1 3850.9 9713.7 9819.2 9925.3 10036.2 10069.1 9728.7 9983.4 10012.8 10019.5 10041.8 6936.1 7590.5 7691.8 7953.2 7961.4 5446.0 5504.2 5529.8 5541.9 5574.0 9017.7 9280.4 9302.6 9305.9 9331.0 9071.4 9337.3 9512.7 9629.4 9630.7 6586.8 6813.4 6959.3 7059.1 7236.6 6569.9 7915.4 7937.5 7960.6 7971.2 7013.6 7130.2 7199.3 7211.7 7268.3 6956.2 6971.2 6998.2 7110.1 7126.3 6623.8 6653.9 6719.6 6720.1 6727.7 4089.6 4813.3 4820.2 4888.8 4908.5 4750.6 4780.2 4854.7 4941.1 5044.4 22:07:25 INFO - PROCESS | 1928 | 22:07:25 INFO - PROCESS | 1928 | [#4] prototype.html Cycles:180 Average:4391.58 Median:4951.85 stddev:1343.70 (27.1%) stddev-sans-first:1312.24 22:07:25 INFO - PROCESS | 1928 | Values: 318.9 338.2 351.7 356.5 362.8 4982.9 5077.1 5081.9 5084.9 5250.7 4486.9 5220.6 5221.7 5244.0 5322.9 3932.7 4177.2 4179.0 4179.5 4194.5 3558.4 3578.4 4181.2 4202.8 4213.4 3118.5 3797.6 3969.9 3982.6 4026.2 3121.8 3439.8 3658.4 3658.5 4030.9 3474.5 3483.8 3502.0 3514.0 3531.2 4478.0 5089.9 5127.9 5146.1 5214.7 4360.1 4533.8 4537.9 4563.1 4593.5 4657.5 4753.0 5245.2 5528.1 5550.8 3567.6 3777.3 3778.7 3794.3 3799.4 5201.5 5275.6 5708.5 5822.0 5841.1 3839.8 3857.6 3863.9 3871.9 3890.9 3570.8 3597.7 3915.4 3945.7 3954.3 4289.8 4484.5 4536.8 4542.8 4548.8 4776.7 5505.0 5596.1 5629.8 5642.4 1154.6 1161.7 1174.5 1193.0 1198.9 823.6 829.7 852.9 855.5 872.4 4406.6 4424.0 4590.8 5032.7 5152.8 5286.2 5412.6 5432.0 5450.8 5471.5 4623.7 4709.6 4818.3 4885.4 5051.0 2685.9 2772.8 2856.3 2872.2 2879.9 5176.0 5290.8 5419.5 5755.9 5768.3 5267.3 5570.0 5585.0 5606.8 5625.7 5038.5 5580.6 5590.6 5762.9 5766.6 4629.4 4904.9 4986.8 5350.5 5473.6 4738.1 5053.8 5071.1 5327.0 5421.7 4960.4 5161.6 5334.5 5611.3 5920.2 3087.5 3093.4 3164.2 3538.7 3668.8 4606.0 4869.3 4943.3 5335.9 5415.3 4898.8 5401.4 5470.0 5518.9 5550.9 5099.0 5206.7 5560.4 5586.6 5593.2 5019.7 5197.1 5432.6 5441.3 5447.6 5480.8 5500.1 5536.9 5576.7 5577.0 4591.7 4794.3 5438.0 5471.7 5474.4 22:07:25 INFO - PROCESS | 1928 | 22:07:25 INFO - PROCESS | 1928 | [#5] yui.html Cycles:180 Average:20054.02 Median:24956.07 stddev:28830.77 (115.5%) stddev-sans-first:28874.91 22:07:25 INFO - PROCESS | 1928 | Values: 671.3 687.9 742.9 780.4 816.9 14333.6 15351.7 15392.7 15443.0 15482.8 13874.2 14015.6 14021.2 14067.5 14088.8 680.0 702.0 846.9 874.7 881.3 867.7 880.2 881.7 881.7 882.8 916.5 922.4 925.2 925.7 930.1 910.1 913.2 922.6 923.1 925.9 672.6 682.9 684.1 688.5 691.2 13613.8 13839.4 14059.3 14399.0 14460.0 12077.5 13714.9 14069.3 14165.6 14170.4 10716.2 11206.6 11406.3 11473.4 11533.8 4368.3 4431.1 4540.6 4562.6 4632.4 97805.0 99525.5 101379.0 103022.0 103921.0 10320.3 10361.6 10486.2 10584.6 10597.5 19577.3 19675.8 19688.7 19721.6 20023.2 7613.0 7643.8 7649.3 7829.8 7971.9 3411.6 4290.3 4329.6 4390.5 4424.8 3548.6 3612.4 3643.8 3649.6 3656.6 645.2 651.4 655.4 655.4 665.0 570.7 641.8 663.8 664.3 687.9 7607.7 8256.5 9078.0 9248.4 9463.2 600.5 641.9 677.1 698.5 708.2 3750.9 4288.0 4557.8 4605.4 4676.5 99352.0 99733.0 102701.5 105903.5 105944.5 73706.3 88125.0 89644.0 89679.0 90035.0 76449.6 77763.6 78048.6 78527.0 81498.0 8533.7 8647.0 8793.4 8806.6 8923.3 45371.5 50312.5 50358.2 52919.5 52999.5 61550.7 64727.7 66177.0 66629.0 71017.6 10216.0 10808.7 12063.9 13050.6 13226.0 14085.3 14442.4 14547.6 14814.7 15189.9 13118.1 13903.4 13927.0 14057.0 14394.6 12050.5 12870.7 13684.0 13895.6 14267.1 12937.7 13365.9 13374.0 13445.9 13550.3 11064.1 11177.3 11584.7 11739.7 11772.2 10378.5 11274.6 11468.9 11559.9 11599.5 22:07:25 INFO - PROCESS | 1928 | -------- Summary: end -------- 22:07:25 INFO - PROCESS | 1928 | 22:07:26 INFO - TEST-INFO | 1928: exit 0 22:07:26 INFO - TEST-OK | dromaeo_css | took 1117207ms 22:07:26 INFO - TEST-START | kraken 22:07:26 INFO - operating with platform_type : mac_ 22:07:26 INFO - Initialising browser for kraken test... 22:07:26 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmp8jUBam/profile http://localhost:56177/getInfo.html) 22:07:28 INFO - PROCESS | 1932 | 2016-12-17 22:07:28.984 plugin-container[1933:26827] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 22:07:28 INFO - PROCESS | 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:07:28 INFO - PROCESS | 1932 | 2016-12-17 22:07:28.986 plugin-container[1933:26827] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:07:28 INFO - PROCESS | 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:07:28 INFO - PROCESS | 1932 | 2016-12-17 22:07:28.986 plugin-container[1933:26827] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:07:29 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 22:07:29 INFO - PROCESS | 1932 | colorDepth:24 22:07:29 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 22:07:29 INFO - PROCESS | 1932 | __metrics 22:07:29 ERROR - PROCESS | 1932 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 22:07:29 INFO - TEST-INFO | 1932: exit 0 22:07:29 INFO - Browser initialized. 22:07:29 INFO - Running cycle 1/1 for kraken test... 22:07:29 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmp8jUBam/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:07:32 INFO - PROCESS | 1934 | 2016-12-17 22:07:32.073 plugin-container[1935:26942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 22:07:32 INFO - PROCESS | 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:07:32 INFO - PROCESS | 1934 | 2016-12-17 22:07:32.074 plugin-container[1935:26942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 22:07:32 INFO - PROCESS | 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:07:32 INFO - PROCESS | 1934 | 2016-12-17 22:07:32.074 plugin-container[1935:26942] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:07:41 INFO - PROCESS | 1934 | RSS: Main: 237019136 22:07:41 INFO - PROCESS | 1934 | 22:07:44 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56177/tests/kraken/driver.html?testName=audio-beat-detection) 22:07:45 INFO - PROCESS | 1934 | RSS: Main: 249614336 22:07:45 INFO - PROCESS | 1934 | 22:07:48 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56177/tests/kraken/driver.html?testName=audio-dft) 22:07:49 INFO - PROCESS | 1934 | RSS: Main: 257290240 22:07:49 INFO - PROCESS | 1934 | 22:07:52 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56177/tests/kraken/driver.html?testName=audio-fft) 22:07:53 INFO - PROCESS | 1934 | RSS: Main: 259792896 22:07:53 INFO - PROCESS | 1934 | 22:07:55 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56177/tests/kraken/driver.html?testName=audio-oscillator) 22:07:56 INFO - PROCESS | 1934 | RSS: Main: 262004736 22:07:56 INFO - PROCESS | 1934 | 22:07:58 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56177/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:07:58 INFO - PROCESS | 1934 | RSS: Main: 262311936 22:07:58 INFO - PROCESS | 1934 | 22:08:02 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56177/tests/kraken/driver.html?testName=imaging-darkroom) 22:08:02 INFO - PROCESS | 1934 | RSS: Main: 267976704 22:08:02 INFO - PROCESS | 1934 | 22:08:05 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56177/tests/kraken/driver.html?testName=imaging-desaturate) 22:08:06 INFO - PROCESS | 1934 | RSS: Main: 267882496 22:08:06 INFO - PROCESS | 1934 | 22:08:09 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56177/tests/kraken/driver.html?testName=json-parse-financial) 22:08:10 INFO - PROCESS | 1934 | RSS: Main: 268218368 22:08:10 INFO - PROCESS | 1934 | 22:08:11 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56177/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:08:12 INFO - PROCESS | 1934 | RSS: Main: 267046912 22:08:12 INFO - PROCESS | 1934 | 22:08:14 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56177/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:08:15 INFO - PROCESS | 1934 | RSS: Main: 267018240 22:08:15 INFO - PROCESS | 1934 | 22:08:17 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56177/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:08:17 INFO - PROCESS | 1934 | RSS: Main: 267132928 22:08:17 INFO - PROCESS | 1934 | 22:08:20 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56177/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:08:21 INFO - PROCESS | 1934 | RSS: Main: 267464704 22:08:21 INFO - PROCESS | 1934 | 22:08:24 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56177/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:08:25 INFO - PROCESS | 1934 | RSS: Main: 267280384 22:08:25 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:56177/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56177/tests/kraken/driver.html?testName=ai-astar) 22:08:27 INFO - PROCESS | 1934 | RSS: Main: 269860864 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | __start_tp_report 22:08:27 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 22:08:27 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 22:08:27 INFO - PROCESS | 1934 | |i|pagename|runs| 22:08:27 INFO - PROCESS | 1934 | |0;ai-astar;106;125;176;174;119;119;119;117;118;117 22:08:27 INFO - PROCESS | 1934 | |1;audio-beat-detection;152;117;140;174;110;116;114;111;165;103 22:08:27 INFO - PROCESS | 1934 | |2;audio-dft;212;262;267;202;254;196;279;270;217;285 22:08:27 INFO - PROCESS | 1934 | |3;audio-fft;82;58;57;85;65;83;81;65;65;86 22:08:27 INFO - PROCESS | 1934 | |4;audio-oscillator;89;74;95;71;71;70;83;71;71;80 22:08:27 INFO - PROCESS | 1934 | |5;imaging-gaussian-blur;111;115;134;150;168;133;135;116;112;117 22:08:27 INFO - PROCESS | 1934 | |6;imaging-darkroom;94;103;97;110;96;154;91;92;100;139 22:08:27 INFO - PROCESS | 1934 | |7;imaging-desaturate;148;135;93;96;92;119;138;147;132;89 22:08:27 INFO - PROCESS | 1934 | |8;json-parse-financial;58;57;58;58;58;58;57;61;59;59 22:08:27 INFO - PROCESS | 1934 | |9;json-stringify-tinderbox;74;71;70;80;78;73;71;74;72;73 22:08:27 INFO - PROCESS | 1934 | |10;stanford-crypto-aes;93;83;85;108;74;90;111;113;107;92 22:08:27 INFO - PROCESS | 1934 | |11;stanford-crypto-ccm;139;168;166;150;166;181;156;155;205;172 22:08:27 INFO - PROCESS | 1934 | |12;stanford-crypto-pbkdf2;175;176;173;199;242;174;182;237;169;223 22:08:27 INFO - PROCESS | 1934 | |13;stanford-crypto-sha256-iterative;69;67;67;73;64;66;65;71;69;66 22:08:27 INFO - PROCESS | 1934 | __end_tp_report 22:08:27 INFO - PROCESS | 1934 | __start_cc_report 22:08:27 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,954 22:08:27 INFO - PROCESS | 1934 | __end_cc_report 22:08:27 INFO - PROCESS | 1934 | __startTimestamp1482041307610__endTimestamp 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | ------- Summary: start ------- 22:08:27 INFO - PROCESS | 1934 | Number of tests: 14 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#0] ai-astar Cycles:10 Average:129.00 Median:119.00 stddev:24.69 (20.8%) stddev-sans-first:24.75 22:08:27 INFO - PROCESS | 1934 | Values: 106.0 125.0 176.0 174.0 119.0 119.0 119.0 117.0 118.0 117.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:128.50 stddev:25.51 (19.9%) stddev-sans-first:25.81 22:08:27 INFO - PROCESS | 1934 | Values: 152.0 117.0 140.0 174.0 110.0 116.0 114.0 111.0 165.0 103.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#2] audio-dft Cycles:10 Average:244.40 Median:264.50 stddev:33.92 (12.8%) stddev-sans-first:33.89 22:08:27 INFO - PROCESS | 1934 | Values: 212.0 262.0 267.0 202.0 254.0 196.0 279.0 270.0 217.0 285.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#3] audio-fft Cycles:10 Average:72.70 Median:81.50 stddev:11.69 (14.3%) stddev-sans-first:11.91 22:08:27 INFO - PROCESS | 1934 | Values: 82.0 58.0 57.0 85.0 65.0 83.0 81.0 65.0 65.0 86.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:77.00 stddev:8.90 (11.6%) stddev-sans-first:8.41 22:08:27 INFO - PROCESS | 1934 | Values: 89.0 74.0 95.0 71.0 71.0 70.0 83.0 71.0 71.0 80.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#5] imaging-gaussian-blur Cycles:10 Average:129.10 Median:133.50 stddev:18.74 (14.0%) stddev-sans-first:18.70 22:08:27 INFO - PROCESS | 1934 | Values: 111.0 115.0 134.0 150.0 168.0 133.0 135.0 116.0 112.0 117.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#6] imaging-darkroom Cycles:10 Average:107.60 Median:91.50 stddev:21.54 (23.5%) stddev-sans-first:22.27 22:08:27 INFO - PROCESS | 1934 | Values: 94.0 103.0 97.0 110.0 96.0 154.0 91.0 92.0 100.0 139.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#7] imaging-desaturate Cycles:10 Average:118.90 Median:118.50 stddev:24.14 (20.4%) stddev-sans-first:23.19 22:08:27 INFO - PROCESS | 1934 | Values: 148.0 135.0 93.0 96.0 92.0 119.0 138.0 147.0 132.0 89.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.00 stddev:1.16 (2.0%) stddev-sans-first:1.22 22:08:27 INFO - PROCESS | 1934 | Values: 58.0 57.0 58.0 58.0 58.0 58.0 57.0 61.0 59.0 59.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#9] json-stringify-tinderbox Cycles:10 Average:73.60 Median:73.50 stddev:3.17 (4.3%) stddev-sans-first:3.36 22:08:27 INFO - PROCESS | 1934 | Values: 74.0 71.0 70.0 80.0 78.0 73.0 71.0 74.0 72.0 73.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#10] stanford-crypto-aes Cycles:10 Average:95.60 Median:84.00 stddev:13.38 (15.9%) stddev-sans-first:14.16 22:08:27 INFO - PROCESS | 1934 | Values: 93.0 83.0 85.0 108.0 74.0 90.0 111.0 113.0 107.0 92.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#11] stanford-crypto-ccm Cycles:10 Average:165.80 Median:167.00 stddev:18.23 (10.9%) stddev-sans-first:16.56 22:08:27 INFO - PROCESS | 1934 | Values: 139.0 168.0 166.0 150.0 166.0 181.0 156.0 155.0 205.0 172.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.00 Median:190.50 stddev:28.49 (15.0%) stddev-sans-first:29.28 22:08:27 INFO - PROCESS | 1934 | Values: 175.0 176.0 173.0 199.0 242.0 174.0 182.0 237.0 169.0 223.0 22:08:27 INFO - PROCESS | 1934 | 22:08:27 INFO - PROCESS | 1934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:2.79 (4.1%) stddev-sans-first:2.92 22:08:27 INFO - PROCESS | 1934 | Values: 69.0 67.0 67.0 73.0 64.0 66.0 65.0 71.0 69.0 66.0 22:08:27 INFO - PROCESS | 1934 | -------- Summary: end -------- 22:08:27 INFO - PROCESS | 1934 | 22:08:28 INFO - TEST-INFO | 1934: exit 0 22:08:28 INFO - TEST-OK | kraken | took 62376ms 22:08:28 INFO - SUITE-END | took 1179s 22:08:28 INFO - Completed test suite (00:19:39) 22:08:28 INFO - javascript benchmark 22:08:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7048.912363954593, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2852.871620777065, 3359.1266270769606, 3369.4271973764457, 3399.881004164853, 3409.4544872820347, 4486.551344865512, 4532.932006019908, 5590.16147577863, 5638.097904335307, 5669.291338582677, 5558.416366281543, 5617.9157312640245, 5678.148277758328, 5695.117256825189, 5719.685417302057, 4462.709923854939, 4937.654364194508, 5148.330717006784, 5264.341957255343, 5285.947140528603, 5082.567981721558, 5190.532852043315, 5213.322268105141, 5268.025415298182, 5279.076161671711, 5115.693058416469, 5150.356205474316, 5246.711430871301, 5257.789688412459, 5266.210068489719, 5127.717975511346, 5149.253358263069, 5162.97418512905, 5181.5077567631015, 5238.0, 5111.718855462948, 5169.689818610857, 5178.637495375326, 5181.844544663666, 5185.611079169077, 5500.42245564218, 5672.40439753824, 5672.4894759471845, 5681.517071048966, 5712.285964254468, 5943.821685349403, 6015.699215039231, 6030.155778191057, 6127.234095738033, 6198.690065496708, 4466.776661166961, 5715.542756579505, 6117.553376797881, 6173.0, 6237.251529816405, 5751.194832723422, 5753.0, 5755.510781583613, 5769.365369809359, 5771.624844385144, 6218.782342618078, 6330.715117819709, 6348.079528468346, 6355.650439225887, 6363.268224154189, 5888.587798854039, 5960.135780311831, 5965.761369545169, 6008.09878518217, 6048.939510604838, 6062.878742425215, 6122.977604208209, 6144.832481828527, 6145.955187618116, 6147.770445910835, 6052.515798736091, 6091.451769340781, 6117.614269788186, 6122.032243765697, 6127.938720612826, 4683.718976861399, 6038.762053778964, 6177.083021988384, 6233.657148856766, 6240.438360547572, 4751.00228951924, 4823.541763532458, 4864.635152363516, 4871.293662419001, 4924.4583095859425, 4227.00665343644, 4233.724807887477, 4319.97840010798, 4350.521442641307, 4358.455193100863, 5692.57305702074, 5694.4590263924865, 5700.743466544015, 5704.286964129484, 5763.221965034667, 6070.2412198475195, 6073.848153796191, 6078.270607527036, 6085.604435711664, 6094.8171554853425, 5671.319441667027, 5696.1455781633085, 5732.828015159552, 5751.194832723422, 5775.566832487496, 5292.788288468495, 5303.7878484860175, 5309.973450132725, 5332.200169974535, 5333.333333333333, 6392.8400191785495, 6416.422521973045, 6418.26189988148, 6421.775237866652, 6431.6462594556815, 5190.0, 5377.193420986883, 6071.72493776312, 6208.0, 6285.277193122666, 6192.318844927145, 6251.218597675291, 6279.748810047547, 6319.58922670025, 6325.335839736761, 6135.998322733915, 6141.946315431794, 6143.784967526115, 6157.476614487819, 6170.876582468236, 6170.876582468236, 6178.721957511832, 6227.96886015567, 6235.96882015587, 6280.152179455715, 5702.6863522506665, 5865.4134586541, 5866.325372582208, 5933.347331793586, 6059.939400605937, 5919.200907877382, 5931.9406805931385, 5939.07944268639, 5958.553108516965, 5967.134765459071, 4682.274247491644, 5770.5095066919785, 5874.324452687824, 5885.882282354414, 5900.675462849585, 6076.787312444043, 6089.817305480843, 6090.086487026982, 6098.786542470993, 6108.797423689751, 5826.970865145647, 5847.590668653153, 5855.824325270249, 5860.794872179454, 5877.85381850535], "value": 5587.263415147303, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.93269144245477, 409.20205599081777, 451.52138732943075, 461.59149152999595, 480.13815201712924, 36858.44712329314, 39084.63203787865, 39207.21585568287, 41325.380119298236, 41409.792951035204, 40689.389659155066, 41293.79353103238, 41585.0, 41609.75170744883, 41727.16545669093, 8313.043999939993, 9258.58336374863, 9516.762080947963, 9529.761755956088, 9555.856662150074, 8361.83276334473, 9054.909450905505, 11329.660110196708, 11345.149113816455, 11471.59849405271, 11346.20576559641, 11386.259893106962, 11388.886111138907, 11395.487203075882, 11413.0, 11307.434628268553, 11372.431378431045, 11380.430978451044, 11396.145289103268, 11412.31526108436, 11199.66401007971, 11246.775064498746, 11268.887311126866, 11269.60556380531, 11276.661700149009, 38877.41683874744, 39156.80421597902, 39182.60817391818, 39319.0, 39361.40957885634, 3503.404421248368, 3534.4344904815107, 3627.873024444158, 3721.237146385012, 4041.090754580225, 29260.8536957316, 29302.99851342144, 29570.0, 30244.69755302441, 30676.386472270653, 3157.763167762427, 3164.7309978651842, 3165.3036332006923, 3171.3181665941934, 3274.5415641810164, 473769.0, 475120.0, 478980.0, 479281.60359197983, 480479.59760201327, 57851.710741446026, 58271.0, 58502.0, 58616.706916466, 58662.12006819901, 62225.68887155535, 62311.688441558406, 62395.0, 62552.0, 62610.68694656497, 14714.264286785618, 15055.096694198382, 15095.622609434633, 15132.546023619308, 15143.621409464631, 10382.480875956171, 10531.368117912949, 11264.267822591506, 11624.186306958432, 11624.593139240254, 13277.93361033208, 13282.933585332012, 13958.930205348908, 16076.75884861728, 16560.66878662444, 5803.390643982406, 5889.204957330791, 6202.627842329474, 6230.937690623035, 6689.0, 746.4476287547176, 856.6402111113347, 986.2849434160177, 992.4839083676447, 1040.9895901041043, 25117.87441062783, 27786.722132778817, 28996.0, 29602.851985739933, 29742.0, 1053.1732589916878, 1065.8454524093963, 1081.599808071003, 1107.4186052322434, 1127.9436028198722, 14589.927050364893, 15038.774418383733, 15272.770908436383, 15584.688306233811, 15603.921980390025, 569837.0, 722815.0, 731819.0, 735418.0, 735986.0, 48900.5109948896, 49457.010859782604, 49609.0, 49702.005959881324, 49766.751166243936, 1329.0829327763813, 1350.8581598932167, 1359.9116057456233, 1364.34511434512, 1376.3049659921876, 27266.454670906867, 27343.72656273412, 27730.029448969188, 27889.157708327813, 27908.720912790614, 42114.57885421107, 42168.78915605525, 42552.0, 42572.7228183155, 42580.36129457998, 438650.0, 441992.0, 442563.0, 442843.0, 444112.0, 22105.77894221037, 22702.0, 23379.88310058439, 23432.88283558571, 23706.762932370457, 40137.996550085314, 40864.18271634491, 41088.58911410967, 42060.57939420567, 43056.56943430525, 27241.45517089687, 31888.521672174473, 33699.66300336965, 34753.47869782006, 35273.0, 33413.832930836164, 33731.15672108217, 33783.662163379035, 34534.0, 34541.82729086439, 32044.51933220957, 32162.678373215967, 32829.17927051843, 32885.50671739878, 33169.83415082909, 27548.0, 30185.849070754506, 30419.391612168074, 30597.694023059485, 30622.693773061983, 19144.425667230007, 22914.198003069814, 23462.061517539107, 23469.178578749663, 23539.529209415374], "value": 18888.88439816289, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.4113130687858, 375.4968342421155, 380.78675941472767, 387.9786611736357, 405.0784465341348, 17844.73232901506, 19041.619167616675, 19563.804361956376, 19773.110210040548, 19884.60230795383, 15699.843001569981, 18627.534311642215, 19342.03289835506, 19775.703364449546, 19861.40415787525, 4493.9775301123445, 5117.104506711329, 5298.682079075248, 5306.946930530684, 5314.627976041678, 5586.972065139689, 5654.830355089333, 5733.627314224583, 5733.913991290134, 5739.512141467981, 5515.448455154493, 5681.375048744635, 5717.0, 5720.370759216483, 5742.6841523716175, 5628.774849006035, 5685.061964775807, 5685.744141513621, 5737.254156959611, 5749.913751293713, 5332.0, 5504.889902201933, 5531.612787104904, 5565.220869078333, 5567.2762540869835, 10986.450677466095, 12535.122541422104, 12614.558490452882, 12646.177998430072, 20838.27066052696, 2091.362134548962, 2641.7583735644216, 2657.2161212442375, 2922.1379692990618, 2930.692277310873, 9757.609695612167, 10019.947905469966, 10264.74338141545, 11902.702432439171, 15093.773593396108, 1577.250805867204, 1662.742274947373, 1671.2980548169799, 1800.5842955661042, 1896.8577356698304, 86025.0, 95291.66479173265, 96692.0, 98514.50742746312, 102468.0, 8462.788430289293, 11309.773804523864, 11457.197996140358, 11650.708732281591, 11653.53385864556, 11124.61063862761, 14033.929830350782, 14248.287585620677, 14251.857481425053, 14276.214808185441, 6402.135711678957, 7934.127246002935, 7984.441089123822, 8047.436679432501, 8138.552379619059, 7581.886271705929, 7641.541507509567, 7741.0, 7864.134945156028, 7883.842323153504, 3827.7559793067367, 4286.5284818669925, 4459.888502787391, 4478.641708663299, 4555.316702494586, 1761.9559511012328, 1967.7933816949014, 1988.5625162464235, 1997.7402937618017, 1998.4704053425853, 805.399977017126, 872.581161042703, 936.0405584276035, 951.6288647427509, 996.4768496539375, 16496.17519124039, 18476.465215633583, 19169.32907348236, 20454.795452045288, 20560.485987850425, 799.9120096789349, 810.2302812328194, 924.0851556958563, 929.256594724218, 957.7318350861769, 10325.173986081245, 10665.84001239982, 10705.732356690989, 10715.571377144983, 10717.678469645924, 531842.4302420408, 533415.0, 537685.0, 540996.0, 542613.0, 116101.0, 119986.0, 123310.0, 127033.02636709955, 127337.0, 975.7463059604554, 993.9552720127467, 1035.7980193862275, 1037.8806437259661, 1039.142707266508, 18291.63416731631, 18341.266349345875, 18650.34723784661, 18733.250669973593, 18851.0, 28891.85554072216, 43977.78011110052, 49640.021598704196, 50935.49064509456, 50960.74519627378, 69515.95726064012, 70869.64565177141, 71016.64491677508, 71630.28369716444, 72192.63903680448, 7928.365730741642, 7981.92018079835, 8074.475159114639, 8087.3125784308995, 8138.9186108138165, 8464.0, 11012.889871101186, 12130.150889437655, 12188.268703877795, 12410.131290809557, 10634.521446534769, 10683.03852653265, 10784.245102842726, 10948.835767463335, 11031.0, 10161.593536258466, 10503.527341269508, 10625.681229562817, 10648.680539583826, 10690.075786358218, 9142.0, 10345.068943794793, 10889.618863340062, 11379.829302560634, 11460.0, 7981.321587665114, 7991.2807847294025, 8167.224113709188, 8230.506169629842, 8240.093589705008, 10799.514021868878, 10828.133749300194, 11013.559457621606, 11161.386123763272, 11188.216824822184], "value": 9009.286970405397, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.5660686704289, 1524.2074121456842, 1750.903700296484, 1828.1133650179652, 1858.962820743586, 7560.735374261902, 7822.491538050022, 7832.334251588614, 8211.958940205299, 8230.547319897403, 7956.323712484431, 8280.296174825133, 8304.626291816867, 8314.085450600429, 8330.875036874451, 1978.3570339639602, 2291.278247352079, 2318.678353338589, 2351.988240058797, 2355.9293221203393, 1910.4650697804636, 1920.2990908318502, 2085.384811480617, 2277.646964720471, 2385.940351491218, 1930.671785796418, 2269.7503274639776, 2322.6051571232847, 2351.6354964980455, 2359.8112151027963, 2270.4323919020244, 2288.2677543186187, 2300.3903965449167, 2310.8151347892126, 2320.1995311617466, 2306.342692332677, 2324.32594547581, 2329.3827135809024, 2331.1724337859987, 2342.5900467418305, 7786.844263114706, 7838.0, 8173.8365232695605, 8312.625931833081, 8332.375071869634, 5074.873128171788, 5251.86870328241, 5252.474752524717, 5261.736913154327, 5280.524752772269, 3279.39331223722, 3405.6083550391772, 3491.3540994915766, 3510.6840384365273, 3518.7009104225917, 1043.4730461117163, 1062.606835470873, 1083.3175099687146, 1086.3699054548422, 1096.961606343774, 10548.630797922113, 10601.310914790514, 10693.091087257593, 10749.032587067202, 10784.4607769612, 4485.618722408566, 4643.976780116078, 4721.716696998191, 4739.905201895943, 4743.6916600421555, 9435.150836424618, 9472.289578281656, 9477.95742468328, 9479.90520094804, 9484.19384352324, 5240.554552862973, 5246.396664383568, 5249.475052494797, 5253.50091741284, 5255.316808814906, 3725.8882233533036, 4739.957209413923, 4743.003969166513, 4767.4517430495935, 4830.275458681226, 3831.9042023949623, 3851.0, 3900.414937759359, 3940.763554186758, 3949.5260568731937, 1277.6486466221677, 1296.9935150324188, 1300.5058077930325, 1305.5039085147773, 1312.7571399291162, 793.5238856686058, 808.8301456694043, 831.4285999461024, 896.7400801873379, 932.9953350233341, 5402.35171779389, 5510.476504732044, 5701.372848986608, 5726.0, 5806.129080637938, 878.0911756332166, 890.4479222881855, 908.9181973622406, 966.8308046091905, 988.5172241637543, 3678.8528458861347, 3799.0502374406396, 3846.576876543578, 3847.115163512408, 3850.9229815404096, 9713.660021899199, 9819.16537094355, 9925.30522863407, 10036.24728145392, 10069.144122749649, 9728.659496917573, 9983.351082179635, 10012.849807252896, 10019.549120289605, 10041.849372259421, 6936.132983377078, 7590.506617069983, 7691.769246922601, 7953.204679532, 7961.402894782915, 5446.0, 5504.22940788282, 5529.751161197836, 5541.861453463534, 5574.0, 9017.684381046633, 9280.443173409618, 9302.627894884128, 9305.929818071008, 9331.0, 9071.369302902085, 9337.253019758267, 9512.667056652985, 9629.422234665943, 9630.711078667651, 6586.769463068769, 6813.386795188457, 6959.269276726073, 7059.082319298458, 7236.638168091469, 6569.9343006569325, 7915.366770658218, 7937.523748575104, 7960.601969901598, 7971.202879712215, 7013.579185248901, 7130.180029296697, 7199.280071992759, 7211.711531538889, 7268.345848873627, 6956.2000369958105, 6971.195579386975, 6998.16022077354, 7110.075690160246, 7126.28737126304, 6623.768168114093, 6653.933460665525, 6719.630420326929, 6720.092787473628, 6727.688100820293, 4089.550149483614, 4813.302071199727, 4820.204666230173, 4888.780004899717, 4908.509149085063, 4750.643701722453, 4780.1873681473535, 4854.660173787801, 4941.085899108676, 5044.39467263916], "value": 4398.060205361326, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.9362127574485, 338.237274944999, 351.66943073510896, 356.4973387523592, 362.82806534892296, 4982.900341993162, 5077.136886729256, 5081.865616441094, 5084.923726144101, 5250.737463126848, 4486.8653940381755, 5220.556252718514, 5221.660592061523, 5243.973780131104, 5322.893542129164, 3932.744371615836, 4177.227212965611, 4179.0, 4179.498460184782, 4194.53860075393, 3558.395072837624, 3578.4274516077435, 4181.205570941526, 4202.768847713374, 4213.43118678979, 3118.469860123773, 3797.6012518685557, 3969.880903572883, 3982.574985512457, 4026.1947610477873, 3121.82829944353, 3439.7592168548213, 3658.396364599838, 3658.5426821647293, 4030.9116538534727, 3474.498361179946, 3483.756137070406, 3501.9824900875333, 3513.9809455257946, 3531.205478767257, 4477.954990606387, 5089.898202035975, 5127.897621938748, 5146.073706732786, 5214.739263036833, 4360.149770794696, 4533.753217865081, 4537.863864084083, 4563.110193512233, 4593.494715581283, 4657.510961349078, 4752.979983506995, 5245.213218017289, 5528.087865502187, 5550.833474995757, 3567.571891373026, 3777.338965680995, 3778.659920607131, 3794.335991201556, 3799.3731034379293, 5201.491567445436, 5275.57795376369, 5708.514776244025, 5821.970890145564, 5841.108651217632, 3839.827207775657, 3857.5949525300002, 3863.9034024149623, 3871.864484743077, 3890.9299942515468, 3570.803605801706, 3597.6582224689137, 3915.432262322004, 3945.743526670757, 3954.3079961007284, 4289.828406863753, 4484.529124441952, 4536.818527258873, 4542.772861356919, 4548.818047278071, 4776.737279449663, 5505.009098362253, 5596.110703061415, 5629.802956896489, 5642.435756424325, 1154.6132045764764, 1161.73280145567, 1174.4890972427022, 1192.9821052684217, 1198.898093662031, 823.6211342782387, 829.672279449614, 852.884860543831, 855.4952577978897, 872.358816270041, 4406.647468202536, 4423.955760442419, 4590.816367345269, 5032.748362581856, 5152.819651312261, 5286.198314893113, 5412.5940554458575, 5431.972840135774, 5450.809221677222, 5471.480209380103, 4623.722576645412, 4709.576138147583, 4818.325434439182, 4885.4366602687505, 5051.015052064812, 2685.9328516787236, 2772.819766715157, 2856.300206449433, 2872.224499385154, 2879.8704058317426, 5175.964807038607, 5290.761915713802, 5419.512243898068, 5755.920582488348, 5768.26951914424, 5267.288915996368, 5570.0, 5584.972075139598, 5606.831795046155, 5625.662460252398, 5038.513638476658, 5580.581456390706, 5590.608657393944, 5762.88474230513, 5766.567507436959, 4629.375034370317, 4904.877378065434, 4986.825461108844, 5350.5184533392185, 5473.644213126134, 4738.076075165448, 5053.797848086036, 5071.066851783496, 5326.97336513315, 5421.674699518042, 4960.404751429852, 5161.612879034163, 5334.54610879, 5611.326640802967, 5920.200772895604, 3087.5368694695976, 3093.3967876263914, 3164.240582260287, 3538.699845100602, 3668.761530500509, 4605.953940460552, 4869.342638743724, 4943.292530602049, 5335.946640533545, 5415.296011518478, 4898.804047838046, 5401.432849550741, 5470.0, 5518.944810551843, 5550.916736249041, 5098.974505127599, 5206.7350702444555, 5560.388357280777, 5586.636868603528, 5593.168679454424, 5019.7239151847, 5197.0905091609575, 5432.619716619799, 5441.29263195782, 5447.564194864322, 5480.808171713971, 5500.074973759312, 5536.944630553643, 5576.721163941867, 5577.0, 4591.747453890068, 4794.328793968777, 5437.972810135924, 5471.72641367938, 5474.3704473986], "value": 3969.9109094460487, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.278375746073, 687.9105716256886, 742.8551432470667, 780.426386605845, 816.9101398846128, 14333.569992900204, 15351.692966140698, 15392.692146157071, 15443.0, 15482.845171548295, 13874.167549947011, 14015.649608759786, 14021.228832414214, 14067.507637232673, 14088.78866816996, 679.9864002719955, 702.0417130616697, 846.9026062002856, 874.6851133591902, 881.3345923827534, 867.7266661001764, 880.1638443478689, 881.7002219245455, 881.7310720230328, 882.7969566999562, 916.5188276155005, 922.3912217936163, 925.1765928323797, 925.7361651929201, 930.1117432851644, 910.0853642089724, 913.1827014821697, 922.5617831530003, 923.1138107416888, 925.8611208318806, 672.6132473827569, 682.8975653651943, 684.090160087088, 688.4870771275382, 691.1878542712293, 13613.795793063113, 13839.377228024763, 14059.297035148202, 14399.0, 14460.0, 12077.516899324017, 13714.862851371458, 14069.2965351732, 14165.645858853508, 14170.433182672681, 10716.196285278558, 11206.607768728056, 11406.315621062762, 11473.368964706939, 11533.826992595117, 4368.257132178896, 4431.091626216617, 4540.591346778772, 4562.5665561771575, 4632.444106707183, 97805.0, 99525.5023724884, 101378.99999999926, 103021.96978030198, 103921.0, 10320.277580569287, 10361.585536578603, 10486.213533984828, 10584.629537966135, 10597.523111460003, 19577.314793982143, 19675.803241967682, 19688.70466942997, 19721.605567888848, 20023.195592837314, 7613.0, 7643.808904777425, 7649.273319034683, 7829.765107046797, 7971.880421793678, 3411.573553305837, 4290.334998075304, 4329.610335069859, 4390.517043125254, 4424.823007079681, 3548.591911930161, 3612.3678356287546, 3643.8178091095338, 3649.61679023704, 3656.5794933582447, 645.1709553224179, 651.3844417025865, 655.3741177175785, 655.4003087175181, 664.9567778094505, 570.7146426786607, 641.7721708793405, 663.7554585152839, 664.2726214794792, 687.924328323884, 7607.744722120899, 8256.545889975989, 9078.0, 9248.398854074598, 9463.242940564738, 600.5315853634172, 641.9329758178658, 677.109600874848, 698.5319835710086, 708.1643660480682, 3750.9062273443383, 4288.0, 4557.772111394417, 4605.447346318396, 4676.462206846256, 99352.0, 99733.0, 102701.48649256706, 105903.4704826471, 105944.47027764811, 73706.26293736995, 88125.0, 89644.0, 89679.0, 90035.0, 76449.61775191089, 77763.61118194372, 78048.60975695199, 78527.0, 81498.0, 8533.658653653783, 8647.048824629412, 8793.384463087523, 8806.559672016501, 8923.286137109146, 45371.54628453673, 50312.49687503078, 50358.24462633137, 52919.47080529299, 52999.470005300995, 61550.69224653848, 64727.67636161789, 66177.00734489082, 66629.0, 71017.6449117751, 10216.0, 10808.675739727822, 12063.879361206627, 13050.608481745563, 13226.0, 14085.295455340603, 14442.422303107758, 14547.563573092824, 14814.703705926036, 15189.924050379677, 13118.147320424141, 13903.443862245396, 13926.955478339367, 14057.0, 14394.640133996734, 12050.457729402022, 12870.678233043875, 13684.0, 13895.643439301106, 14267.072640278764, 12937.741245175232, 13365.866341336463, 13374.0, 13445.865541344461, 13550.322483875963, 11064.114870810155, 11177.329360238411, 11584.710382240512, 11739.706507337385, 11772.234804737665, 10378.481075946022, 11274.605388811353, 11468.942655287005, 11559.942200288944, 11599.536018559163], "value": 7388.370944393078, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1665.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 125.0, 176.0, 174.0, 119.0, 119.0, 119.0, 117.0, 118.0, 117.0], "value": 129.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 140.0, 174.0, 110.0, 116.0, 114.0, 111.0, 165.0, 103.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 262.0, 267.0, 202.0, 254.0, 196.0, 279.0, 270.0, 217.0, 285.0], "value": 244.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 57.0, 85.0, 65.0, 83.0, 81.0, 65.0, 65.0, 86.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 74.0, 95.0, 71.0, 71.0, 70.0, 83.0, 71.0, 71.0, 80.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 115.0, 134.0, 150.0, 168.0, 133.0, 135.0, 116.0, 112.0, 117.0], "value": 129.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 103.0, 97.0, 110.0, 96.0, 154.0, 91.0, 92.0, 100.0, 139.0], "value": 107.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 135.0, 93.0, 96.0, 92.0, 119.0, 138.0, 147.0, 132.0, 89.0], "value": 118.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 58.0, 58.0, 58.0, 57.0, 61.0, 59.0, 59.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 71.0, 70.0, 80.0, 78.0, 73.0, 71.0, 74.0, 72.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 83.0, 85.0, 108.0, 74.0, 90.0, 111.0, 113.0, 107.0, 92.0], "value": 95.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 168.0, 166.0, 150.0, 166.0, 181.0, 156.0, 155.0, 205.0, 172.0], "value": 165.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 173.0, 199.0, 242.0, 174.0, 182.0, 237.0, 169.0, 223.0], "value": 195.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 73.0, 64.0, 66.0, 65.0, 71.0, 69.0, 66.0], "value": 67.7, "unit": "score"}]}]} 22:08:28 INFO - Return code: 0 22:08:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:08:28 ERROR - # TBPL SUCCESS # 22:08:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:08:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:08:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:08:28 INFO - 2016-12-17 22:08:28.937 screenresolution[1939:27328] starting screenresolution argv=screenresolution get 22:08:28 INFO - 2016-12-17 22:08:28.941 screenresolution[1939:27328] Display 0: 1600x1200x32@60 22:08:28 INFO - 2016-12-17 22:08:28.947 screenresolution[1940:27331] starting screenresolution argv=screenresolution list 22:08:28 INFO - Available Modes on Display 0 22:08:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:08:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:08:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:08:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:08:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:08:29 INFO - Intel Iris: 22:08:29 INFO - Chipset Model: Intel Iris 22:08:29 INFO - Type: GPU 22:08:29 INFO - Bus: Built-In 22:08:29 INFO - VRAM (Dynamic, Max): 1536 MB 22:08:29 INFO - Vendor: Intel (0x8086) 22:08:29 INFO - Device ID: 0x0a2e 22:08:29 INFO - Revision ID: 0x0009 22:08:29 INFO - Displays: 22:08:29 INFO - Display: 22:08:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:08:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:08:29 INFO - Display Serial Number: 1600x1200 60 22:08:29 INFO - Main Display: Yes 22:08:29 INFO - Mirror: Off 22:08:29 INFO - Online: Yes 22:08:29 INFO - Rotation: Supported 22:08:29 INFO - Return code: 0 22:08:29 INFO - Running post-action listener: _resource_record_post_action 22:08:29 INFO - [mozharness: 2016-12-18 06:08:29.194023Z] Finished run-tests step (success) 22:08:29 INFO - Running post-run listener: _resource_record_post_run 22:08:29 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 581632; Write bytes: 555895296; Read time: 6; Write time: 2076 22:08:29 INFO - TinderboxPrint: CPU usage
54.7% 22:08:29 INFO - TinderboxPrint: I/O read bytes / time
581,632 / 6 22:08:29 INFO - TinderboxPrint: I/O write bytes / time
555,895,296 / 2,076 22:08:29 INFO - TinderboxPrint: CPU idle
2,179.2 (45.3%) 22:08:29 INFO - TinderboxPrint: CPU system
970.8 (20.2%) 22:08:29 INFO - TinderboxPrint: CPU user
1,659.7 (34.5%) 22:08:29 INFO - TinderboxPrint: Swap in / out
249,536,512 / 0 22:08:29 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250163712; Write bytes: 242122752; Read time: 18175; Write time: 465 22:08:29 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 544768; Write bytes: 304241152; Read time: 6; Write time: 1574 22:08:29 INFO - Running post-run listener: _upload_blobber_files 22:08:29 INFO - Blob upload gear active. 22:08:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:08:29 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 22:08:29 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:08:29 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:08:30 INFO - (blobuploader) - INFO - Open directory for files ... 22:08:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:08:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:08:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:31 INFO - (blobuploader) - INFO - Done attempting. 22:08:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:08:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:08:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:32 INFO - (blobuploader) - INFO - Done attempting. 22:08:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:08:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:08:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:33 INFO - (blobuploader) - INFO - Done attempting. 22:08:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:08:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:08:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:35 INFO - (blobuploader) - INFO - Done attempting. 22:08:35 INFO - (blobuploader) - INFO - Iteration through files over. 22:08:35 INFO - Return code: 0 22:08:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:08:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:08:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d5e738a2060018837921d922be534be1aff4f33e2c598cdf85abfa0c894cf438725894c2beebaf1d33fa75b4be7d6a372313ba1e778800faeb56805725d23d9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5920aadaf9f71afd54062a62da454eb736f22d4ff8e2f0c3c45788d15324e4d5ea1c49a4a8c50df3bcd573cdce14e5cac87cea366ab84906577931fcbb70df2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85d198f2f916d5955596cea17d60d142647d2ebaa994eccde62da05477ce07b64e82ab0e9a1549142b1d89da8d1ae9e74a76a52ae655a0d9a14e8f8030ae1cd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5723f19e58573deac5c70f510a8dd75ad741b8424bd4c14244ca5dda62a18cd2e8bbd26ac70dbaf0992020c5e75c2b2d61afc178c097552dc12d90fa8df6c0bd"} 22:08:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:08:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:08:35 INFO - Contents: 22:08:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d5e738a2060018837921d922be534be1aff4f33e2c598cdf85abfa0c894cf438725894c2beebaf1d33fa75b4be7d6a372313ba1e778800faeb56805725d23d9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5920aadaf9f71afd54062a62da454eb736f22d4ff8e2f0c3c45788d15324e4d5ea1c49a4a8c50df3bcd573cdce14e5cac87cea366ab84906577931fcbb70df2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85d198f2f916d5955596cea17d60d142647d2ebaa994eccde62da05477ce07b64e82ab0e9a1549142b1d89da8d1ae9e74a76a52ae655a0d9a14e8f8030ae1cd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5723f19e58573deac5c70f510a8dd75ad741b8424bd4c14244ca5dda62a18cd2e8bbd26ac70dbaf0992020c5e75c2b2d61afc178c097552dc12d90fa8df6c0bd"} 22:08:35 INFO - Running post-run listener: copy_logs_to_upload_dir 22:08:35 INFO - Copying logs to upload dir... 22:08:35 INFO - mkdir: /builds/slave/test/build/upload/logs 22:08:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.686855 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-17 22:08:35.520455) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 22:08:35.524023) ========= 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.b7BItHYavl/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.tQh5wupGZF/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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/Firefox/sha512/1d5e738a2060018837921d922be534be1aff4f33e2c598cdf85abfa0c894cf438725894c2beebaf1d33fa75b4be7d6a372313ba1e778800faeb56805725d23d9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5920aadaf9f71afd54062a62da454eb736f22d4ff8e2f0c3c45788d15324e4d5ea1c49a4a8c50df3bcd573cdce14e5cac87cea366ab84906577931fcbb70df2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85d198f2f916d5955596cea17d60d142647d2ebaa994eccde62da05477ce07b64e82ab0e9a1549142b1d89da8d1ae9e74a76a52ae655a0d9a14e8f8030ae1cd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5723f19e58573deac5c70f510a8dd75ad741b8424bd4c14244ca5dda62a18cd2e8bbd26ac70dbaf0992020c5e75c2b2d61afc178c097552dc12d90fa8df6c0bd"} build_url:https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011020 build_url: 'https://queue.taskcluster.net/v1/task/MjrPUE4PQKqGx7Ftc9KkIA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d5e738a2060018837921d922be534be1aff4f33e2c598cdf85abfa0c894cf438725894c2beebaf1d33fa75b4be7d6a372313ba1e778800faeb56805725d23d9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5920aadaf9f71afd54062a62da454eb736f22d4ff8e2f0c3c45788d15324e4d5ea1c49a4a8c50df3bcd573cdce14e5cac87cea366ab84906577931fcbb70df2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85d198f2f916d5955596cea17d60d142647d2ebaa994eccde62da05477ce07b64e82ab0e9a1549142b1d89da8d1ae9e74a76a52ae655a0d9a14e8f8030ae1cd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5723f19e58573deac5c70f510a8dd75ad741b8424bd4c14244ca5dda62a18cd2e8bbd26ac70dbaf0992020c5e75c2b2d61afc178c097552dc12d90fa8df6c0bd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 22:08:35.559599) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 22:08:35.559926) ========= 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.b7BItHYavl/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.tQh5wupGZF/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.005332 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 22:08:35.608297) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 22:08:35.608618) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 22:08:35.608955) ========= ========= Total master_lag: 0.30 =========