builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0086 starttime: 1476121723.14 results: success (0) buildid: 20161010084223 builduid: 29e0b39395d6422d938883f4e6babefc revision: 090c736d36d255900ca32c37fe33dc9446c994da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:43.147738) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:43.148263) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:43.148609) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:43.340357) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:43.340740) ========= 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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-10 10:48:43-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.50M=0.001s 2016-10-10 10:48:43 (9.50 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090816 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:43.465390) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:43.465741) ========= 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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.029152 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:43.518213) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 10:48:43.518669) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 090c736d36d255900ca32c37fe33dc9446c994da --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 090c736d36d255900ca32c37fe33dc9446c994da --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-10 10:48:43,592 truncating revision to first 12 chars 2016-10-10 10:48:43,592 Setting DEBUG logging. 2016-10-10 10:48:43,592 attempt 1/10 2016-10-10 10:48:43,592 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/090c736d36d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-10 10:48:44,665 unpacking tar archive at: larch-090c736d36d2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.321096 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 10:48:44.861787) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:44.862113) ========= script_repo_revision: 090c736d36d255900ca32c37fe33dc9446c994da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:44.862565) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:44.862879) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 10:48:44.881167) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-10 10:48:44.881558) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:48:45 INFO - MultiFileLogger online at 20161010 10:48:45 in /builds/slave/test 10:48:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 10:48:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:48:45 INFO - {'append_to_log': False, 10:48:45 INFO - 'base_work_dir': '/builds/slave/test', 10:48:45 INFO - 'blob_upload_branch': 'Larch', 10:48:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:48:45 INFO - 'branch': 'Larch', 10:48:45 INFO - 'buildbot_json_path': 'buildprops.json', 10:48:45 INFO - 'config_files': ('talos/mac_config.py',), 10:48:45 INFO - 'default_actions': ('clobber', 10:48:45 INFO - 'read-buildbot-config', 10:48:45 INFO - 'download-and-extract', 10:48:45 INFO - 'populate-webroot', 10:48:45 INFO - 'create-virtualenv', 10:48:45 INFO - 'install', 10:48:45 INFO - 'run-tests'), 10:48:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:48:45 INFO - 'download_minidump_stackwalk': True, 10:48:45 INFO - 'download_symbols': 'ondemand', 10:48:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:48:45 INFO - 'tooltool.py': '/tools/tooltool.py', 10:48:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:48:45 INFO - '/tools/misc-python/virtualenv.py')}, 10:48:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:48:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:48:45 INFO - 'installer_path': 'installer.exe', 10:48:45 INFO - 'log_level': 'info', 10:48:45 INFO - 'log_name': 'talos', 10:48:45 INFO - 'log_to_console': True, 10:48:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:48:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:48:45 INFO - 'opt_config_files': (), 10:48:45 INFO - 'pip_index': False, 10:48:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:48:45 INFO - 'cmd': ('bash', 10:48:45 INFO - '-c', 10:48:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:48:45 INFO - 'enabled': True, 10:48:45 INFO - 'halt_on_failure': False, 10:48:45 INFO - 'name': 'check_screen_resolution'},), 10:48:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:48:45 INFO - 'cmd': ('bash', 10:48:45 INFO - '-c', 10:48:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:48:45 INFO - 'enabled': True, 10:48:45 INFO - 'halt_on_failure': False, 10:48:45 INFO - 'name': 'check_screen_resolution'},), 10:48:45 INFO - 'run_cmd_checks_enabled': True, 10:48:45 INFO - 'sps_profile': False, 10:48:45 INFO - 'sps_profile_interval': 0, 10:48:45 INFO - 'suite': 'dromaeojs-e10s', 10:48:45 INFO - 'system_bits': '32', 10:48:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:48:45 INFO - 'title': 't-yosemite-r7-0086', 10:48:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:48:45 INFO - 'use_talos_json': True, 10:48:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:48:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:48:45 INFO - 'work_dir': 'build'} 10:48:45 INFO - [mozharness: 2016-10-10 17:48:45.089444Z] Running clobber step. 10:48:45 INFO - Running pre-action listener: _resource_record_pre_action 10:48:45 INFO - Running main action method: clobber 10:48:45 INFO - rmtree: /builds/slave/test/build 10:48:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:48:47 INFO - Running post-action listener: _resource_record_post_action 10:48:47 INFO - [mozharness: 2016-10-10 17:48:47.363977Z] Finished clobber step (success) 10:48:47 INFO - [mozharness: 2016-10-10 17:48:47.364114Z] Running read-buildbot-config step. 10:48:47 INFO - Running pre-action listener: _resource_record_pre_action 10:48:47 INFO - Running main action method: read_buildbot_config 10:48:47 INFO - Using buildbot properties: 10:48:47 INFO - { 10:48:47 INFO - "project": "", 10:48:47 INFO - "product": "firefox", 10:48:47 INFO - "script_repo_revision": "production", 10:48:47 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 10:48:47 INFO - "repository": "", 10:48:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 10:48:47 INFO - "buildid": "20161010084223", 10:48:47 INFO - "pgo_build": "False", 10:48:47 INFO - "basedir": "/builds/slave/test", 10:48:47 INFO - "buildnumber": 0, 10:48:47 INFO - "slavename": "t-yosemite-r7-0086", 10:48:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:48:47 INFO - "platform": "yosemite_r7", 10:48:47 INFO - "scheduler": "tests-larch-macosx64-talos", 10:48:47 INFO - "branch": "larch", 10:48:47 INFO - "repo_path": "projects/larch", 10:48:47 INFO - "revision": "090c736d36d255900ca32c37fe33dc9446c994da", 10:48:47 INFO - "stage_platform": "macosx64", 10:48:47 INFO - "builduid": "29e0b39395d6422d938883f4e6babefc", 10:48:47 INFO - "slavebuilddir": "test" 10:48:47 INFO - } 10:48:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 10:48:47 INFO - Running post-action listener: _resource_record_post_action 10:48:47 INFO - [mozharness: 2016-10-10 17:48:47.367472Z] Finished read-buildbot-config step (success) 10:48:47 INFO - [mozharness: 2016-10-10 17:48:47.367606Z] Running download-and-extract step. 10:48:47 INFO - Running pre-action listener: _resource_record_pre_action 10:48:47 INFO - Running main action method: download_and_extract 10:48:47 INFO - mkdir: /builds/slave/test/build/tests 10:48:47 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')]} 10:48:47 INFO - trying https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 10:48:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:48:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 10:48:48 INFO - Downloaded 1199 bytes. 10:48:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:48:48 INFO - Using the following test package requirements: 10:48:48 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:48:48 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:48:48 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 10:48:48 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:48:48 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 10:48:48 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:48:48 INFO - u'jsshell-mac.zip'], 10:48:48 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:48:48 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 10:48:48 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:48:48 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:48:48 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 10:48:48 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:48:48 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 10:48:48 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:48:48 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 10:48:48 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:48:48 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 10:48:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:48:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:48:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:48:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:48:49 INFO - Http code: 200 10:48:49 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 10:48:49 INFO - x-amz-cf-id: F_QNog4svqv7BcazJBs04KKsME_UQarq0SQcf1Y92ffQYf2PSdkXPw== 10:48:49 INFO - x-amz-version-id: XvVhLxf4EwFcSOGawc77_AeJIaAQDiQQ 10:48:49 INFO - Expected file size: 16476610 10:48:49 INFO - Obtained file size: 16476610 10:48:56 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:48:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:48:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:48:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:48:56 INFO - Http code: 200 10:48:56 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 10:48:56 INFO - x-amz-cf-id: gt8U3R9k9J0M_8nNyb622JTEm54hElssfcacuGtBuZoxXyva1IyyhQ== 10:48:56 INFO - x-amz-version-id: XvVhLxf4EwFcSOGawc77_AeJIaAQDiQQ 10:48:57 INFO - Expected file size: 16476610 10:48:57 INFO - Obtained file size: 16476610 10:49:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 10:49:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:49:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 10:49:04 INFO - Http code: 200 10:49:04 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 10:49:04 INFO - x-amz-cf-id: shT-tp6JNeGbRHEYRVXSxDTwZVWC2cizXPSiCWZWopAe-ezH5LsX1g== 10:49:04 INFO - x-amz-version-id: eoY6UUsDG.esQ7.8U6mxfX2pEWWg.8e8 10:49:04 INFO - Expected file size: 14408522 10:49:04 INFO - Obtained file size: 14408522 10:49:04 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')]} 10:49:04 INFO - trying https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:49:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 10:49:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 10:49:07 INFO - Downloaded 111658451 bytes. 10:49:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:49:07 INFO - mkdir: /builds/slave/test/properties 10:49:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:49:07 INFO - Writing to file /builds/slave/test/properties/build_url 10:49:07 INFO - Contents: 10:49:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:49:08 INFO - Running post-action listener: _resource_record_post_action 10:49:08 INFO - Running post-action listener: set_extra_try_arguments 10:49:08 INFO - [mozharness: 2016-10-10 17:49:08.160083Z] Finished download-and-extract step (success) 10:49:08 INFO - [mozharness: 2016-10-10 17:49:08.160203Z] Running populate-webroot step. 10:49:08 INFO - Running pre-action listener: _resource_record_pre_action 10:49:08 INFO - Running main action method: populate_webroot 10:49:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:49:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:49:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:49:08 INFO - '--tppagecycles', 10:49:08 INFO - '7'], 10:49:08 INFO - 'tests': ['tsvgm']}}, 10:49:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:49:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:49:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:49:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:49:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:49:08 INFO - 'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:49:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:49:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:49:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:49:08 INFO - 'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['damp', 'tps']}, 10:49:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:49:08 INFO - 'tests': ['damp', 'tps']}, 10:49:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['dromaeo_dom']}, 10:49:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:49:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['basic_compositor_video']}, 10:49:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:49:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['a11yr', 10:49:08 INFO - 'ts_paint', 10:49:08 INFO - 'tpaint', 10:49:08 INFO - 'sessionrestore', 10:49:08 INFO - 'sessionrestore_no_auto_restore', 10:49:08 INFO - 'tabpaint']}, 10:49:08 INFO - 'other-e10s': {'tests': ['a11yr', 10:49:08 INFO - 'ts_paint', 10:49:08 INFO - 'tpaint', 10:49:08 INFO - 'sessionrestore', 10:49:08 INFO - 'sessionrestore_no_auto_restore', 10:49:08 INFO - 'tabpaint']}, 10:49:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:49:08 INFO - 'ts_paint', 10:49:08 INFO - 'tpaint', 10:49:08 INFO - 'sessionrestore', 10:49:08 INFO - 'sessionrestore_no_auto_restore', 10:49:08 INFO - 'tabpaint']}, 10:49:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:49:08 INFO - 'ts_paint', 10:49:08 INFO - 'tpaint', 10:49:08 INFO - 'sessionrestore', 10:49:08 INFO - 'sessionrestore_no_auto_restore', 10:49:08 INFO - 'tabpaint']}, 10:49:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['a11yr', 10:49:08 INFO - 'ts_paint', 10:49:08 INFO - 'tpaint', 10:49:08 INFO - 'sessionrestore', 10:49:08 INFO - 'sessionrestore_no_auto_restore', 10:49:08 INFO - 'tabpaint']}, 10:49:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['a11yr', 10:49:08 INFO - 'ts_paint', 10:49:08 INFO - 'tpaint', 10:49:08 INFO - 'sessionrestore', 10:49:08 INFO - 'sessionrestore_no_auto_restore', 10:49:08 INFO - 'tabpaint']}, 10:49:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['tsvgx', 10:49:08 INFO - 'tsvgr_opacity', 10:49:08 INFO - 'tart', 10:49:08 INFO - 'tscrollx', 10:49:08 INFO - 'cart']}, 10:49:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:49:08 INFO - 'tsvgr_opacity', 10:49:08 INFO - 'tart', 10:49:08 INFO - 'tscrollx', 10:49:08 INFO - 'cart']}, 10:49:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:49:08 INFO - 'talos_options': ['--disable-e10s'], 10:49:08 INFO - 'tests': ['tp5o']}, 10:49:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:49:08 INFO - 'tests': ['tp5o']}, 10:49:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:49:08 INFO - 'talos_options': ['--disable-e10s', 10:49:08 INFO - '--xperf_path', 10:49:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:49:08 INFO - 'tests': ['tp5n']}, 10:49:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:49:08 INFO - 'talos_options': ['--xperf_path', 10:49:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:49:08 INFO - 'tests': ['tp5n']}}, 10:49:08 INFO - 'talos.zip': {'path': '', 10:49:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:49:08 INFO - Running post-action listener: _resource_record_post_action 10:49:08 INFO - [mozharness: 2016-10-10 17:49:08.179392Z] Finished populate-webroot step (success) 10:49:08 INFO - [mozharness: 2016-10-10 17:49:08.179517Z] Running create-virtualenv step. 10:49:08 INFO - Running pre-action listener: _resource_record_pre_action 10:49:08 INFO - Running main action method: create_virtualenv 10:49:08 INFO - Creating virtualenv /builds/slave/test/build/venv 10:49:08 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 10:49:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:49:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:49:08 INFO - Using real prefix '/tools/python27' 10:49:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:49:09 INFO - Installing distribute.............................................................................................................................................................................................done. 10:49:11 INFO - Installing pip.................done. 10:49:11 INFO - Return code: 0 10:49:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:49:12 INFO - Reading from file tmpfile_stdout 10:49:12 INFO - Output received: 10:49:12 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) 10:49:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:49:12 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')]} 10:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:12 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')]} 10:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:12 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 0x109ecd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee51559fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, '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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:49:12 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 10:49:12 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 10:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:12 INFO - 'HOME': '/Users/cltbld', 10:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:12 INFO - 'LOGNAME': 'cltbld', 10:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:12 INFO - 'MOZ_NO_REMOTE': '1', 10:49:12 INFO - 'NO_EM_RESTART': '1', 10:49:12 INFO - 'PAGER': '/bin/cat', 10:49:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:12 INFO - 'PWD': '/builds/slave/test', 10:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:12 INFO - 'SHELL': '/bin/bash', 10:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:49:12 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:12 INFO - 'USER': 'cltbld', 10:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:12 INFO - 'XPC_FLAGS': '0x0', 10:49:12 INFO - 'XPC_SERVICE_NAME': '0', 10:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:12 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 10:49:12 INFO - Cleaning up... 10:49:12 INFO - Return code: 0 10:49:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:49:12 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')]} 10:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:12 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')]} 10:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:12 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 0x109ecd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee51559fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, '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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:49:12 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 10:49:12 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 10:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:12 INFO - 'HOME': '/Users/cltbld', 10:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:12 INFO - 'LOGNAME': 'cltbld', 10:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:12 INFO - 'MOZ_NO_REMOTE': '1', 10:49:12 INFO - 'NO_EM_RESTART': '1', 10:49:12 INFO - 'PAGER': '/bin/cat', 10:49:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:12 INFO - 'PWD': '/builds/slave/test', 10:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:12 INFO - 'SHELL': '/bin/bash', 10:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:49:12 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:12 INFO - 'USER': 'cltbld', 10:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:12 INFO - 'XPC_FLAGS': '0x0', 10:49:12 INFO - 'XPC_SERVICE_NAME': '0', 10:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:12 INFO - Downloading/unpacking psutil>=3.1.1 10:49:12 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 10:49:12 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 10:49:12 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 10:49:12 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 10:49:12 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 10:49:12 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 10:49:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:49:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:49:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:49:16 INFO - Installing collected packages: psutil 10:49:16 INFO - Running setup.py install for psutil 10:49:16 INFO - building 'psutil._psutil_osx' extension 10:49:16 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 10:49:16 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 10:49:16 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 10:49:16 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 10:49:17 INFO - building 'psutil._psutil_posix' extension 10:49:17 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 10:49:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:49:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:49:17 INFO - ^ 10:49:17 INFO - 1 warning generated. 10:49:17 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 10:49:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:49:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:49:17 INFO - Successfully installed psutil 10:49:17 INFO - Cleaning up... 10:49:17 INFO - Return code: 0 10:49:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ecd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee51559fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, '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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:49:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:49:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:49:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:17 INFO - 'HOME': '/Users/cltbld', 10:49:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:17 INFO - 'LOGNAME': 'cltbld', 10:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:17 INFO - 'MOZ_NO_REMOTE': '1', 10:49:17 INFO - 'NO_EM_RESTART': '1', 10:49:17 INFO - 'PAGER': '/bin/cat', 10:49:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:17 INFO - 'PWD': '/builds/slave/test', 10:49:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:17 INFO - 'SHELL': '/bin/bash', 10:49:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:49:17 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:49:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:17 INFO - 'USER': 'cltbld', 10:49:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:17 INFO - 'XPC_FLAGS': '0x0', 10:49:17 INFO - 'XPC_SERVICE_NAME': '0', 10:49:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:49:20 INFO - Installing collected packages: mozsystemmonitor 10:49:20 INFO - Running setup.py install for mozsystemmonitor 10:49:20 INFO - Successfully installed mozsystemmonitor 10:49:20 INFO - Cleaning up... 10:49:20 INFO - Return code: 0 10:49:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:20 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 0x109ecd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee51559fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, '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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:49:20 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 10:49:20 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 10:49:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:49:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:20 INFO - 'HOME': '/Users/cltbld', 10:49:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:20 INFO - 'LOGNAME': 'cltbld', 10:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:20 INFO - 'MOZ_NO_REMOTE': '1', 10:49:20 INFO - 'NO_EM_RESTART': '1', 10:49:20 INFO - 'PAGER': '/bin/cat', 10:49:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:20 INFO - 'PWD': '/builds/slave/test', 10:49:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:20 INFO - 'SHELL': '/bin/bash', 10:49:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:49:20 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:49:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:20 INFO - 'USER': 'cltbld', 10:49:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:20 INFO - 'XPC_FLAGS': '0x0', 10:49:20 INFO - 'XPC_SERVICE_NAME': '0', 10:49:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:20 INFO - Downloading/unpacking jsonschema==2.5.1 10:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:49:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:49:23 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 10:49:23 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 10:49:23 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 10:49:23 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 10:49:23 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 10:49:23 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 10:49:23 INFO - Downloading functools32-3.2.3-2.tar.gz 10:49:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:49:23 INFO - warning: no files found matching '*.txt' 10:49:23 INFO - no previously-included directories found matching 'build' 10:49:23 INFO - no previously-included directories found matching 'dist' 10:49:23 INFO - no previously-included directories found matching '.git*' 10:49:23 INFO - Installing collected packages: jsonschema, functools32 10:49:23 INFO - Running setup.py install for functools32 10:49:23 INFO - warning: no files found matching '*.txt' 10:49:23 INFO - no previously-included directories found matching 'build' 10:49:23 INFO - no previously-included directories found matching 'dist' 10:49:23 INFO - no previously-included directories found matching '.git*' 10:49:23 INFO - Successfully installed jsonschema functools32 10:49:23 INFO - Cleaning up... 10:49:24 INFO - Return code: 0 10:49:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:24 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 0x109ecd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee51559fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, '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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:49:24 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 10:49:24 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 10:49:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:49:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:24 INFO - 'HOME': '/Users/cltbld', 10:49:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:24 INFO - 'LOGNAME': 'cltbld', 10:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:24 INFO - 'MOZ_NO_REMOTE': '1', 10:49:24 INFO - 'NO_EM_RESTART': '1', 10:49:24 INFO - 'PAGER': '/bin/cat', 10:49:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:24 INFO - 'PWD': '/builds/slave/test', 10:49:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:24 INFO - 'SHELL': '/bin/bash', 10:49:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:49:24 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:49:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:24 INFO - 'USER': 'cltbld', 10:49:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:24 INFO - 'XPC_FLAGS': '0x0', 10:49:24 INFO - 'XPC_SERVICE_NAME': '0', 10:49:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:49:24 INFO - Cleaning up... 10:49:24 INFO - Return code: 0 10:49:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:24 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 0x109ecd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee51559fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, '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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:49:24 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 10:49:24 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 10:49:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:49:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:24 INFO - 'HOME': '/Users/cltbld', 10:49:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:24 INFO - 'LOGNAME': 'cltbld', 10:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:24 INFO - 'MOZ_NO_REMOTE': '1', 10:49:24 INFO - 'NO_EM_RESTART': '1', 10:49:24 INFO - 'PAGER': '/bin/cat', 10:49:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:24 INFO - 'PWD': '/builds/slave/test', 10:49:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:24 INFO - 'SHELL': '/bin/bash', 10:49:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:49:24 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:49:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:24 INFO - 'USER': 'cltbld', 10:49:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:24 INFO - 'XPC_FLAGS': '0x0', 10:49:24 INFO - 'XPC_SERVICE_NAME': '0', 10:49:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:24 INFO - Downloading/unpacking blobuploader==1.2.4 10:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:27 INFO - Downloading blobuploader-1.2.4.tar.gz 10:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:49:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:49:27 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 10:49:27 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 10:49:27 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 10:49:27 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 10:49:27 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 10:49:27 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 10:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:49:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:49:27 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 10:49:27 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 10:49:27 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 10:49:27 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 10:49:27 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 10:49:27 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 10:49:28 INFO - Downloading docopt-0.6.1.tar.gz 10:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:49:28 INFO - Installing collected packages: blobuploader, requests, docopt 10:49:28 INFO - Running setup.py install for blobuploader 10:49:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:49:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:49:28 INFO - Running setup.py install for requests 10:49:29 INFO - Running setup.py install for docopt 10:49:29 INFO - Successfully installed blobuploader requests docopt 10:49:29 INFO - Cleaning up... 10:49:29 INFO - Return code: 0 10:49:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:49:29 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')]} 10:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:29 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')]} 10:49:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:30 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 0x109ecd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee51559fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, '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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:49:30 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 10:49:30 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 10:49:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:49:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:30 INFO - 'HOME': '/Users/cltbld', 10:49:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:30 INFO - 'LOGNAME': 'cltbld', 10:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:30 INFO - 'MOZ_NO_REMOTE': '1', 10:49:30 INFO - 'NO_EM_RESTART': '1', 10:49:30 INFO - 'PAGER': '/bin/cat', 10:49:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:30 INFO - 'PWD': '/builds/slave/test', 10:49:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:30 INFO - 'SHELL': '/bin/bash', 10:49:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:49:30 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:49:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:30 INFO - 'USER': 'cltbld', 10:49:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:30 INFO - 'XPC_FLAGS': '0x0', 10:49:30 INFO - 'XPC_SERVICE_NAME': '0', 10:49:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:49:30 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ay9w78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:49:30 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-o2mc8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:49:30 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-CuoI1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:49:30 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ReRAS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:49:30 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-FfPSwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:49:30 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-2EwTtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:49:31 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-W0nG_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:49:31 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-UjwU5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:49:31 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-FQ1HzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:49:31 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-xngpkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:49:31 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-jwXOLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:49:31 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-smrdqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:49:31 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-x85fVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:49:31 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-lMpysr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:49:31 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-P4xb85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:49:31 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-waMlUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:49:32 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Ak61WD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:49:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:49:32 INFO - Running setup.py install for manifestparser 10:49:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:49:32 INFO - Running setup.py install for mozcrash 10:49:32 INFO - Running setup.py install for mozdebug 10:49:32 INFO - Running setup.py install for mozdevice 10:49:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:49:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:49:32 INFO - Running setup.py install for mozfile 10:49:32 INFO - Running setup.py install for mozhttpd 10:49:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:49:33 INFO - Running setup.py install for mozinfo 10:49:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:49:33 INFO - Running setup.py install for mozInstall 10:49:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:49:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:49:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:49:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:49:33 INFO - Running setup.py install for mozleak 10:49:33 INFO - Running setup.py install for mozlog 10:49:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:49:33 INFO - Running setup.py install for moznetwork 10:49:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:49:33 INFO - Running setup.py install for mozprocess 10:49:33 INFO - Running setup.py install for mozprofile 10:49:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:49:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:49:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:49:34 INFO - Running setup.py install for mozrunner 10:49:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:49:34 INFO - Running setup.py install for mozscreenshot 10:49:34 INFO - Running setup.py install for moztest 10:49:34 INFO - Running setup.py install for mozversion 10:49:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:49:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:49:34 INFO - Cleaning up... 10:49:34 INFO - Return code: 0 10:49:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:49:34 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')]} 10:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:34 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')]} 10:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:35 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 0x109ecd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee51559fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, '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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:49:35 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 10:49:35 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 10:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:35 INFO - 'HOME': '/Users/cltbld', 10:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:35 INFO - 'LOGNAME': 'cltbld', 10:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:35 INFO - 'MOZ_NO_REMOTE': '1', 10:49:35 INFO - 'NO_EM_RESTART': '1', 10:49:35 INFO - 'PAGER': '/bin/cat', 10:49:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:35 INFO - 'PWD': '/builds/slave/test', 10:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:35 INFO - 'SHELL': '/bin/bash', 10:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:49:35 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:35 INFO - 'USER': 'cltbld', 10:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:35 INFO - 'XPC_FLAGS': '0x0', 10:49:35 INFO - 'XPC_SERVICE_NAME': '0', 10:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:49:35 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-bTe36b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:49:35 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)) 10:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:49:35 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-DFEuJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:49:35 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-nbYtOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:49:35 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)) 10:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:49:35 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-dytjs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:49:35 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-CVf_99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:49:35 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)) 10:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:49:35 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-DuI40M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:49:35 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)) 10:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:49:36 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-FbaKGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:49:36 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)) 10:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:49:36 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-rRkZOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:49:36 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)) 10:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:49:36 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-c64fJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:49:36 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)) 10:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:49:36 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-4AxrcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:49:36 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)) 10:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:49:36 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-oh_fbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:49:36 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)) 10:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:49:36 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-dhFGcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:49:36 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)) 10:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:49:36 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-J1FQcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:49:36 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)) 10:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:49:36 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-lFXc3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:49:36 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-N20O4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:49:36 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)) 10:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:49:36 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-D_Vk5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:49:37 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)) 10:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:49:37 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ewnNDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:49:37 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)) 10:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:49:37 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)) 10:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:49:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:49:37 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 10:49:37 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 10:49:37 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 10:49:37 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 10:49:37 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 10:49:37 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 10:49:39 INFO - Downloading blessings-1.6.tar.gz 10:49:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:49:40 INFO - Installing collected packages: blessings 10:49:40 INFO - Running setup.py install for blessings 10:49:40 INFO - Successfully installed blessings 10:49:40 INFO - Cleaning up... 10:49:40 INFO - Return code: 0 10:49:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:49:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:49:40 INFO - Reading from file tmpfile_stdout 10:49:40 INFO - Current package versions: 10:49:40 INFO - blessings == 1.6 10:49:40 INFO - blobuploader == 1.2.4 10:49:40 INFO - docopt == 0.6.1 10:49:40 INFO - functools32 == 3.2.3-2 10:49:40 INFO - jsonschema == 2.5.1 10:49:40 INFO - manifestparser == 1.1 10:49:40 INFO - mozInstall == 1.12 10:49:40 INFO - mozcrash == 0.17 10:49:40 INFO - mozdebug == 0.1 10:49:40 INFO - mozdevice == 0.48 10:49:40 INFO - mozfile == 1.2 10:49:40 INFO - mozhttpd == 0.7 10:49:40 INFO - mozinfo == 0.9 10:49:40 INFO - mozleak == 0.1 10:49:40 INFO - mozlog == 3.4 10:49:40 INFO - moznetwork == 0.27 10:49:40 INFO - mozprocess == 0.23 10:49:40 INFO - mozprofile == 0.28 10:49:40 INFO - mozrunner == 6.12 10:49:40 INFO - mozscreenshot == 0.1 10:49:40 INFO - mozsystemmonitor == 0.3 10:49:40 INFO - moztest == 0.7 10:49:40 INFO - mozversion == 1.4 10:49:40 INFO - psutil == 3.1.1 10:49:40 INFO - requests == 1.2.3 10:49:40 INFO - wsgiref == 0.1.2 10:49:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:40 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 0x109ecd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee51559fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, '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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:49:40 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 10:49:40 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 10:49:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:49:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:40 INFO - 'HOME': '/Users/cltbld', 10:49:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:40 INFO - 'LOGNAME': 'cltbld', 10:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:40 INFO - 'MOZ_NO_REMOTE': '1', 10:49:40 INFO - 'NO_EM_RESTART': '1', 10:49:40 INFO - 'PAGER': '/bin/cat', 10:49:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:40 INFO - 'PWD': '/builds/slave/test', 10:49:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:40 INFO - 'SHELL': '/bin/bash', 10:49:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:49:40 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:49:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:40 INFO - 'USER': 'cltbld', 10:49:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:40 INFO - 'XPC_FLAGS': '0x0', 10:49:40 INFO - 'XPC_SERVICE_NAME': '0', 10:49:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:40 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)) 10:49:40 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)) 10:49:40 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)) 10:49:40 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)) 10:49:40 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)) 10:49:40 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)) 10:49:40 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)) 10:49:40 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)) 10:49:40 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)) 10:49:40 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)) 10:49:40 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)) 10:49:40 INFO - Cleaning up... 10:49:40 INFO - Return code: 0 10:49:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:40 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 0x109ecd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee51559fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, '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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:49:40 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 10:49:40 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 10:49:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:49:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:40 INFO - 'HOME': '/Users/cltbld', 10:49:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:40 INFO - 'LOGNAME': 'cltbld', 10:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:40 INFO - 'MOZ_NO_REMOTE': '1', 10:49:40 INFO - 'NO_EM_RESTART': '1', 10:49:40 INFO - 'PAGER': '/bin/cat', 10:49:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:40 INFO - 'PWD': '/builds/slave/test', 10:49:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:40 INFO - 'SHELL': '/bin/bash', 10:49:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:49:40 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:49:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:40 INFO - 'USER': 'cltbld', 10:49:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:40 INFO - 'XPC_FLAGS': '0x0', 10:49:40 INFO - 'XPC_SERVICE_NAME': '0', 10:49:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:49:41 INFO - Cleaning up... 10:49:41 INFO - Return code: 0 10:49:41 INFO - Running post-action listener: _resource_record_post_action 10:49:41 INFO - Running post-action listener: _start_resource_monitoring 10:49:41 INFO - Starting resource monitoring. 10:49:41 INFO - [mozharness: 2016-10-10 17:49:41.252837Z] Finished create-virtualenv step (success) 10:49:41 INFO - [mozharness: 2016-10-10 17:49:41.253896Z] Running install step. 10:49:41 INFO - Running pre-action listener: _resource_record_pre_action 10:49:41 INFO - Running main action method: install 10:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:49:41 INFO - Reading from file tmpfile_stdout 10:49:41 INFO - Detecting whether we're running mozinstall >=1.0... 10:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:49:41 INFO - Reading from file tmpfile_stdout 10:49:41 INFO - Output received: 10:49:41 INFO - Usage: mozinstall [options] installer 10:49:41 INFO - Options: 10:49:41 INFO - -h, --help show this help message and exit 10:49:41 INFO - -d DEST, --destination=DEST 10:49:41 INFO - Directory to install application into. [default: 10:49:41 INFO - "/builds/slave/test"] 10:49:41 INFO - --app=APP Application being installed. [default: firefox] 10:49:41 INFO - mkdir: /builds/slave/test/build/application 10:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:50:15 INFO - Reading from file tmpfile_stdout 10:50:15 INFO - Output received: 10:50:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:50:15 INFO - Running post-action listener: _resource_record_post_action 10:50:15 INFO - [mozharness: 2016-10-10 17:50:15.902041Z] Finished install step (success) 10:50:15 INFO - [mozharness: 2016-10-10 17:50:15.902196Z] Running run-tests step. 10:50:15 INFO - Running pre-action listener: _resource_record_pre_action 10:50:15 INFO - Running main action method: run_tests 10:50:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:50:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:50:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:50:15 INFO - 2016-10-10 10:50:15.916 screenresolution[1636:7675] starting screenresolution argv=screenresolution get 10:50:15 INFO - 2016-10-10 10:50:15.919 screenresolution[1636:7675] Display 0: 1600x1200x32@60 10:50:15 INFO - 2016-10-10 10:50:15.925 screenresolution[1637:7677] starting screenresolution argv=screenresolution list 10:50:15 INFO - Available Modes on Display 0 10:50:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:50:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:50:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:50:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:50:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:50:16 INFO - Intel Iris: 10:50:16 INFO - Chipset Model: Intel Iris 10:50:16 INFO - Type: GPU 10:50:16 INFO - Bus: Built-In 10:50:16 INFO - VRAM (Dynamic, Max): 1536 MB 10:50:16 INFO - Vendor: Intel (0x8086) 10:50:16 INFO - Device ID: 0x0a2e 10:50:16 INFO - Revision ID: 0x0009 10:50:16 INFO - Displays: 10:50:16 INFO - Display: 10:50:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:50:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:50:16 INFO - Display Serial Number: 1600x1200 60 10:50:16 INFO - Main Display: Yes 10:50:16 INFO - Mirror: Off 10:50:16 INFO - Online: Yes 10:50:16 INFO - Rotation: Supported 10:50:16 INFO - Return code: 0 10:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:50:16 INFO - Python 2.7.3 10:50:16 INFO - Return code: 0 10:50:16 INFO - grabbing minidump binary from tooltool 10:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:16 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 0x109ce1d30>, '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 0x109b07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cd9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:50:16 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 10:50:16 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 10:50:16 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 10:50:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:50:16 INFO - Return code: 0 10:50:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:50:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:50:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:50:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:50:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 10:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0086 --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 10:50:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4n9rkpnRy/Render', 10:50:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:16 INFO - 'HOME': '/Users/cltbld', 10:50:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:16 INFO - 'LOGNAME': 'cltbld', 10:50:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:50:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:16 INFO - 'MOZ_NO_REMOTE': '1', 10:50:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:50:16 INFO - 'NO_EM_RESTART': '1', 10:50:16 INFO - 'PAGER': '/bin/cat', 10:50:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:16 INFO - 'PWD': '/builds/slave/test', 10:50:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:50:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:16 INFO - 'SHELL': '/bin/bash', 10:50:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3jwNHxBVEy/Listeners', 10:50:16 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:50:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:16 INFO - 'USER': 'cltbld', 10:50:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:16 INFO - 'XPC_FLAGS': '0x0', 10:50:16 INFO - 'XPC_SERVICE_NAME': '0', 10:50:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 10:50:16 INFO - mozversion application_buildid: 20161010084223 10:50:16 INFO - mozversion application_changeset: 090c736d36d255900ca32c37fe33dc9446c994da 10:50:16 INFO - mozversion application_display_name: Nightly 10:50:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:50:16 INFO - mozversion application_name: Firefox 10:50:16 INFO - mozversion application_remotingname: firefox 10:50:16 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 10:50:16 INFO - mozversion application_vendor: Mozilla 10:50:16 INFO - mozversion application_version: 52.0a1 10:50:16 INFO - mozversion platform_buildid: 20161010084223 10:50:16 INFO - mozversion platform_changeset: 090c736d36d255900ca32c37fe33dc9446c994da 10:50:16 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 10:50:16 INFO - mozversion platform_version: 52.0a1 10:50:16 INFO - using testdate: 1476121816 10:50:16 INFO - actual date: 1476121816 10:50:16 INFO - starting webserver on 'localhost:49277' 10:50:16 INFO - SUITE-START | Running 2 tests 10:50:16 INFO - TEST-START | dromaeo_css 10:50:16 INFO - operating with platform_type : mac_ 10:50:16 INFO - Initialising browser for dromaeo_css test... 10:50:16 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpIIPByG/profile http://localhost:49277/getInfo.html) 10:50:19 INFO - PROCESS | 1647 | 2016-10-10 10:50:19.412 plugin-container[1649:7852] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 10:50:19 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:19 INFO - PROCESS | 1647 | 2016-10-10 10:50:19.415 plugin-container[1649:7852] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 10:50:19 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:19 INFO - PROCESS | 1647 | 2016-10-10 10:50:19.415 plugin-container[1649:7852] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:50:19 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 10:50:19 INFO - PROCESS | 1647 | colorDepth:24 10:50:19 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 10:50:19 INFO - PROCESS | 1647 | __metrics 10:50:19 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 10:50:20 INFO - TEST-INFO | 1647: exit 0 10:50:20 INFO - Browser initialized. 10:50:20 INFO - Running cycle 1/1 for dromaeo_css test... 10:50:20 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpIIPByG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:50:22 INFO - PROCESS | 1650 | 2016-10-10 10:50:22.759 plugin-container[1651:7969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.tsm.portname' 10:50:22 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:22 INFO - PROCESS | 1650 | 2016-10-10 10:50:22.761 plugin-container[1651:7969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 10:50:22 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:22 INFO - PROCESS | 1650 | 2016-10-10 10:50:22.761 plugin-container[1651:7969] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:50:32 INFO - PROCESS | 1650 | RSS: Main: 245587968 10:50:32 INFO - PROCESS | 1650 | 10:51:22 INFO - PROCESS | 1650 | 1476121882115 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] 10:53:35 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 10:53:36 INFO - PROCESS | 1650 | RSS: Main: 257093632 10:53:36 INFO - PROCESS | 1650 | 10:56:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 10:56:39 INFO - PROCESS | 1650 | RSS: Main: 268537856 10:56:39 INFO - PROCESS | 1650 | 10:59:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 10:59:42 INFO - PROCESS | 1650 | RSS: Main: 274030592 10:59:42 INFO - PROCESS | 1650 | 11:02:44 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 11:02:44 INFO - PROCESS | 1650 | RSS: Main: 275075072 11:02:44 INFO - PROCESS | 1650 | 11:05:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 11:05:48 INFO - PROCESS | 1650 | RSS: Main: 280469504 11:05:48 INFO - PROCESS | 1650 | 11:08:50 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 11:08:51 INFO - PROCESS | 1650 | RSS: Main: 282849280 11:08:51 INFO - PROCESS | 1650 | 11:08:51 INFO - PROCESS | 1650 | __start_tp_report 11:08:51 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 11:08:51 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 11:08:51 INFO - PROCESS | 1650 | |i|pagename|runs| 11:08:51 INFO - PROCESS | 1650 | |0;dojo.html;3374.510695949086;3383.424817780977;3407.591089069312;3420.828958552072;3431.3651974384757;4261.893452663685;5388.706710389508;5516.668999860001;5649.1526271059365;5787.797427090053;5698.886022279552;5729.828105156842;5732.656040637554;5786.884262314762;5824.912626310609;4381.707396318078;4413.786208792576;5188.558972487343;5220.895582088356;5279.762410691528;4385.934210986822;5052.736815796051;5320.14877619581;5348.491893270133;5415.458454154573;4534.047849951481;5132.28148059272;5200.583953283766;5235.214717792323;5304.73476326186;4934.481879402683;5292.497212764782;5298.602604804655;5299.284596579451;5332.946670533283;5151.484851514856;5174.922376164361;5202.843914682566;5204.973975130138;5285.339332583427;4605.37827393301;5586.608937374385;5648.3222013358245;5787.102999035156;5791.652500849962;6111.257501106181;6122;6207.068939659087;6234.532410069263;6244.438000579969;6231.127642130105;6246.469050130744;6253.3433989430905;6264.21697287839;6279.843003924892;5661.688607126565;5828.737706803204;5899.203607513016;5907.940920590782;5957.195778569924;5163.457836927144;6204.069389591597;6222;6249.468795152463;6337.714802833883;6091.695415229309;6116;6150.231221097363;6159.5072394208355;6164.383561643889;6088.786892458743;6220.253569571681;6240.282367527792;6275.748970041147;6282.905756413657;6099.521645064636;6120.9347721082095;6128.258691089271;6138.539609529304;6208.534359922934;6203.2556093268195;6204.689765511796;6236.6258024518675;6247.5939063960695;6249.250089989139;4955;4970.428400733889;4971.1797553403285;4988.226824842155;5011.899762004739;4329.935050974238;4370.934435983463;4374.03771170342;4383.276759334738;4499.820007199675;5777.306723193244;5798.565107616945;5822.446867547576;5842.357340692521;5938.2280303560265;6164.784232551929;6212.285587157535;6215.844603884849;6250.218722659668;6257.311695713468;5671.291088613923;5683.914741278884;5724.484796368347;5727.341355744059;5827.038538641077;5417.8103766367985;5427.0773968425465;5456.781728730806;5464.098423760035;5482.095454250084;5300.416954135119;6342.175517182736;6360.77737279193;6371.490280777527;6377.585456945284;6102.701334035014;6153.953827849186;6155.015197568547;6192.349803270592;6222.346653601488;6225.159603453657;6341.682915854281;6362.904556431749;6384.585001974857;6415.518836087405;5255.211718242142;6001.4298641628975;6157.230346206724;6184.412480814315;6244.063390491463;6127.678242056131;6147.586055207416;6167.994727697612;6213.502919766317;6247.312795592572;5881.235439393023;6069.666168360606;6089.360617135137;6125.28353168067;6161.476274516716;5072.594192464668;5737.713114344349;6110.749897012629;6126.426068710427;6136;5512.476314750093;5733;5926.348101708895;5932.525397968238;5992.910106348321;4957.603391728581;6071;6133.969330153321;6141.332587233645;6147.170132032118;6012.789552365646;6022.2171117754415;6027.156198132353;6039.245094363205;6039.667818270037 11:08:51 INFO - PROCESS | 1650 | |1;ext.html;503.2250334484892;523.4085483403754;536.5734241278186;542.576790103719;544.9917652343163;38303.23393532131;39716.404253936176;39759.80120099403;39819.800900995455;41978.790106049506;41102.588974110324;41673.7916310418;41784.37323440151;41802.58197418025;41899;9655.5172241388;9719.076687714656;9802.676638653762;9841.50792460374;9862.506874656274;9652.324337296392;9694.757631059245;9701.611935522571;9736.659216927408;9739.415635061883;9551.761205969837;9625.37435066722;9671.274654400913;9742.415455072683;9766.853497197548;9681.70954871355;9700.078492543196;9709.563069661812;9739.318247722662;9764.365316254492;8624.698135565224;9643.27675424339;9675.274354423376;9683.806323873554;9685.32202745808;39206.803965980274;39412.802935985426;39498.80250598757;39602.20795584072;39638;3250.2524419383676;3279.1310302769784;3323;3334.7999120052873;3429.9142521436916;30008.699913000808;30564.235894102603;30886.691133088607;31003.534946975815;31379.686203137906;2956.9408611827857;2972.687867773896;2977.1217490840254;3063.969360306413;3069.815811051344;381085;386218;386388;387704;388374.0581297104;56241.43758562444;56496.43503564994;56550.71724641351;56624;56684.43315566874;58012.41987580155;58386.708066459396;58497;58702.412975870546;58744;14799;15297.770533442008;15649.452269170524;15827.129507876947;15872.52382428506;12345;12367.93816030914;12412.193207441487;12415.75168496625;12419.75160496785;14455.85544144545;15524;15735.560988046871;15742.606434839221;15862.920685396499;3195.8721651133696;3254;6224.875502489925;6398.584092034096;6413.422791948654;755.3277582951177;851.2679095977454;899.9325050621229;962.3023308101571;1058.5924419098703;25452.981880724936;26296.73703262943;28838;31443.37113257722;33578.985260883936;782.8669126248551;999.9650361875424;1035.471909326234;1058.5977328615231;1142.9028532574823;12351.567695130807;14803.703925921636;15338.463153789564;15443.602407470338;15721.68556628861;662126;675765;683597;685229;687422;56235;56480.717596411756;57203.85592288131;57305.71347143321;57450;1336.1582203211915;1390.1520072755611;1416.3980308368903;1420.9857901421267;1424.4373472478312;28665.28336791597;29410.264743381587;29550.96571619983;30229.697703022688;30850.228744281572;50853;51643;51678.741606291725;51708.22437663513;51800.96398072093;619008;622571;623833.8808305929;624531;625609;40102.398464023645;41185.38221926614;41565.584344156174;41604;41889;45576.544234558554;45790.85522861955;47092.76453617847;47305.526944731486;47656.52343476521;37919.62080379161;38594.80702596581;38663.8400847975;38769.6123038766;39994.40008399818;37907.81046094752;38350;38514.614853851104;39096;39290;36670.266594668494;37217.4417383734;37359.06602334855;38077.048073798374;38273;33016;34021.829890851375;34287.79992700244;34443.4833477493;34871.5949027891;24885.502289953736;25221.74778252194;25433;25454.490910182063;25994.350141245865 11:08:51 INFO - PROCESS | 1650 | |2;jquery.html;370.68862155789134;371.4020427112349;377.8488604558178;377.91496913194527;399.14383647077;19438;19973.800261997374;20131.19475220993;20217.696734548994;20219.39341819746;18048.18783154758;19311.517212069706;19731.309404170857;20235.595288094228;20372.592548149027;4059.654929330996;4838.9838133991725;4855.5387238212315;5151.201563757623;5635.6055076144685;5864.2962844458725;5942.138389933457;6040.939590604104;6058.273007239138;6086.2087928569445;5275.920861187085;5688.544916406699;5856.648601083949;5867.1492633568105;5981.312149102843;5842.444967728059;5908.1433192187105;5917.88164236715;5929.1995580596495;5951.553633477463;5074.31496747938;5145.742712864379;5382.569394448474;5406.54044406226;5498.065328894098;12699.428525716272;12853.742925141445;12981.286029268387;13056.804147937788;13088.607341779762;2141.539568992674;2332.1103370419282;2452.227548322273;2676.317539027555;2871.583620375034;9168.724938251864;9757.707268781947;10195.337303075275;12879.227246365248;14426.567202983928;1819.7270409438559;1827.1138497828542;1834.5780470491861;1854.9443516694387;1859.0797555210122;112475.43762281218;116262.41868790686;117419.41290293494;120290.38402887838;120372;11272.098232141409;11303.943480282545;11567.652970410902;11571;11971;14388;14392.913027611146;14401;14417.125773649359;14455.349509272108;8156.184381561797;8317.625706843206;8365;8410.915890841135;8525.57372131391;8356.958215209006;8387.916120838836;8426.53654049021;8525.957370213233;8575.914240857512;4408.867733967986;4478.373027776114;4497.662675299365;4557.384753058361;4575.611073058827;1750.7023805953017;1783.7157246782297;1856.7626010657395;1918.3573502876693;1929.922803087889;855.6278019061729;857.8970523537215;897.4660077254024;930.5114814722325;968.6245531345365;20717.482062948246;20856.791432085483;20914.686279705817;20956.37130886076;21046.158153673987;764.8623247815335;899.6176624934377;907.6055552234595;918.3296193778579;935.5976929920064;10417.531211095518;10979.011888930034;11017.008469237648;11035.889641103486;11071.335719856834;570005;602012;612964.9351753301;613150.934245326;614538;120662.19006714906;129785.35107324403;130449;131776;137446;803.5982008995502;913.4701872913694;915.6657819895823;949.2690628216235;950.0357137505438;18324.54188645241;18573.90713046426;18586.349477768214;18611.534711631775;18661.626767464848;40704;54345.45654543562;55142.44857551373;55248;56440.7177964124;72816.63591682007;75167;75550.8667369979;75685.86471202827;76560;7801.843363989549;7813.453058285865;7823.452358334862;7978.122406535161;8013.078495972804;10060.899391006289;11879.58421455245;12261.386930653252;12393.070519710878;12471.93764031174;10189.133923616575;10840.512176952214;10842.566297348334;10976.176786740867;10993.780124397308;9556.044395560388;10734.516946737573;10850.511726972463;11037.337759734442;11070;9948.502574871372;11111.277766945122;11466;11489.138314626603;11532.654020379403;7999.840003199787;8003.4797738146835;8169.959150204211;8354.58227088631;8384.70653527148;9858;10940.234183607072;11212;11262.774744505228;11400.429978500875 11:08:51 INFO - PROCESS | 1650 | |3;mootools.html;1481.6518118242216;1516.0979217365666;1598.5843680643045;1762.2598508626377;1883.52911772057;8148.266656000958;8413.495190288588;8678.566071696421;9255.722328330152;9255.814883702322;7711;8650.697225597105;8990.775230619238;9007.414518056321;9117.407368521059;2347.6478528220778;2376.572217000939;2390.7370189279163;2393.652920326552;2399.700037495313;2333.7549557296493;2366.183666635017;2366.4202270443766;2366.5800519688137;2384.380061184096;2314.0628045641542;2329.1615018593293;2331.988340058297;2358.988205058972;2369.680093187415;2344.3553022918663;2344.824138189634;2359.127122964497;2360.150345875475;2364.8817559121976;2335.019291897407;2338.6024375855977;2338.7427382987858;2339.391758142878;2344.8358614896965;9108.362414630978;9176.770580735469;9181.816363672757;9195;9202.677906273248;4511.819527218908;5244.344456942882;5338.252644629755;5380.3274590676165;5408.756605952742;2804.495190865664;3529.6646818552194;3575.803330816804;3634.182308980485;3639.6178401267753;1038.657243109772;1039.4023436524028;1048.1516306230528;1048.4810019040547;1060.3002018667719;11679;11824.645260642195;11951.223170493977;11965.46155423008;12100.334481603597;4594.70134441267;4628.74541900192;4833.564979151823;4840.322354870321;4845.0067736114715;8552.44409113418;10234.33476824004;10235.744106397251;10295.227857910686;10318.742031449123;5350.143976963667;5384.273123128406;5387.595930305164;5400.513953744182;5401.081816091274;4420.717991782347;4557.15692596864;4745.976270118696;4870.196417591129;4931.975340123348;3912.941305880414;3914.0410599402758;3960.5643379228263;4069.104796944667;4086.5096188457574;1082.9404382759024;1275.2667216350635;1284.07546566472;1293.1206779390104;1302.6482849630736;768.4006474949548;803.7508372404574;911.4212475078373;919.4391421233042;937.5921474158627;5396.622236443412;5737.684427356536;5863.882722345529;5864.237649105589;6040.486558642477;427.53398795313257;435.9106383191438;436.93009118541517;444.83096423358916;445.56112229453964;3652.0139562318027;3836.290286297044;4027.690371302678;4066.1867626474823;4109.034376921421;10731.678049658523;11001.779964400826;11092.445377731081;11124.944375278072;11125;11076.556937722562;11103.888961110286;11147.72130696739;11148.219624626197;11152.219344645959;7380.741674041383;7935.603219838985;8177.223163799429;8407.327413806881;8423.663053477792;5743.483086522233;5844.15259787337;5909.674967876808;5973.1338955852025;5973.731182096729;8746.562671866508;10180.778306602959;10313.381197128196;10318.071373576115;10325.122364599094;8165.836683266182;8660.913390866011;10062.195024397884;10103.24225683062;10474.952504749768;7455.776326710207;7555.622218889143;7569.205233450629;7640.73257435987;7665.540067595962;7210.855782884418;7463.3656139226605;8183.5499047550575;8354.373421993254;8407.831843363221;6652.002199669894;6765.714514242278;7299.4160467163565;7333.339999400079;7502.174760776419;7206.387457065998;7385.667644956097;7472.738454154079;7546.698132074655;7616.428767842323;6995.720171193096;7029.29707029293;7164.426845852424;7195.6761945713615;7215.2063273038875;4949.9110195756875;5161.122609156378;5172.741362931763;5192.1952097424955;5258.395284542326;5159.277701121771;5303.337082864642;5318.787248510171;5401.891962160814;5485.4788795064405 11:08:51 INFO - PROCESS | 1650 | |4;prototype.html;252.75861552217629;305.6179775280899;309.1035995612723;312.5592913991271;314.05469536694545;4641.675082744209;5583.246261754667;5626.774929002845;5642.661440313584;5661.60368774186;5479.7944452220545;5496.422875598065;5528.723563821813;5543.61194716369;5582.720863956806;3960.72274940755;4030.576789437096;4067.2068946555432;4086.3053280942163;4098.692598055143;4110.486189226347;4124.339800828464;4155.252054630166;4160.916781664365;4163.354680024601;3743.1390780120587;4101.323281658522;4129.298019336705;4151.418801367811;4153.750774953497;4035.47538819953;4105.835766569334;4110.609492098246;4141.64795992341;4161.91676166478;3228.935421291561;3531.187826799825;3557.1818481749083;3568.1793187566745;3572.5712914450173;4321.546237645033;4912.975435122801;5251.737413129328;5272;5291.444398338196;4146.357314616239;4352.564743525654;4724.464549021582;4724.503927087675;4766.73782941938;5342.103553238583;5686.431356864322;5713.6000479966415;5749.540036797046;5755.668954073565;3405.8637654494037;3451.3941973809865;3919.4708714323488;3930.862419815321;3953.133977866024;5211.609129315279;5780.8843823123725;6029.758809647609;6038.879222415527;6087.391260873921;3868.2263547290654;3998.0604557928864;3999.34010888206;4014.598540145962;4014.9761810738073;4057.69567282455;4069.633732963988;4076.5515793262716;4080.326746086922;4092.2224777292217;3935.4490371348033;3986.4219688145636;4693.155232058262;4735.266033764703;4739;4670.8365207217585;5441.918371224435;5684.516816070681;5792.218050563204;5871.7651293947765;1374.1960952842499;1396.1623026184209;1397.196611948134;1399.9650008749659;1400.8739213470838;801.2668408406244;850.6050077872294;861.9138086191331;864.5590748718199;864.9048604653483;5312.442193569696;5351.81268655603;5501.834944951658;5514.648911016827;5561.499465048165;4843.975780121077;5520.309961254843;5632.746526406321;5671.773129074791;6049.818505444844;4646.512116227747;5008.873003574166;5123.513266239701;5324.9733751331;5539.335279766454;3093.2421556718746;3159.842007899596;3161.8577164027674;3168.8574014169417;3175.285560748859;5662.546996240291;6109.877802444015;6238.937610623926;6275.403836635513;6325.525585581007;5390.21841832932;5413.2692086568595;5765.07758758605;6171.5988460750805;6202.472789812826;5005.524475174852;6312.999999999908;6321.367863213642;6324.3043265240785;6360.554761166859;4888.755562221946;5216.37403511581;5219.817306394258;5847.6668299070825;6072.817815465543;5951.404859514188;5963.761449541969;5981.89334973014;6019.307779605401;6023.548233882389;4745.905081898273;5726.026575482095;5890.14592884038;5972.762170224177;6313.454312894709;3258.1365938026916;3313.652066533076;3399.94900076504;3952.9209415810947;3962.4254483100367;4865.318855360181;5705.771769129188;5961.880762384815;6031.155638210566;6107.267127944675;4849.599448273128;5564.972175139098;5871.941280587311;5876.41235876409;5877.206577112206;4579.358889755504;5524.889502209853;5750.396208398058;5800.970995144998;5814.651120932757;5230.391030189157;5480.410680902493;5676.262085928803;5764.538836893123;5769.990251706018;4963.528464795839;5365.785368585213;5523.585731070266;5690.203371527907;5812.564057695774;4945.826896058621;4961.231009193581;5353.812616558402;5713.742976545153;5759.884802304015 11:08:51 INFO - PROCESS | 1650 | |5;yui.html;680.1056610557118;704.0882057735232;715.3704739828952;754.9169591344954;784.157031191469;15306.69386612267;16347.346106155754;16368.099754513498;16401.343946242137;16402.17989100546;14693.118412895234;14710.48513302032;14849.406023759064;14857.182854942974;14876.553703388889;667.6027763480721;694.0872752330669;818.467995802727;826.4958375391006;845.222395396237;834.2991886815074;852.7399143261302;853.9103394143618;854.5257382152903;856.7643897928089;892.6964831957133;906.5965645287849;907.3059109781022;907.6006557114881;908.0465511213233;895.2390468102126;899.9190072893439;901.6889173235256;903.2142036428284;903.8644203369481;539.962202645815;616.7902913009598;623.5853157650157;666.7133132752914;673.007314211539;13014.828665420158;14049.64875878101;14776.48282310114;15075.472358467401;15136.016158949744;13330.200187988752;14624.341904614317;14655.34050967709;14724.779128313085;14830.629234269121;11739.178257521977;11910.285382876968;11983.161178717493;12000.21998570099;12016.098792590503;3793.525809273841;3823.3691440912216;3836.55879573847;3849.826757795906;3878.3600705883496;79956.60021699971;115756.42121789337;116647.41676291564;117233.41383293114;119344;11365.602203922823;11525.308481491138;11525.827112593317;11606.825897611543;11955.498756699417;23726.644100338508;23759.049638014632;23812.04751809943;23822.88088559581;23859.92630331639;8063.556504392197;8234.752957411287;8349.874751878726;8434.873476897852;8439.873401898976;4195.183713754509;4443.000324926866;4455.1312494064;4521.909561808746;4559.156556037077;3391.745619078579;3508.877189298413;3537.1510837398837;3540.46892966052;3549.1304630365207;554.8668319603347;646.1858058845794;648.3322178156507;669.4142625202948;672.4116398151617;513.3531749995033;524.908141075318;528.3527678593769;530.9946900531022;600.6515372987584;7144.9285507145305;7585.862120681832;8151.274289037718;8979;8999.955000224956;503.6424138861376;545.3455852976444;563.259314002086;603.8309273403366;685.3523420367704;3972.7616343019513;4367.868963931087;4657.231556793091;4664.65015123867;4761.262004389325;146026.26986864998;149933.25033374762;150372.99254014858;150538.9461053908;150749;119676;123427;127132;129280;130220;94207;111727.44136279267;112610;113131.43434282776;116008;7254.528455650366;9036.683716069905;9065.501397423204;9264.305177111879;9342.859857102014;48367;62071.44821379502;62444.68777656083;62834;62839.6858015707;91288;91325;92511.53744231505;93652;98566.5071674637;11509.13681473876;11551;11795.528178873092;11925.10561707858;12167.574134905235;11319.493297011877;12813.167144135601;12947.964162867136;13209.735805284034;13681.521146759816;9578.521073946415;10638.946805266234;10899.509522071367;11735.706607334543;11837.934585887311;12271.325077120844;12529.874701252871;12544.49822007146;13112.475500979854;13739.656508587364;10782.568697252022;13551.457941682222;13587.456501740215;13815.86184138186;13997.930010349883;11303;11365.204435689422;11379.032782213604;11596.710082248012;11783.528658853893;7930;10786.298890572089;11523.423828808694;11691.421658076051;11718.531258749554 11:08:51 INFO - PROCESS | 1650 | __end_tp_report 11:08:51 INFO - PROCESS | 1650 | __start_cc_report 11:08:51 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,365 11:08:51 INFO - PROCESS | 1650 | __end_cc_report 11:08:51 INFO - PROCESS | 1650 | __startTimestamp1476122931021__endTimestamp 11:08:51 INFO - PROCESS | 1650 | 11:08:51 INFO - PROCESS | 1650 | ------- Summary: start ------- 11:08:51 INFO - PROCESS | 1650 | Number of tests: 6 11:08:51 INFO - PROCESS | 1650 | 11:08:51 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5684.42 Median:5926.35 stddev:672.66 (11.4%) stddev-sans-first:649.85 11:08:51 INFO - PROCESS | 1650 | Values: 3374.5 3383.4 3407.6 3420.8 3431.4 4261.9 5388.7 5516.7 5649.2 5787.8 5698.9 5729.8 5732.7 5786.9 5824.9 4381.7 4413.8 5188.6 5220.9 5279.8 4385.9 5052.7 5320.1 5348.5 5415.5 4534.0 5132.3 5200.6 5235.2 5304.7 4934.5 5292.5 5298.6 5299.3 5332.9 5151.5 5174.9 5202.8 5205.0 5285.3 4605.4 5586.6 5648.3 5787.1 5791.7 6111.3 6122.0 6207.1 6234.5 6244.4 6231.1 6246.5 6253.3 6264.2 6279.8 5661.7 5828.7 5899.2 5907.9 5957.2 5163.5 6204.1 6222.0 6249.5 6337.7 6091.7 6116.0 6150.2 6159.5 6164.4 6088.8 6220.3 6240.3 6275.7 6282.9 6099.5 6120.9 6128.3 6138.5 6208.5 6203.3 6204.7 6236.6 6247.6 6249.3 4955.0 4970.4 4971.2 4988.2 5011.9 4329.9 4370.9 4374.0 4383.3 4499.8 5777.3 5798.6 5822.4 5842.4 5938.2 6164.8 6212.3 6215.8 6250.2 6257.3 5671.3 5683.9 5724.5 5727.3 5827.0 5417.8 5427.1 5456.8 5464.1 5482.1 5300.4 6342.2 6360.8 6371.5 6377.6 6102.7 6154.0 6155.0 6192.3 6222.3 6225.2 6341.7 6362.9 6384.6 6415.5 5255.2 6001.4 6157.2 6184.4 6244.1 6127.7 6147.6 6168.0 6213.5 6247.3 5881.2 6069.7 6089.4 6125.3 6161.5 5072.6 5737.7 6110.7 6126.4 6136.0 5512.5 5733.0 5926.3 5932.5 5992.9 4957.6 6071.0 6134.0 6141.3 6147.2 6012.8 6022.2 6027.2 6039.2 6039.7 11:08:51 INFO - PROCESS | 1650 | 11:08:51 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:69614.46 Median:39677.20 stddev:154560.88 (389.5%) stddev-sans-first:154907.36 11:08:51 INFO - PROCESS | 1650 | Values: 503.2 523.4 536.6 542.6 545.0 38303.2 39716.4 39759.8 39819.8 41978.8 41102.6 41673.8 41784.4 41802.6 41899.0 9655.5 9719.1 9802.7 9841.5 9862.5 9652.3 9694.8 9701.6 9736.7 9739.4 9551.8 9625.4 9671.3 9742.4 9766.9 9681.7 9700.1 9709.6 9739.3 9764.4 8624.7 9643.3 9675.3 9683.8 9685.3 39206.8 39412.8 39498.8 39602.2 39638.0 3250.3 3279.1 3323.0 3334.8 3429.9 30008.7 30564.2 30886.7 31003.5 31379.7 2956.9 2972.7 2977.1 3064.0 3069.8 381085.0 386218.0 386388.0 387704.0 388374.1 56241.4 56496.4 56550.7 56624.0 56684.4 58012.4 58386.7 58497.0 58702.4 58744.0 14799.0 15297.8 15649.5 15827.1 15872.5 12345.0 12367.9 12412.2 12415.8 12419.8 14455.9 15524.0 15735.6 15742.6 15862.9 3195.9 3254.0 6224.9 6398.6 6413.4 755.3 851.3 899.9 962.3 1058.6 25453.0 26296.7 28838.0 31443.4 33579.0 782.9 1000.0 1035.5 1058.6 1142.9 12351.6 14803.7 15338.5 15443.6 15721.7 662126.0 675765.0 683597.0 685229.0 687422.0 56235.0 56480.7 57203.9 57305.7 57450.0 1336.2 1390.2 1416.4 1421.0 1424.4 28665.3 29410.3 29551.0 30229.7 30850.2 50853.0 51643.0 51678.7 51708.2 51801.0 619008.0 622571.0 623833.9 624531.0 625609.0 40102.4 41185.4 41565.6 41604.0 41889.0 45576.5 45790.9 47092.8 47305.5 47656.5 37919.6 38594.8 38663.8 38769.6 39994.4 37907.8 38350.0 38514.6 39096.0 39290.0 36670.3 37217.4 37359.1 38077.0 38273.0 33016.0 34021.8 34287.8 34443.5 34871.6 24885.5 25221.7 25433.0 25454.5 25994.4 11:08:51 INFO - PROCESS | 1650 | 11:08:51 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:34646.60 Median:371.05 stddev:100649.13 (27125.8%) stddev-sans-first:100898.57 11:08:51 INFO - PROCESS | 1650 | Values: 370.7 371.4 377.8 377.9 399.1 19438.0 19973.8 20131.2 20217.7 20219.4 18048.2 19311.5 19731.3 20235.6 20372.6 4059.7 4839.0 4855.5 5151.2 5635.6 5864.3 5942.1 6040.9 6058.3 6086.2 5275.9 5688.5 5856.6 5867.1 5981.3 5842.4 5908.1 5917.9 5929.2 5951.6 5074.3 5145.7 5382.6 5406.5 5498.1 12699.4 12853.7 12981.3 13056.8 13088.6 2141.5 2332.1 2452.2 2676.3 2871.6 9168.7 9757.7 10195.3 12879.2 14426.6 1819.7 1827.1 1834.6 1854.9 1859.1 112475.4 116262.4 117419.4 120290.4 120372.0 11272.1 11303.9 11567.7 11571.0 11971.0 14388.0 14392.9 14401.0 14417.1 14455.3 8156.2 8317.6 8365.0 8410.9 8525.6 8357.0 8387.9 8426.5 8526.0 8575.9 4408.9 4478.4 4497.7 4557.4 4575.6 1750.7 1783.7 1856.8 1918.4 1929.9 855.6 857.9 897.5 930.5 968.6 20717.5 20856.8 20914.7 20956.4 21046.2 764.9 899.6 907.6 918.3 935.6 10417.5 10979.0 11017.0 11035.9 11071.3 570005.0 602012.0 612964.9 613150.9 614538.0 120662.2 129785.4 130449.0 131776.0 137446.0 803.6 913.5 915.7 949.3 950.0 18324.5 18573.9 18586.3 18611.5 18661.6 40704.0 54345.5 55142.4 55248.0 56440.7 72816.6 75167.0 75550.9 75685.9 76560.0 7801.8 7813.5 7823.5 7978.1 8013.1 10060.9 11879.6 12261.4 12393.1 12471.9 10189.1 10840.5 10842.6 10976.2 10993.8 9556.0 10734.5 10850.5 11037.3 11070.0 9948.5 11111.3 11466.0 11489.1 11532.7 7999.8 8003.5 8170.0 8354.6 8384.7 9858.0 10940.2 11212.0 11262.8 11400.4 11:08:51 INFO - PROCESS | 1650 | 11:08:51 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5718.38 Median:4687.36 stddev:3258.23 (69.5%) stddev-sans-first:3251.81 11:08:51 INFO - PROCESS | 1650 | Values: 1481.7 1516.1 1598.6 1762.3 1883.5 8148.3 8413.5 8678.6 9255.7 9255.8 7711.0 8650.7 8990.8 9007.4 9117.4 2347.6 2376.6 2390.7 2393.7 2399.7 2333.8 2366.2 2366.4 2366.6 2384.4 2314.1 2329.2 2332.0 2359.0 2369.7 2344.4 2344.8 2359.1 2360.2 2364.9 2335.0 2338.6 2338.7 2339.4 2344.8 9108.4 9176.8 9181.8 9195.0 9202.7 4511.8 5244.3 5338.3 5380.3 5408.8 2804.5 3529.7 3575.8 3634.2 3639.6 1038.7 1039.4 1048.2 1048.5 1060.3 11679.0 11824.6 11951.2 11965.5 12100.3 4594.7 4628.7 4833.6 4840.3 4845.0 8552.4 10234.3 10235.7 10295.2 10318.7 5350.1 5384.3 5387.6 5400.5 5401.1 4420.7 4557.2 4746.0 4870.2 4932.0 3912.9 3914.0 3960.6 4069.1 4086.5 1082.9 1275.3 1284.1 1293.1 1302.6 768.4 803.8 911.4 919.4 937.6 5396.6 5737.7 5863.9 5864.2 6040.5 427.5 435.9 436.9 444.8 445.6 3652.0 3836.3 4027.7 4066.2 4109.0 10731.7 11001.8 11092.4 11124.9 11125.0 11076.6 11103.9 11147.7 11148.2 11152.2 7380.7 7935.6 8177.2 8407.3 8423.7 5743.5 5844.2 5909.7 5973.1 5973.7 8746.6 10180.8 10313.4 10318.1 10325.1 8165.8 8660.9 10062.2 10103.2 10475.0 7455.8 7555.6 7569.2 7640.7 7665.5 7210.9 7463.4 8183.5 8354.4 8407.8 6652.0 6765.7 7299.4 7333.3 7502.2 7206.4 7385.7 7472.7 7546.7 7616.4 6995.7 7029.3 7164.4 7195.7 7215.2 4949.9 5161.1 5172.7 5192.2 5258.4 5159.3 5303.3 5318.8 5401.9 5485.5 11:08:51 INFO - PROCESS | 1650 | 11:08:51 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4647.46 Median:5241.06 stddev:1446.49 (27.6%) stddev-sans-first:1412.43 11:08:51 INFO - PROCESS | 1650 | Values: 252.8 305.6 309.1 312.6 314.1 4641.7 5583.2 5626.8 5642.7 5661.6 5479.8 5496.4 5528.7 5543.6 5582.7 3960.7 4030.6 4067.2 4086.3 4098.7 4110.5 4124.3 4155.3 4160.9 4163.4 3743.1 4101.3 4129.3 4151.4 4153.8 4035.5 4105.8 4110.6 4141.6 4161.9 3228.9 3531.2 3557.2 3568.2 3572.6 4321.5 4913.0 5251.7 5272.0 5291.4 4146.4 4352.6 4724.5 4724.5 4766.7 5342.1 5686.4 5713.6 5749.5 5755.7 3405.9 3451.4 3919.5 3930.9 3953.1 5211.6 5780.9 6029.8 6038.9 6087.4 3868.2 3998.1 3999.3 4014.6 4015.0 4057.7 4069.6 4076.6 4080.3 4092.2 3935.4 3986.4 4693.2 4735.3 4739.0 4670.8 5441.9 5684.5 5792.2 5871.8 1374.2 1396.2 1397.2 1400.0 1400.9 801.3 850.6 861.9 864.6 864.9 5312.4 5351.8 5501.8 5514.6 5561.5 4844.0 5520.3 5632.7 5671.8 6049.8 4646.5 5008.9 5123.5 5325.0 5539.3 3093.2 3159.8 3161.9 3168.9 3175.3 5662.5 6109.9 6238.9 6275.4 6325.5 5390.2 5413.3 5765.1 6171.6 6202.5 5005.5 6313.0 6321.4 6324.3 6360.6 4888.8 5216.4 5219.8 5847.7 6072.8 5951.4 5963.8 5981.9 6019.3 6023.5 4745.9 5726.0 5890.1 5972.8 6313.5 3258.1 3313.7 3399.9 3952.9 3962.4 4865.3 5705.8 5961.9 6031.2 6107.3 4849.6 5565.0 5871.9 5876.4 5877.2 4579.4 5524.9 5750.4 5801.0 5814.7 5230.4 5480.4 5676.3 5764.5 5770.0 4963.5 5365.8 5523.6 5690.2 5812.6 4945.8 4961.2 5353.8 5713.7 5759.9 11:08:51 INFO - PROCESS | 1650 | 11:08:51 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:24635.95 Median:3671.33 stddev:39587.76 (1078.3%) stddev-sans-first:39657.96 11:08:51 INFO - PROCESS | 1650 | Values: 680.1 704.1 715.4 754.9 784.2 15306.7 16347.3 16368.1 16401.3 16402.2 14693.1 14710.5 14849.4 14857.2 14876.6 667.6 694.1 818.5 826.5 845.2 834.3 852.7 853.9 854.5 856.8 892.7 906.6 907.3 907.6 908.0 895.2 899.9 901.7 903.2 903.9 540.0 616.8 623.6 666.7 673.0 13014.8 14049.6 14776.5 15075.5 15136.0 13330.2 14624.3 14655.3 14724.8 14830.6 11739.2 11910.3 11983.2 12000.2 12016.1 3793.5 3823.4 3836.6 3849.8 3878.4 79956.6 115756.4 116647.4 117233.4 119344.0 11365.6 11525.3 11525.8 11606.8 11955.5 23726.6 23759.0 23812.0 23822.9 23859.9 8063.6 8234.8 8349.9 8434.9 8439.9 4195.2 4443.0 4455.1 4521.9 4559.2 3391.7 3508.9 3537.2 3540.5 3549.1 554.9 646.2 648.3 669.4 672.4 513.4 524.9 528.4 531.0 600.7 7144.9 7585.9 8151.3 8979.0 9000.0 503.6 545.3 563.3 603.8 685.4 3972.8 4367.9 4657.2 4664.7 4761.3 146026.3 149933.3 150373.0 150538.9 150749.0 119676.0 123427.0 127132.0 129280.0 130220.0 94207.0 111727.4 112610.0 113131.4 116008.0 7254.5 9036.7 9065.5 9264.3 9342.9 48367.0 62071.4 62444.7 62834.0 62839.7 91288.0 91325.0 92511.5 93652.0 98566.5 11509.1 11551.0 11795.5 11925.1 12167.6 11319.5 12813.2 12948.0 13209.7 13681.5 9578.5 10638.9 10899.5 11735.7 11837.9 12271.3 12529.9 12544.5 13112.5 13739.7 10782.6 13551.5 13587.5 13815.9 13997.9 11303.0 11365.2 11379.0 11596.7 11783.5 7930.0 10786.3 11523.4 11691.4 11718.5 11:08:51 INFO - PROCESS | 1650 | -------- Summary: end -------- 11:08:51 INFO - PROCESS | 1650 | 11:08:51 INFO - TEST-INFO | 1650: exit 0 11:08:51 INFO - TEST-OK | dromaeo_css | took 1115198ms 11:08:51 INFO - TEST-START | kraken 11:08:51 INFO - operating with platform_type : mac_ 11:08:51 INFO - Initialising browser for kraken test... 11:08:51 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpQgGvVc/profile http://localhost:49277/getInfo.html) 11:08:54 INFO - PROCESS | 1662 | 2016-10-10 11:08:54.290 plugin-container[1663:10375] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.tsm.portname' 11:08:54 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:08:54 INFO - PROCESS | 1662 | 2016-10-10 11:08:54.293 plugin-container[1663:10375] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470f, name = 'com.apple.CFPasteboardClient' 11:08:54 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:08:54 INFO - PROCESS | 1662 | 2016-10-10 11:08:54.293 plugin-container[1663:10375] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:08:54 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 11:08:54 INFO - PROCESS | 1662 | colorDepth:24 11:08:54 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 11:08:54 INFO - PROCESS | 1662 | __metrics 11:08:54 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 11:08:55 INFO - TEST-INFO | 1662: exit 0 11:08:55 INFO - Browser initialized. 11:08:55 INFO - Running cycle 1/1 for kraken test... 11:08:55 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpQgGvVc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:08:57 INFO - PROCESS | 1664 | 2016-10-10 11:08:57.817 plugin-container[1665:10488] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.tsm.portname' 11:08:57 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:08:57 INFO - PROCESS | 1664 | 2016-10-10 11:08:57.821 plugin-container[1665:10488] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 11:08:57 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:08:57 INFO - PROCESS | 1664 | 2016-10-10 11:08:57.821 plugin-container[1665:10488] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:07 INFO - PROCESS | 1664 | RSS: Main: 245850112 11:09:07 INFO - PROCESS | 1664 | 11:09:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 11:09:10 INFO - PROCESS | 1664 | RSS: Main: 257650688 11:09:10 INFO - PROCESS | 1664 | 11:09:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 11:09:14 INFO - PROCESS | 1664 | RSS: Main: 265093120 11:09:14 INFO - PROCESS | 1664 | 11:09:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 11:09:18 INFO - PROCESS | 1664 | RSS: Main: 263581696 11:09:18 INFO - PROCESS | 1664 | 11:09:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 11:09:20 INFO - PROCESS | 1664 | RSS: Main: 265756672 11:09:20 INFO - PROCESS | 1664 | 11:09:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:09:23 INFO - PROCESS | 1664 | RSS: Main: 266158080 11:09:23 INFO - PROCESS | 1664 | 11:09:26 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 11:09:26 INFO - PROCESS | 1664 | RSS: Main: 266752000 11:09:26 INFO - PROCESS | 1664 | 11:09:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 11:09:30 INFO - PROCESS | 1664 | RSS: Main: 266563584 11:09:30 INFO - PROCESS | 1664 | 11:09:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 11:09:34 INFO - PROCESS | 1664 | RSS: Main: 267202560 11:09:34 INFO - PROCESS | 1664 | 11:09:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:09:36 INFO - PROCESS | 1664 | RSS: Main: 266674176 11:09:36 INFO - PROCESS | 1664 | 11:09:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:09:38 INFO - PROCESS | 1664 | RSS: Main: 266756096 11:09:38 INFO - PROCESS | 1664 | 11:09:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:09:41 INFO - PROCESS | 1664 | RSS: Main: 266813440 11:09:41 INFO - PROCESS | 1664 | 11:09:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:09:45 INFO - PROCESS | 1664 | RSS: Main: 266813440 11:09:45 INFO - PROCESS | 1664 | 11:09:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:09:49 INFO - PROCESS | 1664 | RSS: Main: 267177984 11:09:49 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 11:09:51 INFO - PROCESS | 1664 | RSS: Main: 268865536 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | __start_tp_report 11:09:51 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 11:09:51 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 11:09:51 INFO - PROCESS | 1664 | |i|pagename|runs| 11:09:51 INFO - PROCESS | 1664 | |0;ai-astar;104;120;118;118;117;117;118;118;117;118 11:09:51 INFO - PROCESS | 1664 | |1;audio-beat-detection;148;114;115;153;115;116;117;114;145;114 11:09:51 INFO - PROCESS | 1664 | |2;audio-dft;186;201;203;209;239;258;222;197;239;257 11:09:51 INFO - PROCESS | 1664 | |3;audio-fft;83;60;64;100;84;85;59;59;79;61 11:09:51 INFO - PROCESS | 1664 | |4;audio-oscillator;85;71;88;74;70;71;88;71;71;90 11:09:51 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;91;90;91;89;92;122;93;91;92;85 11:09:51 INFO - PROCESS | 1664 | |6;imaging-darkroom;125;124;152;152;99;98;100;96;100;97 11:09:51 INFO - PROCESS | 1664 | |7;imaging-desaturate;91;95;91;91;92;93;93;91;94;92 11:09:51 INFO - PROCESS | 1664 | |8;json-parse-financial;58;57;57;58;59;58;57;56;59;57 11:09:51 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;67;64;85;117;105;74;70;69;71;103 11:09:51 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;90;104;123;82;99;81;89;110;109;110 11:09:51 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;156;151;150;154;162;164;170;157;186;179 11:09:51 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;213;245;229;185;175;177;177;202;175;163 11:09:51 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;72;72;69;69;73;71;71;70;69;69 11:09:51 INFO - PROCESS | 1664 | __end_tp_report 11:09:51 INFO - PROCESS | 1664 | __start_cc_report 11:09:51 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,954 11:09:51 INFO - PROCESS | 1664 | __end_cc_report 11:09:51 INFO - PROCESS | 1664 | __startTimestamp1476122991511__endTimestamp 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | ------- Summary: start ------- 11:09:51 INFO - PROCESS | 1664 | Number of tests: 14 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:116.50 Median:118.00 stddev:4.48 (3.8%) stddev-sans-first:0.93 11:09:51 INFO - PROCESS | 1664 | Values: 104.0 120.0 118.0 118.0 117.0 117.0 118.0 118.0 117.0 118.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:125.10 Median:116.50 stddev:16.40 (14.1%) stddev-sans-first:15.16 11:09:51 INFO - PROCESS | 1664 | Values: 148.0 114.0 115.0 153.0 115.0 116.0 117.0 114.0 145.0 114.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:221.10 Median:230.50 stddev:25.78 (11.2%) stddev-sans-first:24.02 11:09:51 INFO - PROCESS | 1664 | Values: 186.0 201.0 203.0 209.0 239.0 258.0 222.0 197.0 239.0 257.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:73.40 Median:71.50 stddev:14.58 (20.4%) stddev-sans-first:15.05 11:09:51 INFO - PROCESS | 1664 | Values: 83.0 60.0 64.0 100.0 84.0 85.0 59.0 59.0 79.0 61.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:79.50 stddev:8.62 (10.8%) stddev-sans-first:8.75 11:09:51 INFO - PROCESS | 1664 | Values: 85.0 71.0 88.0 74.0 70.0 71.0 88.0 71.0 71.0 90.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:91.00 stddev:10.22 (11.2%) stddev-sans-first:10.80 11:09:51 INFO - PROCESS | 1664 | Values: 91.0 90.0 91.0 89.0 92.0 122.0 93.0 91.0 92.0 85.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:114.30 Median:124.00 stddev:22.59 (18.2%) stddev-sans-first:23.63 11:09:51 INFO - PROCESS | 1664 | Values: 125.0 124.0 152.0 152.0 99.0 98.0 100.0 96.0 100.0 97.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:92.50 stddev:1.42 (1.5%) stddev-sans-first:1.42 11:09:51 INFO - PROCESS | 1664 | Values: 91.0 95.0 91.0 91.0 92.0 93.0 93.0 91.0 94.0 92.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:0.97 (1.7%) stddev-sans-first:1.01 11:09:51 INFO - PROCESS | 1664 | Values: 58.0 57.0 57.0 58.0 59.0 58.0 57.0 56.0 59.0 57.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:82.50 Median:69.50 stddev:19.00 (27.3%) stddev-sans-first:19.31 11:09:51 INFO - PROCESS | 1664 | Values: 67.0 64.0 85.0 117.0 105.0 74.0 70.0 69.0 71.0 103.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:99.70 Median:81.50 stddev:13.87 (17.0%) stddev-sans-first:14.26 11:09:51 INFO - PROCESS | 1664 | Values: 90.0 104.0 123.0 82.0 99.0 81.0 89.0 110.0 109.0 110.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:162.90 Median:163.00 stddev:12.09 (7.4%) stddev-sans-first:12.56 11:09:51 INFO - PROCESS | 1664 | Values: 156.0 151.0 150.0 154.0 162.0 164.0 170.0 157.0 186.0 179.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.10 Median:193.50 stddev:27.07 (14.0%) stddev-sans-first:27.83 11:09:51 INFO - PROCESS | 1664 | Values: 213.0 245.0 229.0 185.0 175.0 177.0 177.0 202.0 175.0 163.0 11:09:51 INFO - PROCESS | 1664 | 11:09:51 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:71.00 stddev:1.51 (2.1%) stddev-sans-first:1.50 11:09:51 INFO - PROCESS | 1664 | Values: 72.0 72.0 69.0 69.0 73.0 71.0 71.0 70.0 69.0 69.0 11:09:51 INFO - PROCESS | 1664 | -------- Summary: end -------- 11:09:51 INFO - PROCESS | 1664 | 11:09:52 INFO - TEST-INFO | 1664: exit 0 11:09:52 INFO - TEST-OK | kraken | took 60670ms 11:09:52 INFO - SUITE-END | took 1175s 11:09:52 INFO - Completed test suite (00:19:36) 11:09:52 INFO - javascript benchmark 11:09:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7261.874290238562, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3374.510695949086, 3383.424817780977, 3407.591089069312, 3420.828958552072, 3431.3651974384757, 4261.893452663685, 5388.706710389508, 5516.668999860001, 5649.1526271059365, 5787.797427090053, 5698.886022279552, 5729.828105156842, 5732.656040637554, 5786.884262314762, 5824.912626310609, 4381.707396318078, 4413.786208792576, 5188.558972487343, 5220.895582088356, 5279.762410691528, 4385.934210986822, 5052.736815796051, 5320.14877619581, 5348.491893270133, 5415.458454154573, 4534.047849951481, 5132.28148059272, 5200.583953283766, 5235.214717792323, 5304.73476326186, 4934.481879402683, 5292.497212764782, 5298.602604804655, 5299.284596579451, 5332.946670533283, 5151.484851514856, 5174.922376164361, 5202.843914682566, 5204.973975130138, 5285.339332583427, 4605.37827393301, 5586.608937374385, 5648.3222013358245, 5787.102999035156, 5791.652500849962, 6111.257501106181, 6122.0, 6207.068939659087, 6234.532410069263, 6244.438000579969, 6231.127642130105, 6246.469050130744, 6253.3433989430905, 6264.21697287839, 6279.843003924892, 5661.688607126565, 5828.737706803204, 5899.203607513016, 5907.940920590782, 5957.195778569924, 5163.457836927144, 6204.069389591597, 6222.0, 6249.468795152463, 6337.714802833883, 6091.695415229309, 6116.0, 6150.231221097363, 6159.5072394208355, 6164.383561643889, 6088.786892458743, 6220.253569571681, 6240.282367527792, 6275.748970041147, 6282.905756413657, 6099.521645064636, 6120.9347721082095, 6128.258691089271, 6138.539609529304, 6208.534359922934, 6203.2556093268195, 6204.689765511796, 6236.6258024518675, 6247.5939063960695, 6249.250089989139, 4955.0, 4970.428400733889, 4971.1797553403285, 4988.226824842155, 5011.899762004739, 4329.935050974238, 4370.934435983463, 4374.03771170342, 4383.276759334738, 4499.820007199675, 5777.306723193244, 5798.565107616945, 5822.446867547576, 5842.357340692521, 5938.2280303560265, 6164.784232551929, 6212.285587157535, 6215.844603884849, 6250.218722659668, 6257.311695713468, 5671.291088613923, 5683.914741278884, 5724.484796368347, 5727.341355744059, 5827.038538641077, 5417.8103766367985, 5427.0773968425465, 5456.781728730806, 5464.098423760035, 5482.095454250084, 5300.416954135119, 6342.175517182736, 6360.77737279193, 6371.490280777527, 6377.585456945284, 6102.701334035014, 6153.953827849186, 6155.015197568547, 6192.349803270592, 6222.346653601488, 6225.159603453657, 6341.682915854281, 6362.904556431749, 6384.585001974857, 6415.518836087405, 5255.211718242142, 6001.4298641628975, 6157.230346206724, 6184.412480814315, 6244.063390491463, 6127.678242056131, 6147.586055207416, 6167.994727697612, 6213.502919766317, 6247.312795592572, 5881.235439393023, 6069.666168360606, 6089.360617135137, 6125.28353168067, 6161.476274516716, 5072.594192464668, 5737.713114344349, 6110.749897012629, 6126.426068710427, 6136.0, 5512.476314750093, 5733.0, 5926.348101708895, 5932.525397968238, 5992.910106348321, 4957.603391728581, 6071.0, 6133.969330153321, 6141.332587233645, 6147.170132032118, 6012.789552365646, 6022.2171117754415, 6027.156198132353, 6039.245094363205, 6039.667818270037], "value": 5643.865174212839, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.2250334484892, 523.4085483403754, 536.5734241278186, 542.576790103719, 544.9917652343163, 38303.23393532131, 39716.404253936176, 39759.80120099403, 39819.800900995455, 41978.790106049506, 41102.588974110324, 41673.7916310418, 41784.37323440151, 41802.58197418025, 41899.0, 9655.5172241388, 9719.076687714656, 9802.676638653762, 9841.50792460374, 9862.506874656274, 9652.324337296392, 9694.757631059245, 9701.611935522571, 9736.659216927408, 9739.415635061883, 9551.761205969837, 9625.37435066722, 9671.274654400913, 9742.415455072683, 9766.853497197548, 9681.70954871355, 9700.078492543196, 9709.563069661812, 9739.318247722662, 9764.365316254492, 8624.698135565224, 9643.27675424339, 9675.274354423376, 9683.806323873554, 9685.32202745808, 39206.803965980274, 39412.802935985426, 39498.80250598757, 39602.20795584072, 39638.0, 3250.2524419383676, 3279.1310302769784, 3323.0, 3334.7999120052873, 3429.9142521436916, 30008.699913000808, 30564.235894102603, 30886.691133088607, 31003.534946975815, 31379.686203137906, 2956.9408611827857, 2972.687867773896, 2977.1217490840254, 3063.969360306413, 3069.815811051344, 381085.0, 386218.0, 386388.0, 387704.0, 388374.0581297104, 56241.43758562444, 56496.43503564994, 56550.71724641351, 56624.0, 56684.43315566874, 58012.41987580155, 58386.708066459396, 58497.0, 58702.412975870546, 58744.0, 14799.0, 15297.770533442008, 15649.452269170524, 15827.129507876947, 15872.52382428506, 12345.0, 12367.93816030914, 12412.193207441487, 12415.75168496625, 12419.75160496785, 14455.85544144545, 15524.0, 15735.560988046871, 15742.606434839221, 15862.920685396499, 3195.8721651133696, 3254.0, 6224.875502489925, 6398.584092034096, 6413.422791948654, 755.3277582951177, 851.2679095977454, 899.9325050621229, 962.3023308101571, 1058.5924419098703, 25452.981880724936, 26296.73703262943, 28838.0, 31443.37113257722, 33578.985260883936, 782.8669126248551, 999.9650361875424, 1035.471909326234, 1058.5977328615231, 1142.9028532574823, 12351.567695130807, 14803.703925921636, 15338.463153789564, 15443.602407470338, 15721.68556628861, 662126.0, 675765.0, 683597.0, 685229.0, 687422.0, 56235.0, 56480.717596411756, 57203.85592288131, 57305.71347143321, 57450.0, 1336.1582203211915, 1390.1520072755611, 1416.3980308368903, 1420.9857901421267, 1424.4373472478312, 28665.28336791597, 29410.264743381587, 29550.96571619983, 30229.697703022688, 30850.228744281572, 50853.0, 51643.0, 51678.741606291725, 51708.22437663513, 51800.96398072093, 619008.0, 622571.0, 623833.8808305929, 624531.0, 625609.0, 40102.398464023645, 41185.38221926614, 41565.584344156174, 41604.0, 41889.0, 45576.544234558554, 45790.85522861955, 47092.76453617847, 47305.526944731486, 47656.52343476521, 37919.62080379161, 38594.80702596581, 38663.8400847975, 38769.6123038766, 39994.40008399818, 37907.81046094752, 38350.0, 38514.614853851104, 39096.0, 39290.0, 36670.266594668494, 37217.4417383734, 37359.06602334855, 38077.048073798374, 38273.0, 33016.0, 34021.829890851375, 34287.79992700244, 34443.4833477493, 34871.5949027891, 24885.502289953736, 25221.74778252194, 25433.0, 25454.490910182063, 25994.350141245865], "value": 19598.02932901175, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.68862155789134, 371.4020427112349, 377.8488604558178, 377.91496913194527, 399.14383647077, 19438.0, 19973.800261997374, 20131.19475220993, 20217.696734548994, 20219.39341819746, 18048.18783154758, 19311.517212069706, 19731.309404170857, 20235.595288094228, 20372.592548149027, 4059.654929330996, 4838.9838133991725, 4855.5387238212315, 5151.201563757623, 5635.6055076144685, 5864.2962844458725, 5942.138389933457, 6040.939590604104, 6058.273007239138, 6086.2087928569445, 5275.920861187085, 5688.544916406699, 5856.648601083949, 5867.1492633568105, 5981.312149102843, 5842.444967728059, 5908.1433192187105, 5917.88164236715, 5929.1995580596495, 5951.553633477463, 5074.31496747938, 5145.742712864379, 5382.569394448474, 5406.54044406226, 5498.065328894098, 12699.428525716272, 12853.742925141445, 12981.286029268387, 13056.804147937788, 13088.607341779762, 2141.539568992674, 2332.1103370419282, 2452.227548322273, 2676.317539027555, 2871.583620375034, 9168.724938251864, 9757.707268781947, 10195.337303075275, 12879.227246365248, 14426.567202983928, 1819.7270409438559, 1827.1138497828542, 1834.5780470491861, 1854.9443516694387, 1859.0797555210122, 112475.43762281218, 116262.41868790686, 117419.41290293494, 120290.38402887838, 120372.0, 11272.098232141409, 11303.943480282545, 11567.652970410902, 11571.0, 11971.0, 14388.0, 14392.913027611146, 14401.0, 14417.125773649359, 14455.349509272108, 8156.184381561797, 8317.625706843206, 8365.0, 8410.915890841135, 8525.57372131391, 8356.958215209006, 8387.916120838836, 8426.53654049021, 8525.957370213233, 8575.914240857512, 4408.867733967986, 4478.373027776114, 4497.662675299365, 4557.384753058361, 4575.611073058827, 1750.7023805953017, 1783.7157246782297, 1856.7626010657395, 1918.3573502876693, 1929.922803087889, 855.6278019061729, 857.8970523537215, 897.4660077254024, 930.5114814722325, 968.6245531345365, 20717.482062948246, 20856.791432085483, 20914.686279705817, 20956.37130886076, 21046.158153673987, 764.8623247815335, 899.6176624934377, 907.6055552234595, 918.3296193778579, 935.5976929920064, 10417.531211095518, 10979.011888930034, 11017.008469237648, 11035.889641103486, 11071.335719856834, 570005.0, 602012.0, 612964.9351753301, 613150.934245326, 614538.0, 120662.19006714906, 129785.35107324403, 130449.0, 131776.0, 137446.0, 803.5982008995502, 913.4701872913694, 915.6657819895823, 949.2690628216235, 950.0357137505438, 18324.54188645241, 18573.90713046426, 18586.349477768214, 18611.534711631775, 18661.626767464848, 40704.0, 54345.45654543562, 55142.44857551373, 55248.0, 56440.7177964124, 72816.63591682007, 75167.0, 75550.8667369979, 75685.86471202827, 76560.0, 7801.843363989549, 7813.453058285865, 7823.452358334862, 7978.122406535161, 8013.078495972804, 10060.899391006289, 11879.58421455245, 12261.386930653252, 12393.070519710878, 12471.93764031174, 10189.133923616575, 10840.512176952214, 10842.566297348334, 10976.176786740867, 10993.780124397308, 9556.044395560388, 10734.516946737573, 10850.511726972463, 11037.337759734442, 11070.0, 9948.502574871372, 11111.277766945122, 11466.0, 11489.138314626603, 11532.654020379403, 7999.840003199787, 8003.4797738146835, 8169.959150204211, 8354.58227088631, 8384.70653527148, 9858.0, 10940.234183607072, 11212.0, 11262.774744505228, 11400.429978500875], "value": 9251.42236630287, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.6518118242216, 1516.0979217365666, 1598.5843680643045, 1762.2598508626377, 1883.52911772057, 8148.266656000958, 8413.495190288588, 8678.566071696421, 9255.722328330152, 9255.814883702322, 7711.0, 8650.697225597105, 8990.775230619238, 9007.414518056321, 9117.407368521059, 2347.6478528220778, 2376.572217000939, 2390.7370189279163, 2393.652920326552, 2399.700037495313, 2333.7549557296493, 2366.183666635017, 2366.4202270443766, 2366.5800519688137, 2384.380061184096, 2314.0628045641542, 2329.1615018593293, 2331.988340058297, 2358.988205058972, 2369.680093187415, 2344.3553022918663, 2344.824138189634, 2359.127122964497, 2360.150345875475, 2364.8817559121976, 2335.019291897407, 2338.6024375855977, 2338.7427382987858, 2339.391758142878, 2344.8358614896965, 9108.362414630978, 9176.770580735469, 9181.816363672757, 9195.0, 9202.677906273248, 4511.819527218908, 5244.344456942882, 5338.252644629755, 5380.3274590676165, 5408.756605952742, 2804.495190865664, 3529.6646818552194, 3575.803330816804, 3634.182308980485, 3639.6178401267753, 1038.657243109772, 1039.4023436524028, 1048.1516306230528, 1048.4810019040547, 1060.3002018667719, 11679.0, 11824.645260642195, 11951.223170493977, 11965.46155423008, 12100.334481603597, 4594.70134441267, 4628.74541900192, 4833.564979151823, 4840.322354870321, 4845.0067736114715, 8552.44409113418, 10234.33476824004, 10235.744106397251, 10295.227857910686, 10318.742031449123, 5350.143976963667, 5384.273123128406, 5387.595930305164, 5400.513953744182, 5401.081816091274, 4420.717991782347, 4557.15692596864, 4745.976270118696, 4870.196417591129, 4931.975340123348, 3912.941305880414, 3914.0410599402758, 3960.5643379228263, 4069.104796944667, 4086.5096188457574, 1082.9404382759024, 1275.2667216350635, 1284.07546566472, 1293.1206779390104, 1302.6482849630736, 768.4006474949548, 803.7508372404574, 911.4212475078373, 919.4391421233042, 937.5921474158627, 5396.622236443412, 5737.684427356536, 5863.882722345529, 5864.237649105589, 6040.486558642477, 427.53398795313257, 435.9106383191438, 436.93009118541517, 444.83096423358916, 445.56112229453964, 3652.0139562318027, 3836.290286297044, 4027.690371302678, 4066.1867626474823, 4109.034376921421, 10731.678049658523, 11001.779964400826, 11092.445377731081, 11124.944375278072, 11125.0, 11076.556937722562, 11103.888961110286, 11147.72130696739, 11148.219624626197, 11152.219344645959, 7380.741674041383, 7935.603219838985, 8177.223163799429, 8407.327413806881, 8423.663053477792, 5743.483086522233, 5844.15259787337, 5909.674967876808, 5973.1338955852025, 5973.731182096729, 8746.562671866508, 10180.778306602959, 10313.381197128196, 10318.071373576115, 10325.122364599094, 8165.836683266182, 8660.913390866011, 10062.195024397884, 10103.24225683062, 10474.952504749768, 7455.776326710207, 7555.622218889143, 7569.205233450629, 7640.73257435987, 7665.540067595962, 7210.855782884418, 7463.3656139226605, 8183.5499047550575, 8354.373421993254, 8407.831843363221, 6652.002199669894, 6765.714514242278, 7299.4160467163565, 7333.339999400079, 7502.174760776419, 7206.387457065998, 7385.667644956097, 7472.738454154079, 7546.698132074655, 7616.428767842323, 6995.720171193096, 7029.29707029293, 7164.426845852424, 7195.6761945713615, 7215.2063273038875, 4949.9110195756875, 5161.122609156378, 5172.741362931763, 5192.1952097424955, 5258.395284542326, 5159.277701121771, 5303.337082864642, 5318.787248510171, 5401.891962160814, 5485.4788795064405], "value": 4499.944647605683, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.75861552217629, 305.6179775280899, 309.1035995612723, 312.5592913991271, 314.05469536694545, 4641.675082744209, 5583.246261754667, 5626.774929002845, 5642.661440313584, 5661.60368774186, 5479.7944452220545, 5496.422875598065, 5528.723563821813, 5543.61194716369, 5582.720863956806, 3960.72274940755, 4030.576789437096, 4067.2068946555432, 4086.3053280942163, 4098.692598055143, 4110.486189226347, 4124.339800828464, 4155.252054630166, 4160.916781664365, 4163.354680024601, 3743.1390780120587, 4101.323281658522, 4129.298019336705, 4151.418801367811, 4153.750774953497, 4035.47538819953, 4105.835766569334, 4110.609492098246, 4141.64795992341, 4161.91676166478, 3228.935421291561, 3531.187826799825, 3557.1818481749083, 3568.1793187566745, 3572.5712914450173, 4321.546237645033, 4912.975435122801, 5251.737413129328, 5272.0, 5291.444398338196, 4146.357314616239, 4352.564743525654, 4724.464549021582, 4724.503927087675, 4766.73782941938, 5342.103553238583, 5686.431356864322, 5713.6000479966415, 5749.540036797046, 5755.668954073565, 3405.8637654494037, 3451.3941973809865, 3919.4708714323488, 3930.862419815321, 3953.133977866024, 5211.609129315279, 5780.8843823123725, 6029.758809647609, 6038.879222415527, 6087.391260873921, 3868.2263547290654, 3998.0604557928864, 3999.34010888206, 4014.598540145962, 4014.9761810738073, 4057.69567282455, 4069.633732963988, 4076.5515793262716, 4080.326746086922, 4092.2224777292217, 3935.4490371348033, 3986.4219688145636, 4693.155232058262, 4735.266033764703, 4739.0, 4670.8365207217585, 5441.918371224435, 5684.516816070681, 5792.218050563204, 5871.7651293947765, 1374.1960952842499, 1396.1623026184209, 1397.196611948134, 1399.9650008749659, 1400.8739213470838, 801.2668408406244, 850.6050077872294, 861.9138086191331, 864.5590748718199, 864.9048604653483, 5312.442193569696, 5351.81268655603, 5501.834944951658, 5514.648911016827, 5561.499465048165, 4843.975780121077, 5520.309961254843, 5632.746526406321, 5671.773129074791, 6049.818505444844, 4646.512116227747, 5008.873003574166, 5123.513266239701, 5324.9733751331, 5539.335279766454, 3093.2421556718746, 3159.842007899596, 3161.8577164027674, 3168.8574014169417, 3175.285560748859, 5662.546996240291, 6109.877802444015, 6238.937610623926, 6275.403836635513, 6325.525585581007, 5390.21841832932, 5413.2692086568595, 5765.07758758605, 6171.5988460750805, 6202.472789812826, 5005.524475174852, 6312.999999999908, 6321.367863213642, 6324.3043265240785, 6360.554761166859, 4888.755562221946, 5216.37403511581, 5219.817306394258, 5847.6668299070825, 6072.817815465543, 5951.404859514188, 5963.761449541969, 5981.89334973014, 6019.307779605401, 6023.548233882389, 4745.905081898273, 5726.026575482095, 5890.14592884038, 5972.762170224177, 6313.454312894709, 3258.1365938026916, 3313.652066533076, 3399.94900076504, 3952.9209415810947, 3962.4254483100367, 4865.318855360181, 5705.771769129188, 5961.880762384815, 6031.155638210566, 6107.267127944675, 4849.599448273128, 5564.972175139098, 5871.941280587311, 5876.41235876409, 5877.206577112206, 4579.358889755504, 5524.889502209853, 5750.396208398058, 5800.970995144998, 5814.651120932757, 5230.391030189157, 5480.410680902493, 5676.262085928803, 5764.538836893123, 5769.990251706018, 4963.528464795839, 5365.785368585213, 5523.585731070266, 5690.203371527907, 5812.564057695774, 4945.826896058621, 4961.231009193581, 5353.812616558402, 5713.742976545153, 5759.884802304015], "value": 4180.7300409690715, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.1056610557118, 704.0882057735232, 715.3704739828952, 754.9169591344954, 784.157031191469, 15306.69386612267, 16347.346106155754, 16368.099754513498, 16401.343946242137, 16402.17989100546, 14693.118412895234, 14710.48513302032, 14849.406023759064, 14857.182854942974, 14876.553703388889, 667.6027763480721, 694.0872752330669, 818.467995802727, 826.4958375391006, 845.222395396237, 834.2991886815074, 852.7399143261302, 853.9103394143618, 854.5257382152903, 856.7643897928089, 892.6964831957133, 906.5965645287849, 907.3059109781022, 907.6006557114881, 908.0465511213233, 895.2390468102126, 899.9190072893439, 901.6889173235256, 903.2142036428284, 903.8644203369481, 539.962202645815, 616.7902913009598, 623.5853157650157, 666.7133132752914, 673.007314211539, 13014.828665420158, 14049.64875878101, 14776.48282310114, 15075.472358467401, 15136.016158949744, 13330.200187988752, 14624.341904614317, 14655.34050967709, 14724.779128313085, 14830.629234269121, 11739.178257521977, 11910.285382876968, 11983.161178717493, 12000.21998570099, 12016.098792590503, 3793.525809273841, 3823.3691440912216, 3836.55879573847, 3849.826757795906, 3878.3600705883496, 79956.60021699971, 115756.42121789337, 116647.41676291564, 117233.41383293114, 119344.0, 11365.602203922823, 11525.308481491138, 11525.827112593317, 11606.825897611543, 11955.498756699417, 23726.644100338508, 23759.049638014632, 23812.04751809943, 23822.88088559581, 23859.92630331639, 8063.556504392197, 8234.752957411287, 8349.874751878726, 8434.873476897852, 8439.873401898976, 4195.183713754509, 4443.000324926866, 4455.1312494064, 4521.909561808746, 4559.156556037077, 3391.745619078579, 3508.877189298413, 3537.1510837398837, 3540.46892966052, 3549.1304630365207, 554.8668319603347, 646.1858058845794, 648.3322178156507, 669.4142625202948, 672.4116398151617, 513.3531749995033, 524.908141075318, 528.3527678593769, 530.9946900531022, 600.6515372987584, 7144.9285507145305, 7585.862120681832, 8151.274289037718, 8979.0, 8999.955000224956, 503.6424138861376, 545.3455852976444, 563.259314002086, 603.8309273403366, 685.3523420367704, 3972.7616343019513, 4367.868963931087, 4657.231556793091, 4664.65015123867, 4761.262004389325, 146026.26986864998, 149933.25033374762, 150372.99254014858, 150538.9461053908, 150749.0, 119676.0, 123427.0, 127132.0, 129280.0, 130220.0, 94207.0, 111727.44136279267, 112610.0, 113131.43434282776, 116008.0, 7254.528455650366, 9036.683716069905, 9065.501397423204, 9264.305177111879, 9342.859857102014, 48367.0, 62071.44821379502, 62444.68777656083, 62834.0, 62839.6858015707, 91288.0, 91325.0, 92511.53744231505, 93652.0, 98566.5071674637, 11509.13681473876, 11551.0, 11795.528178873092, 11925.10561707858, 12167.574134905235, 11319.493297011877, 12813.167144135601, 12947.964162867136, 13209.735805284034, 13681.521146759816, 9578.521073946415, 10638.946805266234, 10899.509522071367, 11735.706607334543, 11837.934585887311, 12271.325077120844, 12529.874701252871, 12544.49822007146, 13112.475500979854, 13739.656508587364, 10782.568697252022, 13551.457941682222, 13587.456501740215, 13815.86184138186, 13997.930010349883, 11303.0, 11365.204435689422, 11379.032782213604, 11596.710082248012, 11783.528658853893, 7930.0, 10786.298890572089, 11523.423828808694, 11691.421658076051, 11718.531258749554], "value": 7617.121810696735, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1581.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 120.0, 118.0, 118.0, 117.0, 117.0, 118.0, 118.0, 117.0, 118.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 115.0, 153.0, 115.0, 116.0, 117.0, 114.0, 145.0, 114.0], "value": 125.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 201.0, 203.0, 209.0, 239.0, 258.0, 222.0, 197.0, 239.0, 257.0], "value": 221.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 64.0, 100.0, 84.0, 85.0, 59.0, 59.0, 79.0, 61.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 88.0, 74.0, 70.0, 71.0, 88.0, 71.0, 71.0, 90.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 89.0, 92.0, 122.0, 93.0, 91.0, 92.0, 85.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 124.0, 152.0, 152.0, 99.0, 98.0, 100.0, 96.0, 100.0, 97.0], "value": 114.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 91.0, 91.0, 92.0, 93.0, 93.0, 91.0, 94.0, 92.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 58.0, 59.0, 58.0, 57.0, 56.0, 59.0, 57.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 85.0, 117.0, 105.0, 74.0, 70.0, 69.0, 71.0, 103.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 104.0, 123.0, 82.0, 99.0, 81.0, 89.0, 110.0, 109.0, 110.0], "value": 99.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 151.0, 150.0, 154.0, 162.0, 164.0, 170.0, 157.0, 186.0, 179.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 245.0, 229.0, 185.0, 175.0, 177.0, 177.0, 202.0, 175.0, 163.0], "value": 194.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 69.0, 69.0, 73.0, 71.0, 71.0, 70.0, 69.0, 69.0], "value": 70.5, "unit": "score"}]}]} 11:09:52 INFO - Return code: 0 11:09:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:09:52 ERROR - # TBPL SUCCESS # 11:09:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:09:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:09:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:09:52 INFO - 2016-10-10 11:09:52.837 screenresolution[1669:10875] starting screenresolution argv=screenresolution get 11:09:52 INFO - 2016-10-10 11:09:52.839 screenresolution[1669:10875] Display 0: 1600x1200x32@60 11:09:52 INFO - 2016-10-10 11:09:52.845 screenresolution[1670:10878] starting screenresolution argv=screenresolution list 11:09:52 INFO - Available Modes on Display 0 11:09:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:09:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:09:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:09:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:09:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:09:53 INFO - Intel Iris: 11:09:53 INFO - Chipset Model: Intel Iris 11:09:53 INFO - Type: GPU 11:09:53 INFO - Bus: Built-In 11:09:53 INFO - VRAM (Dynamic, Max): 1536 MB 11:09:53 INFO - Vendor: Intel (0x8086) 11:09:53 INFO - Device ID: 0x0a2e 11:09:53 INFO - Revision ID: 0x0009 11:09:53 INFO - Displays: 11:09:53 INFO - Display: 11:09:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:09:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:09:53 INFO - Display Serial Number: 1600x1200 60 11:09:53 INFO - Main Display: Yes 11:09:53 INFO - Mirror: Off 11:09:53 INFO - Online: Yes 11:09:53 INFO - Rotation: Supported 11:09:53 INFO - Return code: 0 11:09:53 INFO - Running post-action listener: _resource_record_post_action 11:09:53 INFO - [mozharness: 2016-10-10 18:09:53.080967Z] Finished run-tests step (success) 11:09:53 INFO - Running post-run listener: _resource_record_post_run 11:09:53 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 39434752; Write bytes: 710479360; Read time: 417; Write time: 2091 11:09:53 INFO - TinderboxPrint: CPU usage
54.5% 11:09:53 INFO - TinderboxPrint: I/O read bytes / time
39,434,752 / 417 11:09:53 INFO - TinderboxPrint: I/O write bytes / time
710,479,360 / 2,091 11:09:53 INFO - TinderboxPrint: CPU idle
2,203.6 (45.5%) 11:09:53 INFO - TinderboxPrint: CPU system
980.1 (20.2%) 11:09:53 INFO - TinderboxPrint: CPU user
1,661.9 (34.3%) 11:09:53 INFO - TinderboxPrint: Swap in / out
446,996,480 / 0 11:09:53 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395588096; Write bytes: 389953024; Read time: 29488; Write time: 652 11:09:53 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 37632512; Write bytes: 299345920; Read time: 399; Write time: 1397 11:09:53 INFO - Running post-run listener: _upload_blobber_files 11:09:53 INFO - Blob upload gear active. 11:09:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:09:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:09:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:09:53 INFO - (blobuploader) - INFO - Open directory for files ... 11:09:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:09:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:09:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:54 INFO - (blobuploader) - INFO - Done attempting. 11:09:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:09:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:09:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:56 INFO - (blobuploader) - INFO - Done attempting. 11:09:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:09:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:09:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:58 INFO - (blobuploader) - INFO - Done attempting. 11:09:58 INFO - (blobuploader) - INFO - Iteration through files over. 11:09:58 INFO - Return code: 0 11:09:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:09:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:09:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d3057a4d6d801b016e50c56628e5eae8ab0ea9db3fb79eec6cd274a1ba00c0e022b283fd1d2cdbbc16c46638a196f3137ae83caf9c75bab92a69307748b2824", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/76d6d92a39a2acfa510901c6c3758bc41fd3c75ce7f33c56040560101252dfc6301374f5b296d4e44a33d5e5ef9d5bcd7d0f22fb97b3d6c07a51dcd11c1b672e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a48363f5d191c0029e08c6e9392c5e759a32f2950324548c4b09ecb61fe5d6606a31148532b6eb0c5267f0654d6be4038b9d4f3e0cbb6205fe39ed5babf9bfb3"} 11:09:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:09:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:09:58 INFO - Contents: 11:09:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d3057a4d6d801b016e50c56628e5eae8ab0ea9db3fb79eec6cd274a1ba00c0e022b283fd1d2cdbbc16c46638a196f3137ae83caf9c75bab92a69307748b2824", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/76d6d92a39a2acfa510901c6c3758bc41fd3c75ce7f33c56040560101252dfc6301374f5b296d4e44a33d5e5ef9d5bcd7d0f22fb97b3d6c07a51dcd11c1b672e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a48363f5d191c0029e08c6e9392c5e759a32f2950324548c4b09ecb61fe5d6606a31148532b6eb0c5267f0654d6be4038b9d4f3e0cbb6205fe39ed5babf9bfb3"} 11:09:58 INFO - Running post-run listener: copy_logs_to_upload_dir 11:09:58 INFO - Copying logs to upload dir... 11:09:58 INFO - mkdir: /builds/slave/test/build/upload/logs 11:09:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.425410 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-10 11:09:58.353932) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 11:09:58.356959) ========= 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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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/Larch/sha512/6d3057a4d6d801b016e50c56628e5eae8ab0ea9db3fb79eec6cd274a1ba00c0e022b283fd1d2cdbbc16c46638a196f3137ae83caf9c75bab92a69307748b2824", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/76d6d92a39a2acfa510901c6c3758bc41fd3c75ce7f33c56040560101252dfc6301374f5b296d4e44a33d5e5ef9d5bcd7d0f22fb97b3d6c07a51dcd11c1b672e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a48363f5d191c0029e08c6e9392c5e759a32f2950324548c4b09ecb61fe5d6606a31148532b6eb0c5267f0654d6be4038b9d4f3e0cbb6205fe39ed5babf9bfb3"} build_url:https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011498 build_url: 'https://queue.taskcluster.net/v1/task/Nv6I7Ac3TTCJVU3u6PJqaA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d3057a4d6d801b016e50c56628e5eae8ab0ea9db3fb79eec6cd274a1ba00c0e022b283fd1d2cdbbc16c46638a196f3137ae83caf9c75bab92a69307748b2824", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/76d6d92a39a2acfa510901c6c3758bc41fd3c75ce7f33c56040560101252dfc6301374f5b296d4e44a33d5e5ef9d5bcd7d0f22fb97b3d6c07a51dcd11c1b672e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a48363f5d191c0029e08c6e9392c5e759a32f2950324548c4b09ecb61fe5d6606a31148532b6eb0c5267f0654d6be4038b9d4f3e0cbb6205fe39ed5babf9bfb3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 11:09:58.390259) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 11:09:58.390597) ========= 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.Z4n9rkpnRy/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.3jwNHxBVEy/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.005270 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 11:09:58.439537) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 11:09:58.439855) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 11:09:58.440178) ========= ========= Total master_lag: 0.19 =========