builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0209 starttime: 1479787479.06 results: success (0) buildid: 20161121185750 builduid: 0f09016b289d43789f52069fa037d49c revision: 998a69cbaf54e31bb5d1d95d49cde70cfe89153c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:39.059467) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:39.059987) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:39.060402) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:39.087287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:39.087688) ========= 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.ezlCsQchIg/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.efWtdxrCQY/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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-11-21 20:04:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 54.3M=0s 2016-11-21 20:04:39 (54.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.187333 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:39.300115) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:39.300466) ========= 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.ezlCsQchIg/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.efWtdxrCQY/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.031083 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:39.355759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-21 20:04:39.356105) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 998a69cbaf54e31bb5d1d95d49cde70cfe89153c --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 998a69cbaf54e31bb5d1d95d49cde70cfe89153c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ezlCsQchIg/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.efWtdxrCQY/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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-11-21 20:04:39,456 truncating revision to first 12 chars 2016-11-21 20:04:39,456 Setting DEBUG logging. 2016-11-21 20:04:39,456 attempt 1/10 2016-11-21 20:04:39,457 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/998a69cbaf54?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-21 20:04:41,248 unpacking tar archive at: graphics-998a69cbaf54/testing/mozharness/ program finished with exit code 0 elapsedTime=2.066488 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-21 20:04:41.443598) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:41.443934) ========= script_repo_revision: 998a69cbaf54e31bb5d1d95d49cde70cfe89153c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:41.444305) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:41.444568) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-21 20:04:41.464196) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-21 20:04:41.464471) ========= /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.ezlCsQchIg/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.efWtdxrCQY/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:04:41 INFO - MultiFileLogger online at 20161121 20:04:41 in /builds/slave/test 20:04:41 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 20:04:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:04:41 INFO - {'append_to_log': False, 20:04:41 INFO - 'base_work_dir': '/builds/slave/test', 20:04:41 INFO - 'blob_upload_branch': 'Graphics', 20:04:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:04:41 INFO - 'branch': 'Graphics', 20:04:41 INFO - 'buildbot_json_path': 'buildprops.json', 20:04:41 INFO - 'config_files': ('talos/mac_config.py',), 20:04:41 INFO - 'default_actions': ('clobber', 20:04:41 INFO - 'read-buildbot-config', 20:04:41 INFO - 'download-and-extract', 20:04:41 INFO - 'populate-webroot', 20:04:41 INFO - 'create-virtualenv', 20:04:41 INFO - 'install', 20:04:41 INFO - 'run-tests'), 20:04:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:04:41 INFO - 'download_minidump_stackwalk': True, 20:04:41 INFO - 'download_symbols': 'ondemand', 20:04:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:04:41 INFO - 'tooltool.py': '/tools/tooltool.py', 20:04:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:04:41 INFO - '/tools/misc-python/virtualenv.py')}, 20:04:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:04:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:04:41 INFO - 'installer_path': 'installer.exe', 20:04:41 INFO - 'log_level': 'info', 20:04:41 INFO - 'log_name': 'talos', 20:04:41 INFO - 'log_to_console': True, 20:04:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:04:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:04:41 INFO - 'opt_config_files': (), 20:04:41 INFO - 'pip_index': False, 20:04:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:04:41 INFO - 'cmd': ('bash', 20:04:41 INFO - '-c', 20:04:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:04:41 INFO - 'enabled': True, 20:04:41 INFO - 'halt_on_failure': False, 20:04:41 INFO - 'name': 'check_screen_resolution'},), 20:04:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:04:41 INFO - 'cmd': ('bash', 20:04:41 INFO - '-c', 20:04:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:04:41 INFO - 'enabled': True, 20:04:41 INFO - 'halt_on_failure': False, 20:04:41 INFO - 'name': 'check_screen_resolution'},), 20:04:41 INFO - 'run_cmd_checks_enabled': True, 20:04:41 INFO - 'sps_profile': False, 20:04:41 INFO - 'sps_profile_interval': 0, 20:04:41 INFO - 'suite': 'dromaeojs-e10s', 20:04:41 INFO - 'system_bits': '32', 20:04:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:04:41 INFO - 'title': 't-yosemite-r7-0209', 20:04:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:04:41 INFO - 'use_talos_json': True, 20:04:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:04:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:04:41 INFO - 'work_dir': 'build'} 20:04:41 INFO - [mozharness: 2016-11-22 04:04:41.671372Z] Running clobber step. 20:04:41 INFO - Running pre-action listener: _resource_record_pre_action 20:04:41 INFO - Running main action method: clobber 20:04:41 INFO - rmtree: /builds/slave/test/build 20:04:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:04:44 INFO - Running post-action listener: _resource_record_post_action 20:04:44 INFO - [mozharness: 2016-11-22 04:04:44.063444Z] Finished clobber step (success) 20:04:44 INFO - [mozharness: 2016-11-22 04:04:44.063570Z] Running read-buildbot-config step. 20:04:44 INFO - Running pre-action listener: _resource_record_pre_action 20:04:44 INFO - Running main action method: read_buildbot_config 20:04:44 INFO - Using buildbot properties: 20:04:44 INFO - { 20:04:44 INFO - "project": "", 20:04:44 INFO - "product": "firefox", 20:04:44 INFO - "script_repo_revision": "default", 20:04:44 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 20:04:44 INFO - "repository": "", 20:04:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 20:04:44 INFO - "buildid": "20161121185750", 20:04:44 INFO - "pgo_build": "False", 20:04:44 INFO - "basedir": "/builds/slave/test", 20:04:44 INFO - "buildnumber": 0, 20:04:44 INFO - "slavename": "t-yosemite-r7-0209", 20:04:44 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 20:04:44 INFO - "platform": "yosemite_r7", 20:04:44 INFO - "scheduler": "tests-graphics-macosx64-talos", 20:04:44 INFO - "branch": "graphics", 20:04:44 INFO - "repo_path": "projects/graphics", 20:04:44 INFO - "revision": "998a69cbaf54e31bb5d1d95d49cde70cfe89153c", 20:04:44 INFO - "stage_platform": "macosx64", 20:04:44 INFO - "builduid": "0f09016b289d43789f52069fa037d49c", 20:04:44 INFO - "slavebuilddir": "test" 20:04:44 INFO - } 20:04:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:04:44 INFO - Running post-action listener: _resource_record_post_action 20:04:44 INFO - [mozharness: 2016-11-22 04:04:44.066798Z] Finished read-buildbot-config step (success) 20:04:44 INFO - [mozharness: 2016-11-22 04:04:44.066903Z] Running download-and-extract step. 20:04:44 INFO - Running pre-action listener: _resource_record_pre_action 20:04:44 INFO - Running main action method: download_and_extract 20:04:44 INFO - mkdir: /builds/slave/test/build/tests 20:04:44 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')]} 20:04:44 INFO - trying https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:04:44 INFO - Downloading https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/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 20:04:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/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 20:04:45 INFO - Downloaded 1199 bytes. 20:04:45 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:04:45 INFO - Using the following test package requirements: 20:04:45 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:04:45 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:04:45 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:04:45 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:04:45 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:04:45 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:04:45 INFO - u'jsshell-mac.zip'], 20:04:45 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:04:45 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:04:45 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:04:45 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:04:45 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:04:45 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:04:45 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:04:45 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:04:45 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:04:45 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:04:45 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:04:45 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:04:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:04:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:04:45 INFO - Fetch https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:04:45 INFO - Http code: 200 20:04:45 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 20:04:45 INFO - x-amz-cf-id: _9dIfmD6UaerovLn78VTUEfE4nojuHd28OPuNgfhL3Qhwpixis6GVA== 20:04:45 INFO - x-amz-version-id: 7too5E18PHwlB2C98R3Uqbz8fjfkZKdg 20:04:46 INFO - Expected file size: 16281993 20:04:46 INFO - Obtained file size: 16281993 20:04:53 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 20:04:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:04:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:04:53 INFO - Fetch https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:04:53 INFO - Http code: 200 20:04:53 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 20:04:53 INFO - x-amz-cf-id: tdiG6dsfEZu9ZFlUfGzSP81AeFQytVc5N1I2JxZoMSngG9__ObBpoA== 20:04:53 INFO - x-amz-version-id: 7too5E18PHwlB2C98R3Uqbz8fjfkZKdg 20:04:53 INFO - Expected file size: 16281993 20:04:53 INFO - Obtained file size: 16281993 20:05:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:05:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:05:00 INFO - Fetch https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:05:01 INFO - Http code: 200 20:05:01 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 20:05:01 INFO - x-amz-cf-id: m5_LzrAty3Tt2uxD9Mwlhz9-xg334LpLE8Gdlwx2l6IWE8cTjSZLpQ== 20:05:01 INFO - x-amz-version-id: GMGAy7RD5y033IDffZUJogBOdSwpi_YA 20:05:01 INFO - Expected file size: 14409392 20:05:01 INFO - Obtained file size: 14409392 20:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:01 INFO - trying https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:05:01 INFO - Downloading https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:05:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/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 20:05:04 INFO - Downloaded 107567007 bytes. 20:05:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:05:04 INFO - mkdir: /builds/slave/test/properties 20:05:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:05:04 INFO - Writing to file /builds/slave/test/properties/build_url 20:05:04 INFO - Contents: 20:05:04 INFO - build_url:https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:05:05 INFO - Running post-action listener: _resource_record_post_action 20:05:05 INFO - Running post-action listener: set_extra_try_arguments 20:05:05 INFO - [mozharness: 2016-11-22 04:05:05.280205Z] Finished download-and-extract step (success) 20:05:05 INFO - [mozharness: 2016-11-22 04:05:05.280324Z] Running populate-webroot step. 20:05:05 INFO - Running pre-action listener: _resource_record_pre_action 20:05:05 INFO - Running main action method: populate_webroot 20:05:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:05:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:05:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:05:05 INFO - '--tppagecycles', 20:05:05 INFO - '7'], 20:05:05 INFO - 'tests': ['tsvgm']}}, 20:05:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:05:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:05:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:05:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:05:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:05:05 INFO - 'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:05:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:05:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:05:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:05:05 INFO - 'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['damp', 'tps']}, 20:05:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:05:05 INFO - 'tests': ['damp', 'tps']}, 20:05:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['dromaeo_dom']}, 20:05:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:05:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['basic_compositor_video']}, 20:05:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:05:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['a11yr', 20:05:05 INFO - 'ts_paint', 20:05:05 INFO - 'tpaint', 20:05:05 INFO - 'sessionrestore', 20:05:05 INFO - 'sessionrestore_no_auto_restore', 20:05:05 INFO - 'tabpaint']}, 20:05:05 INFO - 'other-e10s': {'tests': ['a11yr', 20:05:05 INFO - 'ts_paint', 20:05:05 INFO - 'tpaint', 20:05:05 INFO - 'sessionrestore', 20:05:05 INFO - 'sessionrestore_no_auto_restore', 20:05:05 INFO - 'tabpaint']}, 20:05:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:05:05 INFO - 'ts_paint', 20:05:05 INFO - 'tpaint', 20:05:05 INFO - 'sessionrestore', 20:05:05 INFO - 'sessionrestore_no_auto_restore', 20:05:05 INFO - 'tabpaint']}, 20:05:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:05:05 INFO - 'ts_paint', 20:05:05 INFO - 'tpaint', 20:05:05 INFO - 'sessionrestore', 20:05:05 INFO - 'sessionrestore_no_auto_restore', 20:05:05 INFO - 'tabpaint']}, 20:05:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['a11yr', 20:05:05 INFO - 'ts_paint', 20:05:05 INFO - 'tpaint', 20:05:05 INFO - 'sessionrestore', 20:05:05 INFO - 'sessionrestore_no_auto_restore', 20:05:05 INFO - 'tabpaint']}, 20:05:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['a11yr', 20:05:05 INFO - 'ts_paint', 20:05:05 INFO - 'tpaint', 20:05:05 INFO - 'sessionrestore', 20:05:05 INFO - 'sessionrestore_no_auto_restore', 20:05:05 INFO - 'tabpaint']}, 20:05:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['tsvgx', 20:05:05 INFO - 'tsvgr_opacity', 20:05:05 INFO - 'tart', 20:05:05 INFO - 'tscrollx', 20:05:05 INFO - 'cart']}, 20:05:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:05:05 INFO - 'tsvgr_opacity', 20:05:05 INFO - 'tart', 20:05:05 INFO - 'tscrollx', 20:05:05 INFO - 'cart']}, 20:05:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:05:05 INFO - 'talos_options': ['--disable-e10s'], 20:05:05 INFO - 'tests': ['tp5o']}, 20:05:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:05:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:05:05 INFO - 'talos_options': ['--disable-e10s', 20:05:05 INFO - '--xperf_path', 20:05:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:05:05 INFO - 'tests': ['tp5n']}, 20:05:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:05:05 INFO - 'talos_options': ['--xperf_path', 20:05:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:05:05 INFO - 'tests': ['tp5n']}}, 20:05:05 INFO - 'talos.zip': {'path': '', 20:05:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:05:05 INFO - Running post-action listener: _resource_record_post_action 20:05:05 INFO - [mozharness: 2016-11-22 04:05:05.298067Z] Finished populate-webroot step (success) 20:05:05 INFO - [mozharness: 2016-11-22 04:05:05.298199Z] Running create-virtualenv step. 20:05:05 INFO - Running pre-action listener: _resource_record_pre_action 20:05:05 INFO - Running main action method: create_virtualenv 20:05:05 INFO - Creating virtualenv /builds/slave/test/build/venv 20:05:05 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 20:05:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:05:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:05:05 INFO - Using real prefix '/tools/python27' 20:05:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:05:06 INFO - Installing distribute.............................................................................................................................................................................................done. 20:05:08 INFO - Installing pip.................done. 20:05:08 INFO - Return code: 0 20:05:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:05:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:05:09 INFO - Reading from file tmpfile_stdout 20:05:09 INFO - Output received: 20:05:09 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) 20:05:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:05:09 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')]} 20:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:09 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')]} 20:05:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:09 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 0x10442a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043a0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8395ba310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, '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.ezlCsQchIg/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.efWtdxrCQY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:05:09 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 20:05:09 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 20:05:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:05:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:09 INFO - 'HOME': '/Users/cltbld', 20:05:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:09 INFO - 'LOGNAME': 'cltbld', 20:05:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:09 INFO - 'MOZ_NO_REMOTE': '1', 20:05:09 INFO - 'NO_EM_RESTART': '1', 20:05:09 INFO - 'PAGER': '/bin/cat', 20:05:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:09 INFO - 'PWD': '/builds/slave/test', 20:05:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:09 INFO - 'SHELL': '/bin/bash', 20:05:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:05:09 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:05:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:09 INFO - 'USER': 'cltbld', 20:05:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:09 INFO - 'XPC_FLAGS': '0x0', 20:05:09 INFO - 'XPC_SERVICE_NAME': '0', 20:05:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:09 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 20:05:09 INFO - Cleaning up... 20:05:09 INFO - Return code: 0 20:05:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:05:09 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')]} 20:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:09 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')]} 20:05:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:09 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 0x10442a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043a0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8395ba310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, '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.ezlCsQchIg/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.efWtdxrCQY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:05:09 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 20:05:09 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 20:05:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:05:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:09 INFO - 'HOME': '/Users/cltbld', 20:05:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:09 INFO - 'LOGNAME': 'cltbld', 20:05:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:09 INFO - 'MOZ_NO_REMOTE': '1', 20:05:09 INFO - 'NO_EM_RESTART': '1', 20:05:09 INFO - 'PAGER': '/bin/cat', 20:05:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:09 INFO - 'PWD': '/builds/slave/test', 20:05:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:09 INFO - 'SHELL': '/bin/bash', 20:05:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:05:09 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:05:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:09 INFO - 'USER': 'cltbld', 20:05:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:09 INFO - 'XPC_FLAGS': '0x0', 20:05:09 INFO - 'XPC_SERVICE_NAME': '0', 20:05:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:09 INFO - Downloading/unpacking psutil>=3.1.1 20:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:05:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:05:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:05:13 INFO - Installing collected packages: psutil 20:05:13 INFO - Running setup.py install for psutil 20:05:13 INFO - building 'psutil._psutil_osx' extension 20:05:13 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 20:05:13 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 20:05:13 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 20:05:13 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 20:05:14 INFO - building 'psutil._psutil_posix' extension 20:05:14 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 20:05:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:05:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:05:14 INFO - ^ 20:05:14 INFO - 1 warning generated. 20:05:14 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 20:05:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:05:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:05:14 INFO - Successfully installed psutil 20:05:14 INFO - Cleaning up... 20:05:14 INFO - Return code: 0 20:05:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:05:14 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')]} 20:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:14 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')]} 20:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:14 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 0x10442a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043a0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8395ba310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, '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.ezlCsQchIg/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.efWtdxrCQY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:05:14 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 20:05:14 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 20:05:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:05:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:14 INFO - 'HOME': '/Users/cltbld', 20:05:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:14 INFO - 'LOGNAME': 'cltbld', 20:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:14 INFO - 'MOZ_NO_REMOTE': '1', 20:05:14 INFO - 'NO_EM_RESTART': '1', 20:05:14 INFO - 'PAGER': '/bin/cat', 20:05:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:14 INFO - 'PWD': '/builds/slave/test', 20:05:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:14 INFO - 'SHELL': '/bin/bash', 20:05:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:05:14 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:05:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:14 INFO - 'USER': 'cltbld', 20:05:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:14 INFO - 'XPC_FLAGS': '0x0', 20:05:14 INFO - 'XPC_SERVICE_NAME': '0', 20:05:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:05: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 20:05: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 20:05: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 20:05: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 20:05: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 20:05: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 20:05:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:05:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:05:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:05:17 INFO - Installing collected packages: mozsystemmonitor 20:05:17 INFO - Running setup.py install for mozsystemmonitor 20:05:17 INFO - Successfully installed mozsystemmonitor 20:05:17 INFO - Cleaning up... 20:05:17 INFO - Return code: 0 20:05:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10442a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043a0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8395ba310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, '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.ezlCsQchIg/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.efWtdxrCQY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:05:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:05:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:05:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:17 INFO - 'HOME': '/Users/cltbld', 20:05:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:17 INFO - 'LOGNAME': 'cltbld', 20:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:17 INFO - 'MOZ_NO_REMOTE': '1', 20:05:17 INFO - 'NO_EM_RESTART': '1', 20:05:17 INFO - 'PAGER': '/bin/cat', 20:05:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:17 INFO - 'PWD': '/builds/slave/test', 20:05:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:17 INFO - 'SHELL': '/bin/bash', 20:05:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:05:17 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:05:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:17 INFO - 'USER': 'cltbld', 20:05:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:17 INFO - 'XPC_FLAGS': '0x0', 20:05:17 INFO - 'XPC_SERVICE_NAME': '0', 20:05:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:17 INFO - Downloading/unpacking jsonschema==2.5.1 20:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:05:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:20 INFO - Downloading functools32-3.2.3-2.tar.gz 20:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:05:20 INFO - warning: no files found matching '*.txt' 20:05:20 INFO - no previously-included directories found matching 'build' 20:05:20 INFO - no previously-included directories found matching 'dist' 20:05:20 INFO - no previously-included directories found matching '.git*' 20:05:20 INFO - Installing collected packages: jsonschema, functools32 20:05:20 INFO - Running setup.py install for functools32 20:05:20 INFO - warning: no files found matching '*.txt' 20:05:20 INFO - no previously-included directories found matching 'build' 20:05:20 INFO - no previously-included directories found matching 'dist' 20:05:20 INFO - no previously-included directories found matching '.git*' 20:05:20 INFO - Successfully installed jsonschema functools32 20:05:20 INFO - Cleaning up... 20:05:21 INFO - Return code: 0 20:05:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:21 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 0x10442a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043a0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8395ba310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, '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.ezlCsQchIg/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.efWtdxrCQY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:05:21 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 20:05:21 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 20:05:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:05:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:21 INFO - 'HOME': '/Users/cltbld', 20:05:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:21 INFO - 'LOGNAME': 'cltbld', 20:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:21 INFO - 'MOZ_NO_REMOTE': '1', 20:05:21 INFO - 'NO_EM_RESTART': '1', 20:05:21 INFO - 'PAGER': '/bin/cat', 20:05:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:21 INFO - 'PWD': '/builds/slave/test', 20:05:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:21 INFO - 'SHELL': '/bin/bash', 20:05:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:05:21 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:05:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:21 INFO - 'USER': 'cltbld', 20:05:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:21 INFO - 'XPC_FLAGS': '0x0', 20:05:21 INFO - 'XPC_SERVICE_NAME': '0', 20:05:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:05:21 INFO - Cleaning up... 20:05:21 INFO - Return code: 0 20:05:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:21 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 0x10442a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043a0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8395ba310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, '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.ezlCsQchIg/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.efWtdxrCQY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:05:21 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 20:05:21 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 20:05:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:05:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:21 INFO - 'HOME': '/Users/cltbld', 20:05:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:21 INFO - 'LOGNAME': 'cltbld', 20:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:21 INFO - 'MOZ_NO_REMOTE': '1', 20:05:21 INFO - 'NO_EM_RESTART': '1', 20:05:21 INFO - 'PAGER': '/bin/cat', 20:05:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:21 INFO - 'PWD': '/builds/slave/test', 20:05:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:21 INFO - 'SHELL': '/bin/bash', 20:05:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:05:21 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:05:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:21 INFO - 'USER': 'cltbld', 20:05:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:21 INFO - 'XPC_FLAGS': '0x0', 20:05:21 INFO - 'XPC_SERVICE_NAME': '0', 20:05:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:21 INFO - Downloading/unpacking blobuploader==1.2.4 20:05:21 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 20:05:21 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 20:05:21 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 20:05:21 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 20:05:21 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 20:05:21 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 20:05:24 INFO - Downloading blobuploader-1.2.4.tar.gz 20:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:05:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:05:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:25 INFO - Downloading docopt-0.6.1.tar.gz 20:05:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:05:25 INFO - Installing collected packages: blobuploader, requests, docopt 20:05:25 INFO - Running setup.py install for blobuploader 20:05:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:05:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:05:25 INFO - Running setup.py install for requests 20:05:26 INFO - Running setup.py install for docopt 20:05:26 INFO - Successfully installed blobuploader requests docopt 20:05:26 INFO - Cleaning up... 20:05:26 INFO - Return code: 0 20:05:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:26 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 0x10442a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043a0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8395ba310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, '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.ezlCsQchIg/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.efWtdxrCQY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:05:26 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 20:05:26 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 20:05:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:05:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:26 INFO - 'HOME': '/Users/cltbld', 20:05:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:26 INFO - 'LOGNAME': 'cltbld', 20:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:26 INFO - 'MOZ_NO_REMOTE': '1', 20:05:26 INFO - 'NO_EM_RESTART': '1', 20:05:26 INFO - 'PAGER': '/bin/cat', 20:05:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:26 INFO - 'PWD': '/builds/slave/test', 20:05:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:26 INFO - 'SHELL': '/bin/bash', 20:05:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:05:26 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:05:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:26 INFO - 'USER': 'cltbld', 20:05:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:26 INFO - 'XPC_FLAGS': '0x0', 20:05:26 INFO - 'XPC_SERVICE_NAME': '0', 20:05:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:05:26 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-t8FusF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:05:26 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-IkKEVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:05:26 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-tesr5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:05:26 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-WbIBSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:05:27 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-m36fvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:05:27 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-vLREGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:05:27 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-6pClmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:05:27 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Q8h6gX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:05:27 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-45f1xZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:05:27 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-AIn77j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:05:27 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Hd5siN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:05:27 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-OnZG2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:05:27 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-x4K169-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:05:27 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-nV4IxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:05:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-TGiyiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:05:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-TaZbE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:05:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-ECXlz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:05:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:05:28 INFO - Running setup.py install for manifestparser 20:05:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:05:28 INFO - Running setup.py install for mozcrash 20:05:28 INFO - Running setup.py install for mozdebug 20:05:28 INFO - Running setup.py install for mozdevice 20:05:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:05:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:05:28 INFO - Running setup.py install for mozfile 20:05:29 INFO - Running setup.py install for mozhttpd 20:05:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:05:29 INFO - Running setup.py install for mozinfo 20:05:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:05:29 INFO - Running setup.py install for mozInstall 20:05:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:05:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:05:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:05:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:05:29 INFO - Running setup.py install for mozleak 20:05:29 INFO - Running setup.py install for mozlog 20:05:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:05:29 INFO - Running setup.py install for moznetwork 20:05:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:05:30 INFO - Running setup.py install for mozprocess 20:05:30 INFO - Running setup.py install for mozprofile 20:05:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:05:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:05:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:05:30 INFO - Running setup.py install for mozrunner 20:05:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:05:30 INFO - Running setup.py install for mozscreenshot 20:05:30 INFO - Running setup.py install for moztest 20:05:30 INFO - Running setup.py install for mozversion 20:05:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:05:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:05:30 INFO - Cleaning up... 20:05:30 INFO - Return code: 0 20:05:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:05: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')]} 20:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05: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')]} 20:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:30 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 0x10442a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043a0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8395ba310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, '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.ezlCsQchIg/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.efWtdxrCQY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:05:30 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 20:05:30 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 20:05:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:05:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:30 INFO - 'HOME': '/Users/cltbld', 20:05:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:30 INFO - 'LOGNAME': 'cltbld', 20:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:30 INFO - 'MOZ_NO_REMOTE': '1', 20:05:30 INFO - 'NO_EM_RESTART': '1', 20:05:30 INFO - 'PAGER': '/bin/cat', 20:05:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:30 INFO - 'PWD': '/builds/slave/test', 20:05:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:30 INFO - 'SHELL': '/bin/bash', 20:05:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:05:30 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:05:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:30 INFO - 'USER': 'cltbld', 20:05:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:30 INFO - 'XPC_FLAGS': '0x0', 20:05:30 INFO - 'XPC_SERVICE_NAME': '0', 20:05:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:05:31 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-MyKHrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:05:31 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)) 20:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:05:31 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-QuRz5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:05:31 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)) 20:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:05:31 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Ek9TgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:05:31 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)) 20:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:05:31 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-37Svnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:05:31 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)) 20:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:05:31 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-0I1u6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:05:31 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)) 20:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:05:31 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-EL4w2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:05:31 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)) 20:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:05:31 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-mjXnS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:05:31 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)) 20:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:05:31 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Ck705G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:05:32 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)) 20:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:05:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-G6h4QY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:05:32 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)) 20:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:05:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-8Nl4cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:05:32 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)) 20:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:05:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-GLFpnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:05:32 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)) 20:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:05:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-g21_Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:05:32 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)) 20:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:05:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-1DTFll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:05:32 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)) 20:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:05:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-4ccNPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:05:32 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)) 20:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:05:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-JLx8te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:05:32 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)) 20:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:05:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-PSI_Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:05:32 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)) 20:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:05:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-QUqB9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:05:33 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)) 20:05:33 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)) 20:05:33 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)) 20:05:33 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)) 20:05:33 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)) 20:05:33 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)) 20:05:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:05:33 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 20:05:33 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 20:05:33 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 20:05:33 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 20:05:33 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 20:05:33 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 20:05:35 INFO - Downloading blessings-1.6.tar.gz 20:05:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:05:35 INFO - Installing collected packages: blessings 20:05:35 INFO - Running setup.py install for blessings 20:05:35 INFO - Successfully installed blessings 20:05:35 INFO - Cleaning up... 20:05:35 INFO - Return code: 0 20:05:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:05:36 INFO - Reading from file tmpfile_stdout 20:05:36 INFO - Current package versions: 20:05:36 INFO - blessings == 1.6 20:05:36 INFO - blobuploader == 1.2.4 20:05:36 INFO - docopt == 0.6.1 20:05:36 INFO - functools32 == 3.2.3-2 20:05:36 INFO - jsonschema == 2.5.1 20:05:36 INFO - manifestparser == 1.1 20:05:36 INFO - mozInstall == 1.12 20:05:36 INFO - mozcrash == 1.0 20:05:36 INFO - mozdebug == 0.1 20:05:36 INFO - mozdevice == 0.49 20:05:36 INFO - mozfile == 1.2 20:05:36 INFO - mozhttpd == 0.7 20:05:36 INFO - mozinfo == 0.9 20:05:36 INFO - mozleak == 0.1 20:05:36 INFO - mozlog == 3.4 20:05:36 INFO - moznetwork == 0.27 20:05:36 INFO - mozprocess == 0.23 20:05:36 INFO - mozprofile == 0.28 20:05:36 INFO - mozrunner == 6.13 20:05:36 INFO - mozscreenshot == 0.1 20:05:36 INFO - mozsystemmonitor == 0.3 20:05:36 INFO - moztest == 0.7 20:05:36 INFO - mozversion == 1.4 20:05:36 INFO - psutil == 3.1.1 20:05:36 INFO - requests == 1.2.3 20:05:36 INFO - wsgiref == 0.1.2 20:05:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:05: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')]} 20:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05: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')]} 20:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:36 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 0x10442a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043a0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8395ba310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, '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.ezlCsQchIg/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.efWtdxrCQY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:05:36 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 20:05:36 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 20:05:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:05:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:36 INFO - 'HOME': '/Users/cltbld', 20:05:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:36 INFO - 'LOGNAME': 'cltbld', 20:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:36 INFO - 'MOZ_NO_REMOTE': '1', 20:05:36 INFO - 'NO_EM_RESTART': '1', 20:05:36 INFO - 'PAGER': '/bin/cat', 20:05:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:36 INFO - 'PWD': '/builds/slave/test', 20:05:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:36 INFO - 'SHELL': '/bin/bash', 20:05:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:05:36 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:05:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:36 INFO - 'USER': 'cltbld', 20:05:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:36 INFO - 'XPC_FLAGS': '0x0', 20:05:36 INFO - 'XPC_SERVICE_NAME': '0', 20:05:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:37 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)) 20:05:37 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)) 20:05:37 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)) 20:05:37 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)) 20:05:37 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)) 20:05:37 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)) 20:05:37 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)) 20:05:37 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)) 20:05:37 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)) 20:05:37 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)) 20:05:37 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)) 20:05:37 INFO - Cleaning up... 20:05:37 INFO - Return code: 0 20:05:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:05:37 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')]} 20:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:37 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')]} 20:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:37 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 0x10442a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043a0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8395ba310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, '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.ezlCsQchIg/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.efWtdxrCQY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:05:37 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 20:05:37 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 20:05:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:05:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:37 INFO - 'HOME': '/Users/cltbld', 20:05:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:37 INFO - 'LOGNAME': 'cltbld', 20:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:37 INFO - 'MOZ_NO_REMOTE': '1', 20:05:37 INFO - 'NO_EM_RESTART': '1', 20:05:37 INFO - 'PAGER': '/bin/cat', 20:05:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:37 INFO - 'PWD': '/builds/slave/test', 20:05:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:37 INFO - 'SHELL': '/bin/bash', 20:05:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:05:37 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:05:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:37 INFO - 'USER': 'cltbld', 20:05:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:37 INFO - 'XPC_FLAGS': '0x0', 20:05:37 INFO - 'XPC_SERVICE_NAME': '0', 20:05:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:05:37 INFO - Cleaning up... 20:05:37 INFO - Return code: 0 20:05:37 INFO - Running post-action listener: _resource_record_post_action 20:05:37 INFO - Running post-action listener: _start_resource_monitoring 20:05:37 INFO - Starting resource monitoring. 20:05:37 INFO - [mozharness: 2016-11-22 04:05:37.951028Z] Finished create-virtualenv step (success) 20:05:37 INFO - [mozharness: 2016-11-22 04:05:37.952007Z] Running install step. 20:05:37 INFO - Running pre-action listener: _resource_record_pre_action 20:05:37 INFO - Running main action method: install 20:05:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:05:38 INFO - Reading from file tmpfile_stdout 20:05:38 INFO - Detecting whether we're running mozinstall >=1.0... 20:05:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:05:38 INFO - Reading from file tmpfile_stdout 20:05:38 INFO - Output received: 20:05:38 INFO - Usage: mozinstall [options] installer 20:05:38 INFO - Options: 20:05:38 INFO - -h, --help show this help message and exit 20:05:38 INFO - -d DEST, --destination=DEST 20:05:38 INFO - Directory to install application into. [default: 20:05:38 INFO - "/builds/slave/test"] 20:05:38 INFO - --app=APP Application being installed. [default: firefox] 20:05:38 INFO - mkdir: /builds/slave/test/build/application 20:05:38 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'] 20:05:38 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 20:06:10 INFO - Reading from file tmpfile_stdout 20:06:10 INFO - Output received: 20:06:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:06:10 INFO - Running post-action listener: _resource_record_post_action 20:06:10 INFO - [mozharness: 2016-11-22 04:06:10.929563Z] Finished install step (success) 20:06:10 INFO - [mozharness: 2016-11-22 04:06:10.929719Z] Running run-tests step. 20:06:10 INFO - Running pre-action listener: _resource_record_pre_action 20:06:10 INFO - Running main action method: run_tests 20:06:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:06:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:06:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:06:10 INFO - 2016-11-21 20:06:10.944 screenresolution[1617:7397] starting screenresolution argv=screenresolution get 20:06:10 INFO - 2016-11-21 20:06:10.946 screenresolution[1617:7397] Display 0: 1600x1200x32@60 20:06:10 INFO - 2016-11-21 20:06:10.952 screenresolution[1618:7399] starting screenresolution argv=screenresolution list 20:06:10 INFO - Available Modes on Display 0 20:06:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:06:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:06:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:06:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:06:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:06:11 INFO - Intel Iris: 20:06:11 INFO - Chipset Model: Intel Iris 20:06:11 INFO - Type: GPU 20:06:11 INFO - Bus: Built-In 20:06:11 INFO - VRAM (Dynamic, Max): 1536 MB 20:06:11 INFO - Vendor: Intel (0x8086) 20:06:11 INFO - Device ID: 0x0a2e 20:06:11 INFO - Revision ID: 0x0009 20:06:11 INFO - Displays: 20:06:11 INFO - Display: 20:06:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:06:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:06:11 INFO - Display Serial Number: 1600x1200 60 20:06:11 INFO - Main Display: Yes 20:06:11 INFO - Mirror: Off 20:06:11 INFO - Online: Yes 20:06:11 INFO - Rotation: Supported 20:06:11 INFO - Return code: 0 20:06:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:06:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:06:11 INFO - Python 2.7.3 20:06:11 INFO - Return code: 0 20:06:11 INFO - grabbing minidump binary from tooltool 20:06: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')]} 20:06:11 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 0x104240d30>, '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 0x1040a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104238b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:06:11 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 20:06:11 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 20:06:11 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 20:06:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:06:11 INFO - Return code: 0 20:06:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:06:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:06:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:06:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:06:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:06:11 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/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 20:06:11 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/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0209 --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 20:06:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ezlCsQchIg/Render', 20:06:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:11 INFO - 'HOME': '/Users/cltbld', 20:06:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:11 INFO - 'LOGNAME': 'cltbld', 20:06:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:06:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:06:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:11 INFO - 'MOZ_NO_REMOTE': '1', 20:06:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:06:11 INFO - 'NO_EM_RESTART': '1', 20:06:11 INFO - 'PAGER': '/bin/cat', 20:06:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:11 INFO - 'PWD': '/builds/slave/test', 20:06:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:06:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:11 INFO - 'SHELL': '/bin/bash', 20:06:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.efWtdxrCQY/Listeners', 20:06:11 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:06:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:11 INFO - 'USER': 'cltbld', 20:06:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:11 INFO - 'XPC_FLAGS': '0x0', 20:06:11 INFO - 'XPC_SERVICE_NAME': '0', 20:06:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:11 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/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 20:06:11 INFO - mozversion application_buildid: 20161121185750 20:06:11 INFO - mozversion application_changeset: 998a69cbaf54e31bb5d1d95d49cde70cfe89153c 20:06:11 INFO - mozversion application_display_name: Nightly 20:06:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:06:11 INFO - mozversion application_name: Firefox 20:06:11 INFO - mozversion application_remotingname: firefox 20:06:11 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 20:06:11 INFO - mozversion application_vendor: Mozilla 20:06:11 INFO - mozversion application_version: 53.0a1 20:06:11 INFO - mozversion platform_buildid: 20161121185750 20:06:11 INFO - mozversion platform_changeset: 998a69cbaf54e31bb5d1d95d49cde70cfe89153c 20:06:11 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 20:06:11 INFO - mozversion platform_version: 53.0a1 20:06:11 INFO - using testdate: 1479787571 20:06:11 INFO - actual date: 1479787571 20:06:11 INFO - starting webserver on 'localhost:49243' 20:06:11 INFO - SUITE-START | Running 2 tests 20:06:11 INFO - TEST-START | dromaeo_css 20:06:11 INFO - operating with platform_type : mac_ 20:06:11 INFO - Initialising browser for dromaeo_css test... 20:06:11 INFO - TEST-INFO | started process 1628 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp9ikv2x/profile http://localhost:49243/getInfo.html) 20:06:14 INFO - PROCESS | 1628 | 2016-11-21 20:06:14.787 plugin-container[1630:7567] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 20:06:14 INFO - PROCESS | 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:06:14 INFO - PROCESS | 1628 | 2016-11-21 20:06:14.790 plugin-container[1630:7567] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 20:06:14 INFO - PROCESS | 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:06:14 INFO - PROCESS | 1628 | 2016-11-21 20:06:14.790 plugin-container[1630:7567] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:06:14 INFO - PROCESS | 1628 | __metrics Screen width/height:1600/1200 20:06:14 INFO - PROCESS | 1628 | colorDepth:24 20:06:14 INFO - PROCESS | 1628 | Browser inner width/height: 1024/689 20:06:14 INFO - PROCESS | 1628 | __metrics 20:06:14 ERROR - PROCESS | 1628 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 20:06:15 INFO - TEST-INFO | 1628: exit 0 20:06:15 INFO - Browser initialized. 20:06:15 INFO - Running cycle 1/1 for dromaeo_css test... 20:06:15 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp9ikv2x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:06:18 INFO - PROCESS | 1631 | 2016-11-21 20:06:18.599 plugin-container[1632:7798] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 20:06:18 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:06:18 INFO - PROCESS | 1631 | 2016-11-21 20:06:18.600 plugin-container[1632:7798] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 20:06:18 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:06:18 INFO - PROCESS | 1631 | 2016-11-21 20:06:18.601 plugin-container[1632:7798] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:06:28 INFO - PROCESS | 1631 | RSS: Main: 243290112 20:06:28 INFO - PROCESS | 1631 | 20:07:17 INFO - PROCESS | 1631 | 1479787637810 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] 20:09:31 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 20:09:33 INFO - PROCESS | 1631 | RSS: Main: 250261504 20:09:33 INFO - PROCESS | 1631 | 20:12:35 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 20:12:36 INFO - PROCESS | 1631 | RSS: Main: 260116480 20:12:36 INFO - PROCESS | 1631 | 20:15:38 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 20:15:39 INFO - PROCESS | 1631 | RSS: Main: 264433664 20:15:39 INFO - PROCESS | 1631 | 20:18:40 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 20:18:41 INFO - PROCESS | 1631 | RSS: Main: 263032832 20:18:41 INFO - PROCESS | 1631 | 20:21:44 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 20:21:45 INFO - PROCESS | 1631 | RSS: Main: 267485184 20:21:45 INFO - PROCESS | 1631 | 20:24:47 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 20:24:48 INFO - PROCESS | 1631 | RSS: Main: 270106624 20:24:48 INFO - PROCESS | 1631 | 20:24:48 INFO - PROCESS | 1631 | __start_tp_report 20:24:48 INFO - PROCESS | 1631 | _x_x_mozilla_page_load 20:24:48 INFO - PROCESS | 1631 | _x_x_mozilla_page_load_details 20:24:48 INFO - PROCESS | 1631 | |i|pagename|runs| 20:24:48 INFO - PROCESS | 1631 | |0;dojo.html;2563.3847876509635;3358.815265160417;3372.510985907042;3392.2537041850783;3405.829708514575;4699.90600187996;5633.40849210832;5639.68981706006;5653.575981801366;5712.200291959129;4569.702969307001;5627.915581266284;5665.150227465882;5715.057015592432;5719.113537401699;5094.235864620299;5162.561182299509;5176.404713457944;5245.501677340666;5265;5207.218917162418;5316.707581083039;5322.7072511011875;5329.786808527654;5362.1420572708375;4437.467503899553;4557.817687292504;4791.305260737174;5042;5212.973935130319;5095.949040509621;5158.458361871987;5171.7672704728375;5214.191800270926;5223.921641175385;5139.897202055938;5146.511081447294;5159.922601160985;5180.274761533388;5227.712475813829;5420.13277875542;5613;5630.864448959108;5644.3508996465525;5685.744141513642;6052.848678783022;6130.703893785035;6135.171751813493;6212.502999760053;6220.377962203788;6235.501159907197;6291.307956124822;6295.842603934892;6298.212723409574;6318.589291696025;5686.232358631614;5744.82765517031;5763.106718458645;5798.623089499169;5805.01314776489;6216.533759968021;6223.0354295084335;6270.498360131179;6284.622922624566;6293.213348331458;5029.64792464524;6128.900367973304;6131.294901086343;6185.257769067651;6196.628202307876;6107.097787096225;6250.9062364064575;6270.968645156745;6284.183056202664;6285.465735412449;6050.6974651267265;6121.57491608479;6134.447899689049;6142.754289828446;6160.969195154085;6029.336772954972;6137.693115344215;6188.1027251048345;6220.7511699531515;6257.937420625735;3930.253251882133;4841.8547443576745;4880.633952453581;4915.778789954461;4926.064047830901;3771.717121215865;4352.673549483801;4378.408914796525;4410.625096866738;4416.933745993812;4728.95271047292;5808.3901190375245;5810.4770570648;5812.534997200214;5837.182794408786;6114.419130182626;6149.169862068653;6151.661658608819;6182.381761823872;6243.594166379171;5734.225879506297;5747.195392645033;5762.510186634099;5767.653940763567;5824.184614154022;5362.570994320445;5367.087595108841;5379.569634429236;5388.272583201296;5388.676679399248;5599.8880022400135;6126.754929802847;6228.719707613168;6242.5942313749465;6243.406876346921;5103.464136265788;5124.718140502308;6113.780377410931;6124.808163753463;6125.11336343068;5193.68837869729;6174.66039367839;6193.318734939063;6230.844228894314;6288.811335659754;4964.975175124101;5145.716985565829;6205.503559715302;6211.0372892201785;6260.686965651608;6125.111858780541;6237.313895471404;6252.406021427957;6259.405356491126;6264.154339164337;6018.909716354171;6048.939510605013;6075.1798507200965;6084;6102.542309326729;5907.32065812437;5963.493103086113;5969.582129250911;6046.879062418815;6087.726052327567;5753.395893431297;5765.97117014429;5843.766249350148;5999.91000135007;6041.879162416815;6093.634381937098;6099.359567245553;6103.23709536308;6109.480694141048;6132.202813634378;5739.540836732968;5812.041013232769;5828.213191219131;5843.795467158629;5943.910841337297 20:24:48 INFO - PROCESS | 1631 | |1;ext.html;416.3338658146964;481.16998178142705;505.33800721055445;548.4186762032249;549.3243310727803;39092.60907390929;40604.79697601512;40761.77714668557;42000.52998145066;42024.36963445551;41084.58915410845;41627.58372416275;41723.79138104313;41753.16493670125;41797;8318;9163.08369163085;9342.972273049993;9498.382605130679;9517.524123793783;9304.441733495978;9324.533773311341;9434.528273586327;9458.621655133786;9488.525573721321;7624.428167887403;9315.906840931571;9328.626854925795;9398.483083430408;9466.574004169828;9273.675421360254;9374.953125234364;9399.953000235024;9451.52742362886;9471.715848524555;9254.07459254076;9317.953410232905;9387.249020078378;9396.261691587079;9428.717138485856;39369.60630393688;39684.60315396838;39769.80115099406;39812.60187398118;39823.20353592912;3121.9687803122133;3322.4850148227065;3371.9884034789466;3419.452887537982;3488.215151590897;29930.95241666553;29945.401091978038;30626.928057518104;30956.690433095606;31581;3027.318853258021;3197.520371944204;3285.737141028712;3295.703386695185;3356.4461863792444;423440;482620;503671.96328036615;507854;510550.89449105406;61963.6901815488;62342;62407.75184496376;62549.68725156345;62607.747845042846;34345.48481772775;34655.82672086624;34956.12609684727;36145.27709445796;36190.63809361925;15499;15637.452689155825;15663.530094097196;15665.29506172225;15695.372185112696;11872.940635296769;11965;11997.880021199851;12000.57997970067;12035;16045.358185672676;19099.713504297448;19283.710744338845;19474.902625486782;19504.70742938857;7779.7666070017995;7838.451308408356;7839.961609981332;7913.525188488709;7933.087694915159;817.9468334558235;909.6816114359921;986.487026746088;1012.898710128996;1034.886162522122;28741;29614.851925740666;29733.55399669007;30021.39957200844;30329.393412131634;899.6356475627381;965.1747755668943;972.6206779356057;1074.2856000759564;1167.6146871532374;14779.704405911822;15339.846601534065;15437.070366204225;15509.379624814881;15743.30032442398;670438;674928;675255;676864;677848;57132.714336428886;57291.42708572861;57512.71243643755;57542.13686794702;57779.13331300034;1310.2793463595176;1339.9531016414185;1350.3383342162467;1368.5415385845654;1377.6831328794433;27608.678375585725;28116.71883281141;30222;30899;31321.37357252888;41270.793646031576;41668.58331416647;48180.759096204296;51040.70316835677;51257.743711281204;473762;479339;576488;576696;577446;22974.42563935848;25753.613695794204;26308.8684556576;26799.330016749736;27078.458430831666;46048.07903841837;46391.216527007135;46755.29867052065;46828;46921.53078469171;37872.24255514929;38072.80963595164;38463;38840.61159388483;40029.39955900722;38237.23525529418;38531.83490641484;38967.22065558616;39383;39595;30483.69516304897;32751.34497310088;36952.81523592365;37426;38286.61713382942;27068.729312706622;28211.71788282091;28700.569491457227;33822.83088584541;34656;25504.489910201322;25524.23427297184;25566.10518631839;25844.870775646003;25938.481230375663 20:24:48 INFO - PROCESS | 1631 | |2;jquery.html;338.2862160840627;370.7164019525062;380.30024754451813;387.190771288008;403.2298310227466;18822.52943676407;19184.136713847874;19432.708509372354;20223.39329820104;20410.979451027426;15535.844641553582;19250.133743981518;19385.32151374702;19759.901200493976;19935.102920368583;5203.557697595709;5206.45332240115;5467.480589344006;5473.945260547403;5474.91787623186;4540.455145382559;5242.9213561796605;5309.097453432925;5378.973105134469;5391.056565101111;5271.894562108756;5390.946090539083;5395.298611180541;5396.325459317585;5407.864803379907;5371.630234290251;5521.503064724174;5992.101184822268;6002.039673652238;6007.339192688801;5014.222795466672;5177.37871455424;5248.422673505911;5260.316158899319;5286.022085914118;12886.420111095023;12982.156159849674;12998;13008.739825203538;13051.543195988095;1920.2319072371163;2143.74275086989;2149.6775483677416;2150.354893531934;2198.967015494769;10009.949950250202;10022.89977100227;10305;10372;14667.606577375132;1838.117703502313;1848.5563464768493;1902.6289873474677;1938.4959910423252;1953.8339241164517;102027.46958795624;119233;121670.17494737585;122597;123854.38072809669;11114.55541778336;11262.324260544394;11450.312981221154;11535.711607209887;11708.648740537797;14355.425782968774;14536.27318634064;14576.489822856149;14597.416103355747;14604;6835;7973.362131029504;8254.876176857351;8276.751697449086;8386.580670966548;8091.635876385577;8106.9594652027545;8109.75670729879;8260.173982601691;8285.295749861329;4500.77496125199;4508.661850361236;4521.784325680459;4531.932021019687;4603.700759450625;1910.3695780392436;1946.7223348682076;1947.9123439444975;1959.9510012249807;1979.435860779671;751.5115175136206;779.3764988009569;801.0107517216234;847.9527783187758;908.7137551671202;15926;17879.73180402295;19358.903205483883;19767.209311627663;21493.08390864332;892.161368313796;922.8338898998111;930.2557953637063;959.1176117971482;1014.452195814252;11022.944885275683;11335.036521895567;11342.092632589372;11385.975262226273;11408.657740267805;517709;585247;586585;591391;594839;115415.42292288653;123145;130244;134196;135994;954.2747511891013;969.2100937735722;996.4917891875195;1002.2282842211456;1004.2066767253933;17190.570235743708;17600.29598816033;18352.541186470444;18504.81495185031;18531.44405667832;43575.9106022352;44686;45185.77407113075;51750.223746643074;52510.73744631252;67676;71335.28664713286;73281;80167.19832801598;80556.59721701354;7594.202608726005;7780.610969451392;7842.9215707842195;7846.176151504238;7944.285014348737;8954.417962832395;12154.75690486203;12164.39178041112;12273.631791046282;12473.688157796129;9304.069593040913;10019.649312274036;10614.778846297815;10712.35725856451;10866.05862673244;10437.68686939393;10473;10583.576656933945;10755.731106722395;10782.460876956278;10734.51694673726;10968.670939871816;10978.231523793578;11050.447477626247;11150.107991360834;6309.90535141964;7653.961730191313;7710.228977102245;7797.259260370256;7894.789239989482;9445.480498572644;10467.057964783206;10699.090577301016;10787.867273936125;10794.762788655395 20:24:48 INFO - PROCESS | 1631 | |3;mootools.html;1481.8221813382395;1525.5804653720224;1709.769181160543;1738.895801166259;1807.9819201807977;6344.777932772354;7818.765437036884;8061.476004059739;8501.91498085019;8558.828823423535;8016.839663206732;8316.542590157538;8333.333333333334;8461.53461559614;8462.661493540267;2283.8401311908215;2310.260716570698;2326.906923723049;2340.1692399198296;2345.2729653807373;2328.9650655240102;2351.1065794997876;2380.0836677879056;2382.0233704181287;2388.7014123234594;2298.2645553422913;2322.036354912709;2334.7781960713705;2352.070931981866;2358.905643774247;2289.1301305503966;2305.2853615379204;2309.180241014449;2322.349742072214;2322.686437330964;2292.0487997481027;2311.8034967027825;2313.5372925414986;2317.7798109179603;2319.5592837360846;8355.749327520183;8355.832883342298;8361.665533378658;8406.159384061606;8421.831563368698;5061.443241243497;5235.240890070956;5242.9213561796605;5245.423003469616;5263.973680131575;3317.4526203176447;3419.1793969447403;3445;3634.1459756957092;3664.0839790052487;1010.5200029985772;1040.344091101471;1073.3613499967507;1075.0109898893038;1096.1011970184452;10894.564217431292;10946.014858662758;10982.341059536453;11013;11017.173711971634;3730.8321125549414;4576.977115114403;4635.930461043087;4653.139169253699;4711.175544279806;9332.860007099898;9340.20608248293;9436.622535098657;9492.240620750323;9523.952380238054;5202.3497062867145;5202.713850738246;5218.843434696965;5221.477852214824;5222.2427747976335;4307.203167414038;4343.695941284143;4698.95301046992;4752.857414277577;4796.760161991887;3777.395616701315;3797.810109494514;3859.4403811447182;3869.5356557212754;3873.748206366577;1263.6019653809205;1264.7913094339515;1264.9051321150766;1271.4723389793317;1280.731046480231;747.872861613527;802.253903869407;888.0985799413601;891.0154279521165;893.3344658229661;4504.662150338738;5460.26286451324;5531.419200983919;5616.185653080281;5678.744456499468;369.76335145506897;424.35074336265563;429.9852348457637;441.76807176232734;452.9139463501924;3719.1445967427644;3719.9070023249637;3745.887623371303;3814.9427758583643;3832.5784163741964;9813.362131461576;9960.003999600127;10055.346402483816;10203.336783109222;10211.591536338612;10168.186545076524;10176;10184.338018028804;10188.541515631814;10203.693889183336;6964.616946068015;7750.457467977188;7889.171636978034;7945.205479452125;7995.440319177718;5450.91823622638;5540.085885828793;5680.233168522197;5688.943110568841;5726.398728133486;9037.909620903707;9259;9264.675736349196;9284.489353085644;9302.209312208539;9281.39670921388;9344.299177561574;9377;9387.014363491735;9446.24430045581;6636.435902948304;6851.143607049119;6978.406835419046;7081.787546373617;7293.197748247582;7883.605819709106;8014.158513356014;8060.39547033963;8061.516309021477;8067.354611630938;6262.718177682106;6466.935330646634;7088.574685518885;7108.893366599608;7333.816654583678;5812.9709351452975;5846.713722980938;5912.46787789101;6226.3151053385;6861.833488307099;6532.8366790830605;6669.266380698216;6696.19645642526;6742.494312926452;6778.356056174655;4170;4713.882152946204;4734.97632511849;4777.641676874179;4809.374781278411;4685.2035154023215;4738.265568836836;4786.803299175206;4832.589229915497;4948.480409557089 20:24:48 INFO - PROCESS | 1631 | |4;prototype.html;241.98306118571702;304.54926708471476;308.98764049437995;313.131061304879;316.69122605459665;4387.881090321967;4910.680805747628;5311.097113490706;5349.786008559663;5564.944350556499;3954.8050831788346;4390.121975604876;4447.888802779932;4778.426588809348;5395.514403703666;3446.4141096013677;4122.051928056549;4126.5667104954;4162;4178.415021896937;3121.6878312168715;4140.917181656365;4141.6065473779945;4146.502419709639;4177.561356057616;3274.4433446314065;3372.6964573188407;3554.484599733037;4055.2295063937904;4091.9386209206887;3330.834208027183;3427.9485807712904;3456.4296891012955;3668.614795446493;4041.7979101045125;3068.2329417645587;3359.7312215022985;3558.9856890786;3560.2345495718287;3572.2498275362204;4438.423005009328;5150.892558099989;5264.52619264268;5352.732363381815;5400.756965936543;4042;4071.307877660805;4625.653076019312;4676.953230467686;4703.871925169758;5687.061634830248;5693.601447898649;5725.227094342252;5726.226959360475;5764.33710123337;3261.0053933550257;3327.085051610802;3421.794692318469;3691;3907.760080312864;5239.947600523984;5963.344032156459;6008.6094403864045;6010.62580280791;6028.5478589106;3908.882733517999;3920.1375697347107;3933.9803300983885;3936.193080418507;3956.742811717229;3372.8819491317686;4006.8580454570306;4021.195760847842;4031.4355990161403;4041;4293.025208404107;4566.95433045672;4625.121226966866;4637.211674015425;4647.442306923191;5276.472352764769;5750.591105179258;5751;5751.769929202785;5781.306243250837;1050.4590136079926;1223.957161499361;1250.8248845161684;1258.4018296763059;1281.7628738683372;852.7740148860557;864.2524216552637;875.0636818604032;876.7506303517462;878.4114643189079;4260.146836126295;4673;4701.040402375458;5019.949800501949;5162.6128040397125;5016.372953380827;5316.873250699751;5541.722913854291;5574.888502229933;5602.487328421304;4372.497162826316;4556.407667003268;4721.763911804396;4750.881227969259;5142.948570514322;2611.973880261211;2903.970960290412;2913.2571194345305;2916.9936371951644;2921.0643303479865;4903.899944058211;5266.78932842682;5537.369749369018;5682.204491371212;5725.742341594639;5320.866978325573;5660.377358490563;5704.80033198836;5736.758731316792;5764.5676574257095;4871.95128048715;5723.7424315905055;5729.650843154287;5743.310802703619;5753.769849206069;4555.681102322806;5120.465369642051;5564.248826408545;5608.279554854107;5619.466150715675;4826.541478559531;4918.5573298403315;5056.544910958031;5318.893622127458;5401.72991350423;5431.972840135774;5765.798197063083;6049.667268300285;6141.662208578571;6154.599461385131;3453.723702103876;3743.2326373093997;3966.504186976628;3970.372147419544;3989.3218152914656;4741.857744267678;5113.360829896263;5475.4524547544925;5925.4370834770625;5978.282606087298;4730.982838689698;4757.35775670294;5113.028524580318;5824.94175058244;5827.912581311369;5772.451617096369;5789.131630255495;5799.5070419014855;5800.854978625568;5809.709514524341;5311.840644780663;5328.706921119376;5417.702026388429;5666.0735349981205;5674.716264186856;4789.616830653609;5770.798022069207;5801.506871915934;5806.738696758741;5824.213731146241;5214.271795666117;5382.434844341287;5653.491185793298;5659.745311461076;5700.828975130753 20:24:48 INFO - PROCESS | 1631 | |5;yui.html;637.4747645520272;694.1947341084342;721.9711211551542;753.6985205917629;787.4212453846421;14315.069520481175;14829.77755333671;15072.171030593314;15089.924550377247;15192.772108418369;13682.384292706827;13834.584962451117;13926.79109813351;13937;13944.302784860767;704.7392464788036;761.8628646843566;824.4764574495193;832.8958880139983;841.7811369044032;647.6470323673585;670.1355251725269;740.4557650127152;805.3879051920524;825.2407784837964;727.8726222910996;893.8435773739569;898.6225785170244;899.8065415935572;901.1574178143425;805.9597020148998;819.6557445872747;854.4360721923532;888.3781353052889;889.3418870036191;642.1715986377567;646.977355792545;655.8360409897525;657.7960832142052;658.256170527301;13810.376028554643;14124.858751412457;14126.646833829134;14163;14165.008449408546;11188.776224475465;12876.613701588967;12993.350332483433;13213.009024323213;13265.469381224835;10747.731306717316;11493.655190344303;11496.310221386744;11524.135689823297;11583.594574189863;3013.5027720426106;3575.1777091268896;3592.4790905318846;3601.873934412283;3626.818659067036;73654.79035628939;82468;86544.70182947261;90901;92572.14855702849;8250.752477425687;10156.441395723159;10190.694279171637;10207.183425325957;10644.09525190352;18928.05359732008;19735.210591576175;19815.20739170417;20113.29603463872;20175.39473815788;6888.793336199922;6995.580265184105;7079.787606371817;7231.566106033655;7342.632868356668;4155.148194620096;4184.832606695759;4263.680223983214;4287.421198138274;4342.739435633872;3379.881704140343;3432.828358582061;3435.2957643682985;3474.7915125092577;3477.1307173206696;583.6877270660261;642.913476225178;650.06066234291;658.3285049249738;673.9191297044387;532.6857154278994;582.4175824175824;584.4681339980597;620.6617393520543;658.8085883240038;7145.499815013003;7675.654595543214;8066.677332906618;8776.122387761172;9124.863127053099;581.1254062635707;604.4469310580857;604.9939500605026;641.1760887259942;643.9356064393617;4286.507051689034;4292.763897985641;4543.628654318272;4546.727196368229;4565.155446242456;78777.60611196907;78923.60538197387;89195;121653.39173304077;124206;77140;77257;105664.47167764265;105792;107293.46353268184;78399.60800195963;84892;87527.56236218906;87778.56110719533;89649.10350896537;8595.269402100892;8664.306855451674;8702.216800487986;8809.823803523765;8833;46110.30834537418;50676.74661626668;51037;53196;55883.16175257293;42430.78784606181;67548.32451675617;70680.29319706737;74418;84419.31161376862;9231.753713248625;10027.498625068863;10151.390916545031;12358.382080896099;12877.871221287667;14056.086354386933;14085;14174.14955102697;14291.499797507038;14406.711865762416;13125.278644672238;13128;13161;13274.867251327749;13847;13018.934905325414;13099.738005240033;13290.269035203157;13583.32083395846;13715.588532344045;12703.555375561811;12715.427805748948;12728.363581821057;12821.807672884728;12898.871011289766;10299.793845160091;10506.579736810747;10783;10833.837492437777;10874.184436167161;10430.634927777492;10929.945350273198;11086.833697494383;11356.432178391213;11527.365994870028 20:24:48 INFO - PROCESS | 1631 | __end_tp_report 20:24:48 INFO - PROCESS | 1631 | __start_cc_report 20:24:48 INFO - PROCESS | 1631 | _x_x_mozilla_cycle_collect,376 20:24:48 INFO - PROCESS | 1631 | __end_cc_report 20:24:48 INFO - PROCESS | 1631 | __startTimestamp1479788688018__endTimestamp 20:24:48 INFO - PROCESS | 1631 | 20:24:48 INFO - PROCESS | 1631 | ------- Summary: start ------- 20:24:48 INFO - PROCESS | 1631 | Number of tests: 6 20:24:48 INFO - PROCESS | 1631 | 20:24:48 INFO - PROCESS | 1631 | [#0] dojo.html Cycles:165 Average:5632.71 Median:5812.04 stddev:696.01 (12.0%) stddev-sans-first:655.18 20:24:48 INFO - PROCESS | 1631 | Values: 2563.4 3358.8 3372.5 3392.3 3405.8 4699.9 5633.4 5639.7 5653.6 5712.2 4569.7 5627.9 5665.2 5715.1 5719.1 5094.2 5162.6 5176.4 5245.5 5265.0 5207.2 5316.7 5322.7 5329.8 5362.1 4437.5 4557.8 4791.3 5042.0 5213.0 5095.9 5158.5 5171.8 5214.2 5223.9 5139.9 5146.5 5159.9 5180.3 5227.7 5420.1 5613.0 5630.9 5644.4 5685.7 6052.8 6130.7 6135.2 6212.5 6220.4 6235.5 6291.3 6295.8 6298.2 6318.6 5686.2 5744.8 5763.1 5798.6 5805.0 6216.5 6223.0 6270.5 6284.6 6293.2 5029.6 6128.9 6131.3 6185.3 6196.6 6107.1 6250.9 6271.0 6284.2 6285.5 6050.7 6121.6 6134.4 6142.8 6161.0 6029.3 6137.7 6188.1 6220.8 6257.9 3930.3 4841.9 4880.6 4915.8 4926.1 3771.7 4352.7 4378.4 4410.6 4416.9 4729.0 5808.4 5810.5 5812.5 5837.2 6114.4 6149.2 6151.7 6182.4 6243.6 5734.2 5747.2 5762.5 5767.7 5824.2 5362.6 5367.1 5379.6 5388.3 5388.7 5599.9 6126.8 6228.7 6242.6 6243.4 5103.5 5124.7 6113.8 6124.8 6125.1 5193.7 6174.7 6193.3 6230.8 6288.8 4965.0 5145.7 6205.5 6211.0 6260.7 6125.1 6237.3 6252.4 6259.4 6264.2 6018.9 6048.9 6075.2 6084.0 6102.5 5907.3 5963.5 5969.6 6046.9 6087.7 5753.4 5766.0 5843.8 5999.9 6041.9 6093.6 6099.4 6103.2 6109.5 6132.2 5739.5 5812.0 5828.2 5843.8 5943.9 20:24:48 INFO - PROCESS | 1631 | 20:24:48 INFO - PROCESS | 1631 | [#1] ext.html Cycles:180 Average:68858.23 Median:39727.20 stddev:153264.45 (385.8%) stddev-sans-first:153608.25 20:24:48 INFO - PROCESS | 1631 | Values: 416.3 481.2 505.3 548.4 549.3 39092.6 40604.8 40761.8 42000.5 42024.4 41084.6 41627.6 41723.8 41753.2 41797.0 8318.0 9163.1 9343.0 9498.4 9517.5 9304.4 9324.5 9434.5 9458.6 9488.5 7624.4 9315.9 9328.6 9398.5 9466.6 9273.7 9375.0 9400.0 9451.5 9471.7 9254.1 9318.0 9387.2 9396.3 9428.7 39369.6 39684.6 39769.8 39812.6 39823.2 3122.0 3322.5 3372.0 3419.5 3488.2 29931.0 29945.4 30626.9 30956.7 31581.0 3027.3 3197.5 3285.7 3295.7 3356.4 423440.0 482620.0 503672.0 507854.0 510550.9 61963.7 62342.0 62407.8 62549.7 62607.7 34345.5 34655.8 34956.1 36145.3 36190.6 15499.0 15637.5 15663.5 15665.3 15695.4 11872.9 11965.0 11997.9 12000.6 12035.0 16045.4 19099.7 19283.7 19474.9 19504.7 7779.8 7838.5 7840.0 7913.5 7933.1 817.9 909.7 986.5 1012.9 1034.9 28741.0 29614.9 29733.6 30021.4 30329.4 899.6 965.2 972.6 1074.3 1167.6 14779.7 15339.8 15437.1 15509.4 15743.3 670438.0 674928.0 675255.0 676864.0 677848.0 57132.7 57291.4 57512.7 57542.1 57779.1 1310.3 1340.0 1350.3 1368.5 1377.7 27608.7 28116.7 30222.0 30899.0 31321.4 41270.8 41668.6 48180.8 51040.7 51257.7 473762.0 479339.0 576488.0 576696.0 577446.0 22974.4 25753.6 26308.9 26799.3 27078.5 46048.1 46391.2 46755.3 46828.0 46921.5 37872.2 38072.8 38463.0 38840.6 40029.4 38237.2 38531.8 38967.2 39383.0 39595.0 30483.7 32751.3 36952.8 37426.0 38286.6 27068.7 28211.7 28700.6 33822.8 34656.0 25504.5 25524.2 25566.1 25844.9 25938.5 20:24:48 INFO - PROCESS | 1631 | 20:24:48 INFO - PROCESS | 1631 | [#2] jquery.html Cycles:180 Average:33483.67 Median:2146.71 stddev:96401.12 (4490.6%) stddev-sans-first:96639.42 20:24:48 INFO - PROCESS | 1631 | Values: 338.3 370.7 380.3 387.2 403.2 18822.5 19184.1 19432.7 20223.4 20411.0 15535.8 19250.1 19385.3 19759.9 19935.1 5203.6 5206.5 5467.5 5473.9 5474.9 4540.5 5242.9 5309.1 5379.0 5391.1 5271.9 5390.9 5395.3 5396.3 5407.9 5371.6 5521.5 5992.1 6002.0 6007.3 5014.2 5177.4 5248.4 5260.3 5286.0 12886.4 12982.2 12998.0 13008.7 13051.5 1920.2 2143.7 2149.7 2150.4 2199.0 10009.9 10022.9 10305.0 10372.0 14667.6 1838.1 1848.6 1902.6 1938.5 1953.8 102027.5 119233.0 121670.2 122597.0 123854.4 11114.6 11262.3 11450.3 11535.7 11708.6 14355.4 14536.3 14576.5 14597.4 14604.0 6835.0 7973.4 8254.9 8276.8 8386.6 8091.6 8107.0 8109.8 8260.2 8285.3 4500.8 4508.7 4521.8 4531.9 4603.7 1910.4 1946.7 1947.9 1960.0 1979.4 751.5 779.4 801.0 848.0 908.7 15926.0 17879.7 19358.9 19767.2 21493.1 892.2 922.8 930.3 959.1 1014.5 11022.9 11335.0 11342.1 11386.0 11408.7 517709.0 585247.0 586585.0 591391.0 594839.0 115415.4 123145.0 130244.0 134196.0 135994.0 954.3 969.2 996.5 1002.2 1004.2 17190.6 17600.3 18352.5 18504.8 18531.4 43575.9 44686.0 45185.8 51750.2 52510.7 67676.0 71335.3 73281.0 80167.2 80556.6 7594.2 7780.6 7842.9 7846.2 7944.3 8954.4 12154.8 12164.4 12273.6 12473.7 9304.1 10019.6 10614.8 10712.4 10866.1 10437.7 10473.0 10583.6 10755.7 10782.5 10734.5 10968.7 10978.2 11050.4 11150.1 6309.9 7654.0 7710.2 7797.3 7894.8 9445.5 10467.1 10699.1 10787.9 10794.8 20:24:48 INFO - PROCESS | 1631 | 20:24:48 INFO - PROCESS | 1631 | [#3] mootools.html Cycles:180 Average:5389.23 Median:4803.07 stddev:2990.87 (62.3%) stddev-sans-first:2984.84 20:24:48 INFO - PROCESS | 1631 | Values: 1481.8 1525.6 1709.8 1738.9 1808.0 6344.8 7818.8 8061.5 8501.9 8558.8 8016.8 8316.5 8333.3 8461.5 8462.7 2283.8 2310.3 2326.9 2340.2 2345.3 2329.0 2351.1 2380.1 2382.0 2388.7 2298.3 2322.0 2334.8 2352.1 2358.9 2289.1 2305.3 2309.2 2322.3 2322.7 2292.0 2311.8 2313.5 2317.8 2319.6 8355.7 8355.8 8361.7 8406.2 8421.8 5061.4 5235.2 5242.9 5245.4 5264.0 3317.5 3419.2 3445.0 3634.1 3664.1 1010.5 1040.3 1073.4 1075.0 1096.1 10894.6 10946.0 10982.3 11013.0 11017.2 3730.8 4577.0 4635.9 4653.1 4711.2 9332.9 9340.2 9436.6 9492.2 9524.0 5202.3 5202.7 5218.8 5221.5 5222.2 4307.2 4343.7 4699.0 4752.9 4796.8 3777.4 3797.8 3859.4 3869.5 3873.7 1263.6 1264.8 1264.9 1271.5 1280.7 747.9 802.3 888.1 891.0 893.3 4504.7 5460.3 5531.4 5616.2 5678.7 369.8 424.4 430.0 441.8 452.9 3719.1 3719.9 3745.9 3814.9 3832.6 9813.4 9960.0 10055.3 10203.3 10211.6 10168.2 10176.0 10184.3 10188.5 10203.7 6964.6 7750.5 7889.2 7945.2 7995.4 5450.9 5540.1 5680.2 5688.9 5726.4 9037.9 9259.0 9264.7 9284.5 9302.2 9281.4 9344.3 9377.0 9387.0 9446.2 6636.4 6851.1 6978.4 7081.8 7293.2 7883.6 8014.2 8060.4 8061.5 8067.4 6262.7 6466.9 7088.6 7108.9 7333.8 5813.0 5846.7 5912.5 6226.3 6861.8 6532.8 6669.3 6696.2 6742.5 6778.4 4170.0 4713.9 4735.0 4777.6 4809.4 4685.2 4738.3 4786.8 4832.6 4948.5 20:24:48 INFO - PROCESS | 1631 | 20:24:48 INFO - PROCESS | 1631 | [#4] prototype.html Cycles:180 Average:4471.48 Median:4907.29 stddev:1413.35 (28.8%) stddev-sans-first:1381.20 20:24:48 INFO - PROCESS | 1631 | Values: 242.0 304.5 309.0 313.1 316.7 4387.9 4910.7 5311.1 5349.8 5564.9 3954.8 4390.1 4447.9 4778.4 5395.5 3446.4 4122.1 4126.6 4162.0 4178.4 3121.7 4140.9 4141.6 4146.5 4177.6 3274.4 3372.7 3554.5 4055.2 4091.9 3330.8 3427.9 3456.4 3668.6 4041.8 3068.2 3359.7 3559.0 3560.2 3572.2 4438.4 5150.9 5264.5 5352.7 5400.8 4042.0 4071.3 4625.7 4677.0 4703.9 5687.1 5693.6 5725.2 5726.2 5764.3 3261.0 3327.1 3421.8 3691.0 3907.8 5239.9 5963.3 6008.6 6010.6 6028.5 3908.9 3920.1 3934.0 3936.2 3956.7 3372.9 4006.9 4021.2 4031.4 4041.0 4293.0 4567.0 4625.1 4637.2 4647.4 5276.5 5750.6 5751.0 5751.8 5781.3 1050.5 1224.0 1250.8 1258.4 1281.8 852.8 864.3 875.1 876.8 878.4 4260.1 4673.0 4701.0 5019.9 5162.6 5016.4 5316.9 5541.7 5574.9 5602.5 4372.5 4556.4 4721.8 4750.9 5142.9 2612.0 2904.0 2913.3 2917.0 2921.1 4903.9 5266.8 5537.4 5682.2 5725.7 5320.9 5660.4 5704.8 5736.8 5764.6 4872.0 5723.7 5729.7 5743.3 5753.8 4555.7 5120.5 5564.2 5608.3 5619.5 4826.5 4918.6 5056.5 5318.9 5401.7 5432.0 5765.8 6049.7 6141.7 6154.6 3453.7 3743.2 3966.5 3970.4 3989.3 4741.9 5113.4 5475.5 5925.4 5978.3 4731.0 4757.4 5113.0 5824.9 5827.9 5772.5 5789.1 5799.5 5800.9 5809.7 5311.8 5328.7 5417.7 5666.1 5674.7 4789.6 5770.8 5801.5 5806.7 5824.2 5214.3 5382.4 5653.5 5659.7 5700.8 20:24:48 INFO - PROCESS | 1631 | 20:24:48 INFO - PROCESS | 1631 | [#5] yui.html Cycles:180 Average:19784.82 Median:4443.18 stddev:28965.57 (651.9%) stddev-sans-first:29011.14 20:24:48 INFO - PROCESS | 1631 | Values: 637.5 694.2 722.0 753.7 787.4 14315.1 14829.8 15072.2 15089.9 15192.8 13682.4 13834.6 13926.8 13937.0 13944.3 704.7 761.9 824.5 832.9 841.8 647.6 670.1 740.5 805.4 825.2 727.9 893.8 898.6 899.8 901.2 806.0 819.7 854.4 888.4 889.3 642.2 647.0 655.8 657.8 658.3 13810.4 14124.9 14126.6 14163.0 14165.0 11188.8 12876.6 12993.4 13213.0 13265.5 10747.7 11493.7 11496.3 11524.1 11583.6 3013.5 3575.2 3592.5 3601.9 3626.8 73654.8 82468.0 86544.7 90901.0 92572.1 8250.8 10156.4 10190.7 10207.2 10644.1 18928.1 19735.2 19815.2 20113.3 20175.4 6888.8 6995.6 7079.8 7231.6 7342.6 4155.1 4184.8 4263.7 4287.4 4342.7 3379.9 3432.8 3435.3 3474.8 3477.1 583.7 642.9 650.1 658.3 673.9 532.7 582.4 584.5 620.7 658.8 7145.5 7675.7 8066.7 8776.1 9124.9 581.1 604.4 605.0 641.2 643.9 4286.5 4292.8 4543.6 4546.7 4565.2 78777.6 78923.6 89195.0 121653.4 124206.0 77140.0 77257.0 105664.5 105792.0 107293.5 78399.6 84892.0 87527.6 87778.6 89649.1 8595.3 8664.3 8702.2 8809.8 8833.0 46110.3 50676.7 51037.0 53196.0 55883.2 42430.8 67548.3 70680.3 74418.0 84419.3 9231.8 10027.5 10151.4 12358.4 12877.9 14056.1 14085.0 14174.1 14291.5 14406.7 13125.3 13128.0 13161.0 13274.9 13847.0 13018.9 13099.7 13290.3 13583.3 13715.6 12703.6 12715.4 12728.4 12821.8 12898.9 10299.8 10506.6 10783.0 10833.8 10874.2 10430.6 10929.9 11086.8 11356.4 11527.4 20:24:48 INFO - PROCESS | 1631 | -------- Summary: end -------- 20:24:48 INFO - PROCESS | 1631 | 20:24:48 INFO - TEST-INFO | 1631: exit 0 20:24:48 INFO - TEST-OK | dromaeo_css | took 1117132ms 20:24:48 INFO - TEST-START | kraken 20:24:48 INFO - operating with platform_type : mac_ 20:24:48 INFO - Initialising browser for kraken test... 20:24:48 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp4aChTn/profile http://localhost:49243/getInfo.html) 20:24:51 INFO - PROCESS | 1643 | 2016-11-21 20:24:51.839 plugin-container[1644:10294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 20:24:51 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:24:51 INFO - PROCESS | 1643 | 2016-11-21 20:24:51.842 plugin-container[1644:10294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 20:24:51 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:24:51 INFO - PROCESS | 1643 | 2016-11-21 20:24:51.842 plugin-container[1644:10294] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:24:51 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 20:24:51 INFO - PROCESS | 1643 | colorDepth:24 20:24:51 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 20:24:51 INFO - PROCESS | 1643 | __metrics 20:24:52 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 20:24:52 INFO - TEST-INFO | 1643: exit 0 20:24:52 INFO - Browser initialized. 20:24:52 INFO - Running cycle 1/1 for kraken test... 20:24:52 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp4aChTn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:24:55 INFO - PROCESS | 1645 | 2016-11-21 20:24:55.745 plugin-container[1646:10411] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 20:24:55 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:24:55 INFO - PROCESS | 1645 | 2016-11-21 20:24:55.745 plugin-container[1646:10411] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 20:24:55 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:24:55 INFO - PROCESS | 1645 | 2016-11-21 20:24:55.746 plugin-container[1646:10411] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:25:05 INFO - PROCESS | 1645 | RSS: Main: 243494912 20:25:05 INFO - PROCESS | 1645 | 20:25:08 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 20:25:09 INFO - PROCESS | 1645 | RSS: Main: 255692800 20:25:09 INFO - PROCESS | 1645 | 20:25:12 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 20:25:12 INFO - PROCESS | 1645 | RSS: Main: 263213056 20:25:12 INFO - PROCESS | 1645 | 20:25:16 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 20:25:16 INFO - PROCESS | 1645 | RSS: Main: 266362880 20:25:16 INFO - PROCESS | 1645 | 20:25:18 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 20:25:19 INFO - PROCESS | 1645 | RSS: Main: 267243520 20:25:19 INFO - PROCESS | 1645 | 20:25:21 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:25:22 INFO - PROCESS | 1645 | RSS: Main: 268836864 20:25:22 INFO - PROCESS | 1645 | 20:25:24 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 20:25:25 INFO - PROCESS | 1645 | RSS: Main: 274767872 20:25:25 INFO - PROCESS | 1645 | 20:25:28 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 20:25:29 INFO - PROCESS | 1645 | RSS: Main: 274063360 20:25:29 INFO - PROCESS | 1645 | 20:25:32 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 20:25:33 INFO - PROCESS | 1645 | RSS: Main: 274092032 20:25:33 INFO - PROCESS | 1645 | 20:25:34 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:25:35 INFO - PROCESS | 1645 | RSS: Main: 273195008 20:25:35 INFO - PROCESS | 1645 | 20:25:37 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:25:38 INFO - PROCESS | 1645 | RSS: Main: 273240064 20:25:38 INFO - PROCESS | 1645 | 20:25:40 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:25:40 INFO - PROCESS | 1645 | RSS: Main: 273313792 20:25:40 INFO - PROCESS | 1645 | 20:25:44 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:25:44 INFO - PROCESS | 1645 | RSS: Main: 273637376 20:25:44 INFO - PROCESS | 1645 | 20:25:47 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:25:48 INFO - PROCESS | 1645 | RSS: Main: 273358848 20:25:48 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 20:25:50 INFO - PROCESS | 1645 | RSS: Main: 276406272 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | __start_tp_report 20:25:50 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 20:25:50 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 20:25:50 INFO - PROCESS | 1645 | |i|pagename|runs| 20:25:50 INFO - PROCESS | 1645 | |0;ai-astar;111;175;120;116;119;117;117;119;119;117 20:25:50 INFO - PROCESS | 1645 | |1;audio-beat-detection;161;150;145;162;125;106;127;117;180;149 20:25:50 INFO - PROCESS | 1645 | |2;audio-dft;270;250;198;197;197;200;195;189;195;194 20:25:50 INFO - PROCESS | 1645 | |3;audio-fft;82;60;61;89;63;63;67;52;85;54 20:25:50 INFO - PROCESS | 1645 | |4;audio-oscillator;81;86;81;61;55;66;120;99;101;74 20:25:50 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;89;87;104;90;89;86;89;86;89;108 20:25:50 INFO - PROCESS | 1645 | |6;imaging-darkroom;110;154;109;96;95;102;97;129;156;127 20:25:50 INFO - PROCESS | 1645 | |7;imaging-desaturate;141;150;92;96;125;148;91;90;94;91 20:25:50 INFO - PROCESS | 1645 | |8;json-parse-financial;57;58;58;57;59;59;59;59;61;67 20:25:50 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;76;64;78;74;78;70;68;70;87;90 20:25:50 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;88;101;111;109;89;89;90;89;89;89 20:25:50 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;167;170;147;171;165;160;147;151;184;170 20:25:50 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;212;197;186;169;192;177;231;239;177;175 20:25:50 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;67;69;68;71;71;69;69;68;68;70 20:25:50 INFO - PROCESS | 1645 | __end_tp_report 20:25:50 INFO - PROCESS | 1645 | __start_cc_report 20:25:50 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,967 20:25:50 INFO - PROCESS | 1645 | __end_cc_report 20:25:50 INFO - PROCESS | 1645 | __startTimestamp1479788750534__endTimestamp 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | ------- Summary: start ------- 20:25:50 INFO - PROCESS | 1645 | Number of tests: 14 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:123.00 Median:119.00 stddev:18.45 (15.5%) stddev-sans-first:19.05 20:25:50 INFO - PROCESS | 1645 | Values: 111.0 175.0 120.0 116.0 119.0 117.0 117.0 119.0 119.0 117.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:149.50 stddev:23.00 (15.4%) stddev-sans-first:23.37 20:25:50 INFO - PROCESS | 1645 | Values: 161.0 150.0 145.0 162.0 125.0 106.0 127.0 117.0 180.0 149.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:208.50 Median:197.50 stddev:27.70 (14.0%) stddev-sans-first:18.38 20:25:50 INFO - PROCESS | 1645 | Values: 270.0 250.0 198.0 197.0 197.0 200.0 195.0 189.0 195.0 194.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:67.60 Median:65.00 stddev:13.08 (20.1%) stddev-sans-first:12.80 20:25:50 INFO - PROCESS | 1645 | Values: 82.0 60.0 61.0 89.0 63.0 63.0 67.0 52.0 85.0 54.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:77.50 stddev:20.00 (25.8%) stddev-sans-first:21.21 20:25:50 INFO - PROCESS | 1645 | Values: 81.0 86.0 81.0 61.0 55.0 66.0 120.0 99.0 101.0 74.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:89.00 stddev:7.72 (8.7%) stddev-sans-first:8.12 20:25:50 INFO - PROCESS | 1645 | Values: 89.0 87.0 104.0 90.0 89.0 86.0 89.0 86.0 89.0 108.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:117.50 Median:155.00 stddev:23.08 (14.9%) stddev-sans-first:24.32 20:25:50 INFO - PROCESS | 1645 | Values: 110.0 154.0 109.0 96.0 95.0 102.0 97.0 129.0 156.0 127.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:111.80 Median:91.00 stddev:26.02 (28.6%) stddev-sans-first:25.37 20:25:50 INFO - PROCESS | 1645 | Values: 141.0 150.0 92.0 96.0 125.0 148.0 91.0 90.0 94.0 91.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:59.00 stddev:2.91 (4.9%) stddev-sans-first:2.96 20:25:50 INFO - PROCESS | 1645 | Values: 57.0 58.0 58.0 57.0 59.0 59.0 59.0 59.0 61.0 67.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:75.50 Median:77.00 stddev:8.21 (10.7%) stddev-sans-first:8.71 20:25:50 INFO - PROCESS | 1645 | Values: 76.0 64.0 78.0 74.0 78.0 70.0 68.0 70.0 87.0 90.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:89.00 stddev:9.06 (10.2%) stddev-sans-first:9.31 20:25:50 INFO - PROCESS | 1645 | Values: 88.0 101.0 111.0 109.0 89.0 89.0 90.0 89.0 89.0 89.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:163.20 Median:168.50 stddev:11.96 (7.1%) stddev-sans-first:12.61 20:25:50 INFO - PROCESS | 1645 | Values: 167.0 170.0 147.0 171.0 165.0 160.0 147.0 151.0 184.0 170.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.50 Median:194.50 stddev:24.35 (12.5%) stddev-sans-first:25.08 20:25:50 INFO - PROCESS | 1645 | Values: 212.0 197.0 186.0 169.0 192.0 177.0 231.0 239.0 177.0 175.0 20:25:50 INFO - PROCESS | 1645 | 20:25:50 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.00 stddev:1.33 (1.9%) stddev-sans-first:1.20 20:25:50 INFO - PROCESS | 1645 | Values: 67.0 69.0 68.0 71.0 71.0 69.0 69.0 68.0 68.0 70.0 20:25:50 INFO - PROCESS | 1645 | -------- Summary: end -------- 20:25:50 INFO - PROCESS | 1645 | 20:25:51 INFO - TEST-INFO | 1645: exit 0 20:25:51 INFO - TEST-OK | kraken | took 63109ms 20:25:51 INFO - SUITE-END | took 1180s 20:25:52 INFO - Completed test suite (00:19:40) 20:25:52 INFO - javascript benchmark 20:25:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7018.724538992982, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2563.3847876509635, 3358.815265160417, 3372.510985907042, 3392.2537041850783, 3405.829708514575, 4699.90600187996, 5633.40849210832, 5639.68981706006, 5653.575981801366, 5712.200291959129, 4569.702969307001, 5627.915581266284, 5665.150227465882, 5715.057015592432, 5719.113537401699, 5094.235864620299, 5162.561182299509, 5176.404713457944, 5245.501677340666, 5265.0, 5207.218917162418, 5316.707581083039, 5322.7072511011875, 5329.786808527654, 5362.1420572708375, 4437.467503899553, 4557.817687292504, 4791.305260737174, 5042.0, 5212.973935130319, 5095.949040509621, 5158.458361871987, 5171.7672704728375, 5214.191800270926, 5223.921641175385, 5139.897202055938, 5146.511081447294, 5159.922601160985, 5180.274761533388, 5227.712475813829, 5420.13277875542, 5613.0, 5630.864448959108, 5644.3508996465525, 5685.744141513642, 6052.848678783022, 6130.703893785035, 6135.171751813493, 6212.502999760053, 6220.377962203788, 6235.501159907197, 6291.307956124822, 6295.842603934892, 6298.212723409574, 6318.589291696025, 5686.232358631614, 5744.82765517031, 5763.106718458645, 5798.623089499169, 5805.01314776489, 6216.533759968021, 6223.0354295084335, 6270.498360131179, 6284.622922624566, 6293.213348331458, 5029.64792464524, 6128.900367973304, 6131.294901086343, 6185.257769067651, 6196.628202307876, 6107.097787096225, 6250.9062364064575, 6270.968645156745, 6284.183056202664, 6285.465735412449, 6050.6974651267265, 6121.57491608479, 6134.447899689049, 6142.754289828446, 6160.969195154085, 6029.336772954972, 6137.693115344215, 6188.1027251048345, 6220.7511699531515, 6257.937420625735, 3930.253251882133, 4841.8547443576745, 4880.633952453581, 4915.778789954461, 4926.064047830901, 3771.717121215865, 4352.673549483801, 4378.408914796525, 4410.625096866738, 4416.933745993812, 4728.95271047292, 5808.3901190375245, 5810.4770570648, 5812.534997200214, 5837.182794408786, 6114.419130182626, 6149.169862068653, 6151.661658608819, 6182.381761823872, 6243.594166379171, 5734.225879506297, 5747.195392645033, 5762.510186634099, 5767.653940763567, 5824.184614154022, 5362.570994320445, 5367.087595108841, 5379.569634429236, 5388.272583201296, 5388.676679399248, 5599.8880022400135, 6126.754929802847, 6228.719707613168, 6242.5942313749465, 6243.406876346921, 5103.464136265788, 5124.718140502308, 6113.780377410931, 6124.808163753463, 6125.11336343068, 5193.68837869729, 6174.66039367839, 6193.318734939063, 6230.844228894314, 6288.811335659754, 4964.975175124101, 5145.716985565829, 6205.503559715302, 6211.0372892201785, 6260.686965651608, 6125.111858780541, 6237.313895471404, 6252.406021427957, 6259.405356491126, 6264.154339164337, 6018.909716354171, 6048.939510605013, 6075.1798507200965, 6084.0, 6102.542309326729, 5907.32065812437, 5963.493103086113, 5969.582129250911, 6046.879062418815, 6087.726052327567, 5753.395893431297, 5765.97117014429, 5843.766249350148, 5999.91000135007, 6041.879162416815, 6093.634381937098, 6099.359567245553, 6103.23709536308, 6109.480694141048, 6132.202813634378, 5739.540836732968, 5812.041013232769, 5828.213191219131, 5843.795467158629, 5943.910841337297], "value": 5587.87410424744, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.3338658146964, 481.16998178142705, 505.33800721055445, 548.4186762032249, 549.3243310727803, 39092.60907390929, 40604.79697601512, 40761.77714668557, 42000.52998145066, 42024.36963445551, 41084.58915410845, 41627.58372416275, 41723.79138104313, 41753.16493670125, 41797.0, 8318.0, 9163.08369163085, 9342.972273049993, 9498.382605130679, 9517.524123793783, 9304.441733495978, 9324.533773311341, 9434.528273586327, 9458.621655133786, 9488.525573721321, 7624.428167887403, 9315.906840931571, 9328.626854925795, 9398.483083430408, 9466.574004169828, 9273.675421360254, 9374.953125234364, 9399.953000235024, 9451.52742362886, 9471.715848524555, 9254.07459254076, 9317.953410232905, 9387.249020078378, 9396.261691587079, 9428.717138485856, 39369.60630393688, 39684.60315396838, 39769.80115099406, 39812.60187398118, 39823.20353592912, 3121.9687803122133, 3322.4850148227065, 3371.9884034789466, 3419.452887537982, 3488.215151590897, 29930.95241666553, 29945.401091978038, 30626.928057518104, 30956.690433095606, 31581.0, 3027.318853258021, 3197.520371944204, 3285.737141028712, 3295.703386695185, 3356.4461863792444, 423440.0, 482620.0, 503671.96328036615, 507854.0, 510550.89449105406, 61963.6901815488, 62342.0, 62407.75184496376, 62549.68725156345, 62607.747845042846, 34345.48481772775, 34655.82672086624, 34956.12609684727, 36145.27709445796, 36190.63809361925, 15499.0, 15637.452689155825, 15663.530094097196, 15665.29506172225, 15695.372185112696, 11872.940635296769, 11965.0, 11997.880021199851, 12000.57997970067, 12035.0, 16045.358185672676, 19099.713504297448, 19283.710744338845, 19474.902625486782, 19504.70742938857, 7779.7666070017995, 7838.451308408356, 7839.961609981332, 7913.525188488709, 7933.087694915159, 817.9468334558235, 909.6816114359921, 986.487026746088, 1012.898710128996, 1034.886162522122, 28741.0, 29614.851925740666, 29733.55399669007, 30021.39957200844, 30329.393412131634, 899.6356475627381, 965.1747755668943, 972.6206779356057, 1074.2856000759564, 1167.6146871532374, 14779.704405911822, 15339.846601534065, 15437.070366204225, 15509.379624814881, 15743.30032442398, 670438.0, 674928.0, 675255.0, 676864.0, 677848.0, 57132.714336428886, 57291.42708572861, 57512.71243643755, 57542.13686794702, 57779.13331300034, 1310.2793463595176, 1339.9531016414185, 1350.3383342162467, 1368.5415385845654, 1377.6831328794433, 27608.678375585725, 28116.71883281141, 30222.0, 30899.0, 31321.37357252888, 41270.793646031576, 41668.58331416647, 48180.759096204296, 51040.70316835677, 51257.743711281204, 473762.0, 479339.0, 576488.0, 576696.0, 577446.0, 22974.42563935848, 25753.613695794204, 26308.8684556576, 26799.330016749736, 27078.458430831666, 46048.07903841837, 46391.216527007135, 46755.29867052065, 46828.0, 46921.53078469171, 37872.24255514929, 38072.80963595164, 38463.0, 38840.61159388483, 40029.39955900722, 38237.23525529418, 38531.83490641484, 38967.22065558616, 39383.0, 39595.0, 30483.69516304897, 32751.34497310088, 36952.81523592365, 37426.0, 38286.61713382942, 27068.729312706622, 28211.71788282091, 28700.569491457227, 33822.83088584541, 34656.0, 25504.489910201322, 25524.23427297184, 25566.10518631839, 25844.870775646003, 25938.481230375663], "value": 19305.614597533793, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.2862160840627, 370.7164019525062, 380.30024754451813, 387.190771288008, 403.2298310227466, 18822.52943676407, 19184.136713847874, 19432.708509372354, 20223.39329820104, 20410.979451027426, 15535.844641553582, 19250.133743981518, 19385.32151374702, 19759.901200493976, 19935.102920368583, 5203.557697595709, 5206.45332240115, 5467.480589344006, 5473.945260547403, 5474.91787623186, 4540.455145382559, 5242.9213561796605, 5309.097453432925, 5378.973105134469, 5391.056565101111, 5271.894562108756, 5390.946090539083, 5395.298611180541, 5396.325459317585, 5407.864803379907, 5371.630234290251, 5521.503064724174, 5992.101184822268, 6002.039673652238, 6007.339192688801, 5014.222795466672, 5177.37871455424, 5248.422673505911, 5260.316158899319, 5286.022085914118, 12886.420111095023, 12982.156159849674, 12998.0, 13008.739825203538, 13051.543195988095, 1920.2319072371163, 2143.74275086989, 2149.6775483677416, 2150.354893531934, 2198.967015494769, 10009.949950250202, 10022.89977100227, 10305.0, 10372.0, 14667.606577375132, 1838.117703502313, 1848.5563464768493, 1902.6289873474677, 1938.4959910423252, 1953.8339241164517, 102027.46958795624, 119233.0, 121670.17494737585, 122597.0, 123854.38072809669, 11114.55541778336, 11262.324260544394, 11450.312981221154, 11535.711607209887, 11708.648740537797, 14355.425782968774, 14536.27318634064, 14576.489822856149, 14597.416103355747, 14604.0, 6835.0, 7973.362131029504, 8254.876176857351, 8276.751697449086, 8386.580670966548, 8091.635876385577, 8106.9594652027545, 8109.75670729879, 8260.173982601691, 8285.295749861329, 4500.77496125199, 4508.661850361236, 4521.784325680459, 4531.932021019687, 4603.700759450625, 1910.3695780392436, 1946.7223348682076, 1947.9123439444975, 1959.9510012249807, 1979.435860779671, 751.5115175136206, 779.3764988009569, 801.0107517216234, 847.9527783187758, 908.7137551671202, 15926.0, 17879.73180402295, 19358.903205483883, 19767.209311627663, 21493.08390864332, 892.161368313796, 922.8338898998111, 930.2557953637063, 959.1176117971482, 1014.452195814252, 11022.944885275683, 11335.036521895567, 11342.092632589372, 11385.975262226273, 11408.657740267805, 517709.0, 585247.0, 586585.0, 591391.0, 594839.0, 115415.42292288653, 123145.0, 130244.0, 134196.0, 135994.0, 954.2747511891013, 969.2100937735722, 996.4917891875195, 1002.2282842211456, 1004.2066767253933, 17190.570235743708, 17600.29598816033, 18352.541186470444, 18504.81495185031, 18531.44405667832, 43575.9106022352, 44686.0, 45185.77407113075, 51750.223746643074, 52510.73744631252, 67676.0, 71335.28664713286, 73281.0, 80167.19832801598, 80556.59721701354, 7594.202608726005, 7780.610969451392, 7842.9215707842195, 7846.176151504238, 7944.285014348737, 8954.417962832395, 12154.75690486203, 12164.39178041112, 12273.631791046282, 12473.688157796129, 9304.069593040913, 10019.649312274036, 10614.778846297815, 10712.35725856451, 10866.05862673244, 10437.68686939393, 10473.0, 10583.576656933945, 10755.731106722395, 10782.460876956278, 10734.51694673726, 10968.670939871816, 10978.231523793578, 11050.447477626247, 11150.107991360834, 6309.90535141964, 7653.961730191313, 7710.228977102245, 7797.259260370256, 7894.789239989482, 9445.480498572644, 10467.057964783206, 10699.090577301016, 10787.867273936125, 10794.762788655395], "value": 9036.428168630298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.8221813382395, 1525.5804653720224, 1709.769181160543, 1738.895801166259, 1807.9819201807977, 6344.777932772354, 7818.765437036884, 8061.476004059739, 8501.91498085019, 8558.828823423535, 8016.839663206732, 8316.542590157538, 8333.333333333334, 8461.53461559614, 8462.661493540267, 2283.8401311908215, 2310.260716570698, 2326.906923723049, 2340.1692399198296, 2345.2729653807373, 2328.9650655240102, 2351.1065794997876, 2380.0836677879056, 2382.0233704181287, 2388.7014123234594, 2298.2645553422913, 2322.036354912709, 2334.7781960713705, 2352.070931981866, 2358.905643774247, 2289.1301305503966, 2305.2853615379204, 2309.180241014449, 2322.349742072214, 2322.686437330964, 2292.0487997481027, 2311.8034967027825, 2313.5372925414986, 2317.7798109179603, 2319.5592837360846, 8355.749327520183, 8355.832883342298, 8361.665533378658, 8406.159384061606, 8421.831563368698, 5061.443241243497, 5235.240890070956, 5242.9213561796605, 5245.423003469616, 5263.973680131575, 3317.4526203176447, 3419.1793969447403, 3445.0, 3634.1459756957092, 3664.0839790052487, 1010.5200029985772, 1040.344091101471, 1073.3613499967507, 1075.0109898893038, 1096.1011970184452, 10894.564217431292, 10946.014858662758, 10982.341059536453, 11013.0, 11017.173711971634, 3730.8321125549414, 4576.977115114403, 4635.930461043087, 4653.139169253699, 4711.175544279806, 9332.860007099898, 9340.20608248293, 9436.622535098657, 9492.240620750323, 9523.952380238054, 5202.3497062867145, 5202.713850738246, 5218.843434696965, 5221.477852214824, 5222.2427747976335, 4307.203167414038, 4343.695941284143, 4698.95301046992, 4752.857414277577, 4796.760161991887, 3777.395616701315, 3797.810109494514, 3859.4403811447182, 3869.5356557212754, 3873.748206366577, 1263.6019653809205, 1264.7913094339515, 1264.9051321150766, 1271.4723389793317, 1280.731046480231, 747.872861613527, 802.253903869407, 888.0985799413601, 891.0154279521165, 893.3344658229661, 4504.662150338738, 5460.26286451324, 5531.419200983919, 5616.185653080281, 5678.744456499468, 369.76335145506897, 424.35074336265563, 429.9852348457637, 441.76807176232734, 452.9139463501924, 3719.1445967427644, 3719.9070023249637, 3745.887623371303, 3814.9427758583643, 3832.5784163741964, 9813.362131461576, 9960.003999600127, 10055.346402483816, 10203.336783109222, 10211.591536338612, 10168.186545076524, 10176.0, 10184.338018028804, 10188.541515631814, 10203.693889183336, 6964.616946068015, 7750.457467977188, 7889.171636978034, 7945.205479452125, 7995.440319177718, 5450.91823622638, 5540.085885828793, 5680.233168522197, 5688.943110568841, 5726.398728133486, 9037.909620903707, 9259.0, 9264.675736349196, 9284.489353085644, 9302.209312208539, 9281.39670921388, 9344.299177561574, 9377.0, 9387.014363491735, 9446.24430045581, 6636.435902948304, 6851.143607049119, 6978.406835419046, 7081.787546373617, 7293.197748247582, 7883.605819709106, 8014.158513356014, 8060.39547033963, 8061.516309021477, 8067.354611630938, 6262.718177682106, 6466.935330646634, 7088.574685518885, 7108.893366599608, 7333.816654583678, 5812.9709351452975, 5846.713722980938, 5912.46787789101, 6226.3151053385, 6861.833488307099, 6532.8366790830605, 6669.266380698216, 6696.19645642526, 6742.494312926452, 6778.356056174655, 4170.0, 4713.882152946204, 4734.97632511849, 4777.641676874179, 4809.374781278411, 4685.2035154023215, 4738.265568836836, 4786.803299175206, 4832.589229915497, 4948.480409557089], "value": 4296.891718463773, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [241.98306118571702, 304.54926708471476, 308.98764049437995, 313.131061304879, 316.69122605459665, 4387.881090321967, 4910.680805747628, 5311.097113490706, 5349.786008559663, 5564.944350556499, 3954.8050831788346, 4390.121975604876, 4447.888802779932, 4778.426588809348, 5395.514403703666, 3446.4141096013677, 4122.051928056549, 4126.5667104954, 4162.0, 4178.415021896937, 3121.6878312168715, 4140.917181656365, 4141.6065473779945, 4146.502419709639, 4177.561356057616, 3274.4433446314065, 3372.6964573188407, 3554.484599733037, 4055.2295063937904, 4091.9386209206887, 3330.834208027183, 3427.9485807712904, 3456.4296891012955, 3668.614795446493, 4041.7979101045125, 3068.2329417645587, 3359.7312215022985, 3558.9856890786, 3560.2345495718287, 3572.2498275362204, 4438.423005009328, 5150.892558099989, 5264.52619264268, 5352.732363381815, 5400.756965936543, 4042.0, 4071.307877660805, 4625.653076019312, 4676.953230467686, 4703.871925169758, 5687.061634830248, 5693.601447898649, 5725.227094342252, 5726.226959360475, 5764.33710123337, 3261.0053933550257, 3327.085051610802, 3421.794692318469, 3691.0, 3907.760080312864, 5239.947600523984, 5963.344032156459, 6008.6094403864045, 6010.62580280791, 6028.5478589106, 3908.882733517999, 3920.1375697347107, 3933.9803300983885, 3936.193080418507, 3956.742811717229, 3372.8819491317686, 4006.8580454570306, 4021.195760847842, 4031.4355990161403, 4041.0, 4293.025208404107, 4566.95433045672, 4625.121226966866, 4637.211674015425, 4647.442306923191, 5276.472352764769, 5750.591105179258, 5751.0, 5751.769929202785, 5781.306243250837, 1050.4590136079926, 1223.957161499361, 1250.8248845161684, 1258.4018296763059, 1281.7628738683372, 852.7740148860557, 864.2524216552637, 875.0636818604032, 876.7506303517462, 878.4114643189079, 4260.146836126295, 4673.0, 4701.040402375458, 5019.949800501949, 5162.6128040397125, 5016.372953380827, 5316.873250699751, 5541.722913854291, 5574.888502229933, 5602.487328421304, 4372.497162826316, 4556.407667003268, 4721.763911804396, 4750.881227969259, 5142.948570514322, 2611.973880261211, 2903.970960290412, 2913.2571194345305, 2916.9936371951644, 2921.0643303479865, 4903.899944058211, 5266.78932842682, 5537.369749369018, 5682.204491371212, 5725.742341594639, 5320.866978325573, 5660.377358490563, 5704.80033198836, 5736.758731316792, 5764.5676574257095, 4871.95128048715, 5723.7424315905055, 5729.650843154287, 5743.310802703619, 5753.769849206069, 4555.681102322806, 5120.465369642051, 5564.248826408545, 5608.279554854107, 5619.466150715675, 4826.541478559531, 4918.5573298403315, 5056.544910958031, 5318.893622127458, 5401.72991350423, 5431.972840135774, 5765.798197063083, 6049.667268300285, 6141.662208578571, 6154.599461385131, 3453.723702103876, 3743.2326373093997, 3966.504186976628, 3970.372147419544, 3989.3218152914656, 4741.857744267678, 5113.360829896263, 5475.4524547544925, 5925.4370834770625, 5978.282606087298, 4730.982838689698, 4757.35775670294, 5113.028524580318, 5824.94175058244, 5827.912581311369, 5772.451617096369, 5789.131630255495, 5799.5070419014855, 5800.854978625568, 5809.709514524341, 5311.840644780663, 5328.706921119376, 5417.702026388429, 5666.0735349981205, 5674.716264186856, 4789.616830653609, 5770.798022069207, 5801.506871915934, 5806.738696758741, 5824.213731146241, 5214.271795666117, 5382.434844341287, 5653.491185793298, 5659.745311461076, 5700.828975130753], "value": 4018.7824555982697, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.4747645520272, 694.1947341084342, 721.9711211551542, 753.6985205917629, 787.4212453846421, 14315.069520481175, 14829.77755333671, 15072.171030593314, 15089.924550377247, 15192.772108418369, 13682.384292706827, 13834.584962451117, 13926.79109813351, 13937.0, 13944.302784860767, 704.7392464788036, 761.8628646843566, 824.4764574495193, 832.8958880139983, 841.7811369044032, 647.6470323673585, 670.1355251725269, 740.4557650127152, 805.3879051920524, 825.2407784837964, 727.8726222910996, 893.8435773739569, 898.6225785170244, 899.8065415935572, 901.1574178143425, 805.9597020148998, 819.6557445872747, 854.4360721923532, 888.3781353052889, 889.3418870036191, 642.1715986377567, 646.977355792545, 655.8360409897525, 657.7960832142052, 658.256170527301, 13810.376028554643, 14124.858751412457, 14126.646833829134, 14163.0, 14165.008449408546, 11188.776224475465, 12876.613701588967, 12993.350332483433, 13213.009024323213, 13265.469381224835, 10747.731306717316, 11493.655190344303, 11496.310221386744, 11524.135689823297, 11583.594574189863, 3013.5027720426106, 3575.1777091268896, 3592.4790905318846, 3601.873934412283, 3626.818659067036, 73654.79035628939, 82468.0, 86544.70182947261, 90901.0, 92572.14855702849, 8250.752477425687, 10156.441395723159, 10190.694279171637, 10207.183425325957, 10644.09525190352, 18928.05359732008, 19735.210591576175, 19815.20739170417, 20113.29603463872, 20175.39473815788, 6888.793336199922, 6995.580265184105, 7079.787606371817, 7231.566106033655, 7342.632868356668, 4155.148194620096, 4184.832606695759, 4263.680223983214, 4287.421198138274, 4342.739435633872, 3379.881704140343, 3432.828358582061, 3435.2957643682985, 3474.7915125092577, 3477.1307173206696, 583.6877270660261, 642.913476225178, 650.06066234291, 658.3285049249738, 673.9191297044387, 532.6857154278994, 582.4175824175824, 584.4681339980597, 620.6617393520543, 658.8085883240038, 7145.499815013003, 7675.654595543214, 8066.677332906618, 8776.122387761172, 9124.863127053099, 581.1254062635707, 604.4469310580857, 604.9939500605026, 641.1760887259942, 643.9356064393617, 4286.507051689034, 4292.763897985641, 4543.628654318272, 4546.727196368229, 4565.155446242456, 78777.60611196907, 78923.60538197387, 89195.0, 121653.39173304077, 124206.0, 77140.0, 77257.0, 105664.47167764265, 105792.0, 107293.46353268184, 78399.60800195963, 84892.0, 87527.56236218906, 87778.56110719533, 89649.10350896537, 8595.269402100892, 8664.306855451674, 8702.216800487986, 8809.823803523765, 8833.0, 46110.30834537418, 50676.74661626668, 51037.0, 53196.0, 55883.16175257293, 42430.78784606181, 67548.32451675617, 70680.29319706737, 74418.0, 84419.31161376862, 9231.753713248625, 10027.498625068863, 10151.390916545031, 12358.382080896099, 12877.871221287667, 14056.086354386933, 14085.0, 14174.14955102697, 14291.499797507038, 14406.711865762416, 13125.278644672238, 13128.0, 13161.0, 13274.867251327749, 13847.0, 13018.934905325414, 13099.738005240033, 13290.269035203157, 13583.32083395846, 13715.588532344045, 12703.555375561811, 12715.427805748948, 12728.363581821057, 12821.807672884728, 12898.871011289766, 10299.793845160091, 10506.579736810747, 10783.0, 10833.837492437777, 10874.184436167161, 10430.634927777492, 10929.945350273198, 11086.833697494383, 11356.432178391213, 11527.365994870028], "value": 7101.105011565921, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1601.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 175.0, 120.0, 116.0, 119.0, 117.0, 117.0, 119.0, 119.0, 117.0], "value": 123.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 150.0, 145.0, 162.0, 125.0, 106.0, 127.0, 117.0, 180.0, 149.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [270.0, 250.0, 198.0, 197.0, 197.0, 200.0, 195.0, 189.0, 195.0, 194.0], "value": 208.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 61.0, 89.0, 63.0, 63.0, 67.0, 52.0, 85.0, 54.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 81.0, 61.0, 55.0, 66.0, 120.0, 99.0, 101.0, 74.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 104.0, 90.0, 89.0, 86.0, 89.0, 86.0, 89.0, 108.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 154.0, 109.0, 96.0, 95.0, 102.0, 97.0, 129.0, 156.0, 127.0], "value": 117.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 150.0, 92.0, 96.0, 125.0, 148.0, 91.0, 90.0, 94.0, 91.0], "value": 111.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 57.0, 59.0, 59.0, 59.0, 59.0, 61.0, 67.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 64.0, 78.0, 74.0, 78.0, 70.0, 68.0, 70.0, 87.0, 90.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 101.0, 111.0, 109.0, 89.0, 89.0, 90.0, 89.0, 89.0, 89.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 147.0, 171.0, 165.0, 160.0, 147.0, 151.0, 184.0, 170.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 197.0, 186.0, 169.0, 192.0, 177.0, 231.0, 239.0, 177.0, 175.0], "value": 195.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 71.0, 71.0, 69.0, 69.0, 68.0, 68.0, 70.0], "value": 69.0, "unit": "score"}]}]} 20:25:52 INFO - Return code: 0 20:25:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:25:52 ERROR - # TBPL SUCCESS # 20:25:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:25:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:25:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:25:52 INFO - 2016-11-21 20:25:52.342 screenresolution[1650:10804] starting screenresolution argv=screenresolution get 20:25:52 INFO - 2016-11-21 20:25:52.344 screenresolution[1650:10804] Display 0: 1600x1200x32@60 20:25:52 INFO - 2016-11-21 20:25:52.350 screenresolution[1651:10807] starting screenresolution argv=screenresolution list 20:25:52 INFO - Available Modes on Display 0 20:25:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:25:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:25:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:25:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:25:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:25:52 INFO - Intel Iris: 20:25:52 INFO - Chipset Model: Intel Iris 20:25:52 INFO - Type: GPU 20:25:52 INFO - Bus: Built-In 20:25:52 INFO - VRAM (Dynamic, Max): 1536 MB 20:25:52 INFO - Vendor: Intel (0x8086) 20:25:52 INFO - Device ID: 0x0a2e 20:25:52 INFO - Revision ID: 0x0009 20:25:52 INFO - Displays: 20:25:52 INFO - Display: 20:25:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:25:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:25:52 INFO - Display Serial Number: 1600x1200 60 20:25:52 INFO - Main Display: Yes 20:25:52 INFO - Mirror: Off 20:25:52 INFO - Online: Yes 20:25:52 INFO - Rotation: Supported 20:25:52 INFO - Return code: 0 20:25:52 INFO - Running post-action listener: _resource_record_post_action 20:25:52 INFO - [mozharness: 2016-11-22 04:25:52.586171Z] Finished run-tests step (success) 20:25:52 INFO - Running post-run listener: _resource_record_post_run 20:25:52 INFO - Total resource usage - Wall time: 1214s; CPU: 55.0%; Read bytes: 38386176; Write bytes: 697875456; Read time: 241; Write time: 2062 20:25:52 INFO - TinderboxPrint: CPU usage
54.5% 20:25:52 INFO - TinderboxPrint: I/O read bytes / time
38,386,176 / 241 20:25:52 INFO - TinderboxPrint: I/O write bytes / time
697,875,456 / 2,062 20:25:52 INFO - TinderboxPrint: CPU idle
2,208.6 (45.5%) 20:25:52 INFO - TinderboxPrint: CPU system
983.8 (20.3%) 20:25:52 INFO - TinderboxPrint: CPU user
1,665.4 (34.3%) 20:25:52 INFO - TinderboxPrint: Swap in / out
421,875,712 / 0 20:25:52 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 362379776; Write bytes: 356001280; Read time: 27526; Write time: 772 20:25:52 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 37464576; Write bytes: 312649216; Read time: 231; Write time: 1212 20:25:53 INFO - Running post-run listener: _upload_blobber_files 20:25:53 INFO - Blob upload gear active. 20:25:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:25:53 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 20:25:53 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'] 20:25:53 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 20:25:53 INFO - (blobuploader) - INFO - Open directory for files ... 20:25:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:25:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:25:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:25:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:25:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:25:54 INFO - (blobuploader) - INFO - Done attempting. 20:25:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:25:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:25:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:25:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:25:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:25:55 INFO - (blobuploader) - INFO - Done attempting. 20:25:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:25:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:25:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:25:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:25:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:25:57 INFO - (blobuploader) - INFO - Done attempting. 20:25:57 INFO - (blobuploader) - INFO - Iteration through files over. 20:25:57 INFO - Return code: 0 20:25:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:25:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:25:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d787c7f48b9c295d7ab4b7aea05b7908eefb2d3de92bb8436b0e956dc1d18f06850fc28ad1556be57add377e35b5426b84d78b6a042fe56890f5ace1ac02bd75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/da8f54b9238a6a70bf79a0e2558d45773920b5ded40be4e602f4a069128058ae746036d6d39d57d16eb63a9dca3374f8f9474e49465cca8d822ea52054fe797c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/15a34874dcbd17041885e8ddbd0d8a63f2d624b64e1e02dcd015833a3c95b4b4171544d34581ca5222269e1c9b6a038e8153fe3ab434121c348d482f70701aab"} 20:25:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:25:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:25:57 INFO - Contents: 20:25:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d787c7f48b9c295d7ab4b7aea05b7908eefb2d3de92bb8436b0e956dc1d18f06850fc28ad1556be57add377e35b5426b84d78b6a042fe56890f5ace1ac02bd75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/da8f54b9238a6a70bf79a0e2558d45773920b5ded40be4e602f4a069128058ae746036d6d39d57d16eb63a9dca3374f8f9474e49465cca8d822ea52054fe797c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/15a34874dcbd17041885e8ddbd0d8a63f2d624b64e1e02dcd015833a3c95b4b4171544d34581ca5222269e1c9b6a038e8153fe3ab434121c348d482f70701aab"} 20:25:57 INFO - Running post-run listener: copy_logs_to_upload_dir 20:25:57 INFO - Copying logs to upload dir... 20:25:57 INFO - mkdir: /builds/slave/test/build/upload/logs 20:25:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.386391 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-21 20:25:57.888631) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 20:25:57.892178) ========= 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.ezlCsQchIg/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.efWtdxrCQY/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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/d787c7f48b9c295d7ab4b7aea05b7908eefb2d3de92bb8436b0e956dc1d18f06850fc28ad1556be57add377e35b5426b84d78b6a042fe56890f5ace1ac02bd75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/da8f54b9238a6a70bf79a0e2558d45773920b5ded40be4e602f4a069128058ae746036d6d39d57d16eb63a9dca3374f8f9474e49465cca8d822ea52054fe797c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/15a34874dcbd17041885e8ddbd0d8a63f2d624b64e1e02dcd015833a3c95b4b4171544d34581ca5222269e1c9b6a038e8153fe3ab434121c348d482f70701aab"} build_url:https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010441 build_url: 'https://queue.taskcluster.net/v1/task/TlErQtRHS5Cdjdqn8esb5Q/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/d787c7f48b9c295d7ab4b7aea05b7908eefb2d3de92bb8436b0e956dc1d18f06850fc28ad1556be57add377e35b5426b84d78b6a042fe56890f5ace1ac02bd75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/da8f54b9238a6a70bf79a0e2558d45773920b5ded40be4e602f4a069128058ae746036d6d39d57d16eb63a9dca3374f8f9474e49465cca8d822ea52054fe797c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/15a34874dcbd17041885e8ddbd0d8a63f2d624b64e1e02dcd015833a3c95b4b4171544d34581ca5222269e1c9b6a038e8153fe3ab434121c348d482f70701aab"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 20:25:57.928460) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 20:25:57.928796) ========= 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.ezlCsQchIg/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.efWtdxrCQY/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.005078 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 20:25:57.977765) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 20:25:57.978093) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 20:25:57.978413) ========= ========= Total master_lag: 0.18 =========