builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0390 starttime: 1478614019.06 results: success (0) buildid: 20161108030212 builduid: 5f4e42c5c6fb4ba2822ef60cdb11a2b3 revision: f13e90d496cf1bc6dfc4fd398da33e4afe785bde ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:59.062656) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:59.063177) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:59.063457) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:59.088789) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:59.089184) ========= 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.lTkunpRdJp/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.53hINyAt6T/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-08 06:06:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 71.3M=0s 2016-11-08 06:06:59 (71.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111342 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:59.222997) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:59.223388) ========= 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.lTkunpRdJp/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.53hINyAt6T/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.035729 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:59.280801) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-08 06:06:59.281175) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f13e90d496cf1bc6dfc4fd398da33e4afe785bde --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f13e90d496cf1bc6dfc4fd398da33e4afe785bde --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lTkunpRdJp/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.53hINyAt6T/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-08 06:06:59,368 truncating revision to first 12 chars 2016-11-08 06:06:59,368 Setting DEBUG logging. 2016-11-08 06:06:59,368 attempt 1/10 2016-11-08 06:06:59,368 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f13e90d496cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-08 06:07:00,373 unpacking tar archive at: mozilla-central-f13e90d496cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.250177 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-08 06:07:00.553668) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 06:07:00.554000) ========= script_repo_revision: f13e90d496cf1bc6dfc4fd398da33e4afe785bde ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 06:07:00.554490) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 06:07:00.554810) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 06:07:00.573560) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-08 06:07:00.573897) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lTkunpRdJp/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.53hINyAt6T/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:00 INFO - MultiFileLogger online at 20161108 06:07:00 in /builds/slave/test 06:07:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:07:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:07:00 INFO - {'append_to_log': False, 06:07:00 INFO - 'base_work_dir': '/builds/slave/test', 06:07:00 INFO - 'blob_upload_branch': 'Firefox', 06:07:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:07:00 INFO - 'branch': 'Firefox', 06:07:00 INFO - 'buildbot_json_path': 'buildprops.json', 06:07:00 INFO - 'config_files': ('talos/mac_config.py',), 06:07:00 INFO - 'default_actions': ('clobber', 06:07:00 INFO - 'read-buildbot-config', 06:07:00 INFO - 'download-and-extract', 06:07:00 INFO - 'populate-webroot', 06:07:00 INFO - 'create-virtualenv', 06:07:00 INFO - 'install', 06:07:00 INFO - 'run-tests'), 06:07:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:07:00 INFO - 'download_minidump_stackwalk': True, 06:07:00 INFO - 'download_symbols': 'ondemand', 06:07:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:07:00 INFO - 'tooltool.py': '/tools/tooltool.py', 06:07:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:07:00 INFO - '/tools/misc-python/virtualenv.py')}, 06:07:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:07:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:07:00 INFO - 'installer_path': 'installer.exe', 06:07:00 INFO - 'log_level': 'info', 06:07:00 INFO - 'log_name': 'talos', 06:07:00 INFO - 'log_to_console': True, 06:07:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:07:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:07:00 INFO - 'opt_config_files': (), 06:07:00 INFO - 'pip_index': False, 06:07:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:07:00 INFO - 'cmd': ('bash', 06:07:00 INFO - '-c', 06:07:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:07:00 INFO - 'enabled': True, 06:07:00 INFO - 'halt_on_failure': False, 06:07:00 INFO - 'name': 'check_screen_resolution'},), 06:07:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:07:00 INFO - 'cmd': ('bash', 06:07:00 INFO - '-c', 06:07:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:07:00 INFO - 'enabled': True, 06:07:00 INFO - 'halt_on_failure': False, 06:07:00 INFO - 'name': 'check_screen_resolution'},), 06:07:00 INFO - 'run_cmd_checks_enabled': True, 06:07:00 INFO - 'sps_profile': False, 06:07:00 INFO - 'sps_profile_interval': 0, 06:07:00 INFO - 'suite': 'dromaeojs-e10s', 06:07:00 INFO - 'system_bits': '32', 06:07:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:07:00 INFO - 'title': 't-yosemite-r7-0390', 06:07:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:07:00 INFO - 'use_talos_json': True, 06:07:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:07:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:07:00 INFO - 'work_dir': 'build'} 06:07:00 INFO - [mozharness: 2016-11-08 14:07:00.767337Z] Running clobber step. 06:07:00 INFO - Running pre-action listener: _resource_record_pre_action 06:07:00 INFO - Running main action method: clobber 06:07:00 INFO - rmtree: /builds/slave/test/build 06:07:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:07:02 INFO - Running post-action listener: _resource_record_post_action 06:07:02 INFO - [mozharness: 2016-11-08 14:07:02.985299Z] Finished clobber step (success) 06:07:02 INFO - [mozharness: 2016-11-08 14:07:02.985431Z] Running read-buildbot-config step. 06:07:02 INFO - Running pre-action listener: _resource_record_pre_action 06:07:02 INFO - Running main action method: read_buildbot_config 06:07:02 INFO - Using buildbot properties: 06:07:02 INFO - { 06:07:02 INFO - "project": "", 06:07:02 INFO - "product": "firefox", 06:07:02 INFO - "script_repo_revision": "production", 06:07:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:07:02 INFO - "repository": "", 06:07:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:07:02 INFO - "buildid": "20161108030212", 06:07:02 INFO - "stage_platform": "macosx64", 06:07:02 INFO - "basedir": "/builds/slave/test", 06:07:02 INFO - "nightly_build": "True", 06:07:02 INFO - "buildnumber": 4, 06:07:02 INFO - "slavename": "t-yosemite-r7-0390", 06:07:02 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 06:07:02 INFO - "platform": "yosemite_r7", 06:07:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:07:02 INFO - "branch": "mozilla-central", 06:07:02 INFO - "repo_path": "mozilla-central", 06:07:02 INFO - "revision": "f13e90d496cf1bc6dfc4fd398da33e4afe785bde", 06:07:02 INFO - "pgo_build": "False", 06:07:02 INFO - "builduid": "5f4e42c5c6fb4ba2822ef60cdb11a2b3", 06:07:02 INFO - "slavebuilddir": "test" 06:07:02 INFO - } 06:07:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 06:07:02 INFO - Running post-action listener: _resource_record_post_action 06:07:02 INFO - [mozharness: 2016-11-08 14:07:02.989271Z] Finished read-buildbot-config step (success) 06:07:02 INFO - [mozharness: 2016-11-08 14:07:02.989428Z] Running download-and-extract step. 06:07:02 INFO - Running pre-action listener: _resource_record_pre_action 06:07:02 INFO - Running main action method: download_and_extract 06:07:02 INFO - mkdir: /builds/slave/test/build/tests 06:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:02 INFO - trying https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 06:07:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/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 06:07:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/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 06:07:04 INFO - Downloaded 1199 bytes. 06:07:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:07:04 INFO - Using the following test package requirements: 06:07:04 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:07:04 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 06:07:04 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 06:07:04 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'jsshell-mac.zip'], 06:07:04 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 06:07:04 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:07:04 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 06:07:04 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 06:07:04 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 06:07:04 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 06:07:04 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:07:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:07:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:07:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:07:04 INFO - Http code: 200 06:07:04 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 06:07:05 INFO - x-amz-cf-id: Z8lPW19DhyuqImDClcYDImqqKpG5cAjMI3KG6GteH0buti4dsQIX1A== 06:07:05 INFO - x-amz-version-id: JvHEZkLDM1GkblODwVZ4tewZz5Ems8nR 06:07:05 INFO - Expected file size: 16310308 06:07:05 INFO - Obtained file size: 16310308 06:07:11 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 06:07:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:07:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:07:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:07:12 INFO - Http code: 200 06:07:12 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 06:07:12 INFO - x-amz-cf-id: nROJexYUy1ZTG47OffjkYFhhK6sAcRmHOiZ1UL5R7-X89Hb_f8_VWg== 06:07:12 INFO - x-amz-version-id: JvHEZkLDM1GkblODwVZ4tewZz5Ems8nR 06:07:12 INFO - Expected file size: 16310308 06:07:12 INFO - Obtained file size: 16310308 06:07:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 06:07:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:07:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 06:07:19 INFO - Http code: 200 06:07:19 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 06:07:19 INFO - x-amz-cf-id: 1djk5_e9pyFxAvuNWSMAx7SL5ukTTZ9xlY7zcXZFMO5QiYtHquG67Q== 06:07:19 INFO - x-amz-version-id: DBAksHU9dggF2xpUwS9AF5BUFF8fM2Bz 06:07:19 INFO - Expected file size: 14409038 06:07:19 INFO - Obtained file size: 14409038 06:07: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')]} 06:07:20 INFO - trying https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:07:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 06:07:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/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 06:07:23 INFO - Downloaded 111728953 bytes. 06:07:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:07:23 INFO - mkdir: /builds/slave/test/properties 06:07:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:07:23 INFO - Writing to file /builds/slave/test/properties/build_url 06:07:23 INFO - Contents: 06:07:23 INFO - build_url:https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:07:24 INFO - Running post-action listener: _resource_record_post_action 06:07:24 INFO - Running post-action listener: set_extra_try_arguments 06:07:24 INFO - [mozharness: 2016-11-08 14:07:24.013751Z] Finished download-and-extract step (success) 06:07:24 INFO - [mozharness: 2016-11-08 14:07:24.013916Z] Running populate-webroot step. 06:07:24 INFO - Running pre-action listener: _resource_record_pre_action 06:07:24 INFO - Running main action method: populate_webroot 06:07:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:07:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:07:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:07:24 INFO - '--tppagecycles', 06:07:24 INFO - '7'], 06:07:24 INFO - 'tests': ['tsvgm']}}, 06:07:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:07:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:07:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:07:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:07:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:24 INFO - 'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:24 INFO - 'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['damp', 'tps']}, 06:07:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:24 INFO - 'tests': ['damp', 'tps']}, 06:07:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['dromaeo_dom']}, 06:07:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:07:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['basic_compositor_video']}, 06:07:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:07:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'other-e10s': {'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['tsvgx', 06:07:24 INFO - 'tsvgr_opacity', 06:07:24 INFO - 'tart', 06:07:24 INFO - 'tscrollx', 06:07:24 INFO - 'cart']}, 06:07:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:07:24 INFO - 'tsvgr_opacity', 06:07:24 INFO - 'tart', 06:07:24 INFO - 'tscrollx', 06:07:24 INFO - 'cart']}, 06:07:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:24 INFO - 'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['tp5o']}, 06:07:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:24 INFO - 'tests': ['tp5o']}, 06:07:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:24 INFO - 'talos_options': ['--disable-e10s', 06:07:24 INFO - '--xperf_path', 06:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:24 INFO - 'tests': ['tp5n']}, 06:07:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:24 INFO - 'talos_options': ['--xperf_path', 06:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:24 INFO - 'tests': ['tp5n']}}, 06:07:24 INFO - 'talos.zip': {'path': '', 06:07:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:07:24 INFO - Running post-action listener: _resource_record_post_action 06:07:24 INFO - [mozharness: 2016-11-08 14:07:24.033121Z] Finished populate-webroot step (success) 06:07:24 INFO - [mozharness: 2016-11-08 14:07:24.033254Z] Running create-virtualenv step. 06:07:24 INFO - Running pre-action listener: _resource_record_pre_action 06:07:24 INFO - Running main action method: create_virtualenv 06:07:24 INFO - Creating virtualenv /builds/slave/test/build/venv 06:07:24 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 06:07:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:07:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:07:24 INFO - Using real prefix '/tools/python27' 06:07:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:07:24 INFO - Installing distribute.............................................................................................................................................................................................done. 06:07:27 INFO - Installing pip.................done. 06:07:27 INFO - Return code: 0 06:07:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:07:27 INFO - Reading from file tmpfile_stdout 06:07:27 INFO - Output received: 06:07:27 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) 06:07:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:27 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 0x108250490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b35a4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, '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.lTkunpRdJp/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.53hINyAt6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:27 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 06:07:27 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 06:07:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:07:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:27 INFO - 'HOME': '/Users/cltbld', 06:07:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:27 INFO - 'LOGNAME': 'cltbld', 06:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:27 INFO - 'MOZ_NO_REMOTE': '1', 06:07:27 INFO - 'NO_EM_RESTART': '1', 06:07:27 INFO - 'PAGER': '/bin/cat', 06:07:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:27 INFO - 'PWD': '/builds/slave/test', 06:07:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:27 INFO - 'SHELL': '/bin/bash', 06:07:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:07:27 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:07:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:27 INFO - 'USER': 'cltbld', 06:07:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:27 INFO - 'XPC_FLAGS': '0x0', 06:07:27 INFO - 'XPC_SERVICE_NAME': '0', 06:07:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:28 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 06:07:28 INFO - Cleaning up... 06:07:28 INFO - Return code: 0 06:07:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:07:28 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')]} 06:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:28 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')]} 06:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:28 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 0x108250490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b35a4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, '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.lTkunpRdJp/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.53hINyAt6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:28 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 06:07:28 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 06:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:28 INFO - 'HOME': '/Users/cltbld', 06:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:28 INFO - 'LOGNAME': 'cltbld', 06:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:28 INFO - 'MOZ_NO_REMOTE': '1', 06:07:28 INFO - 'NO_EM_RESTART': '1', 06:07:28 INFO - 'PAGER': '/bin/cat', 06:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:28 INFO - 'PWD': '/builds/slave/test', 06:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:28 INFO - 'SHELL': '/bin/bash', 06:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:07:28 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:28 INFO - 'USER': 'cltbld', 06:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:28 INFO - 'XPC_FLAGS': '0x0', 06:07:28 INFO - 'XPC_SERVICE_NAME': '0', 06:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:28 INFO - Downloading/unpacking psutil>=3.1.1 06:07:28 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 06:07:28 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 06:07:28 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 06:07:28 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 06:07:28 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 06:07:28 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 06:07:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:07:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:07:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:07:31 INFO - Installing collected packages: psutil 06:07:31 INFO - Running setup.py install for psutil 06:07:31 INFO - building 'psutil._psutil_osx' extension 06:07:31 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 06:07:32 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 06:07:32 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 06:07:32 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 06:07:32 INFO - building 'psutil._psutil_posix' extension 06:07:32 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 06:07:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:07:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:07:32 INFO - ^ 06:07:32 INFO - 1 warning generated. 06:07:32 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 06:07:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:07:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:07:32 INFO - Successfully installed psutil 06:07:32 INFO - Cleaning up... 06:07:33 INFO - Return code: 0 06:07:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108250490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b35a4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, '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.lTkunpRdJp/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.53hINyAt6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:33 INFO - 'HOME': '/Users/cltbld', 06:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:33 INFO - 'LOGNAME': 'cltbld', 06:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:33 INFO - 'MOZ_NO_REMOTE': '1', 06:07:33 INFO - 'NO_EM_RESTART': '1', 06:07:33 INFO - 'PAGER': '/bin/cat', 06:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:33 INFO - 'PWD': '/builds/slave/test', 06:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:33 INFO - 'SHELL': '/bin/bash', 06:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:07:33 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:33 INFO - 'USER': 'cltbld', 06:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:33 INFO - 'XPC_FLAGS': '0x0', 06:07:33 INFO - 'XPC_SERVICE_NAME': '0', 06:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:07:35 INFO - Installing collected packages: mozsystemmonitor 06:07:35 INFO - Running setup.py install for mozsystemmonitor 06:07:35 INFO - Successfully installed mozsystemmonitor 06:07:35 INFO - Cleaning up... 06:07:36 INFO - Return code: 0 06:07:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108250490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b35a4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, '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.lTkunpRdJp/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.53hINyAt6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:07:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:07:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:36 INFO - 'HOME': '/Users/cltbld', 06:07:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:36 INFO - 'LOGNAME': 'cltbld', 06:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:36 INFO - 'MOZ_NO_REMOTE': '1', 06:07:36 INFO - 'NO_EM_RESTART': '1', 06:07:36 INFO - 'PAGER': '/bin/cat', 06:07:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:36 INFO - 'PWD': '/builds/slave/test', 06:07:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:36 INFO - 'SHELL': '/bin/bash', 06:07:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:07:36 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:07:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:36 INFO - 'USER': 'cltbld', 06:07:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:36 INFO - 'XPC_FLAGS': '0x0', 06:07:36 INFO - 'XPC_SERVICE_NAME': '0', 06:07:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:36 INFO - Downloading/unpacking jsonschema==2.5.1 06:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:07:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:07:38 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 06:07:38 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 06:07:38 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 06:07:38 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 06:07:38 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 06:07:38 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 06:07:39 INFO - Downloading functools32-3.2.3-2.tar.gz 06:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:07:39 INFO - warning: no files found matching '*.txt' 06:07:39 INFO - no previously-included directories found matching 'build' 06:07:39 INFO - no previously-included directories found matching 'dist' 06:07:39 INFO - no previously-included directories found matching '.git*' 06:07:39 INFO - Installing collected packages: jsonschema, functools32 06:07:39 INFO - Running setup.py install for functools32 06:07:39 INFO - warning: no files found matching '*.txt' 06:07:39 INFO - no previously-included directories found matching 'build' 06:07:39 INFO - no previously-included directories found matching 'dist' 06:07:39 INFO - no previously-included directories found matching '.git*' 06:07:39 INFO - Successfully installed jsonschema functools32 06:07:39 INFO - Cleaning up... 06:07:39 INFO - Return code: 0 06:07:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108250490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b35a4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, '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.lTkunpRdJp/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.53hINyAt6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:07:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:07:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:39 INFO - 'HOME': '/Users/cltbld', 06:07:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:39 INFO - 'LOGNAME': 'cltbld', 06:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:39 INFO - 'MOZ_NO_REMOTE': '1', 06:07:39 INFO - 'NO_EM_RESTART': '1', 06:07:39 INFO - 'PAGER': '/bin/cat', 06:07:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:39 INFO - 'PWD': '/builds/slave/test', 06:07:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:39 INFO - 'SHELL': '/bin/bash', 06:07:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:07:39 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:07:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:39 INFO - 'USER': 'cltbld', 06:07:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:39 INFO - 'XPC_FLAGS': '0x0', 06:07:39 INFO - 'XPC_SERVICE_NAME': '0', 06:07:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:07:39 INFO - Cleaning up... 06:07:39 INFO - Return code: 0 06:07:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108250490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b35a4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, '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.lTkunpRdJp/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.53hINyAt6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:07:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:07:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:39 INFO - 'HOME': '/Users/cltbld', 06:07:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:39 INFO - 'LOGNAME': 'cltbld', 06:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:39 INFO - 'MOZ_NO_REMOTE': '1', 06:07:39 INFO - 'NO_EM_RESTART': '1', 06:07:39 INFO - 'PAGER': '/bin/cat', 06:07:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:39 INFO - 'PWD': '/builds/slave/test', 06:07:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:39 INFO - 'SHELL': '/bin/bash', 06:07:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:07:39 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:07:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:39 INFO - 'USER': 'cltbld', 06:07:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:39 INFO - 'XPC_FLAGS': '0x0', 06:07:39 INFO - 'XPC_SERVICE_NAME': '0', 06:07:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:40 INFO - Downloading/unpacking blobuploader==1.2.4 06:07:40 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 06:07:40 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 06:07:40 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 06:07:40 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 06:07:40 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 06:07:40 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 06:07:42 INFO - Downloading blobuploader-1.2.4.tar.gz 06:07:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:07:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:07:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:43 INFO - Downloading docopt-0.6.1.tar.gz 06:07:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:07:43 INFO - Installing collected packages: blobuploader, requests, docopt 06:07:43 INFO - Running setup.py install for blobuploader 06:07:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:07:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:07:44 INFO - Running setup.py install for requests 06:07:44 INFO - Running setup.py install for docopt 06:07:44 INFO - Successfully installed blobuploader requests docopt 06:07:44 INFO - Cleaning up... 06:07:44 INFO - Return code: 0 06:07:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:44 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 0x108250490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b35a4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, '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.lTkunpRdJp/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.53hINyAt6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:44 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 06:07:44 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 06:07:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:07:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:44 INFO - 'HOME': '/Users/cltbld', 06:07:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:44 INFO - 'LOGNAME': 'cltbld', 06:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:44 INFO - 'MOZ_NO_REMOTE': '1', 06:07:44 INFO - 'NO_EM_RESTART': '1', 06:07:44 INFO - 'PAGER': '/bin/cat', 06:07:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:44 INFO - 'PWD': '/builds/slave/test', 06:07:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:44 INFO - 'SHELL': '/bin/bash', 06:07:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:07:44 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:07:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:44 INFO - 'USER': 'cltbld', 06:07:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:44 INFO - 'XPC_FLAGS': '0x0', 06:07:44 INFO - 'XPC_SERVICE_NAME': '0', 06:07:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:45 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-0Zyamt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:45 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-DvaxZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:45 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-YcCneO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:45 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-KaG2oN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:45 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-rqUF9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:45 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-NP7HlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:45 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-uwPc27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:45 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-_Yb5gU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:45 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-R1ORDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:46 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-alC6sR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:46 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-0brpSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:46 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-e9uO3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:46 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-R6PgYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:46 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-No_O9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:46 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-WO4Obh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:46 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-BCi_qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:46 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-2w2nwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:07:46 INFO - Running setup.py install for manifestparser 06:07:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:07:47 INFO - Running setup.py install for mozcrash 06:07:47 INFO - Running setup.py install for mozdebug 06:07:47 INFO - Running setup.py install for mozdevice 06:07:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:07:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:07:47 INFO - Running setup.py install for mozfile 06:07:47 INFO - Running setup.py install for mozhttpd 06:07:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:07:47 INFO - Running setup.py install for mozinfo 06:07:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:07:47 INFO - Running setup.py install for mozInstall 06:07:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Running setup.py install for mozleak 06:07:48 INFO - Running setup.py install for mozlog 06:07:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Running setup.py install for moznetwork 06:07:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Running setup.py install for mozprocess 06:07:48 INFO - Running setup.py install for mozprofile 06:07:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Running setup.py install for mozrunner 06:07:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Running setup.py install for mozscreenshot 06:07:49 INFO - Running setup.py install for moztest 06:07:49 INFO - Running setup.py install for mozversion 06:07:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:07:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:07:49 INFO - Cleaning up... 06:07:49 INFO - Return code: 0 06:07:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:49 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')]} 06:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:49 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')]} 06:07:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:49 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 0x108250490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b35a4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, '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.lTkunpRdJp/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.53hINyAt6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:49 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 06:07:49 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 06:07:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:07:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:49 INFO - 'HOME': '/Users/cltbld', 06:07:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:49 INFO - 'LOGNAME': 'cltbld', 06:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:49 INFO - 'MOZ_NO_REMOTE': '1', 06:07:49 INFO - 'NO_EM_RESTART': '1', 06:07:49 INFO - 'PAGER': '/bin/cat', 06:07:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:49 INFO - 'PWD': '/builds/slave/test', 06:07:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:49 INFO - 'SHELL': '/bin/bash', 06:07:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:07:49 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:07:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:49 INFO - 'USER': 'cltbld', 06:07:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:49 INFO - 'XPC_FLAGS': '0x0', 06:07:49 INFO - 'XPC_SERVICE_NAME': '0', 06:07:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-HpyBL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:49 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)) 06:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-kn5W2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:49 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)) 06:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-2ZIlgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:50 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)) 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-4qgyk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:50 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)) 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-CqT7SL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:50 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)) 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-rd7vue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:50 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)) 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-wV5XFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:50 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)) 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Tx6cV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:50 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)) 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-y3bhyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:50 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)) 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-DSNoan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:50 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)) 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-g83lgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:50 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)) 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-gPbEhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:50 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)) 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-sHhdjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:51 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-YYJfzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:51 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-cbpX7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:51 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-RtlWeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:51 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-RZ47qW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:51 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)) 06:07:51 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)) 06:07:51 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)) 06:07:51 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)) 06:07:51 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)) 06:07:51 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)) 06:07:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:07:51 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 06:07:51 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 06:07:51 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 06:07:51 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 06:07:51 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 06:07:51 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 06:07:54 INFO - Downloading blessings-1.6.tar.gz 06:07:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:07:54 INFO - Installing collected packages: blessings 06:07:54 INFO - Running setup.py install for blessings 06:07:54 INFO - Successfully installed blessings 06:07:54 INFO - Cleaning up... 06:07:54 INFO - Return code: 0 06:07:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:07:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:54 INFO - Reading from file tmpfile_stdout 06:07:54 INFO - Current package versions: 06:07:54 INFO - blessings == 1.6 06:07:54 INFO - blobuploader == 1.2.4 06:07:54 INFO - docopt == 0.6.1 06:07:54 INFO - functools32 == 3.2.3-2 06:07:54 INFO - jsonschema == 2.5.1 06:07:54 INFO - manifestparser == 1.1 06:07:54 INFO - mozInstall == 1.12 06:07:54 INFO - mozcrash == 0.17 06:07:54 INFO - mozdebug == 0.1 06:07:54 INFO - mozdevice == 0.48 06:07:54 INFO - mozfile == 1.2 06:07:54 INFO - mozhttpd == 0.7 06:07:54 INFO - mozinfo == 0.9 06:07:54 INFO - mozleak == 0.1 06:07:54 INFO - mozlog == 3.4 06:07:54 INFO - moznetwork == 0.27 06:07:54 INFO - mozprocess == 0.23 06:07:54 INFO - mozprofile == 0.28 06:07:54 INFO - mozrunner == 6.12 06:07:54 INFO - mozscreenshot == 0.1 06:07:54 INFO - mozsystemmonitor == 0.3 06:07:54 INFO - moztest == 0.7 06:07:54 INFO - mozversion == 1.4 06:07:54 INFO - psutil == 3.1.1 06:07:54 INFO - requests == 1.2.3 06:07:54 INFO - wsgiref == 0.1.2 06:07:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:54 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')]} 06:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:55 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')]} 06:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:55 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 0x108250490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b35a4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, '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.lTkunpRdJp/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.53hINyAt6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:55 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 06:07:55 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 06:07:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:07:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:55 INFO - 'HOME': '/Users/cltbld', 06:07:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:55 INFO - 'LOGNAME': 'cltbld', 06:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:55 INFO - 'MOZ_NO_REMOTE': '1', 06:07:55 INFO - 'NO_EM_RESTART': '1', 06:07:55 INFO - 'PAGER': '/bin/cat', 06:07:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:55 INFO - 'PWD': '/builds/slave/test', 06:07:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:55 INFO - 'SHELL': '/bin/bash', 06:07:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:07:55 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:07:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:55 INFO - 'USER': 'cltbld', 06:07:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:55 INFO - 'XPC_FLAGS': '0x0', 06:07:55 INFO - 'XPC_SERVICE_NAME': '0', 06:07:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 INFO - Cleaning up... 06:07:55 INFO - Return code: 0 06:07:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:07:55 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')]} 06:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:55 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')]} 06:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:56 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 0x108250490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b35a4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, '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.lTkunpRdJp/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.53hINyAt6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 06:07:56 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 06:07:56 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 06:07:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:07:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:56 INFO - 'HOME': '/Users/cltbld', 06:07:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:56 INFO - 'LOGNAME': 'cltbld', 06:07:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:56 INFO - 'MOZ_NO_REMOTE': '1', 06:07:56 INFO - 'NO_EM_RESTART': '1', 06:07:56 INFO - 'PAGER': '/bin/cat', 06:07:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:56 INFO - 'PWD': '/builds/slave/test', 06:07:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:56 INFO - 'SHELL': '/bin/bash', 06:07:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:07:56 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:07:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:56 INFO - 'USER': 'cltbld', 06:07:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:56 INFO - 'XPC_FLAGS': '0x0', 06:07:56 INFO - 'XPC_SERVICE_NAME': '0', 06:07:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:07:56 INFO - Cleaning up... 06:07:56 INFO - Return code: 0 06:07:56 INFO - Running post-action listener: _resource_record_post_action 06:07:56 INFO - Running post-action listener: _start_resource_monitoring 06:07:56 INFO - Starting resource monitoring. 06:07:56 INFO - [mozharness: 2016-11-08 14:07:56.345248Z] Finished create-virtualenv step (success) 06:07:56 INFO - [mozharness: 2016-11-08 14:07:56.346227Z] Running install step. 06:07:56 INFO - Running pre-action listener: _resource_record_pre_action 06:07:56 INFO - Running main action method: install 06:07:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:56 INFO - Reading from file tmpfile_stdout 06:07:56 INFO - Detecting whether we're running mozinstall >=1.0... 06:07:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:07:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:07:56 INFO - Reading from file tmpfile_stdout 06:07:56 INFO - Output received: 06:07:56 INFO - Usage: mozinstall [options] installer 06:07:56 INFO - Options: 06:07:56 INFO - -h, --help show this help message and exit 06:07:56 INFO - -d DEST, --destination=DEST 06:07:56 INFO - Directory to install application into. [default: 06:07:56 INFO - "/builds/slave/test"] 06:07:56 INFO - --app=APP Application being installed. [default: firefox] 06:07:56 INFO - mkdir: /builds/slave/test/build/application 06:07:56 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'] 06:07:56 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 06:08:30 INFO - Reading from file tmpfile_stdout 06:08:30 INFO - Output received: 06:08:30 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:08:30 INFO - Running post-action listener: _resource_record_post_action 06:08:30 INFO - [mozharness: 2016-11-08 14:08:30.906104Z] Finished install step (success) 06:08:30 INFO - [mozharness: 2016-11-08 14:08:30.906268Z] Running run-tests step. 06:08:30 INFO - Running pre-action listener: _resource_record_pre_action 06:08:30 INFO - Running main action method: run_tests 06:08:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:30 INFO - 2016-11-08 06:08:30.920 screenresolution[1657:7497] starting screenresolution argv=screenresolution get 06:08:30 INFO - 2016-11-08 06:08:30.923 screenresolution[1657:7497] Display 0: 1600x1200x32@60 06:08:30 INFO - 2016-11-08 06:08:30.929 screenresolution[1658:7500] starting screenresolution argv=screenresolution list 06:08:30 INFO - Available Modes on Display 0 06:08:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:31 INFO - Intel Iris: 06:08:31 INFO - Chipset Model: Intel Iris 06:08:31 INFO - Type: GPU 06:08:31 INFO - Bus: Built-In 06:08:31 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:31 INFO - Vendor: Intel (0x8086) 06:08:31 INFO - Device ID: 0x0a2e 06:08:31 INFO - Revision ID: 0x0009 06:08:31 INFO - Displays: 06:08:31 INFO - Display: 06:08:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:31 INFO - Display Serial Number: 1600x1200 60 06:08:31 INFO - Main Display: Yes 06:08:31 INFO - Mirror: Off 06:08:31 INFO - Online: Yes 06:08:31 INFO - Rotation: Supported 06:08:31 INFO - Return code: 0 06:08:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:08:31 INFO - Python 2.7.3 06:08:31 INFO - Return code: 0 06:08:31 INFO - grabbing minidump binary from tooltool 06:08:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:31 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 0x108064d30>, '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 0x107e8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:08:31 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 06:08:31 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 06:08:31 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 06:08:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:08:31 INFO - Return code: 0 06:08:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:08:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:08:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:08:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:08:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:08:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 06:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0390 --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 06:08:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lTkunpRdJp/Render', 06:08:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:31 INFO - 'HOME': '/Users/cltbld', 06:08:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:31 INFO - 'LOGNAME': 'cltbld', 06:08:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:08:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:31 INFO - 'MOZ_NO_REMOTE': '1', 06:08:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:08:31 INFO - 'NO_EM_RESTART': '1', 06:08:31 INFO - 'PAGER': '/bin/cat', 06:08:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:31 INFO - 'PWD': '/builds/slave/test', 06:08:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:08:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:31 INFO - 'SHELL': '/bin/bash', 06:08:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53hINyAt6T/Listeners', 06:08:31 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 06:08:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:31 INFO - 'USER': 'cltbld', 06:08:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:31 INFO - 'XPC_FLAGS': '0x0', 06:08:31 INFO - 'XPC_SERVICE_NAME': '0', 06:08:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 06:08:31 INFO - mozversion application_buildid: 20161108030212 06:08:31 INFO - mozversion application_changeset: f13e90d496cf1bc6dfc4fd398da33e4afe785bde 06:08:31 INFO - mozversion application_display_name: Nightly 06:08:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:08:31 INFO - mozversion application_name: Firefox 06:08:31 INFO - mozversion application_remotingname: firefox 06:08:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:08:31 INFO - mozversion application_vendor: Mozilla 06:08:31 INFO - mozversion application_version: 52.0a1 06:08:31 INFO - mozversion platform_buildid: 20161108030212 06:08:31 INFO - mozversion platform_changeset: f13e90d496cf1bc6dfc4fd398da33e4afe785bde 06:08:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:08:31 INFO - mozversion platform_version: 52.0a1 06:08:31 INFO - using testdate: 1478614111 06:08:31 INFO - actual date: 1478614111 06:08:31 INFO - starting webserver on 'localhost:49266' 06:08:31 INFO - SUITE-START | Running 2 tests 06:08:31 INFO - TEST-START | dromaeo_css 06:08:31 INFO - operating with platform_type : mac_ 06:08:31 INFO - Initialising browser for dromaeo_css test... 06:08:31 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpM3anMO/profile http://localhost:49266/getInfo.html) 06:08:34 INFO - PROCESS | 1668 | 2016-11-08 06:08:34.658 plugin-container[1671:7675] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 06:08:34 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:34 INFO - PROCESS | 1668 | 2016-11-08 06:08:34.660 plugin-container[1671:7675] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 06:08:34 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:34 INFO - PROCESS | 1668 | 2016-11-08 06:08:34.660 plugin-container[1671:7675] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:08:34 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 06:08:34 INFO - PROCESS | 1668 | colorDepth:24 06:08:34 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 06:08:34 INFO - PROCESS | 1668 | __metrics 06:08:34 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 06:08:35 INFO - TEST-INFO | 1668: exit 0 06:08:35 INFO - Browser initialized. 06:08:35 INFO - Running cycle 1/1 for dromaeo_css test... 06:08:35 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpM3anMO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:38 INFO - PROCESS | 1675 | 2016-11-08 06:08:38.130 plugin-container[1676:7854] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 06:08:38 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:38 INFO - PROCESS | 1675 | 2016-11-08 06:08:38.131 plugin-container[1676:7854] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 06:08:38 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:38 INFO - PROCESS | 1675 | 2016-11-08 06:08:38.131 plugin-container[1676:7854] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:08:47 INFO - PROCESS | 1675 | RSS: Main: 237252608 06:08:47 INFO - PROCESS | 1675 | 06:09:37 INFO - PROCESS | 1675 | 1478614177489 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] 06:11:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 06:11:52 INFO - PROCESS | 1675 | RSS: Main: 253239296 06:11:52 INFO - PROCESS | 1675 | 06:14:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 06:14:55 INFO - PROCESS | 1675 | RSS: Main: 262295552 06:14:55 INFO - PROCESS | 1675 | 06:17:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 06:17:57 INFO - PROCESS | 1675 | RSS: Main: 267546624 06:17:57 INFO - PROCESS | 1675 | 06:20:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 06:21:00 INFO - PROCESS | 1675 | RSS: Main: 269389824 06:21:00 INFO - PROCESS | 1675 | 06:24:03 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 06:24:04 INFO - PROCESS | 1675 | RSS: Main: 273760256 06:24:04 INFO - PROCESS | 1675 | 06:27:06 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 06:27:07 INFO - PROCESS | 1675 | RSS: Main: 276447232 06:27:07 INFO - PROCESS | 1675 | 06:27:07 INFO - PROCESS | 1675 | __start_tp_report 06:27:07 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 06:27:07 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 06:27:07 INFO - PROCESS | 1675 | |i|pagename|runs| 06:27:07 INFO - PROCESS | 1675 | |0;dojo.html;2617.685877694677;3289.095498737847;3318.8838390656315;3326.933461330772;3346.9497957530634;5584.944150558493;5602.719864006794;5622.4939755422;5655.604107712452;5676.233708449358;4881.536254055859;5467.808626698066;5601.831945041645;5603.467670571299;5640.407757185498;5115.488451154892;5125.66683165594;5240.685558866461;5243.108671525831;5311.9468805311835;5111.974440127813;5193.065248255312;5222.112240919053;5236.136037553799;5249.343832020997;5132.101882170624;5233.9738301308635;5263.1578947368425;5271.762770675329;5291.735413229323;5223.1904054871175;5236.81671141512;5265.394479634852;5271.393789714193;5275.762590683428;4305;5057.418396884332;5063.721495317756;5075.6447048706605;5228.111221092423;5364.141737322048;5640.379558248589;5669.376368599451;5671.149327600851;5679.006173919547;6029.728662210211;6128.111423843562;6173.351798061184;6239.438450539473;6261.937380626181;6115.848865095079;6156.322804491502;6174.536909731788;6209.96895015522;6283.340249273806;5749.798757043483;5752.482276595085;5767.826965191051;5779.826605201851;5790.913136302959;6266.62400255977;6267.059941008794;6288.308286088527;6290.402411770966;6307.463865571386;4734.526547345238;5783.595148339576;5902.763889444374;6007.549433792483;6074.696265186723;5238.580913526908;6188.81433556994;6222.253329600387;6229.190205273286;6234.937650623527;4966.205407134841;6112.633242005494;6190.442860142519;6195.938040619626;6217.88078145929;5982.461578457873;6025.4577088061405;6071.241094863142;6088.756449742049;6212.099245609361;4883.536064073907;4885.853424397274;4900.289458028566;4904.166291730415;4940.654154209243;4396.648268138541;4420.31485119807;4432.2908334666945;4453.710508816916;4460.665450091256;5481.232627432163;5649.745761440745;5702.287214098238;5702.914456283159;5798.9130163047585;6241.843953901189;6256.280527739368;6263.655498947602;6276.780312689125;6327.651979141099;5804.651720896675;5811.622244554091;5836.737346819404;5841.503472204825;5882.794102206402;5168.457311982263;5297.602679799031;5318.601104917069;5363.0614642438195;5373.650712703662;6155.507559395238;6224.813255602339;6231.937680623227;6251.749930002931;6258.092576576462;6235.563510554218;6254.906176407267;6255.593386429868;6266.530010249158;6273.184486016971;6125.540584456273;6220.937790622036;6246.593971391845;6254.874902501833;6279.780207692709;5995.730192141277;6027.21646185993;6082.7262773174425;6121.173641558333;6121.418465245794;6194.690265486798;6220.906686399798;6227.0970709246;6236.126942228182;6264.874702506016;5801.506871915934;5874.530037597067;5955.76176952917;6024.427679370453;6057.939420605738;5894.469497745224;6015.819525414245;6063.120847477003;6078.331383547895;6117.051856962178;5403.703111253192;5565.471280228372;5844.035734103825;5905.202797622266;5949.8215053548465;5087.313212716236;5808.751118509541;5908.734106965283;5982.312034115958;6015.248093988252;5833.970830145992;5870.26621672291;5872.706364681834;5917.822465326047;5919.911201331897 06:27:07 INFO - PROCESS | 1675 | |1;ext.html;476.8188088526359;481.6748694631123;481.7302310706005;512.8640910158806;515.65966462135;36788;38232.85301440958;38236.80881595595;38301;38412.00643916287;40502.59497405025;40886.59113408865;40900;40998;41038.79480602593;9191.310651701098;9408.670696525623;9427.292953028516;9436.197923176538;9442.386244894094;9274.165325120737;9275.628974840998;9357.251419886426;9376.296777741663;9380.530973451334;9340;9397.201237894787;9450.19673327768;9460.24318054554;9468.57391417388;9363.34456588039;9435.245180385553;9443.338966272364;9446.480443575601;9464.479453630047;9197.67808126719;9292.814143717087;9298.302627302912;9302.720918372459;9329.580168892417;38067.61932380696;38114;38349.80825095884;38393.61606383928;38394.61605383938;2914.3151359430517;3435.656434356569;3806.980965095157;3894.9805250973845;3907;24788.5042299153;29596.852015739783;29931.551026734618;29951.101466956025;30459.781608735622;3020.7885448018646;3105.8291793951325;3126.45287074761;3171.540126681641;3243.3351163011525;433856;468310;468658;468955.6552217251;469832;56608.150877736865;56722.43277567254;56960.14559781607;57046;57079;56764;57891.71054144702;58135.70932145312;58490.41509584935;58551;15498.380064797508;15510.069395836286;15695.372185112468;15703.528894133195;15741.606459838596;11824.763504729857;12068.637940861789;12073.396330183456;12184.329861857683;12207.633770986884;15600.29798659063;15783.763243551355;15806.36774529006;15845.445409410615;15909.443169489012;5880.470757631833;6134.570580059442;6604.207495100619;6664.966675166593;6797.456203503708;690.4993879437368;753.2279413601103;798.297498201579;914.3919293669768;966.2511553545918;28727.856360718062;29109;29559.704402956126;30262.69737302643;30423.98004329261;1002.0580654185043;1007.8387458006683;1036;1094.6934858239706;1150.5972909481782;14487.20320382389;14920.850791492163;15005.32476038559;15204.444622087294;15344.462943796914;573406;580613;656654;658040;658178.963736267;54161.72919135379;55965.72017139888;56506.7174664124;56602;56676;1281.2952875918209;1295.1710905020793;1301.1477482249143;1317.703516708752;1323.7617228898698;28129;28605.570916436685;28745.856270718512;28775.5683664741;29255.85372073126;47794.28308575304;47923.76038119787;48510.75744621254;48713.75643121762;48876.75561622169;607875;612385;612592;612955;616013;25452.56390387274;25665;25702.485950281265;25913.22260332193;25998.740012599632;44328.11343773171;45439.318410224536;45626.54373456223;45901;46139.30791038204;37685.623143768215;37745.62254377421;37905.81047094747;38658.2268354637;39555;31337;33339.99980000604;33764.15589610279;34975;36933;35247.647523524436;36278;37464.876053718435;37593;38943.805280973414;29823.70176298209;33731.83134084314;33847.32305353929;33915.830420847735;34035.65964340325;21573;24474;24891.128810491547;24993.420170528825;25411.618825718 06:27:07 INFO - PROCESS | 1675 | |2;jquery.html;312.97496200303976;367.2141616939751;367.73339328986486;372.17006076449536;397.3503322068419;16429.83570164298;17941.730874036883;18835.811641883593;20119;20140.798592014075;18349.990750508718;19037.2385104596;19042.42872713821;19186.42440726777;19398.127084281205;4456.2647163218035;5078.339815823947;5299.311089558352;5304.602154838383;5318.175682769158;4911.82808601699;5152.098382782998;5219.321488206547;5221.451747566489;5234.895302093956;5145.202493613496;5168.948310516903;5216.347956505437;5243.475652434764;5260.184671375943;4936.210206366982;6548.8035358939305;7437.405007599379;7500.399968002601;7508.662110204999;6971.163460384786;7054.541454805421;7267.63661816907;7275.199728029913;7293.52592081513;23688.526229475487;26879.73120268792;27271.727282727115;27563.586546201823;27781.722182778114;2331.720193576765;2403.6154215325464;2488.4525404411;3220.5813244278092;3232.2404235004756;13180.472781088745;15662.843371566252;15921.044737315799;16493.518158139;16999.40502082421;1906.266087556287;1907.9220240564036;1980.7326010988477;2072.450800537859;2074.502119491326;108016.13927373287;111165.44417277862;130827;132474;138504;17006.48980530586;20285.377169826377;20948.895255523625;21183.046762895705;21334.35996920064;33988;34040;34124.97625071252;34165.829170854486;34201.460934258015;10137.898621013843;10326.896731032744;10540.474807788756;10622.93770622947;10822.296550724177;8635.913640863511;8654.48073115615;8692.174243446863;8818.51498167594;8853.424527405698;9304.627814887328;9393.76515587102;9429.434233945985;9523.809523809485;9541.284403669753;2882.3802882380387;3718.8326525306425;3747.3816820224356;3750.362438385481;3812.7331086823656;788.9289963903161;835.853725598018;838.0362583029565;844.5777111444278;898.7034278688018;16144.677106457804;20615.793842061386;20659.793402066087;20732.481687957923;21277.36167914965;840.3529282452478;886.3086792302015;887.2502735188755;899.7345782994032;977.6654866107712;8922.643094276285;10560.2079844012;10651.520681569347;10869.728256793644;10951.726206844893;550609;574159;586715;595527;599019;117240;117364;131078.44607769573;134006;134776.32611837075;940.3088729783603;958.7411398922252;984.4585477987222;985.1183191044119;986.5856340337076;14274.429022838971;17525.737113943556;18770.71843922315;18838.49292056605;19083.90458047701;37209.99940051499;49545.50454495409;50779.74610126926;57646;59354.51613709519;78178;85399.1460085416;85541.57229213814;86056;87979.68030479676;6883.586984780929;7254.746083887038;7579.393648508217;7953.443258972048;8212.589370531568;11000.339979601249;12494.500219991462;12728.796981975926;12852.164609300366;13036;9257.722268331961;10720.785584288427;11085.334879907232;11122.870779000248;11304.49129789145;10451.634192803216;10745.838812417664;10881.238313317992;10943.562257509611;11254.099672026054;10996.23026388145;11131.220814542905;11273.492692829006;11341.036011938748;11414.771704565695;6070.757169713339;6886.24251332363;7102;7356.374708149867;7578.431617628589;6753.750556147129;10068.949655251678;10787.460626968463;11096.500657470271;11273.463470719023 06:27:07 INFO - PROCESS | 1675 | |3;mootools.html;1435.3397437178894;1459.8759105476036;1616.8520350551107;1751.640913612708;1766.8321509456598;7577.734779282726;7837.451378403514;8081.9191808081905;8533.786655333619;8561.143885611444;8038.437309388345;8305.91694083059;8326.958365208166;8431.95784021079;8437.746867593967;2251;2296.2766728480474;2299.4136495193784;2299.677685330933;2307.1002309099463;2271.7955384015436;2301.344116926676;2311.318161142467;2312.537492501498;2313.571989181998;2262.1856131792542;2270.795628393444;2286.1998300594823;2286.8856557172157;2292.805111565519;2230.475838178027;2240.0591751464426;2260.1185537640335;2264.1396269417596;2267.7051983242236;2053.4455696961736;2133.6557968479915;2284.127015847006;2293.4495721027;2301.873396963166;8388.538630375328;8394.286485648727;8444;8491.278241349431;8496.405251632388;5056.62075344347;5210.583153347723;5219.791208351661;5226.268322434862;5230.686158830445;3215.180129067073;3293.9835300823584;3553.6979356754705;3563.625819288989;3571.6606922342594;986.6201512417771;997.2321312589338;1039.7140786283758;1067.210264404343;1070.9732256693567;9871.111599955966;10084.49577521121;10495.160387169008;10795;10807.513661885234;4255.1915136124035;4327.307630779123;4447.844325448594;4460.464744230648;4478.6864919455975;7694;8028;8711.912880871238;8727.694530691395;8729.694460693845;5059.62052846038;5077.365329333833;5078.365204349457;5080.77136528857;5100.336956195671;4004.619561141687;4300.720453170534;4443.933455970544;4626.7223966562115;4784.736839473862;3576.8390422431053;3624.5288112545727;3665.9816700916326;3697.852085916587;3709.536307961505;1128.4357821089454;1137.8122609769462;1138.743782648914;1147.9782993135948;1150.4132892225025;850.2177996243347;856.8457677618088;873.4846440600077;908.5457271364318;909.4679612426672;5648.717564121777;5721.542276617861;5825.883482330414;5841.503472204825;6012.188354572164;789.976979811146;800.519688187083;808.5916612110964;855.9856569964612;881.9735407937773;3295.3574053059424;3683.9263214735556;3712.535933008374;3833.6358045985585;3880.8447662093763;9637.759056023655;10217.13154381871;10289.639862604772;10405.791884162274;10422.062014418738;10252.128569071563;10290.434026128634;10326.122279606167;10382.961703829556;10400.427976461217;7819.413543984224;7860.999999999885;7862.135165131831;7961.442699011013;8042.9597852010365;4769.952300476951;4864.73243971585;5083.212102124176;5225.111731005814;5737.168110624019;9343.34596578233;9613.855792162984;9616.423014619146;9621.08599683029;9663.903360966582;8754.69358572447;8979.685711000084;9721.4653194075;9724.319297649097;9736.269779766404;6283.717232724447;6512.73949042033;6656.267810540933;6722.9663851680425;6879.724811007503;7848;8074.232947870178;8079.434439589172;8113.0669972953865;8130.349572034342;7135.072440582691;7305;7343.742968996059;7366.337029667141;7415.369693576106;7085.397741192057;7091.858162836817;7116.074910261633;7295.124585049828;7299.562026278228;6626.56927299724;6764.492663050192;6775.0176224446195;6792.320767923168;6811.352921472452;4354.390385346117;4715.976420117878;4755.881102972453;4800.799800049987;4839.733814640229;4678.836240731558;4771.761411929459;4836.637252206028;4890.682105663121;5073.670211436245 06:27:07 INFO - PROCESS | 1675 | |4;prototype.html;284.628559729553;296.58774303717837;300.4756699559269;300.6107091316745;312.69043646789686;5191.818286359978;5298.867528311795;5370.3555573331205;5484.890302193954;5485.424030476797;4387.63983165219;5041.269015992679;5187.48125187481;5223.555997740187;5313.840584782453;4069.511658600972;4109.363048727452;4110.198511290299;4112.773797441139;4121.711480196387;3460.8406183928287;3711.7030637549065;4075.6535694466006;4108.938365924499;4123.340265557511;3379.7972121672656;3964.544077431089;4016.4294851295194;4038.5759495253014;4041.2523683118716;3595.370810108234;3627.927441451169;4026.9194616107807;4056.762687380348;4057.857974970862;3524.2951409718157;3545.166885781839;3553.0406790166767;3560.753736192338;3572.696320812734;4696.506866778973;5021.347224860745;5183.611229157828;5191.506806853343;5209.004822227234;3965.4250133730734;4168.937465938014;4403.339499075164;4621.2837010263465;4637.234856248749;4260.08408192237;4756.66703330767;4841.055994081155;5182.689038657692;5587.776488940438;3293.736501079908;3707.6663100321102;3809.8189561236104;3853;3882.5340959084806;5268.578513718893;5806.535477161817;5875.9118613220835;5903.291605007384;5928.377520360344;3949.926288698399;3950.6839452843706;3958.050067983721;3965.9603403966166;3985.262726395626;3856.2480316338606;3989.7606143631474;3997.620226078518;4002.7598344099447;4028.959710402917;3957.3470377387985;4086.8773936781945;4164.958760309923;4611.976940115278;4613.607843333287;4507.752073635916;5650.63270887391;5660.943390566042;5664.943350566524;5698.6580805151825;1215.9574414895437;1233.734747029393;1263.387257180267;1278.8554243951612;1298.8246586710861;767.681412213925;808.9838203235902;865.7446053414258;876.5755647073505;898.6405437824923;4366.956330436655;4838.080764654705;5048.980407836894;5064.215046667772;5120.159880089933;4729.716217026989;4973.701577905337;5280.894382112337;5476.397596264408;5535.723213839292;4177.352510360899;4198.832046718097;4529.977350113229;4817.084753896748;5027.79888804451;2573.305207593939;2658.5081759874097;2702.1083042595897;2789.595508651234;2971.985140074286;5362.463753624606;5584.766093562607;5815.302163740378;5823.388544202882;5828.883422331614;4635.261776833688;4757.26262429324;5345.9732701337025;5766;5767.740451679684;4881.365422495053;5291.682499049993;5450.5639548836;5609.4390560943575;6175.722092505758;5947.583669143119;5984.31180414258;6002.249718785152;6018.428249316308;6021.969890150521;5420.457954204706;5737.799177028784;5807.796727114531;5865.794697185578;5879.853003674943;5244.37067551881;6017.886690962186;6116.418940200674;6147.661878596541;6159.168512250789;3750.943735844019;3806.695464362789;3942.625450582305;3958.524977002778;3969.2656858481273;5357.8124765634575;5742.942570574241;5824.359320474659;5920.911186332122;5921.14143449206;5046.495350464924;5584.413636568264;5636.436356364331;5751.7411716472025;5777.277840269966;4819.855404337875;5742.885142297214;5747.798827041033;5786.161006654096;5792.362840087502;4421.894526368408;4688.0858232644305;5166.793328266827;5533.446655334435;5629.4652008059165;5006.674566153189;5144.614153938544;5469.890602188014;5739.282589676291;5838.532917366685;4725.432948046257;4969.925451118163;5093.592512598909;5617.859553511195;5749.453801888814 06:27:07 INFO - PROCESS | 1675 | |5;yui.html;678.1590827374056;690.5649440852262;721.6139365439491;739.1315204634553;740.5852722475414;14020.64948376291;14414.783778243322;14432.20622865742;14780.482683106093;14803.185824779634;13155;13326.666833329171;13340.199588024725;13351.265680387574;13360.866391336109;636.6975686548899;654.6628486329544;784.5214419204281;795.3557618329172;807.4347956430522;792.6472719639762;798.5288679678988;804.2761514636816;804.8470790549806;804.9678012879507;857.3526987124712;860.9311255099577;864.7189663359402;868.2706526517724;868.5613765048641;827.1976183102381;848.5587494502855;859.9312055035582;861.6984055580529;862.0991064337782;624.2883113250898;631.9431251187415;639.8688268904864;639.9200099987502;640.2445114764575;13361.398737056856;13496.39266233022;13499.66250843727;13545.864541354658;13898;10693.732656683567;13086.86913130866;13223;13405.757306467207;13741.519046833413;11187.328760274328;11305.321680699102;11356.772864542745;11373.260738052082;11378.671342605494;3583.5341405617364;3649.525561676965;3700.1859590889962;3754.2116155607355;3770.830312635938;94134;96348.51825740826;96949.51525242328;97479;97909;10398.272120951611;10425.9478702606;10446.320989135682;10448.634297799541;10819.296745711503;20590.485237869172;20598.38204853857;20625.89687051585;20668.276610318568;20672.379828605164;6545.461816473125;7532.058492688414;7549.396048316122;7699.615019249015;7751;4125.381192821101;4214.178235244098;4219.261629214875;4220.556841531595;4231.640310573574;3341.2482191506715;3435.2270739083506;3443.2941247044296;3469.4275444551367;3490.5636795400574;623.9188905442263;631.1542533005703;641.9293877673452;648.5524987758431;650.778735230024;619.4765423217374;631.7473010795627;650.3366566102549;652.9869402611921;675.3381685947701;7151.284871512911;8151.592420378957;8825.69110081144;8952.462852228888;9004.054574269738;529.6186745543203;640.309582701668;641.4643772449963;671.0873212431187;677.8508728079814;3588.6949609282983;3819.866681887876;4292.892677683083;4512.278035514302;4651.302304654261;62891;75945;86999;98377;105183;82486;101302;101750.49124754476;101803.49098254461;105223;67125.66437167881;89288;89450.65824012645;91910;92508.07491925129;8194.590270486571;8231.312580920778;8240.175982401952;8509.446885952393;8516.361272904433;42745.1450970985;50728;50776.74611627066;53815.730921345144;57053.14420283782;64437.35562644314;66377.66811165914;68142;70888;75396.11509712081;10869.619563315246;10890.29213101146;10938.234323597271;10997.780044399227;11414;11870.169088163746;11918.463669134737;11930.880691193324;12025.278483291031;12462.252264864137;11620.88379116198;11733.823992640288;11756.353400563052;11828.58599949032;12152.966997805286;8298.419110662197;9720.951395242979;10596.8940310599;10792.974667406585;11092.500837462172;10910.399928004035;11287.548498059985;11424.885751142383;11820.527178912747;11921.821172682243;9020.727738488646;9567.521623918916;9698.418094914328;9709.56306966174;9984.652071970191;7265;9729.902700973184;9857.507124643882;9861.704148875546;9867.25995550351 06:27:07 INFO - PROCESS | 1675 | __end_tp_report 06:27:07 INFO - PROCESS | 1675 | __start_cc_report 06:27:07 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,369 06:27:07 INFO - PROCESS | 1675 | __end_cc_report 06:27:07 INFO - PROCESS | 1675 | __startTimestamp1478615227227__endTimestamp 06:27:07 INFO - PROCESS | 1675 | 06:27:07 INFO - PROCESS | 1675 | ------- Summary: start ------- 06:27:07 INFO - PROCESS | 1675 | Number of tests: 6 06:27:07 INFO - PROCESS | 1675 | 06:27:07 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5655.11 Median:5836.74 stddev:660.81 (11.3%) stddev-sans-first:618.39 06:27:07 INFO - PROCESS | 1675 | Values: 2617.7 3289.1 3318.9 3326.9 3346.9 5584.9 5602.7 5622.5 5655.6 5676.2 4881.5 5467.8 5601.8 5603.5 5640.4 5115.5 5125.7 5240.7 5243.1 5311.9 5112.0 5193.1 5222.1 5236.1 5249.3 5132.1 5234.0 5263.2 5271.8 5291.7 5223.2 5236.8 5265.4 5271.4 5275.8 4305.0 5057.4 5063.7 5075.6 5228.1 5364.1 5640.4 5669.4 5671.1 5679.0 6029.7 6128.1 6173.4 6239.4 6261.9 6115.8 6156.3 6174.5 6210.0 6283.3 5749.8 5752.5 5767.8 5779.8 5790.9 6266.6 6267.1 6288.3 6290.4 6307.5 4734.5 5783.6 5902.8 6007.5 6074.7 5238.6 6188.8 6222.3 6229.2 6234.9 4966.2 6112.6 6190.4 6195.9 6217.9 5982.5 6025.5 6071.2 6088.8 6212.1 4883.5 4885.9 4900.3 4904.2 4940.7 4396.6 4420.3 4432.3 4453.7 4460.7 5481.2 5649.7 5702.3 5702.9 5798.9 6241.8 6256.3 6263.7 6276.8 6327.7 5804.7 5811.6 5836.7 5841.5 5882.8 5168.5 5297.6 5318.6 5363.1 5373.7 6155.5 6224.8 6231.9 6251.7 6258.1 6235.6 6254.9 6255.6 6266.5 6273.2 6125.5 6220.9 6246.6 6254.9 6279.8 5995.7 6027.2 6082.7 6121.2 6121.4 6194.7 6220.9 6227.1 6236.1 6264.9 5801.5 5874.5 5955.8 6024.4 6057.9 5894.5 6015.8 6063.1 6078.3 6117.1 5403.7 5565.5 5844.0 5905.2 5949.8 5087.3 5808.8 5908.7 5982.3 6015.2 5834.0 5870.3 5872.7 5917.8 5919.9 06:27:07 INFO - PROCESS | 1675 | 06:27:07 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:68985.11 Median:21276.60 stddev:153078.37 (719.5%) stddev-sans-first:153421.37 06:27:07 INFO - PROCESS | 1675 | Values: 476.8 481.7 481.7 512.9 515.7 36788.0 38232.9 38236.8 38301.0 38412.0 40502.6 40886.6 40900.0 40998.0 41038.8 9191.3 9408.7 9427.3 9436.2 9442.4 9274.2 9275.6 9357.3 9376.3 9380.5 9340.0 9397.2 9450.2 9460.2 9468.6 9363.3 9435.2 9443.3 9446.5 9464.5 9197.7 9292.8 9298.3 9302.7 9329.6 38067.6 38114.0 38349.8 38393.6 38394.6 2914.3 3435.7 3807.0 3895.0 3907.0 24788.5 29596.9 29931.6 29951.1 30459.8 3020.8 3105.8 3126.5 3171.5 3243.3 433856.0 468310.0 468658.0 468955.7 469832.0 56608.2 56722.4 56960.1 57046.0 57079.0 56764.0 57891.7 58135.7 58490.4 58551.0 15498.4 15510.1 15695.4 15703.5 15741.6 11824.8 12068.6 12073.4 12184.3 12207.6 15600.3 15783.8 15806.4 15845.4 15909.4 5880.5 6134.6 6604.2 6665.0 6797.5 690.5 753.2 798.3 914.4 966.3 28727.9 29109.0 29559.7 30262.7 30424.0 1002.1 1007.8 1036.0 1094.7 1150.6 14487.2 14920.9 15005.3 15204.4 15344.5 573406.0 580613.0 656654.0 658040.0 658179.0 54161.7 55965.7 56506.7 56602.0 56676.0 1281.3 1295.2 1301.1 1317.7 1323.8 28129.0 28605.6 28745.9 28775.6 29255.9 47794.3 47923.8 48510.8 48713.8 48876.8 607875.0 612385.0 612592.0 612955.0 616013.0 25452.6 25665.0 25702.5 25913.2 25998.7 44328.1 45439.3 45626.5 45901.0 46139.3 37685.6 37745.6 37905.8 38658.2 39555.0 31337.0 33340.0 33764.2 34975.0 36933.0 35247.6 36278.0 37464.9 37593.0 38943.8 29823.7 33731.8 33847.3 33915.8 34035.7 21573.0 24474.0 24891.1 24993.4 25411.6 06:27:07 INFO - PROCESS | 1675 | 06:27:07 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:35813.16 Median:34082.49 stddev:97277.46 (285.4%) stddev-sans-first:97513.83 06:27:07 INFO - PROCESS | 1675 | Values: 313.0 367.2 367.7 372.2 397.4 16429.8 17941.7 18835.8 20119.0 20140.8 18350.0 19037.2 19042.4 19186.4 19398.1 4456.3 5078.3 5299.3 5304.6 5318.2 4911.8 5152.1 5219.3 5221.5 5234.9 5145.2 5168.9 5216.3 5243.5 5260.2 4936.2 6548.8 7437.4 7500.4 7508.7 6971.2 7054.5 7267.6 7275.2 7293.5 23688.5 26879.7 27271.7 27563.6 27781.7 2331.7 2403.6 2488.5 3220.6 3232.2 13180.5 15662.8 15921.0 16493.5 16999.4 1906.3 1907.9 1980.7 2072.5 2074.5 108016.1 111165.4 130827.0 132474.0 138504.0 17006.5 20285.4 20948.9 21183.0 21334.4 33988.0 34040.0 34125.0 34165.8 34201.5 10137.9 10326.9 10540.5 10622.9 10822.3 8635.9 8654.5 8692.2 8818.5 8853.4 9304.6 9393.8 9429.4 9523.8 9541.3 2882.4 3718.8 3747.4 3750.4 3812.7 788.9 835.9 838.0 844.6 898.7 16144.7 20615.8 20659.8 20732.5 21277.4 840.4 886.3 887.3 899.7 977.7 8922.6 10560.2 10651.5 10869.7 10951.7 550609.0 574159.0 586715.0 595527.0 599019.0 117240.0 117364.0 131078.4 134006.0 134776.3 940.3 958.7 984.5 985.1 986.6 14274.4 17525.7 18770.7 18838.5 19083.9 37210.0 49545.5 50779.7 57646.0 59354.5 78178.0 85399.1 85541.6 86056.0 87979.7 6883.6 7254.7 7579.4 7953.4 8212.6 11000.3 12494.5 12728.8 12852.2 13036.0 9257.7 10720.8 11085.3 11122.9 11304.5 10451.6 10745.8 10881.2 10943.6 11254.1 10996.2 11131.2 11273.5 11341.0 11414.8 6070.8 6886.2 7102.0 7356.4 7578.4 6753.8 10068.9 10787.5 11096.5 11273.5 06:27:07 INFO - PROCESS | 1675 | 06:27:07 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:5392.69 Median:5077.87 stddev:2986.50 (58.8%) stddev-sans-first:2980.07 06:27:07 INFO - PROCESS | 1675 | Values: 1435.3 1459.9 1616.9 1751.6 1766.8 7577.7 7837.5 8081.9 8533.8 8561.1 8038.4 8305.9 8327.0 8432.0 8437.7 2251.0 2296.3 2299.4 2299.7 2307.1 2271.8 2301.3 2311.3 2312.5 2313.6 2262.2 2270.8 2286.2 2286.9 2292.8 2230.5 2240.1 2260.1 2264.1 2267.7 2053.4 2133.7 2284.1 2293.4 2301.9 8388.5 8394.3 8444.0 8491.3 8496.4 5056.6 5210.6 5219.8 5226.3 5230.7 3215.2 3294.0 3553.7 3563.6 3571.7 986.6 997.2 1039.7 1067.2 1071.0 9871.1 10084.5 10495.2 10795.0 10807.5 4255.2 4327.3 4447.8 4460.5 4478.7 7694.0 8028.0 8711.9 8727.7 8729.7 5059.6 5077.4 5078.4 5080.8 5100.3 4004.6 4300.7 4443.9 4626.7 4784.7 3576.8 3624.5 3666.0 3697.9 3709.5 1128.4 1137.8 1138.7 1148.0 1150.4 850.2 856.8 873.5 908.5 909.5 5648.7 5721.5 5825.9 5841.5 6012.2 790.0 800.5 808.6 856.0 882.0 3295.4 3683.9 3712.5 3833.6 3880.8 9637.8 10217.1 10289.6 10405.8 10422.1 10252.1 10290.4 10326.1 10383.0 10400.4 7819.4 7861.0 7862.1 7961.4 8043.0 4770.0 4864.7 5083.2 5225.1 5737.2 9343.3 9613.9 9616.4 9621.1 9663.9 8754.7 8979.7 9721.5 9724.3 9736.3 6283.7 6512.7 6656.3 6723.0 6879.7 7848.0 8074.2 8079.4 8113.1 8130.3 7135.1 7305.0 7343.7 7366.3 7415.4 7085.4 7091.9 7116.1 7295.1 7299.6 6626.6 6764.5 6775.0 6792.3 6811.4 4354.4 4716.0 4755.9 4800.8 4839.7 4678.8 4771.8 4836.6 4890.7 5073.7 06:27:07 INFO - PROCESS | 1675 | 06:27:07 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:4488.54 Median:4971.81 stddev:1399.54 (28.1%) stddev-sans-first:1367.44 06:27:07 INFO - PROCESS | 1675 | Values: 284.6 296.6 300.5 300.6 312.7 5191.8 5298.9 5370.4 5484.9 5485.4 4387.6 5041.3 5187.5 5223.6 5313.8 4069.5 4109.4 4110.2 4112.8 4121.7 3460.8 3711.7 4075.7 4108.9 4123.3 3379.8 3964.5 4016.4 4038.6 4041.3 3595.4 3627.9 4026.9 4056.8 4057.9 3524.3 3545.2 3553.0 3560.8 3572.7 4696.5 5021.3 5183.6 5191.5 5209.0 3965.4 4168.9 4403.3 4621.3 4637.2 4260.1 4756.7 4841.1 5182.7 5587.8 3293.7 3707.7 3809.8 3853.0 3882.5 5268.6 5806.5 5875.9 5903.3 5928.4 3949.9 3950.7 3958.1 3966.0 3985.3 3856.2 3989.8 3997.6 4002.8 4029.0 3957.3 4086.9 4165.0 4612.0 4613.6 4507.8 5650.6 5660.9 5664.9 5698.7 1216.0 1233.7 1263.4 1278.9 1298.8 767.7 809.0 865.7 876.6 898.6 4367.0 4838.1 5049.0 5064.2 5120.2 4729.7 4973.7 5280.9 5476.4 5535.7 4177.4 4198.8 4530.0 4817.1 5027.8 2573.3 2658.5 2702.1 2789.6 2972.0 5362.5 5584.8 5815.3 5823.4 5828.9 4635.3 4757.3 5346.0 5766.0 5767.7 4881.4 5291.7 5450.6 5609.4 6175.7 5947.6 5984.3 6002.2 6018.4 6022.0 5420.5 5737.8 5807.8 5865.8 5879.9 5244.4 6017.9 6116.4 6147.7 6159.2 3750.9 3806.7 3942.6 3958.5 3969.3 5357.8 5742.9 5824.4 5920.9 5921.1 5046.5 5584.4 5636.4 5751.7 5777.3 4819.9 5742.9 5747.8 5786.2 5792.4 4421.9 4688.1 5166.8 5533.4 5629.5 5006.7 5144.6 5469.9 5739.3 5838.5 4725.4 4969.9 5093.6 5617.9 5749.5 06:27:07 INFO - PROCESS | 1675 | 06:27:07 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:19591.44 Median:624.10 stddev:29080.54 (4659.6%) stddev-sans-first:29127.45 06:27:07 INFO - PROCESS | 1675 | Values: 678.2 690.6 721.6 739.1 740.6 14020.6 14414.8 14432.2 14780.5 14803.2 13155.0 13326.7 13340.2 13351.3 13360.9 636.7 654.7 784.5 795.4 807.4 792.6 798.5 804.3 804.8 805.0 857.4 860.9 864.7 868.3 868.6 827.2 848.6 859.9 861.7 862.1 624.3 631.9 639.9 639.9 640.2 13361.4 13496.4 13499.7 13545.9 13898.0 10693.7 13086.9 13223.0 13405.8 13741.5 11187.3 11305.3 11356.8 11373.3 11378.7 3583.5 3649.5 3700.2 3754.2 3770.8 94134.0 96348.5 96949.5 97479.0 97909.0 10398.3 10425.9 10446.3 10448.6 10819.3 20590.5 20598.4 20625.9 20668.3 20672.4 6545.5 7532.1 7549.4 7699.6 7751.0 4125.4 4214.2 4219.3 4220.6 4231.6 3341.2 3435.2 3443.3 3469.4 3490.6 623.9 631.2 641.9 648.6 650.8 619.5 631.7 650.3 653.0 675.3 7151.3 8151.6 8825.7 8952.5 9004.1 529.6 640.3 641.5 671.1 677.9 3588.7 3819.9 4292.9 4512.3 4651.3 62891.0 75945.0 86999.0 98377.0 105183.0 82486.0 101302.0 101750.5 101803.5 105223.0 67125.7 89288.0 89450.7 91910.0 92508.1 8194.6 8231.3 8240.2 8509.4 8516.4 42745.1 50728.0 50776.7 53815.7 57053.1 64437.4 66377.7 68142.0 70888.0 75396.1 10869.6 10890.3 10938.2 10997.8 11414.0 11870.2 11918.5 11930.9 12025.3 12462.3 11620.9 11733.8 11756.4 11828.6 12153.0 8298.4 9721.0 10596.9 10793.0 11092.5 10910.4 11287.5 11424.9 11820.5 11921.8 9020.7 9567.5 9698.4 9709.6 9984.7 7265.0 9729.9 9857.5 9861.7 9867.3 06:27:07 INFO - PROCESS | 1675 | -------- Summary: end -------- 06:27:07 INFO - PROCESS | 1675 | 06:27:07 INFO - TEST-INFO | 1675: exit 0 06:27:07 INFO - TEST-OK | dromaeo_css | took 1116416ms 06:27:07 INFO - TEST-START | kraken 06:27:07 INFO - operating with platform_type : mac_ 06:27:07 INFO - Initialising browser for kraken test... 06:27:07 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmp8Sef3Z/profile http://localhost:49266/getInfo.html) 06:27:10 INFO - PROCESS | 1684 | 2016-11-08 06:27:10.763 plugin-container[1686:10001] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 06:27:10 INFO - PROCESS | 1684 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:10 INFO - PROCESS | 1684 | 2016-11-08 06:27:10.765 plugin-container[1686:10001] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 06:27:10 INFO - PROCESS | 1684 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:10 INFO - PROCESS | 1684 | 2016-11-08 06:27:10.765 plugin-container[1686:10001] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:27:10 INFO - PROCESS | 1684 | __metrics Screen width/height:1600/1200 06:27:10 INFO - PROCESS | 1684 | colorDepth:24 06:27:10 INFO - PROCESS | 1684 | Browser inner width/height: 1024/689 06:27:10 INFO - PROCESS | 1684 | __metrics 06:27:10 ERROR - PROCESS | 1684 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 06:27:11 INFO - TEST-INFO | 1684: exit 0 06:27:11 INFO - Browser initialized. 06:27:11 INFO - Running cycle 1/1 for kraken test... 06:27:11 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmp8Sef3Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:27:14 INFO - PROCESS | 1687 | 2016-11-08 06:27:14.144 plugin-container[1688:10116] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 06:27:14 INFO - PROCESS | 1687 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:14 INFO - PROCESS | 1687 | 2016-11-08 06:27:14.144 plugin-container[1688:10116] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 06:27:14 INFO - PROCESS | 1687 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:14 INFO - PROCESS | 1687 | 2016-11-08 06:27:14.144 plugin-container[1688:10116] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:27:23 INFO - PROCESS | 1687 | RSS: Main: 238411776 06:27:23 INFO - PROCESS | 1687 | 06:27:26 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 06:27:27 INFO - PROCESS | 1687 | RSS: Main: 250454016 06:27:27 INFO - PROCESS | 1687 | 06:27:30 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 06:27:31 INFO - PROCESS | 1687 | RSS: Main: 258576384 06:27:31 INFO - PROCESS | 1687 | 06:27:34 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 06:27:35 INFO - PROCESS | 1687 | RSS: Main: 261603328 06:27:35 INFO - PROCESS | 1687 | 06:27:37 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 06:27:38 INFO - PROCESS | 1687 | RSS: Main: 263835648 06:27:38 INFO - PROCESS | 1687 | 06:27:40 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:27:40 INFO - PROCESS | 1687 | RSS: Main: 264376320 06:27:40 INFO - PROCESS | 1687 | 06:27:43 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 06:27:44 INFO - PROCESS | 1687 | RSS: Main: 264466432 06:27:44 INFO - PROCESS | 1687 | 06:27:47 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 06:27:48 INFO - PROCESS | 1687 | RSS: Main: 264949760 06:27:48 INFO - PROCESS | 1687 | 06:27:50 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 06:27:51 INFO - PROCESS | 1687 | RSS: Main: 264986624 06:27:51 INFO - PROCESS | 1687 | 06:27:53 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:27:53 INFO - PROCESS | 1687 | RSS: Main: 264720384 06:27:53 INFO - PROCESS | 1687 | 06:27:55 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:27:56 INFO - PROCESS | 1687 | RSS: Main: 264691712 06:27:56 INFO - PROCESS | 1687 | 06:27:58 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:27:59 INFO - PROCESS | 1687 | RSS: Main: 264740864 06:27:59 INFO - PROCESS | 1687 | 06:28:02 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:28:03 INFO - PROCESS | 1687 | RSS: Main: 265019392 06:28:03 INFO - PROCESS | 1687 | 06:28:06 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:28:06 INFO - PROCESS | 1687 | RSS: Main: 264798208 06:28:06 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 06:28:08 INFO - PROCESS | 1687 | RSS: Main: 266412032 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | __start_tp_report 06:28:08 INFO - PROCESS | 1687 | _x_x_mozilla_page_load 06:28:08 INFO - PROCESS | 1687 | _x_x_mozilla_page_load_details 06:28:08 INFO - PROCESS | 1687 | |i|pagename|runs| 06:28:08 INFO - PROCESS | 1687 | |0;ai-astar;147;169;145;123;162;143;117;100;168;112 06:28:08 INFO - PROCESS | 1687 | |1;audio-beat-detection;155;144;117;192;149;147;117;116;145;144 06:28:08 INFO - PROCESS | 1687 | |2;audio-dft;236;272;271;191;191;204;269;237;194;195 06:28:08 INFO - PROCESS | 1687 | |3;audio-fft;82;60;60;87;57;76;87;54;95;83 06:28:08 INFO - PROCESS | 1687 | |4;audio-oscillator;98;96;123;75;68;72;87;72;70;86 06:28:08 INFO - PROCESS | 1687 | |5;imaging-gaussian-blur;107;131;79;132;135;88;90;87;90;87 06:28:08 INFO - PROCESS | 1687 | |6;imaging-darkroom;95;95;138;129;152;150;95;95;96;123 06:28:08 INFO - PROCESS | 1687 | |7;imaging-desaturate;97;128;84;94;126;97;80;74;89;72 06:28:08 INFO - PROCESS | 1687 | |8;json-parse-financial;58;58;59;72;47;47;58;79;91;91 06:28:08 INFO - PROCESS | 1687 | |9;json-stringify-tinderbox;71;76;71;88;114;72;72;73;67;63 06:28:08 INFO - PROCESS | 1687 | |10;stanford-crypto-aes;92;89;93;89;89;91;93;89;88;90 06:28:08 INFO - PROCESS | 1687 | |11;stanford-crypto-ccm;171;168;167;142;159;157;166;146;197;162 06:28:08 INFO - PROCESS | 1687 | |12;stanford-crypto-pbkdf2;175;174;176;173;179;176;178;180;240;226 06:28:08 INFO - PROCESS | 1687 | |13;stanford-crypto-sha256-iterative;67;68;69;69;69;68;68;69;67;67 06:28:08 INFO - PROCESS | 1687 | __end_tp_report 06:28:08 INFO - PROCESS | 1687 | __start_cc_report 06:28:08 INFO - PROCESS | 1687 | _x_x_mozilla_cycle_collect,949 06:28:08 INFO - PROCESS | 1687 | __end_cc_report 06:28:08 INFO - PROCESS | 1687 | __startTimestamp1478615288742__endTimestamp 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | ------- Summary: start ------- 06:28:08 INFO - PROCESS | 1687 | Number of tests: 14 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#0] ai-astar Cycles:10 Average:138.60 Median:146.00 stddev:24.44 (16.7%) stddev-sans-first:25.73 06:28:08 INFO - PROCESS | 1687 | Values: 147.0 169.0 145.0 123.0 162.0 143.0 117.0 100.0 168.0 112.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:146.00 stddev:22.81 (15.6%) stddev-sans-first:23.75 06:28:08 INFO - PROCESS | 1687 | Values: 155.0 144.0 117.0 192.0 149.0 147.0 117.0 116.0 145.0 144.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#2] audio-dft Cycles:10 Average:226.00 Median:236.50 stddev:35.17 (14.9%) stddev-sans-first:37.11 06:28:08 INFO - PROCESS | 1687 | Values: 236.0 272.0 271.0 191.0 191.0 204.0 269.0 237.0 194.0 195.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#3] audio-fft Cycles:10 Average:74.10 Median:82.50 stddev:14.94 (18.1%) stddev-sans-first:15.57 06:28:08 INFO - PROCESS | 1687 | Values: 82.0 60.0 60.0 87.0 57.0 76.0 87.0 54.0 95.0 83.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#4] audio-oscillator Cycles:10 Average:84.70 Median:80.50 stddev:17.29 (21.5%) stddev-sans-first:17.65 06:28:08 INFO - PROCESS | 1687 | Values: 98.0 96.0 123.0 75.0 68.0 72.0 87.0 72.0 70.0 86.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#5] imaging-gaussian-blur Cycles:10 Average:102.60 Median:87.00 stddev:21.89 (25.2%) stddev-sans-first:23.16 06:28:08 INFO - PROCESS | 1687 | Values: 107.0 131.0 79.0 132.0 135.0 88.0 90.0 87.0 90.0 87.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#6] imaging-darkroom Cycles:10 Average:116.80 Median:95.00 stddev:24.29 (25.6%) stddev-sans-first:24.45 06:28:08 INFO - PROCESS | 1687 | Values: 95.0 95.0 138.0 129.0 152.0 150.0 95.0 95.0 96.0 123.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:86.50 stddev:19.44 (22.5%) stddev-sans-first:20.60 06:28:08 INFO - PROCESS | 1687 | Values: 97.0 128.0 84.0 94.0 126.0 97.0 80.0 74.0 89.0 72.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:65.50 stddev:16.39 (25.0%) stddev-sans-first:17.13 06:28:08 INFO - PROCESS | 1687 | Values: 58.0 58.0 59.0 72.0 47.0 47.0 58.0 79.0 91.0 91.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#9] json-stringify-tinderbox Cycles:10 Average:76.70 Median:72.00 stddev:14.62 (20.3%) stddev-sans-first:15.36 06:28:08 INFO - PROCESS | 1687 | Values: 71.0 76.0 71.0 88.0 114.0 72.0 72.0 73.0 67.0 63.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:90.50 stddev:1.83 (2.0%) stddev-sans-first:1.83 06:28:08 INFO - PROCESS | 1687 | Values: 92.0 89.0 93.0 89.0 89.0 91.0 93.0 89.0 88.0 90.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#11] stanford-crypto-ccm Cycles:10 Average:163.50 Median:166.50 stddev:15.09 (9.1%) stddev-sans-first:15.76 06:28:08 INFO - PROCESS | 1687 | Values: 171.0 168.0 167.0 142.0 159.0 157.0 166.0 146.0 197.0 162.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.70 Median:178.50 stddev:24.20 (13.6%) stddev-sans-first:25.23 06:28:08 INFO - PROCESS | 1687 | Values: 175.0 174.0 176.0 173.0 179.0 176.0 178.0 180.0 240.0 226.0 06:28:08 INFO - PROCESS | 1687 | 06:28:08 INFO - PROCESS | 1687 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:0.88 (1.3%) stddev-sans-first:0.83 06:28:08 INFO - PROCESS | 1687 | Values: 67.0 68.0 69.0 69.0 69.0 68.0 68.0 69.0 67.0 67.0 06:28:08 INFO - PROCESS | 1687 | -------- Summary: end -------- 06:28:08 INFO - PROCESS | 1687 | 06:28:09 INFO - TEST-INFO | 1687: exit 0 06:28:09 INFO - TEST-OK | kraken | took 61680ms 06:28:09 INFO - SUITE-END | took 1178s 06:28:09 INFO - Completed test suite (00:19:38) 06:28:09 INFO - javascript benchmark 06:28:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7169.315720485471, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2617.685877694677, 3289.095498737847, 3318.8838390656315, 3326.933461330772, 3346.9497957530634, 5584.944150558493, 5602.719864006794, 5622.4939755422, 5655.604107712452, 5676.233708449358, 4881.536254055859, 5467.808626698066, 5601.831945041645, 5603.467670571299, 5640.407757185498, 5115.488451154892, 5125.66683165594, 5240.685558866461, 5243.108671525831, 5311.9468805311835, 5111.974440127813, 5193.065248255312, 5222.112240919053, 5236.136037553799, 5249.343832020997, 5132.101882170624, 5233.9738301308635, 5263.1578947368425, 5271.762770675329, 5291.735413229323, 5223.1904054871175, 5236.81671141512, 5265.394479634852, 5271.393789714193, 5275.762590683428, 4305.0, 5057.418396884332, 5063.721495317756, 5075.6447048706605, 5228.111221092423, 5364.141737322048, 5640.379558248589, 5669.376368599451, 5671.149327600851, 5679.006173919547, 6029.728662210211, 6128.111423843562, 6173.351798061184, 6239.438450539473, 6261.937380626181, 6115.848865095079, 6156.322804491502, 6174.536909731788, 6209.96895015522, 6283.340249273806, 5749.798757043483, 5752.482276595085, 5767.826965191051, 5779.826605201851, 5790.913136302959, 6266.62400255977, 6267.059941008794, 6288.308286088527, 6290.402411770966, 6307.463865571386, 4734.526547345238, 5783.595148339576, 5902.763889444374, 6007.549433792483, 6074.696265186723, 5238.580913526908, 6188.81433556994, 6222.253329600387, 6229.190205273286, 6234.937650623527, 4966.205407134841, 6112.633242005494, 6190.442860142519, 6195.938040619626, 6217.88078145929, 5982.461578457873, 6025.4577088061405, 6071.241094863142, 6088.756449742049, 6212.099245609361, 4883.536064073907, 4885.853424397274, 4900.289458028566, 4904.166291730415, 4940.654154209243, 4396.648268138541, 4420.31485119807, 4432.2908334666945, 4453.710508816916, 4460.665450091256, 5481.232627432163, 5649.745761440745, 5702.287214098238, 5702.914456283159, 5798.9130163047585, 6241.843953901189, 6256.280527739368, 6263.655498947602, 6276.780312689125, 6327.651979141099, 5804.651720896675, 5811.622244554091, 5836.737346819404, 5841.503472204825, 5882.794102206402, 5168.457311982263, 5297.602679799031, 5318.601104917069, 5363.0614642438195, 5373.650712703662, 6155.507559395238, 6224.813255602339, 6231.937680623227, 6251.749930002931, 6258.092576576462, 6235.563510554218, 6254.906176407267, 6255.593386429868, 6266.530010249158, 6273.184486016971, 6125.540584456273, 6220.937790622036, 6246.593971391845, 6254.874902501833, 6279.780207692709, 5995.730192141277, 6027.21646185993, 6082.7262773174425, 6121.173641558333, 6121.418465245794, 6194.690265486798, 6220.906686399798, 6227.0970709246, 6236.126942228182, 6264.874702506016, 5801.506871915934, 5874.530037597067, 5955.76176952917, 6024.427679370453, 6057.939420605738, 5894.469497745224, 6015.819525414245, 6063.120847477003, 6078.331383547895, 6117.051856962178, 5403.703111253192, 5565.471280228372, 5844.035734103825, 5905.202797622266, 5949.8215053548465, 5087.313212716236, 5808.751118509541, 5908.734106965283, 5982.312034115958, 6015.248093988252, 5833.970830145992, 5870.26621672291, 5872.706364681834, 5917.822465326047, 5919.911201331897], "value": 5612.212354848304, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.8188088526359, 481.6748694631123, 481.7302310706005, 512.8640910158806, 515.65966462135, 36788.0, 38232.85301440958, 38236.80881595595, 38301.0, 38412.00643916287, 40502.59497405025, 40886.59113408865, 40900.0, 40998.0, 41038.79480602593, 9191.310651701098, 9408.670696525623, 9427.292953028516, 9436.197923176538, 9442.386244894094, 9274.165325120737, 9275.628974840998, 9357.251419886426, 9376.296777741663, 9380.530973451334, 9340.0, 9397.201237894787, 9450.19673327768, 9460.24318054554, 9468.57391417388, 9363.34456588039, 9435.245180385553, 9443.338966272364, 9446.480443575601, 9464.479453630047, 9197.67808126719, 9292.814143717087, 9298.302627302912, 9302.720918372459, 9329.580168892417, 38067.61932380696, 38114.0, 38349.80825095884, 38393.61606383928, 38394.61605383938, 2914.3151359430517, 3435.656434356569, 3806.980965095157, 3894.9805250973845, 3907.0, 24788.5042299153, 29596.852015739783, 29931.551026734618, 29951.101466956025, 30459.781608735622, 3020.7885448018646, 3105.8291793951325, 3126.45287074761, 3171.540126681641, 3243.3351163011525, 433856.0, 468310.0, 468658.0, 468955.6552217251, 469832.0, 56608.150877736865, 56722.43277567254, 56960.14559781607, 57046.0, 57079.0, 56764.0, 57891.71054144702, 58135.70932145312, 58490.41509584935, 58551.0, 15498.380064797508, 15510.069395836286, 15695.372185112468, 15703.528894133195, 15741.606459838596, 11824.763504729857, 12068.637940861789, 12073.396330183456, 12184.329861857683, 12207.633770986884, 15600.29798659063, 15783.763243551355, 15806.36774529006, 15845.445409410615, 15909.443169489012, 5880.470757631833, 6134.570580059442, 6604.207495100619, 6664.966675166593, 6797.456203503708, 690.4993879437368, 753.2279413601103, 798.297498201579, 914.3919293669768, 966.2511553545918, 28727.856360718062, 29109.0, 29559.704402956126, 30262.69737302643, 30423.98004329261, 1002.0580654185043, 1007.8387458006683, 1036.0, 1094.6934858239706, 1150.5972909481782, 14487.20320382389, 14920.850791492163, 15005.32476038559, 15204.444622087294, 15344.462943796914, 573406.0, 580613.0, 656654.0, 658040.0, 658178.963736267, 54161.72919135379, 55965.72017139888, 56506.7174664124, 56602.0, 56676.0, 1281.2952875918209, 1295.1710905020793, 1301.1477482249143, 1317.703516708752, 1323.7617228898698, 28129.0, 28605.570916436685, 28745.856270718512, 28775.5683664741, 29255.85372073126, 47794.28308575304, 47923.76038119787, 48510.75744621254, 48713.75643121762, 48876.75561622169, 607875.0, 612385.0, 612592.0, 612955.0, 616013.0, 25452.56390387274, 25665.0, 25702.485950281265, 25913.22260332193, 25998.740012599632, 44328.11343773171, 45439.318410224536, 45626.54373456223, 45901.0, 46139.30791038204, 37685.623143768215, 37745.62254377421, 37905.81047094747, 38658.2268354637, 39555.0, 31337.0, 33339.99980000604, 33764.15589610279, 34975.0, 36933.0, 35247.647523524436, 36278.0, 37464.876053718435, 37593.0, 38943.805280973414, 29823.70176298209, 33731.83134084314, 33847.32305353929, 33915.830420847735, 34035.65964340325, 21573.0, 24474.0, 24891.128810491547, 24993.420170528825, 25411.618825718], "value": 19128.654426695986, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.97496200303976, 367.2141616939751, 367.73339328986486, 372.17006076449536, 397.3503322068419, 16429.83570164298, 17941.730874036883, 18835.811641883593, 20119.0, 20140.798592014075, 18349.990750508718, 19037.2385104596, 19042.42872713821, 19186.42440726777, 19398.127084281205, 4456.2647163218035, 5078.339815823947, 5299.311089558352, 5304.602154838383, 5318.175682769158, 4911.82808601699, 5152.098382782998, 5219.321488206547, 5221.451747566489, 5234.895302093956, 5145.202493613496, 5168.948310516903, 5216.347956505437, 5243.475652434764, 5260.184671375943, 4936.210206366982, 6548.8035358939305, 7437.405007599379, 7500.399968002601, 7508.662110204999, 6971.163460384786, 7054.541454805421, 7267.63661816907, 7275.199728029913, 7293.52592081513, 23688.526229475487, 26879.73120268792, 27271.727282727115, 27563.586546201823, 27781.722182778114, 2331.720193576765, 2403.6154215325464, 2488.4525404411, 3220.5813244278092, 3232.2404235004756, 13180.472781088745, 15662.843371566252, 15921.044737315799, 16493.518158139, 16999.40502082421, 1906.266087556287, 1907.9220240564036, 1980.7326010988477, 2072.450800537859, 2074.502119491326, 108016.13927373287, 111165.44417277862, 130827.0, 132474.0, 138504.0, 17006.48980530586, 20285.377169826377, 20948.895255523625, 21183.046762895705, 21334.35996920064, 33988.0, 34040.0, 34124.97625071252, 34165.829170854486, 34201.460934258015, 10137.898621013843, 10326.896731032744, 10540.474807788756, 10622.93770622947, 10822.296550724177, 8635.913640863511, 8654.48073115615, 8692.174243446863, 8818.51498167594, 8853.424527405698, 9304.627814887328, 9393.76515587102, 9429.434233945985, 9523.809523809485, 9541.284403669753, 2882.3802882380387, 3718.8326525306425, 3747.3816820224356, 3750.362438385481, 3812.7331086823656, 788.9289963903161, 835.853725598018, 838.0362583029565, 844.5777111444278, 898.7034278688018, 16144.677106457804, 20615.793842061386, 20659.793402066087, 20732.481687957923, 21277.36167914965, 840.3529282452478, 886.3086792302015, 887.2502735188755, 899.7345782994032, 977.6654866107712, 8922.643094276285, 10560.2079844012, 10651.520681569347, 10869.728256793644, 10951.726206844893, 550609.0, 574159.0, 586715.0, 595527.0, 599019.0, 117240.0, 117364.0, 131078.44607769573, 134006.0, 134776.32611837075, 940.3088729783603, 958.7411398922252, 984.4585477987222, 985.1183191044119, 986.5856340337076, 14274.429022838971, 17525.737113943556, 18770.71843922315, 18838.49292056605, 19083.90458047701, 37209.99940051499, 49545.50454495409, 50779.74610126926, 57646.0, 59354.51613709519, 78178.0, 85399.1460085416, 85541.57229213814, 86056.0, 87979.68030479676, 6883.586984780929, 7254.746083887038, 7579.393648508217, 7953.443258972048, 8212.589370531568, 11000.339979601249, 12494.500219991462, 12728.796981975926, 12852.164609300366, 13036.0, 9257.722268331961, 10720.785584288427, 11085.334879907232, 11122.870779000248, 11304.49129789145, 10451.634192803216, 10745.838812417664, 10881.238313317992, 10943.562257509611, 11254.099672026054, 10996.23026388145, 11131.220814542905, 11273.492692829006, 11341.036011938748, 11414.771704565695, 6070.757169713339, 6886.24251332363, 7102.0, 7356.374708149867, 7578.431617628589, 6753.750556147129, 10068.949655251678, 10787.460626968463, 11096.500657470271, 11273.463470719023], "value": 10376.88917956407, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.3397437178894, 1459.8759105476036, 1616.8520350551107, 1751.640913612708, 1766.8321509456598, 7577.734779282726, 7837.451378403514, 8081.9191808081905, 8533.786655333619, 8561.143885611444, 8038.437309388345, 8305.91694083059, 8326.958365208166, 8431.95784021079, 8437.746867593967, 2251.0, 2296.2766728480474, 2299.4136495193784, 2299.677685330933, 2307.1002309099463, 2271.7955384015436, 2301.344116926676, 2311.318161142467, 2312.537492501498, 2313.571989181998, 2262.1856131792542, 2270.795628393444, 2286.1998300594823, 2286.8856557172157, 2292.805111565519, 2230.475838178027, 2240.0591751464426, 2260.1185537640335, 2264.1396269417596, 2267.7051983242236, 2053.4455696961736, 2133.6557968479915, 2284.127015847006, 2293.4495721027, 2301.873396963166, 8388.538630375328, 8394.286485648727, 8444.0, 8491.278241349431, 8496.405251632388, 5056.62075344347, 5210.583153347723, 5219.791208351661, 5226.268322434862, 5230.686158830445, 3215.180129067073, 3293.9835300823584, 3553.6979356754705, 3563.625819288989, 3571.6606922342594, 986.6201512417771, 997.2321312589338, 1039.7140786283758, 1067.210264404343, 1070.9732256693567, 9871.111599955966, 10084.49577521121, 10495.160387169008, 10795.0, 10807.513661885234, 4255.1915136124035, 4327.307630779123, 4447.844325448594, 4460.464744230648, 4478.6864919455975, 7694.0, 8028.0, 8711.912880871238, 8727.694530691395, 8729.694460693845, 5059.62052846038, 5077.365329333833, 5078.365204349457, 5080.77136528857, 5100.336956195671, 4004.619561141687, 4300.720453170534, 4443.933455970544, 4626.7223966562115, 4784.736839473862, 3576.8390422431053, 3624.5288112545727, 3665.9816700916326, 3697.852085916587, 3709.536307961505, 1128.4357821089454, 1137.8122609769462, 1138.743782648914, 1147.9782993135948, 1150.4132892225025, 850.2177996243347, 856.8457677618088, 873.4846440600077, 908.5457271364318, 909.4679612426672, 5648.717564121777, 5721.542276617861, 5825.883482330414, 5841.503472204825, 6012.188354572164, 789.976979811146, 800.519688187083, 808.5916612110964, 855.9856569964612, 881.9735407937773, 3295.3574053059424, 3683.9263214735556, 3712.535933008374, 3833.6358045985585, 3880.8447662093763, 9637.759056023655, 10217.13154381871, 10289.639862604772, 10405.791884162274, 10422.062014418738, 10252.128569071563, 10290.434026128634, 10326.122279606167, 10382.961703829556, 10400.427976461217, 7819.413543984224, 7860.999999999885, 7862.135165131831, 7961.442699011013, 8042.9597852010365, 4769.952300476951, 4864.73243971585, 5083.212102124176, 5225.111731005814, 5737.168110624019, 9343.34596578233, 9613.855792162984, 9616.423014619146, 9621.08599683029, 9663.903360966582, 8754.69358572447, 8979.685711000084, 9721.4653194075, 9724.319297649097, 9736.269779766404, 6283.717232724447, 6512.73949042033, 6656.267810540933, 6722.9663851680425, 6879.724811007503, 7848.0, 8074.232947870178, 8079.434439589172, 8113.0669972953865, 8130.349572034342, 7135.072440582691, 7305.0, 7343.742968996059, 7366.337029667141, 7415.369693576106, 7085.397741192057, 7091.858162836817, 7116.074910261633, 7295.124585049828, 7299.562026278228, 6626.56927299724, 6764.492663050192, 6775.0176224446195, 6792.320767923168, 6811.352921472452, 4354.390385346117, 4715.976420117878, 4755.881102972453, 4800.799800049987, 4839.733814640229, 4678.836240731558, 4771.761411929459, 4836.637252206028, 4890.682105663121, 5073.670211436245], "value": 4346.027596692069, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.628559729553, 296.58774303717837, 300.4756699559269, 300.6107091316745, 312.69043646789686, 5191.818286359978, 5298.867528311795, 5370.3555573331205, 5484.890302193954, 5485.424030476797, 4387.63983165219, 5041.269015992679, 5187.48125187481, 5223.555997740187, 5313.840584782453, 4069.511658600972, 4109.363048727452, 4110.198511290299, 4112.773797441139, 4121.711480196387, 3460.8406183928287, 3711.7030637549065, 4075.6535694466006, 4108.938365924499, 4123.340265557511, 3379.7972121672656, 3964.544077431089, 4016.4294851295194, 4038.5759495253014, 4041.2523683118716, 3595.370810108234, 3627.927441451169, 4026.9194616107807, 4056.762687380348, 4057.857974970862, 3524.2951409718157, 3545.166885781839, 3553.0406790166767, 3560.753736192338, 3572.696320812734, 4696.506866778973, 5021.347224860745, 5183.611229157828, 5191.506806853343, 5209.004822227234, 3965.4250133730734, 4168.937465938014, 4403.339499075164, 4621.2837010263465, 4637.234856248749, 4260.08408192237, 4756.66703330767, 4841.055994081155, 5182.689038657692, 5587.776488940438, 3293.736501079908, 3707.6663100321102, 3809.8189561236104, 3853.0, 3882.5340959084806, 5268.578513718893, 5806.535477161817, 5875.9118613220835, 5903.291605007384, 5928.377520360344, 3949.926288698399, 3950.6839452843706, 3958.050067983721, 3965.9603403966166, 3985.262726395626, 3856.2480316338606, 3989.7606143631474, 3997.620226078518, 4002.7598344099447, 4028.959710402917, 3957.3470377387985, 4086.8773936781945, 4164.958760309923, 4611.976940115278, 4613.607843333287, 4507.752073635916, 5650.63270887391, 5660.943390566042, 5664.943350566524, 5698.6580805151825, 1215.9574414895437, 1233.734747029393, 1263.387257180267, 1278.8554243951612, 1298.8246586710861, 767.681412213925, 808.9838203235902, 865.7446053414258, 876.5755647073505, 898.6405437824923, 4366.956330436655, 4838.080764654705, 5048.980407836894, 5064.215046667772, 5120.159880089933, 4729.716217026989, 4973.701577905337, 5280.894382112337, 5476.397596264408, 5535.723213839292, 4177.352510360899, 4198.832046718097, 4529.977350113229, 4817.084753896748, 5027.79888804451, 2573.305207593939, 2658.5081759874097, 2702.1083042595897, 2789.595508651234, 2971.985140074286, 5362.463753624606, 5584.766093562607, 5815.302163740378, 5823.388544202882, 5828.883422331614, 4635.261776833688, 4757.26262429324, 5345.9732701337025, 5766.0, 5767.740451679684, 4881.365422495053, 5291.682499049993, 5450.5639548836, 5609.4390560943575, 6175.722092505758, 5947.583669143119, 5984.31180414258, 6002.249718785152, 6018.428249316308, 6021.969890150521, 5420.457954204706, 5737.799177028784, 5807.796727114531, 5865.794697185578, 5879.853003674943, 5244.37067551881, 6017.886690962186, 6116.418940200674, 6147.661878596541, 6159.168512250789, 3750.943735844019, 3806.695464362789, 3942.625450582305, 3958.524977002778, 3969.2656858481273, 5357.8124765634575, 5742.942570574241, 5824.359320474659, 5920.911186332122, 5921.14143449206, 5046.495350464924, 5584.413636568264, 5636.436356364331, 5751.7411716472025, 5777.277840269966, 4819.855404337875, 5742.885142297214, 5747.798827041033, 5786.161006654096, 5792.362840087502, 4421.894526368408, 4688.0858232644305, 5166.793328266827, 5533.446655334435, 5629.4652008059165, 5006.674566153189, 5144.614153938544, 5469.890602188014, 5739.282589676291, 5838.532917366685, 4725.432948046257, 4969.925451118163, 5093.592512598909, 5617.859553511195, 5749.453801888814], "value": 4038.3625005560934, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.1590827374056, 690.5649440852262, 721.6139365439491, 739.1315204634553, 740.5852722475414, 14020.64948376291, 14414.783778243322, 14432.20622865742, 14780.482683106093, 14803.185824779634, 13155.0, 13326.666833329171, 13340.199588024725, 13351.265680387574, 13360.866391336109, 636.6975686548899, 654.6628486329544, 784.5214419204281, 795.3557618329172, 807.4347956430522, 792.6472719639762, 798.5288679678988, 804.2761514636816, 804.8470790549806, 804.9678012879507, 857.3526987124712, 860.9311255099577, 864.7189663359402, 868.2706526517724, 868.5613765048641, 827.1976183102381, 848.5587494502855, 859.9312055035582, 861.6984055580529, 862.0991064337782, 624.2883113250898, 631.9431251187415, 639.8688268904864, 639.9200099987502, 640.2445114764575, 13361.398737056856, 13496.39266233022, 13499.66250843727, 13545.864541354658, 13898.0, 10693.732656683567, 13086.86913130866, 13223.0, 13405.757306467207, 13741.519046833413, 11187.328760274328, 11305.321680699102, 11356.772864542745, 11373.260738052082, 11378.671342605494, 3583.5341405617364, 3649.525561676965, 3700.1859590889962, 3754.2116155607355, 3770.830312635938, 94134.0, 96348.51825740826, 96949.51525242328, 97479.0, 97909.0, 10398.272120951611, 10425.9478702606, 10446.320989135682, 10448.634297799541, 10819.296745711503, 20590.485237869172, 20598.38204853857, 20625.89687051585, 20668.276610318568, 20672.379828605164, 6545.461816473125, 7532.058492688414, 7549.396048316122, 7699.615019249015, 7751.0, 4125.381192821101, 4214.178235244098, 4219.261629214875, 4220.556841531595, 4231.640310573574, 3341.2482191506715, 3435.2270739083506, 3443.2941247044296, 3469.4275444551367, 3490.5636795400574, 623.9188905442263, 631.1542533005703, 641.9293877673452, 648.5524987758431, 650.778735230024, 619.4765423217374, 631.7473010795627, 650.3366566102549, 652.9869402611921, 675.3381685947701, 7151.284871512911, 8151.592420378957, 8825.69110081144, 8952.462852228888, 9004.054574269738, 529.6186745543203, 640.309582701668, 641.4643772449963, 671.0873212431187, 677.8508728079814, 3588.6949609282983, 3819.866681887876, 4292.892677683083, 4512.278035514302, 4651.302304654261, 62891.0, 75945.0, 86999.0, 98377.0, 105183.0, 82486.0, 101302.0, 101750.49124754476, 101803.49098254461, 105223.0, 67125.66437167881, 89288.0, 89450.65824012645, 91910.0, 92508.07491925129, 8194.590270486571, 8231.312580920778, 8240.175982401952, 8509.446885952393, 8516.361272904433, 42745.1450970985, 50728.0, 50776.74611627066, 53815.730921345144, 57053.14420283782, 64437.35562644314, 66377.66811165914, 68142.0, 70888.0, 75396.11509712081, 10869.619563315246, 10890.29213101146, 10938.234323597271, 10997.780044399227, 11414.0, 11870.169088163746, 11918.463669134737, 11930.880691193324, 12025.278483291031, 12462.252264864137, 11620.88379116198, 11733.823992640288, 11756.353400563052, 11828.58599949032, 12152.966997805286, 8298.419110662197, 9720.951395242979, 10596.8940310599, 10792.974667406585, 11092.500837462172, 10910.399928004035, 11287.548498059985, 11424.885751142383, 11820.527178912747, 11921.821172682243, 9020.727738488646, 9567.521623918916, 9698.418094914328, 9709.56306966174, 9984.652071970191, 7265.0, 9729.902700973184, 9857.507124643882, 9861.704148875546, 9867.25995550351], "value": 6944.392921660123, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1631.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 169.0, 145.0, 123.0, 162.0, 143.0, 117.0, 100.0, 168.0, 112.0], "value": 138.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 144.0, 117.0, 192.0, 149.0, 147.0, 117.0, 116.0, 145.0, 144.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [236.0, 272.0, 271.0, 191.0, 191.0, 204.0, 269.0, 237.0, 194.0, 195.0], "value": 226.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 60.0, 87.0, 57.0, 76.0, 87.0, 54.0, 95.0, 83.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 123.0, 75.0, 68.0, 72.0, 87.0, 72.0, 70.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 131.0, 79.0, 132.0, 135.0, 88.0, 90.0, 87.0, 90.0, 87.0], "value": 102.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 138.0, 129.0, 152.0, 150.0, 95.0, 95.0, 96.0, 123.0], "value": 116.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 128.0, 84.0, 94.0, 126.0, 97.0, 80.0, 74.0, 89.0, 72.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 72.0, 47.0, 47.0, 58.0, 79.0, 91.0, 91.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 71.0, 88.0, 114.0, 72.0, 72.0, 73.0, 67.0, 63.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 93.0, 89.0, 89.0, 91.0, 93.0, 89.0, 88.0, 90.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 167.0, 142.0, 159.0, 157.0, 166.0, 146.0, 197.0, 162.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 176.0, 173.0, 179.0, 176.0, 178.0, 180.0, 240.0, 226.0], "value": 187.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 69.0, 69.0, 68.0, 68.0, 69.0, 67.0, 67.0], "value": 68.1, "unit": "score"}]}]} 06:28:10 INFO - Return code: 0 06:28:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:28:10 ERROR - # TBPL SUCCESS # 06:28:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:28:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:28:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:28:10 INFO - 2016-11-08 06:28:10.091 screenresolution[1692:10499] starting screenresolution argv=screenresolution get 06:28:10 INFO - 2016-11-08 06:28:10.093 screenresolution[1692:10499] Display 0: 1600x1200x32@60 06:28:10 INFO - 2016-11-08 06:28:10.099 screenresolution[1693:10502] starting screenresolution argv=screenresolution list 06:28:10 INFO - Available Modes on Display 0 06:28:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:28:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:28:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:28:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:28:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:28:10 INFO - Intel Iris: 06:28:10 INFO - Chipset Model: Intel Iris 06:28:10 INFO - Type: GPU 06:28:10 INFO - Bus: Built-In 06:28:10 INFO - VRAM (Dynamic, Max): 1536 MB 06:28:10 INFO - Vendor: Intel (0x8086) 06:28:10 INFO - Device ID: 0x0a2e 06:28:10 INFO - Revision ID: 0x0009 06:28:10 INFO - Displays: 06:28:10 INFO - Display: 06:28:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:28:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:28:10 INFO - Display Serial Number: 1600x1200 60 06:28:10 INFO - Main Display: Yes 06:28:10 INFO - Mirror: Off 06:28:10 INFO - Online: Yes 06:28:10 INFO - Rotation: Supported 06:28:10 INFO - Return code: 0 06:28:10 INFO - Running post-action listener: _resource_record_post_action 06:28:10 INFO - [mozharness: 2016-11-08 14:28:10.338421Z] Finished run-tests step (success) 06:28:10 INFO - Running post-run listener: _resource_record_post_run 06:28:10 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 38524928; Write bytes: 708000768; Read time: 252; Write time: 1987 06:28:10 INFO - TinderboxPrint: CPU usage
54.4% 06:28:10 INFO - TinderboxPrint: I/O read bytes / time
38,524,928 / 252 06:28:10 INFO - TinderboxPrint: I/O write bytes / time
708,000,768 / 1,987 06:28:10 INFO - TinderboxPrint: CPU idle
2,211.5 (45.6%) 06:28:10 INFO - TinderboxPrint: CPU system
981.8 (20.2%) 06:28:10 INFO - TinderboxPrint: CPU user
1,660.3 (34.2%) 06:28:10 INFO - TinderboxPrint: Swap in / out
441,974,784 / 0 06:28:10 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394711552; Write bytes: 389253120; Read time: 29565; Write time: 800 06:28:10 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36587520; Write bytes: 300643328; Read time: 233; Write time: 1136 06:28:10 INFO - Running post-run listener: _upload_blobber_files 06:28:10 INFO - Blob upload gear active. 06:28:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:28:10 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 06:28:10 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:28:10 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:28:11 INFO - (blobuploader) - INFO - Open directory for files ... 06:28:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:28:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:28:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:12 INFO - (blobuploader) - INFO - Done attempting. 06:28:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:28:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:28:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:13 INFO - (blobuploader) - INFO - Done attempting. 06:28:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:28:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:28:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:16 INFO - (blobuploader) - INFO - Done attempting. 06:28:16 INFO - (blobuploader) - INFO - Iteration through files over. 06:28:16 INFO - Return code: 0 06:28:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:28:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:28:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/597d2d7deed5d9a762412333553c8d981325ad8ab5274c4bf46cbe634b886b3ca61961ccc52930baf4f811ee0c2e3c2f60e3360891051b72b5efea52b49fca97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d0613c9d26296e1c2bb6a7c1be5f6fdaf243293a103d0cd8e6a65f29802877021e4ab483964eacaa5cfa6467318da3092fac4fa93a57887f630e9f372e612a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34296180f1c69a558bc715cfa8a62df78c43bfaa64c4c34b53fc5e263f95d79f85b48c2782dae2998c9d582222db380d9d6b4d8c6b66a54cd689d76dde29042d"} 06:28:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:28:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:28:16 INFO - Contents: 06:28:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/597d2d7deed5d9a762412333553c8d981325ad8ab5274c4bf46cbe634b886b3ca61961ccc52930baf4f811ee0c2e3c2f60e3360891051b72b5efea52b49fca97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d0613c9d26296e1c2bb6a7c1be5f6fdaf243293a103d0cd8e6a65f29802877021e4ab483964eacaa5cfa6467318da3092fac4fa93a57887f630e9f372e612a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34296180f1c69a558bc715cfa8a62df78c43bfaa64c4c34b53fc5e263f95d79f85b48c2782dae2998c9d582222db380d9d6b4d8c6b66a54cd689d76dde29042d"} 06:28:16 INFO - Running post-run listener: copy_logs_to_upload_dir 06:28:16 INFO - Copying logs to upload dir... 06:28:16 INFO - mkdir: /builds/slave/test/build/upload/logs 06:28:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.012760 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-08 06:28:16.626895) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 06:28:16.630427) ========= 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.lTkunpRdJp/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.53hINyAt6T/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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/Firefox/sha512/597d2d7deed5d9a762412333553c8d981325ad8ab5274c4bf46cbe634b886b3ca61961ccc52930baf4f811ee0c2e3c2f60e3360891051b72b5efea52b49fca97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d0613c9d26296e1c2bb6a7c1be5f6fdaf243293a103d0cd8e6a65f29802877021e4ab483964eacaa5cfa6467318da3092fac4fa93a57887f630e9f372e612a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34296180f1c69a558bc715cfa8a62df78c43bfaa64c4c34b53fc5e263f95d79f85b48c2782dae2998c9d582222db380d9d6b4d8c6b66a54cd689d76dde29042d"} build_url:https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/597d2d7deed5d9a762412333553c8d981325ad8ab5274c4bf46cbe634b886b3ca61961ccc52930baf4f811ee0c2e3c2f60e3360891051b72b5efea52b49fca97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d0613c9d26296e1c2bb6a7c1be5f6fdaf243293a103d0cd8e6a65f29802877021e4ab483964eacaa5cfa6467318da3092fac4fa93a57887f630e9f372e612a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34296180f1c69a558bc715cfa8a62df78c43bfaa64c4c34b53fc5e263f95d79f85b48c2782dae2998c9d582222db380d9d6b4d8c6b66a54cd689d76dde29042d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 06:28:16.663335) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:28:16.663670) ========= 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.lTkunpRdJp/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.53hINyAt6T/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.005189 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:28:16.711939) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 06:28:16.712248) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 06:28:16.712627) ========= ========= Total master_lag: 0.17 =========