builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0290 starttime: 1479789902.05 results: success (0) buildid: 20161121182450 builduid: 62d2a20c84d84c05a229ca320e111856 revision: 8a3e765c52b23d55b39dc483e34de440e8d49ab9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:02.045545) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:02.046085) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:02.046362) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:02.095151) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:02.095492) ========= 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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:02-- 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% 171M=0s 2016-11-21 20:45:02 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152741 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:02.273022) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:02.273619) ========= 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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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.026053 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:02.323838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-21 20:45:02.324251) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 8a3e765c52b23d55b39dc483e34de440e8d49ab9 --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 8a3e765c52b23d55b39dc483e34de440e8d49ab9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zXQvSwjDI7/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.NFk1TvGXiF/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:02,424 truncating revision to first 12 chars 2016-11-21 20:45:02,424 Setting DEBUG logging. 2016-11-21 20:45:02,424 attempt 1/10 2016-11-21 20:45:02,424 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/8a3e765c52b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-21 20:45:03,132 unpacking tar archive at: graphics-8a3e765c52b2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.977597 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-21 20:45:03.325132) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:03.325445) ========= script_repo_revision: 8a3e765c52b23d55b39dc483e34de440e8d49ab9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:03.325807) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:03.326087) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-21 20:45:03.346276) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-21 20:45:03.346645) ========= /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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:03 INFO - MultiFileLogger online at 20161121 20:45:03 in /builds/slave/test 20:45:03 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:45:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:45:03 INFO - {'append_to_log': False, 20:45:03 INFO - 'base_work_dir': '/builds/slave/test', 20:45:03 INFO - 'blob_upload_branch': 'Graphics', 20:45:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:45:03 INFO - 'branch': 'Graphics', 20:45:03 INFO - 'buildbot_json_path': 'buildprops.json', 20:45:03 INFO - 'config_files': ('talos/mac_config.py',), 20:45:03 INFO - 'default_actions': ('clobber', 20:45:03 INFO - 'read-buildbot-config', 20:45:03 INFO - 'download-and-extract', 20:45:03 INFO - 'populate-webroot', 20:45:03 INFO - 'create-virtualenv', 20:45:03 INFO - 'install', 20:45:03 INFO - 'run-tests'), 20:45:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:45:03 INFO - 'download_minidump_stackwalk': True, 20:45:03 INFO - 'download_symbols': 'ondemand', 20:45:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:45:03 INFO - 'tooltool.py': '/tools/tooltool.py', 20:45:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:45:03 INFO - '/tools/misc-python/virtualenv.py')}, 20:45:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:45:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:45:03 INFO - 'installer_path': 'installer.exe', 20:45:03 INFO - 'log_level': 'info', 20:45:03 INFO - 'log_name': 'talos', 20:45:03 INFO - 'log_to_console': True, 20:45:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:45:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:45:03 INFO - 'opt_config_files': (), 20:45:03 INFO - 'pip_index': False, 20:45:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:45:03 INFO - 'cmd': ('bash', 20:45:03 INFO - '-c', 20:45:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:45:03 INFO - 'enabled': True, 20:45:03 INFO - 'halt_on_failure': False, 20:45:03 INFO - 'name': 'check_screen_resolution'},), 20:45:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:45:03 INFO - 'cmd': ('bash', 20:45:03 INFO - '-c', 20:45:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:45:03 INFO - 'enabled': True, 20:45:03 INFO - 'halt_on_failure': False, 20:45:03 INFO - 'name': 'check_screen_resolution'},), 20:45:03 INFO - 'run_cmd_checks_enabled': True, 20:45:03 INFO - 'sps_profile': False, 20:45:03 INFO - 'sps_profile_interval': 0, 20:45:03 INFO - 'suite': 'dromaeojs-e10s', 20:45:03 INFO - 'system_bits': '32', 20:45:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:45:03 INFO - 'title': 't-yosemite-r7-0290', 20:45:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:45:03 INFO - 'use_talos_json': True, 20:45:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:45:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:45:03 INFO - 'work_dir': 'build'} 20:45:03 INFO - [mozharness: 2016-11-22 04:45:03.552184Z] Running clobber step. 20:45:03 INFO - Running pre-action listener: _resource_record_pre_action 20:45:03 INFO - Running main action method: clobber 20:45:03 INFO - rmtree: /builds/slave/test/build 20:45:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:45:05 INFO - Running post-action listener: _resource_record_post_action 20:45:05 INFO - [mozharness: 2016-11-22 04:45:05.912089Z] Finished clobber step (success) 20:45:05 INFO - [mozharness: 2016-11-22 04:45:05.912296Z] Running read-buildbot-config step. 20:45:05 INFO - Running pre-action listener: _resource_record_pre_action 20:45:05 INFO - Running main action method: read_buildbot_config 20:45:05 INFO - Using buildbot properties: 20:45:05 INFO - { 20:45:05 INFO - "project": "", 20:45:05 INFO - "product": "firefox", 20:45:05 INFO - "script_repo_revision": "default", 20:45:05 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 20:45:05 INFO - "repository": "", 20:45:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 20:45:05 INFO - "buildid": "20161121182450", 20:45:05 INFO - "pgo_build": "False", 20:45:05 INFO - "basedir": "/builds/slave/test", 20:45:05 INFO - "buildnumber": 0, 20:45:05 INFO - "slavename": "t-yosemite-r7-0290", 20:45:05 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 20:45:05 INFO - "platform": "yosemite_r7", 20:45:05 INFO - "scheduler": "tests-graphics-macosx64-talos", 20:45:05 INFO - "branch": "graphics", 20:45:05 INFO - "repo_path": "projects/graphics", 20:45:05 INFO - "revision": "8a3e765c52b23d55b39dc483e34de440e8d49ab9", 20:45:05 INFO - "stage_platform": "macosx64", 20:45:05 INFO - "builduid": "62d2a20c84d84c05a229ca320e111856", 20:45:05 INFO - "slavebuilddir": "test" 20:45:05 INFO - } 20:45:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:45:05 INFO - Running post-action listener: _resource_record_post_action 20:45:05 INFO - [mozharness: 2016-11-22 04:45:05.917790Z] Finished read-buildbot-config step (success) 20:45:05 INFO - [mozharness: 2016-11-22 04:45:05.918019Z] Running download-and-extract step. 20:45:05 INFO - Running pre-action listener: _resource_record_pre_action 20:45:05 INFO - Running main action method: download_and_extract 20:45:05 INFO - mkdir: /builds/slave/test/build/tests 20:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:05 INFO - trying https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:45:05 INFO - Downloading https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/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:45:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/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:45:07 INFO - Downloaded 1199 bytes. 20:45:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:45:07 INFO - Using the following test package requirements: 20:45:07 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:45:07 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:07 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:45:07 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:07 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:45:07 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:07 INFO - u'jsshell-mac.zip'], 20:45:07 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:07 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:45:07 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:45:07 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:07 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:45:07 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:07 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:45:07 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:07 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:45:07 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:07 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:45:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:45:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:45:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:45:07 INFO - Fetch https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:45:07 INFO - Http code: 200 20:45:07 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 20:45:07 INFO - x-amz-cf-id: WOhMR_S6vVb7s-L5vuk5kecC7AtVgUeSN52DZGBGCoqf0Nv8JAHNsg== 20:45:07 INFO - x-amz-version-id: OjN_WQVksk0Ln20TetrwGUsTZjJsQjnQ 20:45:08 INFO - Expected file size: 16282104 20:45:08 INFO - Obtained file size: 16282104 20:45:14 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:45:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:45:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:45:14 INFO - Fetch https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:45:15 INFO - Http code: 200 20:45:15 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 20:45:15 INFO - x-amz-cf-id: JssT8juH0fJJM5fG4YU2CSw_K4zD3O8tJsyrQPFwP9HMnipKaqtSqg== 20:45:15 INFO - x-amz-version-id: OjN_WQVksk0Ln20TetrwGUsTZjJsQjnQ 20:45:15 INFO - Expected file size: 16282104 20:45:15 INFO - Obtained file size: 16282104 20:45:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:45:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:45:21 INFO - Fetch https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:45:22 INFO - Http code: 200 20:45:22 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 20:45:22 INFO - x-amz-cf-id: k3I_3RxoXaYpVq2VVshS2qnM61g4O6AJ6zWEtH4TVpnpGGlPnaGCdg== 20:45:22 INFO - x-amz-version-id: 1hXK4HPVTXBhcTgR0iGXgMQvDY1vdkDn 20:45:22 INFO - Expected file size: 14409392 20:45:22 INFO - Obtained file size: 14409392 20:45:23 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:45:23 INFO - trying https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:45:23 INFO - Downloading https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:45:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/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:45:26 INFO - Downloaded 107572829 bytes. 20:45:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:45:26 INFO - mkdir: /builds/slave/test/properties 20:45:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:45:26 INFO - Writing to file /builds/slave/test/properties/build_url 20:45:26 INFO - Contents: 20:45:26 INFO - build_url:https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:45:26 INFO - Running post-action listener: _resource_record_post_action 20:45:26 INFO - Running post-action listener: set_extra_try_arguments 20:45:26 INFO - [mozharness: 2016-11-22 04:45:26.744082Z] Finished download-and-extract step (success) 20:45:26 INFO - [mozharness: 2016-11-22 04:45:26.744208Z] Running populate-webroot step. 20:45:26 INFO - Running pre-action listener: _resource_record_pre_action 20:45:26 INFO - Running main action method: populate_webroot 20:45:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:45:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:45:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:45:26 INFO - '--tppagecycles', 20:45:26 INFO - '7'], 20:45:26 INFO - 'tests': ['tsvgm']}}, 20:45:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:45:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:45:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:45:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:45:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:45:26 INFO - 'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:45:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:45:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:45:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:45:26 INFO - 'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['damp', 'tps']}, 20:45:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:45:26 INFO - 'tests': ['damp', 'tps']}, 20:45:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['dromaeo_dom']}, 20:45:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:45:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['basic_compositor_video']}, 20:45:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:45:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['a11yr', 20:45:26 INFO - 'ts_paint', 20:45:26 INFO - 'tpaint', 20:45:26 INFO - 'sessionrestore', 20:45:26 INFO - 'sessionrestore_no_auto_restore', 20:45:26 INFO - 'tabpaint']}, 20:45:26 INFO - 'other-e10s': {'tests': ['a11yr', 20:45:26 INFO - 'ts_paint', 20:45:26 INFO - 'tpaint', 20:45:26 INFO - 'sessionrestore', 20:45:26 INFO - 'sessionrestore_no_auto_restore', 20:45:26 INFO - 'tabpaint']}, 20:45:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:45:26 INFO - 'ts_paint', 20:45:26 INFO - 'tpaint', 20:45:26 INFO - 'sessionrestore', 20:45:26 INFO - 'sessionrestore_no_auto_restore', 20:45:26 INFO - 'tabpaint']}, 20:45:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:45:26 INFO - 'ts_paint', 20:45:26 INFO - 'tpaint', 20:45:26 INFO - 'sessionrestore', 20:45:26 INFO - 'sessionrestore_no_auto_restore', 20:45:26 INFO - 'tabpaint']}, 20:45:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['a11yr', 20:45:26 INFO - 'ts_paint', 20:45:26 INFO - 'tpaint', 20:45:26 INFO - 'sessionrestore', 20:45:26 INFO - 'sessionrestore_no_auto_restore', 20:45:26 INFO - 'tabpaint']}, 20:45:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['a11yr', 20:45:26 INFO - 'ts_paint', 20:45:26 INFO - 'tpaint', 20:45:26 INFO - 'sessionrestore', 20:45:26 INFO - 'sessionrestore_no_auto_restore', 20:45:26 INFO - 'tabpaint']}, 20:45:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['tsvgx', 20:45:26 INFO - 'tsvgr_opacity', 20:45:26 INFO - 'tart', 20:45:26 INFO - 'tscrollx', 20:45:26 INFO - 'cart']}, 20:45:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:45:26 INFO - 'tsvgr_opacity', 20:45:26 INFO - 'tart', 20:45:26 INFO - 'tscrollx', 20:45:26 INFO - 'cart']}, 20:45:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:45:26 INFO - 'talos_options': ['--disable-e10s'], 20:45:26 INFO - 'tests': ['tp5o']}, 20:45:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:45:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:45:26 INFO - 'talos_options': ['--disable-e10s', 20:45:26 INFO - '--xperf_path', 20:45:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:45:26 INFO - 'tests': ['tp5n']}, 20:45:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:45:26 INFO - 'talos_options': ['--xperf_path', 20:45:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:45:26 INFO - 'tests': ['tp5n']}}, 20:45:26 INFO - 'talos.zip': {'path': '', 20:45:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:45:26 INFO - Running post-action listener: _resource_record_post_action 20:45:26 INFO - [mozharness: 2016-11-22 04:45:26.762427Z] Finished populate-webroot step (success) 20:45:26 INFO - [mozharness: 2016-11-22 04:45:26.762556Z] Running create-virtualenv step. 20:45:26 INFO - Running pre-action listener: _resource_record_pre_action 20:45:26 INFO - Running main action method: create_virtualenv 20:45:26 INFO - Creating virtualenv /builds/slave/test/build/venv 20:45:26 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:45:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:45:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:45:26 INFO - Using real prefix '/tools/python27' 20:45:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:45:27 INFO - Installing distribute.............................................................................................................................................................................................done. 20:45:30 INFO - Installing pip.................done. 20:45:30 INFO - Return code: 0 20:45:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:45:30 INFO - Reading from file tmpfile_stdout 20:45:30 INFO - Output received: 20:45:30 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:45:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:45: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:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:45:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45: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:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:45:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1015f03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff9469d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, '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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:45:30 INFO - 'HOME': '/Users/cltbld', 20:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:45:30 INFO - 'LOGNAME': 'cltbld', 20:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:45:30 INFO - 'MOZ_NO_REMOTE': '1', 20:45:30 INFO - 'NO_EM_RESTART': '1', 20:45:30 INFO - 'PAGER': '/bin/cat', 20:45:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:45:30 INFO - 'PWD': '/builds/slave/test', 20:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:45:30 INFO - 'SHELL': '/bin/bash', 20:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:45:30 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:45:30 INFO - 'USER': 'cltbld', 20:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:45:30 INFO - 'XPC_FLAGS': '0x0', 20:45:30 INFO - 'XPC_SERVICE_NAME': '0', 20:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:45:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:45:31 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:45:31 INFO - Cleaning up... 20:45:31 INFO - Return code: 0 20:45:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:45:31 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:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:31 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:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:31 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 0x1015f03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff9469d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, '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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:31 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:45:31 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:45:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:45:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:45:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:45:31 INFO - 'HOME': '/Users/cltbld', 20:45:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:45:31 INFO - 'LOGNAME': 'cltbld', 20:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:45:31 INFO - 'MOZ_NO_REMOTE': '1', 20:45:31 INFO - 'NO_EM_RESTART': '1', 20:45:31 INFO - 'PAGER': '/bin/cat', 20:45:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:45:31 INFO - 'PWD': '/builds/slave/test', 20:45:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:45:31 INFO - 'SHELL': '/bin/bash', 20:45:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:45:31 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:45:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:45:31 INFO - 'USER': 'cltbld', 20:45:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:45:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:45:31 INFO - 'XPC_FLAGS': '0x0', 20:45:31 INFO - 'XPC_SERVICE_NAME': '0', 20:45:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:45:31 INFO - Downloading/unpacking psutil>=3.1.1 20:45:31 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:45:31 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:45:31 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:45:31 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:45:31 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:45:31 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:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:45:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:45:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:45:35 INFO - Installing collected packages: psutil 20:45:35 INFO - Running setup.py install for psutil 20:45:35 INFO - building 'psutil._psutil_osx' extension 20:45:35 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:45:35 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:45:35 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:45:35 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:45:35 INFO - building 'psutil._psutil_posix' extension 20:45:35 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:45:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:45:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:45:36 INFO - ^ 20:45:36 INFO - 1 warning generated. 20:45:36 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:45:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:45:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:45:36 INFO - Successfully installed psutil 20:45:36 INFO - Cleaning up... 20:45:36 INFO - Return code: 0 20:45:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:45: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:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45: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:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:36 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 0x1015f03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff9469d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, '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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:36 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:45:36 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:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:45:36 INFO - 'HOME': '/Users/cltbld', 20:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:45:36 INFO - 'LOGNAME': 'cltbld', 20:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:45:36 INFO - 'MOZ_NO_REMOTE': '1', 20:45:36 INFO - 'NO_EM_RESTART': '1', 20:45:36 INFO - 'PAGER': '/bin/cat', 20:45:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:45:36 INFO - 'PWD': '/builds/slave/test', 20:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:45:36 INFO - 'SHELL': '/bin/bash', 20:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:45:36 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:45:36 INFO - 'USER': 'cltbld', 20:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:45:36 INFO - 'XPC_FLAGS': '0x0', 20:45:36 INFO - 'XPC_SERVICE_NAME': '0', 20:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:45:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:45:36 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:45:36 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:45:36 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:45:36 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:45:36 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:45:36 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:45:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:45:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:45:39 INFO - Installing collected packages: mozsystemmonitor 20:45:39 INFO - Running setup.py install for mozsystemmonitor 20:45:39 INFO - Successfully installed mozsystemmonitor 20:45:39 INFO - Cleaning up... 20:45:39 INFO - Return code: 0 20:45:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1015f03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff9469d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, '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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:45:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:45:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:45:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:45:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:45:39 INFO - 'HOME': '/Users/cltbld', 20:45:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:45:39 INFO - 'LOGNAME': 'cltbld', 20:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:45:39 INFO - 'MOZ_NO_REMOTE': '1', 20:45:39 INFO - 'NO_EM_RESTART': '1', 20:45:39 INFO - 'PAGER': '/bin/cat', 20:45:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:45:39 INFO - 'PWD': '/builds/slave/test', 20:45:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:45:39 INFO - 'SHELL': '/bin/bash', 20:45:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:45:39 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:45:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:45:39 INFO - 'USER': 'cltbld', 20:45:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:45:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:45:39 INFO - 'XPC_FLAGS': '0x0', 20:45:39 INFO - 'XPC_SERVICE_NAME': '0', 20:45:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:45:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:45:39 INFO - Downloading/unpacking jsonschema==2.5.1 20:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:45:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:45:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:45:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:45:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:45:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:45:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:45:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:45:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:45:42 INFO - Downloading functools32-3.2.3-2.tar.gz 20:45:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:45:42 INFO - warning: no files found matching '*.txt' 20:45:42 INFO - no previously-included directories found matching 'build' 20:45:42 INFO - no previously-included directories found matching 'dist' 20:45:42 INFO - no previously-included directories found matching '.git*' 20:45:42 INFO - Installing collected packages: jsonschema, functools32 20:45:42 INFO - Running setup.py install for functools32 20:45:42 INFO - warning: no files found matching '*.txt' 20:45:42 INFO - no previously-included directories found matching 'build' 20:45:42 INFO - no previously-included directories found matching 'dist' 20:45:42 INFO - no previously-included directories found matching '.git*' 20:45:42 INFO - Successfully installed jsonschema functools32 20:45:42 INFO - Cleaning up... 20:45:43 INFO - Return code: 0 20:45:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:45:43 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:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:45:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:43 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:45:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:45:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:43 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 0x1015f03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff9469d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, '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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:43 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:45:43 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:45:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:45:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:45:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:45:43 INFO - 'HOME': '/Users/cltbld', 20:45:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:45:43 INFO - 'LOGNAME': 'cltbld', 20:45:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:45:43 INFO - 'MOZ_NO_REMOTE': '1', 20:45:43 INFO - 'NO_EM_RESTART': '1', 20:45:43 INFO - 'PAGER': '/bin/cat', 20:45:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:45:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:45:43 INFO - 'PWD': '/builds/slave/test', 20:45:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:45:43 INFO - 'SHELL': '/bin/bash', 20:45:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:45:43 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:45:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:45:43 INFO - 'USER': 'cltbld', 20:45:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:45:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:45:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:45:43 INFO - 'XPC_FLAGS': '0x0', 20:45:43 INFO - 'XPC_SERVICE_NAME': '0', 20:45:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:45:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:45:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:45:43 INFO - Cleaning up... 20:45:43 INFO - Return code: 0 20:45:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:45:43 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:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:45:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:43 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:45:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:45:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:43 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 0x1015f03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff9469d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, '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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:43 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:45:43 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:45:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:45:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:45:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:45:43 INFO - 'HOME': '/Users/cltbld', 20:45:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:45:43 INFO - 'LOGNAME': 'cltbld', 20:45:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:45:43 INFO - 'MOZ_NO_REMOTE': '1', 20:45:43 INFO - 'NO_EM_RESTART': '1', 20:45:43 INFO - 'PAGER': '/bin/cat', 20:45:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:45:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:45:43 INFO - 'PWD': '/builds/slave/test', 20:45:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:45:43 INFO - 'SHELL': '/bin/bash', 20:45:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:45:43 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:45:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:45:43 INFO - 'USER': 'cltbld', 20:45:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:45:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:45:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:45:43 INFO - 'XPC_FLAGS': '0x0', 20:45:43 INFO - 'XPC_SERVICE_NAME': '0', 20:45:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:45:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:45:43 INFO - Downloading/unpacking blobuploader==1.2.4 20:45:43 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:45:43 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:45:43 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:45:43 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:45:43 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:45:43 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:45:45 INFO - Downloading blobuploader-1.2.4.tar.gz 20:45:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:45:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:45:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:45:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:45:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:45:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:45:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:45:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:45:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:45:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:45:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:45:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:45:47 INFO - Downloading docopt-0.6.1.tar.gz 20:45:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:45:47 INFO - Installing collected packages: blobuploader, requests, docopt 20:45:47 INFO - Running setup.py install for blobuploader 20:45:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:45:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:45:47 INFO - Running setup.py install for requests 20:45:47 INFO - Running setup.py install for docopt 20:45:48 INFO - Successfully installed blobuploader requests docopt 20:45:48 INFO - Cleaning up... 20:45:48 INFO - Return code: 0 20:45:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:45:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:45:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:45:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:48 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 0x1015f03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff9469d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, '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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:48 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:45:48 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:45:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:45:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:45:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:45:48 INFO - 'HOME': '/Users/cltbld', 20:45:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:45:48 INFO - 'LOGNAME': 'cltbld', 20:45:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:45:48 INFO - 'MOZ_NO_REMOTE': '1', 20:45:48 INFO - 'NO_EM_RESTART': '1', 20:45:48 INFO - 'PAGER': '/bin/cat', 20:45:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:45:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:45:48 INFO - 'PWD': '/builds/slave/test', 20:45:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:45:48 INFO - 'SHELL': '/bin/bash', 20:45:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:45:48 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:45:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:45:48 INFO - 'USER': 'cltbld', 20:45:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:45:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:45:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:45:48 INFO - 'XPC_FLAGS': '0x0', 20:45:48 INFO - 'XPC_SERVICE_NAME': '0', 20:45:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:45:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:45:48 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-bLJeeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:45:48 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-GPclAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:45:48 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-_uUfjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:45:48 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-W4ZOkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:45:48 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Hxawju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:45:49 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-xQY4o4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:45:49 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-pr3BVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:45:49 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Ss2U7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:45:49 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-T8Shtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:45:49 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-9OPukT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:45:49 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-VOMfPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:45:49 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-ymH_an-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:45:49 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-430UiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:45:49 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-e5TVdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:45:49 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-8jEcTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:45:50 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-I6bpWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:45:50 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-3gr4G1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:45:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:45:50 INFO - Running setup.py install for manifestparser 20:45:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:45:50 INFO - Running setup.py install for mozcrash 20:45:50 INFO - Running setup.py install for mozdebug 20:45:50 INFO - Running setup.py install for mozdevice 20:45:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:45:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:45:50 INFO - Running setup.py install for mozfile 20:45:51 INFO - Running setup.py install for mozhttpd 20:45:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:45:51 INFO - Running setup.py install for mozinfo 20:45:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:45:51 INFO - Running setup.py install for mozInstall 20:45:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:45:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:45:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:45:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:45:51 INFO - Running setup.py install for mozleak 20:45:51 INFO - Running setup.py install for mozlog 20:45:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:45:51 INFO - Running setup.py install for moznetwork 20:45:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:45:51 INFO - Running setup.py install for mozprocess 20:45:52 INFO - Running setup.py install for mozprofile 20:45:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:45:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:45:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:45:52 INFO - Running setup.py install for mozrunner 20:45:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:45:52 INFO - Running setup.py install for mozscreenshot 20:45:52 INFO - Running setup.py install for moztest 20:45:52 INFO - Running setup.py install for mozversion 20:45:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:45:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:45:52 INFO - Cleaning up... 20:45:52 INFO - Return code: 0 20:45:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:45:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:45:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:52 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 0x1015f03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff9469d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, '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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:52 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:45:52 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:45:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:45:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:45:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:45:52 INFO - 'HOME': '/Users/cltbld', 20:45:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:45:52 INFO - 'LOGNAME': 'cltbld', 20:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:45:52 INFO - 'MOZ_NO_REMOTE': '1', 20:45:52 INFO - 'NO_EM_RESTART': '1', 20:45:52 INFO - 'PAGER': '/bin/cat', 20:45:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:45:52 INFO - 'PWD': '/builds/slave/test', 20:45:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:45:52 INFO - 'SHELL': '/bin/bash', 20:45:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:45:52 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:45:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:45:52 INFO - 'USER': 'cltbld', 20:45:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:45:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:45:52 INFO - 'XPC_FLAGS': '0x0', 20:45:52 INFO - 'XPC_SERVICE_NAME': '0', 20:45:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:45:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-4QFPWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:45:53 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:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:45:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-lgcWYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:45:53 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:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:45:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-gOfPVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:45:53 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:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:45:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-uczLAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:45:53 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:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:45:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-fl2eTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:45:53 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:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:45:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-DWEGfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:45:53 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:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:45:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-taiO03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:45:53 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:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:45:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-X5GUuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:45:53 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:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:45:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-YDj5wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:45:54 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:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:45:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-QHiyzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:45:54 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:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:45:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-hM4tTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:45:54 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:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:45:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-JIodlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:45:54 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:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:45:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-4Pzpjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:45:54 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:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:45:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-7rpT_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:45:54 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:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:45:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-DleznE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:45:54 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:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:45:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-alZDox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:45:54 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:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:45:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-44G5e1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:45:54 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:45:54 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:45:54 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:45:54 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:45:54 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:45:54 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:45:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:45:54 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:45:54 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:45:54 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:45:54 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:45:54 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:45:54 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:45:57 INFO - Downloading blessings-1.6.tar.gz 20:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:45:57 INFO - Installing collected packages: blessings 20:45:57 INFO - Running setup.py install for blessings 20:45:57 INFO - Successfully installed blessings 20:45:57 INFO - Cleaning up... 20:45:57 INFO - Return code: 0 20:45:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:45:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:45:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:45:58 INFO - Reading from file tmpfile_stdout 20:45:58 INFO - Current package versions: 20:45:58 INFO - blessings == 1.6 20:45:58 INFO - blobuploader == 1.2.4 20:45:58 INFO - docopt == 0.6.1 20:45:58 INFO - functools32 == 3.2.3-2 20:45:58 INFO - jsonschema == 2.5.1 20:45:58 INFO - manifestparser == 1.1 20:45:58 INFO - mozInstall == 1.12 20:45:58 INFO - mozcrash == 1.0 20:45:58 INFO - mozdebug == 0.1 20:45:58 INFO - mozdevice == 0.49 20:45:58 INFO - mozfile == 1.2 20:45:58 INFO - mozhttpd == 0.7 20:45:58 INFO - mozinfo == 0.9 20:45:58 INFO - mozleak == 0.1 20:45:58 INFO - mozlog == 3.4 20:45:58 INFO - moznetwork == 0.27 20:45:58 INFO - mozprocess == 0.23 20:45:58 INFO - mozprofile == 0.28 20:45:58 INFO - mozrunner == 6.13 20:45:58 INFO - mozscreenshot == 0.1 20:45:58 INFO - mozsystemmonitor == 0.3 20:45:58 INFO - moztest == 0.7 20:45:58 INFO - mozversion == 1.4 20:45:58 INFO - psutil == 3.1.1 20:45:58 INFO - requests == 1.2.3 20:45:58 INFO - wsgiref == 0.1.2 20:45:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:45:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:45:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:58 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 0x1015f03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff9469d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, '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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:58 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:45:58 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:45:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:45:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:45:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:45:58 INFO - 'HOME': '/Users/cltbld', 20:45:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:45:58 INFO - 'LOGNAME': 'cltbld', 20:45:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:45:58 INFO - 'MOZ_NO_REMOTE': '1', 20:45:58 INFO - 'NO_EM_RESTART': '1', 20:45:58 INFO - 'PAGER': '/bin/cat', 20:45:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:45:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:45:58 INFO - 'PWD': '/builds/slave/test', 20:45:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:45:58 INFO - 'SHELL': '/bin/bash', 20:45:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:45:58 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:45:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:45:58 INFO - 'USER': 'cltbld', 20:45:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:45:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:45:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:45:58 INFO - 'XPC_FLAGS': '0x0', 20:45:58 INFO - 'XPC_SERVICE_NAME': '0', 20:45:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:45:58 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:45:58 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:45:58 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:45:58 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:45:58 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:45:58 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:45:58 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:45:58 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:45:58 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:45:58 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:45:58 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:45:58 INFO - Cleaning up... 20:45:58 INFO - Return code: 0 20:45:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:45:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:45:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:45:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:45:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1015f03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff9469d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, '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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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:45:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:45:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:45:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:45:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:45:58 INFO - 'HOME': '/Users/cltbld', 20:45:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:45:58 INFO - 'LOGNAME': 'cltbld', 20:45:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:45:58 INFO - 'MOZ_NO_REMOTE': '1', 20:45:58 INFO - 'NO_EM_RESTART': '1', 20:45:58 INFO - 'PAGER': '/bin/cat', 20:45:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:45:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:45:58 INFO - 'PWD': '/builds/slave/test', 20:45:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:45:58 INFO - 'SHELL': '/bin/bash', 20:45:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:45:58 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:45:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:45:58 INFO - 'USER': 'cltbld', 20:45:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:45:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:45:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:45:58 INFO - 'XPC_FLAGS': '0x0', 20:45:58 INFO - 'XPC_SERVICE_NAME': '0', 20:45:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:45:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:45:58 INFO - Cleaning up... 20:45:58 INFO - Return code: 0 20:45:58 INFO - Running post-action listener: _resource_record_post_action 20:45:58 INFO - Running post-action listener: _start_resource_monitoring 20:45:58 INFO - Starting resource monitoring. 20:45:58 INFO - [mozharness: 2016-11-22 04:45:58.574351Z] Finished create-virtualenv step (success) 20:45:58 INFO - [mozharness: 2016-11-22 04:45:58.575340Z] Running install step. 20:45:58 INFO - Running pre-action listener: _resource_record_pre_action 20:45:58 INFO - Running main action method: install 20:45:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:45:58 INFO - Reading from file tmpfile_stdout 20:45:58 INFO - Detecting whether we're running mozinstall >=1.0... 20:45:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:45:58 INFO - Reading from file tmpfile_stdout 20:45:58 INFO - Output received: 20:45:58 INFO - Usage: mozinstall [options] installer 20:45:58 INFO - Options: 20:45:58 INFO - -h, --help show this help message and exit 20:45:58 INFO - -d DEST, --destination=DEST 20:45:58 INFO - Directory to install application into. [default: 20:45:58 INFO - "/builds/slave/test"] 20:45:58 INFO - --app=APP Application being installed. [default: firefox] 20:45:58 INFO - mkdir: /builds/slave/test/build/application 20:45:58 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:45:58 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:46:32 INFO - Reading from file tmpfile_stdout 20:46:32 INFO - Output received: 20:46:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:46:32 INFO - Running post-action listener: _resource_record_post_action 20:46:32 INFO - [mozharness: 2016-11-22 04:46:32.307458Z] Finished install step (success) 20:46:32 INFO - [mozharness: 2016-11-22 04:46:32.307605Z] Running run-tests step. 20:46:32 INFO - Running pre-action listener: _resource_record_pre_action 20:46:32 INFO - Running main action method: run_tests 20:46:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:46:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:46:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:46:32 INFO - 2016-11-21 20:46:32.322 screenresolution[1629:7705] starting screenresolution argv=screenresolution get 20:46:32 INFO - 2016-11-21 20:46:32.324 screenresolution[1629:7705] Display 0: 1600x1200x32@60 20:46:32 INFO - 2016-11-21 20:46:32.331 screenresolution[1630:7707] starting screenresolution argv=screenresolution list 20:46:32 INFO - Available Modes on Display 0 20:46:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:46:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:46:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:46:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:46:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:46:32 INFO - Intel Iris: 20:46:32 INFO - Chipset Model: Intel Iris 20:46:32 INFO - Type: GPU 20:46:32 INFO - Bus: Built-In 20:46:32 INFO - VRAM (Dynamic, Max): 1536 MB 20:46:32 INFO - Vendor: Intel (0x8086) 20:46:32 INFO - Device ID: 0x0a2e 20:46:32 INFO - Revision ID: 0x0009 20:46:32 INFO - Displays: 20:46:32 INFO - Display: 20:46:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:46:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:46:32 INFO - Display Serial Number: 1600x1200 60 20:46:32 INFO - Main Display: Yes 20:46:32 INFO - Mirror: Off 20:46:32 INFO - Online: Yes 20:46:32 INFO - Rotation: Supported 20:46:32 INFO - Return code: 0 20:46:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:46:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:46:32 INFO - Python 2.7.3 20:46:32 INFO - Return code: 0 20:46:32 INFO - grabbing minidump binary from tooltool 20:46:32 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:46:32 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 0x101406d30>, '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 0x10126ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013feb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:46:32 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:46:32 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:46:32 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:46:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:46:32 INFO - Return code: 0 20:46:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:46:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:46:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:46:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:46:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:46:32 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/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0290', '--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:46:32 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/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0290 --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:46:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXQvSwjDI7/Render', 20:46:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:32 INFO - 'HOME': '/Users/cltbld', 20:46:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:32 INFO - 'LOGNAME': 'cltbld', 20:46:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:46:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:32 INFO - 'MOZ_NO_REMOTE': '1', 20:46:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:46:32 INFO - 'NO_EM_RESTART': '1', 20:46:32 INFO - 'PAGER': '/bin/cat', 20:46:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:32 INFO - 'PWD': '/builds/slave/test', 20:46:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:46:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:32 INFO - 'SHELL': '/bin/bash', 20:46:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFk1TvGXiF/Listeners', 20:46:32 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:46:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:32 INFO - 'USER': 'cltbld', 20:46:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:32 INFO - 'XPC_FLAGS': '0x0', 20:46:32 INFO - 'XPC_SERVICE_NAME': '0', 20:46:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:32 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/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0290', '--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:46:32 INFO - mozversion application_buildid: 20161121182450 20:46:32 INFO - mozversion application_changeset: 8a3e765c52b23d55b39dc483e34de440e8d49ab9 20:46:32 INFO - mozversion application_display_name: Nightly 20:46:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:46:32 INFO - mozversion application_name: Firefox 20:46:32 INFO - mozversion application_remotingname: firefox 20:46:32 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 20:46:32 INFO - mozversion application_vendor: Mozilla 20:46:32 INFO - mozversion application_version: 53.0a1 20:46:32 INFO - mozversion platform_buildid: 20161121182450 20:46:32 INFO - mozversion platform_changeset: 8a3e765c52b23d55b39dc483e34de440e8d49ab9 20:46:32 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 20:46:32 INFO - mozversion platform_version: 53.0a1 20:46:32 INFO - using testdate: 1479789992 20:46:32 INFO - actual date: 1479789992 20:46:32 INFO - starting webserver on 'localhost:49244' 20:46:32 INFO - SUITE-START | Running 2 tests 20:46:32 INFO - TEST-START | dromaeo_css 20:46:32 INFO - operating with platform_type : mac_ 20:46:32 INFO - Initialising browser for dromaeo_css test... 20:46:32 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpOpNJFu/profile http://localhost:49244/getInfo.html) 20:46:36 INFO - PROCESS | 1640 | 2016-11-21 20:46:36.024 plugin-container[1642:7883] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 20:46:36 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:46:36 INFO - PROCESS | 1640 | 2016-11-21 20:46:36.027 plugin-container[1642:7883] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 20:46:36 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:46:36 INFO - PROCESS | 1640 | 2016-11-21 20:46:36.028 plugin-container[1642:7883] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:46:36 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 20:46:36 INFO - PROCESS | 1640 | colorDepth:24 20:46:36 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 20:46:36 INFO - PROCESS | 1640 | __metrics 20:46:36 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 20:46:36 INFO - TEST-INFO | 1640: exit 0 20:46:36 INFO - Browser initialized. 20:46:36 INFO - Running cycle 1/1 for dromaeo_css test... 20:46:36 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpOpNJFu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:46:39 INFO - PROCESS | 1643 | 2016-11-21 20:46:39.774 plugin-container[1644:8005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b47, name = 'com.apple.tsm.portname' 20:46:39 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:46:39 INFO - PROCESS | 1643 | 2016-11-21 20:46:39.776 plugin-container[1644:8005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 20:46:39 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:46:39 INFO - PROCESS | 1643 | 2016-11-21 20:46:39.776 plugin-container[1644:8005] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:46:49 INFO - PROCESS | 1643 | RSS: Main: 243208192 20:46:49 INFO - PROCESS | 1643 | 20:47:39 INFO - PROCESS | 1643 | 1479790059106 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:49:52 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 20:49:54 INFO - PROCESS | 1643 | RSS: Main: 249024512 20:49:54 INFO - PROCESS | 1643 | 20:52:56 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 20:52:57 INFO - PROCESS | 1643 | RSS: Main: 260280320 20:52:57 INFO - PROCESS | 1643 | 20:55:59 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 20:55:59 INFO - PROCESS | 1643 | RSS: Main: 264732672 20:55:59 INFO - PROCESS | 1643 | 20:59:01 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 20:59:02 INFO - PROCESS | 1643 | RSS: Main: 263266304 20:59:02 INFO - PROCESS | 1643 | 21:02:05 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 21:02:05 INFO - PROCESS | 1643 | RSS: Main: 267776000 21:02:05 INFO - PROCESS | 1643 | 21:05:09 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 21:05:09 INFO - PROCESS | 1643 | RSS: Main: 269938688 21:05:09 INFO - PROCESS | 1643 | 21:05:09 INFO - PROCESS | 1643 | __start_tp_report 21:05:09 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 21:05:09 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 21:05:09 INFO - PROCESS | 1643 | |i|pagename|runs| 21:05:09 INFO - PROCESS | 1643 | |0;dojo.html;2808.9016884409043;3445.1903802606384;3460.3425349183653;3471;3508.842102105406;5767;5790.420957904207;5793.391693872146;5827.096799996002;5867.295924489056;5567.137093750465;5704.201411802351;5713.257276554053;5756.798512052069;5758.337791154018;4807.1587472192405;4921.80312787488;5193.974030129853;5262.236975638531;5328.893422131575;5316.202569614569;5346.6791992480385;5361.08861493547;5365.570754339663;5379.515843574078;5356.571474282048;5357.919631205535;5361.061814182522;5363.704996225206;5375.973120134413;5336.493033161843;5366.758495867695;5369.301990741218;5370.892582148335;5381.4349493303225;5220.477952204749;5309.920351194735;5317.760700768436;5321.946780532223;5331.520163185293;5784.710764461802;5821.09772985188;5885.146653735184;5921.585489015771;5951.464368206839;6120.540959428061;6277.340879207663;6282.465990390822;6283.465905398047;6314.71583778731;6175.4442100210745;6212.689365531705;6261.029540421242;6285.151504546919;6286.308506064329;5956.612820166719;5967.850803729898;5969.343372229051;5998.520118390518;6029.939700602981;6342.8414289642205;6380.553361264667;6394.328595497499;6403.423691867662;6415.390537898892;6105.908411373833;6106.764041036412;6106.794380079898;6241.656708881055;6276.46650034743;5271.71005594696;6108.433136212329;6211.534134939807;6243.282022567372;6257.78097766576;6235.657038862815;6242.563020588515;6274.780382686676;6289.559730818799;6311.747530098836;6176.227971503562;6177.104319873593;6227.532935029891;6229.473778924193;6300.180976473121;3764.8117594120185;3766.9434958475645;5015.874603134951;5044.495550445;5053.772580233898;4465.910681786411;4487.977560112178;4490.685652004328;4495.617872480811;4517.480489743656;4851.587615052689;5736.139579063174;5873.765049397976;5926.4666180044005;6036.63780173191;6250.718717657716;6265.154204182377;6266.843328916722;6267.4986001119805;6274.247090349188;5440.863978400572;5677.574181936371;5918.556108291809;5947.821565353046;6028.9397106029255;5407.378151512547;5409.377921539073;5443.101888188405;5460.235567020621;5503.807366742145;6270;6389.137466441971;6396.744130234924;6402.775902843565;6405.327440618855;5170.612204084784;5335;5858.765649373977;6123.387661233841;6255.311915689362;5424.779424629584;5941.9405805943115;6290.968545157245;6293.96853015732;6377.617342959437;5522.3649280333275;6042.214512113397;6122.816315510542;6251.437370636664;6260.154879091447;6250.406211409909;6252.968735156295;6266.028765541349;6278.874422511615;6302.306746257999;5312.0969435196885;5587.798623296011;6026.758929642765;6144.508439324933;6166.722497487534;4614.446266447913;6122.459144589048;6125.571219981412;6145.569810113428;6157.138000679998;5188.9740551297;5830.125481177857;6005.699715014319;6100.8779824404155;6129.877402452015;5038;6169.2288463942;6179.165812615419;6186.783462578788;6223.782167624111;5906.966280900911;5975.492083172979;5989.490893274121;6112.358202388685;6125.9693701531205 21:05:09 INFO - PROCESS | 1643 | |1;ext.html;480.6371189751737;510.3110800419437;513.7713717395759;524.997255242487;525.8737902903302;30413.695863041365;38967.83096507106;39067;39120.608793912055;39179.0205244869;39757.204855902935;40510.784676459676;40531.594684053154;40560.59439405613;40584.39123413151;9122.635094596208;9438.716838494856;9452.66915657952;9469.526523673823;9483.95258023709;9389.62441502339;9431.104045115702;9460.81078378432;9474.526273686322;9490.430574165572;7960.840783184332;8326.209010144026;8692.43499172555;9233.169014788667;9440.622375104987;9390.342676012648;9401.341906066578;9498.810023799555;9517.476538790364;9525.52372381385;9218.35471516994;9336.626534938594;9337.953310233474;9351.018143094945;9364.672236471692;37549.436758448646;37932;38017.80991095054;38035.429468557995;38110;3332.133645252252;3379.509971054208;3388.15296175956;3531.1525233943667;3716.4239542870896;23607.583544987356;28951.42097158066;29095.272618184503;29659.55510667342;30379.69620303813;3169.2710676544525;3177.4785074759816;3180.0777774445382;3180.6342270638943;3286.277019055804;431007;432450;435613;436666.9999999968;439175;53704.46295537073;53938.730306349;53943.46056539463;53964.222504425095;54003;32830.67169328324;33588.83205584005;56393;56651.71674141603;56714.86570268654;15131.848681513044;15585.922070389803;15604.92197539005;15669.764953525706;15684.372625095097;11911;11927.22473039267;12015.978641815369;12024.639260822189;12051.578194763317;14977.625559361104;18297.35959241447;18365;18395.63208735818;18426.539336516427;6402.6158430494315;7560.845159129622;7708.884366734504;7751.457397982087;7784.494007889469;839.202757380491;924.4545718026396;952.7856232347805;967.5645959318335;987.6691308411763;28487.860485580346;28618.42763144767;29233;29433.55849662257;29513;795.4670370851543;902.8194361127801;1124.7413094988199;1182.9585964491203;1199.2504684572143;11161.441927903572;12135.15053946233;12445.75108497825;14276.928615356857;15002.774958375632;525124;590475;602880;612138;615206;53466;53805;53982.92034159374;54362.45637543574;54562.727186363816;1289.335991964139;1337.9397927093305;1345.3945724423852;1350.473315406992;1361.0880709924375;24014.63978040296;26690.46262135403;27541.86229068842;28477.430451391272;29134;46204.53795462002;46497.83755406142;46627;46705.29942050804;46757;539134;540731;541414;541703;543171;24860.62709059402;24963.37591560153;25215.873920630278;25617.359566011;25657.615135772605;43962.78018609886;44208;44859.10281794411;44974;45121.323180152984;36008.099797505274;36612;36927.44608830816;37286.140401631936;37447.625523745504;35317.1170720734;35349;35967.82016089903;36192.638073618924;36375.45436818397;35118.12204694903;36235.09412264715;36325;36675.816620916725;36826.26347473089;26004.86997565;31865.36269274648;32759.508607370433;33170;33193;21170.788292116882;24450.51098978046;24559.38601534976;24756.38109047288;24871.378215544755 21:05:09 INFO - PROCESS | 1643 | |2;jquery.html;371.4873474605046;373.0710530778362;378.4777007729333;384.65189003951434;395.8278149005183;16506.83493165068;18340;19441.222351105975;19883.80116198836;20076.297329593468;19849.404517864452;19915.701264481046;20208.89895550524;20213.59572808543;20259.489000139623;4093.8157782899693;5413.83758487246;5629.887402251952;5823.767049318022;5980.700964951735;5297.390800057985;5391;5906.37983011784;5911.408859114097;5914.290285165786;5495.53287970523;5736.913946290809;5758.712064396785;5825.980453420634;5858.091995740667;5751.4248575142365;5779.2775903012125;5812.128180772876;5818.91271630928;5821.767129314822;4880.658353915227;5077.695338279678;5088;5135.972805438927;5148.176291793295;24430.755692443203;24745.752542474525;24964.00143994238;25037;25280.98876044956;1693.8475537201712;2266.433391652087;2309.988450057756;2403.42317843718;2415.577273977047;14987.625309367244;14997.550073497812;15137.924310378488;15699.215039247993;15894.443694470638;1847.8152184781413;1849.0939439674596;1853.7034074548142;1889.102676228794;1902.961940761177;111772.44113779461;115535;116100.41949790281;118582;119230;8668.393212475066;10468.52891619879;10514.684559463229;10585.84121238182;10966.56133754657;13427;13565.932170339085;13579.45682172702;13582.660433489242;13632.454701812016;7285.4171666266575;7811.711067673884;8209.179082091863;8412.621432035574;8421.70524031656;8197.303229225463;8206.794830129294;8233.958830205811;8343.582820858932;8373.037100733494;6530.738770449224;6644.534882558174;6697.263301036882;6710;6715.865682686319;1677.4129054830712;1799.379214171109;1826.9269229230947;1830.7436958825774;1844.5757475780645;370.33894498320905;375.69193261525834;806.467731297341;929.9302552308604;1066.9253152279266;11094.114000619915;11230.775384492428;11361.431928403546;11664.358460284597;11805.763884722257;371.4205838891338;414.8589479576958;416.90828017836026;418.6567015047693;432.3881707384079;5289.994900968803;5959.106134079922;6006.969965150146;6109.877802444015;6126.785562505291;556724;558482;559760;562201;588393;91195;108245;111432.44283778529;112353;129939.70060299276;884.566562384427;997.2271489595621;997.3616885193481;1000.7448100734266;1002.138161181398;17896;18790.624187516445;18913;18976.335828245945;19006.809931900505;51880.740596296775;52330.47669523256;54121;54508.45491545034;55285.72357138189;72346;73040.63479682567;73104;74687.25312746804;76065;6332.683365831782;7424.503559385537;7661.637555563183;7979.521228726295;8083.353331733565;9663.758406039906;9715.611375544899;10455.006774356729;11654.533818647498;12818.743625127632;10285.074343309137;10285.382877027403;10536.10443112344;10685.252032357661;11009.944950275198;9407.153356197976;10234.846477302995;10676.733081673021;10910.854360292054;11193.552257909592;9163.49600771964;9579.616815327308;9750.122488976027;11102.056325212447;11155.043913363128;7724.92275077242;7797.92202077972;7855;7967.641456134372;7999.360051195774;9701.417914924845;10685.51915163804;10827.729306767078;10924.836127457935;10933.507992140214 21:05:09 INFO - PROCESS | 1643 | |3;mootools.html;1477.9630509237265;1508.9245537723114;1683.7979442466906;1751.3607533250367;1815.31018213079;7543.585102819343;7770.57261850598;8001.63992620332;8493.108223636515;8509.319254459644;7932.920670793304;8212.384071194654;8259.967504061991;8352.705330673756;8361.24748772612;1985.8312043476249;2293.1744571954123;2322.535492901418;2342.601757701186;2347.565700345433;2324.674545563614;2355.49356888269;2373.5134297468976;2377.132346693462;2380.0241900820665;2309.261036468331;2321.628539433691;2335.9649605255936;2344.367020904355;2348.729896061958;2289.496310811627;2301.746807851135;2312.7687231276823;2315.2822624986306;2316.698829152216;2319.3042087373888;2323.0707716913203;2343.58987177245;2346.272655476808;2358.7759162879497;8217.95891020547;8330.50016998982;8377.70678026266;8390.202930721573;8427.789305267355;4986.177280748709;5156.458571849939;5160.40655324635;5163.406208286057;5177.223416487519;3239.1902024493875;3420.452727563603;3478.4086705259915;3585.587657419404;3591.94612080819;1000.5097502223931;1031.8710161229847;1076.8115579773587;1087.271528076183;1091.0562363555546;8477.491350518989;10404.375737455777;10736.24846260762;10798.352098874093;10868.510917008754;4419.447569053868;4500.347449619787;4583.610393116555;4604.447466304065;4685.4143232095985;9242.537873106317;9308;9319.301052421095;9328.34701570897;9341.159295663423;5143.202803565453;5143.819966301162;5153.845384638467;5165.354330708661;5173.430922598511;4187.20443115807;4595.2188128018315;4664.55686709762;4743;4797.4482934462985;3719.442083687414;3735.4210097434943;3755.7370984030854;3784.7918364489665;3804.2581696568905;1237.7338872142532;1256.47856139701;1259.8992080633527;1275.5981865712272;1276.9297688627214;843.6920524008815;853.180946291567;872.437277955715;900.6037343568809;908.9320048942466;4678.906421871544;5759.452851979056;5787.276590426197;5894.823155305347;5998.850028749229;908.927285817133;910.7085732565515;926.5282005193717;988.5699720621552;1033.0702367868828;2900.7244311790346;3270.869165233364;3695.3348397288746;3744.8689295874515;3803.31540322739;9795.608175673036;10007;10086.193104551618;10093.848592271122;10117.59529618807;9677.129058384779;9814.705558833246;10067.647632332833;10078.395296282246;10114.898851011543;6580.86838263232;7620.80947976305;7736.6518506667335;7845.607719613997;7868.44920855546;5356.901835123713;5449.100898351728;5477.4796394342475;5493.890122197614;5542.3626282977975;9297.953510232406;9372.859407108763;9376.859347109936;9409.1531762139;9431.386959847589;8604.48373097616;9181.265498760216;9457.716268511957;9505.572249248662;9595.568199431182;5644.407337229532;6169.444749972524;6648.368405001517;6679.398854103154;6684.696484185537;7847.372210223054;7873.09459412175;7918.129005809472;7934.928784614003;7985.64064617082;6949.930500695131;7087.929120708934;7110.4667149965;7137.072180616487;7199.316064974028;7003.369696727319;7010.404115650227;7024.719011239493;7034.788956331318;7137.785866424016;6511.413972742476;6543.083968244355;6600.405963463311;6610.4050635443045;6636.13730215069;4717.929231061606;4756.738379389167;4766.165920963748;4823.011282686971;4837.95162048375;4694.671373003994;4712.52874712526;4781.330613714153;4855.708657480563;4931.950680493293 21:05:09 INFO - PROCESS | 1643 | |4;prototype.html;260.84740426850294;293.83545214679765;302.86673863500044;312.515600818731;313.1638525137882;5147.268230532862;5191.299174611427;5384.784608615656;5507.36665283492;5513.696746678931;4539.546045395458;4640.419947506562;5160.277561141434;5286.286351342567;5290.730224746062;3450.7756995795194;4101.097758493142;4122.505299364073;4150.779333056816;4197.559256278093;3651.3610118229203;4035.455213546163;4131.111810960643;4197.265478541259;4199.769012704315;3547.5033495310677;4065.0040740018735;4082.5098838923727;4088.6115818997146;4120.855770048049;3716.628337166289;4090.386442033689;4090.5704900985415;4093.9181216375505;4112.670986321087;3584.444411116281;3608.657177568127;3613.33153366628;3626.9637303627155;3631.110377957391;3932.528096628423;4272.487301523806;4360.694751367406;5211.635185537014;5218.921716174261;3560.1633616100194;4702.717836929761;4715.1512727709;4731.976340118277;4745.6440766942615;5577.470140336662;5636.943630563724;5644.463775941279;5675.120356344773;5756;3186.012336175793;3627.165751877083;3739.607341229186;3973.22522108189;3981.880543583697;5299.417064122943;5542.86142846428;5795.855103622359;5916.852078698024;5964.910526342108;3904.902377440587;3909.237698648736;3913.7651740895635;3915.686745060388;3935.980320098381;3017.7887547871437;3764.0213544478083;3945.349017412152;3957.2085582883537;3985.561588225293;4564.315352697122;4637.837675681335;4669.836555720533;4677;4698.271767875985;5655.575831812548;5660.632058916157;5697.686627235459;5725.370209276976;5741.425857414309;1232.6810312965042;1279.4178648714812;1312.8555858855518;1313.975099422452;1345.414744586108;776.8485145396594;919.8436265834824;921.0605182713729;937.5124935033745;941.8540126280437;4551.907542189851;4817.686850354716;4845.394325709286;5008.323876276729;5073.275086470618;4813.4945830687975;5194.766235519411;5251.212318152231;5286.127788914863;5554.861128471821;4114.588541145861;4525.389072475174;4681.799554209516;4861.829835955725;5016.54851063406;2360.2919124262653;2835.2203144135115;2857.585650080768;2884.624998750149;2885.7835662324987;5374.742544228355;5465.562754979592;5612.635178713371;5730.570207234391;5782.537397008229;4816.494268101869;5001.398992725232;5327.893442131213;5504.256925315111;5595.719274754816;4844.8304309349005;5089.51649593288;5305.973470132624;5621.381648018633;5736.598438109292;4448.977755111204;4680.274557443602;5426.6044503951925;5447;5453.772901097222;4601.976990115028;4975.527324904128;5407.405185429521;5409.891802163856;5485.259489968963;4775.689580177277;4911.456902365227;5421.5391691706645;5572.693501857275;5713.085906254979;3405.812680302607;3703.962960370362;3831.5210598675167;3887.5140607424073;3903.8048097595574;4763.73799441034;5188;5227.581793456438;5773.566982476415;5810.535157187331;4496.460424749051;5323.787048518016;5631.155326701028;5645.491905728504;5668.829935101953;4950;4982.227754698028;5240.606954478353;5261.973690131525;5597.264847897165;4469.575390337913;4643.001754622773;5175.430702622633;5309.442508536548;5631.267935168402;4652.185867473111;4893.874408885905;5711.742971566373;5760.423957604206;5763.250777398911;4968.925466118083;5413.729313534386;5616.371252336777;5623.803166889139;5642.492175704207 21:05:09 INFO - PROCESS | 1643 | |5;yui.html;579.7854793726317;660.8744338575667;740.4779630360596;743.565014466537;810.5055915891307;14091.718165636681;14964.176970266632;15071.246437678099;15120.773188402156;15134.848651513495;13059.934700326485;13650.18098914066;13700.383482743275;13725.93137034316;13784.379702913368;822.3339095332784;832.6128350317101;838.5513750143674;838.8028813228887;842.3345557009985;650.5836264790538;677.7390704578723;802.9638666260032;827.0530242871909;830.4145577367972;862.706679728892;864.9221570058694;877.1360210192955;877.4515927545284;878.6704985630389;846.6147902704286;863.0980625246632;872.1235158665564;872.8865247517782;873.9949058582649;636.258758546293;646.8027251688234;647.5726020826279;652.9902051469231;653.6894974886939;14430.855691443056;14510.637234069127;14552.92723536386;14622.341994610268;14650.120992740469;13656.65858353549;13777.586672399844;13883;13945.790813137812;14122.505712300122;9918.25613079022;11136.498857551349;11145.21983461158;11355.31868087909;11411.144164187635;3026.788124831263;3740.1397678533817;3798.601146879593;3858.5176852893387;3863.8840834775;86684.56657716734;87431.56284218602;89123.10876891213;89625.10374896233;89995.65006524908;9814.46020468881;10067.144292735053;10267.640632577824;10511;10780.191485638608;19147.425577232705;19199.368053715312;19222.519437014187;19376.03119844002;19377.512047831242;6120.785772497941;7268.200497945222;7352.411807055422;7396.260373962561;7451.813704657427;4214.768187749642;4236.872893813191;4240.1731662326165;4242.745435273893;4245.80893859777;3160.8893688720786;3251.4797632378704;3270.8364581770816;3276.9180770480452;3293.1108600677685;606.1332294818452;609.1137395090133;616.5899676715024;618.6504624886925;618.8298217989999;569.7578529125105;585.2771826793906;615.9630422174683;618.1438707390207;623.6476390839161;8513.829723405497;8538.701145460003;8551.230389264996;8561;8603.91957444056;523.3876364653364;598.3119412675371;611.2267981004034;647.0779139725872;649.1885143570537;3707.925841483155;4016.4176194451643;4220.641245494167;4240.491141063092;4263.509696384894;66049;90229.09770902338;103773;104441;105732;59632.70183649054;60070;76485.85271220937;78427;78439;52673;77557;80781.78827317712;82857.17142828615;84074.57962710147;7305.83163871598;9056.54717264121;9147.268218542367;9261.657059726436;9275.907240927774;36149.457758133125;36310.8184459076;39166.43334266597;52798.73600631972;53934.19098713601;56552;69925.9511107344;71581.64209178921;71993;72787.63606181936;12015.519379224734;12040.397980100784;12100.818487722516;12246.877531224574;12279;13611.86388136106;13855.515056973361;13856.93071534636;14434.927825361226;14607.342669579682;12268.938655306667;12278.324692142018;12987.545435909698;13052.54316098932;13072.346382681018;12765.93617031946;12792.424340904869;12861.421236044212;12908.548200812926;13349;12741.155155593502;12813.103082784115;12877;13044.347782611021;13314.894123245285;10396.428196449267;10538.947305263424;10685.76562188043;10760.3543787373;10830.891691083303;9031.142041506046;9956.502174891371;9958.800823983625;10417.9479102604;10668.83996740034 21:05:09 INFO - PROCESS | 1643 | __end_tp_report 21:05:09 INFO - PROCESS | 1643 | __start_cc_report 21:05:09 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,375 21:05:09 INFO - PROCESS | 1643 | __end_cc_report 21:05:09 INFO - PROCESS | 1643 | __startTimestamp1479791109256__endTimestamp 21:05:09 INFO - PROCESS | 1643 | 21:05:09 INFO - PROCESS | 1643 | ------- Summary: start ------- 21:05:09 INFO - PROCESS | 1643 | Number of tests: 6 21:05:09 INFO - PROCESS | 1643 | 21:05:09 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5726.81 Median:5956.61 stddev:679.28 (11.4%) stddev-sans-first:641.64 21:05:09 INFO - PROCESS | 1643 | Values: 2808.9 3445.2 3460.3 3471.0 3508.8 5767.0 5790.4 5793.4 5827.1 5867.3 5567.1 5704.2 5713.3 5756.8 5758.3 4807.2 4921.8 5194.0 5262.2 5328.9 5316.2 5346.7 5361.1 5365.6 5379.5 5356.6 5357.9 5361.1 5363.7 5376.0 5336.5 5366.8 5369.3 5370.9 5381.4 5220.5 5309.9 5317.8 5321.9 5331.5 5784.7 5821.1 5885.1 5921.6 5951.5 6120.5 6277.3 6282.5 6283.5 6314.7 6175.4 6212.7 6261.0 6285.2 6286.3 5956.6 5967.9 5969.3 5998.5 6029.9 6342.8 6380.6 6394.3 6403.4 6415.4 6105.9 6106.8 6106.8 6241.7 6276.5 5271.7 6108.4 6211.5 6243.3 6257.8 6235.7 6242.6 6274.8 6289.6 6311.7 6176.2 6177.1 6227.5 6229.5 6300.2 3764.8 3766.9 5015.9 5044.5 5053.8 4465.9 4488.0 4490.7 4495.6 4517.5 4851.6 5736.1 5873.8 5926.5 6036.6 6250.7 6265.2 6266.8 6267.5 6274.2 5440.9 5677.6 5918.6 5947.8 6028.9 5407.4 5409.4 5443.1 5460.2 5503.8 6270.0 6389.1 6396.7 6402.8 6405.3 5170.6 5335.0 5858.8 6123.4 6255.3 5424.8 5941.9 6291.0 6294.0 6377.6 5522.4 6042.2 6122.8 6251.4 6260.2 6250.4 6253.0 6266.0 6278.9 6302.3 5312.1 5587.8 6026.8 6144.5 6166.7 4614.4 6122.5 6125.6 6145.6 6157.1 5189.0 5830.1 6005.7 6100.9 6129.9 5038.0 6169.2 6179.2 6186.8 6223.8 5907.0 5975.5 5989.5 6112.4 6126.0 21:05:09 INFO - PROCESS | 1643 | 21:05:09 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:64556.88 Median:38538.92 stddev:140539.66 (364.7%) stddev-sans-first:140851.57 21:05:09 INFO - PROCESS | 1643 | Values: 480.6 510.3 513.8 525.0 525.9 30413.7 38967.8 39067.0 39120.6 39179.0 39757.2 40510.8 40531.6 40560.6 40584.4 9122.6 9438.7 9452.7 9469.5 9484.0 9389.6 9431.1 9460.8 9474.5 9490.4 7960.8 8326.2 8692.4 9233.2 9440.6 9390.3 9401.3 9498.8 9517.5 9525.5 9218.4 9336.6 9338.0 9351.0 9364.7 37549.4 37932.0 38017.8 38035.4 38110.0 3332.1 3379.5 3388.2 3531.2 3716.4 23607.6 28951.4 29095.3 29659.6 30379.7 3169.3 3177.5 3180.1 3180.6 3286.3 431007.0 432450.0 435613.0 436667.0 439175.0 53704.5 53938.7 53943.5 53964.2 54003.0 32830.7 33588.8 56393.0 56651.7 56714.9 15131.8 15585.9 15604.9 15669.8 15684.4 11911.0 11927.2 12016.0 12024.6 12051.6 14977.6 18297.4 18365.0 18395.6 18426.5 6402.6 7560.8 7708.9 7751.5 7784.5 839.2 924.5 952.8 967.6 987.7 28487.9 28618.4 29233.0 29433.6 29513.0 795.5 902.8 1124.7 1183.0 1199.3 11161.4 12135.2 12445.8 14276.9 15002.8 525124.0 590475.0 602880.0 612138.0 615206.0 53466.0 53805.0 53982.9 54362.5 54562.7 1289.3 1337.9 1345.4 1350.5 1361.1 24014.6 26690.5 27541.9 28477.4 29134.0 46204.5 46497.8 46627.0 46705.3 46757.0 539134.0 540731.0 541414.0 541703.0 543171.0 24860.6 24963.4 25215.9 25617.4 25657.6 43962.8 44208.0 44859.1 44974.0 45121.3 36008.1 36612.0 36927.4 37286.1 37447.6 35317.1 35349.0 35967.8 36192.6 36375.5 35118.1 36235.1 36325.0 36675.8 36826.3 26004.9 31865.4 32759.5 33170.0 33193.0 21170.8 24450.5 24559.4 24756.4 24871.4 21:05:09 INFO - PROCESS | 1643 | 21:05:09 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:32930.76 Median:390.24 stddev:94268.36 (24156.5%) stddev-sans-first:94501.11 21:05:09 INFO - PROCESS | 1643 | Values: 371.5 373.1 378.5 384.7 395.8 16506.8 18340.0 19441.2 19883.8 20076.3 19849.4 19915.7 20208.9 20213.6 20259.5 4093.8 5413.8 5629.9 5823.8 5980.7 5297.4 5391.0 5906.4 5911.4 5914.3 5495.5 5736.9 5758.7 5826.0 5858.1 5751.4 5779.3 5812.1 5818.9 5821.8 4880.7 5077.7 5088.0 5136.0 5148.2 24430.8 24745.8 24964.0 25037.0 25281.0 1693.8 2266.4 2310.0 2403.4 2415.6 14987.6 14997.6 15137.9 15699.2 15894.4 1847.8 1849.1 1853.7 1889.1 1903.0 111772.4 115535.0 116100.4 118582.0 119230.0 8668.4 10468.5 10514.7 10585.8 10966.6 13427.0 13565.9 13579.5 13582.7 13632.5 7285.4 7811.7 8209.2 8412.6 8421.7 8197.3 8206.8 8234.0 8343.6 8373.0 6530.7 6644.5 6697.3 6710.0 6715.9 1677.4 1799.4 1826.9 1830.7 1844.6 370.3 375.7 806.5 929.9 1066.9 11094.1 11230.8 11361.4 11664.4 11805.8 371.4 414.9 416.9 418.7 432.4 5290.0 5959.1 6007.0 6109.9 6126.8 556724.0 558482.0 559760.0 562201.0 588393.0 91195.0 108245.0 111432.4 112353.0 129939.7 884.6 997.2 997.4 1000.7 1002.1 17896.0 18790.6 18913.0 18976.3 19006.8 51880.7 52330.5 54121.0 54508.5 55285.7 72346.0 73040.6 73104.0 74687.3 76065.0 6332.7 7424.5 7661.6 7979.5 8083.4 9663.8 9715.6 10455.0 11654.5 12818.7 10285.1 10285.4 10536.1 10685.3 11009.9 9407.2 10234.8 10676.7 10910.9 11193.6 9163.5 9579.6 9750.1 11102.1 11155.0 7724.9 7797.9 7855.0 7967.6 7999.4 9701.4 10685.5 10827.7 10924.8 10933.5 21:05:09 INFO - PROCESS | 1643 | 21:05:09 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5376.37 Median:5148.83 stddev:2925.67 (56.8%) stddev-sans-first:2919.21 21:05:09 INFO - PROCESS | 1643 | Values: 1478.0 1508.9 1683.8 1751.4 1815.3 7543.6 7770.6 8001.6 8493.1 8509.3 7932.9 8212.4 8260.0 8352.7 8361.2 1985.8 2293.2 2322.5 2342.6 2347.6 2324.7 2355.5 2373.5 2377.1 2380.0 2309.3 2321.6 2336.0 2344.4 2348.7 2289.5 2301.7 2312.8 2315.3 2316.7 2319.3 2323.1 2343.6 2346.3 2358.8 8218.0 8330.5 8377.7 8390.2 8427.8 4986.2 5156.5 5160.4 5163.4 5177.2 3239.2 3420.5 3478.4 3585.6 3591.9 1000.5 1031.9 1076.8 1087.3 1091.1 8477.5 10404.4 10736.2 10798.4 10868.5 4419.4 4500.3 4583.6 4604.4 4685.4 9242.5 9308.0 9319.3 9328.3 9341.2 5143.2 5143.8 5153.8 5165.4 5173.4 4187.2 4595.2 4664.6 4743.0 4797.4 3719.4 3735.4 3755.7 3784.8 3804.3 1237.7 1256.5 1259.9 1275.6 1276.9 843.7 853.2 872.4 900.6 908.9 4678.9 5759.5 5787.3 5894.8 5998.9 908.9 910.7 926.5 988.6 1033.1 2900.7 3270.9 3695.3 3744.9 3803.3 9795.6 10007.0 10086.2 10093.8 10117.6 9677.1 9814.7 10067.6 10078.4 10114.9 6580.9 7620.8 7736.7 7845.6 7868.4 5356.9 5449.1 5477.5 5493.9 5542.4 9298.0 9372.9 9376.9 9409.2 9431.4 8604.5 9181.3 9457.7 9505.6 9595.6 5644.4 6169.4 6648.4 6679.4 6684.7 7847.4 7873.1 7918.1 7934.9 7985.6 6949.9 7087.9 7110.5 7137.1 7199.3 7003.4 7010.4 7024.7 7034.8 7137.8 6511.4 6543.1 6600.4 6610.4 6636.1 4717.9 4756.7 4766.2 4823.0 4838.0 4694.7 4712.5 4781.3 4855.7 4932.0 21:05:09 INFO - PROCESS | 1643 | 21:05:09 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4426.20 Median:4831.26 stddev:1338.22 (27.7%) stddev-sans-first:1304.95 21:05:09 INFO - PROCESS | 1643 | Values: 260.8 293.8 302.9 312.5 313.2 5147.3 5191.3 5384.8 5507.4 5513.7 4539.5 4640.4 5160.3 5286.3 5290.7 3450.8 4101.1 4122.5 4150.8 4197.6 3651.4 4035.5 4131.1 4197.3 4199.8 3547.5 4065.0 4082.5 4088.6 4120.9 3716.6 4090.4 4090.6 4093.9 4112.7 3584.4 3608.7 3613.3 3627.0 3631.1 3932.5 4272.5 4360.7 5211.6 5218.9 3560.2 4702.7 4715.2 4732.0 4745.6 5577.5 5636.9 5644.5 5675.1 5756.0 3186.0 3627.2 3739.6 3973.2 3981.9 5299.4 5542.9 5795.9 5916.9 5964.9 3904.9 3909.2 3913.8 3915.7 3936.0 3017.8 3764.0 3945.3 3957.2 3985.6 4564.3 4637.8 4669.8 4677.0 4698.3 5655.6 5660.6 5697.7 5725.4 5741.4 1232.7 1279.4 1312.9 1314.0 1345.4 776.8 919.8 921.1 937.5 941.9 4551.9 4817.7 4845.4 5008.3 5073.3 4813.5 5194.8 5251.2 5286.1 5554.9 4114.6 4525.4 4681.8 4861.8 5016.5 2360.3 2835.2 2857.6 2884.6 2885.8 5374.7 5465.6 5612.6 5730.6 5782.5 4816.5 5001.4 5327.9 5504.3 5595.7 4844.8 5089.5 5306.0 5621.4 5736.6 4449.0 4680.3 5426.6 5447.0 5453.8 4602.0 4975.5 5407.4 5409.9 5485.3 4775.7 4911.5 5421.5 5572.7 5713.1 3405.8 3704.0 3831.5 3887.5 3903.8 4763.7 5188.0 5227.6 5773.6 5810.5 4496.5 5323.8 5631.2 5645.5 5668.8 4950.0 4982.2 5240.6 5262.0 5597.3 4469.6 4643.0 5175.4 5309.4 5631.3 4652.2 4893.9 5711.7 5760.4 5763.3 4968.9 5413.7 5616.4 5623.8 5642.5 21:05:09 INFO - PROCESS | 1643 | 21:05:09 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:18616.68 Median:2393.45 stddev:26317.90 (1099.6%) stddev-sans-first:26356.88 21:05:09 INFO - PROCESS | 1643 | Values: 579.8 660.9 740.5 743.6 810.5 14091.7 14964.2 15071.2 15120.8 15134.8 13059.9 13650.2 13700.4 13725.9 13784.4 822.3 832.6 838.6 838.8 842.3 650.6 677.7 803.0 827.1 830.4 862.7 864.9 877.1 877.5 878.7 846.6 863.1 872.1 872.9 874.0 636.3 646.8 647.6 653.0 653.7 14430.9 14510.6 14552.9 14622.3 14650.1 13656.7 13777.6 13883.0 13945.8 14122.5 9918.3 11136.5 11145.2 11355.3 11411.1 3026.8 3740.1 3798.6 3858.5 3863.9 86684.6 87431.6 89123.1 89625.1 89995.7 9814.5 10067.1 10267.6 10511.0 10780.2 19147.4 19199.4 19222.5 19376.0 19377.5 6120.8 7268.2 7352.4 7396.3 7451.8 4214.8 4236.9 4240.2 4242.7 4245.8 3160.9 3251.5 3270.8 3276.9 3293.1 606.1 609.1 616.6 618.7 618.8 569.8 585.3 616.0 618.1 623.6 8513.8 8538.7 8551.2 8561.0 8603.9 523.4 598.3 611.2 647.1 649.2 3707.9 4016.4 4220.6 4240.5 4263.5 66049.0 90229.1 103773.0 104441.0 105732.0 59632.7 60070.0 76485.9 78427.0 78439.0 52673.0 77557.0 80781.8 82857.2 84074.6 7305.8 9056.5 9147.3 9261.7 9275.9 36149.5 36310.8 39166.4 52798.7 53934.2 56552.0 69926.0 71581.6 71993.0 72787.6 12015.5 12040.4 12100.8 12246.9 12279.0 13611.9 13855.5 13856.9 14434.9 14607.3 12268.9 12278.3 12987.5 13052.5 13072.3 12765.9 12792.4 12861.4 12908.5 13349.0 12741.2 12813.1 12877.0 13044.3 13314.9 10396.4 10538.9 10685.8 10760.4 10830.9 9031.1 9956.5 9958.8 10417.9 10668.8 21:05:09 INFO - PROCESS | 1643 | -------- Summary: end -------- 21:05:09 INFO - PROCESS | 1643 | 21:05:09 INFO - TEST-INFO | 1643: exit 0 21:05:09 INFO - TEST-OK | dromaeo_css | took 1117040ms 21:05:09 INFO - TEST-START | kraken 21:05:09 INFO - operating with platform_type : mac_ 21:05:09 INFO - Initialising browser for kraken test... 21:05:09 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmp9V4Auz/profile http://localhost:49244/getInfo.html) 21:05:13 INFO - PROCESS | 1655 | 2016-11-21 21:05:13.000 plugin-container[1656:10560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 21:05:13 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:05:13 INFO - PROCESS | 1655 | 2016-11-21 21:05:13.003 plugin-container[1656:10560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 21:05:13 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:05:13 INFO - PROCESS | 1655 | 2016-11-21 21:05:13.003 plugin-container[1656:10560] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:05:13 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 21:05:13 INFO - PROCESS | 1655 | colorDepth:24 21:05:13 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 21:05:13 INFO - PROCESS | 1655 | __metrics 21:05:13 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 21:05:14 INFO - TEST-INFO | 1655: exit 0 21:05:14 INFO - Browser initialized. 21:05:14 INFO - Running cycle 1/1 for kraken test... 21:05:14 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmp9V4Auz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:05:16 INFO - PROCESS | 1657 | 2016-11-21 21:05:16.784 plugin-container[1658:10674] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 21:05:16 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:05:16 INFO - PROCESS | 1657 | 2016-11-21 21:05:16.784 plugin-container[1658:10674] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b27, name = 'com.apple.CFPasteboardClient' 21:05:16 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:05:16 INFO - PROCESS | 1657 | 2016-11-21 21:05:16.784 plugin-container[1658:10674] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:05:26 INFO - PROCESS | 1657 | RSS: Main: 243757056 21:05:26 INFO - PROCESS | 1657 | 21:05:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 21:05:30 INFO - PROCESS | 1657 | RSS: Main: 255586304 21:05:30 INFO - PROCESS | 1657 | 21:05:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 21:05:33 INFO - PROCESS | 1657 | RSS: Main: 263188480 21:05:33 INFO - PROCESS | 1657 | 21:05:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 21:05:37 INFO - PROCESS | 1657 | RSS: Main: 265863168 21:05:37 INFO - PROCESS | 1657 | 21:05:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 21:05:40 INFO - PROCESS | 1657 | RSS: Main: 268394496 21:05:40 INFO - PROCESS | 1657 | 21:05:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:05:42 INFO - PROCESS | 1657 | RSS: Main: 268660736 21:05:42 INFO - PROCESS | 1657 | 21:05:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 21:05:46 INFO - PROCESS | 1657 | RSS: Main: 274198528 21:05:46 INFO - PROCESS | 1657 | 21:05:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 21:05:50 INFO - PROCESS | 1657 | RSS: Main: 274165760 21:05:50 INFO - PROCESS | 1657 | 21:05:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 21:05:53 INFO - PROCESS | 1657 | RSS: Main: 274321408 21:05:53 INFO - PROCESS | 1657 | 21:05:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:05:55 INFO - PROCESS | 1657 | RSS: Main: 273285120 21:05:55 INFO - PROCESS | 1657 | 21:05:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:05:58 INFO - PROCESS | 1657 | RSS: Main: 273256448 21:05:58 INFO - PROCESS | 1657 | 21:06:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:06:01 INFO - PROCESS | 1657 | RSS: Main: 273301504 21:06:01 INFO - PROCESS | 1657 | 21:06:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:06:05 INFO - PROCESS | 1657 | RSS: Main: 273707008 21:06:05 INFO - PROCESS | 1657 | 21:06:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:06:08 INFO - PROCESS | 1657 | RSS: Main: 273702912 21:06:08 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 21:06:10 INFO - PROCESS | 1657 | RSS: Main: 275460096 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | __start_tp_report 21:06:10 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 21:06:10 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 21:06:10 INFO - PROCESS | 1657 | |i|pagename|runs| 21:06:10 INFO - PROCESS | 1657 | |0;ai-astar;106;117;118;121;118;117;129;118;117;117 21:06:10 INFO - PROCESS | 1657 | |1;audio-beat-detection;153;148;129;151;153;149;118;121;146;121 21:06:10 INFO - PROCESS | 1657 | |2;audio-dft;197;211;214;199;193;197;193;196;204;194 21:06:10 INFO - PROCESS | 1657 | |3;audio-fft;84;61;61;85;63;61;61;60;81;60 21:06:10 INFO - PROCESS | 1657 | |4;audio-oscillator;91;70;87;72;69;72;89;105;101;125 21:06:10 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;93;85;132;132;90;112;117;87;90;85 21:06:10 INFO - PROCESS | 1657 | |6;imaging-darkroom;95;96;95;95;156;116;97;95;94;94 21:06:10 INFO - PROCESS | 1657 | |7;imaging-desaturate;89;78;78;137;146;126;91;93;92;101 21:06:10 INFO - PROCESS | 1657 | |8;json-parse-financial;58;56;55;57;58;60;57;57;58;58 21:06:10 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;68;75;70;100;112;106;103;78;71;107 21:06:10 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;86;89;113;77;101;109;81;109;102;83 21:06:10 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;161;164;143;166;163;161;169;171;226;167 21:06:10 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;178;175;174;176;175;176;177;176;174;174 21:06:10 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;70;70;69;67;69;68;69;68;70;70 21:06:10 INFO - PROCESS | 1657 | __end_tp_report 21:06:10 INFO - PROCESS | 1657 | __start_cc_report 21:06:10 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,953 21:06:10 INFO - PROCESS | 1657 | __end_cc_report 21:06:10 INFO - PROCESS | 1657 | __startTimestamp1479791170960__endTimestamp 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | ------- Summary: start ------- 21:06:10 INFO - PROCESS | 1657 | Number of tests: 14 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:117.80 Median:118.00 stddev:5.55 (4.7%) stddev-sans-first:3.92 21:06:10 INFO - PROCESS | 1657 | Values: 106.0 117.0 118.0 121.0 118.0 117.0 129.0 118.0 117.0 117.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:148.50 stddev:14.74 (9.9%) stddev-sans-first:14.72 21:06:10 INFO - PROCESS | 1657 | Values: 153.0 148.0 129.0 151.0 153.0 149.0 118.0 121.0 146.0 121.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:199.80 Median:198.00 stddev:7.47 (3.8%) stddev-sans-first:7.85 21:06:10 INFO - PROCESS | 1657 | Values: 197.0 211.0 214.0 199.0 193.0 197.0 193.0 196.0 204.0 194.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:67.70 Median:62.00 stddev:10.86 (17.5%) stddev-sans-first:9.79 21:06:10 INFO - PROCESS | 1657 | Values: 84.0 61.0 61.0 85.0 63.0 61.0 61.0 60.0 81.0 60.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:72.00 stddev:18.36 (25.5%) stddev-sans-first:19.45 21:06:10 INFO - PROCESS | 1657 | Values: 91.0 70.0 87.0 72.0 69.0 72.0 89.0 105.0 101.0 125.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:102.30 Median:86.00 stddev:19.14 (22.3%) stddev-sans-first:20.00 21:06:10 INFO - PROCESS | 1657 | Values: 93.0 85.0 132.0 132.0 90.0 112.0 117.0 87.0 90.0 85.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:103.30 Median:95.00 stddev:19.66 (20.7%) stddev-sans-first:20.63 21:06:10 INFO - PROCESS | 1657 | Values: 95.0 96.0 95.0 95.0 156.0 116.0 97.0 95.0 94.0 94.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:103.10 Median:83.50 stddev:24.38 (29.2%) stddev-sans-first:25.32 21:06:10 INFO - PROCESS | 1657 | Values: 89.0 78.0 78.0 137.0 146.0 126.0 91.0 93.0 92.0 101.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:1.35 (2.3%) stddev-sans-first:1.41 21:06:10 INFO - PROCESS | 1657 | Values: 58.0 56.0 55.0 57.0 58.0 60.0 57.0 57.0 58.0 58.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:89.00 Median:69.00 stddev:17.96 (26.0%) stddev-sans-first:17.36 21:06:10 INFO - PROCESS | 1657 | Values: 68.0 75.0 70.0 100.0 112.0 106.0 103.0 78.0 71.0 107.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:95.00 Median:79.00 stddev:13.26 (16.8%) stddev-sans-first:13.66 21:06:10 INFO - PROCESS | 1657 | Values: 86.0 89.0 113.0 77.0 101.0 109.0 81.0 109.0 102.0 83.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:169.10 Median:166.50 stddev:21.42 (12.9%) stddev-sans-first:22.52 21:06:10 INFO - PROCESS | 1657 | Values: 161.0 164.0 143.0 166.0 163.0 161.0 169.0 171.0 226.0 167.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.50 Median:176.00 stddev:1.35 (0.8%) stddev-sans-first:1.09 21:06:10 INFO - PROCESS | 1657 | Values: 178.0 175.0 174.0 176.0 175.0 176.0 177.0 176.0 174.0 174.0 21:06:10 INFO - PROCESS | 1657 | 21:06:10 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.50 stddev:1.05 (1.5%) stddev-sans-first:1.05 21:06:10 INFO - PROCESS | 1657 | Values: 70.0 70.0 69.0 67.0 69.0 68.0 69.0 68.0 70.0 70.0 21:06:10 INFO - PROCESS | 1657 | -------- Summary: end -------- 21:06:10 INFO - PROCESS | 1657 | 21:06:11 INFO - TEST-INFO | 1657: exit 0 21:06:12 INFO - TEST-OK | kraken | took 62053ms 21:06:12 INFO - SUITE-END | took 1179s 21:06:12 INFO - Completed test suite (00:19:39) 21:06:12 INFO - javascript benchmark 21:06:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6977.593462813771, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2808.9016884409043, 3445.1903802606384, 3460.3425349183653, 3471.0, 3508.842102105406, 5767.0, 5790.420957904207, 5793.391693872146, 5827.096799996002, 5867.295924489056, 5567.137093750465, 5704.201411802351, 5713.257276554053, 5756.798512052069, 5758.337791154018, 4807.1587472192405, 4921.80312787488, 5193.974030129853, 5262.236975638531, 5328.893422131575, 5316.202569614569, 5346.6791992480385, 5361.08861493547, 5365.570754339663, 5379.515843574078, 5356.571474282048, 5357.919631205535, 5361.061814182522, 5363.704996225206, 5375.973120134413, 5336.493033161843, 5366.758495867695, 5369.301990741218, 5370.892582148335, 5381.4349493303225, 5220.477952204749, 5309.920351194735, 5317.760700768436, 5321.946780532223, 5331.520163185293, 5784.710764461802, 5821.09772985188, 5885.146653735184, 5921.585489015771, 5951.464368206839, 6120.540959428061, 6277.340879207663, 6282.465990390822, 6283.465905398047, 6314.71583778731, 6175.4442100210745, 6212.689365531705, 6261.029540421242, 6285.151504546919, 6286.308506064329, 5956.612820166719, 5967.850803729898, 5969.343372229051, 5998.520118390518, 6029.939700602981, 6342.8414289642205, 6380.553361264667, 6394.328595497499, 6403.423691867662, 6415.390537898892, 6105.908411373833, 6106.764041036412, 6106.794380079898, 6241.656708881055, 6276.46650034743, 5271.71005594696, 6108.433136212329, 6211.534134939807, 6243.282022567372, 6257.78097766576, 6235.657038862815, 6242.563020588515, 6274.780382686676, 6289.559730818799, 6311.747530098836, 6176.227971503562, 6177.104319873593, 6227.532935029891, 6229.473778924193, 6300.180976473121, 3764.8117594120185, 3766.9434958475645, 5015.874603134951, 5044.495550445, 5053.772580233898, 4465.910681786411, 4487.977560112178, 4490.685652004328, 4495.617872480811, 4517.480489743656, 4851.587615052689, 5736.139579063174, 5873.765049397976, 5926.4666180044005, 6036.63780173191, 6250.718717657716, 6265.154204182377, 6266.843328916722, 6267.4986001119805, 6274.247090349188, 5440.863978400572, 5677.574181936371, 5918.556108291809, 5947.821565353046, 6028.9397106029255, 5407.378151512547, 5409.377921539073, 5443.101888188405, 5460.235567020621, 5503.807366742145, 6270.0, 6389.137466441971, 6396.744130234924, 6402.775902843565, 6405.327440618855, 5170.612204084784, 5335.0, 5858.765649373977, 6123.387661233841, 6255.311915689362, 5424.779424629584, 5941.9405805943115, 6290.968545157245, 6293.96853015732, 6377.617342959437, 5522.3649280333275, 6042.214512113397, 6122.816315510542, 6251.437370636664, 6260.154879091447, 6250.406211409909, 6252.968735156295, 6266.028765541349, 6278.874422511615, 6302.306746257999, 5312.0969435196885, 5587.798623296011, 6026.758929642765, 6144.508439324933, 6166.722497487534, 4614.446266447913, 6122.459144589048, 6125.571219981412, 6145.569810113428, 6157.138000679998, 5188.9740551297, 5830.125481177857, 6005.699715014319, 6100.8779824404155, 6129.877402452015, 5038.0, 6169.2288463942, 6179.165812615419, 6186.783462578788, 6223.782167624111, 5906.966280900911, 5975.492083172979, 5989.490893274121, 6112.358202388685, 6125.9693701531205], "value": 5685.3381248647365, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.6371189751737, 510.3110800419437, 513.7713717395759, 524.997255242487, 525.8737902903302, 30413.695863041365, 38967.83096507106, 39067.0, 39120.608793912055, 39179.0205244869, 39757.204855902935, 40510.784676459676, 40531.594684053154, 40560.59439405613, 40584.39123413151, 9122.635094596208, 9438.716838494856, 9452.66915657952, 9469.526523673823, 9483.95258023709, 9389.62441502339, 9431.104045115702, 9460.81078378432, 9474.526273686322, 9490.430574165572, 7960.840783184332, 8326.209010144026, 8692.43499172555, 9233.169014788667, 9440.622375104987, 9390.342676012648, 9401.341906066578, 9498.810023799555, 9517.476538790364, 9525.52372381385, 9218.35471516994, 9336.626534938594, 9337.953310233474, 9351.018143094945, 9364.672236471692, 37549.436758448646, 37932.0, 38017.80991095054, 38035.429468557995, 38110.0, 3332.133645252252, 3379.509971054208, 3388.15296175956, 3531.1525233943667, 3716.4239542870896, 23607.583544987356, 28951.42097158066, 29095.272618184503, 29659.55510667342, 30379.69620303813, 3169.2710676544525, 3177.4785074759816, 3180.0777774445382, 3180.6342270638943, 3286.277019055804, 431007.0, 432450.0, 435613.0, 436666.9999999968, 439175.0, 53704.46295537073, 53938.730306349, 53943.46056539463, 53964.222504425095, 54003.0, 32830.67169328324, 33588.83205584005, 56393.0, 56651.71674141603, 56714.86570268654, 15131.848681513044, 15585.922070389803, 15604.92197539005, 15669.764953525706, 15684.372625095097, 11911.0, 11927.22473039267, 12015.978641815369, 12024.639260822189, 12051.578194763317, 14977.625559361104, 18297.35959241447, 18365.0, 18395.63208735818, 18426.539336516427, 6402.6158430494315, 7560.845159129622, 7708.884366734504, 7751.457397982087, 7784.494007889469, 839.202757380491, 924.4545718026396, 952.7856232347805, 967.5645959318335, 987.6691308411763, 28487.860485580346, 28618.42763144767, 29233.0, 29433.55849662257, 29513.0, 795.4670370851543, 902.8194361127801, 1124.7413094988199, 1182.9585964491203, 1199.2504684572143, 11161.441927903572, 12135.15053946233, 12445.75108497825, 14276.928615356857, 15002.774958375632, 525124.0, 590475.0, 602880.0, 612138.0, 615206.0, 53466.0, 53805.0, 53982.92034159374, 54362.45637543574, 54562.727186363816, 1289.335991964139, 1337.9397927093305, 1345.3945724423852, 1350.473315406992, 1361.0880709924375, 24014.63978040296, 26690.46262135403, 27541.86229068842, 28477.430451391272, 29134.0, 46204.53795462002, 46497.83755406142, 46627.0, 46705.29942050804, 46757.0, 539134.0, 540731.0, 541414.0, 541703.0, 543171.0, 24860.62709059402, 24963.37591560153, 25215.873920630278, 25617.359566011, 25657.615135772605, 43962.78018609886, 44208.0, 44859.10281794411, 44974.0, 45121.323180152984, 36008.099797505274, 36612.0, 36927.44608830816, 37286.140401631936, 37447.625523745504, 35317.1170720734, 35349.0, 35967.82016089903, 36192.638073618924, 36375.45436818397, 35118.12204694903, 36235.09412264715, 36325.0, 36675.816620916725, 36826.26347473089, 26004.86997565, 31865.36269274648, 32759.508607370433, 33170.0, 33193.0, 21170.788292116882, 24450.51098978046, 24559.38601534976, 24756.38109047288, 24871.378215544755], "value": 18803.92665328424, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.4873474605046, 373.0710530778362, 378.4777007729333, 384.65189003951434, 395.8278149005183, 16506.83493165068, 18340.0, 19441.222351105975, 19883.80116198836, 20076.297329593468, 19849.404517864452, 19915.701264481046, 20208.89895550524, 20213.59572808543, 20259.489000139623, 4093.8157782899693, 5413.83758487246, 5629.887402251952, 5823.767049318022, 5980.700964951735, 5297.390800057985, 5391.0, 5906.37983011784, 5911.408859114097, 5914.290285165786, 5495.53287970523, 5736.913946290809, 5758.712064396785, 5825.980453420634, 5858.091995740667, 5751.4248575142365, 5779.2775903012125, 5812.128180772876, 5818.91271630928, 5821.767129314822, 4880.658353915227, 5077.695338279678, 5088.0, 5135.972805438927, 5148.176291793295, 24430.755692443203, 24745.752542474525, 24964.00143994238, 25037.0, 25280.98876044956, 1693.8475537201712, 2266.433391652087, 2309.988450057756, 2403.42317843718, 2415.577273977047, 14987.625309367244, 14997.550073497812, 15137.924310378488, 15699.215039247993, 15894.443694470638, 1847.8152184781413, 1849.0939439674596, 1853.7034074548142, 1889.102676228794, 1902.961940761177, 111772.44113779461, 115535.0, 116100.41949790281, 118582.0, 119230.0, 8668.393212475066, 10468.52891619879, 10514.684559463229, 10585.84121238182, 10966.56133754657, 13427.0, 13565.932170339085, 13579.45682172702, 13582.660433489242, 13632.454701812016, 7285.4171666266575, 7811.711067673884, 8209.179082091863, 8412.621432035574, 8421.70524031656, 8197.303229225463, 8206.794830129294, 8233.958830205811, 8343.582820858932, 8373.037100733494, 6530.738770449224, 6644.534882558174, 6697.263301036882, 6710.0, 6715.865682686319, 1677.4129054830712, 1799.379214171109, 1826.9269229230947, 1830.7436958825774, 1844.5757475780645, 370.33894498320905, 375.69193261525834, 806.467731297341, 929.9302552308604, 1066.9253152279266, 11094.114000619915, 11230.775384492428, 11361.431928403546, 11664.358460284597, 11805.763884722257, 371.4205838891338, 414.8589479576958, 416.90828017836026, 418.6567015047693, 432.3881707384079, 5289.994900968803, 5959.106134079922, 6006.969965150146, 6109.877802444015, 6126.785562505291, 556724.0, 558482.0, 559760.0, 562201.0, 588393.0, 91195.0, 108245.0, 111432.44283778529, 112353.0, 129939.70060299276, 884.566562384427, 997.2271489595621, 997.3616885193481, 1000.7448100734266, 1002.138161181398, 17896.0, 18790.624187516445, 18913.0, 18976.335828245945, 19006.809931900505, 51880.740596296775, 52330.47669523256, 54121.0, 54508.45491545034, 55285.72357138189, 72346.0, 73040.63479682567, 73104.0, 74687.25312746804, 76065.0, 6332.683365831782, 7424.503559385537, 7661.637555563183, 7979.521228726295, 8083.353331733565, 9663.758406039906, 9715.611375544899, 10455.006774356729, 11654.533818647498, 12818.743625127632, 10285.074343309137, 10285.382877027403, 10536.10443112344, 10685.252032357661, 11009.944950275198, 9407.153356197976, 10234.846477302995, 10676.733081673021, 10910.854360292054, 11193.552257909592, 9163.49600771964, 9579.616815327308, 9750.122488976027, 11102.056325212447, 11155.043913363128, 7724.92275077242, 7797.92202077972, 7855.0, 7967.641456134372, 7999.360051195774, 9701.417914924845, 10685.51915163804, 10827.729306767078, 10924.836127457935, 10933.507992140214], "value": 8807.359855624512, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1477.9630509237265, 1508.9245537723114, 1683.7979442466906, 1751.3607533250367, 1815.31018213079, 7543.585102819343, 7770.57261850598, 8001.63992620332, 8493.108223636515, 8509.319254459644, 7932.920670793304, 8212.384071194654, 8259.967504061991, 8352.705330673756, 8361.24748772612, 1985.8312043476249, 2293.1744571954123, 2322.535492901418, 2342.601757701186, 2347.565700345433, 2324.674545563614, 2355.49356888269, 2373.5134297468976, 2377.132346693462, 2380.0241900820665, 2309.261036468331, 2321.628539433691, 2335.9649605255936, 2344.367020904355, 2348.729896061958, 2289.496310811627, 2301.746807851135, 2312.7687231276823, 2315.2822624986306, 2316.698829152216, 2319.3042087373888, 2323.0707716913203, 2343.58987177245, 2346.272655476808, 2358.7759162879497, 8217.95891020547, 8330.50016998982, 8377.70678026266, 8390.202930721573, 8427.789305267355, 4986.177280748709, 5156.458571849939, 5160.40655324635, 5163.406208286057, 5177.223416487519, 3239.1902024493875, 3420.452727563603, 3478.4086705259915, 3585.587657419404, 3591.94612080819, 1000.5097502223931, 1031.8710161229847, 1076.8115579773587, 1087.271528076183, 1091.0562363555546, 8477.491350518989, 10404.375737455777, 10736.24846260762, 10798.352098874093, 10868.510917008754, 4419.447569053868, 4500.347449619787, 4583.610393116555, 4604.447466304065, 4685.4143232095985, 9242.537873106317, 9308.0, 9319.301052421095, 9328.34701570897, 9341.159295663423, 5143.202803565453, 5143.819966301162, 5153.845384638467, 5165.354330708661, 5173.430922598511, 4187.20443115807, 4595.2188128018315, 4664.55686709762, 4743.0, 4797.4482934462985, 3719.442083687414, 3735.4210097434943, 3755.7370984030854, 3784.7918364489665, 3804.2581696568905, 1237.7338872142532, 1256.47856139701, 1259.8992080633527, 1275.5981865712272, 1276.9297688627214, 843.6920524008815, 853.180946291567, 872.437277955715, 900.6037343568809, 908.9320048942466, 4678.906421871544, 5759.452851979056, 5787.276590426197, 5894.823155305347, 5998.850028749229, 908.927285817133, 910.7085732565515, 926.5282005193717, 988.5699720621552, 1033.0702367868828, 2900.7244311790346, 3270.869165233364, 3695.3348397288746, 3744.8689295874515, 3803.31540322739, 9795.608175673036, 10007.0, 10086.193104551618, 10093.848592271122, 10117.59529618807, 9677.129058384779, 9814.705558833246, 10067.647632332833, 10078.395296282246, 10114.898851011543, 6580.86838263232, 7620.80947976305, 7736.6518506667335, 7845.607719613997, 7868.44920855546, 5356.901835123713, 5449.100898351728, 5477.4796394342475, 5493.890122197614, 5542.3626282977975, 9297.953510232406, 9372.859407108763, 9376.859347109936, 9409.1531762139, 9431.386959847589, 8604.48373097616, 9181.265498760216, 9457.716268511957, 9505.572249248662, 9595.568199431182, 5644.407337229532, 6169.444749972524, 6648.368405001517, 6679.398854103154, 6684.696484185537, 7847.372210223054, 7873.09459412175, 7918.129005809472, 7934.928784614003, 7985.64064617082, 6949.930500695131, 7087.929120708934, 7110.4667149965, 7137.072180616487, 7199.316064974028, 7003.369696727319, 7010.404115650227, 7024.719011239493, 7034.788956331318, 7137.785866424016, 6511.413972742476, 6543.083968244355, 6600.405963463311, 6610.4050635443045, 6636.13730215069, 4717.929231061606, 4756.738379389167, 4766.165920963748, 4823.011282686971, 4837.95162048375, 4694.671373003994, 4712.52874712526, 4781.330613714153, 4855.708657480563, 4931.950680493293], "value": 4380.998011591138, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.84740426850294, 293.83545214679765, 302.86673863500044, 312.515600818731, 313.1638525137882, 5147.268230532862, 5191.299174611427, 5384.784608615656, 5507.36665283492, 5513.696746678931, 4539.546045395458, 4640.419947506562, 5160.277561141434, 5286.286351342567, 5290.730224746062, 3450.7756995795194, 4101.097758493142, 4122.505299364073, 4150.779333056816, 4197.559256278093, 3651.3610118229203, 4035.455213546163, 4131.111810960643, 4197.265478541259, 4199.769012704315, 3547.5033495310677, 4065.0040740018735, 4082.5098838923727, 4088.6115818997146, 4120.855770048049, 3716.628337166289, 4090.386442033689, 4090.5704900985415, 4093.9181216375505, 4112.670986321087, 3584.444411116281, 3608.657177568127, 3613.33153366628, 3626.9637303627155, 3631.110377957391, 3932.528096628423, 4272.487301523806, 4360.694751367406, 5211.635185537014, 5218.921716174261, 3560.1633616100194, 4702.717836929761, 4715.1512727709, 4731.976340118277, 4745.6440766942615, 5577.470140336662, 5636.943630563724, 5644.463775941279, 5675.120356344773, 5756.0, 3186.012336175793, 3627.165751877083, 3739.607341229186, 3973.22522108189, 3981.880543583697, 5299.417064122943, 5542.86142846428, 5795.855103622359, 5916.852078698024, 5964.910526342108, 3904.902377440587, 3909.237698648736, 3913.7651740895635, 3915.686745060388, 3935.980320098381, 3017.7887547871437, 3764.0213544478083, 3945.349017412152, 3957.2085582883537, 3985.561588225293, 4564.315352697122, 4637.837675681335, 4669.836555720533, 4677.0, 4698.271767875985, 5655.575831812548, 5660.632058916157, 5697.686627235459, 5725.370209276976, 5741.425857414309, 1232.6810312965042, 1279.4178648714812, 1312.8555858855518, 1313.975099422452, 1345.414744586108, 776.8485145396594, 919.8436265834824, 921.0605182713729, 937.5124935033745, 941.8540126280437, 4551.907542189851, 4817.686850354716, 4845.394325709286, 5008.323876276729, 5073.275086470618, 4813.4945830687975, 5194.766235519411, 5251.212318152231, 5286.127788914863, 5554.861128471821, 4114.588541145861, 4525.389072475174, 4681.799554209516, 4861.829835955725, 5016.54851063406, 2360.2919124262653, 2835.2203144135115, 2857.585650080768, 2884.624998750149, 2885.7835662324987, 5374.742544228355, 5465.562754979592, 5612.635178713371, 5730.570207234391, 5782.537397008229, 4816.494268101869, 5001.398992725232, 5327.893442131213, 5504.256925315111, 5595.719274754816, 4844.8304309349005, 5089.51649593288, 5305.973470132624, 5621.381648018633, 5736.598438109292, 4448.977755111204, 4680.274557443602, 5426.6044503951925, 5447.0, 5453.772901097222, 4601.976990115028, 4975.527324904128, 5407.405185429521, 5409.891802163856, 5485.259489968963, 4775.689580177277, 4911.456902365227, 5421.5391691706645, 5572.693501857275, 5713.085906254979, 3405.812680302607, 3703.962960370362, 3831.5210598675167, 3887.5140607424073, 3903.8048097595574, 4763.73799441034, 5188.0, 5227.581793456438, 5773.566982476415, 5810.535157187331, 4496.460424749051, 5323.787048518016, 5631.155326701028, 5645.491905728504, 5668.829935101953, 4950.0, 4982.227754698028, 5240.606954478353, 5261.973690131525, 5597.264847897165, 4469.575390337913, 4643.001754622773, 5175.430702622633, 5309.442508536548, 5631.267935168402, 4652.185867473111, 4893.874408885905, 5711.742971566373, 5760.423957604206, 5763.250777398911, 4968.925466118083, 5413.729313534386, 5616.371252336777, 5623.803166889139, 5642.492175704207], "value": 4002.324255480186, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.7854793726317, 660.8744338575667, 740.4779630360596, 743.565014466537, 810.5055915891307, 14091.718165636681, 14964.176970266632, 15071.246437678099, 15120.773188402156, 15134.848651513495, 13059.934700326485, 13650.18098914066, 13700.383482743275, 13725.93137034316, 13784.379702913368, 822.3339095332784, 832.6128350317101, 838.5513750143674, 838.8028813228887, 842.3345557009985, 650.5836264790538, 677.7390704578723, 802.9638666260032, 827.0530242871909, 830.4145577367972, 862.706679728892, 864.9221570058694, 877.1360210192955, 877.4515927545284, 878.6704985630389, 846.6147902704286, 863.0980625246632, 872.1235158665564, 872.8865247517782, 873.9949058582649, 636.258758546293, 646.8027251688234, 647.5726020826279, 652.9902051469231, 653.6894974886939, 14430.855691443056, 14510.637234069127, 14552.92723536386, 14622.341994610268, 14650.120992740469, 13656.65858353549, 13777.586672399844, 13883.0, 13945.790813137812, 14122.505712300122, 9918.25613079022, 11136.498857551349, 11145.21983461158, 11355.31868087909, 11411.144164187635, 3026.788124831263, 3740.1397678533817, 3798.601146879593, 3858.5176852893387, 3863.8840834775, 86684.56657716734, 87431.56284218602, 89123.10876891213, 89625.10374896233, 89995.65006524908, 9814.46020468881, 10067.144292735053, 10267.640632577824, 10511.0, 10780.191485638608, 19147.425577232705, 19199.368053715312, 19222.519437014187, 19376.03119844002, 19377.512047831242, 6120.785772497941, 7268.200497945222, 7352.411807055422, 7396.260373962561, 7451.813704657427, 4214.768187749642, 4236.872893813191, 4240.1731662326165, 4242.745435273893, 4245.80893859777, 3160.8893688720786, 3251.4797632378704, 3270.8364581770816, 3276.9180770480452, 3293.1108600677685, 606.1332294818452, 609.1137395090133, 616.5899676715024, 618.6504624886925, 618.8298217989999, 569.7578529125105, 585.2771826793906, 615.9630422174683, 618.1438707390207, 623.6476390839161, 8513.829723405497, 8538.701145460003, 8551.230389264996, 8561.0, 8603.91957444056, 523.3876364653364, 598.3119412675371, 611.2267981004034, 647.0779139725872, 649.1885143570537, 3707.925841483155, 4016.4176194451643, 4220.641245494167, 4240.491141063092, 4263.509696384894, 66049.0, 90229.09770902338, 103773.0, 104441.0, 105732.0, 59632.70183649054, 60070.0, 76485.85271220937, 78427.0, 78439.0, 52673.0, 77557.0, 80781.78827317712, 82857.17142828615, 84074.57962710147, 7305.83163871598, 9056.54717264121, 9147.268218542367, 9261.657059726436, 9275.907240927774, 36149.457758133125, 36310.8184459076, 39166.43334266597, 52798.73600631972, 53934.19098713601, 56552.0, 69925.9511107344, 71581.64209178921, 71993.0, 72787.63606181936, 12015.519379224734, 12040.397980100784, 12100.818487722516, 12246.877531224574, 12279.0, 13611.86388136106, 13855.515056973361, 13856.93071534636, 14434.927825361226, 14607.342669579682, 12268.938655306667, 12278.324692142018, 12987.545435909698, 13052.54316098932, 13072.346382681018, 12765.93617031946, 12792.424340904869, 12861.421236044212, 12908.548200812926, 13349.0, 12741.155155593502, 12813.103082784115, 12877.0, 13044.347782611021, 13314.894123245285, 10396.428196449267, 10538.947305263424, 10685.76562188043, 10760.3543787373, 10830.891691083303, 9031.142041506046, 9956.502174891371, 9958.800823983625, 10417.9479102604, 10668.83996740034], "value": 6989.610073481277, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1576.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 118.0, 121.0, 118.0, 117.0, 129.0, 118.0, 117.0, 117.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 148.0, 129.0, 151.0, 153.0, 149.0, 118.0, 121.0, 146.0, 121.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 211.0, 214.0, 199.0, 193.0, 197.0, 193.0, 196.0, 204.0, 194.0], "value": 199.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 61.0, 61.0, 85.0, 63.0, 61.0, 61.0, 60.0, 81.0, 60.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 87.0, 72.0, 69.0, 72.0, 89.0, 105.0, 101.0, 125.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 85.0, 132.0, 132.0, 90.0, 112.0, 117.0, 87.0, 90.0, 85.0], "value": 102.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 156.0, 116.0, 97.0, 95.0, 94.0, 94.0], "value": 103.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 78.0, 78.0, 137.0, 146.0, 126.0, 91.0, 93.0, 92.0, 101.0], "value": 103.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 55.0, 57.0, 58.0, 60.0, 57.0, 57.0, 58.0, 58.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 70.0, 100.0, 112.0, 106.0, 103.0, 78.0, 71.0, 107.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 113.0, 77.0, 101.0, 109.0, 81.0, 109.0, 102.0, 83.0], "value": 95.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 143.0, 166.0, 163.0, 161.0, 169.0, 171.0, 226.0, 167.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 175.0, 174.0, 176.0, 175.0, 176.0, 177.0, 176.0, 174.0, 174.0], "value": 175.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 67.0, 69.0, 68.0, 69.0, 68.0, 70.0, 70.0], "value": 69.0, "unit": "score"}]}]} 21:06:12 INFO - Return code: 0 21:06:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:06:12 ERROR - # TBPL SUCCESS # 21:06:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:06:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:06:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:06:12 INFO - 2016-11-21 21:06:12.276 screenresolution[1662:11060] starting screenresolution argv=screenresolution get 21:06:12 INFO - 2016-11-21 21:06:12.279 screenresolution[1662:11060] Display 0: 1600x1200x32@60 21:06:12 INFO - 2016-11-21 21:06:12.285 screenresolution[1663:11063] starting screenresolution argv=screenresolution list 21:06:12 INFO - Available Modes on Display 0 21:06:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:06:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:06:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:06:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:06:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:06:12 INFO - Intel Iris: 21:06:12 INFO - Chipset Model: Intel Iris 21:06:12 INFO - Type: GPU 21:06:12 INFO - Bus: Built-In 21:06:12 INFO - VRAM (Dynamic, Max): 1536 MB 21:06:12 INFO - Vendor: Intel (0x8086) 21:06:12 INFO - Device ID: 0x0a2e 21:06:12 INFO - Revision ID: 0x0009 21:06:12 INFO - Displays: 21:06:12 INFO - Display: 21:06:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:06:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:06:12 INFO - Display Serial Number: 1600x1200 60 21:06:12 INFO - Main Display: Yes 21:06:12 INFO - Mirror: Off 21:06:12 INFO - Online: Yes 21:06:12 INFO - Rotation: Supported 21:06:12 INFO - Return code: 0 21:06:12 INFO - Running post-action listener: _resource_record_post_action 21:06:12 INFO - [mozharness: 2016-11-22 05:06:12.528285Z] Finished run-tests step (success) 21:06:12 INFO - Running post-run listener: _resource_record_post_run 21:06:12 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38496768; Write bytes: 697939968; Read time: 249; Write time: 2054 21:06:12 INFO - TinderboxPrint: CPU usage
54.5% 21:06:12 INFO - TinderboxPrint: I/O read bytes / time
38,496,768 / 249 21:06:12 INFO - TinderboxPrint: I/O write bytes / time
697,939,968 / 2,054 21:06:12 INFO - TinderboxPrint: CPU idle
2,207.1 (45.5%) 21:06:12 INFO - TinderboxPrint: CPU system
979.7 (20.2%) 21:06:12 INFO - TinderboxPrint: CPU user
1,667.0 (34.3%) 21:06:12 INFO - TinderboxPrint: Swap in / out
422,187,008 / 0 21:06:12 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 367794688; Write bytes: 366282240; Read time: 29043; Write time: 790 21:06:12 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 37554688; Write bytes: 313078272; Read time: 237; Write time: 1211 21:06:13 INFO - Running post-run listener: _upload_blobber_files 21:06:13 INFO - Blob upload gear active. 21:06:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:06:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:06:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:06:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:06:13 INFO - (blobuploader) - INFO - Open directory for files ... 21:06:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:06:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:06:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:06:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:06:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:06:14 INFO - (blobuploader) - INFO - Done attempting. 21:06:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:06:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:06:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:06:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:06:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:06:15 INFO - (blobuploader) - INFO - Done attempting. 21:06:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:06:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:06:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:06:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:06:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:06:17 INFO - (blobuploader) - INFO - Done attempting. 21:06:17 INFO - (blobuploader) - INFO - Iteration through files over. 21:06:17 INFO - Return code: 0 21:06:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:06:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:06:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/43e7795be9d040bcc9de6795dfa309df108bc74900ef0559e538ce3651c1d40652982ca4bdc855d5c1a74a90141561e7a57a62c3e2cfd20abac09995795e6926", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/27764c4e4058d7e6174457b0dd4e2fdef0952b9b5d22e4e6ab82df895f7e82b8de002df632643823ddeb459672dc0d856741c0390e28c221267b70c99599e04f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6776d7d683acd141328f1bce4608d297c056e415ed4d304fe8c5080173239c42d73f66f53e877bc907e7a77aab7eb04d70f11feb1133fcd7741a861105d300d2"} 21:06:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:06:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:06:17 INFO - Contents: 21:06:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/43e7795be9d040bcc9de6795dfa309df108bc74900ef0559e538ce3651c1d40652982ca4bdc855d5c1a74a90141561e7a57a62c3e2cfd20abac09995795e6926", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/27764c4e4058d7e6174457b0dd4e2fdef0952b9b5d22e4e6ab82df895f7e82b8de002df632643823ddeb459672dc0d856741c0390e28c221267b70c99599e04f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6776d7d683acd141328f1bce4608d297c056e415ed4d304fe8c5080173239c42d73f66f53e877bc907e7a77aab7eb04d70f11feb1133fcd7741a861105d300d2"} 21:06:17 INFO - Running post-run listener: copy_logs_to_upload_dir 21:06:17 INFO - Copying logs to upload dir... 21:06:17 INFO - mkdir: /builds/slave/test/build/upload/logs 21:06:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.250956 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-21 21:06:17.684389) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 21:06:17.688334) ========= 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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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/43e7795be9d040bcc9de6795dfa309df108bc74900ef0559e538ce3651c1d40652982ca4bdc855d5c1a74a90141561e7a57a62c3e2cfd20abac09995795e6926", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/27764c4e4058d7e6174457b0dd4e2fdef0952b9b5d22e4e6ab82df895f7e82b8de002df632643823ddeb459672dc0d856741c0390e28c221267b70c99599e04f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6776d7d683acd141328f1bce4608d297c056e415ed4d304fe8c5080173239c42d73f66f53e877bc907e7a77aab7eb04d70f11feb1133fcd7741a861105d300d2"} build_url:https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/TNYWPlUQR_-hqy_ua4pKxQ/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/43e7795be9d040bcc9de6795dfa309df108bc74900ef0559e538ce3651c1d40652982ca4bdc855d5c1a74a90141561e7a57a62c3e2cfd20abac09995795e6926", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/27764c4e4058d7e6174457b0dd4e2fdef0952b9b5d22e4e6ab82df895f7e82b8de002df632643823ddeb459672dc0d856741c0390e28c221267b70c99599e04f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6776d7d683acd141328f1bce4608d297c056e415ed4d304fe8c5080173239c42d73f66f53e877bc907e7a77aab7eb04d70f11feb1133fcd7741a861105d300d2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 21:06:17.730620) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 21:06:17.734976) ========= 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.zXQvSwjDI7/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.NFk1TvGXiF/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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.004689 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 21:06:17.782656) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 21:06:17.782974) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 21:06:17.783286) ========= ========= Total master_lag: 0.23 =========