builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0362 starttime: 1476855756.32 results: success (0) buildid: 20161018195923 builduid: 3526de9da3f441f2bf1608e295abe08f revision: f52b380bc02e1022631ab8dcd43ed246930974c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:36.317544) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:36.317991) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:36.318267) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:36.342260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:36.342642) ========= 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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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-10-18 22:42:36-- 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% 164M=0s 2016-10-18 22:42:36 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138246 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:36.502977) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:36.503291) ========= 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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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.028257 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:36.552957) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 22:42:36.553305) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f52b380bc02e1022631ab8dcd43ed246930974c3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f52b380bc02e1022631ab8dcd43ed246930974c3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l7XjBvMhXk/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.mP5fGwmgqB/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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-10-18 22:42:36,639 truncating revision to first 12 chars 2016-10-18 22:42:36,639 Setting DEBUG logging. 2016-10-18 22:42:36,640 attempt 1/10 2016-10-18 22:42:36,640 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f52b380bc02e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 22:42:37,431 unpacking tar archive at: fx-team-f52b380bc02e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.059885 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 22:42:37.635222) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:37.635600) ========= script_repo_revision: f52b380bc02e1022631ab8dcd43ed246930974c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:37.636017) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:37.636306) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 22:42:37.654153) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-18 22:42:37.654518) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l7XjBvMhXk/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.mP5fGwmgqB/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:42:37 INFO - MultiFileLogger online at 20161018 22:42:37 in /builds/slave/test 22:42:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:42:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:42:37 INFO - {'append_to_log': False, 22:42:37 INFO - 'base_work_dir': '/builds/slave/test', 22:42:37 INFO - 'blob_upload_branch': 'Fx-Team', 22:42:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:42:37 INFO - 'branch': 'Fx-Team', 22:42:37 INFO - 'buildbot_json_path': 'buildprops.json', 22:42:37 INFO - 'config_files': ('talos/mac_config.py',), 22:42:37 INFO - 'default_actions': ('clobber', 22:42:37 INFO - 'read-buildbot-config', 22:42:37 INFO - 'download-and-extract', 22:42:37 INFO - 'populate-webroot', 22:42:37 INFO - 'create-virtualenv', 22:42:37 INFO - 'install', 22:42:37 INFO - 'run-tests'), 22:42:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:42:37 INFO - 'download_minidump_stackwalk': True, 22:42:37 INFO - 'download_symbols': 'ondemand', 22:42:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:42:37 INFO - 'tooltool.py': '/tools/tooltool.py', 22:42:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:42:37 INFO - '/tools/misc-python/virtualenv.py')}, 22:42:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:42:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:42:37 INFO - 'installer_path': 'installer.exe', 22:42:37 INFO - 'log_level': 'info', 22:42:37 INFO - 'log_name': 'talos', 22:42:37 INFO - 'log_to_console': True, 22:42:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:42:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:42:37 INFO - 'opt_config_files': (), 22:42:37 INFO - 'pip_index': False, 22:42:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:42:37 INFO - 'cmd': ('bash', 22:42:37 INFO - '-c', 22:42:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:42:37 INFO - 'enabled': True, 22:42:37 INFO - 'halt_on_failure': False, 22:42:37 INFO - 'name': 'check_screen_resolution'},), 22:42:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:42:37 INFO - 'cmd': ('bash', 22:42:37 INFO - '-c', 22:42:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:42:37 INFO - 'enabled': True, 22:42:37 INFO - 'halt_on_failure': False, 22:42:37 INFO - 'name': 'check_screen_resolution'},), 22:42:37 INFO - 'run_cmd_checks_enabled': True, 22:42:37 INFO - 'sps_profile': False, 22:42:37 INFO - 'sps_profile_interval': 0, 22:42:37 INFO - 'suite': 'dromaeojs', 22:42:37 INFO - 'system_bits': '32', 22:42:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:42:37 INFO - 'title': 't-yosemite-r7-0362', 22:42:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:42:37 INFO - 'use_talos_json': True, 22:42:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:42:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:42:37 INFO - 'work_dir': 'build'} 22:42:37 INFO - [mozharness: 2016-10-19 05:42:37.862490Z] Running clobber step. 22:42:37 INFO - Running pre-action listener: _resource_record_pre_action 22:42:37 INFO - Running main action method: clobber 22:42:37 INFO - rmtree: /builds/slave/test/build 22:42:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:42:40 INFO - Running post-action listener: _resource_record_post_action 22:42:40 INFO - [mozharness: 2016-10-19 05:42:40.023700Z] Finished clobber step (success) 22:42:40 INFO - [mozharness: 2016-10-19 05:42:40.023824Z] Running read-buildbot-config step. 22:42:40 INFO - Running pre-action listener: _resource_record_pre_action 22:42:40 INFO - Running main action method: read_buildbot_config 22:42:40 INFO - Using buildbot properties: 22:42:40 INFO - { 22:42:40 INFO - "project": "", 22:42:40 INFO - "product": "firefox", 22:42:40 INFO - "script_repo_revision": "production", 22:42:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:42:40 INFO - "repository": "", 22:42:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:42:40 INFO - "buildid": "20161018195923", 22:42:40 INFO - "pgo_build": "False", 22:42:40 INFO - "basedir": "/builds/slave/test", 22:42:40 INFO - "buildnumber": 10, 22:42:40 INFO - "slavename": "t-yosemite-r7-0362", 22:42:40 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 22:42:40 INFO - "platform": "yosemite_r7", 22:42:40 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:42:40 INFO - "branch": "fx-team", 22:42:40 INFO - "repo_path": "integration/fx-team", 22:42:40 INFO - "revision": "f52b380bc02e1022631ab8dcd43ed246930974c3", 22:42:40 INFO - "stage_platform": "macosx64", 22:42:40 INFO - "builduid": "3526de9da3f441f2bf1608e295abe08f", 22:42:40 INFO - "slavebuilddir": "test" 22:42:40 INFO - } 22:42:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:42:40 INFO - Running post-action listener: _resource_record_post_action 22:42:40 INFO - [mozharness: 2016-10-19 05:42:40.027031Z] Finished read-buildbot-config step (success) 22:42:40 INFO - [mozharness: 2016-10-19 05:42:40.027155Z] Running download-and-extract step. 22:42:40 INFO - Running pre-action listener: _resource_record_pre_action 22:42:40 INFO - Running main action method: download_and_extract 22:42:40 INFO - mkdir: /builds/slave/test/build/tests 22:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:42:40 INFO - trying https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:42:40 INFO - Downloading https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:42:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 22:42:40 INFO - Downloaded 1199 bytes. 22:42:40 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:42:40 INFO - Using the following test package requirements: 22:42:40 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:42:40 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:42:40 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:42:40 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'jsshell-mac.zip'], 22:42:40 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:42:40 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:42:40 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:42:40 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:42:40 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:42:40 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:42:40 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:42:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:42:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:42:40 INFO - Fetch https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:42:41 INFO - Http code: 200 22:42:41 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 22:42:41 INFO - x-amz-cf-id: Jie9lH0GSbGRYH9oulnWYyjw3Swux8G9zjx0giyXx1lK-_4LStqn3w== 22:42:41 INFO - x-amz-version-id: XHurmWQKcwP2D0ZVKCXFcZ3uEetYXPD3 22:42:41 INFO - Expected file size: 16500000 22:42:41 INFO - Obtained file size: 16500000 22:42:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:42:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:42:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:42:48 INFO - Fetch https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:42:48 INFO - Http code: 200 22:42:48 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 22:42:48 INFO - x-amz-cf-id: ZP_pp_peFCA33UydS4dcwrfuMtNTFkJ3_faWsMEv0t9t-JvkWEu3Zw== 22:42:48 INFO - x-amz-version-id: XHurmWQKcwP2D0ZVKCXFcZ3uEetYXPD3 22:42:49 INFO - Expected file size: 16500000 22:42:49 INFO - Obtained file size: 16500000 22:42:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:42:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:42:55 INFO - Fetch https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:42:56 INFO - Http code: 200 22:42:56 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 22:42:56 INFO - x-amz-cf-id: wu-Ny5RE63OZl_UMQ_HYenUUDShls4r76y2gosb4QksSweC_Hf7O5Q== 22:42:56 INFO - x-amz-version-id: V3G5TxVXEKPvoF7zv8g2uxR_Xq1IlOlP 22:42:57 INFO - Expected file size: 14409028 22:42:57 INFO - Obtained file size: 14409028 22:42:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:42:57 INFO - trying https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:42:57 INFO - Downloading https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:42:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 22:43:00 INFO - Downloaded 111645606 bytes. 22:43:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:43:00 INFO - mkdir: /builds/slave/test/properties 22:43:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:43:00 INFO - Writing to file /builds/slave/test/properties/build_url 22:43:00 INFO - Contents: 22:43:00 INFO - build_url:https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:43:01 INFO - Running post-action listener: _resource_record_post_action 22:43:01 INFO - Running post-action listener: set_extra_try_arguments 22:43:01 INFO - [mozharness: 2016-10-19 05:43:01.007232Z] Finished download-and-extract step (success) 22:43:01 INFO - [mozharness: 2016-10-19 05:43:01.007340Z] Running populate-webroot step. 22:43:01 INFO - Running pre-action listener: _resource_record_pre_action 22:43:01 INFO - Running main action method: populate_webroot 22:43:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:43:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:43:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:43:01 INFO - '--tppagecycles', 22:43:01 INFO - '7'], 22:43:01 INFO - 'tests': ['tsvgm']}}, 22:43:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:43:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:43:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:43:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:43:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:01 INFO - 'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:43:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:43:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:01 INFO - 'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['damp', 'tps']}, 22:43:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:01 INFO - 'tests': ['damp', 'tps']}, 22:43:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['dromaeo_dom']}, 22:43:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:43:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['basic_compositor_video']}, 22:43:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:43:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['a11yr', 22:43:01 INFO - 'ts_paint', 22:43:01 INFO - 'tpaint', 22:43:01 INFO - 'sessionrestore', 22:43:01 INFO - 'sessionrestore_no_auto_restore', 22:43:01 INFO - 'tabpaint']}, 22:43:01 INFO - 'other-e10s': {'tests': ['a11yr', 22:43:01 INFO - 'ts_paint', 22:43:01 INFO - 'tpaint', 22:43:01 INFO - 'sessionrestore', 22:43:01 INFO - 'sessionrestore_no_auto_restore', 22:43:01 INFO - 'tabpaint']}, 22:43:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:43:01 INFO - 'ts_paint', 22:43:01 INFO - 'tpaint', 22:43:01 INFO - 'sessionrestore', 22:43:01 INFO - 'sessionrestore_no_auto_restore', 22:43:01 INFO - 'tabpaint']}, 22:43:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:43:01 INFO - 'ts_paint', 22:43:01 INFO - 'tpaint', 22:43:01 INFO - 'sessionrestore', 22:43:01 INFO - 'sessionrestore_no_auto_restore', 22:43:01 INFO - 'tabpaint']}, 22:43:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['a11yr', 22:43:01 INFO - 'ts_paint', 22:43:01 INFO - 'tpaint', 22:43:01 INFO - 'sessionrestore', 22:43:01 INFO - 'sessionrestore_no_auto_restore', 22:43:01 INFO - 'tabpaint']}, 22:43:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['a11yr', 22:43:01 INFO - 'ts_paint', 22:43:01 INFO - 'tpaint', 22:43:01 INFO - 'sessionrestore', 22:43:01 INFO - 'sessionrestore_no_auto_restore', 22:43:01 INFO - 'tabpaint']}, 22:43:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['tsvgx', 22:43:01 INFO - 'tsvgr_opacity', 22:43:01 INFO - 'tart', 22:43:01 INFO - 'tscrollx', 22:43:01 INFO - 'cart']}, 22:43:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:43:01 INFO - 'tsvgr_opacity', 22:43:01 INFO - 'tart', 22:43:01 INFO - 'tscrollx', 22:43:01 INFO - 'cart']}, 22:43:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:01 INFO - 'talos_options': ['--disable-e10s'], 22:43:01 INFO - 'tests': ['tp5o']}, 22:43:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:01 INFO - 'tests': ['tp5o']}, 22:43:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:01 INFO - 'talos_options': ['--disable-e10s', 22:43:01 INFO - '--xperf_path', 22:43:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:43:01 INFO - 'tests': ['tp5n']}, 22:43:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:01 INFO - 'talos_options': ['--xperf_path', 22:43:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:43:01 INFO - 'tests': ['tp5n']}}, 22:43:01 INFO - 'talos.zip': {'path': '', 22:43:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:43:01 INFO - Running post-action listener: _resource_record_post_action 22:43:01 INFO - [mozharness: 2016-10-19 05:43:01.025837Z] Finished populate-webroot step (success) 22:43:01 INFO - [mozharness: 2016-10-19 05:43:01.025948Z] Running create-virtualenv step. 22:43:01 INFO - Running pre-action listener: _resource_record_pre_action 22:43:01 INFO - Running main action method: create_virtualenv 22:43:01 INFO - Creating virtualenv /builds/slave/test/build/venv 22:43:01 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 22:43:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:43:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:43:01 INFO - Using real prefix '/tools/python27' 22:43:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:43:01 INFO - Installing distribute.............................................................................................................................................................................................done. 22:43:04 INFO - Installing pip.................done. 22:43:04 INFO - Return code: 0 22:43:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:43:05 INFO - Reading from file tmpfile_stdout 22:43:05 INFO - Output received: 22:43:05 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) 22:43:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:43:05 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')]} 22:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:05 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')]} 22:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:05 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 0x11069f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110615570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc94b5c0aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, '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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:43:05 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 22:43:05 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 22:43:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:43:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:05 INFO - 'HOME': '/Users/cltbld', 22:43:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:05 INFO - 'LOGNAME': 'cltbld', 22:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:05 INFO - 'MOZ_NO_REMOTE': '1', 22:43:05 INFO - 'NO_EM_RESTART': '1', 22:43:05 INFO - 'PAGER': '/bin/cat', 22:43:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:05 INFO - 'PWD': '/builds/slave/test', 22:43:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:05 INFO - 'SHELL': '/bin/bash', 22:43:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:43:05 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:43:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:05 INFO - 'USER': 'cltbld', 22:43:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:05 INFO - 'XPC_FLAGS': '0x0', 22:43:05 INFO - 'XPC_SERVICE_NAME': '0', 22:43:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:05 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 22:43:05 INFO - Cleaning up... 22:43:05 INFO - Return code: 0 22:43:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:43:05 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')]} 22:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:05 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')]} 22:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:05 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 0x11069f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110615570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc94b5c0aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, '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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:43:05 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 22:43:05 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 22:43:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:43:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:05 INFO - 'HOME': '/Users/cltbld', 22:43:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:05 INFO - 'LOGNAME': 'cltbld', 22:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:05 INFO - 'MOZ_NO_REMOTE': '1', 22:43:05 INFO - 'NO_EM_RESTART': '1', 22:43:05 INFO - 'PAGER': '/bin/cat', 22:43:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:05 INFO - 'PWD': '/builds/slave/test', 22:43:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:05 INFO - 'SHELL': '/bin/bash', 22:43:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:43:05 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:43:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:05 INFO - 'USER': 'cltbld', 22:43:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:05 INFO - 'XPC_FLAGS': '0x0', 22:43:05 INFO - 'XPC_SERVICE_NAME': '0', 22:43:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:05 INFO - Downloading/unpacking psutil>=3.1.1 22:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:43:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:43:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:43:09 INFO - Installing collected packages: psutil 22:43:09 INFO - Running setup.py install for psutil 22:43:09 INFO - building 'psutil._psutil_osx' extension 22:43:09 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 22:43:09 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 22:43:09 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 22:43:09 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 22:43:09 INFO - building 'psutil._psutil_posix' extension 22:43:09 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 22:43:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:43:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:43:10 INFO - ^ 22:43:10 INFO - 1 warning generated. 22:43:10 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 22:43:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:43:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:43:10 INFO - Successfully installed psutil 22:43:10 INFO - Cleaning up... 22:43:10 INFO - Return code: 0 22:43:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:43: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')]} 22:43:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43: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')]} 22:43:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11069f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110615570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc94b5c0aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, '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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:43: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:43: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 mozsystemmonitor==0.3 22:43:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:43:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:10 INFO - 'HOME': '/Users/cltbld', 22:43:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:10 INFO - 'LOGNAME': 'cltbld', 22:43:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:10 INFO - 'MOZ_NO_REMOTE': '1', 22:43:10 INFO - 'NO_EM_RESTART': '1', 22:43:10 INFO - 'PAGER': '/bin/cat', 22:43:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:10 INFO - 'PWD': '/builds/slave/test', 22:43:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:10 INFO - 'SHELL': '/bin/bash', 22:43:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:43:10 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:43:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:10 INFO - 'USER': 'cltbld', 22:43:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:10 INFO - 'XPC_FLAGS': '0x0', 22:43:10 INFO - 'XPC_SERVICE_NAME': '0', 22:43:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:43:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:43:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:43:13 INFO - Installing collected packages: mozsystemmonitor 22:43:13 INFO - Running setup.py install for mozsystemmonitor 22:43:13 INFO - Successfully installed mozsystemmonitor 22:43:13 INFO - Cleaning up... 22:43:13 INFO - Return code: 0 22:43:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:13 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 0x11069f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110615570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc94b5c0aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, '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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:43:13 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 22:43:13 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 22:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:13 INFO - 'HOME': '/Users/cltbld', 22:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:13 INFO - 'LOGNAME': 'cltbld', 22:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:13 INFO - 'MOZ_NO_REMOTE': '1', 22:43:13 INFO - 'NO_EM_RESTART': '1', 22:43:13 INFO - 'PAGER': '/bin/cat', 22:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:13 INFO - 'PWD': '/builds/slave/test', 22:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:13 INFO - 'SHELL': '/bin/bash', 22:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:43:13 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:13 INFO - 'USER': 'cltbld', 22:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:13 INFO - 'XPC_FLAGS': '0x0', 22:43:13 INFO - 'XPC_SERVICE_NAME': '0', 22:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:13 INFO - Downloading/unpacking jsonschema==2.5.1 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:43:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:43:16 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 22:43:16 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 22:43:16 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 22:43:16 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 22:43:16 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 22:43:16 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 22:43:16 INFO - Downloading functools32-3.2.3-2.tar.gz 22:43:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:43:16 INFO - warning: no files found matching '*.txt' 22:43:16 INFO - no previously-included directories found matching 'build' 22:43:16 INFO - no previously-included directories found matching 'dist' 22:43:16 INFO - no previously-included directories found matching '.git*' 22:43:16 INFO - Installing collected packages: jsonschema, functools32 22:43:16 INFO - Running setup.py install for functools32 22:43:16 INFO - warning: no files found matching '*.txt' 22:43:16 INFO - no previously-included directories found matching 'build' 22:43:16 INFO - no previously-included directories found matching 'dist' 22:43:16 INFO - no previously-included directories found matching '.git*' 22:43:16 INFO - Successfully installed jsonschema functools32 22:43:16 INFO - Cleaning up... 22:43:16 INFO - Return code: 0 22:43:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:43:16 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')]} 22:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:16 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')]} 22:43:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:16 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 0x11069f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110615570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc94b5c0aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, '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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:43:16 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 22:43:16 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 22:43:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:43:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:16 INFO - 'HOME': '/Users/cltbld', 22:43:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:16 INFO - 'LOGNAME': 'cltbld', 22:43:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:16 INFO - 'MOZ_NO_REMOTE': '1', 22:43:16 INFO - 'NO_EM_RESTART': '1', 22:43:16 INFO - 'PAGER': '/bin/cat', 22:43:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:16 INFO - 'PWD': '/builds/slave/test', 22:43:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:16 INFO - 'SHELL': '/bin/bash', 22:43:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:43:16 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:43:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:16 INFO - 'USER': 'cltbld', 22:43:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:16 INFO - 'XPC_FLAGS': '0x0', 22:43:16 INFO - 'XPC_SERVICE_NAME': '0', 22:43:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:43:17 INFO - Cleaning up... 22:43:17 INFO - Return code: 0 22:43:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:17 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 0x11069f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110615570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc94b5c0aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, '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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:43:17 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 22:43:17 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 22:43:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:43:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:17 INFO - 'HOME': '/Users/cltbld', 22:43:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:17 INFO - 'LOGNAME': 'cltbld', 22:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:17 INFO - 'MOZ_NO_REMOTE': '1', 22:43:17 INFO - 'NO_EM_RESTART': '1', 22:43:17 INFO - 'PAGER': '/bin/cat', 22:43:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:17 INFO - 'PWD': '/builds/slave/test', 22:43:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:17 INFO - 'SHELL': '/bin/bash', 22:43:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:43:17 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:43:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:17 INFO - 'USER': 'cltbld', 22:43:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:17 INFO - 'XPC_FLAGS': '0x0', 22:43:17 INFO - 'XPC_SERVICE_NAME': '0', 22:43:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:17 INFO - Downloading/unpacking blobuploader==1.2.4 22:43:17 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 22:43:17 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 22:43:17 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 22:43:17 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 22:43:17 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 22:43:17 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 22:43:19 INFO - Downloading blobuploader-1.2.4.tar.gz 22:43:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:43:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:43:20 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 22:43:20 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 22:43:20 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 22:43:20 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 22:43:20 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 22:43:20 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 22:43:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:43:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:43:20 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 22:43:20 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 22:43:20 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 22:43:20 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 22:43:20 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 22:43:20 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 22:43:21 INFO - Downloading docopt-0.6.1.tar.gz 22:43:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:43:21 INFO - Installing collected packages: blobuploader, requests, docopt 22:43:21 INFO - Running setup.py install for blobuploader 22:43:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:43:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:43:21 INFO - Running setup.py install for requests 22:43:21 INFO - Running setup.py install for docopt 22:43:21 INFO - Successfully installed blobuploader requests docopt 22:43:21 INFO - Cleaning up... 22:43:22 INFO - Return code: 0 22:43:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:43:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:22 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 0x11069f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110615570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc94b5c0aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, '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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:43:22 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 22:43:22 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 22:43:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:43:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:22 INFO - 'HOME': '/Users/cltbld', 22:43:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:22 INFO - 'LOGNAME': 'cltbld', 22:43:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:22 INFO - 'MOZ_NO_REMOTE': '1', 22:43:22 INFO - 'NO_EM_RESTART': '1', 22:43:22 INFO - 'PAGER': '/bin/cat', 22:43:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:22 INFO - 'PWD': '/builds/slave/test', 22:43:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:22 INFO - 'SHELL': '/bin/bash', 22:43:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:43:22 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:43:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:22 INFO - 'USER': 'cltbld', 22:43:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:22 INFO - 'XPC_FLAGS': '0x0', 22:43:22 INFO - 'XPC_SERVICE_NAME': '0', 22:43:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:43:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-oNHJ46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:43:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-lxS8OP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:43:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-8wDi8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:43:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-qjgM2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:43:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-tfesCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:43:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-xcLcOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:43:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-aQWAvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:43:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-emcwd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:43:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-deJsia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:43:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-_M_ltH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:43:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-CTCbND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:43:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-XAvQgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:43:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-cWWtZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:43:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-RlGH_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-bVVcAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:43:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-92HvBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:43:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-FNuezT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:43:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:43:24 INFO - Running setup.py install for manifestparser 22:43:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:43:24 INFO - Running setup.py install for mozcrash 22:43:24 INFO - Running setup.py install for mozdebug 22:43:24 INFO - Running setup.py install for mozdevice 22:43:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:43:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:43:24 INFO - Running setup.py install for mozfile 22:43:24 INFO - Running setup.py install for mozhttpd 22:43:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:43:24 INFO - Running setup.py install for mozinfo 22:43:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:43:25 INFO - Running setup.py install for mozInstall 22:43:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:43:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:43:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:43:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:43:25 INFO - Running setup.py install for mozleak 22:43:25 INFO - Running setup.py install for mozlog 22:43:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:43:25 INFO - Running setup.py install for moznetwork 22:43:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:43:25 INFO - Running setup.py install for mozprocess 22:43:25 INFO - Running setup.py install for mozprofile 22:43:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:43:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:43:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:43:26 INFO - Running setup.py install for mozrunner 22:43:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:43:26 INFO - Running setup.py install for mozscreenshot 22:43:26 INFO - Running setup.py install for moztest 22:43:26 INFO - Running setup.py install for mozversion 22:43:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:43:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:43:26 INFO - Cleaning up... 22:43:26 INFO - Return code: 0 22:43:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:43:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:27 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 0x11069f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110615570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc94b5c0aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, '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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:43:27 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 22:43:27 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 22:43:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:43:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:27 INFO - 'HOME': '/Users/cltbld', 22:43:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:27 INFO - 'LOGNAME': 'cltbld', 22:43:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:27 INFO - 'MOZ_NO_REMOTE': '1', 22:43:27 INFO - 'NO_EM_RESTART': '1', 22:43:27 INFO - 'PAGER': '/bin/cat', 22:43:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:27 INFO - 'PWD': '/builds/slave/test', 22:43:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:27 INFO - 'SHELL': '/bin/bash', 22:43:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:43:27 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:43:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:27 INFO - 'USER': 'cltbld', 22:43:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:27 INFO - 'XPC_FLAGS': '0x0', 22:43:27 INFO - 'XPC_SERVICE_NAME': '0', 22:43:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:43:27 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-JHFjNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:43:27 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)) 22:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:43:27 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-8eUc0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:43:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:43:27 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-2Yvqrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:43:28 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)) 22:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:43:28 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-GalZAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:43:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:43:28 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-RK8ko7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:43:28 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)) 22:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:43:28 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-ijC4wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:43:28 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)) 22:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:43:28 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-dOY99w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:43:28 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)) 22:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:43:28 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-IVHPj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:43:28 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)) 22:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:43:28 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-LAcPEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:43:28 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)) 22:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:43:28 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-2TlHh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:43:28 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)) 22:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:43:28 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-1Bp5tl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:43:28 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)) 22:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:43:28 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-3KBMc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:43:29 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)) 22:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:43:29 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-tmWxvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:43:29 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)) 22:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:43:29 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-nzLSP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:29 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-4yicX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:29 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)) 22:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:43:29 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-IcLwp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:43:29 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)) 22:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:43:29 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-pkYr6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:43:29 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)) 22:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:43:29 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)) 22:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:43:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:43:29 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 22:43:29 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 22:43:29 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 22:43:29 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 22:43:29 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 22:43:29 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 22:43:32 INFO - Downloading blessings-1.6.tar.gz 22:43:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:43:32 INFO - Installing collected packages: blessings 22:43:32 INFO - Running setup.py install for blessings 22:43:32 INFO - Successfully installed blessings 22:43:32 INFO - Cleaning up... 22:43:32 INFO - Return code: 0 22:43:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:43:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:43:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:43:33 INFO - Reading from file tmpfile_stdout 22:43:33 INFO - Current package versions: 22:43:33 INFO - blessings == 1.6 22:43:33 INFO - blobuploader == 1.2.4 22:43:33 INFO - docopt == 0.6.1 22:43:33 INFO - functools32 == 3.2.3-2 22:43:33 INFO - jsonschema == 2.5.1 22:43:33 INFO - manifestparser == 1.1 22:43:33 INFO - mozInstall == 1.12 22:43:33 INFO - mozcrash == 0.17 22:43:33 INFO - mozdebug == 0.1 22:43:33 INFO - mozdevice == 0.48 22:43:33 INFO - mozfile == 1.2 22:43:33 INFO - mozhttpd == 0.7 22:43:33 INFO - mozinfo == 0.9 22:43:33 INFO - mozleak == 0.1 22:43:33 INFO - mozlog == 3.4 22:43:33 INFO - moznetwork == 0.27 22:43:33 INFO - mozprocess == 0.23 22:43:33 INFO - mozprofile == 0.28 22:43:33 INFO - mozrunner == 6.12 22:43:33 INFO - mozscreenshot == 0.1 22:43:33 INFO - mozsystemmonitor == 0.3 22:43:33 INFO - moztest == 0.7 22:43:33 INFO - mozversion == 1.4 22:43:33 INFO - psutil == 3.1.1 22:43:33 INFO - requests == 1.2.3 22:43:33 INFO - wsgiref == 0.1.2 22:43:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:43:33 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')]} 22:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:33 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')]} 22:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:33 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 0x11069f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110615570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc94b5c0aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, '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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:43:33 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 22:43:33 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 22:43:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:43:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:33 INFO - 'HOME': '/Users/cltbld', 22:43:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:33 INFO - 'LOGNAME': 'cltbld', 22:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:33 INFO - 'MOZ_NO_REMOTE': '1', 22:43:33 INFO - 'NO_EM_RESTART': '1', 22:43:33 INFO - 'PAGER': '/bin/cat', 22:43:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:33 INFO - 'PWD': '/builds/slave/test', 22:43:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:33 INFO - 'SHELL': '/bin/bash', 22:43:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:43:33 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:43:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:33 INFO - 'USER': 'cltbld', 22:43:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:33 INFO - 'XPC_FLAGS': '0x0', 22:43:33 INFO - 'XPC_SERVICE_NAME': '0', 22:43:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:33 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)) 22:43:33 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)) 22:43:33 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)) 22:43:33 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)) 22:43:33 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)) 22:43:33 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)) 22:43:33 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)) 22:43:33 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)) 22:43:33 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)) 22:43:33 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)) 22:43:33 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)) 22:43:33 INFO - Cleaning up... 22:43:33 INFO - Return code: 0 22:43:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:43:33 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')]} 22:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:33 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')]} 22:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:33 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 0x11069f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110615570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc94b5c0aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, '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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 22:43:33 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 22:43:33 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 22:43:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:43:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:33 INFO - 'HOME': '/Users/cltbld', 22:43:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:33 INFO - 'LOGNAME': 'cltbld', 22:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:33 INFO - 'MOZ_NO_REMOTE': '1', 22:43:33 INFO - 'NO_EM_RESTART': '1', 22:43:33 INFO - 'PAGER': '/bin/cat', 22:43:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:33 INFO - 'PWD': '/builds/slave/test', 22:43:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:33 INFO - 'SHELL': '/bin/bash', 22:43:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:43:33 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:43:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:33 INFO - 'USER': 'cltbld', 22:43:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:33 INFO - 'XPC_FLAGS': '0x0', 22:43:33 INFO - 'XPC_SERVICE_NAME': '0', 22:43:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:43:33 INFO - Cleaning up... 22:43:33 INFO - Return code: 0 22:43:33 INFO - Running post-action listener: _resource_record_post_action 22:43:33 INFO - Running post-action listener: _start_resource_monitoring 22:43:33 INFO - Starting resource monitoring. 22:43:33 INFO - [mozharness: 2016-10-19 05:43:33.610253Z] Finished create-virtualenv step (success) 22:43:33 INFO - [mozharness: 2016-10-19 05:43:33.611274Z] Running install step. 22:43:33 INFO - Running pre-action listener: _resource_record_pre_action 22:43:33 INFO - Running main action method: install 22:43:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:43:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:43:33 INFO - Reading from file tmpfile_stdout 22:43:33 INFO - Detecting whether we're running mozinstall >=1.0... 22:43:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:43:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:43:33 INFO - Reading from file tmpfile_stdout 22:43:33 INFO - Output received: 22:43:33 INFO - Usage: mozinstall [options] installer 22:43:33 INFO - Options: 22:43:33 INFO - -h, --help show this help message and exit 22:43:33 INFO - -d DEST, --destination=DEST 22:43:33 INFO - Directory to install application into. [default: 22:43:33 INFO - "/builds/slave/test"] 22:43:33 INFO - --app=APP Application being installed. [default: firefox] 22:43:33 INFO - mkdir: /builds/slave/test/build/application 22:43:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:43:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:44:08 INFO - Reading from file tmpfile_stdout 22:44:08 INFO - Output received: 22:44:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:44:08 INFO - Running post-action listener: _resource_record_post_action 22:44:08 INFO - [mozharness: 2016-10-19 05:44:08.183636Z] Finished install step (success) 22:44:08 INFO - [mozharness: 2016-10-19 05:44:08.183773Z] Running run-tests step. 22:44:08 INFO - Running pre-action listener: _resource_record_pre_action 22:44:08 INFO - Running main action method: run_tests 22:44:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:44:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:44:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:44:08 INFO - 2016-10-18 22:44:08.198 screenresolution[1654:7911] starting screenresolution argv=screenresolution get 22:44:08 INFO - 2016-10-18 22:44:08.201 screenresolution[1654:7911] Display 0: 1600x1200x32@60 22:44:08 INFO - 2016-10-18 22:44:08.207 screenresolution[1655:7913] starting screenresolution argv=screenresolution list 22:44:08 INFO - Available Modes on Display 0 22:44:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:44:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:44:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:44:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:44:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:44:08 INFO - Intel Iris: 22:44:08 INFO - Chipset Model: Intel Iris 22:44:08 INFO - Type: GPU 22:44:08 INFO - Bus: Built-In 22:44:08 INFO - VRAM (Dynamic, Max): 1536 MB 22:44:08 INFO - Vendor: Intel (0x8086) 22:44:08 INFO - Device ID: 0x0a2e 22:44:08 INFO - Revision ID: 0x0009 22:44:08 INFO - Displays: 22:44:08 INFO - Display: 22:44:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:44:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:44:08 INFO - Display Serial Number: 1600x1200 60 22:44:08 INFO - Main Display: Yes 22:44:08 INFO - Mirror: Off 22:44:08 INFO - Online: Yes 22:44:08 INFO - Rotation: Supported 22:44:08 INFO - Return code: 0 22:44:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:44:08 INFO - Python 2.7.3 22:44:08 INFO - Return code: 0 22:44:08 INFO - grabbing minidump binary from tooltool 22:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:08 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 0x1104b3d30>, '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 0x1102d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104abb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:44:08 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 22:44:08 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 22:44:08 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 22:44:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:44:08 INFO - Return code: 0 22:44:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:44:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:44:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:44:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:44:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:44:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0362', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0362 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:44:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l7XjBvMhXk/Render', 22:44:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:08 INFO - 'HOME': '/Users/cltbld', 22:44:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:08 INFO - 'LOGNAME': 'cltbld', 22:44:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:44:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:08 INFO - 'MOZ_NO_REMOTE': '1', 22:44:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:44:08 INFO - 'NO_EM_RESTART': '1', 22:44:08 INFO - 'PAGER': '/bin/cat', 22:44:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:08 INFO - 'PWD': '/builds/slave/test', 22:44:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:44:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:08 INFO - 'SHELL': '/bin/bash', 22:44:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mP5fGwmgqB/Listeners', 22:44:08 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:44:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:08 INFO - 'USER': 'cltbld', 22:44:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:08 INFO - 'XPC_FLAGS': '0x0', 22:44:08 INFO - 'XPC_SERVICE_NAME': '0', 22:44:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0362', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:44:08 INFO - mozversion application_buildid: 20161018195923 22:44:08 INFO - mozversion application_changeset: f52b380bc02e1022631ab8dcd43ed246930974c3 22:44:08 INFO - mozversion application_display_name: Nightly 22:44:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:44:08 INFO - mozversion application_name: Firefox 22:44:08 INFO - mozversion application_remotingname: firefox 22:44:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:44:08 INFO - mozversion application_vendor: Mozilla 22:44:08 INFO - mozversion application_version: 52.0a1 22:44:08 INFO - mozversion platform_buildid: 20161018195923 22:44:08 INFO - mozversion platform_changeset: f52b380bc02e1022631ab8dcd43ed246930974c3 22:44:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:44:08 INFO - mozversion platform_version: 52.0a1 22:44:08 INFO - using testdate: 1476855848 22:44:08 INFO - actual date: 1476855848 22:44:08 INFO - starting webserver on 'localhost:49272' 22:44:08 INFO - SUITE-START | Running 2 tests 22:44:08 INFO - TEST-START | dromaeo_css 22:44:08 INFO - operating with platform_type : mac_ 22:44:08 INFO - Initialising browser for dromaeo_css test... 22:44:08 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpTvSfFq/profile http://localhost:49272/getInfo.html) 22:44:11 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 22:44:11 INFO - PROCESS | 1665 | colorDepth:24 22:44:11 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 22:44:11 INFO - PROCESS | 1665 | __metrics 22:44:11 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:44:11 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:44:12 INFO - TEST-INFO | 1665: exit 0 22:44:12 INFO - Browser initialized. 22:44:12 INFO - Running cycle 1/1 for dromaeo_css test... 22:44:12 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpTvSfFq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:44:24 INFO - PROCESS | 1667 | RSS: Main: 231882752 22:44:24 INFO - PROCESS | 1667 | 22:45:15 INFO - PROCESS | 1667 | 1476855915274 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] 22:47:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 22:47:30 INFO - PROCESS | 1667 | RSS: Main: 421396480 22:47:30 INFO - PROCESS | 1667 | 22:50:33 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 22:50:34 INFO - PROCESS | 1667 | RSS: Main: 372219904 22:50:34 INFO - PROCESS | 1667 | 22:53:36 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 22:53:37 INFO - PROCESS | 1667 | RSS: Main: 361623552 22:53:37 INFO - PROCESS | 1667 | 22:56:39 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 22:56:40 INFO - PROCESS | 1667 | RSS: Main: 336920576 22:56:40 INFO - PROCESS | 1667 | 22:59:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 22:59:44 INFO - PROCESS | 1667 | RSS: Main: 328159232 22:59:44 INFO - PROCESS | 1667 | 23:02:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 23:02:47 INFO - PROCESS | 1667 | RSS: Main: 479412224 23:02:47 INFO - PROCESS | 1667 | 23:02:47 INFO - PROCESS | 1667 | __start_tp_report 23:02:47 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 23:02:47 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 23:02:47 INFO - PROCESS | 1667 | |i|pagename|runs| 23:02:47 INFO - PROCESS | 1667 | |0;dojo.html;3342.4819153031276;3349.9162520936957;3367.680070393312;3392.236746731984;3429.4512877939533;5622.522085622727;5704.914426283609;5711.3431955325095;5745.741441635126;5767.740451679674;5689.630174038684;5691.004074286993;5779.508741756956;5797.681127537984;5811.099279611667;5092.668976516533;5148.536631703146;5183.818566350178;5224.059669259532;5226.216067589873;5285.68285902847;5315.255864178998;5323.441038690939;5335.146376579748;5382.569394448455;5269.973650131763;5297.152455607084;5307.973460132694;5332.626716129853;5374.3013408256875;5159.484051594811;5192.3249977503065;5206.505381988705;5214.556762675177;5241.187615919539;5140.8457746267295;5175.7670904809;5186.818461353872;5232.136697444917;5266.552343050846;5670.092785154397;5676.602637815355;5721.4564616361795;5725.942740572583;5739.569532285095;6090.756369745205;6113.902014424272;6134.4365870334295;6235.87528249437;6245.437910588023;6200.689965501752;6228.719707613168;6265.467435268008;6297.842553936142;6304.653244071575;5718.971405142989;5759.107338362561;5774.971125144348;5783.479486846204;5824.592278540504;5583.357913839879;5764.769409223668;6125.723854043132;6245.718942647592;6261.405166509175;5936.821895343147;5979.970100149471;5988.5508586855285;6039.818805435844;6050.394960503914;6141.723622436912;6147.538934579924;6150.661713605705;6157.168782214344;6161.507079433634;5957.3744756799915;6017.879642407215;6084.695765211722;6097.2683278006925;6139.171211886338;6113.902014424272;6122.4896596401695;6164.229471316085;6182.752689892444;6183.072539119078;4483.50681425043;4802.039592081597;4920.606351491872;4937.185364414831;4968.30443737877;4401.163778882003;4416.668749843775;4425.291953287458;4427.313766366218;4484.327350897392;5519.530839878575;5634.04221282383;5649.661020338793;5700.8859822803315;5719.628224165416;6148.585825260216;6193.071039344134;6216.191895053614;6223.190985171898;6225.28409232944;5683.658980461186;5696.174054762037;5707.771689132472;5713.2572765541045;5714.914276285859;5359.22291267764;5379.946200538023;5386.569074474032;5388.488093631099;5394.244805727201;6208.627482351073;6215;6219.937800621936;6220.7511699531515;6225.968870155621;5273.314469119144;6160.691965401622;6169.938300616936;6238.469730072813;6296;6263.123162757127;6293.622382657055;6310.49516038725;6313.463355614824;6323.9683801580695;6160.9383906160365;6169.475594574332;6171.938280617136;6175.2589689237575;6181.381861813783;6128.448439640454;6132.600268413531;6146.477549408171;6266.561340706106;6276.623402595859;6056.9697151513965;6091.939080609137;6128.233970753656;6155.938440615537;6157.784477543264;5951.196588460675;6125.632462052113;6126.448619624255;6136.509079273558;6145.201123853871;5788.0739081746715;5796.884062318814;5967.8209653710455;5978.551608629457;5985.371535988658;6012.128241405059;6049.304330002106;6064.06007068905;6106;6130.049842274455;5969.253843269591;5972.372900845348;5974.103884417198;6009;6018.909716354171 23:02:47 INFO - PROCESS | 1667 | |1;ext.html;472.61718008413175;523.5706720489198;524.8373004368646;528.5428104689443;549.87352908831;31088.689113108863;31606.841965790198;31619.20951976199;31658.841705791467;40557.797211013974;40947.59052409483;43074;43256.35115473271;43359.34960975588;43414.56585434145;9651.662191823305;9825.066618671215;9825.70522884311;9826.950865245664;9845.11393974542;8677.566121693922;8889.511076890802;9649.710508684715;9775.902240977606;9778.853317200212;8825.117488251188;8861.379703420764;8917.152870477326;8931.196192342688;11601.129915256348;11646.708832279177;11821.527138914433;11845.94077029618;11847.940760296186;11854;11628.476718547685;11684.12369072323;11695.824562631567;11700.824487632692;11702.005329546999;33075.51160197797;40274.59725402738;40727.18545629071;40803;40975.38536921948;3322.335532893431;3433.828308584561;3513.5256740339983;3560.2523470071314;4050.189962007581;29997.400051998837;30978.127734033245;31030.37939241225;31125.533117003262;31193.688063119305;3216.244182617083;3232.7090561849545;3264.9183770405693;3317.303366293057;3337.916552086193;473887;476613;478911;480656;491441.4842293146;28216.576751348744;60238.69880650657;60729.08906366408;60782.69608651928;60942.69528652328;34324.485132722526;35276.47085293723;35595;35603;35731.82134089365;14300.570982870531;14466.782998255034;14469.638259043397;14472.204028778518;14481.348339324755;11993.400329983466;12212.816807747891;12368.567100151451;12373.072019598567;12380;7856.685732570748;7856.803579910434;7909.802254943673;7923.7226697065325;7942.563159026309;3083.8458077096057;3168.081256435654;3168.287135394564;3196.7122958933805;3293.5059741039036;830.8919840420707;892.3797960417577;955.7371722776153;1062.8512008318842;1075.9892401076045;27506.862465687544;28652.570211446844;29061.85469072641;29987.550186747216;30203;997.4563862694848;1022.044388496758;1074.978500429987;1083.3824719909576;1233.7779199744132;15268.236588170546;15541.689166216613;15693.607659808597;15867.365305387886;15971.03409859536;643655;648696;649977;650053;651524;58786;59105.704471478224;59153.704231478565;59226.70386648126;59312;1331.6338007047946;1378.8896888249117;1392.0534036855036;1393.986060139406;1399.3842709207916;24151;24598.877005614857;29652.258693532833;29673.25816854596;30477.08568742941;51942;52209.47790522198;52235;52247.47752522426;52342;439328;441950;442433;442806;442926;22100.447488812908;26309.079182228532;26339.078132266044;26343.604845926944;26385.73614263833;47737;47939.520604793506;48852.755736221094;48944.510554894;48976.75511622561;33602;39167.17709687245;39395.80302098471;40415;41291.7935410321;38553.2289354217;38918.027049323995;39319.41020884632;40149.799251003555;40557;35827.74602888886;37765.62234377621;37907.78621081664;38312.42531362088;38577.80711096426;33055;33594.66405336013;34538.30923381468;34569.48145777765;34764.65235347614;23133.88433057824;24765.62851557192;25601.74398256068;25627.35931601725;26326 23:02:47 INFO - PROCESS | 1667 | |2;jquery.html;323.86883312258533;344.08130292120035;365.0381245418322;365.8317174099915;395.95050618672667;15110.924445377772;15396.307166177521;15905.204739762994;16596.917015414907;17920.64158716828;20111.39665810028;20346.796532034674;20483.87338696371;20661.690074648894;20955.266565670205;5574.191742197389;5939.613925094855;5942.465178133967;5942.732577034022;5950.970245148768;4512.842050528232;5783;6465.321141280168;6539;6620.702068406909;5656.915146272789;6316.926122559153;6540.149780528548;6556.868862622745;6559.409653131217;6419.293877673475;6447.258565265008;6479.35206479353;6488.124103246048;6509.609423434586;5329.733513324357;5518.503334699896;5596.104623260258;5603;5612.438756124396;24952.875235623887;26007.089751858784;26143.215703528924;26225.344366390993;26559.07043253477;2171.3485954213675;2207.315732123047;2220.330426346999;2299.8045166160728;2301.9884900575557;15310.540683779505;15455.381784728597;15682.294296756674;15879.52361429159;16429.67140657192;1825.4979880532826;1862.7299041639021;1899.097928483973;1923.2018712234405;1948.4641723526001;111411.44294278558;116417;119683;131571.68428315787;132070.3396483021;11103.500342484607;11224.382658953837;11628.18602697803;11638.6508404748;11832.76334473323;14568.271586420637;14589;14594.781078283835;14610.415583376758;14623.415063397557;8118.472299300526;8362.45644033136;8369.916300836914;8513.787155321043;8545;8164.918350816534;8176.918230817735;8216.753497395088;8353.874691879626;8369.707060252862;4422.270325396338;4502.144592527475;4513.661475389359;4573.47405048417;4580.763193937618;1786.5087101047316;1909.8663093583316;1922.2118931238126;1950.0737149853705;1982.7211448615851;747.986478321866;781.2343902975116;839.236294971573;944.2776276148752;968.6997030920437;17264.395746149075;20455.28406505765;21626.918654067234;21747.67378489324;21981.450463738536;919.1406035356963;935.5825923725562;978.7112801723508;983.6458874805064;1001.0790073132736;9755.219582433388;10810.027097561257;10863.044651962722;11256.774864502828;11410.942945285387;564656;571519;572460;582902;584214;99402;120560.3971980152;127687;133649.3317533406;138875;929.0755698080453;948.7011591348703;959.318883592643;960.9375390599589;962.9518524073768;18373;19179;19204.519887002938;19243.61512769765;19299.236648853337;56587.15119273132;56769.43230567641;56879.018468706796;57471.85056298934;57830.7108464455;60039.69980150071;65604;76681;84863;86522;7654;7767.9223207769455;7919.722809701863;7936.682532698627;8063.193680632125;9096.056421972677;12820.23078615286;12929.870701293243;13144.0799144059;13154.276514791396;11045.28205666629;11309.038731707567;11322.94338528302;11339.319640821577;11368.715782105513;10237.692869213935;10386.065254127165;10611.628592999208;10688;11069.944650277019;10146.543405546621;10685.145188384755;10888;11488.54045838157;11543;7641.388688904985;7888.684452621831;7920.445568810128;8042.356611471186;8284.875726864222;7853.764387068397;10174.796504070026;11058;11387.145964052563;11427 23:02:47 INFO - PROCESS | 1667 | |3;mootools.html;1533.6855944531371;1554.114154931689;1779.5640068183297;1883.1902282018743;1954.9706754398678;8338.082810890797;8860.158284962918;8863.778405539864;8878.378513504049;8930.821383572325;7752.302292793647;8008.239217274364;8020.39847011475;8451.61967711453;8673.2245681382;2043.5708501214688;2335.089315167082;2382.511585125054;2390.3785015895905;2394.6767186429784;1999.4301624037153;2347.5774360615083;2376.013954209001;2389.259329607827;2392.9760702393014;2006.9698954515693;2351.8000969917575;2364.8935797889135;2370.1349007612184;2390.964135537968;2337.625979843217;2353.5057637895893;2355.2580937004873;2365.3968238099346;2371.67982322386;2321.3732292281156;2360.5632957902844;2361.9173328933407;2366.6568347589505;2368.1237941961585;8832.55837208137;8885.422447540954;8888.377813553054;8903.55482225886;8908.599113039929;5282;5396.622236443451;5398.406175320712;5398.78404863805;5399.568034557226;3395.5076513905346;3467.861285548575;3593.928121437583;3608.657177568127;3610.5847827499647;1034.8781372193569;1060.7030031591166;1066.1470823341297;1081.2539347849959;1083.7290677330668;11587.130965177561;11719.472623731952;11720.531178752839;11728.472218750092;11753.4710938008;4063.329550624173;4488.955110448854;4553.4991150973365;4747.264174053027;4750.572448479585;9730.902690973142;9879.259055570861;9885.802283954425;9901.405915645084;9908.80182396348;5340.652857564246;5364.490373414519;5371.382291036581;5377.3816011159215;5379.327584051994;4193.7693426861815;4360.672949528798;4499.617532509707;4529.139463501924;4919.262110683426;3817.6373244541724;3823.330917089498;3899.434581985596;3942.073612701013;3992.6207010333974;1261.400834603556;1274.0635632809876;1288.5683296095726;1288.6585054960442;1294.973366681141;807.4307613132727;830.66773290683;899.9010108888017;907.26964793342;912.748994026648;5409.6213265071065;5571.9721401392735;5770.653760774367;5793.768249269982;5956.195913551702;433.25480174100494;442.4005472584666;963.8409662405764;982.513655740413;1011.9544620492095;3810.866619668354;3813.599572044951;3846.826892789831;3918.7256892017285;3919.2749341371937;10544.630937917289;10765.3540787553;10860.076893464144;10873.891261087447;10881.564737410574;10814.513346899252;10815.56737730482;10834.349939003685;10838.349699018085;10838.945805270923;7721.420893432903;8137.308328792119;8287.171282871665;8292.253697167283;8353.749387518385;5254.65844720092;5696.943030569807;5768.769249230152;5948.910766338422;5987.730552125077;8448.366372522209;9697.6120955161;9701.466419346722;9762.804743905224;10026.24803139782;9886.752831179278;10204.183665306906;10212.387256764618;10218.489075546044;10327.173826093744;6933.1333583302085;7154.320339567733;7202.063731715053;7536.0956685198125;7682.116556595839;8109.75670729879;8411.915880841114;8438.945131858518;8475.618597163018;8519.829603407774;7297.197308295977;7444.2927921849505;7530.811729706801;7648.464607477423;7740.92259077402;7227.819304517218;7370.115586129698;7395.889061664187;7414.406847452299;7587.848243035219;6947.617881016593;7110.822229444305;7122.3974605715375;7129.643517824192;7137.892931605927;4514.503404625554;4841.763188885433;4854.805807767791;4948.604111670986;4983.725895075806;4922.950770492249;4962.354893863775;4981.402231732215;5050.166722490895;5088.949110508847 23:02:47 INFO - PROCESS | 1667 | |4;prototype.html;296.42789416426314;297.14866906313415;304.2606466286923;308.3663072386247;312.2552711782401;5194.246834209042;5247.396549396821;5267.868303292415;5271;5289.709066001368;4931.605471562275;4954.603631709464;5053.646244762868;5086.821961231358;5177.223416487529;3318.7510936679723;3960.732565579016;4005.0175163294066;4024.1086689256003;4026.0136266614572;3977.125032492847;3986.9202615947665;3988.7207895447186;4002.259581977329;4028.6777057835448;3390.440577304742;3501.7548771586;3963.544192417866;3966.325724626806;3996.8601098961544;3994.3409337459284;3995.4805875236325;3999.7800120993334;4004.5394779600283;4039.4551432597423;3475.461303497962;3500.879718490084;3500.9474857877153;3506.333796578642;3515.5078289039557;4848.2727590861305;5001.8499445016705;5034.572061374787;5050.797968081272;5127.974360128175;4188.141431006637;4275.866895272755;4564.52072532386;4570.200214962369;4572.245579479382;5457.536109430703;5457.590680698924;5477.561795056386;5509.834704958857;5529.225908372831;3809.104860357814;3835.42468629705;3842.6541611254843;3849.3648547989546;3915.4909861717792;5535.778568857241;5675.460831221028;5701.2018317435595;5715.485606295411;5727.021948586712;3594.5866225383893;3624.3838547447;3880.941785873214;3892.1826416452864;3922.4508568800393;3469.392856250146;3691.7046636269033;3840.5007349044445;3956.5252169739238;3981.402789581586;3568.3398571264397;4072.857449989295;4290.871273861727;4589.793459294273;4590.655700822452;4657.46439159501;5329.786808527693;5375.354957405137;5432.782688692487;5495.862603434946;1198.8441502604605;1272.5864094169433;1326.7678156322604;1359.9660008499668;1371.3074897176862;754.9207333230041;762.9395140754356;823.8764185372243;839.8320335932838;885.1369914333602;4461.553844615577;5026.824061157842;5040.042391945519;5060.84817455477;5138.794448222029;5185.7407129644125;5535.501804837584;5571.192177134293;5575.386707462176;5599.692016939026;4268.492010817383;4280.373458085907;4929.605631549467;4952.777125029383;5093.6569178177715;2487.4776296977475;2915.1691767846064;2919.1972207643066;2921.28428535006;2930.340673348522;5176.715280659525;5817.825465236049;5832.708364581838;5851.356350801493;5880.706244626175;5156.819511317086;5361;5837.416258374214;5840.678762668094;5846.883062338729;4854.101991131652;5447.319085114361;5904.9999999999145;5907.3797251288;5955.166276721176;5202.687838729688;5268.051750684914;5315.335583052119;5356.464353564768;5668.348139963957;4782.354382158364;4987.551120399182;5677.3471050829685;5677.687727175045;5700.486956173964;5958.404159584007;5972.940270597413;5976;6006.639601623917;6036.336003039574;3530.7175425965347;3760.172761992357;3768.7550309229814;3813.485179500843;3834.559025712079;5629.4652008059165;5657.773689052392;5665.971670141787;5689.715514224355;5695.658260504383;5507.173923911285;5518.041095411071;5533.122776156457;5547.944520554905;5572.693501857275;5188.870278243074;5293.973530132324;5396.974574830927;5425.104857698593;5520.53075488588;5150;5394.433584473573;5395.838124856261;5404.459554044564;5416.6479178854825;5367.913449766478;5390.97304513475;5418.214358918013;5528.369131106253;5542.750576223999;4140.60664236897;5392.301425051096;5431.972840135774;5444.564434845282;5460.689434535762 23:02:47 INFO - PROCESS | 1667 | |5;yui.html;714.7784186902057;729.9270072992699;753.1338960195774;808.7412028150994;832.384035813498;15328.54014379569;15804.130772807519;15959;15984.520464386072;15999.280032398541;14342.21117838521;14458;14462.059966102222;14469.782953255683;14475.059121157145;709.1808960650432;730.0071902212986;735.327175634294;866.086278975682;877.0746862060523;886.6807949138305;891.9643214271422;901.5717534171249;903.8418276801566;904.6426661468716;956.1585804492038;957.1337939165032;957.25812495316;957.2629075611775;957.5690939077408;736.3225832234303;759.4949358676473;949.6818565780471;951.8810148731409;952.342883410448;565.5362602665816;566.122510109333;566.3147591414394;679.4089142446104;701.7333413302963;15180;15229.61925951849;15242.085474871432;15246.542603721906;15250.923745381202;13687.863121368859;13934.372953217027;14014.22921739304;14116.92941535296;14364.569062928129;12853.228806271654;12854.485820567166;12865.871341286562;12993.805092923614;13048.86951130486;4071.42999980003;4453.465584129925;4516.616087632555;4572.382728331666;4705.717656940595;114338.42830785876;115007.84992150139;117558;119531;119554.80445195523;9121.361504694607;11158.1631377647;11171.66485005451;11174.217804753589;11383.943080284545;22833.657495137588;22837.429064273194;22847.086116555485;22860.88569557175;22912.198073067364;7869.685212591432;7934;7942.761717148494;7986.7603971880935;7995.920040799634;4236.152769446123;4281.97859010703;4290.721103128287;4311.9784401077795;4422.380866678667;3003.6245469316336;3603.837827297778;3609.8917032489067;3612.1692010837655;3612.8258316046863;633.499535367064;640.3820313397594;652.210824901874;656.0749343425747;658.9950325753169;586.302300262686;598.6467983889526;615.187951903492;619.6344156947423;619.7118339971935;7781.8832717509285;8754.51850148248;8776.648934042567;8893.155150260844;9005.414648047856;581.7207740284603;584.0480017571332;605.7788907048896;629.7260691599168;635.6726285962733;4362.8036738346855;4385.188740083095;4436.711613745097;4487.775611219426;4529.977350113229;123303;128491.35754321169;131739.34130329287;132777.3361133188;141082;98151;121012;124508;124538.94185343255;125473;71570.9264361035;95177.52411238039;96219;107712;108357.91642083478;9291.231711941704;9738.928717841174;9753.960430544377;9764.74666930822;9882.505874706092;46367.768161160326;48980;56369.82637517619;57062.42937570571;59507.80984380375;81011;88936.22127557542;93414.53292733493;93670;96637.51681241549;12535.310557919038;12842.678933026375;13292.734145317232;13366.93316533411;13554.322283885964;13995.370208340446;14257.50098746539;14528;14661.120332780067;14758.778618320948;13537.458501659823;13557.11878727919;13634.522791702242;13776.93111534436;13801.309934503033;13454.327283635583;13762.518311859038;13791.379387927767;14075.296235187994;14140.86873050133;11303.491342889754;13129.474821007434;13331.466741330238;13517.59447216585;13833;11204.327740335606;11458.4270786462;11617.47721352558;11632.941835290769;11858.288502689866;8925.509096999727;9473.952630236805;9784.276198423717;11297.774044519228;11558.248713833575 23:02:47 INFO - PROCESS | 1667 | __end_tp_report 23:02:47 INFO - PROCESS | 1667 | __start_cc_report 23:02:47 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,2058 23:02:47 INFO - PROCESS | 1667 | __end_cc_report 23:02:47 INFO - PROCESS | 1667 | __startTimestamp1476856967865__endTimestamp 23:02:47 INFO - PROCESS | 1667 | 23:02:47 INFO - PROCESS | 1667 | ------- Summary: start ------- 23:02:47 INFO - PROCESS | 1667 | Number of tests: 6 23:02:47 INFO - PROCESS | 1667 | 23:02:47 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5715.09 Median:5957.37 stddev:618.37 (10.4%) stddev-sans-first:591.59 23:02:47 INFO - PROCESS | 1667 | Values: 3342.5 3349.9 3367.7 3392.2 3429.5 5622.5 5704.9 5711.3 5745.7 5767.7 5689.6 5691.0 5779.5 5797.7 5811.1 5092.7 5148.5 5183.8 5224.1 5226.2 5285.7 5315.3 5323.4 5335.1 5382.6 5270.0 5297.2 5308.0 5332.6 5374.3 5159.5 5192.3 5206.5 5214.6 5241.2 5140.8 5175.8 5186.8 5232.1 5266.6 5670.1 5676.6 5721.5 5725.9 5739.6 6090.8 6113.9 6134.4 6235.9 6245.4 6200.7 6228.7 6265.5 6297.8 6304.7 5719.0 5759.1 5775.0 5783.5 5824.6 5583.4 5764.8 6125.7 6245.7 6261.4 5936.8 5980.0 5988.6 6039.8 6050.4 6141.7 6147.5 6150.7 6157.2 6161.5 5957.4 6017.9 6084.7 6097.3 6139.2 6113.9 6122.5 6164.2 6182.8 6183.1 4483.5 4802.0 4920.6 4937.2 4968.3 4401.2 4416.7 4425.3 4427.3 4484.3 5519.5 5634.0 5649.7 5700.9 5719.6 6148.6 6193.1 6216.2 6223.2 6225.3 5683.7 5696.2 5707.8 5713.3 5714.9 5359.2 5379.9 5386.6 5388.5 5394.2 6208.6 6215.0 6219.9 6220.8 6226.0 5273.3 6160.7 6169.9 6238.5 6296.0 6263.1 6293.6 6310.5 6313.5 6324.0 6160.9 6169.5 6171.9 6175.3 6181.4 6128.4 6132.6 6146.5 6266.6 6276.6 6057.0 6091.9 6128.2 6155.9 6157.8 5951.2 6125.6 6126.4 6136.5 6145.2 5788.1 5796.9 5967.8 5978.6 5985.4 6012.1 6049.3 6064.1 6106.0 6130.0 5969.3 5972.4 5974.1 6009.0 6018.9 23:02:47 INFO - PROCESS | 1667 | 23:02:47 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:65004.42 Median:19581.63 stddev:142109.70 (725.7%) stddev-sans-first:142425.76 23:02:47 INFO - PROCESS | 1667 | Values: 472.6 523.6 524.8 528.5 549.9 31088.7 31606.8 31619.2 31658.8 40557.8 40947.6 43074.0 43256.4 43359.3 43414.6 9651.7 9825.1 9825.7 9827.0 9845.1 8677.6 8889.5 9649.7 9775.9 9778.9 8825.1 8861.4 8917.2 8931.2 11601.1 11646.7 11821.5 11845.9 11847.9 11854.0 11628.5 11684.1 11695.8 11700.8 11702.0 33075.5 40274.6 40727.2 40803.0 40975.4 3322.3 3433.8 3513.5 3560.3 4050.2 29997.4 30978.1 31030.4 31125.5 31193.7 3216.2 3232.7 3264.9 3317.3 3337.9 473887.0 476613.0 478911.0 480656.0 491441.5 28216.6 60238.7 60729.1 60782.7 60942.7 34324.5 35276.5 35595.0 35603.0 35731.8 14300.6 14466.8 14469.6 14472.2 14481.3 11993.4 12212.8 12368.6 12373.1 12380.0 7856.7 7856.8 7909.8 7923.7 7942.6 3083.8 3168.1 3168.3 3196.7 3293.5 830.9 892.4 955.7 1062.9 1076.0 27506.9 28652.6 29061.9 29987.6 30203.0 997.5 1022.0 1075.0 1083.4 1233.8 15268.2 15541.7 15693.6 15867.4 15971.0 643655.0 648696.0 649977.0 650053.0 651524.0 58786.0 59105.7 59153.7 59226.7 59312.0 1331.6 1378.9 1392.1 1394.0 1399.4 24151.0 24598.9 29652.3 29673.3 30477.1 51942.0 52209.5 52235.0 52247.5 52342.0 439328.0 441950.0 442433.0 442806.0 442926.0 22100.4 26309.1 26339.1 26343.6 26385.7 47737.0 47939.5 48852.8 48944.5 48976.8 33602.0 39167.2 39395.8 40415.0 41291.8 38553.2 38918.0 39319.4 40149.8 40557.0 35827.7 37765.6 37907.8 38312.4 38577.8 33055.0 33594.7 34538.3 34569.5 34764.7 23133.9 24765.6 25601.7 25627.4 26326.0 23:02:47 INFO - PROCESS | 1667 | 23:02:47 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:34445.52 Median:26392.21 stddev:96248.43 (364.7%) stddev-sans-first:96484.33 23:02:47 INFO - PROCESS | 1667 | Values: 323.9 344.1 365.0 365.8 396.0 15110.9 15396.3 15905.2 16596.9 17920.6 20111.4 20346.8 20483.9 20661.7 20955.3 5574.2 5939.6 5942.5 5942.7 5951.0 4512.8 5783.0 6465.3 6539.0 6620.7 5656.9 6316.9 6540.1 6556.9 6559.4 6419.3 6447.3 6479.4 6488.1 6509.6 5329.7 5518.5 5596.1 5603.0 5612.4 24952.9 26007.1 26143.2 26225.3 26559.1 2171.3 2207.3 2220.3 2299.8 2302.0 15310.5 15455.4 15682.3 15879.5 16429.7 1825.5 1862.7 1899.1 1923.2 1948.5 111411.4 116417.0 119683.0 131571.7 132070.3 11103.5 11224.4 11628.2 11638.7 11832.8 14568.3 14589.0 14594.8 14610.4 14623.4 8118.5 8362.5 8369.9 8513.8 8545.0 8164.9 8176.9 8216.8 8353.9 8369.7 4422.3 4502.1 4513.7 4573.5 4580.8 1786.5 1909.9 1922.2 1950.1 1982.7 748.0 781.2 839.2 944.3 968.7 17264.4 20455.3 21626.9 21747.7 21981.5 919.1 935.6 978.7 983.6 1001.1 9755.2 10810.0 10863.0 11256.8 11410.9 564656.0 571519.0 572460.0 582902.0 584214.0 99402.0 120560.4 127687.0 133649.3 138875.0 929.1 948.7 959.3 960.9 963.0 18373.0 19179.0 19204.5 19243.6 19299.2 56587.2 56769.4 56879.0 57471.9 57830.7 60039.7 65604.0 76681.0 84863.0 86522.0 7654.0 7767.9 7919.7 7936.7 8063.2 9096.1 12820.2 12929.9 13144.1 13154.3 11045.3 11309.0 11322.9 11339.3 11368.7 10237.7 10386.1 10611.6 10688.0 11069.9 10146.5 10685.1 10888.0 11488.5 11543.0 7641.4 7888.7 7920.4 8042.4 8284.9 7853.8 10174.8 11058.0 11387.1 11427.0 23:02:47 INFO - PROCESS | 1667 | 23:02:47 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5673.55 Median:4921.11 stddev:3205.90 (65.1%) stddev-sans-first:3199.80 23:02:47 INFO - PROCESS | 1667 | Values: 1533.7 1554.1 1779.6 1883.2 1955.0 8338.1 8860.2 8863.8 8878.4 8930.8 7752.3 8008.2 8020.4 8451.6 8673.2 2043.6 2335.1 2382.5 2390.4 2394.7 1999.4 2347.6 2376.0 2389.3 2393.0 2007.0 2351.8 2364.9 2370.1 2391.0 2337.6 2353.5 2355.3 2365.4 2371.7 2321.4 2360.6 2361.9 2366.7 2368.1 8832.6 8885.4 8888.4 8903.6 8908.6 5282.0 5396.6 5398.4 5398.8 5399.6 3395.5 3467.9 3593.9 3608.7 3610.6 1034.9 1060.7 1066.1 1081.3 1083.7 11587.1 11719.5 11720.5 11728.5 11753.5 4063.3 4489.0 4553.5 4747.3 4750.6 9730.9 9879.3 9885.8 9901.4 9908.8 5340.7 5364.5 5371.4 5377.4 5379.3 4193.8 4360.7 4499.6 4529.1 4919.3 3817.6 3823.3 3899.4 3942.1 3992.6 1261.4 1274.1 1288.6 1288.7 1295.0 807.4 830.7 899.9 907.3 912.7 5409.6 5572.0 5770.7 5793.8 5956.2 433.3 442.4 963.8 982.5 1012.0 3810.9 3813.6 3846.8 3918.7 3919.3 10544.6 10765.4 10860.1 10873.9 10881.6 10814.5 10815.6 10834.3 10838.3 10838.9 7721.4 8137.3 8287.2 8292.3 8353.7 5254.7 5696.9 5768.8 5948.9 5987.7 8448.4 9697.6 9701.5 9762.8 10026.2 9886.8 10204.2 10212.4 10218.5 10327.2 6933.1 7154.3 7202.1 7536.1 7682.1 8109.8 8411.9 8438.9 8475.6 8519.8 7297.2 7444.3 7530.8 7648.5 7740.9 7227.8 7370.1 7395.9 7414.4 7587.8 6947.6 7110.8 7122.4 7129.6 7137.9 4514.5 4841.8 4854.8 4948.6 4983.7 4923.0 4962.4 4981.4 5050.2 5088.9 23:02:47 INFO - PROCESS | 1667 | 23:02:47 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4479.84 Median:5090.24 stddev:1382.84 (27.2%) stddev-sans-first:1350.60 23:02:47 INFO - PROCESS | 1667 | Values: 296.4 297.1 304.3 308.4 312.3 5194.2 5247.4 5267.9 5271.0 5289.7 4931.6 4954.6 5053.6 5086.8 5177.2 3318.8 3960.7 4005.0 4024.1 4026.0 3977.1 3986.9 3988.7 4002.3 4028.7 3390.4 3501.8 3963.5 3966.3 3996.9 3994.3 3995.5 3999.8 4004.5 4039.5 3475.5 3500.9 3500.9 3506.3 3515.5 4848.3 5001.8 5034.6 5050.8 5128.0 4188.1 4275.9 4564.5 4570.2 4572.2 5457.5 5457.6 5477.6 5509.8 5529.2 3809.1 3835.4 3842.7 3849.4 3915.5 5535.8 5675.5 5701.2 5715.5 5727.0 3594.6 3624.4 3880.9 3892.2 3922.5 3469.4 3691.7 3840.5 3956.5 3981.4 3568.3 4072.9 4290.9 4589.8 4590.7 4657.5 5329.8 5375.4 5432.8 5495.9 1198.8 1272.6 1326.8 1360.0 1371.3 754.9 762.9 823.9 839.8 885.1 4461.6 5026.8 5040.0 5060.8 5138.8 5185.7 5535.5 5571.2 5575.4 5599.7 4268.5 4280.4 4929.6 4952.8 5093.7 2487.5 2915.2 2919.2 2921.3 2930.3 5176.7 5817.8 5832.7 5851.4 5880.7 5156.8 5361.0 5837.4 5840.7 5846.9 4854.1 5447.3 5905.0 5907.4 5955.2 5202.7 5268.1 5315.3 5356.5 5668.3 4782.4 4987.6 5677.3 5677.7 5700.5 5958.4 5972.9 5976.0 6006.6 6036.3 3530.7 3760.2 3768.8 3813.5 3834.6 5629.5 5657.8 5666.0 5689.7 5695.7 5507.2 5518.0 5533.1 5547.9 5572.7 5188.9 5294.0 5397.0 5425.1 5520.5 5150.0 5394.4 5395.8 5404.5 5416.6 5367.9 5391.0 5418.2 5528.4 5542.8 4140.6 5392.3 5432.0 5444.6 5460.7 23:02:47 INFO - PROCESS | 1667 | 23:02:47 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:23702.42 Median:4301.35 stddev:36896.51 (857.8%) stddev-sans-first:36959.64 23:02:47 INFO - PROCESS | 1667 | Values: 714.8 729.9 753.1 808.7 832.4 15328.5 15804.1 15959.0 15984.5 15999.3 14342.2 14458.0 14462.1 14469.8 14475.1 709.2 730.0 735.3 866.1 877.1 886.7 892.0 901.6 903.8 904.6 956.2 957.1 957.3 957.3 957.6 736.3 759.5 949.7 951.9 952.3 565.5 566.1 566.3 679.4 701.7 15180.0 15229.6 15242.1 15246.5 15250.9 13687.9 13934.4 14014.2 14116.9 14364.6 12853.2 12854.5 12865.9 12993.8 13048.9 4071.4 4453.5 4516.6 4572.4 4705.7 114338.4 115007.8 117558.0 119531.0 119554.8 9121.4 11158.2 11171.7 11174.2 11383.9 22833.7 22837.4 22847.1 22860.9 22912.2 7869.7 7934.0 7942.8 7986.8 7995.9 4236.2 4282.0 4290.7 4312.0 4422.4 3003.6 3603.8 3609.9 3612.2 3612.8 633.5 640.4 652.2 656.1 659.0 586.3 598.6 615.2 619.6 619.7 7781.9 8754.5 8776.6 8893.2 9005.4 581.7 584.0 605.8 629.7 635.7 4362.8 4385.2 4436.7 4487.8 4530.0 123303.0 128491.4 131739.3 132777.3 141082.0 98151.0 121012.0 124508.0 124538.9 125473.0 71570.9 95177.5 96219.0 107712.0 108357.9 9291.2 9738.9 9754.0 9764.7 9882.5 46367.8 48980.0 56369.8 57062.4 59507.8 81011.0 88936.2 93414.5 93670.0 96637.5 12535.3 12842.7 13292.7 13366.9 13554.3 13995.4 14257.5 14528.0 14661.1 14758.8 13537.5 13557.1 13634.5 13776.9 13801.3 13454.3 13762.5 13791.4 14075.3 14140.9 11303.5 13129.5 13331.5 13517.6 13833.0 11204.3 11458.4 11617.5 11632.9 11858.3 8925.5 9474.0 9784.3 11297.8 11558.2 23:02:47 INFO - PROCESS | 1667 | -------- Summary: end -------- 23:02:47 INFO - PROCESS | 1667 | 23:02:48 INFO - TEST-INFO | 1667: exit 0 23:02:48 INFO - TEST-OK | dromaeo_css | took 1119702ms 23:02:48 INFO - TEST-START | kraken 23:02:48 INFO - operating with platform_type : mac_ 23:02:48 INFO - Initialising browser for kraken test... 23:02:48 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmp9UmOrA/profile http://localhost:49272/getInfo.html) 23:02:50 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 23:02:50 INFO - PROCESS | 1677 | colorDepth:24 23:02:50 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 23:02:50 INFO - PROCESS | 1677 | __metrics 23:02:50 INFO - PROCESS | 1677 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:02:50 INFO - PROCESS | 1677 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:02:51 INFO - TEST-INFO | 1677: exit 0 23:02:51 INFO - Browser initialized. 23:02:51 INFO - Running cycle 1/1 for kraken test... 23:02:51 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmp9UmOrA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:03:03 INFO - PROCESS | 1678 | RSS: Main: 231813120 23:03:03 INFO - PROCESS | 1678 | 23:03:06 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 23:03:07 INFO - PROCESS | 1678 | RSS: Main: 290062336 23:03:07 INFO - PROCESS | 1678 | 23:03:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 23:03:10 INFO - PROCESS | 1678 | RSS: Main: 291205120 23:03:10 INFO - PROCESS | 1678 | 23:03:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 23:03:14 INFO - PROCESS | 1678 | RSS: Main: 386514944 23:03:14 INFO - PROCESS | 1678 | 23:03:16 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 23:03:17 INFO - PROCESS | 1678 | RSS: Main: 285900800 23:03:17 INFO - PROCESS | 1678 | 23:03:19 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:03:19 INFO - PROCESS | 1678 | RSS: Main: 290697216 23:03:19 INFO - PROCESS | 1678 | 23:03:22 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 23:03:23 INFO - PROCESS | 1678 | RSS: Main: 328314880 23:03:23 INFO - PROCESS | 1678 | 23:03:26 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 23:03:26 INFO - PROCESS | 1678 | RSS: Main: 328839168 23:03:26 INFO - PROCESS | 1678 | 23:03:29 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 23:03:30 INFO - PROCESS | 1678 | RSS: Main: 326488064 23:03:30 INFO - PROCESS | 1678 | 23:03:31 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:03:32 INFO - PROCESS | 1678 | RSS: Main: 302415872 23:03:32 INFO - PROCESS | 1678 | 23:03:34 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:03:34 INFO - PROCESS | 1678 | RSS: Main: 303902720 23:03:34 INFO - PROCESS | 1678 | 23:03:36 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:03:37 INFO - PROCESS | 1678 | RSS: Main: 307355648 23:03:37 INFO - PROCESS | 1678 | 23:03:40 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:03:41 INFO - PROCESS | 1678 | RSS: Main: 316903424 23:03:41 INFO - PROCESS | 1678 | 23:03:44 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:03:44 INFO - PROCESS | 1678 | RSS: Main: 316588032 23:03:44 INFO - PROCESS | 1678 | 23:03:46 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 23:03:47 INFO - PROCESS | 1678 | RSS: Main: 360386560 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | __start_tp_report 23:03:47 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 23:03:47 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 23:03:47 INFO - PROCESS | 1678 | |i|pagename|runs| 23:03:47 INFO - PROCESS | 1678 | |0;ai-astar;103;114;114;115;114;114;113;114;114;113 23:03:47 INFO - PROCESS | 1678 | |1;audio-beat-detection;151;113;116;175;138;119;115;112;145;112 23:03:47 INFO - PROCESS | 1678 | |2;audio-dft;187;183;184;185;183;183;189;258;184;180 23:03:47 INFO - PROCESS | 1678 | |3;audio-fft;81;60;60;87;66;58;59;58;83;58 23:03:47 INFO - PROCESS | 1678 | |4;audio-oscillator;87;71;92;70;68;69;92;67;68;89 23:03:47 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;88;85;87;85;87;84;87;84;88;85 23:03:47 INFO - PROCESS | 1678 | |6;imaging-darkroom;95;96;96;96;97;97;98;96;113;160 23:03:47 INFO - PROCESS | 1678 | |7;imaging-desaturate;84;82;82;82;82;81;83;85;82;81 23:03:47 INFO - PROCESS | 1678 | |8;json-parse-financial;57;54;54;54;53;57;55;54;54;54 23:03:47 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;66;72;71;75;72;69;69;70;69;71 23:03:47 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;88;88;86;89;89;85;86;86;91;86 23:03:47 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;146;146;148;156;155;151;140;146;176;137 23:03:47 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;173;174;174;174;173;174;174;173;170;172 23:03:47 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;63;84;86;86;69;69;68;69;69;69 23:03:47 INFO - PROCESS | 1678 | __end_tp_report 23:03:47 INFO - PROCESS | 1678 | __start_cc_report 23:03:47 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,2057 23:03:47 INFO - PROCESS | 1678 | __end_cc_report 23:03:47 INFO - PROCESS | 1678 | __startTimestamp1476857027043__endTimestamp 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | ------- Summary: start ------- 23:03:47 INFO - PROCESS | 1678 | Number of tests: 14 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.49 (3.1%) stddev-sans-first:0.60 23:03:47 INFO - PROCESS | 1678 | Values: 103.0 114.0 114.0 115.0 114.0 114.0 113.0 114.0 114.0 113.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:128.50 stddev:21.69 (16.9%) stddev-sans-first:21.57 23:03:47 INFO - PROCESS | 1678 | Values: 151.0 113.0 116.0 175.0 138.0 119.0 115.0 112.0 145.0 112.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:191.60 Median:184.50 stddev:23.46 (12.7%) stddev-sans-first:24.82 23:03:47 INFO - PROCESS | 1678 | Values: 187.0 183.0 184.0 185.0 183.0 183.0 189.0 258.0 184.0 180.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:67.00 Median:63.00 stddev:11.82 (18.8%) stddev-sans-first:11.40 23:03:47 INFO - PROCESS | 1678 | Values: 81.0 60.0 60.0 87.0 66.0 58.0 59.0 58.0 83.0 58.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:79.00 stddev:11.08 (14.0%) stddev-sans-first:11.18 23:03:47 INFO - PROCESS | 1678 | Values: 87.0 71.0 92.0 70.0 68.0 69.0 92.0 67.0 68.0 89.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.56 (1.8%) stddev-sans-first:1.48 23:03:47 INFO - PROCESS | 1678 | Values: 88.0 85.0 87.0 85.0 87.0 84.0 87.0 84.0 88.0 85.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:104.40 Median:96.00 stddev:20.24 (21.1%) stddev-sans-first:21.18 23:03:47 INFO - PROCESS | 1678 | Values: 95.0 96.0 96.0 96.0 97.0 97.0 98.0 96.0 113.0 160.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:82.40 Median:82.00 stddev:1.26 (1.5%) stddev-sans-first:1.20 23:03:47 INFO - PROCESS | 1678 | Values: 84.0 82.0 82.0 82.0 82.0 81.0 83.0 85.0 82.0 81.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:54.00 stddev:1.35 (2.5%) stddev-sans-first:1.12 23:03:47 INFO - PROCESS | 1678 | Values: 57.0 54.0 54.0 54.0 53.0 57.0 55.0 54.0 54.0 54.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:2.41 (3.4%) stddev-sans-first:1.96 23:03:47 INFO - PROCESS | 1678 | Values: 66.0 72.0 71.0 75.0 72.0 69.0 69.0 70.0 69.0 71.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:88.00 stddev:1.90 (2.2%) stddev-sans-first:2.00 23:03:47 INFO - PROCESS | 1678 | Values: 88.0 88.0 86.0 89.0 89.0 85.0 86.0 86.0 91.0 86.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:150.10 Median:149.50 stddev:10.85 (7.3%) stddev-sans-first:11.40 23:03:47 INFO - PROCESS | 1678 | Values: 146.0 146.0 148.0 156.0 155.0 151.0 140.0 146.0 176.0 137.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:174.00 stddev:1.29 (0.7%) stddev-sans-first:1.36 23:03:47 INFO - PROCESS | 1678 | Values: 173.0 174.0 174.0 174.0 173.0 174.0 174.0 173.0 170.0 172.0 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:69.00 stddev:8.59 (12.4%) stddev-sans-first:8.28 23:03:47 INFO - PROCESS | 1678 | Values: 63.0 84.0 86.0 86.0 69.0 69.0 68.0 69.0 69.0 69.0 23:03:47 INFO - PROCESS | 1678 | -------- Summary: end -------- 23:03:47 INFO - PROCESS | 1678 | 23:03:47 INFO - TEST-INFO | 1678: exit 0 23:03:48 INFO - TEST-OK | kraken | took 59530ms 23:03:48 INFO - SUITE-END | took 1179s 23:03:48 INFO - Completed test suite (00:19:39) 23:03:48 INFO - javascript benchmark 23:03:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3342.4819153031276, 3349.9162520936957, 3367.680070393312, 3392.236746731984, 3429.4512877939533, 5622.522085622727, 5704.914426283609, 5711.3431955325095, 5745.741441635126, 5767.740451679674, 5689.630174038684, 5691.004074286993, 5779.508741756956, 5797.681127537984, 5811.099279611667, 5092.668976516533, 5148.536631703146, 5183.818566350178, 5224.059669259532, 5226.216067589873, 5285.68285902847, 5315.255864178998, 5323.441038690939, 5335.146376579748, 5382.569394448455, 5269.973650131763, 5297.152455607084, 5307.973460132694, 5332.626716129853, 5374.3013408256875, 5159.484051594811, 5192.3249977503065, 5206.505381988705, 5214.556762675177, 5241.187615919539, 5140.8457746267295, 5175.7670904809, 5186.818461353872, 5232.136697444917, 5266.552343050846, 5670.092785154397, 5676.602637815355, 5721.4564616361795, 5725.942740572583, 5739.569532285095, 6090.756369745205, 6113.902014424272, 6134.4365870334295, 6235.87528249437, 6245.437910588023, 6200.689965501752, 6228.719707613168, 6265.467435268008, 6297.842553936142, 6304.653244071575, 5718.971405142989, 5759.107338362561, 5774.971125144348, 5783.479486846204, 5824.592278540504, 5583.357913839879, 5764.769409223668, 6125.723854043132, 6245.718942647592, 6261.405166509175, 5936.821895343147, 5979.970100149471, 5988.5508586855285, 6039.818805435844, 6050.394960503914, 6141.723622436912, 6147.538934579924, 6150.661713605705, 6157.168782214344, 6161.507079433634, 5957.3744756799915, 6017.879642407215, 6084.695765211722, 6097.2683278006925, 6139.171211886338, 6113.902014424272, 6122.4896596401695, 6164.229471316085, 6182.752689892444, 6183.072539119078, 4483.50681425043, 4802.039592081597, 4920.606351491872, 4937.185364414831, 4968.30443737877, 4401.163778882003, 4416.668749843775, 4425.291953287458, 4427.313766366218, 4484.327350897392, 5519.530839878575, 5634.04221282383, 5649.661020338793, 5700.8859822803315, 5719.628224165416, 6148.585825260216, 6193.071039344134, 6216.191895053614, 6223.190985171898, 6225.28409232944, 5683.658980461186, 5696.174054762037, 5707.771689132472, 5713.2572765541045, 5714.914276285859, 5359.22291267764, 5379.946200538023, 5386.569074474032, 5388.488093631099, 5394.244805727201, 6208.627482351073, 6215.0, 6219.937800621936, 6220.7511699531515, 6225.968870155621, 5273.314469119144, 6160.691965401622, 6169.938300616936, 6238.469730072813, 6296.0, 6263.123162757127, 6293.622382657055, 6310.49516038725, 6313.463355614824, 6323.9683801580695, 6160.9383906160365, 6169.475594574332, 6171.938280617136, 6175.2589689237575, 6181.381861813783, 6128.448439640454, 6132.600268413531, 6146.477549408171, 6266.561340706106, 6276.623402595859, 6056.9697151513965, 6091.939080609137, 6128.233970753656, 6155.938440615537, 6157.784477543264, 5951.196588460675, 6125.632462052113, 6126.448619624255, 6136.509079273558, 6145.201123853871, 5788.0739081746715, 5796.884062318814, 5967.8209653710455, 5978.551608629457, 5985.371535988658, 6012.128241405059, 6049.304330002106, 6064.06007068905, 6106.0, 6130.049842274455, 5969.253843269591, 5972.372900845348, 5974.103884417198, 6009.0, 6018.909716354171], "value": 5676.192988419755, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.61718008413175, 523.5706720489198, 524.8373004368646, 528.5428104689443, 549.87352908831, 31088.689113108863, 31606.841965790198, 31619.20951976199, 31658.841705791467, 40557.797211013974, 40947.59052409483, 43074.0, 43256.35115473271, 43359.34960975588, 43414.56585434145, 9651.662191823305, 9825.066618671215, 9825.70522884311, 9826.950865245664, 9845.11393974542, 8677.566121693922, 8889.511076890802, 9649.710508684715, 9775.902240977606, 9778.853317200212, 8825.117488251188, 8861.379703420764, 8917.152870477326, 8931.196192342688, 11601.129915256348, 11646.708832279177, 11821.527138914433, 11845.94077029618, 11847.940760296186, 11854.0, 11628.476718547685, 11684.12369072323, 11695.824562631567, 11700.824487632692, 11702.005329546999, 33075.51160197797, 40274.59725402738, 40727.18545629071, 40803.0, 40975.38536921948, 3322.335532893431, 3433.828308584561, 3513.5256740339983, 3560.2523470071314, 4050.189962007581, 29997.400051998837, 30978.127734033245, 31030.37939241225, 31125.533117003262, 31193.688063119305, 3216.244182617083, 3232.7090561849545, 3264.9183770405693, 3317.303366293057, 3337.916552086193, 473887.0, 476613.0, 478911.0, 480656.0, 491441.4842293146, 28216.576751348744, 60238.69880650657, 60729.08906366408, 60782.69608651928, 60942.69528652328, 34324.485132722526, 35276.47085293723, 35595.0, 35603.0, 35731.82134089365, 14300.570982870531, 14466.782998255034, 14469.638259043397, 14472.204028778518, 14481.348339324755, 11993.400329983466, 12212.816807747891, 12368.567100151451, 12373.072019598567, 12380.0, 7856.685732570748, 7856.803579910434, 7909.802254943673, 7923.7226697065325, 7942.563159026309, 3083.8458077096057, 3168.081256435654, 3168.287135394564, 3196.7122958933805, 3293.5059741039036, 830.8919840420707, 892.3797960417577, 955.7371722776153, 1062.8512008318842, 1075.9892401076045, 27506.862465687544, 28652.570211446844, 29061.85469072641, 29987.550186747216, 30203.0, 997.4563862694848, 1022.044388496758, 1074.978500429987, 1083.3824719909576, 1233.7779199744132, 15268.236588170546, 15541.689166216613, 15693.607659808597, 15867.365305387886, 15971.03409859536, 643655.0, 648696.0, 649977.0, 650053.0, 651524.0, 58786.0, 59105.704471478224, 59153.704231478565, 59226.70386648126, 59312.0, 1331.6338007047946, 1378.8896888249117, 1392.0534036855036, 1393.986060139406, 1399.3842709207916, 24151.0, 24598.877005614857, 29652.258693532833, 29673.25816854596, 30477.08568742941, 51942.0, 52209.47790522198, 52235.0, 52247.47752522426, 52342.0, 439328.0, 441950.0, 442433.0, 442806.0, 442926.0, 22100.447488812908, 26309.079182228532, 26339.078132266044, 26343.604845926944, 26385.73614263833, 47737.0, 47939.520604793506, 48852.755736221094, 48944.510554894, 48976.75511622561, 33602.0, 39167.17709687245, 39395.80302098471, 40415.0, 41291.7935410321, 38553.2289354217, 38918.027049323995, 39319.41020884632, 40149.799251003555, 40557.0, 35827.74602888886, 37765.62234377621, 37907.78621081664, 38312.42531362088, 38577.80711096426, 33055.0, 33594.66405336013, 34538.30923381468, 34569.48145777765, 34764.65235347614, 23133.88433057824, 24765.62851557192, 25601.74398256068, 25627.35931601725, 26326.0], "value": 18599.615623572503, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.86883312258533, 344.08130292120035, 365.0381245418322, 365.8317174099915, 395.95050618672667, 15110.924445377772, 15396.307166177521, 15905.204739762994, 16596.917015414907, 17920.64158716828, 20111.39665810028, 20346.796532034674, 20483.87338696371, 20661.690074648894, 20955.266565670205, 5574.191742197389, 5939.613925094855, 5942.465178133967, 5942.732577034022, 5950.970245148768, 4512.842050528232, 5783.0, 6465.321141280168, 6539.0, 6620.702068406909, 5656.915146272789, 6316.926122559153, 6540.149780528548, 6556.868862622745, 6559.409653131217, 6419.293877673475, 6447.258565265008, 6479.35206479353, 6488.124103246048, 6509.609423434586, 5329.733513324357, 5518.503334699896, 5596.104623260258, 5603.0, 5612.438756124396, 24952.875235623887, 26007.089751858784, 26143.215703528924, 26225.344366390993, 26559.07043253477, 2171.3485954213675, 2207.315732123047, 2220.330426346999, 2299.8045166160728, 2301.9884900575557, 15310.540683779505, 15455.381784728597, 15682.294296756674, 15879.52361429159, 16429.67140657192, 1825.4979880532826, 1862.7299041639021, 1899.097928483973, 1923.2018712234405, 1948.4641723526001, 111411.44294278558, 116417.0, 119683.0, 131571.68428315787, 132070.3396483021, 11103.500342484607, 11224.382658953837, 11628.18602697803, 11638.6508404748, 11832.76334473323, 14568.271586420637, 14589.0, 14594.781078283835, 14610.415583376758, 14623.415063397557, 8118.472299300526, 8362.45644033136, 8369.916300836914, 8513.787155321043, 8545.0, 8164.918350816534, 8176.918230817735, 8216.753497395088, 8353.874691879626, 8369.707060252862, 4422.270325396338, 4502.144592527475, 4513.661475389359, 4573.47405048417, 4580.763193937618, 1786.5087101047316, 1909.8663093583316, 1922.2118931238126, 1950.0737149853705, 1982.7211448615851, 747.986478321866, 781.2343902975116, 839.236294971573, 944.2776276148752, 968.6997030920437, 17264.395746149075, 20455.28406505765, 21626.918654067234, 21747.67378489324, 21981.450463738536, 919.1406035356963, 935.5825923725562, 978.7112801723508, 983.6458874805064, 1001.0790073132736, 9755.219582433388, 10810.027097561257, 10863.044651962722, 11256.774864502828, 11410.942945285387, 564656.0, 571519.0, 572460.0, 582902.0, 584214.0, 99402.0, 120560.3971980152, 127687.0, 133649.3317533406, 138875.0, 929.0755698080453, 948.7011591348703, 959.318883592643, 960.9375390599589, 962.9518524073768, 18373.0, 19179.0, 19204.519887002938, 19243.61512769765, 19299.236648853337, 56587.15119273132, 56769.43230567641, 56879.018468706796, 57471.85056298934, 57830.7108464455, 60039.69980150071, 65604.0, 76681.0, 84863.0, 86522.0, 7654.0, 7767.9223207769455, 7919.722809701863, 7936.682532698627, 8063.193680632125, 9096.056421972677, 12820.23078615286, 12929.870701293243, 13144.0799144059, 13154.276514791396, 11045.28205666629, 11309.038731707567, 11322.94338528302, 11339.319640821577, 11368.715782105513, 10237.692869213935, 10386.065254127165, 10611.628592999208, 10688.0, 11069.944650277019, 10146.543405546621, 10685.145188384755, 10888.0, 11488.54045838157, 11543.0, 7641.388688904985, 7888.684452621831, 7920.445568810128, 8042.356611471186, 8284.875726864222, 7853.764387068397, 10174.796504070026, 11058.0, 11387.145964052563, 11427.0], "value": 9580.9308759275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1533.6855944531371, 1554.114154931689, 1779.5640068183297, 1883.1902282018743, 1954.9706754398678, 8338.082810890797, 8860.158284962918, 8863.778405539864, 8878.378513504049, 8930.821383572325, 7752.302292793647, 8008.239217274364, 8020.39847011475, 8451.61967711453, 8673.2245681382, 2043.5708501214688, 2335.089315167082, 2382.511585125054, 2390.3785015895905, 2394.6767186429784, 1999.4301624037153, 2347.5774360615083, 2376.013954209001, 2389.259329607827, 2392.9760702393014, 2006.9698954515693, 2351.8000969917575, 2364.8935797889135, 2370.1349007612184, 2390.964135537968, 2337.625979843217, 2353.5057637895893, 2355.2580937004873, 2365.3968238099346, 2371.67982322386, 2321.3732292281156, 2360.5632957902844, 2361.9173328933407, 2366.6568347589505, 2368.1237941961585, 8832.55837208137, 8885.422447540954, 8888.377813553054, 8903.55482225886, 8908.599113039929, 5282.0, 5396.622236443451, 5398.406175320712, 5398.78404863805, 5399.568034557226, 3395.5076513905346, 3467.861285548575, 3593.928121437583, 3608.657177568127, 3610.5847827499647, 1034.8781372193569, 1060.7030031591166, 1066.1470823341297, 1081.2539347849959, 1083.7290677330668, 11587.130965177561, 11719.472623731952, 11720.531178752839, 11728.472218750092, 11753.4710938008, 4063.329550624173, 4488.955110448854, 4553.4991150973365, 4747.264174053027, 4750.572448479585, 9730.902690973142, 9879.259055570861, 9885.802283954425, 9901.405915645084, 9908.80182396348, 5340.652857564246, 5364.490373414519, 5371.382291036581, 5377.3816011159215, 5379.327584051994, 4193.7693426861815, 4360.672949528798, 4499.617532509707, 4529.139463501924, 4919.262110683426, 3817.6373244541724, 3823.330917089498, 3899.434581985596, 3942.073612701013, 3992.6207010333974, 1261.400834603556, 1274.0635632809876, 1288.5683296095726, 1288.6585054960442, 1294.973366681141, 807.4307613132727, 830.66773290683, 899.9010108888017, 907.26964793342, 912.748994026648, 5409.6213265071065, 5571.9721401392735, 5770.653760774367, 5793.768249269982, 5956.195913551702, 433.25480174100494, 442.4005472584666, 963.8409662405764, 982.513655740413, 1011.9544620492095, 3810.866619668354, 3813.599572044951, 3846.826892789831, 3918.7256892017285, 3919.2749341371937, 10544.630937917289, 10765.3540787553, 10860.076893464144, 10873.891261087447, 10881.564737410574, 10814.513346899252, 10815.56737730482, 10834.349939003685, 10838.349699018085, 10838.945805270923, 7721.420893432903, 8137.308328792119, 8287.171282871665, 8292.253697167283, 8353.749387518385, 5254.65844720092, 5696.943030569807, 5768.769249230152, 5948.910766338422, 5987.730552125077, 8448.366372522209, 9697.6120955161, 9701.466419346722, 9762.804743905224, 10026.24803139782, 9886.752831179278, 10204.183665306906, 10212.387256764618, 10218.489075546044, 10327.173826093744, 6933.1333583302085, 7154.320339567733, 7202.063731715053, 7536.0956685198125, 7682.116556595839, 8109.75670729879, 8411.915880841114, 8438.945131858518, 8475.618597163018, 8519.829603407774, 7297.197308295977, 7444.2927921849505, 7530.811729706801, 7648.464607477423, 7740.92259077402, 7227.819304517218, 7370.115586129698, 7395.889061664187, 7414.406847452299, 7587.848243035219, 6947.617881016593, 7110.822229444305, 7122.3974605715375, 7129.643517824192, 7137.892931605927, 4514.503404625554, 4841.763188885433, 4854.805807767791, 4948.604111670986, 4983.725895075806, 4922.950770492249, 4962.354893863775, 4981.402231732215, 5050.166722490895, 5088.949110508847], "value": 4536.15180102336, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.42789416426314, 297.14866906313415, 304.2606466286923, 308.3663072386247, 312.2552711782401, 5194.246834209042, 5247.396549396821, 5267.868303292415, 5271.0, 5289.709066001368, 4931.605471562275, 4954.603631709464, 5053.646244762868, 5086.821961231358, 5177.223416487529, 3318.7510936679723, 3960.732565579016, 4005.0175163294066, 4024.1086689256003, 4026.0136266614572, 3977.125032492847, 3986.9202615947665, 3988.7207895447186, 4002.259581977329, 4028.6777057835448, 3390.440577304742, 3501.7548771586, 3963.544192417866, 3966.325724626806, 3996.8601098961544, 3994.3409337459284, 3995.4805875236325, 3999.7800120993334, 4004.5394779600283, 4039.4551432597423, 3475.461303497962, 3500.879718490084, 3500.9474857877153, 3506.333796578642, 3515.5078289039557, 4848.2727590861305, 5001.8499445016705, 5034.572061374787, 5050.797968081272, 5127.974360128175, 4188.141431006637, 4275.866895272755, 4564.52072532386, 4570.200214962369, 4572.245579479382, 5457.536109430703, 5457.590680698924, 5477.561795056386, 5509.834704958857, 5529.225908372831, 3809.104860357814, 3835.42468629705, 3842.6541611254843, 3849.3648547989546, 3915.4909861717792, 5535.778568857241, 5675.460831221028, 5701.2018317435595, 5715.485606295411, 5727.021948586712, 3594.5866225383893, 3624.3838547447, 3880.941785873214, 3892.1826416452864, 3922.4508568800393, 3469.392856250146, 3691.7046636269033, 3840.5007349044445, 3956.5252169739238, 3981.402789581586, 3568.3398571264397, 4072.857449989295, 4290.871273861727, 4589.793459294273, 4590.655700822452, 4657.46439159501, 5329.786808527693, 5375.354957405137, 5432.782688692487, 5495.862603434946, 1198.8441502604605, 1272.5864094169433, 1326.7678156322604, 1359.9660008499668, 1371.3074897176862, 754.9207333230041, 762.9395140754356, 823.8764185372243, 839.8320335932838, 885.1369914333602, 4461.553844615577, 5026.824061157842, 5040.042391945519, 5060.84817455477, 5138.794448222029, 5185.7407129644125, 5535.501804837584, 5571.192177134293, 5575.386707462176, 5599.692016939026, 4268.492010817383, 4280.373458085907, 4929.605631549467, 4952.777125029383, 5093.6569178177715, 2487.4776296977475, 2915.1691767846064, 2919.1972207643066, 2921.28428535006, 2930.340673348522, 5176.715280659525, 5817.825465236049, 5832.708364581838, 5851.356350801493, 5880.706244626175, 5156.819511317086, 5361.0, 5837.416258374214, 5840.678762668094, 5846.883062338729, 4854.101991131652, 5447.319085114361, 5904.9999999999145, 5907.3797251288, 5955.166276721176, 5202.687838729688, 5268.051750684914, 5315.335583052119, 5356.464353564768, 5668.348139963957, 4782.354382158364, 4987.551120399182, 5677.3471050829685, 5677.687727175045, 5700.486956173964, 5958.404159584007, 5972.940270597413, 5976.0, 6006.639601623917, 6036.336003039574, 3530.7175425965347, 3760.172761992357, 3768.7550309229814, 3813.485179500843, 3834.559025712079, 5629.4652008059165, 5657.773689052392, 5665.971670141787, 5689.715514224355, 5695.658260504383, 5507.173923911285, 5518.041095411071, 5533.122776156457, 5547.944520554905, 5572.693501857275, 5188.870278243074, 5293.973530132324, 5396.974574830927, 5425.104857698593, 5520.53075488588, 5150.0, 5394.433584473573, 5395.838124856261, 5404.459554044564, 5416.6479178854825, 5367.913449766478, 5390.97304513475, 5418.214358918013, 5528.369131106253, 5542.750576223999, 4140.60664236897, 5392.301425051096, 5431.972840135774, 5444.564434845282, 5460.689434535762], "value": 4032.5636820025807, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.7784186902057, 729.9270072992699, 753.1338960195774, 808.7412028150994, 832.384035813498, 15328.54014379569, 15804.130772807519, 15959.0, 15984.520464386072, 15999.280032398541, 14342.21117838521, 14458.0, 14462.059966102222, 14469.782953255683, 14475.059121157145, 709.1808960650432, 730.0071902212986, 735.327175634294, 866.086278975682, 877.0746862060523, 886.6807949138305, 891.9643214271422, 901.5717534171249, 903.8418276801566, 904.6426661468716, 956.1585804492038, 957.1337939165032, 957.25812495316, 957.2629075611775, 957.5690939077408, 736.3225832234303, 759.4949358676473, 949.6818565780471, 951.8810148731409, 952.342883410448, 565.5362602665816, 566.122510109333, 566.3147591414394, 679.4089142446104, 701.7333413302963, 15180.0, 15229.61925951849, 15242.085474871432, 15246.542603721906, 15250.923745381202, 13687.863121368859, 13934.372953217027, 14014.22921739304, 14116.92941535296, 14364.569062928129, 12853.228806271654, 12854.485820567166, 12865.871341286562, 12993.805092923614, 13048.86951130486, 4071.42999980003, 4453.465584129925, 4516.616087632555, 4572.382728331666, 4705.717656940595, 114338.42830785876, 115007.84992150139, 117558.0, 119531.0, 119554.80445195523, 9121.361504694607, 11158.1631377647, 11171.66485005451, 11174.217804753589, 11383.943080284545, 22833.657495137588, 22837.429064273194, 22847.086116555485, 22860.88569557175, 22912.198073067364, 7869.685212591432, 7934.0, 7942.761717148494, 7986.7603971880935, 7995.920040799634, 4236.152769446123, 4281.97859010703, 4290.721103128287, 4311.9784401077795, 4422.380866678667, 3003.6245469316336, 3603.837827297778, 3609.8917032489067, 3612.1692010837655, 3612.8258316046863, 633.499535367064, 640.3820313397594, 652.210824901874, 656.0749343425747, 658.9950325753169, 586.302300262686, 598.6467983889526, 615.187951903492, 619.6344156947423, 619.7118339971935, 7781.8832717509285, 8754.51850148248, 8776.648934042567, 8893.155150260844, 9005.414648047856, 581.7207740284603, 584.0480017571332, 605.7788907048896, 629.7260691599168, 635.6726285962733, 4362.8036738346855, 4385.188740083095, 4436.711613745097, 4487.775611219426, 4529.977350113229, 123303.0, 128491.35754321169, 131739.34130329287, 132777.3361133188, 141082.0, 98151.0, 121012.0, 124508.0, 124538.94185343255, 125473.0, 71570.9264361035, 95177.52411238039, 96219.0, 107712.0, 108357.91642083478, 9291.231711941704, 9738.928717841174, 9753.960430544377, 9764.74666930822, 9882.505874706092, 46367.768161160326, 48980.0, 56369.82637517619, 57062.42937570571, 59507.80984380375, 81011.0, 88936.22127557542, 93414.53292733493, 93670.0, 96637.51681241549, 12535.310557919038, 12842.678933026375, 13292.734145317232, 13366.93316533411, 13554.322283885964, 13995.370208340446, 14257.50098746539, 14528.0, 14661.120332780067, 14758.778618320948, 13537.458501659823, 13557.11878727919, 13634.522791702242, 13776.93111534436, 13801.309934503033, 13454.327283635583, 13762.518311859038, 13791.379387927767, 14075.296235187994, 14140.86873050133, 11303.491342889754, 13129.474821007434, 13331.466741330238, 13517.59447216585, 13833.0, 11204.327740335606, 11458.4270786462, 11617.47721352558, 11632.941835290769, 11858.288502689866, 8925.509096999727, 9473.952630236805, 9784.276198423717, 11297.774044519228, 11558.248713833575], "value": 7736.078419566762, "unit": "score"}], "name": "dromaeo_css", "value": 7232.700763597338, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0, 114.0, 114.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 113.0, 116.0, 175.0, 138.0, 119.0, 115.0, 112.0, 145.0, 112.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 184.0, 185.0, 183.0, 183.0, 189.0, 258.0, 184.0, 180.0], "value": 191.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 60.0, 87.0, 66.0, 58.0, 59.0, 58.0, 83.0, 58.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 92.0, 70.0, 68.0, 69.0, 92.0, 67.0, 68.0, 89.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 87.0, 84.0, 87.0, 84.0, 88.0, 85.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 97.0, 97.0, 98.0, 96.0, 113.0, 160.0], "value": 104.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 82.0, 82.0, 82.0, 81.0, 83.0, 85.0, 82.0, 81.0], "value": 82.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 54.0, 54.0, 53.0, 57.0, 55.0, 54.0, 54.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 71.0, 75.0, 72.0, 69.0, 69.0, 70.0, 69.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 86.0, 89.0, 89.0, 85.0, 86.0, 86.0, 91.0, 86.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 148.0, 156.0, 155.0, 151.0, 140.0, 146.0, 176.0, 137.0], "value": 150.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 174.0, 174.0, 173.0, 174.0, 174.0, 173.0, 170.0, 172.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 84.0, 86.0, 86.0, 69.0, 69.0, 68.0, 69.0, 69.0, 69.0], "value": 73.2, "unit": "score"}], "name": "kraken", "value": 1459.8999999999999, "alertThreshold": 2.0}]} 23:03:48 INFO - Return code: 0 23:03:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:03:48 INFO - # TBPL SUCCESS # 23:03:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:03:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:03:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:03:48 INFO - 2016-10-18 23:03:48.343 screenresolution[1682:10459] starting screenresolution argv=screenresolution get 23:03:48 INFO - 2016-10-18 23:03:48.346 screenresolution[1682:10459] Display 0: 1600x1200x32@60 23:03:48 INFO - 2016-10-18 23:03:48.352 screenresolution[1683:10462] starting screenresolution argv=screenresolution list 23:03:48 INFO - Available Modes on Display 0 23:03:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:03:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:03:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:03:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:03:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:03:48 INFO - Intel Iris: 23:03:48 INFO - Chipset Model: Intel Iris 23:03:48 INFO - Type: GPU 23:03:48 INFO - Bus: Built-In 23:03:48 INFO - VRAM (Dynamic, Max): 1536 MB 23:03:48 INFO - Vendor: Intel (0x8086) 23:03:48 INFO - Device ID: 0x0a2e 23:03:48 INFO - Revision ID: 0x0009 23:03:48 INFO - Displays: 23:03:48 INFO - Display: 23:03:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:03:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:03:48 INFO - Display Serial Number: 1600x1200 60 23:03:48 INFO - Main Display: Yes 23:03:48 INFO - Mirror: Off 23:03:48 INFO - Online: Yes 23:03:48 INFO - Rotation: Supported 23:03:48 INFO - Return code: 0 23:03:48 INFO - Running post-action listener: _resource_record_post_action 23:03:48 INFO - [mozharness: 2016-10-19 06:03:48.595451Z] Finished run-tests step (success) 23:03:48 INFO - Running post-run listener: _resource_record_post_run 23:03:48 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 37417984; Write bytes: 707817472; Read time: 239; Write time: 1982 23:03:48 INFO - TinderboxPrint: CPU usage
53.6% 23:03:48 INFO - TinderboxPrint: I/O read bytes / time
37,417,984 / 239 23:03:48 INFO - TinderboxPrint: I/O write bytes / time
707,817,472 / 1,982 23:03:48 INFO - TinderboxPrint: CPU idle
2,253.8 (46.4%) 23:03:48 INFO - TinderboxPrint: CPU system
972.2 (20.0%) 23:03:48 INFO - TinderboxPrint: CPU user
1,631.7 (33.6%) 23:03:48 INFO - TinderboxPrint: Swap in / out
443,445,248 / 0 23:03:48 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395411968; Write bytes: 389199872; Read time: 29228; Write time: 786 23:03:48 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35488768; Write bytes: 298227712; Read time: 223; Write time: 1139 23:03:49 INFO - Running post-run listener: _upload_blobber_files 23:03:49 INFO - Blob upload gear active. 23:03:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:03:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:03:49 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:03:49 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:03:49 INFO - (blobuploader) - INFO - Open directory for files ... 23:03:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:03:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:03:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:03:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:03:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:03:50 INFO - (blobuploader) - INFO - Done attempting. 23:03:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:03:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:03:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:03:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:03:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:03:51 INFO - (blobuploader) - INFO - Done attempting. 23:03:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:03:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:03:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:03:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:03:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:03:54 INFO - (blobuploader) - INFO - Done attempting. 23:03:54 INFO - (blobuploader) - INFO - Iteration through files over. 23:03:54 INFO - Return code: 0 23:03:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:03:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:03:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/52fa143336db1fab11c5e9ee2e4032f5d4a37cfa70d407c4daa54c6669902b6aa986de614ce9680b1320889896314bc4638be2b8f52d0804ba152f36eb6fca6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66d242797db8e35df43181f1eab9687e96bf1e5e34b843b142ae3f9f12bff527d21b2179959addbf789244f673a0e4e92af3f150cf12bfe655d5443820367aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/974670798dae9945d74c40d43faee4baa21099697050729b0a6ba0c2eae2e140baea1d734d9cb55556563b748ce4e0912d749d170e512f3027da483f79cf8360"} 23:03:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:03:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:03:54 INFO - Contents: 23:03:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/52fa143336db1fab11c5e9ee2e4032f5d4a37cfa70d407c4daa54c6669902b6aa986de614ce9680b1320889896314bc4638be2b8f52d0804ba152f36eb6fca6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66d242797db8e35df43181f1eab9687e96bf1e5e34b843b142ae3f9f12bff527d21b2179959addbf789244f673a0e4e92af3f150cf12bfe655d5443820367aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/974670798dae9945d74c40d43faee4baa21099697050729b0a6ba0c2eae2e140baea1d734d9cb55556563b748ce4e0912d749d170e512f3027da483f79cf8360"} 23:03:54 INFO - Running post-run listener: copy_logs_to_upload_dir 23:03:54 INFO - Copying logs to upload dir... 23:03:54 INFO - mkdir: /builds/slave/test/build/upload/logs 23:03:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.429947 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-18 23:03:54.124282) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 23:03:54.128049) ========= 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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/52fa143336db1fab11c5e9ee2e4032f5d4a37cfa70d407c4daa54c6669902b6aa986de614ce9680b1320889896314bc4638be2b8f52d0804ba152f36eb6fca6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66d242797db8e35df43181f1eab9687e96bf1e5e34b843b142ae3f9f12bff527d21b2179959addbf789244f673a0e4e92af3f150cf12bfe655d5443820367aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/974670798dae9945d74c40d43faee4baa21099697050729b0a6ba0c2eae2e140baea1d734d9cb55556563b748ce4e0912d749d170e512f3027da483f79cf8360"} build_url:https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/52fa143336db1fab11c5e9ee2e4032f5d4a37cfa70d407c4daa54c6669902b6aa986de614ce9680b1320889896314bc4638be2b8f52d0804ba152f36eb6fca6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66d242797db8e35df43181f1eab9687e96bf1e5e34b843b142ae3f9f12bff527d21b2179959addbf789244f673a0e4e92af3f150cf12bfe655d5443820367aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/974670798dae9945d74c40d43faee4baa21099697050729b0a6ba0c2eae2e140baea1d734d9cb55556563b748ce4e0912d749d170e512f3027da483f79cf8360"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 23:03:54.161751) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 23:03:54.162137) ========= 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.l7XjBvMhXk/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.mP5fGwmgqB/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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.005152 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 23:03:54.211841) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 23:03:54.212197) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 23:03:54.212752) ========= ========= Total master_lag: 0.17 =========