builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0187 starttime: 1480656331.97 results: success (0) buildid: 20161201184344 builduid: ef297ffe42384c49abda34f6edfd7899 revision: 02fdf5d71d8e22357c82e0ab828544f4cd2f1250 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:31.974399) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:31.974952) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:31.975422) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:32.066486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:32.066811) ========= 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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-01 21:25:32-- 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% 8.12M=0.001s 2016-12-01 21:25:32 (8.12 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110060 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:32.200483) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:32.200780) ========= 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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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.026239 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:32.250164) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-01 21:25:32.250726) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 02fdf5d71d8e22357c82e0ab828544f4cd2f1250 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 02fdf5d71d8e22357c82e0ab828544f4cd2f1250 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EGtGoYJsdU/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.SyWSBmIzo3/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-01 21:25:32,348 truncating revision to first 12 chars 2016-12-01 21:25:32,348 Setting DEBUG logging. 2016-12-01 21:25:32,348 attempt 1/10 2016-12-01 21:25:32,348 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/02fdf5d71d8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-01 21:25:33,439 unpacking tar archive at: graphics-02fdf5d71d8e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.341623 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-01 21:25:33.617963) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:33.618411) ========= script_repo_revision: 02fdf5d71d8e22357c82e0ab828544f4cd2f1250 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:33.618892) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:33.619317) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 21:25:33.640162) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-01 21:25:33.640559) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EGtGoYJsdU/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.SyWSBmIzo3/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 21:25:33 INFO - MultiFileLogger online at 20161201 21:25:33 in /builds/slave/test 21:25:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 21:25:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:25:33 INFO - {'append_to_log': False, 21:25:33 INFO - 'base_work_dir': '/builds/slave/test', 21:25:33 INFO - 'blob_upload_branch': 'Graphics', 21:25:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:25:33 INFO - 'branch': 'Graphics', 21:25:33 INFO - 'buildbot_json_path': 'buildprops.json', 21:25:33 INFO - 'config_files': ('talos/mac_config.py',), 21:25:33 INFO - 'default_actions': ('clobber', 21:25:33 INFO - 'read-buildbot-config', 21:25:33 INFO - 'download-and-extract', 21:25:33 INFO - 'populate-webroot', 21:25:33 INFO - 'create-virtualenv', 21:25:33 INFO - 'install', 21:25:33 INFO - 'run-tests'), 21:25:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:25:33 INFO - 'download_minidump_stackwalk': True, 21:25:33 INFO - 'download_symbols': 'ondemand', 21:25:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:25:33 INFO - 'tooltool.py': '/tools/tooltool.py', 21:25:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:25:33 INFO - '/tools/misc-python/virtualenv.py')}, 21:25:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:25:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:25:33 INFO - 'installer_path': 'installer.exe', 21:25:33 INFO - 'log_level': 'info', 21:25:33 INFO - 'log_name': 'talos', 21:25:33 INFO - 'log_to_console': True, 21:25:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:25:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:25:33 INFO - 'opt_config_files': (), 21:25:33 INFO - 'pip_index': False, 21:25:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:25:33 INFO - 'cmd': ('bash', 21:25:33 INFO - '-c', 21:25:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:25:33 INFO - 'enabled': True, 21:25:33 INFO - 'halt_on_failure': False, 21:25:33 INFO - 'name': 'check_screen_resolution'},), 21:25:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:25:33 INFO - 'cmd': ('bash', 21:25:33 INFO - '-c', 21:25:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:25:33 INFO - 'enabled': True, 21:25:33 INFO - 'halt_on_failure': False, 21:25:33 INFO - 'name': 'check_screen_resolution'},), 21:25:33 INFO - 'run_cmd_checks_enabled': True, 21:25:33 INFO - 'sps_profile': False, 21:25:33 INFO - 'sps_profile_interval': 0, 21:25:33 INFO - 'suite': 'dromaeojs-e10s', 21:25:33 INFO - 'system_bits': '32', 21:25:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:25:33 INFO - 'title': 't-yosemite-r7-0187', 21:25:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:25:33 INFO - 'use_talos_json': True, 21:25:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:25:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:25:33 INFO - 'work_dir': 'build'} 21:25:33 INFO - [mozharness: 2016-12-02 05:25:33.857820Z] Running clobber step. 21:25:33 INFO - Running pre-action listener: _resource_record_pre_action 21:25:33 INFO - Running main action method: clobber 21:25:33 INFO - rmtree: /builds/slave/test/build 21:25:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:25:36 INFO - Running post-action listener: _resource_record_post_action 21:25:36 INFO - [mozharness: 2016-12-02 05:25:36.438276Z] Finished clobber step (success) 21:25:36 INFO - [mozharness: 2016-12-02 05:25:36.438400Z] Running read-buildbot-config step. 21:25:36 INFO - Running pre-action listener: _resource_record_pre_action 21:25:36 INFO - Running main action method: read_buildbot_config 21:25:36 INFO - Using buildbot properties: 21:25:36 INFO - { 21:25:36 INFO - "project": "", 21:25:36 INFO - "product": "firefox", 21:25:36 INFO - "script_repo_revision": "default", 21:25:36 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 21:25:36 INFO - "repository": "", 21:25:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 21:25:36 INFO - "buildid": "20161201184344", 21:25:36 INFO - "pgo_build": "False", 21:25:36 INFO - "basedir": "/builds/slave/test", 21:25:36 INFO - "buildnumber": 1, 21:25:36 INFO - "slavename": "t-yosemite-r7-0187", 21:25:36 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 21:25:36 INFO - "platform": "yosemite_r7", 21:25:36 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 21:25:36 INFO - "branch": "graphics", 21:25:36 INFO - "repo_path": "projects/graphics", 21:25:36 INFO - "revision": "02fdf5d71d8e22357c82e0ab828544f4cd2f1250", 21:25:36 INFO - "stage_platform": "macosx64", 21:25:36 INFO - "builduid": "ef297ffe42384c49abda34f6edfd7899", 21:25:36 INFO - "slavebuilddir": "test" 21:25:36 INFO - } 21:25:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 21:25:36 INFO - Running post-action listener: _resource_record_post_action 21:25:36 INFO - [mozharness: 2016-12-02 05:25:36.441660Z] Finished read-buildbot-config step (success) 21:25:36 INFO - [mozharness: 2016-12-02 05:25:36.441774Z] Running download-and-extract step. 21:25:36 INFO - Running pre-action listener: _resource_record_pre_action 21:25:36 INFO - Running main action method: download_and_extract 21:25:36 INFO - mkdir: /builds/slave/test/build/tests 21:25:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:25:36 INFO - trying https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 21:25:36 INFO - Downloading https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:25:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 21:25:37 INFO - Downloaded 1199 bytes. 21:25:37 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:25:37 INFO - Using the following test package requirements: 21:25:37 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:25:37 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:25:37 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 21:25:37 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:25:37 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 21:25:37 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:25:37 INFO - u'jsshell-mac.zip'], 21:25:37 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:25:37 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 21:25:37 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:25:37 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:25:37 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 21:25:37 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:25:37 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 21:25:37 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:25:37 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 21:25:37 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:25:37 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 21:25:37 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:25:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:25:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:25:37 INFO - Fetch https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:25:37 INFO - Http code: 200 21:25:37 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 21:25:37 INFO - x-amz-cf-id: DtKXoQW8xtm9oP4wIDsbmKtng5O93OYVXBxjZXNbfMyrny9f8FgfKg== 21:25:37 INFO - x-amz-version-id: OkqVmytp.O3J7zYVeu0X1.Ke50_KsH84 21:25:37 INFO - Expected file size: 16282551 21:25:37 INFO - Obtained file size: 16282551 21:25:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:25:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:25:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:25:44 INFO - Fetch https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:25:45 INFO - Http code: 200 21:25:45 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 21:25:45 INFO - x-amz-cf-id: 0JM37s-Ro91TRSVu9aRi8vjCQtcnBVfr5Lgo9VhtOl_9IMvm8F007Q== 21:25:45 INFO - x-amz-version-id: OkqVmytp.O3J7zYVeu0X1.Ke50_KsH84 21:25:45 INFO - Expected file size: 16282551 21:25:45 INFO - Obtained file size: 16282551 21:25:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 21:25:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:25:51 INFO - Fetch https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 21:25:52 INFO - Http code: 200 21:25:52 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 21:25:52 INFO - x-amz-cf-id: NYzCmPduyD4rnjtmSEmV4SACY2wfx9w-KRBucU8v1QNRgmT8NvRH9w== 21:25:52 INFO - x-amz-version-id: 1iUHVUIvYtNxJyZJnk2GlrI5QnAWfi3. 21:25:52 INFO - Expected file size: 14409702 21:25:52 INFO - Obtained file size: 14409702 21:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:25:52 INFO - trying https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:25:52 INFO - Downloading https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 21:25:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 21:25:58 INFO - Downloaded 107607634 bytes. 21:25:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:25:58 INFO - mkdir: /builds/slave/test/properties 21:25:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:25:58 INFO - Writing to file /builds/slave/test/properties/build_url 21:25:58 INFO - Contents: 21:25:58 INFO - build_url:https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:25:58 INFO - Running post-action listener: _resource_record_post_action 21:25:58 INFO - Running post-action listener: set_extra_try_arguments 21:25:58 INFO - [mozharness: 2016-12-02 05:25:58.600223Z] Finished download-and-extract step (success) 21:25:58 INFO - [mozharness: 2016-12-02 05:25:58.600358Z] Running populate-webroot step. 21:25:58 INFO - Running pre-action listener: _resource_record_pre_action 21:25:58 INFO - Running main action method: populate_webroot 21:25:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:25:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:25:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:25:58 INFO - '--tppagecycles', 21:25:58 INFO - '7'], 21:25:58 INFO - 'tests': ['tsvgm']}}, 21:25:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:25:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:25:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:25:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:25:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:25:58 INFO - 'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:25:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:25:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:25:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:25:58 INFO - 'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['damp', 'tps']}, 21:25:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:25:58 INFO - 'tests': ['damp', 'tps']}, 21:25:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['dromaeo_dom']}, 21:25:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:25:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['basic_compositor_video']}, 21:25:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:25:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['a11yr', 21:25:58 INFO - 'ts_paint', 21:25:58 INFO - 'tpaint', 21:25:58 INFO - 'sessionrestore', 21:25:58 INFO - 'sessionrestore_no_auto_restore', 21:25:58 INFO - 'tabpaint']}, 21:25:58 INFO - 'other-e10s': {'tests': ['a11yr', 21:25:58 INFO - 'ts_paint', 21:25:58 INFO - 'tpaint', 21:25:58 INFO - 'sessionrestore', 21:25:58 INFO - 'sessionrestore_no_auto_restore', 21:25:58 INFO - 'tabpaint']}, 21:25:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:25:58 INFO - 'ts_paint', 21:25:58 INFO - 'tpaint', 21:25:58 INFO - 'sessionrestore', 21:25:58 INFO - 'sessionrestore_no_auto_restore', 21:25:58 INFO - 'tabpaint']}, 21:25:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:25:58 INFO - 'ts_paint', 21:25:58 INFO - 'tpaint', 21:25:58 INFO - 'sessionrestore', 21:25:58 INFO - 'sessionrestore_no_auto_restore', 21:25:58 INFO - 'tabpaint']}, 21:25:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['a11yr', 21:25:58 INFO - 'ts_paint', 21:25:58 INFO - 'tpaint', 21:25:58 INFO - 'sessionrestore', 21:25:58 INFO - 'sessionrestore_no_auto_restore', 21:25:58 INFO - 'tabpaint']}, 21:25:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['a11yr', 21:25:58 INFO - 'ts_paint', 21:25:58 INFO - 'tpaint', 21:25:58 INFO - 'sessionrestore', 21:25:58 INFO - 'sessionrestore_no_auto_restore', 21:25:58 INFO - 'tabpaint']}, 21:25:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['tsvgx', 21:25:58 INFO - 'tsvgr_opacity', 21:25:58 INFO - 'tart', 21:25:58 INFO - 'tscrollx', 21:25:58 INFO - 'cart', 21:25:58 INFO - 'tsvg_static']}, 21:25:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:25:58 INFO - 'tsvgr_opacity', 21:25:58 INFO - 'tart', 21:25:58 INFO - 'tscrollx', 21:25:58 INFO - 'cart', 21:25:58 INFO - 'tsvg_static']}, 21:25:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:25:58 INFO - 'talos_options': ['--disable-e10s'], 21:25:58 INFO - 'tests': ['tp5o']}, 21:25:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:25:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:25:58 INFO - 'talos_options': ['--disable-e10s', 21:25:58 INFO - '--xperf_path', 21:25:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:25:58 INFO - 'tests': ['tp5n']}, 21:25:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:25:58 INFO - 'talos_options': ['--xperf_path', 21:25:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:25:58 INFO - 'tests': ['tp5n']}}, 21:25:58 INFO - 'talos.zip': {'path': '', 21:25:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:25:58 INFO - Running post-action listener: _resource_record_post_action 21:25:58 INFO - [mozharness: 2016-12-02 05:25:58.618686Z] Finished populate-webroot step (success) 21:25:58 INFO - [mozharness: 2016-12-02 05:25:58.618799Z] Running create-virtualenv step. 21:25:58 INFO - Running pre-action listener: _resource_record_pre_action 21:25:58 INFO - Running main action method: create_virtualenv 21:25:58 INFO - Creating virtualenv /builds/slave/test/build/venv 21:25:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:25:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:25:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:25:58 INFO - Using real prefix '/tools/python27' 21:25:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:25:59 INFO - Installing distribute.............................................................................................................................................................................................done. 21:26:02 INFO - Installing pip.................done. 21:26:02 INFO - Return code: 0 21:26:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:26:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:26:02 INFO - Reading from file tmpfile_stdout 21:26:02 INFO - Output received: 21:26:02 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:26:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108de23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d84960b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, '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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:26:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:26:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:26:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:26:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:02 INFO - 'HOME': '/Users/cltbld', 21:26:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:02 INFO - 'LOGNAME': 'cltbld', 21:26:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:02 INFO - 'MOZ_NO_REMOTE': '1', 21:26:02 INFO - 'NO_EM_RESTART': '1', 21:26:02 INFO - 'PAGER': '/bin/cat', 21:26:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:02 INFO - 'PWD': '/builds/slave/test', 21:26:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:02 INFO - 'SHELL': '/bin/bash', 21:26:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:26:02 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:26:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:02 INFO - 'USER': 'cltbld', 21:26:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:02 INFO - 'XPC_FLAGS': '0x0', 21:26:02 INFO - 'XPC_SERVICE_NAME': '0', 21:26:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:26:02 INFO - Cleaning up... 21:26:02 INFO - Return code: 0 21:26:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108de23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d84960b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, '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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:26:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:26:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:26:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:26:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:02 INFO - 'HOME': '/Users/cltbld', 21:26:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:02 INFO - 'LOGNAME': 'cltbld', 21:26:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:02 INFO - 'MOZ_NO_REMOTE': '1', 21:26:02 INFO - 'NO_EM_RESTART': '1', 21:26:02 INFO - 'PAGER': '/bin/cat', 21:26:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:02 INFO - 'PWD': '/builds/slave/test', 21:26:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:02 INFO - 'SHELL': '/bin/bash', 21:26:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:26:02 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:26:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:02 INFO - 'USER': 'cltbld', 21:26:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:02 INFO - 'XPC_FLAGS': '0x0', 21:26:02 INFO - 'XPC_SERVICE_NAME': '0', 21:26:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:03 INFO - Downloading/unpacking psutil>=3.1.1 21:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:26:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:26:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:26:07 INFO - Installing collected packages: psutil 21:26:07 INFO - Running setup.py install for psutil 21:26:07 INFO - building 'psutil._psutil_osx' extension 21:26:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:26:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 21:26:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 21:26:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 21:26:07 INFO - building 'psutil._psutil_posix' extension 21:26:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 21:26:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:26:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:26:08 INFO - ^ 21:26:08 INFO - 1 warning generated. 21:26:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 21:26:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:26:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:26:08 INFO - Successfully installed psutil 21:26:08 INFO - Cleaning up... 21:26:08 INFO - Return code: 0 21:26:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:08 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 0x108de23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d84960b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, '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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:08 INFO - 'HOME': '/Users/cltbld', 21:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:08 INFO - 'LOGNAME': 'cltbld', 21:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:08 INFO - 'MOZ_NO_REMOTE': '1', 21:26:08 INFO - 'NO_EM_RESTART': '1', 21:26:08 INFO - 'PAGER': '/bin/cat', 21:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:08 INFO - 'PWD': '/builds/slave/test', 21:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:08 INFO - 'SHELL': '/bin/bash', 21:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:26:08 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:08 INFO - 'USER': 'cltbld', 21:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:08 INFO - 'XPC_FLAGS': '0x0', 21:26:08 INFO - 'XPC_SERVICE_NAME': '0', 21:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:26:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:26:11 INFO - Installing collected packages: mozsystemmonitor 21:26:11 INFO - Running setup.py install for mozsystemmonitor 21:26:11 INFO - Successfully installed mozsystemmonitor 21:26:11 INFO - Cleaning up... 21:26:11 INFO - Return code: 0 21:26:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:11 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 0x108de23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d84960b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, '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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:26:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:26:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:11 INFO - 'HOME': '/Users/cltbld', 21:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:11 INFO - 'LOGNAME': 'cltbld', 21:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:11 INFO - 'MOZ_NO_REMOTE': '1', 21:26:11 INFO - 'NO_EM_RESTART': '1', 21:26:11 INFO - 'PAGER': '/bin/cat', 21:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:11 INFO - 'PWD': '/builds/slave/test', 21:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:11 INFO - 'SHELL': '/bin/bash', 21:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:26:11 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:11 INFO - 'USER': 'cltbld', 21:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:11 INFO - 'XPC_FLAGS': '0x0', 21:26:11 INFO - 'XPC_SERVICE_NAME': '0', 21:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:11 INFO - Downloading/unpacking jsonschema==2.5.1 21:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:26:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:14 INFO - Downloading functools32-3.2.3-2.tar.gz 21:26:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:26:14 INFO - warning: no files found matching '*.txt' 21:26:14 INFO - no previously-included directories found matching 'build' 21:26:14 INFO - no previously-included directories found matching 'dist' 21:26:14 INFO - no previously-included directories found matching '.git*' 21:26:14 INFO - Installing collected packages: jsonschema, functools32 21:26:14 INFO - Running setup.py install for functools32 21:26:14 INFO - warning: no files found matching '*.txt' 21:26:14 INFO - no previously-included directories found matching 'build' 21:26:14 INFO - no previously-included directories found matching 'dist' 21:26:14 INFO - no previously-included directories found matching '.git*' 21:26:14 INFO - Successfully installed jsonschema functools32 21:26:14 INFO - Cleaning up... 21:26:15 INFO - Return code: 0 21:26:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:15 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 0x108de23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d84960b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, '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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:26:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:15 INFO - 'HOME': '/Users/cltbld', 21:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:15 INFO - 'LOGNAME': 'cltbld', 21:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:15 INFO - 'MOZ_NO_REMOTE': '1', 21:26:15 INFO - 'NO_EM_RESTART': '1', 21:26:15 INFO - 'PAGER': '/bin/cat', 21:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:15 INFO - 'PWD': '/builds/slave/test', 21:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:15 INFO - 'SHELL': '/bin/bash', 21:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:26:15 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:15 INFO - 'USER': 'cltbld', 21:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:15 INFO - 'XPC_FLAGS': '0x0', 21:26:15 INFO - 'XPC_SERVICE_NAME': '0', 21:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:26:15 INFO - Cleaning up... 21:26:15 INFO - Return code: 0 21:26:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:15 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 0x108de23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d84960b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, '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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:26:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:15 INFO - 'HOME': '/Users/cltbld', 21:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:15 INFO - 'LOGNAME': 'cltbld', 21:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:15 INFO - 'MOZ_NO_REMOTE': '1', 21:26:15 INFO - 'NO_EM_RESTART': '1', 21:26:15 INFO - 'PAGER': '/bin/cat', 21:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:15 INFO - 'PWD': '/builds/slave/test', 21:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:15 INFO - 'SHELL': '/bin/bash', 21:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:26:15 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:15 INFO - 'USER': 'cltbld', 21:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:15 INFO - 'XPC_FLAGS': '0x0', 21:26:15 INFO - 'XPC_SERVICE_NAME': '0', 21:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:15 INFO - Downloading/unpacking blobuploader==1.2.4 21:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:18 INFO - Downloading blobuploader-1.2.4.tar.gz 21:26:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:26:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:26:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:19 INFO - Downloading docopt-0.6.1.tar.gz 21:26:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:26:19 INFO - Installing collected packages: blobuploader, requests, docopt 21:26:19 INFO - Running setup.py install for blobuploader 21:26:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:26:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:26:19 INFO - Running setup.py install for requests 21:26:20 INFO - Running setup.py install for docopt 21:26:20 INFO - Successfully installed blobuploader requests docopt 21:26:20 INFO - Cleaning up... 21:26:20 INFO - Return code: 0 21:26:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:20 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 0x108de23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d84960b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, '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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:26:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:26:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:26:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:26:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:20 INFO - 'HOME': '/Users/cltbld', 21:26:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:20 INFO - 'LOGNAME': 'cltbld', 21:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:20 INFO - 'MOZ_NO_REMOTE': '1', 21:26:20 INFO - 'NO_EM_RESTART': '1', 21:26:20 INFO - 'PAGER': '/bin/cat', 21:26:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:20 INFO - 'PWD': '/builds/slave/test', 21:26:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:20 INFO - 'SHELL': '/bin/bash', 21:26:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:26:20 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:26:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:20 INFO - 'USER': 'cltbld', 21:26:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:20 INFO - 'XPC_FLAGS': '0x0', 21:26:20 INFO - 'XPC_SERVICE_NAME': '0', 21:26:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:26:20 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Qg7yI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:26:20 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-loWlLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:26:20 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-XDZkMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:26:20 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-RILqHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:26:20 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-5B0HxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:26:21 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-fyGyOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:26:21 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-TVbUsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:26:21 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-zxpr_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:26:21 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-AFlhJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:26:21 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-C6IOVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:26:21 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Cmycnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:26:21 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-PgO3Ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:26:21 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-0KpIdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:26:21 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-BmilYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:26:21 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-YEVNSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:26:22 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-vPGC3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:26:22 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-u8l1pt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:26:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:26:22 INFO - Running setup.py install for manifestparser 21:26:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:26:22 INFO - Running setup.py install for mozcrash 21:26:22 INFO - Running setup.py install for mozdebug 21:26:22 INFO - Running setup.py install for mozdevice 21:26:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:26:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:26:22 INFO - Running setup.py install for mozfile 21:26:23 INFO - Running setup.py install for mozhttpd 21:26:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:26:23 INFO - Running setup.py install for mozinfo 21:26:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:26:23 INFO - Running setup.py install for mozInstall 21:26:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:26:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:26:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:26:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:26:23 INFO - Running setup.py install for mozleak 21:26:23 INFO - Running setup.py install for mozlog 21:26:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:26:23 INFO - Running setup.py install for moznetwork 21:26:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:26:23 INFO - Running setup.py install for mozprocess 21:26:24 INFO - Running setup.py install for mozprofile 21:26:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:26:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:26:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:26:24 INFO - Running setup.py install for mozrunner 21:26:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:26:24 INFO - Running setup.py install for mozscreenshot 21:26:24 INFO - Running setup.py install for moztest 21:26:24 INFO - Running setup.py install for mozversion 21:26:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:26:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:26:24 INFO - Cleaning up... 21:26:24 INFO - Return code: 0 21:26:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:24 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 0x108de23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d84960b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, '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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:26:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:26:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:26:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:26:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:24 INFO - 'HOME': '/Users/cltbld', 21:26:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:24 INFO - 'LOGNAME': 'cltbld', 21:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:24 INFO - 'MOZ_NO_REMOTE': '1', 21:26:24 INFO - 'NO_EM_RESTART': '1', 21:26:24 INFO - 'PAGER': '/bin/cat', 21:26:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:24 INFO - 'PWD': '/builds/slave/test', 21:26:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:24 INFO - 'SHELL': '/bin/bash', 21:26:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:26:24 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:26:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:24 INFO - 'USER': 'cltbld', 21:26:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:24 INFO - 'XPC_FLAGS': '0x0', 21:26:24 INFO - 'XPC_SERVICE_NAME': '0', 21:26:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:26:25 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-9uHvbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:26:25 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-JQUKcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:26:25 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-XgLy87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:26:25 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-yOUyFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:26:25 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-KAoRhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:26:25 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-hqCRC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:26:25 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-1OyQfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:26:25 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-F1zXhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:26:25 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-FUxbFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:26:26 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-jFEun_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:26:26 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Ki1vMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:26:26 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-JfV2qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:26:26 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-d1_Nks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:26:26 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-LkaE1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:26:26 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-dIAW2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:26:26 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-z8vSyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:26:26 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-JnPiar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:26:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:29 INFO - Downloading blessings-1.6.tar.gz 21:26:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:26:29 INFO - Installing collected packages: blessings 21:26:29 INFO - Running setup.py install for blessings 21:26:29 INFO - Successfully installed blessings 21:26:29 INFO - Cleaning up... 21:26:29 INFO - Return code: 0 21:26:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:26:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:26:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:26:30 INFO - Reading from file tmpfile_stdout 21:26:30 INFO - Current package versions: 21:26:30 INFO - blessings == 1.6 21:26:30 INFO - blobuploader == 1.2.4 21:26:30 INFO - docopt == 0.6.1 21:26:30 INFO - functools32 == 3.2.3-2 21:26:30 INFO - jsonschema == 2.5.1 21:26:30 INFO - manifestparser == 1.1 21:26:30 INFO - mozInstall == 1.12 21:26:30 INFO - mozcrash == 1.0 21:26:30 INFO - mozdebug == 0.1 21:26:30 INFO - mozdevice == 0.49 21:26:30 INFO - mozfile == 1.2 21:26:30 INFO - mozhttpd == 0.7 21:26:30 INFO - mozinfo == 0.9 21:26:30 INFO - mozleak == 0.1 21:26:30 INFO - mozlog == 3.4 21:26:30 INFO - moznetwork == 0.27 21:26:30 INFO - mozprocess == 0.23 21:26:30 INFO - mozprofile == 0.28 21:26:30 INFO - mozrunner == 6.13 21:26:30 INFO - mozscreenshot == 0.1 21:26:30 INFO - mozsystemmonitor == 0.3 21:26:30 INFO - moztest == 0.7 21:26:30 INFO - mozversion == 1.4 21:26:30 INFO - psutil == 3.1.1 21:26:30 INFO - requests == 1.2.3 21:26:30 INFO - wsgiref == 0.1.2 21:26:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:30 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 0x108de23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d84960b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, '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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:26:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:30 INFO - 'HOME': '/Users/cltbld', 21:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:30 INFO - 'LOGNAME': 'cltbld', 21:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:30 INFO - 'MOZ_NO_REMOTE': '1', 21:26:30 INFO - 'NO_EM_RESTART': '1', 21:26:30 INFO - 'PAGER': '/bin/cat', 21:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:30 INFO - 'PWD': '/builds/slave/test', 21:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:30 INFO - 'SHELL': '/bin/bash', 21:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:26:30 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:30 INFO - 'USER': 'cltbld', 21:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:30 INFO - 'XPC_FLAGS': '0x0', 21:26:30 INFO - 'XPC_SERVICE_NAME': '0', 21:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:26:30 INFO - Cleaning up... 21:26:30 INFO - Return code: 0 21:26:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:30 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 0x108de23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d84960b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, '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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:26:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:30 INFO - 'HOME': '/Users/cltbld', 21:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:30 INFO - 'LOGNAME': 'cltbld', 21:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:30 INFO - 'MOZ_NO_REMOTE': '1', 21:26:30 INFO - 'NO_EM_RESTART': '1', 21:26:30 INFO - 'PAGER': '/bin/cat', 21:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:30 INFO - 'PWD': '/builds/slave/test', 21:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:30 INFO - 'SHELL': '/bin/bash', 21:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:26:30 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:30 INFO - 'USER': 'cltbld', 21:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:30 INFO - 'XPC_FLAGS': '0x0', 21:26:30 INFO - 'XPC_SERVICE_NAME': '0', 21:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:26:30 INFO - Cleaning up... 21:26:30 INFO - Return code: 0 21:26:30 INFO - Running post-action listener: _resource_record_post_action 21:26:30 INFO - Running post-action listener: _start_resource_monitoring 21:26:30 INFO - Starting resource monitoring. 21:26:30 INFO - [mozharness: 2016-12-02 05:26:30.714967Z] Finished create-virtualenv step (success) 21:26:30 INFO - [mozharness: 2016-12-02 05:26:30.716026Z] Running install step. 21:26:30 INFO - Running pre-action listener: _resource_record_pre_action 21:26:30 INFO - Running main action method: install 21:26:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:26:31 INFO - Reading from file tmpfile_stdout 21:26:31 INFO - Detecting whether we're running mozinstall >=1.0... 21:26:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:26:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:26:31 INFO - Reading from file tmpfile_stdout 21:26:31 INFO - Output received: 21:26:31 INFO - Usage: mozinstall [options] installer 21:26:31 INFO - Options: 21:26:31 INFO - -h, --help show this help message and exit 21:26:31 INFO - -d DEST, --destination=DEST 21:26:31 INFO - Directory to install application into. [default: 21:26:31 INFO - "/builds/slave/test"] 21:26:31 INFO - --app=APP Application being installed. [default: firefox] 21:26:31 INFO - mkdir: /builds/slave/test/build/application 21:26:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:26:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:27:03 INFO - Reading from file tmpfile_stdout 21:27:03 INFO - Output received: 21:27:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:27:03 INFO - Running post-action listener: _resource_record_post_action 21:27:03 INFO - [mozharness: 2016-12-02 05:27:03.610449Z] Finished install step (success) 21:27:03 INFO - [mozharness: 2016-12-02 05:27:03.610607Z] Running run-tests step. 21:27:03 INFO - Running pre-action listener: _resource_record_pre_action 21:27:03 INFO - Running main action method: run_tests 21:27:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:27:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:27:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:27:03 INFO - 2016-12-01 21:27:03.624 screenresolution[1628:8555] starting screenresolution argv=screenresolution get 21:27:03 INFO - 2016-12-01 21:27:03.627 screenresolution[1628:8555] Display 0: 1600x1200x32@60 21:27:03 INFO - 2016-12-01 21:27:03.633 screenresolution[1629:8557] starting screenresolution argv=screenresolution list 21:27:03 INFO - Available Modes on Display 0 21:27:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:27:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:27:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:27:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:27:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:27:03 INFO - Intel Iris: 21:27:03 INFO - Chipset Model: Intel Iris 21:27:03 INFO - Type: GPU 21:27:03 INFO - Bus: Built-In 21:27:03 INFO - VRAM (Dynamic, Max): 1536 MB 21:27:03 INFO - Vendor: Intel (0x8086) 21:27:03 INFO - Device ID: 0x0a2e 21:27:03 INFO - Revision ID: 0x0009 21:27:03 INFO - Displays: 21:27:03 INFO - Display: 21:27:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:27:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:27:03 INFO - Display Serial Number: 1600x1200 60 21:27:03 INFO - Main Display: Yes 21:27:03 INFO - Mirror: Off 21:27:03 INFO - Online: Yes 21:27:03 INFO - Rotation: Supported 21:27:03 INFO - Return code: 0 21:27:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:27:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:27:03 INFO - Python 2.7.3 21:27:03 INFO - Return code: 0 21:27:03 INFO - grabbing minidump binary from tooltool 21:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:27:03 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bf8d30>, '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 0x108a5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:27:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:27:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 21:27:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:27:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:27:03 INFO - Return code: 0 21:27:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:27:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:27:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:27:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:27:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:27:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0187', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:27:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0187 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:27:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EGtGoYJsdU/Render', 21:27:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:27:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:27:03 INFO - 'HOME': '/Users/cltbld', 21:27:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:27:03 INFO - 'LOGNAME': 'cltbld', 21:27:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:27:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:27:03 INFO - 'MOZ_NO_REMOTE': '1', 21:27:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:27:04 INFO - 'NO_EM_RESTART': '1', 21:27:04 INFO - 'PAGER': '/bin/cat', 21:27:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:27:04 INFO - 'PWD': '/builds/slave/test', 21:27:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:27:04 INFO - 'SHELL': '/bin/bash', 21:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyWSBmIzo3/Listeners', 21:27:04 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 21:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:27:04 INFO - 'USER': 'cltbld', 21:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:27:04 INFO - 'XPC_FLAGS': '0x0', 21:27:04 INFO - 'XPC_SERVICE_NAME': '0', 21:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:27:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0187', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:27:04 INFO - mozversion application_buildid: 20161201184344 21:27:04 INFO - mozversion application_changeset: 02fdf5d71d8e22357c82e0ab828544f4cd2f1250 21:27:04 INFO - mozversion application_display_name: Nightly 21:27:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:27:04 INFO - mozversion application_name: Firefox 21:27:04 INFO - mozversion application_remotingname: firefox 21:27:04 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 21:27:04 INFO - mozversion application_vendor: Mozilla 21:27:04 INFO - mozversion application_version: 53.0a1 21:27:04 INFO - mozversion platform_buildid: 20161201184344 21:27:04 INFO - mozversion platform_changeset: 02fdf5d71d8e22357c82e0ab828544f4cd2f1250 21:27:04 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 21:27:04 INFO - mozversion platform_version: 53.0a1 21:27:04 INFO - using testdate: 1480656424 21:27:04 INFO - actual date: 1480656424 21:27:04 INFO - starting webserver on 'localhost:49235' 21:27:04 INFO - SUITE-START | Running 2 tests 21:27:04 INFO - TEST-START | dromaeo_css 21:27:04 INFO - operating with platform_type : mac_ 21:27:04 INFO - Initialising browser for dromaeo_css test... 21:27:04 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpTQ2myW/profile http://localhost:49235/getInfo.html) 21:27:07 INFO - PROCESS | 1639 | 2016-12-01 21:27:07.690 plugin-container[1641:8848] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9143, name = 'com.apple.tsm.portname' 21:27:07 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:27:07 INFO - PROCESS | 1639 | 2016-12-01 21:27:07.693 plugin-container[1641:8848] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9613, name = 'com.apple.CFPasteboardClient' 21:27:07 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:27:07 INFO - PROCESS | 1639 | 2016-12-01 21:27:07.694 plugin-container[1641:8848] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:27:07 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 21:27:07 INFO - PROCESS | 1639 | colorDepth:24 21:27:07 INFO - PROCESS | 1639 | Browser inner width/height: 1024/663 21:27:07 INFO - PROCESS | 1639 | __metrics 21:27:07 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 21:27:08 INFO - TEST-INFO | 1639: exit 0 21:27:08 INFO - Browser initialized. 21:27:08 INFO - Running cycle 1/1 for dromaeo_css test... 21:27:08 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpTQ2myW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:27:11 INFO - PROCESS | 1643 | 2016-12-01 21:27:11.479 plugin-container[1644:8973] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 21:27:11 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:27:11 INFO - PROCESS | 1643 | 2016-12-01 21:27:11.480 plugin-container[1644:8973] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 21:27:11 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:27:11 INFO - PROCESS | 1643 | 2016-12-01 21:27:11.480 plugin-container[1644:8973] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:27:21 INFO - PROCESS | 1643 | RSS: Main: 251965440 21:27:21 INFO - PROCESS | 1643 | 21:28:10 INFO - PROCESS | 1643 | 1480656490665 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:30:25 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 21:30:27 INFO - PROCESS | 1643 | RSS: Main: 257593344 21:30:27 INFO - PROCESS | 1643 | 21:33:29 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 21:33:30 INFO - PROCESS | 1643 | RSS: Main: 268963840 21:33:30 INFO - PROCESS | 1643 | 21:36:32 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 21:36:32 INFO - PROCESS | 1643 | RSS: Main: 273440768 21:36:32 INFO - PROCESS | 1643 | 21:39:34 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 21:39:35 INFO - PROCESS | 1643 | RSS: Main: 273080320 21:39:35 INFO - PROCESS | 1643 | 21:42:38 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 21:42:38 INFO - PROCESS | 1643 | RSS: Main: 277434368 21:42:38 INFO - PROCESS | 1643 | 21:45:41 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 21:45:41 INFO - PROCESS | 1643 | RSS: Main: 280100864 21:45:41 INFO - PROCESS | 1643 | 21:45:41 INFO - PROCESS | 1643 | __start_tp_report 21:45:41 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 21:45:41 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 21:45:41 INFO - PROCESS | 1643 | |i|pagename|runs| 21:45:41 INFO - PROCESS | 1643 | |0;dojo.html;3143.874245030199;3428.348613763385;3458.676654182272;3475.4786781982716;3497.7551571390013;5684.658920464775;5769.884602307951;5804.042333015047;5851;5852.356240813512;5709.314882214129;5733.713314334287;5798.391168927265;5850.6782126983;5895.056790913465;5153.871153221181;5186.766595503193;5270.81552145675;5295.576353891679;5343.7328133593355;5246.632735708502;5370.785168593252;5381.434949330303;5412.675239485624;5441.102218134023;5127.9230811537855;5156.74216289184;5313.734313284321;5344.599155063386;5357.303550538424;5163.1480805667015;5204.42751297357;5267.47325267474;5304.416514183436;5350.357957045179;5234.973825130888;5238.92141617876;5250.369955605352;5253.290805741214;5271.472852714736;5642.463965923231;5689.0328644130595;5763.827085187451;5779.595428320019;5791.681457519835;6202.937970620326;6204.286507051702;6244.438000579924;6249.093881387219;6297.811065668037;6315.400036996524;6323.494120470351;6330.082138089997;6341.8097457076365;6346.777862774827;4755.667103302736;4961;5932.79235226765;5957.702114894238;5974.970125149346;5465.781368745285;6117.583823141483;6208.534359923024;6220.813375598739;6337.936620633827;5168.3797944246935;6202.245400117539;6214.533909956771;6233.844153896098;6266;5677.148427735872;5876.118582212703;6147.323794382614;6305.432511074026;6342.651154186564;6108.3280839107665;6126.639818174547;6148.385161483816;6159;6190.1952746142715;6192.628442293387;6195.349488303754;6200.0079987202735;6212.875742485216;6323.525735569759;4007.2586571484367;4944.060628480577;4950.925736113961;4991.925121123186;5027.296178535008;4368.453943257093;4416.933745993812;4437.511873693956;4441.289393696993;4461.08547747711;4813.302071199727;5914.881702366015;5919.6152250103605;5971.044632858721;6052.606580572248;6252.487067250317;6273.905891411632;6295.653739044396;6324.430801227912;6350;4314.878131685721;5886.969780288432;5968.552358573124;6044.425779550936;6069.726862291119;4773.11697335994;5423.132298832168;5446.673199608036;5488.945110548923;5532.170174473861;5355.848492574115;6408.702563083703;6415.839604009937;6453.290138084807;6459.418652321314;6274.090256912813;6276.74893004293;6279.529035322278;6316.368363163647;6335.493160547053;6252.499800016079;6313.1477250572425;6338.207724034496;6366.4588509977175;6370.203724534434;6272.435480806749;6274.372562743689;6277.686115694289;6281.968590157203;6307.873842523216;6286.842828929313;6306.716197771203;6313.1477250572425;6313.747450101944;6323.715432805627;6038.607490513103;6100.0849872519475;6126.571140020156;6161.9691901542;6198.318184999736;6116.42707509198;6117.644716737863;6136.3046386708;6144.955357589131;6149.692515374303;5891.823245302648;5995;6071.696415179135;6126.938730612636;6144.109104179958;6092.360302168386;6144.201253836973;6156.692165391623;6174.228221472316;6249.375062493714;6072.362401947732;6088.878222435438;6108.547790461711;6114.48026917717;6118.131909153847 21:45:41 INFO - PROCESS | 1643 | |1;ext.html;432.48102277267276;458.04497622457177;463.26341117623014;482.9734364609945;488.11894530372683;37839.43240851386;39097.609023909754;39103.2179356413;40908.59091409085;40994;39972.40041399381;40425.58510452135;40437.393439098436;40463.19073618533;40695.59304406955;8244.546549939781;9231.861522077174;9398.295127865438;9412.764680882965;9445.244380449582;8326.042505111898;10992.28550144242;11222.607208747695;11238.213325067249;11410.429478526083;9535.904640953606;11220.495077721522;11230.494627741771;11274.379909104997;11283.210175287733;11158.330500170016;11221.775564488746;11222.607208747655;11223.66329010131;11288.548458061668;11085.556577736881;11167.83248251277;11180.664580062612;11182;11214.775704485946;31434.528482072787;37703;37828.43257351142;37920.48318067274;38042.619573804186;3192.5530425740417;3302.9669703302898;3407.16524451511;3452.637473065317;3616.65641764032;29473.410531789457;29670.851645741634;29897;30112.24719382033;30648.2337941551;3194.7603929705365;3216.244182617083;3219.8551065201887;3299.686529779667;3331.350386674575;338772.3061384702;433831;439670;442027;443836.56163438276;56423.717881411154;56540.717296413255;56843.43156568464;56846.147307790416;56846.7157664209;58729.119063214086;58838.705806470694;59126;59165.704171478865;59248.40751592515;15081.924590377197;15432.92283538575;15465.845341546665;15496.915215934776;15505.147216902953;8964.596593153194;10849.457527123612;11084;11896.464659090188;11972.760544789055;15300;15636.92181539085;15710.921445392929;15721.764173537405;15839.841601584067;6090.482309003695;6913.89629155563;7059.964700176569;7076;7081.150261968493;837.5058715357968;870.9390342675952;941.6704153546204;962.2686758063921;975.7804493988938;28776.78531486277;28914.566281505795;29098.41803163925;29699.257518562208;29744.55383169254;1023.3450591621369;1111.6831702964616;1113.9665810025713;1128.9209755317213;1187.9940600297116;13235.27206003679;14563.708725825423;15018.399264029316;15349.693006139814;15383.538493845203;489336.65995010105;572781;578190;582685;585440;43858;50736.49263507391;53522;54569.45430545723;54681.72659136759;1317.8945684345229;1350.6015725361042;1376.4494202318951;1377.8484366719854;1385.2104300548792;28624.57063144096;29397.971071012413;29686.40627187401;30219.848900755354;30336.938207162642;40669;47509.762451187526;47751.52248477471;48011.5198848021;48080.759596201795;530428;531168;532817;534080;535714;23344.299671009896;24433.633495497936;25077;25195.49609007846;25215;43560.91097722582;43636.781816091985;44734.328985064596;44844.775776120914;45233.77383113063;36945;37036.81481592575;37413.87758367253;37957;38085.4287185698;36446;36899.44650830186;37394.62605373911;37405.43891841679;37415;35662.64337356593;35999.820000899825;36622;36922;37048.44427333646;31470.055898323088;32856.27737443776;33230.50154247737;33277.83361083179;33405.83297083499;24127.758722412553;24448.388790279674;24451.877740611184;24829.283113597543;25050.3737406559 21:45:41 INFO - PROCESS | 1643 | |2;jquery.html;362.50699049292973;371.5392912788143;374.86504858251027;379.99620003799953;391.7815593504203;11261.268017578852;11655;11757.17699761015;11789.528418863236;19247.807521924777;19964.70052949207;20657.27699530517;20666.966651667393;20785.480362990933;21146.942652867332;6067.150598916166;6767.695453704595;6774.898376524356;6789.830254243659;6816.863662726767;6646.235682896457;7233.385162261214;7622.733204337849;7656.042994625672;7733.419993500482;5942.673152976585;7215.494915355927;7731.613419329039;7734.187910269425;7743.496672716284;7416.925830741678;7641.617919104023;7675.270849269311;7689.192634773324;7708.267714567107;7418.851622967564;7467;7478.813029674247;7484.625768711543;7523.022007139091;23203.419914502105;25839;26423.943042278286;26615.935362585475;27205;2506.548821212181;2582.845029298248;2611.738826117392;2748.50526905157;2924.830067972807;15586.220688965506;15905.125218112998;16243.106629135393;16560.834391656048;16700;1454.054864338178;1608.6621809420035;1610.066161626254;1736.7742193514762;1838.3473866777235;96601;123026;124141;127018.36490817579;128257;19706.21175152978;20930.848803315963;20985;21147.78852211458;21231;27011.459770804475;27770.861145694547;33664.32671346559;33957.490637640454;35673.46489802655;8225.629846656915;10261.435621040766;10434.895651043393;10511.211659125596;10649.361038337725;8594.140585941355;8600.784980375416;8646.178613031752;8773;8787.428817126865;9430.10414010668;9543.614096475882;9658.275629327689;9659.565319560637;9758.414495130315;3158.38411509758;3327.717144042735;3676.191237927652;3793.8292776824555;3798.487204227449;782.3115658220729;873.76845136039;924.7713011641911;987.6641941739842;1030.2118879057527;19599.804001959797;20212.19151233934;20998.790012099988;21030.369088927357;21298.467538311666;854.2994744309731;892.9464232146092;893.6157452295446;917.9311551633655;941.2470023980788;10399.376037437778;10638.978594067103;11132.485572238706;11210.327380357041;11234.03404404226;529206;529588;543663;544222;546874.2656286772;86623.56688216519;121513.39243303727;123519;123612;125129.37435312766;910.9180173784391;923.2706162131975;943.3585162089845;948.3172116076414;956.2015716876347;18452.538686532946;18474.16866241049;18536.258549658403;18586.814131858508;18767.436976890716;43478.56521434872;49667.75166124146;49957.25064123968;50228.74428139326;50276;64161.67919160374;67182.66408667926;68981;71286;74707.2529274722;7431.925680743339;7572.39420846342;7572.810679733051;8131.349492040505;8207.58962051907;11450.656480305604;11535.596254131066;11984.58053968107;12891.226526408445;13042.478300868239;10648.84026739584;10824.188185886247;11068.723281918017;11266.394753678744;11291.266067705901;10697.839532406864;10811.405372704578;10827.404492752974;11034.393108378796;11095.389753563632;9622.711318660451;10928.071113955402;11276.774464510829;11290.266132701676;11516;7927.563983980936;8139.7965050871835;8152.8369432609825;8280.130586288353;8330.50016998982;10545.841812372668;10815.080718139046;10862.94568527184;11141.554337826396;11245.437728113398 21:45:41 INFO - PROCESS | 1643 | |3;mootools.html;1445.3568162167833;1489.9106053636783;1609.935602575897;1700.659868026395;1742.6079132195257;7404.1855395906405;7655.540667559936;7882.724104656339;8258;8304.252617264445;7802.687892484294;8095.271425571697;8104.1085480597085;8230.753077407673;8240.670373185065;2267.535155293161;2297.5404919016264;2297.689811875392;2297.9655305170436;2307.400075980249;2297.5290065536524;2314.710661167354;2333.1134169015836;2337.2520793346134;2338.8012018978407;2280.441291883483;2285.1430713482446;2304.8617082974993;2307.4462129088984;2310.722713274401;1804.224183601051;1948.986527005949;2058.135583055113;2089.916403343864;2140.315099168267;1641.9901760417376;2156.611809874222;2191.791779780918;2198.9560208795892;2201.2625770366944;8067;8216.424850260484;8217.055038670569;8226.259636632672;8236.83526329476;4150.460440142762;5100.566451851597;5133.974330128362;5139.563137133311;5148.897022059576;3248.5127230915314;3362.2771104212466;3472.2534655049035;3532.6820586147114;3539.150603855082;883.4964070479829;1039.438703100325;1074.215822449616;1085.0776839686282;1085.9728506787314;9196.172344488961;9273.58268877902;10392.220583456196;10416.583336666525;10444.68665940023;4286.5070516890955;4351.347297905276;4403.471583409947;4405.140997505456;4413.029133590605;8494.575271236414;8532.488050716976;8536.359773016924;8564.400491965627;8577.356698247657;5009.12340340439;5027.516882519665;5033.546980771748;5049.31834202378;5051.116054690415;4189.392538081961;4583.312503124492;4628.930566041512;4670.556297151765;4771.093492236464;3354.9264235444425;3411.5394421752744;3462.221000274918;3474.0967348489576;3477.756557040983;1012.9594816207417;1143.6683361824978;1148.8793676663997;1156.8149096144746;1159.2233203753506;745.7017193122686;865.9523726194993;867.1848462445281;890.5146695051212;896.9595269487363;5285.83711583451;5430.375506816687;5475.67145971243;5533.557315414757;5547.306586676666;439.90981848720935;442.09370789880614;450.3087760287942;874.5845723281518;986.7714695204517;3473.4616134499192;3608.386574282378;3668.229671768905;3764.642358975893;3831.63599458051;9667.46828924416;9675.03249675027;9923.354981926152;10011.599536018477;10013.79972400548;9628.614855405845;9904.455254961047;9906.554205060645;9935.254855885692;9940.155086817556;7572.848543029108;7623.237676232443;7713;7737.032870891138;7750.9612451936255;5321.201819727072;5406.621536492407;5417.5665946723375;5480.945190548044;5506.972465137648;8302.377321701017;8418.747437576883;9243;9325.487098209664;9332;8865.512396818236;8998.775030624287;9256.907430925874;9269.629214831331;9388.483633399956;5320.361556613231;6194.969025154845;6549.115869357575;6712.731490740316;7167.820804479721;6339.904901426575;7902.367810575255;7904.881426778488;7904.960475197588;7927.326177274997;6927.93072069293;7042.964785176247;7061.576305421691;7087.82280442993;7181.892271615826;6930.410915072275;6944.965275173592;6959.547629404073;6979.930200697928;7027.964860175871;6489.805305840832;6602.834929126618;6630.171228596426;6730.629815360006;6753.729850805913;4744.4069491313585;4780.306855505863;4796.2805579163405;4830.1305764961235;4896.7758060484875;4640.211164102043;4688.460827004938;4773.809047638195;4835.685680430761;4939.382577177853 21:45:41 INFO - PROCESS | 1643 | |4;prototype.html;270.9850958197299;293.9588457615934;312.1571756258104;312.2786363500315;315.0689711901332;5308.70802105884;5412.9160273294865;5455.481729235721;5494.340679118512;5537.833864984052;5146.125158723016;5277.393099793525;5365.597580181492;5365.826840129744;5393.325834270716;3634.6547078027547;4109.732867363619;4127.484064491939;4142.7514349139;4150.688698347621;2999.2951656360738;3201.879342230224;3674.963250367489;4145.8963525911795;4164;3614.819259037051;3762.3039737648487;3773.8490460381554;3919.7256192066566;4050.432939388488;3325.4679251319913;3624.821932871814;3699.6855267302312;4015.437838702584;4041.939370909439;3061.4489391909447;3329.317489914586;3499.8775042873376;3504.5969713483023;3526.2242306692488;3915.314819906505;4690.906181876378;4960.404751429815;5161.509656582619;5164.295782391801;4336.978315108436;4461.24158892989;4775.116603428342;4794.832180873687;4804.534616941832;5174.584774049147;5740;5743.54051675865;5787.913181302284;5926.348101708809;3537.823108844573;3718.958691566366;3720.720945929066;3725.6460636239785;3894.3963685628773;5224.973875130638;5663.943360566424;5869.266341707286;5908.763649454017;5961.76433837311;3332.766706330582;3397.5922889253447;3571.785692858437;3709.2025214578457;3790.550598954938;3811.866584669523;3901.9804900975314;4084.8570300039355;4113.856015039519;4136.090060186754;4024.5170579530645;4221.97889010553;4706.223473126965;4708.4820669726305;4709.788059537329;4829.396325459317;5029.893423446836;5367.973160134175;5747.971260143672;5847;1269.825411494364;1308.476609356265;1316.3418290854572;1324.38416136497;1333.3533236380347;835.1940377535707;895.6193617712446;897.5691667999397;927.8654595083675;940.055244479294;4959.504049595083;5097.745112744422;5102.974485127551;5138.8972220556125;5178.792848286026;4606.700564463299;4688.898108944395;5416.562546853931;5522.861928451821;5671.51792097668;4566.84016059443;5018.222175562793;5051.4695956924725;5062.316587260746;5112.667676601009;2597.8960841566127;2640.755357945731;2692.865356732195;2787.703717771246;2930.091671581774;5538.390777014525;5772.9711351442975;5801.50687191585;5809.50619197373;5819.912701309484;5261.185265882735;5624.2283680641285;5858.56060795442;6073.423024812636;6166.568340216142;5277.683338999673;5739.450348405907;6117.816465506042;6139.877202455837;6193.876122477615;5697.316322041216;5961.195238642901;5998.790042348672;6033;6077.6353418795015;4613.861584152481;5755.302185855099;5851.1515830205235;5852.239208902815;5886.470217680429;5335.06636338647;6068.696565171812;6168.907466387918;6183.938160618336;6210.285817131088;3484.930301393907;3641.126129728899;3686.189038411545;3816.8091595419564;3856.1902000580194;5627.943720562906;5941.970290148522;5993.490553303018;6010.368911264254;6056.454919057306;5122.513361230677;5196.584273258206;5330.534103121672;5725.656460612377;5750.424957504216;4853.6602437829015;4890.046440943982;4897.142999974919;5580.469855363734;5887.941120588911;5413.025655382069;5574.414686457863;5656.85857853557;5691.459311365414;5694.430556944272;4680.82341711106;5700.857478562903;5741.770329186953;5776.624519406225;5810.82567522975;4453.686162581981;5630.521405680399;5653.886922261614;5675.120356344773;5677.630953987978 21:45:41 INFO - PROCESS | 1643 | |5;yui.html;636.1012378051515;665.8734840380328;685.5680921019757;723.2658851265962;777.114089937471;14615.196164210978;15004.6999060019;15570.766438503431;15744.685106297868;15874.841251587495;13447;14056.929715351409;14176.929115354435;14199.432022719104;14250.786238206436;658.8550518885837;826.2398593294168;848.9405741598059;849.8810166576684;850.1498501498502;672.515788632184;800.967961281548;823.3865770001357;837.2088376484224;843.5908584336592;885.8361203177433;886.1847100667402;889.1730690457872;889.550776857686;891.8974317953421;864.7838040489878;878.1789027259468;878.97802554936;880.0011986395456;880.7665968518347;644.0146575739127;650.453618960071;655.6033599672224;656.4846595422619;656.8719099775545;14292.642683932985;14671.486497972626;14710.55868323952;14713.411463541446;15101.848981510155;13516;14531.418743250257;14640.487582934653;14712.632184195374;14812.407503699838;11211;11566.074714022941;11648.068154547616;11666.533338666442;11802.881971180264;3707.8331475083687;3709.239605880788;3751.7936513491745;3777.7166712496673;3848;106847;108843;110540.44729776299;110660;114084;10835.97058279478;10892;11018;11107.111431085494;11277.492512836941;22469.550608987727;22599.774002260096;22601.434964126027;22691.319260422213;22731.659025114637;7556.735514256975;7617.162112167657;7624.618769061524;7686.269804368576;7707.92292077072;4124.298869192245;4216.515100763451;4225.323948168279;4231.259529582372;4233.491980962304;2879.7473099201916;3085.228692826793;3268.101272150178;3618.3125206210734;3752.774833509998;605.340179204669;671.325318055361;675.4663815585731;677.3937326093119;684.428502200668;586.8796896636179;597.6264834478451;617.712070333351;659.7723785294066;666.8399584099781;7576.393888489018;8932.687355942511;9190.678326258549;9450.57472413743;9500.572474238537;527.5304978569077;583.9211706419663;631.7473010795627;644.2687549631108;672.304165189027;4128.756856029536;4412.227860124465;4539.795709193094;4653.390748105152;4700.083483720641;98619.50690246503;105736.41395379076;108104;108134;108479.45760271305;65625;75581;83643.5817820907;93724;94629.52685236667;64577.67711161414;80440.19559804327;81547;81552.18447815446;83354;8643.481391116553;9530.285228607743;9687.757806054624;9691.12779849817;9700.12698857078;51505.742471288904;53202.73398632982;53816.461835381146;54888;57445.71277143587;68238.29404264933;75376;77199.61400192963;78542.6072869632;78778.60610696909;12207.45066471993;12400.984693573526;12538.623841284776;12632.557860474839;12819.679508012374;14377.856221437652;14604.634884127981;14650.194239316514;14701.92649036748;14702.63243418923;11731.23746956445;12483.313417761747;13474.326283685972;13536.72926541444;13639.795403068763;11917.702057448632;12296.262224266573;13629;13896.444142234197;14210.644733881736;12843.229406235656;13116.672083197614;13259.270740109387;13292.534761283308;13459.192448453125;10626.665213989234;10914.290571112851;11042.392668403316;11349.262297950607;11633.41832908368;9466.721992531197;10636.41499717523;11277.887221127683;11505;11558.190926635054 21:45:41 INFO - PROCESS | 1643 | __end_tp_report 21:45:41 INFO - PROCESS | 1643 | __start_cc_report 21:45:41 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,362 21:45:41 INFO - PROCESS | 1643 | __end_cc_report 21:45:41 INFO - PROCESS | 1643 | __startTimestamp1480657541745__endTimestamp 21:45:41 INFO - PROCESS | 1643 | 21:45:41 INFO - PROCESS | 1643 | ------- Summary: start ------- 21:45:41 INFO - PROCESS | 1643 | Number of tests: 6 21:45:41 INFO - PROCESS | 1643 | 21:45:41 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5756.48 Median:6069.73 stddev:676.89 (11.2%) stddev-sans-first:647.19 21:45:41 INFO - PROCESS | 1643 | Values: 3143.9 3428.3 3458.7 3475.5 3497.8 5684.7 5769.9 5804.0 5851.0 5852.4 5709.3 5733.7 5798.4 5850.7 5895.1 5153.9 5186.8 5270.8 5295.6 5343.7 5246.6 5370.8 5381.4 5412.7 5441.1 5127.9 5156.7 5313.7 5344.6 5357.3 5163.1 5204.4 5267.5 5304.4 5350.4 5235.0 5238.9 5250.4 5253.3 5271.5 5642.5 5689.0 5763.8 5779.6 5791.7 6202.9 6204.3 6244.4 6249.1 6297.8 6315.4 6323.5 6330.1 6341.8 6346.8 4755.7 4961.0 5932.8 5957.7 5975.0 5465.8 6117.6 6208.5 6220.8 6337.9 5168.4 6202.2 6214.5 6233.8 6266.0 5677.1 5876.1 6147.3 6305.4 6342.7 6108.3 6126.6 6148.4 6159.0 6190.2 6192.6 6195.3 6200.0 6212.9 6323.5 4007.3 4944.1 4950.9 4991.9 5027.3 4368.5 4416.9 4437.5 4441.3 4461.1 4813.3 5914.9 5919.6 5971.0 6052.6 6252.5 6273.9 6295.7 6324.4 6350.0 4314.9 5887.0 5968.6 6044.4 6069.7 4773.1 5423.1 5446.7 5488.9 5532.2 5355.8 6408.7 6415.8 6453.3 6459.4 6274.1 6276.7 6279.5 6316.4 6335.5 6252.5 6313.1 6338.2 6366.5 6370.2 6272.4 6274.4 6277.7 6282.0 6307.9 6286.8 6306.7 6313.1 6313.7 6323.7 6038.6 6100.1 6126.6 6162.0 6198.3 6116.4 6117.6 6136.3 6145.0 6149.7 5891.8 5995.0 6071.7 6126.9 6144.1 6092.4 6144.2 6156.7 6174.2 6249.4 6072.4 6088.9 6108.5 6114.5 6118.1 21:45:41 INFO - PROCESS | 1643 | 21:45:41 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:63829.36 Median:20031.33 stddev:135814.08 (678.0%) stddev-sans-first:136111.66 21:45:41 INFO - PROCESS | 1643 | Values: 432.5 458.0 463.3 483.0 488.1 37839.4 39097.6 39103.2 40908.6 40994.0 39972.4 40425.6 40437.4 40463.2 40695.6 8244.5 9231.9 9398.3 9412.8 9445.2 8326.0 10992.3 11222.6 11238.2 11410.4 9535.9 11220.5 11230.5 11274.4 11283.2 11158.3 11221.8 11222.6 11223.7 11288.5 11085.6 11167.8 11180.7 11182.0 11214.8 31434.5 37703.0 37828.4 37920.5 38042.6 3192.6 3303.0 3407.2 3452.6 3616.7 29473.4 29670.9 29897.0 30112.2 30648.2 3194.8 3216.2 3219.9 3299.7 3331.4 338772.3 433831.0 439670.0 442027.0 443836.6 56423.7 56540.7 56843.4 56846.1 56846.7 58729.1 58838.7 59126.0 59165.7 59248.4 15081.9 15432.9 15465.8 15496.9 15505.1 8964.6 10849.5 11084.0 11896.5 11972.8 15300.0 15636.9 15710.9 15721.8 15839.8 6090.5 6913.9 7060.0 7076.0 7081.2 837.5 870.9 941.7 962.3 975.8 28776.8 28914.6 29098.4 29699.3 29744.6 1023.3 1111.7 1114.0 1128.9 1188.0 13235.3 14563.7 15018.4 15349.7 15383.5 489336.7 572781.0 578190.0 582685.0 585440.0 43858.0 50736.5 53522.0 54569.5 54681.7 1317.9 1350.6 1376.4 1377.8 1385.2 28624.6 29398.0 29686.4 30219.8 30336.9 40669.0 47509.8 47751.5 48011.5 48080.8 530428.0 531168.0 532817.0 534080.0 535714.0 23344.3 24433.6 25077.0 25195.5 25215.0 43560.9 43636.8 44734.3 44844.8 45233.8 36945.0 37036.8 37413.9 37957.0 38085.4 36446.0 36899.4 37394.6 37405.4 37415.0 35662.6 35999.8 36622.0 36922.0 37048.4 31470.1 32856.3 33230.5 33277.8 33405.8 24127.8 24448.4 24451.9 24829.3 25050.4 21:45:41 INFO - PROCESS | 1643 | 21:45:41 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:33848.89 Median:18496.02 stddev:89939.42 (486.3%) stddev-sans-first:90156.57 21:45:41 INFO - PROCESS | 1643 | Values: 362.5 371.5 374.9 380.0 391.8 11261.3 11655.0 11757.2 11789.5 19247.8 19964.7 20657.3 20667.0 20785.5 21146.9 6067.2 6767.7 6774.9 6789.8 6816.9 6646.2 7233.4 7622.7 7656.0 7733.4 5942.7 7215.5 7731.6 7734.2 7743.5 7416.9 7641.6 7675.3 7689.2 7708.3 7418.9 7467.0 7478.8 7484.6 7523.0 23203.4 25839.0 26423.9 26615.9 27205.0 2506.5 2582.8 2611.7 2748.5 2924.8 15586.2 15905.1 16243.1 16560.8 16700.0 1454.1 1608.7 1610.1 1736.8 1838.3 96601.0 123026.0 124141.0 127018.4 128257.0 19706.2 20930.8 20985.0 21147.8 21231.0 27011.5 27770.9 33664.3 33957.5 35673.5 8225.6 10261.4 10434.9 10511.2 10649.4 8594.1 8600.8 8646.2 8773.0 8787.4 9430.1 9543.6 9658.3 9659.6 9758.4 3158.4 3327.7 3676.2 3793.8 3798.5 782.3 873.8 924.8 987.7 1030.2 19599.8 20212.2 20998.8 21030.4 21298.5 854.3 892.9 893.6 917.9 941.2 10399.4 10639.0 11132.5 11210.3 11234.0 529206.0 529588.0 543663.0 544222.0 546874.3 86623.6 121513.4 123519.0 123612.0 125129.4 910.9 923.3 943.4 948.3 956.2 18452.5 18474.2 18536.3 18586.8 18767.4 43478.6 49667.8 49957.3 50228.7 50276.0 64161.7 67182.7 68981.0 71286.0 74707.3 7431.9 7572.4 7572.8 8131.3 8207.6 11450.7 11535.6 11984.6 12891.2 13042.5 10648.8 10824.2 11068.7 11266.4 11291.3 10697.8 10811.4 10827.4 11034.4 11095.4 9622.7 10928.1 11276.8 11290.3 11516.0 7927.6 8139.8 8152.8 8280.1 8330.5 10545.8 10815.1 10862.9 11141.6 11245.4 21:45:41 INFO - PROCESS | 1643 | 21:45:41 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5249.22 Median:5144.23 stddev:2880.57 (56.0%) stddev-sans-first:2874.47 21:45:41 INFO - PROCESS | 1643 | Values: 1445.4 1489.9 1609.9 1700.7 1742.6 7404.2 7655.5 7882.7 8258.0 8304.3 7802.7 8095.3 8104.1 8230.8 8240.7 2267.5 2297.5 2297.7 2298.0 2307.4 2297.5 2314.7 2333.1 2337.3 2338.8 2280.4 2285.1 2304.9 2307.4 2310.7 1804.2 1949.0 2058.1 2089.9 2140.3 1642.0 2156.6 2191.8 2199.0 2201.3 8067.0 8216.4 8217.1 8226.3 8236.8 4150.5 5100.6 5134.0 5139.6 5148.9 3248.5 3362.3 3472.3 3532.7 3539.2 883.5 1039.4 1074.2 1085.1 1086.0 9196.2 9273.6 10392.2 10416.6 10444.7 4286.5 4351.3 4403.5 4405.1 4413.0 8494.6 8532.5 8536.4 8564.4 8577.4 5009.1 5027.5 5033.5 5049.3 5051.1 4189.4 4583.3 4628.9 4670.6 4771.1 3354.9 3411.5 3462.2 3474.1 3477.8 1013.0 1143.7 1148.9 1156.8 1159.2 745.7 866.0 867.2 890.5 897.0 5285.8 5430.4 5475.7 5533.6 5547.3 439.9 442.1 450.3 874.6 986.8 3473.5 3608.4 3668.2 3764.6 3831.6 9667.5 9675.0 9923.4 10011.6 10013.8 9628.6 9904.5 9906.6 9935.3 9940.2 7572.8 7623.2 7713.0 7737.0 7751.0 5321.2 5406.6 5417.6 5480.9 5507.0 8302.4 8418.7 9243.0 9325.5 9332.0 8865.5 8998.8 9256.9 9269.6 9388.5 5320.4 6195.0 6549.1 6712.7 7167.8 6339.9 7902.4 7904.9 7905.0 7927.3 6927.9 7043.0 7061.6 7087.8 7181.9 6930.4 6945.0 6959.5 6979.9 7028.0 6489.8 6602.8 6630.2 6730.6 6753.7 4744.4 4780.3 4796.3 4830.1 4896.8 4640.2 4688.5 4773.8 4835.7 4939.4 21:45:41 INFO - PROCESS | 1643 | 21:45:41 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4544.64 Median:5117.59 stddev:1454.39 (28.4%) stddev-sans-first:1422.65 21:45:41 INFO - PROCESS | 1643 | Values: 271.0 294.0 312.2 312.3 315.1 5308.7 5412.9 5455.5 5494.3 5537.8 5146.1 5277.4 5365.6 5365.8 5393.3 3634.7 4109.7 4127.5 4142.8 4150.7 2999.3 3201.9 3675.0 4145.9 4164.0 3614.8 3762.3 3773.8 3919.7 4050.4 3325.5 3624.8 3699.7 4015.4 4041.9 3061.4 3329.3 3499.9 3504.6 3526.2 3915.3 4690.9 4960.4 5161.5 5164.3 4337.0 4461.2 4775.1 4794.8 4804.5 5174.6 5740.0 5743.5 5787.9 5926.3 3537.8 3719.0 3720.7 3725.6 3894.4 5225.0 5663.9 5869.3 5908.8 5961.8 3332.8 3397.6 3571.8 3709.2 3790.6 3811.9 3902.0 4084.9 4113.9 4136.1 4024.5 4222.0 4706.2 4708.5 4709.8 4829.4 5029.9 5368.0 5748.0 5847.0 1269.8 1308.5 1316.3 1324.4 1333.4 835.2 895.6 897.6 927.9 940.1 4959.5 5097.7 5103.0 5138.9 5178.8 4606.7 4688.9 5416.6 5522.9 5671.5 4566.8 5018.2 5051.5 5062.3 5112.7 2597.9 2640.8 2692.9 2787.7 2930.1 5538.4 5773.0 5801.5 5809.5 5819.9 5261.2 5624.2 5858.6 6073.4 6166.6 5277.7 5739.5 6117.8 6139.9 6193.9 5697.3 5961.2 5998.8 6033.0 6077.6 4613.9 5755.3 5851.2 5852.2 5886.5 5335.1 6068.7 6168.9 6183.9 6210.3 3484.9 3641.1 3686.2 3816.8 3856.2 5627.9 5942.0 5993.5 6010.4 6056.5 5122.5 5196.6 5330.5 5725.7 5750.4 4853.7 4890.0 4897.1 5580.5 5887.9 5413.0 5574.4 5656.9 5691.5 5694.4 4680.8 5700.9 5741.8 5776.6 5810.8 4453.7 5630.5 5653.9 5675.1 5677.6 21:45:41 INFO - PROCESS | 1643 | 21:45:41 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20760.91 Median:4220.92 stddev:30221.13 (716.0%) stddev-sans-first:30268.12 21:45:41 INFO - PROCESS | 1643 | Values: 636.1 665.9 685.6 723.3 777.1 14615.2 15004.7 15570.8 15744.7 15874.8 13447.0 14056.9 14176.9 14199.4 14250.8 658.9 826.2 848.9 849.9 850.1 672.5 801.0 823.4 837.2 843.6 885.8 886.2 889.2 889.6 891.9 864.8 878.2 879.0 880.0 880.8 644.0 650.5 655.6 656.5 656.9 14292.6 14671.5 14710.6 14713.4 15101.8 13516.0 14531.4 14640.5 14712.6 14812.4 11211.0 11566.1 11648.1 11666.5 11802.9 3707.8 3709.2 3751.8 3777.7 3848.0 106847.0 108843.0 110540.4 110660.0 114084.0 10836.0 10892.0 11018.0 11107.1 11277.5 22469.6 22599.8 22601.4 22691.3 22731.7 7556.7 7617.2 7624.6 7686.3 7707.9 4124.3 4216.5 4225.3 4231.3 4233.5 2879.7 3085.2 3268.1 3618.3 3752.8 605.3 671.3 675.5 677.4 684.4 586.9 597.6 617.7 659.8 666.8 7576.4 8932.7 9190.7 9450.6 9500.6 527.5 583.9 631.7 644.3 672.3 4128.8 4412.2 4539.8 4653.4 4700.1 98619.5 105736.4 108104.0 108134.0 108479.5 65625.0 75581.0 83643.6 93724.0 94629.5 64577.7 80440.2 81547.0 81552.2 83354.0 8643.5 9530.3 9687.8 9691.1 9700.1 51505.7 53202.7 53816.5 54888.0 57445.7 68238.3 75376.0 77199.6 78542.6 78778.6 12207.5 12401.0 12538.6 12632.6 12819.7 14377.9 14604.6 14650.2 14701.9 14702.6 11731.2 12483.3 13474.3 13536.7 13639.8 11917.7 12296.3 13629.0 13896.4 14210.6 12843.2 13116.7 13259.3 13292.5 13459.2 10626.7 10914.3 11042.4 11349.3 11633.4 9466.7 10636.4 11277.9 11505.0 11558.2 21:45:41 INFO - PROCESS | 1643 | -------- Summary: end -------- 21:45:41 INFO - PROCESS | 1643 | 21:45:42 INFO - TEST-INFO | 1643: exit 0 21:45:42 INFO - TEST-OK | dromaeo_css | took 1118184ms 21:45:42 INFO - TEST-START | kraken 21:45:42 INFO - operating with platform_type : mac_ 21:45:42 INFO - Initialising browser for kraken test... 21:45:42 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpQx3_9H/profile http://localhost:49235/getInfo.html) 21:45:45 INFO - PROCESS | 1652 | 2016-12-01 21:45:45.577 plugin-container[1653:11090] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 21:45:45 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:45 INFO - PROCESS | 1652 | 2016-12-01 21:45:45.580 plugin-container[1653:11090] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 21:45:45 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:45 INFO - PROCESS | 1652 | 2016-12-01 21:45:45.580 plugin-container[1653:11090] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:45:45 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 21:45:45 INFO - PROCESS | 1652 | colorDepth:24 21:45:45 INFO - PROCESS | 1652 | Browser inner width/height: 1024/663 21:45:45 INFO - PROCESS | 1652 | __metrics 21:45:45 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 21:45:46 INFO - TEST-INFO | 1652: exit 0 21:45:46 INFO - Browser initialized. 21:45:46 INFO - Running cycle 1/1 for kraken test... 21:45:46 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpQx3_9H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:45:49 INFO - PROCESS | 1654 | 2016-12-01 21:45:49.185 plugin-container[1655:11206] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453f, name = 'com.apple.tsm.portname' 21:45:49 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:49 INFO - PROCESS | 1654 | 2016-12-01 21:45:49.188 plugin-container[1655:11206] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 21:45:49 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:49 INFO - PROCESS | 1654 | 2016-12-01 21:45:49.188 plugin-container[1655:11206] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:45:58 INFO - PROCESS | 1654 | RSS: Main: 253382656 21:45:58 INFO - PROCESS | 1654 | 21:46:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 21:46:02 INFO - PROCESS | 1654 | RSS: Main: 263168000 21:46:02 INFO - PROCESS | 1654 | 21:46:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 21:46:06 INFO - PROCESS | 1654 | RSS: Main: 270860288 21:46:06 INFO - PROCESS | 1654 | 21:46:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 21:46:10 INFO - PROCESS | 1654 | RSS: Main: 270745600 21:46:10 INFO - PROCESS | 1654 | 21:46:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 21:46:12 INFO - PROCESS | 1654 | RSS: Main: 271360000 21:46:12 INFO - PROCESS | 1654 | 21:46:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:46:15 INFO - PROCESS | 1654 | RSS: Main: 271712256 21:46:15 INFO - PROCESS | 1654 | 21:46:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 21:46:19 INFO - PROCESS | 1654 | RSS: Main: 277016576 21:46:19 INFO - PROCESS | 1654 | 21:46:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 21:46:23 INFO - PROCESS | 1654 | RSS: Main: 276336640 21:46:23 INFO - PROCESS | 1654 | 21:46:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 21:46:26 INFO - PROCESS | 1654 | RSS: Main: 276189184 21:46:26 INFO - PROCESS | 1654 | 21:46:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:46:29 INFO - PROCESS | 1654 | RSS: Main: 275517440 21:46:29 INFO - PROCESS | 1654 | 21:46:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:46:31 INFO - PROCESS | 1654 | RSS: Main: 275533824 21:46:31 INFO - PROCESS | 1654 | 21:46:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:46:34 INFO - PROCESS | 1654 | RSS: Main: 275529728 21:46:34 INFO - PROCESS | 1654 | 21:46:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:46:38 INFO - PROCESS | 1654 | RSS: Main: 275824640 21:46:38 INFO - PROCESS | 1654 | 21:46:41 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:46:42 INFO - PROCESS | 1654 | RSS: Main: 275640320 21:46:42 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 21:46:44 INFO - PROCESS | 1654 | RSS: Main: 278949888 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | __start_tp_report 21:46:44 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 21:46:44 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 21:46:44 INFO - PROCESS | 1654 | |i|pagename|runs| 21:46:44 INFO - PROCESS | 1654 | |0;ai-astar;106;119;121;117;119;118;118;118;118;119 21:46:44 INFO - PROCESS | 1654 | |1;audio-beat-detection;186;153;140;156;120;117;118;114;134;119 21:46:44 INFO - PROCESS | 1654 | |2;audio-dft;234;227;203;203;194;204;225;202;280;266 21:46:44 INFO - PROCESS | 1654 | |3;audio-fft;85;58;61;86;59;62;65;83;75;62 21:46:44 INFO - PROCESS | 1654 | |4;audio-oscillator;117;61;95;60;93;58;68;56;94;107 21:46:44 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;108;133;81;129;136;85;90;106;114;86 21:46:44 INFO - PROCESS | 1654 | |6;imaging-darkroom;107;127;153;98;96;95;146;110;96;143 21:46:44 INFO - PROCESS | 1654 | |7;imaging-desaturate;110;148;119;95;90;93;123;100;149;85 21:46:44 INFO - PROCESS | 1654 | |8;json-parse-financial;59;59;63;61;86;50;65;68;90;92 21:46:44 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;70;77;80;78;77;75;64;73;94;108 21:46:44 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;94;114;79;99;113;111;90;89;90;86 21:46:44 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;161;176;147;159;158;178;160;171;225;182 21:46:44 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;179;181;176;184;247;215;204;191;180;178 21:46:44 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;69;72;72;70;82;73;68;69;75 21:46:44 INFO - PROCESS | 1654 | __end_tp_report 21:46:44 INFO - PROCESS | 1654 | __start_cc_report 21:46:44 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,950 21:46:44 INFO - PROCESS | 1654 | __end_cc_report 21:46:44 INFO - PROCESS | 1654 | __startTimestamp1480657604400__endTimestamp 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | ------- Summary: start ------- 21:46:44 INFO - PROCESS | 1654 | Number of tests: 14 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:117.30 Median:118.50 stddev:4.11 (3.5%) stddev-sans-first:1.13 21:46:44 INFO - PROCESS | 1654 | Values: 106.0 119.0 121.0 117.0 119.0 118.0 118.0 118.0 118.0 119.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:137.00 stddev:23.39 (17.1%) stddev-sans-first:16.24 21:46:44 INFO - PROCESS | 1654 | Values: 186.0 153.0 140.0 156.0 120.0 117.0 118.0 114.0 134.0 119.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:223.80 Median:226.00 stddev:29.20 (12.9%) stddev-sans-first:30.74 21:46:44 INFO - PROCESS | 1654 | Values: 234.0 227.0 203.0 203.0 194.0 204.0 225.0 202.0 280.0 266.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:69.60 Median:70.00 stddev:11.41 (16.3%) stddev-sans-first:10.66 21:46:44 INFO - PROCESS | 1654 | Values: 85.0 58.0 61.0 86.0 59.0 62.0 65.0 83.0 75.0 62.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:64.50 stddev:22.72 (35.2%) stddev-sans-first:19.99 21:46:44 INFO - PROCESS | 1654 | Values: 117.0 61.0 95.0 60.0 93.0 58.0 68.0 56.0 94.0 107.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:106.80 Median:108.50 stddev:20.93 (19.3%) stddev-sans-first:22.19 21:46:44 INFO - PROCESS | 1654 | Values: 108.0 133.0 81.0 129.0 136.0 85.0 90.0 106.0 114.0 86.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:117.10 Median:124.00 stddev:23.07 (18.6%) stddev-sans-first:24.18 21:46:44 INFO - PROCESS | 1654 | Values: 107.0 127.0 153.0 98.0 96.0 95.0 146.0 110.0 96.0 143.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:111.20 Median:117.00 stddev:23.19 (19.8%) stddev-sans-first:24.59 21:46:44 INFO - PROCESS | 1654 | Values: 110.0 148.0 119.0 95.0 90.0 93.0 123.0 100.0 149.0 85.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:69.30 Median:66.50 stddev:14.67 (22.1%) stddev-sans-first:15.08 21:46:44 INFO - PROCESS | 1654 | Values: 59.0 59.0 63.0 61.0 86.0 50.0 65.0 68.0 90.0 92.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:79.60 Median:77.00 stddev:12.61 (16.4%) stddev-sans-first:12.88 21:46:44 INFO - PROCESS | 1654 | Values: 70.0 77.0 80.0 78.0 77.0 75.0 64.0 73.0 94.0 108.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:89.50 stddev:12.29 (13.7%) stddev-sans-first:13.00 21:46:44 INFO - PROCESS | 1654 | Values: 94.0 114.0 79.0 99.0 113.0 111.0 90.0 89.0 90.0 86.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:171.70 Median:173.50 stddev:21.64 (12.5%) stddev-sans-first:22.61 21:46:44 INFO - PROCESS | 1654 | Values: 161.0 176.0 147.0 159.0 158.0 178.0 160.0 171.0 225.0 182.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.50 Median:187.50 stddev:22.67 (12.1%) stddev-sans-first:23.43 21:46:44 INFO - PROCESS | 1654 | Values: 179.0 181.0 176.0 184.0 247.0 215.0 204.0 191.0 180.0 178.0 21:46:44 INFO - PROCESS | 1654 | 21:46:44 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:72.00 stddev:4.26 (5.9%) stddev-sans-first:4.29 21:46:44 INFO - PROCESS | 1654 | Values: 68.0 69.0 72.0 72.0 70.0 82.0 73.0 68.0 69.0 75.0 21:46:44 INFO - PROCESS | 1654 | -------- Summary: end -------- 21:46:44 INFO - PROCESS | 1654 | 21:46:45 INFO - TEST-INFO | 1654: exit 0 21:46:45 INFO - TEST-OK | kraken | took 63037ms 21:46:45 INFO - SUITE-END | took 1181s 21:46:45 INFO - Completed test suite (00:19:41) 21:46:45 INFO - javascript benchmark 21:46:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7269.952806859941, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3143.874245030199, 3428.348613763385, 3458.676654182272, 3475.4786781982716, 3497.7551571390013, 5684.658920464775, 5769.884602307951, 5804.042333015047, 5851.0, 5852.356240813512, 5709.314882214129, 5733.713314334287, 5798.391168927265, 5850.6782126983, 5895.056790913465, 5153.871153221181, 5186.766595503193, 5270.81552145675, 5295.576353891679, 5343.7328133593355, 5246.632735708502, 5370.785168593252, 5381.434949330303, 5412.675239485624, 5441.102218134023, 5127.9230811537855, 5156.74216289184, 5313.734313284321, 5344.599155063386, 5357.303550538424, 5163.1480805667015, 5204.42751297357, 5267.47325267474, 5304.416514183436, 5350.357957045179, 5234.973825130888, 5238.92141617876, 5250.369955605352, 5253.290805741214, 5271.472852714736, 5642.463965923231, 5689.0328644130595, 5763.827085187451, 5779.595428320019, 5791.681457519835, 6202.937970620326, 6204.286507051702, 6244.438000579924, 6249.093881387219, 6297.811065668037, 6315.400036996524, 6323.494120470351, 6330.082138089997, 6341.8097457076365, 6346.777862774827, 4755.667103302736, 4961.0, 5932.79235226765, 5957.702114894238, 5974.970125149346, 5465.781368745285, 6117.583823141483, 6208.534359923024, 6220.813375598739, 6337.936620633827, 5168.3797944246935, 6202.245400117539, 6214.533909956771, 6233.844153896098, 6266.0, 5677.148427735872, 5876.118582212703, 6147.323794382614, 6305.432511074026, 6342.651154186564, 6108.3280839107665, 6126.639818174547, 6148.385161483816, 6159.0, 6190.1952746142715, 6192.628442293387, 6195.349488303754, 6200.0079987202735, 6212.875742485216, 6323.525735569759, 4007.2586571484367, 4944.060628480577, 4950.925736113961, 4991.925121123186, 5027.296178535008, 4368.453943257093, 4416.933745993812, 4437.511873693956, 4441.289393696993, 4461.08547747711, 4813.302071199727, 5914.881702366015, 5919.6152250103605, 5971.044632858721, 6052.606580572248, 6252.487067250317, 6273.905891411632, 6295.653739044396, 6324.430801227912, 6350.0, 4314.878131685721, 5886.969780288432, 5968.552358573124, 6044.425779550936, 6069.726862291119, 4773.11697335994, 5423.132298832168, 5446.673199608036, 5488.945110548923, 5532.170174473861, 5355.848492574115, 6408.702563083703, 6415.839604009937, 6453.290138084807, 6459.418652321314, 6274.090256912813, 6276.74893004293, 6279.529035322278, 6316.368363163647, 6335.493160547053, 6252.499800016079, 6313.1477250572425, 6338.207724034496, 6366.4588509977175, 6370.203724534434, 6272.435480806749, 6274.372562743689, 6277.686115694289, 6281.968590157203, 6307.873842523216, 6286.842828929313, 6306.716197771203, 6313.1477250572425, 6313.747450101944, 6323.715432805627, 6038.607490513103, 6100.0849872519475, 6126.571140020156, 6161.9691901542, 6198.318184999736, 6116.42707509198, 6117.644716737863, 6136.3046386708, 6144.955357589131, 6149.692515374303, 5891.823245302648, 5995.0, 6071.696415179135, 6126.938730612636, 6144.109104179958, 6092.360302168386, 6144.201253836973, 6156.692165391623, 6174.228221472316, 6249.375062493714, 6072.362401947732, 6088.878222435438, 6108.547790461711, 6114.48026917717, 6118.131909153847], "value": 5715.076841585374, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.48102277267276, 458.04497622457177, 463.26341117623014, 482.9734364609945, 488.11894530372683, 37839.43240851386, 39097.609023909754, 39103.2179356413, 40908.59091409085, 40994.0, 39972.40041399381, 40425.58510452135, 40437.393439098436, 40463.19073618533, 40695.59304406955, 8244.546549939781, 9231.861522077174, 9398.295127865438, 9412.764680882965, 9445.244380449582, 8326.042505111898, 10992.28550144242, 11222.607208747695, 11238.213325067249, 11410.429478526083, 9535.904640953606, 11220.495077721522, 11230.494627741771, 11274.379909104997, 11283.210175287733, 11158.330500170016, 11221.775564488746, 11222.607208747655, 11223.66329010131, 11288.548458061668, 11085.556577736881, 11167.83248251277, 11180.664580062612, 11182.0, 11214.775704485946, 31434.528482072787, 37703.0, 37828.43257351142, 37920.48318067274, 38042.619573804186, 3192.5530425740417, 3302.9669703302898, 3407.16524451511, 3452.637473065317, 3616.65641764032, 29473.410531789457, 29670.851645741634, 29897.0, 30112.24719382033, 30648.2337941551, 3194.7603929705365, 3216.244182617083, 3219.8551065201887, 3299.686529779667, 3331.350386674575, 338772.3061384702, 433831.0, 439670.0, 442027.0, 443836.56163438276, 56423.717881411154, 56540.717296413255, 56843.43156568464, 56846.147307790416, 56846.7157664209, 58729.119063214086, 58838.705806470694, 59126.0, 59165.704171478865, 59248.40751592515, 15081.924590377197, 15432.92283538575, 15465.845341546665, 15496.915215934776, 15505.147216902953, 8964.596593153194, 10849.457527123612, 11084.0, 11896.464659090188, 11972.760544789055, 15300.0, 15636.92181539085, 15710.921445392929, 15721.764173537405, 15839.841601584067, 6090.482309003695, 6913.89629155563, 7059.964700176569, 7076.0, 7081.150261968493, 837.5058715357968, 870.9390342675952, 941.6704153546204, 962.2686758063921, 975.7804493988938, 28776.78531486277, 28914.566281505795, 29098.41803163925, 29699.257518562208, 29744.55383169254, 1023.3450591621369, 1111.6831702964616, 1113.9665810025713, 1128.9209755317213, 1187.9940600297116, 13235.27206003679, 14563.708725825423, 15018.399264029316, 15349.693006139814, 15383.538493845203, 489336.65995010105, 572781.0, 578190.0, 582685.0, 585440.0, 43858.0, 50736.49263507391, 53522.0, 54569.45430545723, 54681.72659136759, 1317.8945684345229, 1350.6015725361042, 1376.4494202318951, 1377.8484366719854, 1385.2104300548792, 28624.57063144096, 29397.971071012413, 29686.40627187401, 30219.848900755354, 30336.938207162642, 40669.0, 47509.762451187526, 47751.52248477471, 48011.5198848021, 48080.759596201795, 530428.0, 531168.0, 532817.0, 534080.0, 535714.0, 23344.299671009896, 24433.633495497936, 25077.0, 25195.49609007846, 25215.0, 43560.91097722582, 43636.781816091985, 44734.328985064596, 44844.775776120914, 45233.77383113063, 36945.0, 37036.81481592575, 37413.87758367253, 37957.0, 38085.4287185698, 36446.0, 36899.44650830186, 37394.62605373911, 37405.43891841679, 37415.0, 35662.64337356593, 35999.820000899825, 36622.0, 36922.0, 37048.44427333646, 31470.055898323088, 32856.27737443776, 33230.50154247737, 33277.83361083179, 33405.83297083499, 24127.758722412553, 24448.388790279674, 24451.877740611184, 24829.283113597543, 25050.3737406559], "value": 19249.50985173235, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.50699049292973, 371.5392912788143, 374.86504858251027, 379.99620003799953, 391.7815593504203, 11261.268017578852, 11655.0, 11757.17699761015, 11789.528418863236, 19247.807521924777, 19964.70052949207, 20657.27699530517, 20666.966651667393, 20785.480362990933, 21146.942652867332, 6067.150598916166, 6767.695453704595, 6774.898376524356, 6789.830254243659, 6816.863662726767, 6646.235682896457, 7233.385162261214, 7622.733204337849, 7656.042994625672, 7733.419993500482, 5942.673152976585, 7215.494915355927, 7731.613419329039, 7734.187910269425, 7743.496672716284, 7416.925830741678, 7641.617919104023, 7675.270849269311, 7689.192634773324, 7708.267714567107, 7418.851622967564, 7467.0, 7478.813029674247, 7484.625768711543, 7523.022007139091, 23203.419914502105, 25839.0, 26423.943042278286, 26615.935362585475, 27205.0, 2506.548821212181, 2582.845029298248, 2611.738826117392, 2748.50526905157, 2924.830067972807, 15586.220688965506, 15905.125218112998, 16243.106629135393, 16560.834391656048, 16700.0, 1454.054864338178, 1608.6621809420035, 1610.066161626254, 1736.7742193514762, 1838.3473866777235, 96601.0, 123026.0, 124141.0, 127018.36490817579, 128257.0, 19706.21175152978, 20930.848803315963, 20985.0, 21147.78852211458, 21231.0, 27011.459770804475, 27770.861145694547, 33664.32671346559, 33957.490637640454, 35673.46489802655, 8225.629846656915, 10261.435621040766, 10434.895651043393, 10511.211659125596, 10649.361038337725, 8594.140585941355, 8600.784980375416, 8646.178613031752, 8773.0, 8787.428817126865, 9430.10414010668, 9543.614096475882, 9658.275629327689, 9659.565319560637, 9758.414495130315, 3158.38411509758, 3327.717144042735, 3676.191237927652, 3793.8292776824555, 3798.487204227449, 782.3115658220729, 873.76845136039, 924.7713011641911, 987.6641941739842, 1030.2118879057527, 19599.804001959797, 20212.19151233934, 20998.790012099988, 21030.369088927357, 21298.467538311666, 854.2994744309731, 892.9464232146092, 893.6157452295446, 917.9311551633655, 941.2470023980788, 10399.376037437778, 10638.978594067103, 11132.485572238706, 11210.327380357041, 11234.03404404226, 529206.0, 529588.0, 543663.0, 544222.0, 546874.2656286772, 86623.56688216519, 121513.39243303727, 123519.0, 123612.0, 125129.37435312766, 910.9180173784391, 923.2706162131975, 943.3585162089845, 948.3172116076414, 956.2015716876347, 18452.538686532946, 18474.16866241049, 18536.258549658403, 18586.814131858508, 18767.436976890716, 43478.56521434872, 49667.75166124146, 49957.25064123968, 50228.74428139326, 50276.0, 64161.67919160374, 67182.66408667926, 68981.0, 71286.0, 74707.2529274722, 7431.925680743339, 7572.39420846342, 7572.810679733051, 8131.349492040505, 8207.58962051907, 11450.656480305604, 11535.596254131066, 11984.58053968107, 12891.226526408445, 13042.478300868239, 10648.84026739584, 10824.188185886247, 11068.723281918017, 11266.394753678744, 11291.266067705901, 10697.839532406864, 10811.405372704578, 10827.404492752974, 11034.393108378796, 11095.389753563632, 9622.711318660451, 10928.071113955402, 11276.774464510829, 11290.266132701676, 11516.0, 7927.563983980936, 8139.7965050871835, 8152.8369432609825, 8280.130586288353, 8330.50016998982, 10545.841812372668, 10815.080718139046, 10862.94568527184, 11141.554337826396, 11245.437728113398], "value": 10548.66241756506, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.3568162167833, 1489.9106053636783, 1609.935602575897, 1700.659868026395, 1742.6079132195257, 7404.1855395906405, 7655.540667559936, 7882.724104656339, 8258.0, 8304.252617264445, 7802.687892484294, 8095.271425571697, 8104.1085480597085, 8230.753077407673, 8240.670373185065, 2267.535155293161, 2297.5404919016264, 2297.689811875392, 2297.9655305170436, 2307.400075980249, 2297.5290065536524, 2314.710661167354, 2333.1134169015836, 2337.2520793346134, 2338.8012018978407, 2280.441291883483, 2285.1430713482446, 2304.8617082974993, 2307.4462129088984, 2310.722713274401, 1804.224183601051, 1948.986527005949, 2058.135583055113, 2089.916403343864, 2140.315099168267, 1641.9901760417376, 2156.611809874222, 2191.791779780918, 2198.9560208795892, 2201.2625770366944, 8067.0, 8216.424850260484, 8217.055038670569, 8226.259636632672, 8236.83526329476, 4150.460440142762, 5100.566451851597, 5133.974330128362, 5139.563137133311, 5148.897022059576, 3248.5127230915314, 3362.2771104212466, 3472.2534655049035, 3532.6820586147114, 3539.150603855082, 883.4964070479829, 1039.438703100325, 1074.215822449616, 1085.0776839686282, 1085.9728506787314, 9196.172344488961, 9273.58268877902, 10392.220583456196, 10416.583336666525, 10444.68665940023, 4286.5070516890955, 4351.347297905276, 4403.471583409947, 4405.140997505456, 4413.029133590605, 8494.575271236414, 8532.488050716976, 8536.359773016924, 8564.400491965627, 8577.356698247657, 5009.12340340439, 5027.516882519665, 5033.546980771748, 5049.31834202378, 5051.116054690415, 4189.392538081961, 4583.312503124492, 4628.930566041512, 4670.556297151765, 4771.093492236464, 3354.9264235444425, 3411.5394421752744, 3462.221000274918, 3474.0967348489576, 3477.756557040983, 1012.9594816207417, 1143.6683361824978, 1148.8793676663997, 1156.8149096144746, 1159.2233203753506, 745.7017193122686, 865.9523726194993, 867.1848462445281, 890.5146695051212, 896.9595269487363, 5285.83711583451, 5430.375506816687, 5475.67145971243, 5533.557315414757, 5547.306586676666, 439.90981848720935, 442.09370789880614, 450.3087760287942, 874.5845723281518, 986.7714695204517, 3473.4616134499192, 3608.386574282378, 3668.229671768905, 3764.642358975893, 3831.63599458051, 9667.46828924416, 9675.03249675027, 9923.354981926152, 10011.599536018477, 10013.79972400548, 9628.614855405845, 9904.455254961047, 9906.554205060645, 9935.254855885692, 9940.155086817556, 7572.848543029108, 7623.237676232443, 7713.0, 7737.032870891138, 7750.9612451936255, 5321.201819727072, 5406.621536492407, 5417.5665946723375, 5480.945190548044, 5506.972465137648, 8302.377321701017, 8418.747437576883, 9243.0, 9325.487098209664, 9332.0, 8865.512396818236, 8998.775030624287, 9256.907430925874, 9269.629214831331, 9388.483633399956, 5320.361556613231, 6194.969025154845, 6549.115869357575, 6712.731490740316, 7167.820804479721, 6339.904901426575, 7902.367810575255, 7904.881426778488, 7904.960475197588, 7927.326177274997, 6927.93072069293, 7042.964785176247, 7061.576305421691, 7087.82280442993, 7181.892271615826, 6930.410915072275, 6944.965275173592, 6959.547629404073, 6979.930200697928, 7027.964860175871, 6489.805305840832, 6602.834929126618, 6630.171228596426, 6730.629815360006, 6753.729850805913, 4744.4069491313585, 4780.306855505863, 4796.2805579163405, 4830.1305764961235, 4896.7758060484875, 4640.211164102043, 4688.460827004938, 4773.809047638195, 4835.685680430761, 4939.382577177853], "value": 4220.580945424168, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.9850958197299, 293.9588457615934, 312.1571756258104, 312.2786363500315, 315.0689711901332, 5308.70802105884, 5412.9160273294865, 5455.481729235721, 5494.340679118512, 5537.833864984052, 5146.125158723016, 5277.393099793525, 5365.597580181492, 5365.826840129744, 5393.325834270716, 3634.6547078027547, 4109.732867363619, 4127.484064491939, 4142.7514349139, 4150.688698347621, 2999.2951656360738, 3201.879342230224, 3674.963250367489, 4145.8963525911795, 4164.0, 3614.819259037051, 3762.3039737648487, 3773.8490460381554, 3919.7256192066566, 4050.432939388488, 3325.4679251319913, 3624.821932871814, 3699.6855267302312, 4015.437838702584, 4041.939370909439, 3061.4489391909447, 3329.317489914586, 3499.8775042873376, 3504.5969713483023, 3526.2242306692488, 3915.314819906505, 4690.906181876378, 4960.404751429815, 5161.509656582619, 5164.295782391801, 4336.978315108436, 4461.24158892989, 4775.116603428342, 4794.832180873687, 4804.534616941832, 5174.584774049147, 5740.0, 5743.54051675865, 5787.913181302284, 5926.348101708809, 3537.823108844573, 3718.958691566366, 3720.720945929066, 3725.6460636239785, 3894.3963685628773, 5224.973875130638, 5663.943360566424, 5869.266341707286, 5908.763649454017, 5961.76433837311, 3332.766706330582, 3397.5922889253447, 3571.785692858437, 3709.2025214578457, 3790.550598954938, 3811.866584669523, 3901.9804900975314, 4084.8570300039355, 4113.856015039519, 4136.090060186754, 4024.5170579530645, 4221.97889010553, 4706.223473126965, 4708.4820669726305, 4709.788059537329, 4829.396325459317, 5029.893423446836, 5367.973160134175, 5747.971260143672, 5847.0, 1269.825411494364, 1308.476609356265, 1316.3418290854572, 1324.38416136497, 1333.3533236380347, 835.1940377535707, 895.6193617712446, 897.5691667999397, 927.8654595083675, 940.055244479294, 4959.504049595083, 5097.745112744422, 5102.974485127551, 5138.8972220556125, 5178.792848286026, 4606.700564463299, 4688.898108944395, 5416.562546853931, 5522.861928451821, 5671.51792097668, 4566.84016059443, 5018.222175562793, 5051.4695956924725, 5062.316587260746, 5112.667676601009, 2597.8960841566127, 2640.755357945731, 2692.865356732195, 2787.703717771246, 2930.091671581774, 5538.390777014525, 5772.9711351442975, 5801.50687191585, 5809.50619197373, 5819.912701309484, 5261.185265882735, 5624.2283680641285, 5858.56060795442, 6073.423024812636, 6166.568340216142, 5277.683338999673, 5739.450348405907, 6117.816465506042, 6139.877202455837, 6193.876122477615, 5697.316322041216, 5961.195238642901, 5998.790042348672, 6033.0, 6077.6353418795015, 4613.861584152481, 5755.302185855099, 5851.1515830205235, 5852.239208902815, 5886.470217680429, 5335.06636338647, 6068.696565171812, 6168.907466387918, 6183.938160618336, 6210.285817131088, 3484.930301393907, 3641.126129728899, 3686.189038411545, 3816.8091595419564, 3856.1902000580194, 5627.943720562906, 5941.970290148522, 5993.490553303018, 6010.368911264254, 6056.454919057306, 5122.513361230677, 5196.584273258206, 5330.534103121672, 5725.656460612377, 5750.424957504216, 4853.6602437829015, 4890.046440943982, 4897.142999974919, 5580.469855363734, 5887.941120588911, 5413.025655382069, 5574.414686457863, 5656.85857853557, 5691.459311365414, 5694.430556944272, 4680.82341711106, 5700.857478562903, 5741.770329186953, 5776.624519406225, 5810.82567522975, 4453.686162581981, 5630.521405680399, 5653.886922261614, 5675.120356344773, 5677.630953987978], "value": 4082.020282436999, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.1012378051515, 665.8734840380328, 685.5680921019757, 723.2658851265962, 777.114089937471, 14615.196164210978, 15004.6999060019, 15570.766438503431, 15744.685106297868, 15874.841251587495, 13447.0, 14056.929715351409, 14176.929115354435, 14199.432022719104, 14250.786238206436, 658.8550518885837, 826.2398593294168, 848.9405741598059, 849.8810166576684, 850.1498501498502, 672.515788632184, 800.967961281548, 823.3865770001357, 837.2088376484224, 843.5908584336592, 885.8361203177433, 886.1847100667402, 889.1730690457872, 889.550776857686, 891.8974317953421, 864.7838040489878, 878.1789027259468, 878.97802554936, 880.0011986395456, 880.7665968518347, 644.0146575739127, 650.453618960071, 655.6033599672224, 656.4846595422619, 656.8719099775545, 14292.642683932985, 14671.486497972626, 14710.55868323952, 14713.411463541446, 15101.848981510155, 13516.0, 14531.418743250257, 14640.487582934653, 14712.632184195374, 14812.407503699838, 11211.0, 11566.074714022941, 11648.068154547616, 11666.533338666442, 11802.881971180264, 3707.8331475083687, 3709.239605880788, 3751.7936513491745, 3777.7166712496673, 3848.0, 106847.0, 108843.0, 110540.44729776299, 110660.0, 114084.0, 10835.97058279478, 10892.0, 11018.0, 11107.111431085494, 11277.492512836941, 22469.550608987727, 22599.774002260096, 22601.434964126027, 22691.319260422213, 22731.659025114637, 7556.735514256975, 7617.162112167657, 7624.618769061524, 7686.269804368576, 7707.92292077072, 4124.298869192245, 4216.515100763451, 4225.323948168279, 4231.259529582372, 4233.491980962304, 2879.7473099201916, 3085.228692826793, 3268.101272150178, 3618.3125206210734, 3752.774833509998, 605.340179204669, 671.325318055361, 675.4663815585731, 677.3937326093119, 684.428502200668, 586.8796896636179, 597.6264834478451, 617.712070333351, 659.7723785294066, 666.8399584099781, 7576.393888489018, 8932.687355942511, 9190.678326258549, 9450.57472413743, 9500.572474238537, 527.5304978569077, 583.9211706419663, 631.7473010795627, 644.2687549631108, 672.304165189027, 4128.756856029536, 4412.227860124465, 4539.795709193094, 4653.390748105152, 4700.083483720641, 98619.50690246503, 105736.41395379076, 108104.0, 108134.0, 108479.45760271305, 65625.0, 75581.0, 83643.5817820907, 93724.0, 94629.52685236667, 64577.67711161414, 80440.19559804327, 81547.0, 81552.18447815446, 83354.0, 8643.481391116553, 9530.285228607743, 9687.757806054624, 9691.12779849817, 9700.12698857078, 51505.742471288904, 53202.73398632982, 53816.461835381146, 54888.0, 57445.71277143587, 68238.29404264933, 75376.0, 77199.61400192963, 78542.6072869632, 78778.60610696909, 12207.45066471993, 12400.984693573526, 12538.623841284776, 12632.557860474839, 12819.679508012374, 14377.856221437652, 14604.634884127981, 14650.194239316514, 14701.92649036748, 14702.63243418923, 11731.23746956445, 12483.313417761747, 13474.326283685972, 13536.72926541444, 13639.795403068763, 11917.702057448632, 12296.262224266573, 13629.0, 13896.444142234197, 14210.644733881736, 12843.229406235656, 13116.672083197614, 13259.270740109387, 13292.534761283308, 13459.192448453125, 10626.665213989234, 10914.290571112851, 11042.392668403316, 11349.262297950607, 11633.41832908368, 9466.721992531197, 10636.41499717523, 11277.887221127683, 11505.0, 11558.190926635054], "value": 7383.352825110275, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1644.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 121.0, 117.0, 119.0, 118.0, 118.0, 118.0, 118.0, 119.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 153.0, 140.0, 156.0, 120.0, 117.0, 118.0, 114.0, 134.0, 119.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [234.0, 227.0, 203.0, 203.0, 194.0, 204.0, 225.0, 202.0, 280.0, 266.0], "value": 223.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 61.0, 86.0, 59.0, 62.0, 65.0, 83.0, 75.0, 62.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 61.0, 95.0, 60.0, 93.0, 58.0, 68.0, 56.0, 94.0, 107.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 133.0, 81.0, 129.0, 136.0, 85.0, 90.0, 106.0, 114.0, 86.0], "value": 106.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 127.0, 153.0, 98.0, 96.0, 95.0, 146.0, 110.0, 96.0, 143.0], "value": 117.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 148.0, 119.0, 95.0, 90.0, 93.0, 123.0, 100.0, 149.0, 85.0], "value": 111.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 63.0, 61.0, 86.0, 50.0, 65.0, 68.0, 90.0, 92.0], "value": 69.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 80.0, 78.0, 77.0, 75.0, 64.0, 73.0, 94.0, 108.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 114.0, 79.0, 99.0, 113.0, 111.0, 90.0, 89.0, 90.0, 86.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 176.0, 147.0, 159.0, 158.0, 178.0, 160.0, 171.0, 225.0, 182.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 181.0, 176.0, 184.0, 247.0, 215.0, 204.0, 191.0, 180.0, 178.0], "value": 193.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 72.0, 72.0, 70.0, 82.0, 73.0, 68.0, 69.0, 75.0], "value": 71.8, "unit": "score"}]}]} 21:46:45 INFO - Return code: 0 21:46:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:46:45 ERROR - # TBPL SUCCESS # 21:46:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:46:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:46:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:46:45 INFO - 2016-12-01 21:46:45.742 screenresolution[1659:11601] starting screenresolution argv=screenresolution get 21:46:45 INFO - 2016-12-01 21:46:45.744 screenresolution[1659:11601] Display 0: 1600x1200x32@60 21:46:45 INFO - 2016-12-01 21:46:45.750 screenresolution[1660:11604] starting screenresolution argv=screenresolution list 21:46:45 INFO - Available Modes on Display 0 21:46:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:46:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:46:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:46:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:46:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:46:45 INFO - Intel Iris: 21:46:45 INFO - Chipset Model: Intel Iris 21:46:45 INFO - Type: GPU 21:46:45 INFO - Bus: Built-In 21:46:45 INFO - VRAM (Dynamic, Max): 1536 MB 21:46:45 INFO - Vendor: Intel (0x8086) 21:46:45 INFO - Device ID: 0x0a2e 21:46:45 INFO - Revision ID: 0x0009 21:46:45 INFO - Displays: 21:46:45 INFO - Display: 21:46:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:46:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:46:45 INFO - Display Serial Number: 1600x1200 60 21:46:45 INFO - Main Display: Yes 21:46:45 INFO - Mirror: Off 21:46:45 INFO - Online: Yes 21:46:45 INFO - Rotation: Supported 21:46:45 INFO - Return code: 0 21:46:45 INFO - Running post-action listener: _resource_record_post_action 21:46:45 INFO - [mozharness: 2016-12-02 05:46:45.992352Z] Finished run-tests step (success) 21:46:45 INFO - Running post-run listener: _resource_record_post_run 21:46:46 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 32416256; Write bytes: 695897088; Read time: 314; Write time: 1950 21:46:46 INFO - TinderboxPrint: CPU usage
54.4% 21:46:46 INFO - TinderboxPrint: I/O read bytes / time
32,416,256 / 314 21:46:46 INFO - TinderboxPrint: I/O write bytes / time
695,897,088 / 1,950 21:46:46 INFO - TinderboxPrint: CPU idle
2,215.8 (45.6%) 21:46:46 INFO - TinderboxPrint: CPU system
979.0 (20.2%) 21:46:46 INFO - TinderboxPrint: CPU user
1,662.9 (34.2%) 21:46:46 INFO - TinderboxPrint: Swap in / out
412,852,224 / 0 21:46:46 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 365298176; Write bytes: 359925248; Read time: 27559; Write time: 658 21:46:46 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 31486464; Write bytes: 308876800; Read time: 301; Write time: 1228 21:46:46 INFO - Running post-run listener: _upload_blobber_files 21:46:46 INFO - Blob upload gear active. 21:46:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:46:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:46:46 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:46:46 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:46:46 INFO - (blobuploader) - INFO - Open directory for files ... 21:46:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:46:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:46:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:46:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:46:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:46:48 INFO - (blobuploader) - INFO - Done attempting. 21:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:46:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:46:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:46:49 INFO - (blobuploader) - INFO - Done attempting. 21:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:46:51 INFO - (blobuploader) - INFO - Done attempting. 21:46:51 INFO - (blobuploader) - INFO - Iteration through files over. 21:46:51 INFO - Return code: 0 21:46:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:46:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:46:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/80f42e19790ad2ee7bc6cc6e7446df9f82676a30d0eae71e503bacf802434c4bbfec197a32dd99eef23de981cfa9a17dc2813f9ab547140cdd0e6649e58b22d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/247c4d1c4f5848a336977a2fefd05eeb4a3ccffde6dab71c4be0a6570d3334a888c5435f3ba098164fdeacdcfe54177c96a2a629c0cb3cd8e5783ef19daaa2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9fd6c0413e71d92cad69eb671be27e341006b2129c1519061ab5de0962e7ba29127ab597316c6123f328fad1bc34a05d04d176fe7e727145b9c44d482194ccc1"} 21:46:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:46:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:46:51 INFO - Contents: 21:46:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/80f42e19790ad2ee7bc6cc6e7446df9f82676a30d0eae71e503bacf802434c4bbfec197a32dd99eef23de981cfa9a17dc2813f9ab547140cdd0e6649e58b22d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/247c4d1c4f5848a336977a2fefd05eeb4a3ccffde6dab71c4be0a6570d3334a888c5435f3ba098164fdeacdcfe54177c96a2a629c0cb3cd8e5783ef19daaa2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9fd6c0413e71d92cad69eb671be27e341006b2129c1519061ab5de0962e7ba29127ab597316c6123f328fad1bc34a05d04d176fe7e727145b9c44d482194ccc1"} 21:46:51 INFO - Running post-run listener: copy_logs_to_upload_dir 21:46:51 INFO - Copying logs to upload dir... 21:46:51 INFO - mkdir: /builds/slave/test/build/upload/logs 21:46:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.808061 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-01 21:46:51.490963) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 21:46:51.494671) ========= 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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/80f42e19790ad2ee7bc6cc6e7446df9f82676a30d0eae71e503bacf802434c4bbfec197a32dd99eef23de981cfa9a17dc2813f9ab547140cdd0e6649e58b22d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/247c4d1c4f5848a336977a2fefd05eeb4a3ccffde6dab71c4be0a6570d3334a888c5435f3ba098164fdeacdcfe54177c96a2a629c0cb3cd8e5783ef19daaa2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9fd6c0413e71d92cad69eb671be27e341006b2129c1519061ab5de0962e7ba29127ab597316c6123f328fad1bc34a05d04d176fe7e727145b9c44d482194ccc1"} build_url:https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010954 build_url: 'https://queue.taskcluster.net/v1/task/BnJYe67zSpWOyXe_6tHcPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/80f42e19790ad2ee7bc6cc6e7446df9f82676a30d0eae71e503bacf802434c4bbfec197a32dd99eef23de981cfa9a17dc2813f9ab547140cdd0e6649e58b22d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/247c4d1c4f5848a336977a2fefd05eeb4a3ccffde6dab71c4be0a6570d3334a888c5435f3ba098164fdeacdcfe54177c96a2a629c0cb3cd8e5783ef19daaa2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9fd6c0413e71d92cad69eb671be27e341006b2129c1519061ab5de0962e7ba29127ab597316c6123f328fad1bc34a05d04d176fe7e727145b9c44d482194ccc1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 21:46:51.533319) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 21:46:51.534004) ========= 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.EGtGoYJsdU/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.SyWSBmIzo3/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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.005216 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 21:46:51.585055) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 21:46:51.585648) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 21:46:51.586274) ========= ========= Total master_lag: 0.19 =========