builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0207 starttime: 1477312864.85 results: success (0) buildid: 20161024030205 builduid: 25896b81c02c43e39fb49284fea31552 revision: 215f9686117673a2c914ed207bc7da9bb8d741ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:04.848760) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:04.851868) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:04.852166) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:04.878487) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:04.878849) ========= 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.ufhXp8zVrk/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.aNulkoaU9J/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-24 05:41:04-- 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% 27.8M=0s 2016-10-24 05:41:05 (27.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.615899 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:05.570475) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:05.570795) ========= 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.ufhXp8zVrk/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.aNulkoaU9J/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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.025697 ========= master_lag: 0.11 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:05.708138) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-24 05:41:05.708450) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 215f9686117673a2c914ed207bc7da9bb8d741ad --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 215f9686117673a2c914ed207bc7da9bb8d741ad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ufhXp8zVrk/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.aNulkoaU9J/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-24 05:41:05,842 truncating revision to first 12 chars 2016-10-24 05:41:05,842 Setting DEBUG logging. 2016-10-24 05:41:05,842 attempt 1/10 2016-10-24 05:41:05,842 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/215f96861176?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-24 05:41:07,037 unpacking tar archive at: mozilla-central-215f96861176/testing/mozharness/ program finished with exit code 0 elapsedTime=2.828112 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-24 05:41:08.608891) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:08.609202) ========= script_repo_revision: 215f9686117673a2c914ed207bc7da9bb8d741ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:08.609599) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:08.609879) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 05:41:08.628487) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-24 05:41:08.628875) ========= /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.ufhXp8zVrk/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.aNulkoaU9J/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:41:08 INFO - MultiFileLogger online at 20161024 05:41:08 in /builds/slave/test 05:41:08 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 05:41:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:41:08 INFO - {'append_to_log': False, 05:41:08 INFO - 'base_work_dir': '/builds/slave/test', 05:41:08 INFO - 'blob_upload_branch': 'Firefox', 05:41:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:41:08 INFO - 'branch': 'Firefox', 05:41:08 INFO - 'buildbot_json_path': 'buildprops.json', 05:41:08 INFO - 'config_files': ('talos/mac_config.py',), 05:41:08 INFO - 'default_actions': ('clobber', 05:41:08 INFO - 'read-buildbot-config', 05:41:08 INFO - 'download-and-extract', 05:41:08 INFO - 'populate-webroot', 05:41:08 INFO - 'create-virtualenv', 05:41:08 INFO - 'install', 05:41:08 INFO - 'run-tests'), 05:41:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:41:08 INFO - 'download_minidump_stackwalk': True, 05:41:08 INFO - 'download_symbols': 'ondemand', 05:41:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:41:08 INFO - 'tooltool.py': '/tools/tooltool.py', 05:41:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:41:08 INFO - '/tools/misc-python/virtualenv.py')}, 05:41:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:41:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:41:08 INFO - 'installer_path': 'installer.exe', 05:41:08 INFO - 'log_level': 'info', 05:41:08 INFO - 'log_name': 'talos', 05:41:08 INFO - 'log_to_console': True, 05:41:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:41:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:41:08 INFO - 'opt_config_files': (), 05:41:08 INFO - 'pip_index': False, 05:41:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:41:08 INFO - 'cmd': ('bash', 05:41:08 INFO - '-c', 05:41:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:41:08 INFO - 'enabled': True, 05:41:08 INFO - 'halt_on_failure': False, 05:41:08 INFO - 'name': 'check_screen_resolution'},), 05:41:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:41:08 INFO - 'cmd': ('bash', 05:41:08 INFO - '-c', 05:41:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:41:08 INFO - 'enabled': True, 05:41:08 INFO - 'halt_on_failure': False, 05:41:08 INFO - 'name': 'check_screen_resolution'},), 05:41:08 INFO - 'run_cmd_checks_enabled': True, 05:41:08 INFO - 'sps_profile': False, 05:41:08 INFO - 'sps_profile_interval': 0, 05:41:08 INFO - 'suite': 'dromaeojs-e10s', 05:41:08 INFO - 'system_bits': '32', 05:41:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:41:08 INFO - 'title': 't-yosemite-r7-0207', 05:41:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:41:08 INFO - 'use_talos_json': True, 05:41:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:41:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:41:08 INFO - 'work_dir': 'build'} 05:41:08 INFO - [mozharness: 2016-10-24 12:41:08.837096Z] Running clobber step. 05:41:08 INFO - Running pre-action listener: _resource_record_pre_action 05:41:08 INFO - Running main action method: clobber 05:41:08 INFO - rmtree: /builds/slave/test/build 05:41:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:41:11 INFO - Running post-action listener: _resource_record_post_action 05:41:11 INFO - [mozharness: 2016-10-24 12:41:11.038742Z] Finished clobber step (success) 05:41:11 INFO - [mozharness: 2016-10-24 12:41:11.038867Z] Running read-buildbot-config step. 05:41:11 INFO - Running pre-action listener: _resource_record_pre_action 05:41:11 INFO - Running main action method: read_buildbot_config 05:41:11 INFO - Using buildbot properties: 05:41:11 INFO - { 05:41:11 INFO - "project": "", 05:41:11 INFO - "product": "firefox", 05:41:11 INFO - "script_repo_revision": "production", 05:41:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:41:11 INFO - "repository": "", 05:41:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:41:11 INFO - "buildid": "20161024030205", 05:41:11 INFO - "stage_platform": "macosx64", 05:41:11 INFO - "basedir": "/builds/slave/test", 05:41:11 INFO - "nightly_build": "True", 05:41:11 INFO - "buildnumber": 151, 05:41:11 INFO - "slavename": "t-yosemite-r7-0207", 05:41:11 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:41:11 INFO - "platform": "yosemite_r7", 05:41:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:41:11 INFO - "branch": "mozilla-central", 05:41:11 INFO - "repo_path": "mozilla-central", 05:41:11 INFO - "revision": "215f9686117673a2c914ed207bc7da9bb8d741ad", 05:41:11 INFO - "pgo_build": "False", 05:41:11 INFO - "builduid": "25896b81c02c43e39fb49284fea31552", 05:41:11 INFO - "slavebuilddir": "test" 05:41:11 INFO - } 05:41:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:41:11 INFO - Running post-action listener: _resource_record_post_action 05:41:11 INFO - [mozharness: 2016-10-24 12:41:11.042170Z] Finished read-buildbot-config step (success) 05:41:11 INFO - [mozharness: 2016-10-24 12:41:11.042279Z] Running download-and-extract step. 05:41:11 INFO - Running pre-action listener: _resource_record_pre_action 05:41:11 INFO - Running main action method: download_and_extract 05:41:11 INFO - mkdir: /builds/slave/test/build/tests 05:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:11 INFO - trying https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:41:11 INFO - Downloading https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/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 05:41:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/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 05:41:12 INFO - Downloaded 1199 bytes. 05:41:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:41:12 INFO - Using the following test package requirements: 05:41:12 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:41:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:41:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:41:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'jsshell-mac.zip'], 05:41:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:41:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:41:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:41:12 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:41:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:41:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:41:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:41:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:41:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:41:12 INFO - Fetch https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:41:12 INFO - Http code: 200 05:41:12 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 05:41:12 INFO - x-amz-cf-id: PS50pBI4mK-gTWB-DrXrXOLrMvGXvSDGY_Dbv3nAz18YHFuRIk_8EA== 05:41:12 INFO - x-amz-version-id: Nbv9pYBtR5f_6RgLvJBm2pVI168eLVXz 05:41:13 INFO - Expected file size: 16409521 05:41:13 INFO - Obtained file size: 16409521 05:41:19 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 05:41:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:41:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:41:19 INFO - Fetch https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:41:20 INFO - Http code: 200 05:41:20 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 05:41:20 INFO - x-amz-cf-id: ecWejmZuVkwW10AUUODFpY84p4TcQ_ztfCuQ4cCOHTqtkgNF2DBXJQ== 05:41:20 INFO - x-amz-version-id: Nbv9pYBtR5f_6RgLvJBm2pVI168eLVXz 05:41:20 INFO - Expected file size: 16409521 05:41:20 INFO - Obtained file size: 16409521 05:41:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:41:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:41:26 INFO - Fetch https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:41:28 INFO - Http code: 200 05:41:28 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 05:41:28 INFO - x-amz-cf-id: OlRAldqEDvD57LpvsJgympO_AUdHmCfj6UC4pw8y246wqjvwgfDRKw== 05:41:28 INFO - x-amz-version-id: Fm6lU2Qx2B8PWcCJLDkyyaR1tVx6svBL 05:41:28 INFO - Expected file size: 14409028 05:41:28 INFO - Obtained file size: 14409028 05:41: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')]} 05:41:28 INFO - trying https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:41:28 INFO - Downloading https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:41:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/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 05:41:32 INFO - Downloaded 111345375 bytes. 05:41:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:41:33 INFO - mkdir: /builds/slave/test/properties 05:41:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:41:33 INFO - Writing to file /builds/slave/test/properties/build_url 05:41:33 INFO - Contents: 05:41:33 INFO - build_url:https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:41:33 INFO - Running post-action listener: _resource_record_post_action 05:41:33 INFO - Running post-action listener: set_extra_try_arguments 05:41:33 INFO - [mozharness: 2016-10-24 12:41:33.678839Z] Finished download-and-extract step (success) 05:41:33 INFO - [mozharness: 2016-10-24 12:41:33.678949Z] Running populate-webroot step. 05:41:33 INFO - Running pre-action listener: _resource_record_pre_action 05:41:33 INFO - Running main action method: populate_webroot 05:41:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:41:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:41:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:41:33 INFO - '--tppagecycles', 05:41:33 INFO - '7'], 05:41:33 INFO - 'tests': ['tsvgm']}}, 05:41:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:41:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:41:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:41:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:41:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:33 INFO - 'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:41:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:41:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:33 INFO - 'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['damp', 'tps']}, 05:41:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:33 INFO - 'tests': ['damp', 'tps']}, 05:41:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['dromaeo_dom']}, 05:41:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:41:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['basic_compositor_video']}, 05:41:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:41:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['a11yr', 05:41:33 INFO - 'ts_paint', 05:41:33 INFO - 'tpaint', 05:41:33 INFO - 'sessionrestore', 05:41:33 INFO - 'sessionrestore_no_auto_restore', 05:41:33 INFO - 'tabpaint']}, 05:41:33 INFO - 'other-e10s': {'tests': ['a11yr', 05:41:33 INFO - 'ts_paint', 05:41:33 INFO - 'tpaint', 05:41:33 INFO - 'sessionrestore', 05:41:33 INFO - 'sessionrestore_no_auto_restore', 05:41:33 INFO - 'tabpaint']}, 05:41:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:41:33 INFO - 'ts_paint', 05:41:33 INFO - 'tpaint', 05:41:33 INFO - 'sessionrestore', 05:41:33 INFO - 'sessionrestore_no_auto_restore', 05:41:33 INFO - 'tabpaint']}, 05:41:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:41:33 INFO - 'ts_paint', 05:41:33 INFO - 'tpaint', 05:41:33 INFO - 'sessionrestore', 05:41:33 INFO - 'sessionrestore_no_auto_restore', 05:41:33 INFO - 'tabpaint']}, 05:41:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['a11yr', 05:41:33 INFO - 'ts_paint', 05:41:33 INFO - 'tpaint', 05:41:33 INFO - 'sessionrestore', 05:41:33 INFO - 'sessionrestore_no_auto_restore', 05:41:33 INFO - 'tabpaint']}, 05:41:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['a11yr', 05:41:33 INFO - 'ts_paint', 05:41:33 INFO - 'tpaint', 05:41:33 INFO - 'sessionrestore', 05:41:33 INFO - 'sessionrestore_no_auto_restore', 05:41:33 INFO - 'tabpaint']}, 05:41:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['tsvgx', 05:41:33 INFO - 'tsvgr_opacity', 05:41:33 INFO - 'tart', 05:41:33 INFO - 'tscrollx', 05:41:33 INFO - 'cart']}, 05:41:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:41:33 INFO - 'tsvgr_opacity', 05:41:33 INFO - 'tart', 05:41:33 INFO - 'tscrollx', 05:41:33 INFO - 'cart']}, 05:41:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:33 INFO - 'talos_options': ['--disable-e10s'], 05:41:33 INFO - 'tests': ['tp5o']}, 05:41:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:33 INFO - 'tests': ['tp5o']}, 05:41:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:33 INFO - 'talos_options': ['--disable-e10s', 05:41:33 INFO - '--xperf_path', 05:41:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:41:33 INFO - 'tests': ['tp5n']}, 05:41:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:33 INFO - 'talos_options': ['--xperf_path', 05:41:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:41:33 INFO - 'tests': ['tp5n']}}, 05:41:33 INFO - 'talos.zip': {'path': '', 05:41:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:41:33 INFO - Running post-action listener: _resource_record_post_action 05:41:33 INFO - [mozharness: 2016-10-24 12:41:33.697784Z] Finished populate-webroot step (success) 05:41:33 INFO - [mozharness: 2016-10-24 12:41:33.697906Z] Running create-virtualenv step. 05:41:33 INFO - Running pre-action listener: _resource_record_pre_action 05:41:33 INFO - Running main action method: create_virtualenv 05:41:33 INFO - Creating virtualenv /builds/slave/test/build/venv 05:41:33 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 05:41:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:41:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:41:33 INFO - Using real prefix '/tools/python27' 05:41:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:41:34 INFO - Installing distribute.............................................................................................................................................................................................done. 05:41:37 INFO - Installing pip.................done. 05:41:37 INFO - Return code: 0 05:41:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:41:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:41:37 INFO - Reading from file tmpfile_stdout 05:41:37 INFO - Output received: 05:41:37 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) 05:41:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff07b4547c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, '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.ufhXp8zVrk/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.aNulkoaU9J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:41:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:41:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:41:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:41:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:37 INFO - 'HOME': '/Users/cltbld', 05:41:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:37 INFO - 'LOGNAME': 'cltbld', 05:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:37 INFO - 'MOZ_NO_REMOTE': '1', 05:41:37 INFO - 'NO_EM_RESTART': '1', 05:41:37 INFO - 'PAGER': '/bin/cat', 05:41:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:37 INFO - 'PWD': '/builds/slave/test', 05:41:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:37 INFO - 'SHELL': '/bin/bash', 05:41:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:41:37 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:41:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:37 INFO - 'USER': 'cltbld', 05:41:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:37 INFO - 'XPC_FLAGS': '0x0', 05:41:37 INFO - 'XPC_SERVICE_NAME': '0', 05:41:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:37 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 05:41:37 INFO - Cleaning up... 05:41:37 INFO - Return code: 0 05:41:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a4d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff07b4547c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, '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.ufhXp8zVrk/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.aNulkoaU9J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:41:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:41:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:41:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:41:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:37 INFO - 'HOME': '/Users/cltbld', 05:41:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:37 INFO - 'LOGNAME': 'cltbld', 05:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:37 INFO - 'MOZ_NO_REMOTE': '1', 05:41:37 INFO - 'NO_EM_RESTART': '1', 05:41:37 INFO - 'PAGER': '/bin/cat', 05:41:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:37 INFO - 'PWD': '/builds/slave/test', 05:41:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:37 INFO - 'SHELL': '/bin/bash', 05:41:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:41:37 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:41:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:37 INFO - 'USER': 'cltbld', 05:41:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:37 INFO - 'XPC_FLAGS': '0x0', 05:41:37 INFO - 'XPC_SERVICE_NAME': '0', 05:41:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:38 INFO - Downloading/unpacking psutil>=3.1.1 05:41: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 05:41: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 05:41: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 05:41: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 05:41: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 05:41: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 05:41:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:41:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:41:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:41:42 INFO - Installing collected packages: psutil 05:41:42 INFO - Running setup.py install for psutil 05:41:42 INFO - building 'psutil._psutil_osx' extension 05:41:42 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 05:41:42 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 05:41:42 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 05:41:42 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 05:41:42 INFO - building 'psutil._psutil_posix' extension 05:41:42 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 05:41:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:41:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:41:43 INFO - ^ 05:41:43 INFO - 1 warning generated. 05:41:43 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 05:41:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:41:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:41:43 INFO - Successfully installed psutil 05:41:43 INFO - Cleaning up... 05:41:43 INFO - Return code: 0 05:41:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff07b4547c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, '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.ufhXp8zVrk/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.aNulkoaU9J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:41:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:41:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:41:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:43 INFO - 'HOME': '/Users/cltbld', 05:41:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:43 INFO - 'LOGNAME': 'cltbld', 05:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:43 INFO - 'MOZ_NO_REMOTE': '1', 05:41:43 INFO - 'NO_EM_RESTART': '1', 05:41:43 INFO - 'PAGER': '/bin/cat', 05:41:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:43 INFO - 'PWD': '/builds/slave/test', 05:41:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:43 INFO - 'SHELL': '/bin/bash', 05:41:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:41:43 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:41:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:43 INFO - 'USER': 'cltbld', 05:41:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:43 INFO - 'XPC_FLAGS': '0x0', 05:41:43 INFO - 'XPC_SERVICE_NAME': '0', 05:41:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:41: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 05:41: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 05:41: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 05:41: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 05:41: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 05:41: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 05:41:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:41:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:41:46 INFO - Installing collected packages: mozsystemmonitor 05:41:46 INFO - Running setup.py install for mozsystemmonitor 05:41:46 INFO - Successfully installed mozsystemmonitor 05:41:46 INFO - Cleaning up... 05:41:46 INFO - Return code: 0 05:41:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:41:46 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')]} 05:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:46 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')]} 05:41:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:46 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 0x10a4d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff07b4547c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, '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.ufhXp8zVrk/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.aNulkoaU9J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:41:46 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 05:41:46 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 05:41:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:41:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:46 INFO - 'HOME': '/Users/cltbld', 05:41:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:46 INFO - 'LOGNAME': 'cltbld', 05:41:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:46 INFO - 'MOZ_NO_REMOTE': '1', 05:41:46 INFO - 'NO_EM_RESTART': '1', 05:41:46 INFO - 'PAGER': '/bin/cat', 05:41:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:46 INFO - 'PWD': '/builds/slave/test', 05:41:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:46 INFO - 'SHELL': '/bin/bash', 05:41:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:41:46 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:41:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:46 INFO - 'USER': 'cltbld', 05:41:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:46 INFO - 'XPC_FLAGS': '0x0', 05:41:46 INFO - 'XPC_SERVICE_NAME': '0', 05:41:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:46 INFO - Downloading/unpacking jsonschema==2.5.1 05:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:41:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:41:49 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 05:41:49 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 05:41:49 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 05:41:49 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 05:41:49 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 05:41:49 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 05:41:49 INFO - Downloading functools32-3.2.3-2.tar.gz 05:41:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:41:49 INFO - warning: no files found matching '*.txt' 05:41:49 INFO - no previously-included directories found matching 'build' 05:41:49 INFO - no previously-included directories found matching 'dist' 05:41:49 INFO - no previously-included directories found matching '.git*' 05:41:49 INFO - Installing collected packages: jsonschema, functools32 05:41:49 INFO - Running setup.py install for functools32 05:41:49 INFO - warning: no files found matching '*.txt' 05:41:49 INFO - no previously-included directories found matching 'build' 05:41:49 INFO - no previously-included directories found matching 'dist' 05:41:49 INFO - no previously-included directories found matching '.git*' 05:41:49 INFO - Successfully installed jsonschema functools32 05:41:49 INFO - Cleaning up... 05:41:49 INFO - Return code: 0 05:41:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41: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')]} 05:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:49 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 0x10a4d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff07b4547c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, '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.ufhXp8zVrk/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.aNulkoaU9J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:41:49 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 05:41:49 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 05:41:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:41:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:49 INFO - 'HOME': '/Users/cltbld', 05:41:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:49 INFO - 'LOGNAME': 'cltbld', 05:41:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:49 INFO - 'MOZ_NO_REMOTE': '1', 05:41:49 INFO - 'NO_EM_RESTART': '1', 05:41:49 INFO - 'PAGER': '/bin/cat', 05:41:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:49 INFO - 'PWD': '/builds/slave/test', 05:41:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:49 INFO - 'SHELL': '/bin/bash', 05:41:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:41:49 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:41:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:49 INFO - 'USER': 'cltbld', 05:41:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:49 INFO - 'XPC_FLAGS': '0x0', 05:41:49 INFO - 'XPC_SERVICE_NAME': '0', 05:41:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:41:50 INFO - Cleaning up... 05:41:50 INFO - Return code: 0 05:41:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:41:50 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')]} 05:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:50 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')]} 05:41:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:50 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 0x10a4d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff07b4547c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, '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.ufhXp8zVrk/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.aNulkoaU9J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:41:50 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 05:41:50 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 05:41:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:41:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:50 INFO - 'HOME': '/Users/cltbld', 05:41:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:50 INFO - 'LOGNAME': 'cltbld', 05:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:50 INFO - 'MOZ_NO_REMOTE': '1', 05:41:50 INFO - 'NO_EM_RESTART': '1', 05:41:50 INFO - 'PAGER': '/bin/cat', 05:41:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:50 INFO - 'PWD': '/builds/slave/test', 05:41:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:50 INFO - 'SHELL': '/bin/bash', 05:41:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:41:50 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:41:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:50 INFO - 'USER': 'cltbld', 05:41:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:50 INFO - 'XPC_FLAGS': '0x0', 05:41:50 INFO - 'XPC_SERVICE_NAME': '0', 05:41:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:50 INFO - Downloading/unpacking blobuploader==1.2.4 05:41:50 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 05:41:50 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 05:41:50 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 05:41:50 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 05:41:50 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 05:41:50 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 05:41:52 INFO - Downloading blobuploader-1.2.4.tar.gz 05:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:41:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:41:52 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 05:41:52 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 05:41:52 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 05:41:52 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 05:41:52 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 05:41:52 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 05:41:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:41:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:41:53 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 05:41:53 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 05:41:53 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 05:41:53 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 05:41:53 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 05:41:53 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 05:41:54 INFO - Downloading docopt-0.6.1.tar.gz 05:41:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:41:54 INFO - Installing collected packages: blobuploader, requests, docopt 05:41:54 INFO - Running setup.py install for blobuploader 05:41:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:41:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:41:54 INFO - Running setup.py install for requests 05:41:54 INFO - Running setup.py install for docopt 05:41:54 INFO - Successfully installed blobuploader requests docopt 05:41:54 INFO - Cleaning up... 05:41:54 INFO - Return code: 0 05:41:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41: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')]} 05:41:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:55 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 0x10a4d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff07b4547c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, '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.ufhXp8zVrk/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.aNulkoaU9J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:41:55 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 05:41:55 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 05:41:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:41:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:55 INFO - 'HOME': '/Users/cltbld', 05:41:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:55 INFO - 'LOGNAME': 'cltbld', 05:41:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:55 INFO - 'MOZ_NO_REMOTE': '1', 05:41:55 INFO - 'NO_EM_RESTART': '1', 05:41:55 INFO - 'PAGER': '/bin/cat', 05:41:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:55 INFO - 'PWD': '/builds/slave/test', 05:41:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:55 INFO - 'SHELL': '/bin/bash', 05:41:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:41:55 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:41:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:55 INFO - 'USER': 'cltbld', 05:41:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:55 INFO - 'XPC_FLAGS': '0x0', 05:41:55 INFO - 'XPC_SERVICE_NAME': '0', 05:41:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:55 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-2cUiLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:55 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-d5vU5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:41:55 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-T45dZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:41:55 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-BBrXTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:41:55 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-DQkASY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:41:55 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-sKFmgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:41:55 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Icxr86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:41:55 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Vsipz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:41:56 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Ta1vYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:41:56 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-N_jGJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:41:56 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-CvDt8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:41:56 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-crWMF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:41:56 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-3gUne6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:41:56 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-kzyJ0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:56 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-5DMUFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:41:56 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-GAC3A6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:41:56 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-S9512e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:41:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:41:57 INFO - Running setup.py install for manifestparser 05:41:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Running setup.py install for mozcrash 05:41:57 INFO - Running setup.py install for mozdebug 05:41:57 INFO - Running setup.py install for mozdevice 05:41:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Running setup.py install for mozfile 05:41:57 INFO - Running setup.py install for mozhttpd 05:41:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Running setup.py install for mozinfo 05:41:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Running setup.py install for mozInstall 05:41:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Running setup.py install for mozleak 05:41:58 INFO - Running setup.py install for mozlog 05:41:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Running setup.py install for moznetwork 05:41:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Running setup.py install for mozprocess 05:41:58 INFO - Running setup.py install for mozprofile 05:41:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Running setup.py install for mozrunner 05:41:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:41:59 INFO - Running setup.py install for mozscreenshot 05:41:59 INFO - Running setup.py install for moztest 05:41:59 INFO - Running setup.py install for mozversion 05:41:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:41:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:41:59 INFO - Cleaning up... 05:41:59 INFO - Return code: 0 05:41:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:59 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')]} 05:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:59 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')]} 05:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:59 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 0x10a4d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff07b4547c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, '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.ufhXp8zVrk/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.aNulkoaU9J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:41:59 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 05:41:59 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 05:41:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:41:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:59 INFO - 'HOME': '/Users/cltbld', 05:41:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:59 INFO - 'LOGNAME': 'cltbld', 05:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:59 INFO - 'MOZ_NO_REMOTE': '1', 05:41:59 INFO - 'NO_EM_RESTART': '1', 05:41:59 INFO - 'PAGER': '/bin/cat', 05:41:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:59 INFO - 'PWD': '/builds/slave/test', 05:41:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:59 INFO - 'SHELL': '/bin/bash', 05:41:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:41:59 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:41:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:59 INFO - 'USER': 'cltbld', 05:41:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:59 INFO - 'XPC_FLAGS': '0x0', 05:41:59 INFO - 'XPC_SERVICE_NAME': '0', 05:41:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:59 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-eL5eZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:59 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)) 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:59 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-PnANjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:42:00 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-A2sWGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:42:00 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-RcmPpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:42:00 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-_o4m2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:42:00 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-mO5U_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:42:00 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Q4H1QN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:42:00 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-RuNbsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:42:00 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-pMCU7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:42:00 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Sz3ovt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:42:00 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-79ZKrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:42:01 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)) 05:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:42:01 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-W4pUok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:42:01 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)) 05:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:42:01 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-RnOx38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:42:01 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)) 05:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:42:01 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-_TqK8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:42:01 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)) 05:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:01 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-mGBBI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:01 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)) 05:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:42:01 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-0aLMZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:42:01 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)) 05:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:42:01 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-H5Jvx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:42:01 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)) 05:42:01 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)) 05:42:01 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)) 05:42:01 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)) 05:42:01 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)) 05:42:01 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)) 05:42:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:42:01 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 05:42:01 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 05:42:01 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 05:42:01 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 05:42:01 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 05:42:01 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 05:42:04 INFO - Downloading blessings-1.6.tar.gz 05:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:42:04 INFO - Installing collected packages: blessings 05:42:04 INFO - Running setup.py install for blessings 05:42:04 INFO - Successfully installed blessings 05:42:04 INFO - Cleaning up... 05:42:04 INFO - Return code: 0 05:42:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:42:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:42:04 INFO - Reading from file tmpfile_stdout 05:42:04 INFO - Current package versions: 05:42:04 INFO - blessings == 1.6 05:42:04 INFO - blobuploader == 1.2.4 05:42:04 INFO - docopt == 0.6.1 05:42:04 INFO - functools32 == 3.2.3-2 05:42:04 INFO - jsonschema == 2.5.1 05:42:04 INFO - manifestparser == 1.1 05:42:04 INFO - mozInstall == 1.12 05:42:04 INFO - mozcrash == 0.17 05:42:04 INFO - mozdebug == 0.1 05:42:04 INFO - mozdevice == 0.48 05:42:04 INFO - mozfile == 1.2 05:42:04 INFO - mozhttpd == 0.7 05:42:04 INFO - mozinfo == 0.9 05:42:04 INFO - mozleak == 0.1 05:42:04 INFO - mozlog == 3.4 05:42:04 INFO - moznetwork == 0.27 05:42:04 INFO - mozprocess == 0.23 05:42:04 INFO - mozprofile == 0.28 05:42:04 INFO - mozrunner == 6.12 05:42:04 INFO - mozscreenshot == 0.1 05:42:04 INFO - mozsystemmonitor == 0.3 05:42:04 INFO - moztest == 0.7 05:42:04 INFO - mozversion == 1.4 05:42:04 INFO - psutil == 3.1.1 05:42:04 INFO - requests == 1.2.3 05:42:04 INFO - wsgiref == 0.1.2 05:42:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:04 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 0x10a4d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff07b4547c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, '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.ufhXp8zVrk/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.aNulkoaU9J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:42:04 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 05:42:04 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 05:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:04 INFO - 'HOME': '/Users/cltbld', 05:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:04 INFO - 'LOGNAME': 'cltbld', 05:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:04 INFO - 'MOZ_NO_REMOTE': '1', 05:42:04 INFO - 'NO_EM_RESTART': '1', 05:42:04 INFO - 'PAGER': '/bin/cat', 05:42:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:04 INFO - 'PWD': '/builds/slave/test', 05:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:04 INFO - 'SHELL': '/bin/bash', 05:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:42:04 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:04 INFO - 'USER': 'cltbld', 05:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:04 INFO - 'XPC_FLAGS': '0x0', 05:42:04 INFO - 'XPC_SERVICE_NAME': '0', 05:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 INFO - Cleaning up... 05:42:05 INFO - Return code: 0 05:42:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:05 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 0x10a4d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff07b4547c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, '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.ufhXp8zVrk/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.aNulkoaU9J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 05:42:05 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 05:42:05 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 05:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:05 INFO - 'HOME': '/Users/cltbld', 05:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:05 INFO - 'LOGNAME': 'cltbld', 05:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:05 INFO - 'MOZ_NO_REMOTE': '1', 05:42:05 INFO - 'NO_EM_RESTART': '1', 05:42:05 INFO - 'PAGER': '/bin/cat', 05:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:05 INFO - 'PWD': '/builds/slave/test', 05:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:05 INFO - 'SHELL': '/bin/bash', 05:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:42:05 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:05 INFO - 'USER': 'cltbld', 05:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:05 INFO - 'XPC_FLAGS': '0x0', 05:42:05 INFO - 'XPC_SERVICE_NAME': '0', 05:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:42:05 INFO - Cleaning up... 05:42:05 INFO - Return code: 0 05:42:05 INFO - Running post-action listener: _resource_record_post_action 05:42:05 INFO - Running post-action listener: _start_resource_monitoring 05:42:05 INFO - Starting resource monitoring. 05:42:05 INFO - [mozharness: 2016-10-24 12:42:05.450365Z] Finished create-virtualenv step (success) 05:42:05 INFO - [mozharness: 2016-10-24 12:42:05.451369Z] Running install step. 05:42:05 INFO - Running pre-action listener: _resource_record_pre_action 05:42:05 INFO - Running main action method: install 05:42:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:42:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:42:05 INFO - Reading from file tmpfile_stdout 05:42:05 INFO - Detecting whether we're running mozinstall >=1.0... 05:42:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:42:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:42:05 INFO - Reading from file tmpfile_stdout 05:42:05 INFO - Output received: 05:42:05 INFO - Usage: mozinstall [options] installer 05:42:05 INFO - Options: 05:42:05 INFO - -h, --help show this help message and exit 05:42:05 INFO - -d DEST, --destination=DEST 05:42:05 INFO - Directory to install application into. [default: 05:42:05 INFO - "/builds/slave/test"] 05:42:05 INFO - --app=APP Application being installed. [default: firefox] 05:42:05 INFO - mkdir: /builds/slave/test/build/application 05:42:05 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'] 05:42:05 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 05:42:40 INFO - Reading from file tmpfile_stdout 05:42:40 INFO - Output received: 05:42:40 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:42:40 INFO - Running post-action listener: _resource_record_post_action 05:42:40 INFO - [mozharness: 2016-10-24 12:42:40.014107Z] Finished install step (success) 05:42:40 INFO - [mozharness: 2016-10-24 12:42:40.014273Z] Running run-tests step. 05:42:40 INFO - Running pre-action listener: _resource_record_pre_action 05:42:40 INFO - Running main action method: run_tests 05:42:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:42:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:42:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:42:40 INFO - 2016-10-24 05:42:40.028 screenresolution[1648:8899] starting screenresolution argv=screenresolution get 05:42:40 INFO - 2016-10-24 05:42:40.031 screenresolution[1648:8899] Display 0: 1600x1200x32@60 05:42:40 INFO - 2016-10-24 05:42:40.037 screenresolution[1649:8901] starting screenresolution argv=screenresolution list 05:42:40 INFO - Available Modes on Display 0 05:42:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:42:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:42:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:42:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:42:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:42:40 INFO - Intel Iris: 05:42:40 INFO - Chipset Model: Intel Iris 05:42:40 INFO - Type: GPU 05:42:40 INFO - Bus: Built-In 05:42:40 INFO - VRAM (Dynamic, Max): 1536 MB 05:42:40 INFO - Vendor: Intel (0x8086) 05:42:40 INFO - Device ID: 0x0a2e 05:42:40 INFO - Revision ID: 0x0009 05:42:40 INFO - Displays: 05:42:40 INFO - Display: 05:42:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:42:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:42:40 INFO - Display Serial Number: 1600x1200 60 05:42:40 INFO - Main Display: Yes 05:42:40 INFO - Mirror: Off 05:42:40 INFO - Online: Yes 05:42:40 INFO - Rotation: Supported 05:42:40 INFO - Return code: 0 05:42:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:42:40 INFO - Python 2.7.3 05:42:40 INFO - Return code: 0 05:42:40 INFO - grabbing minidump binary from tooltool 05:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:40 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 0x10a2e9d30>, '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 0x10a10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:42:40 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 05:42:40 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 05:42:40 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 05:42:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:42:40 INFO - Return code: 0 05:42:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:42:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:42:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:42:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:42:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:42:40 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/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--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 05:42:40 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/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0207 --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 05:42:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufhXp8zVrk/Render', 05:42:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:40 INFO - 'HOME': '/Users/cltbld', 05:42:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:40 INFO - 'LOGNAME': 'cltbld', 05:42:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:42:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:40 INFO - 'MOZ_NO_REMOTE': '1', 05:42:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:42:40 INFO - 'NO_EM_RESTART': '1', 05:42:40 INFO - 'PAGER': '/bin/cat', 05:42:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:40 INFO - 'PWD': '/builds/slave/test', 05:42:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:42:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:40 INFO - 'SHELL': '/bin/bash', 05:42:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNulkoaU9J/Listeners', 05:42:40 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:42:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:40 INFO - 'USER': 'cltbld', 05:42:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:40 INFO - 'XPC_FLAGS': '0x0', 05:42:40 INFO - 'XPC_SERVICE_NAME': '0', 05:42:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:40 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/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--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 05:42:40 INFO - mozversion application_buildid: 20161024030205 05:42:40 INFO - mozversion application_changeset: 215f9686117673a2c914ed207bc7da9bb8d741ad 05:42:40 INFO - mozversion application_display_name: Nightly 05:42:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:42:40 INFO - mozversion application_name: Firefox 05:42:40 INFO - mozversion application_remotingname: firefox 05:42:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:42:40 INFO - mozversion application_vendor: Mozilla 05:42:40 INFO - mozversion application_version: 52.0a1 05:42:40 INFO - mozversion platform_buildid: 20161024030205 05:42:40 INFO - mozversion platform_changeset: 215f9686117673a2c914ed207bc7da9bb8d741ad 05:42:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:42:40 INFO - mozversion platform_version: 52.0a1 05:42:40 INFO - using testdate: 1477312960 05:42:40 INFO - actual date: 1477312960 05:42:40 INFO - starting webserver on 'localhost:49272' 05:42:40 INFO - SUITE-START | Running 2 tests 05:42:40 INFO - TEST-START | dromaeo_css 05:42:40 INFO - operating with platform_type : mac_ 05:42:40 INFO - Initialising browser for dromaeo_css test... 05:42:40 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpcUE1Zt/profile http://localhost:49272/getInfo.html) 05:42:43 INFO - PROCESS | 1659 | 2016-10-24 05:42:43.563 plugin-container[1663:9089] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x914f, name = 'com.apple.tsm.portname' 05:42:43 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:43 INFO - PROCESS | 1659 | 2016-10-24 05:42:43.564 plugin-container[1663:9089] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 05:42:43 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:43 INFO - PROCESS | 1659 | 2016-10-24 05:42:43.564 plugin-container[1663:9089] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:42:43 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 05:42:43 INFO - PROCESS | 1659 | colorDepth:24 05:42:43 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 05:42:43 INFO - PROCESS | 1659 | __metrics 05:42:43 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 05:42:44 INFO - TEST-INFO | 1659: exit 0 05:42:44 INFO - Browser initialized. 05:42:44 INFO - Running cycle 1/1 for dromaeo_css test... 05:42:44 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpcUE1Zt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:46 INFO - PROCESS | 1664 | 2016-10-24 05:42:46.775 plugin-container[1665:9205] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 05:42:46 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:46 INFO - PROCESS | 1664 | 2016-10-24 05:42:46.775 plugin-container[1665:9205] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 05:42:46 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:46 INFO - PROCESS | 1664 | 2016-10-24 05:42:46.775 plugin-container[1665:9205] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:42:56 INFO - PROCESS | 1664 | RSS: Main: 236761088 05:42:56 INFO - PROCESS | 1664 | 05:43:46 INFO - PROCESS | 1664 | 1477313026001 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] 05:46:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 05:46:02 INFO - PROCESS | 1664 | RSS: Main: 249479168 05:46:02 INFO - PROCESS | 1664 | 05:49:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 05:49:04 INFO - PROCESS | 1664 | RSS: Main: 261857280 05:49:04 INFO - PROCESS | 1664 | 05:52:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 05:52:07 INFO - PROCESS | 1664 | RSS: Main: 268161024 05:52:07 INFO - PROCESS | 1664 | 05:55:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 05:55:09 INFO - PROCESS | 1664 | RSS: Main: 267919360 05:55:09 INFO - PROCESS | 1664 | 05:58:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 05:58:13 INFO - PROCESS | 1664 | RSS: Main: 273362944 05:58:13 INFO - PROCESS | 1664 | 06:01:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 06:01:15 INFO - PROCESS | 1664 | RSS: Main: 276197376 06:01:15 INFO - PROCESS | 1664 | 06:01:15 INFO - PROCESS | 1664 | __start_tp_report 06:01:15 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 06:01:15 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 06:01:15 INFO - PROCESS | 1664 | |i|pagename|runs| 06:01:15 INFO - PROCESS | 1664 | |0;dojo.html;3386.085756845653;3393.253484233468;3419.162305235218;3427.6915077643007;3431.3651974384725;5618.971905140479;5690.800821971232;5726.1697053927255;5780.682062486567;5800.129980502927;5660.094384898417;5676.375598684142;5710.428957104287;5788.739506722197;5797.304323481178;5223.451537588556;5237.7381130943495;5242.7378631068295;5280.207968804691;5345.465453454643;5220.008198442284;5320.707361095138;5348.117560602496;5367.382750983647;5441.020616289066;4350.36919646651;5291.232771248147;5324.148136298193;5347.358317001966;5381.327334083239;4157.605027522381;4692.741899195543;5186.092433824066;5222.373315202162;5223.085959956992;5150.459201783796;5164.948350516484;5190;5195.792168313263;5199.6360254782185;4345.783180709374;5480.561555075585;5609.635373700738;5646.322441307028;5661.915071273934;4994.675346102492;6101.633901965896;6165.062322903865;6217.440430361244;6290.21372328459;5299.417064122943;6292.968535157341;6298.527610429236;6304.022876454157;6307.148534947769;5634.014047541664;5662.462066103713;5763;5882.999999999957;5886.23478947734;6244.875102498016;6323.9683801580695;6340.841478963062;6352.523560732963;6375.904361434582;6170.136180934673;6172.382761723792;6177.876442471125;6185.628862268279;6194.752209911643;6130.969345153335;6145.293291271471;6178.474829639531;6259.374062593795;6263.686815659199;6131.743695907034;6217.626942383471;6224.595401298901;6228.875422491525;6239.344868788802;5647.407022262685;5818;6228.22147231596;6279.874402511924;6312.337204593543;4851.32081508582;4903.607711383081;4905.730184839868;4908.730019848943;4965.106280869406;4373.868783936487;4394.143142087262;4413.845515406945;4451.221036318631;4519.932201016987;5887.558433117448;5899.970500147558;5938.940610593925;5962.4633782959745;5967;6137.34634165833;6278.937210627927;6297.149884765589;6297.96851015742;6316.9368306317265;5735.913961290584;5785.103308987113;5813.244278243801;5832.708364581754;5993.31076926159;5429.321334833146;5433.483819037185;5442.700651464128;5448.237246785453;5460.34475862899;5610.354809196994;6373.0440433934355;6395.904061439082;6414.679266036586;6454.838629034311;6135.692037377249;6189.566730329014;6203.96898015507;6206.10011548332;6240.095186197885;6440.259370172303;6460.418562329413;6464.612123272431;6472.385123413268;6479.092926990131;6127.754889804358;6163.260408750979;6246.969250073868;6252.562320637694;6265.561410701207;5071.366079240095;6158.753649853956;6225.470834979077;6246.375362463899;6264.060390941395;6139.938600613937;6172.6913654318005;6197;6198.349173336735;6212.192414986023;4738.573528382462;6040.909386359121;6228.813135605939;6228.8788018157165;6291.3708629138555;5774.642958904738;5974.522038237017;5996.1005849122985;6015.0676645120075;6089.421504957022;6135.538443123393;6137.631742095488;6152.876942461216;6163.19878415803;6180;4948.826791062295;5078.644494885322;6087.66517841523;6095;6113.388661133851 06:01:15 INFO - PROCESS | 1664 | |1;ext.html;460.28885372100103;472.9219678753006;482.6259648772201;496.8658462215204;512.1498312800752;35241.82379088104;41308.17383652325;41391.793041034754;41407.79296103519;41453.378199326995;42078.94802629928;42617.147657046844;42680;42713.4371937684;42769;9534.903486099101;9688.854667179998;9708.563114659857;9719.902800971971;9736.659216927408;7522.360599349062;9432.245420366355;9510.191633711109;9549.236061115129;9594.808103837919;9559.904400955971;9564.282678799083;9600.32797704161;9604.327697061208;9623.518824058805;9531.761705957371;9554.904450955471;9566.904330956671;9580.61677532898;9583.904160958371;9520.000399957973;9547.331686781929;9569.904300957041;9589.90410095904;9631.373960692532;40574.79712601448;40727.16727747259;40808.18383632311;40855;40855.1828963422;3405.1827561385335;3412.812295323756;3464.7574669773126;3633.65480279373;3916.3733802591732;29697.851510742523;29727.25681857949;30132.397352052838;30140.54789178164;30996.845015775;3206.3587282543353;3212.3254116635535;3224.274538228903;3253.707166355016;3331.3503866745996;419258;469369;469961;472960;475736;57890;58114;58163.70918145382;58251.41748582545;58368.708156458946;60602.696986514784;60651.78696426135;61100.69449652812;61144.69427652922;61199.38800611937;14425.350859211361;14832.739217166662;16116.838831611534;16120.838791611934;16163;12481.875181248253;12663.556775512812;12664.81002784959;12673.112882098349;12693;15193.012454190442;15627.593516583562;15629;15649.608759781097;15679.137647429501;5067.543921047123;5187.377514698261;5987.880242395127;6175.289841668266;6175.536834737323;717.5587013986465;810.2424233341844;923.6997975657938;945.239082538563;1009.7576581620358;25346.493070138495;29062.564061539095;29614.851925740666;30290.84854575757;30340.448932660278;816.2124946367436;823.8146417056114;956.2350119904049;1054.446415631784;1073.838924161382;13506.797398039038;13638.52265170734;13705.903527717759;14054.929725351309;16083.839161608234;611886;613183;616533;617753;623481;51941;57665.42334576601;57904.552386190684;58125.41874581284;58127.67489300381;1312.875276848717;1386.778115501515;1397.475946520055;1402.3479082226813;1404.108391171593;25183.49633007293;28235.870565177163;29039.98360057388;30161.396772064876;30398.088057358316;47857.539232203206;49042.50957490522;49872.25191622201;49888;49957.25064123968;572988;573769;574951;576655;577307;38153.84923035466;38649.80675096607;39426.21147576975;39505.604943950195;39647.40528892127;45959.3106103402;47023.52976470192;47662.76168619135;47840.7607961958;48182.75908620434;39074;39173;39213.80393098016;39771.60799372008;40178.99552511211;38608;38674.80662596669;39249;39995.400068999574;40495.392569112075;36410;37971.240575188895;38992.83021509359;39144.217115658095;39839.80080099581;31148.688513115485;33128.1717957053;34433.655663443045;35195.824020880755;35351.93944181679;25774.742252577234;25850.482990340464;26126.954921803674;26159.607605885547;26431 06:01:15 INFO - PROCESS | 1664 | |2;jquery.html;314.9858256378463;364.42239051103985;374.63473113714133;381.2185020707549;408.8037741883896;16375.508734737961;19376.418707438763;20322.983850807475;20857.895710521447;21065.684014739752;19298.228070877183;20060.297889573867;20397.28609498664;20870.68693969588;20875.58248835026;4687.742174180418;5118.4113826909825;5341.599380046502;5530;5535.418781027995;5425.159100339434;5447.373552041507;5489;5540.473655002768;5542.974549708297;5418.783248670068;5527.972360138214;5533.031719449101;5691.573132015094;6016.39836016397;5938.821835344904;6058.454739073482;6094.664793436359;6110.297315808694;6115.938840611604;4471.262241730111;5049;5396.460353964611;5450.991566560199;5906;13101.672458188526;13130.803037954438;13251.933740331333;13279.203247805162;13388.665283367896;2056.311135769519;2061.165228082629;2071.078370125295;2122.6073176462405;2273.5566564519927;9269;9838.508074596242;10142.89857101427;10261.281710280284;10359.378437293786;1785;1789.8926064436046;1806.349714102922;1822.6081392500676;1835.880667756605;104961;112767;113862.99999999917;114924;115192;10955.890441095487;11463;11709.414529273672;11731.824022639667;11751.412429378497;14376.784348234785;14491.985561010628;14506.854931450762;14512.709745805236;14554.908381871403;8271.54506502136;8374.665013399517;8417.452865563719;8463.407561470762;8488.915110848937;8181.918180818235;8267.338612911073;8290.4611200273;8416.705415310435;8449.704260350858;3755.6244375562223;3830.9808450958126;4553.453585569753;4568.223402021665;4577.130345234395;1647.5798671338732;1828.4331857124334;1831.5238038110188;1941.3496478679801;1955.217912834849;375.8628100743264;377.5016977589555;412.40819424126477;727.294524311417;806.1817255485687;11502.194846360842;11618.186726929034;11812.940935295268;11943.343116128523;12053.216540924812;420.7286300336266;427.81603910318864;432.65387689847995;439.3629237605485;445.1941985007147;4764.499727528629;5523.1991361253195;5948.791792287249;5973.37279585646;6146.477549408351;548044;562300;567495;589895;590256;98900.01099988908;116950;130584.69415305916;133184;134594;431.85964561517005;440.7641911577291;446.79894047678675;447.57480393626184;449.94150760401595;10523.684289471328;10900.127989760958;11296.152788540729;11337.093032557541;11609.30344179352;55600;55645.60885977883;55657;56706;57338.7133064332;59822.70088649529;83442.58278708567;84808;85641.71537426818;86143.2471077606;7502.512336698097;7717.421193410404;7786.221377862168;7882.251186137308;8097.676092956216;12195.024398048312;12198;12503.56237531682;12686;12716.237025778482;8109.75670729879;10423.270371073952;10740.409277489813;10881.619143329945;10900.672979810619;8729.781755456164;10194.949025254826;10582.523786429783;10811.729706757395;10848.67453976382;10225.488725563544;10641.680749577525;10986.890131098906;11123.555057797921;11215.495302711235;7797.493162944391;7971.521708697497;8079.757607271791;8105.878411823709;8174.427790054877;9645.565949532147;10431.94784026075;10760.300580462244;10791.352518848895;10912.672619821418 06:01:15 INFO - PROCESS | 1664 | |3;mootools.html;1385.6051025457743;1543.282373696231;1771.8305918094052;1898.9050547472623;1915.5402703351192;7749.612519374037;8034.758957231293;8206.138355472678;8749.912500874989;8782.736517904457;7622.923770762291;8163.346932245421;8408.537530435824;8425.747227583168;8562.44344117632;1987.4435158155668;2284.9375040606114;2313.687652166953;2316.4787922717337;2318.2233951626226;2221.600111979843;2265.0486795545826;2304.4584522637165;2312.6646636237733;2321.280403075052;2292.1175347491253;2303.170858490942;2306.7577904320055;2321.4892723600865;2323.5236776460783;1668.4410722408008;2210.5910406574676;2264.33202205349;2301.390131615123;2303.5392921415782;1917.5397904502959;2271.806896413807;2273.886305684709;2277.487565297811;2281.6577513372963;8484.703035393793;8603.870941935877;8608.053114157437;8621.56892155396;8622.827543449159;5101.515356041207;5216.426193118754;5221.164613661834;5226.477352264781;5234.241035049935;2798.524250877356;3350.274608576585;3518.313928783888;3520.6655367740022;3544.486049522805;1041.2138835179392;1051.8474821150967;1052.0899421999916;1063.3035361838008;1065.5471424644572;11153.219274650697;11153.776924461465;11175.888241117565;11276.154288428319;11282.830757538644;4277.935830962538;4400.44994375703;4432.601065904019;4612.630989520831;4636.327732478839;9499.857502137473;9573.760655983517;9582.185514231229;9590.520473976412;9598.856017159747;4932.679375840559;4938.111139994764;4994.275830004629;4995.575376093;5009.824656137018;4228.894277643083;4246.681498887534;4575.24508456101;4578.793954272066;4675.13510000651;3789.5831458539537;3875.6318149775725;3886.009067687778;3905.941410878839;3985.06351007513;1245.8255844181822;1248.5942068827667;1256.1395444120806;1256.5413624027165;1258.572085490919;907.9546022698971;913.6756451459769;928.4846909965034;944.2540393089382;946.3233787841726;5328.92006619901;5730.069378193633;5905.43898329658;5943.673097979567;5979.252593425822;868.4789126524036;934.4860326820221;977.4330888084894;993.4238141877694;994.721477986165;3103.6275646922063;3713.665770080706;3929.528456585228;3933.869437091043;3994.6005399460405;10126.848097278547;10340.638077667245;10385.896141038644;10445.529951152064;10447.895521044844;10353.275270731127;10366;10377.169826413869;10408.739781505523;10414.271001030007;7142.714291428285;7477.775666730006;7493.737719179802;7565.962170189224;7870;5800.1299805029585;5831.854203644943;5950.910736339045;5994.850128746816;6053.092036194606;9505.04949505044;9579;9657.806843863224;9675.70972870815;9679.951600241953;9439.05609439078;9584.137427631267;9728.708138755848;9732.659356922473;9785.07041831025;7268.41852651776;7284.817879553054;7329.926700733137;7348.52234604749;7382.335589796944;7725.493528761837;7930.088039875257;8023.879641805494;8039.276465118168;8039.67841286342;6330.461910737639;7311.341979221896;7327.413806895543;7389.224131466333;7391.260873912565;7159.8926016110845;7206.012819107032;7207;7237.203907570269;7269.7455589054125;6709.832254193684;6742.426893713893;6743.865122697617;6816.931830681629;6826.385625293748;4925.335079764329;4940.308356830118;4940.7776650051555;4977.875553111201;5051.949480505148;4203.306454434984;4832.178529649897;4843.927341089816;4894.094592500399;5066.493350664904 06:01:15 INFO - PROCESS | 1664 | |4;prototype.html;304.95552731893275;305.3435114503817;311.82849432811946;319.9376121656278;319.98086095784925;4840.419149702032;4875.731834749087;4938.925916111257;5456.727163641817;5576.86057848554;5073.23901414788;5164.586833053356;5313.362396512414;5348.181618249794;5383.192521121824;3757.0463359053274;3775.509183806101;3966.9603303967024;4111.69162312827;4166.291730405823;3871.709621778378;4001.9599804001878;4137.482814648169;4160.043190066287;4161.334186530155;3897.473841031467;4058.756474611518;4081.714260008088;4092.4270602115726;4140.606642368985;3758.830852611639;3931;4080.6939479539005;4120.938185927213;4204.285271503852;3308.387948229561;3350.095474221971;3578.248567800765;3600.531930848999;3601.981990090059;4293.763842988635;4860.708357498562;5058.282162610479;5229.398619158807;5261.973690131563;3916.784576848272;4472.948857018598;4533.5013148553635;4685.1800934836265;4719.834805781816;5655.123455864306;5678.517326027292;5696.287964004499;5723.284589426322;5753.942460575383;3594.26317604893;3827.282629333177;3877.4683999820195;3884.3202439573242;3930.6658933990643;5109.233614957786;5140.511651393112;5479.4520547945285;5912.586118971673;5981.833542459222;3410.744194185446;3652.726045546595;3655.1593133579427;3882.6893848492055;3921.8823435296986;3701.7964011979598;3932.8762468267387;3934.7048971327263;3944.7633142011573;3960.980195099006;3882.4176373544196;4659.4175728034;4672.761718144694;4683.1336202802595;4684.1334353144775;4774.689645173053;4943.876403089952;5584.9388616163615;5878.267300264363;5902.930476152376;1227.8403807505147;1321.669582604349;1341.2287934437738;1377.3319939829175;1391.373881753215;762;899.1862364560084;914.3142643017736;922.9247926166075;946.682861241478;3841.75143078495;4012.7391719538136;4405.647548196137;4528.62247320153;4568.515232549435;4467.352233926063;4616.515265897033;4968.30443737877;4985.825496107619;4987.700737955734;4009.799510024487;4516.435445569304;4547.681662283675;4580.580020193751;4586.862394128181;2164.837637177218;2302.7582103879186;2343.093053859781;2589.080876288928;2633.841969481837;4532.119170544253;4680.040591678698;4774.594159496482;4780.713157210578;5001.874953126201;4564.425273280713;5056.772445239973;5065.670731402447;5105.0345616937;5159.819406320761;4847.515248475124;5367.033933891937;5444.50999410055;6201.441940173969;6334.809955701244;4949.505049495022;5545.2513910623165;6019.428154325332;6041.758329666764;6052.822268308997;5266.78932842682;5933.881322373615;5964.850878728066;5967.045272756338;5987.970060149672;5479.3424789023975;6179.505639548735;6186.051393810935;6215.782447614311;6221.4089661482085;3926.0381206604566;3931.0958479550436;3933.4069701770827;3963.740667819315;3969.9206015878945;4882.877928051827;5304.9204261936875;5641.323041235078;5649.435056494318;5707.600467967202;4782.780391000273;5309.65487243328;5570.944290557042;5693.373728889902;5759.452851979056;4949.851504454872;5750.5112065474905;5848.561357898089;5854.565631420159;5861.805316102649;4433.445819272591;4854.073115787549;5377.821982097257;5428.83713488596;5655.802046928337;5014.0974624567925;5132.255822905583;5137.563307118788;5336.425754402383;5830;5318.574514038945;5320.813771517978;5691.601587888808;5701.750002498536;5716.28546431696 06:01:15 INFO - PROCESS | 1664 | |5;yui.html;713.900053992441;728.1189760389925;737.7786664000798;797.5374282915909;821.2239433735123;13565.864341356584;13853.930730346334;14171.503997360094;14208.928955355235;14229.64425889352;14555.1994640295;14583.197924114169;14652.901032422584;14756.852431475681;14839.777403338932;895.3732387328863;895.8342706599284;906.6328137104483;914.7941713114529;915.4644532948233;877.6971944679108;902.4946030223064;908.0919080919081;908.2779190543533;908.9000209976897;950.4582388038821;961.4567769210374;964.5177411294353;965.1747755668907;966.6471737815682;948.7296120605663;954.2223088183143;958.5303201431318;958.9643185359795;958.966436174734;708.8405108850519;713.2546488919093;717.8887272472775;719.8488317453341;724.4675163754636;13556.796648050287;13557.728845423135;13573.981951353688;13582.93208533961;13607;11782.528698852035;11919.582814601534;12153.392330383445;12612.243265404106;12629.24224546521;10399.532021058994;10583.894161058444;10767.300125491818;11116.888831111748;11183.608573699963;3691.3540130477327;3693.353663108945;3709.9443508347395;3732.6080761520193;3745.831437585288;99466.50800238003;110996;113446;114856.42571787171;115076;9112.49881256524;9170.541472926461;9208.723738287863;9363.25093992479;9568.904310956941;16600.252988615543;20010.99945002773;20152.69770953437;20180.899095504723;20212.494687632927;7672.578008209602;7768.262015108556;7818.648160832776;7857.528548287121;7913.525188488709;3471.67019133187;4339.7396156230725;4377.124575084996;4382.561743825592;4511.233090374644;3543.805090720035;3644.344018076719;3667.3215455140885;3667.7249206309098;3672.3389789838084;585.0755805826783;658.558765627031;666.6133642487346;677.1670844860848;693.993060069403;482.5102520941247;500.74462024367836;565.6947937156106;678.2098854834162;681.918169819615;9292.581833817494;9314.20829229523;9321.533923303808;9414.62341506332;9496.572654230577;577.3216470647056;632.3347092007211;632.9082283069022;653.3270731146831;663.0088018412504;4173.833046678099;4200.957990420056;4407.867763967086;4443.7111587746695;4624.237000894882;115320.42339788248;123411;152498;153512.23243883933;155934.2203288999;86874.69687954686;90245;95887.04112958921;135074;136563;108905;109786.45106774574;112216.43891780652;113230.86769132368;113397.4330128344;9622.807543849223;9715.854262185932;9745.853812192965;9775.75560610962;9819.607215711578;41511.377329340685;54475.72762136164;54918.725406372716;55715.721421392635;55856.72071639616;50922.49077509178;51058;71392;81331.78002330087;81337;13154.877623248862;13288.003399745252;13578.86421135776;13749.175049497062;13766.93116534451;14232.786508202593;14323.355449004612;14357.56927292183;14579.343929522985;14775.96568240213;13338.93330533341;13339.626391097056;13395.062345635712;13410.463581457023;13739.862601373858;13228.735425291632;13271.734565308447;13448.798268026183;13453.394597242952;13898.436799474064;10730.785384292114;13189.340532973505;13305.401256943655;13668.043236973317;13846.584602461942;11242.831357529807;11342.149338799458;11379.772404552028;11736.823947640964;11813.586524471602;8784.033756286932;10314.535845887103;10371.377717336984;10690.946545267223;11594.594189203337 06:01:15 INFO - PROCESS | 1664 | __end_tp_report 06:01:15 INFO - PROCESS | 1664 | __start_cc_report 06:01:15 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,358 06:01:15 INFO - PROCESS | 1664 | __end_cc_report 06:01:15 INFO - PROCESS | 1664 | __startTimestamp1477314075926__endTimestamp 06:01:15 INFO - PROCESS | 1664 | 06:01:15 INFO - PROCESS | 1664 | ------- Summary: start ------- 06:01:15 INFO - PROCESS | 1664 | Number of tests: 6 06:01:15 INFO - PROCESS | 1664 | 06:01:15 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5715.76 Median:5962.46 stddev:680.55 (11.4%) stddev-sans-first:657.64 06:01:15 INFO - PROCESS | 1664 | Values: 3386.1 3393.3 3419.2 3427.7 3431.4 5619.0 5690.8 5726.2 5780.7 5800.1 5660.1 5676.4 5710.4 5788.7 5797.3 5223.5 5237.7 5242.7 5280.2 5345.5 5220.0 5320.7 5348.1 5367.4 5441.0 4350.4 5291.2 5324.1 5347.4 5381.3 4157.6 4692.7 5186.1 5222.4 5223.1 5150.5 5164.9 5190.0 5195.8 5199.6 4345.8 5480.6 5609.6 5646.3 5661.9 4994.7 6101.6 6165.1 6217.4 6290.2 5299.4 6293.0 6298.5 6304.0 6307.1 5634.0 5662.5 5763.0 5883.0 5886.2 6244.9 6324.0 6340.8 6352.5 6375.9 6170.1 6172.4 6177.9 6185.6 6194.8 6131.0 6145.3 6178.5 6259.4 6263.7 6131.7 6217.6 6224.6 6228.9 6239.3 5647.4 5818.0 6228.2 6279.9 6312.3 4851.3 4903.6 4905.7 4908.7 4965.1 4373.9 4394.1 4413.8 4451.2 4519.9 5887.6 5900.0 5938.9 5962.5 5967.0 6137.3 6278.9 6297.1 6298.0 6316.9 5735.9 5785.1 5813.2 5832.7 5993.3 5429.3 5433.5 5442.7 5448.2 5460.3 5610.4 6373.0 6395.9 6414.7 6454.8 6135.7 6189.6 6204.0 6206.1 6240.1 6440.3 6460.4 6464.6 6472.4 6479.1 6127.8 6163.3 6247.0 6252.6 6265.6 5071.4 6158.8 6225.5 6246.4 6264.1 6139.9 6172.7 6197.0 6198.3 6212.2 4738.6 6040.9 6228.8 6228.9 6291.4 5774.6 5974.5 5996.1 6015.1 6089.4 6135.5 6137.6 6152.9 6163.2 6180.0 4948.8 5078.6 6087.7 6095.0 6113.4 06:01:15 INFO - PROCESS | 1664 | 06:01:15 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:68746.10 Median:41399.79 stddev:148190.02 (357.9%) stddev-sans-first:148517.04 06:01:15 INFO - PROCESS | 1664 | Values: 460.3 472.9 482.6 496.9 512.1 35241.8 41308.2 41391.8 41407.8 41453.4 42078.9 42617.1 42680.0 42713.4 42769.0 9534.9 9688.9 9708.6 9719.9 9736.7 7522.4 9432.2 9510.2 9549.2 9594.8 9559.9 9564.3 9600.3 9604.3 9623.5 9531.8 9554.9 9566.9 9580.6 9583.9 9520.0 9547.3 9569.9 9589.9 9631.4 40574.8 40727.2 40808.2 40855.0 40855.2 3405.2 3412.8 3464.8 3633.7 3916.4 29697.9 29727.3 30132.4 30140.5 30996.8 3206.4 3212.3 3224.3 3253.7 3331.4 419258.0 469369.0 469961.0 472960.0 475736.0 57890.0 58114.0 58163.7 58251.4 58368.7 60602.7 60651.8 61100.7 61144.7 61199.4 14425.4 14832.7 16116.8 16120.8 16163.0 12481.9 12663.6 12664.8 12673.1 12693.0 15193.0 15627.6 15629.0 15649.6 15679.1 5067.5 5187.4 5987.9 6175.3 6175.5 717.6 810.2 923.7 945.2 1009.8 25346.5 29062.6 29614.9 30290.8 30340.4 816.2 823.8 956.2 1054.4 1073.8 13506.8 13638.5 13705.9 14054.9 16083.8 611886.0 613183.0 616533.0 617753.0 623481.0 51941.0 57665.4 57904.6 58125.4 58127.7 1312.9 1386.8 1397.5 1402.3 1404.1 25183.5 28235.9 29040.0 30161.4 30398.1 47857.5 49042.5 49872.3 49888.0 49957.3 572988.0 573769.0 574951.0 576655.0 577307.0 38153.8 38649.8 39426.2 39505.6 39647.4 45959.3 47023.5 47662.8 47840.8 48182.8 39074.0 39173.0 39213.8 39771.6 40179.0 38608.0 38674.8 39249.0 39995.4 40495.4 36410.0 37971.2 38992.8 39144.2 39839.8 31148.7 33128.2 34433.7 35195.8 35351.9 25774.7 25850.5 26127.0 26159.6 26431.0 06:01:15 INFO - PROCESS | 1664 | 06:01:15 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:32970.66 Median:2106.10 stddev:95744.34 (4546.0%) stddev-sans-first:95981.53 06:01:15 INFO - PROCESS | 1664 | Values: 315.0 364.4 374.6 381.2 408.8 16375.5 19376.4 20323.0 20857.9 21065.7 19298.2 20060.3 20397.3 20870.7 20875.6 4687.7 5118.4 5341.6 5530.0 5535.4 5425.2 5447.4 5489.0 5540.5 5543.0 5418.8 5528.0 5533.0 5691.6 6016.4 5938.8 6058.5 6094.7 6110.3 6115.9 4471.3 5049.0 5396.5 5451.0 5906.0 13101.7 13130.8 13251.9 13279.2 13388.7 2056.3 2061.2 2071.1 2122.6 2273.6 9269.0 9838.5 10142.9 10261.3 10359.4 1785.0 1789.9 1806.3 1822.6 1835.9 104961.0 112767.0 113863.0 114924.0 115192.0 10955.9 11463.0 11709.4 11731.8 11751.4 14376.8 14492.0 14506.9 14512.7 14554.9 8271.5 8374.7 8417.5 8463.4 8488.9 8181.9 8267.3 8290.5 8416.7 8449.7 3755.6 3831.0 4553.5 4568.2 4577.1 1647.6 1828.4 1831.5 1941.3 1955.2 375.9 377.5 412.4 727.3 806.2 11502.2 11618.2 11812.9 11943.3 12053.2 420.7 427.8 432.7 439.4 445.2 4764.5 5523.2 5948.8 5973.4 6146.5 548044.0 562300.0 567495.0 589895.0 590256.0 98900.0 116950.0 130584.7 133184.0 134594.0 431.9 440.8 446.8 447.6 449.9 10523.7 10900.1 11296.2 11337.1 11609.3 55600.0 55645.6 55657.0 56706.0 57338.7 59822.7 83442.6 84808.0 85641.7 86143.2 7502.5 7717.4 7786.2 7882.3 8097.7 12195.0 12198.0 12503.6 12686.0 12716.2 8109.8 10423.3 10740.4 10881.6 10900.7 8729.8 10194.9 10582.5 10811.7 10848.7 10225.5 10641.7 10986.9 11123.6 11215.5 7797.5 7971.5 8079.8 8105.9 8174.4 9645.6 10431.9 10760.3 10791.4 10912.7 06:01:15 INFO - PROCESS | 1664 | 06:01:15 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5512.03 Median:4994.93 stddev:3074.02 (61.5%) stddev-sans-first:3067.00 06:01:15 INFO - PROCESS | 1664 | Values: 1385.6 1543.3 1771.8 1898.9 1915.5 7749.6 8034.8 8206.1 8749.9 8782.7 7622.9 8163.3 8408.5 8425.7 8562.4 1987.4 2284.9 2313.7 2316.5 2318.2 2221.6 2265.0 2304.5 2312.7 2321.3 2292.1 2303.2 2306.8 2321.5 2323.5 1668.4 2210.6 2264.3 2301.4 2303.5 1917.5 2271.8 2273.9 2277.5 2281.7 8484.7 8603.9 8608.1 8621.6 8622.8 5101.5 5216.4 5221.2 5226.5 5234.2 2798.5 3350.3 3518.3 3520.7 3544.5 1041.2 1051.8 1052.1 1063.3 1065.5 11153.2 11153.8 11175.9 11276.2 11282.8 4277.9 4400.4 4432.6 4612.6 4636.3 9499.9 9573.8 9582.2 9590.5 9598.9 4932.7 4938.1 4994.3 4995.6 5009.8 4228.9 4246.7 4575.2 4578.8 4675.1 3789.6 3875.6 3886.0 3905.9 3985.1 1245.8 1248.6 1256.1 1256.5 1258.6 908.0 913.7 928.5 944.3 946.3 5328.9 5730.1 5905.4 5943.7 5979.3 868.5 934.5 977.4 993.4 994.7 3103.6 3713.7 3929.5 3933.9 3994.6 10126.8 10340.6 10385.9 10445.5 10447.9 10353.3 10366.0 10377.2 10408.7 10414.3 7142.7 7477.8 7493.7 7566.0 7870.0 5800.1 5831.9 5950.9 5994.9 6053.1 9505.0 9579.0 9657.8 9675.7 9680.0 9439.1 9584.1 9728.7 9732.7 9785.1 7268.4 7284.8 7329.9 7348.5 7382.3 7725.5 7930.1 8023.9 8039.3 8039.7 6330.5 7311.3 7327.4 7389.2 7391.3 7159.9 7206.0 7207.0 7237.2 7269.7 6709.8 6742.4 6743.9 6816.9 6826.4 4925.3 4940.3 4940.8 4977.9 5051.9 4203.3 4832.2 4843.9 4894.1 5066.5 06:01:15 INFO - PROCESS | 1664 | 06:01:15 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4440.68 Median:4811.60 stddev:1380.72 (28.7%) stddev-sans-first:1349.25 06:01:15 INFO - PROCESS | 1664 | Values: 305.0 305.3 311.8 319.9 320.0 4840.4 4875.7 4938.9 5456.7 5576.9 5073.2 5164.6 5313.4 5348.2 5383.2 3757.0 3775.5 3967.0 4111.7 4166.3 3871.7 4002.0 4137.5 4160.0 4161.3 3897.5 4058.8 4081.7 4092.4 4140.6 3758.8 3931.0 4080.7 4120.9 4204.3 3308.4 3350.1 3578.2 3600.5 3602.0 4293.8 4860.7 5058.3 5229.4 5262.0 3916.8 4472.9 4533.5 4685.2 4719.8 5655.1 5678.5 5696.3 5723.3 5753.9 3594.3 3827.3 3877.5 3884.3 3930.7 5109.2 5140.5 5479.5 5912.6 5981.8 3410.7 3652.7 3655.2 3882.7 3921.9 3701.8 3932.9 3934.7 3944.8 3961.0 3882.4 4659.4 4672.8 4683.1 4684.1 4774.7 4943.9 5584.9 5878.3 5902.9 1227.8 1321.7 1341.2 1377.3 1391.4 762.0 899.2 914.3 922.9 946.7 3841.8 4012.7 4405.6 4528.6 4568.5 4467.4 4616.5 4968.3 4985.8 4987.7 4009.8 4516.4 4547.7 4580.6 4586.9 2164.8 2302.8 2343.1 2589.1 2633.8 4532.1 4680.0 4774.6 4780.7 5001.9 4564.4 5056.8 5065.7 5105.0 5159.8 4847.5 5367.0 5444.5 6201.4 6334.8 4949.5 5545.3 6019.4 6041.8 6052.8 5266.8 5933.9 5964.9 5967.0 5988.0 5479.3 6179.5 6186.1 6215.8 6221.4 3926.0 3931.1 3933.4 3963.7 3969.9 4882.9 5304.9 5641.3 5649.4 5707.6 4782.8 5309.7 5570.9 5693.4 5759.5 4949.9 5750.5 5848.6 5854.6 5861.8 4433.4 4854.1 5377.8 5428.8 5655.8 5014.1 5132.3 5137.6 5336.4 5830.0 5318.6 5320.8 5691.6 5701.8 5716.3 06:01:15 INFO - PROCESS | 1664 | 06:01:15 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:22906.61 Median:22628.60 stddev:36782.79 (162.5%) stddev-sans-first:36848.23 06:01:15 INFO - PROCESS | 1664 | Values: 713.9 728.1 737.8 797.5 821.2 13565.9 13853.9 14171.5 14208.9 14229.6 14555.2 14583.2 14652.9 14756.9 14839.8 895.4 895.8 906.6 914.8 915.5 877.7 902.5 908.1 908.3 908.9 950.5 961.5 964.5 965.2 966.6 948.7 954.2 958.5 959.0 959.0 708.8 713.3 717.9 719.8 724.5 13556.8 13557.7 13574.0 13582.9 13607.0 11782.5 11919.6 12153.4 12612.2 12629.2 10399.5 10583.9 10767.3 11116.9 11183.6 3691.4 3693.4 3709.9 3732.6 3745.8 99466.5 110996.0 113446.0 114856.4 115076.0 9112.5 9170.5 9208.7 9363.3 9568.9 16600.3 20011.0 20152.7 20180.9 20212.5 7672.6 7768.3 7818.6 7857.5 7913.5 3471.7 4339.7 4377.1 4382.6 4511.2 3543.8 3644.3 3667.3 3667.7 3672.3 585.1 658.6 666.6 677.2 694.0 482.5 500.7 565.7 678.2 681.9 9292.6 9314.2 9321.5 9414.6 9496.6 577.3 632.3 632.9 653.3 663.0 4173.8 4201.0 4407.9 4443.7 4624.2 115320.4 123411.0 152498.0 153512.2 155934.2 86874.7 90245.0 95887.0 135074.0 136563.0 108905.0 109786.5 112216.4 113230.9 113397.4 9622.8 9715.9 9745.9 9775.8 9819.6 41511.4 54475.7 54918.7 55715.7 55856.7 50922.5 51058.0 71392.0 81331.8 81337.0 13154.9 13288.0 13578.9 13749.2 13766.9 14232.8 14323.4 14357.6 14579.3 14776.0 13338.9 13339.6 13395.1 13410.5 13739.9 13228.7 13271.7 13448.8 13453.4 13898.4 10730.8 13189.3 13305.4 13668.0 13846.6 11242.8 11342.1 11379.8 11736.8 11813.6 8784.0 10314.5 10371.4 10690.9 11594.6 06:01:15 INFO - PROCESS | 1664 | -------- Summary: end -------- 06:01:15 INFO - PROCESS | 1664 | 06:01:16 INFO - TEST-INFO | 1664: exit 0 06:01:16 INFO - TEST-OK | dromaeo_css | took 1115976ms 06:01:16 INFO - TEST-START | kraken 06:01:16 INFO - operating with platform_type : mac_ 06:01:16 INFO - Initialising browser for kraken test... 06:01:16 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpCRr1x1/profile http://localhost:49272/getInfo.html) 06:01:19 INFO - PROCESS | 1671 | 2016-10-24 06:01:19.297 plugin-container[1673:11291] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 06:01:19 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:01:19 INFO - PROCESS | 1671 | 2016-10-24 06:01:19.298 plugin-container[1673:11291] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 06:01:19 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:01:19 INFO - PROCESS | 1671 | 2016-10-24 06:01:19.298 plugin-container[1673:11291] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:01:19 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 06:01:19 INFO - PROCESS | 1671 | colorDepth:24 06:01:19 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 06:01:19 INFO - PROCESS | 1671 | __metrics 06:01:19 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 06:01:20 INFO - TEST-INFO | 1671: exit 0 06:01:20 INFO - Browser initialized. 06:01:20 INFO - Running cycle 1/1 for kraken test... 06:01:20 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpCRr1x1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:01:22 INFO - PROCESS | 1674 | 2016-10-24 06:01:22.927 plugin-container[1675:11404] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 06:01:22 INFO - PROCESS | 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:01:22 INFO - PROCESS | 1674 | 2016-10-24 06:01:22.930 plugin-container[1675:11404] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 06:01:22 INFO - PROCESS | 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:01:22 INFO - PROCESS | 1674 | 2016-10-24 06:01:22.930 plugin-container[1675:11404] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:01:32 INFO - PROCESS | 1674 | RSS: Main: 236990464 06:01:32 INFO - PROCESS | 1674 | 06:01:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 06:01:35 INFO - PROCESS | 1674 | RSS: Main: 249368576 06:01:35 INFO - PROCESS | 1674 | 06:01:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 06:01:39 INFO - PROCESS | 1674 | RSS: Main: 256872448 06:01:39 INFO - PROCESS | 1674 | 06:01:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 06:01:43 INFO - PROCESS | 1674 | RSS: Main: 259792896 06:01:43 INFO - PROCESS | 1674 | 06:01:45 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 06:01:46 INFO - PROCESS | 1674 | RSS: Main: 262258688 06:01:46 INFO - PROCESS | 1674 | 06:01:48 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:01:48 INFO - PROCESS | 1674 | RSS: Main: 262565888 06:01:48 INFO - PROCESS | 1674 | 06:01:51 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 06:01:52 INFO - PROCESS | 1674 | RSS: Main: 262774784 06:01:52 INFO - PROCESS | 1674 | 06:01:55 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 06:01:55 INFO - PROCESS | 1674 | RSS: Main: 262701056 06:01:55 INFO - PROCESS | 1674 | 06:01:58 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 06:01:59 INFO - PROCESS | 1674 | RSS: Main: 263286784 06:01:59 INFO - PROCESS | 1674 | 06:02:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:02:01 INFO - PROCESS | 1674 | RSS: Main: 262774784 06:02:01 INFO - PROCESS | 1674 | 06:02:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:02:04 INFO - PROCESS | 1674 | RSS: Main: 262836224 06:02:04 INFO - PROCESS | 1674 | 06:02:06 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:02:06 INFO - PROCESS | 1674 | RSS: Main: 262922240 06:02:06 INFO - PROCESS | 1674 | 06:02:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:02:10 INFO - PROCESS | 1674 | RSS: Main: 262897664 06:02:10 INFO - PROCESS | 1674 | 06:02:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:02:14 INFO - PROCESS | 1674 | RSS: Main: 263192576 06:02:14 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 06:02:16 INFO - PROCESS | 1674 | RSS: Main: 264601600 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | __start_tp_report 06:02:16 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 06:02:16 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 06:02:16 INFO - PROCESS | 1674 | |i|pagename|runs| 06:02:16 INFO - PROCESS | 1674 | |0;ai-astar;106;116;116;114;115;114;115;115;117;114 06:02:16 INFO - PROCESS | 1674 | |1;audio-beat-detection;158;113;113;148;114;114;128;112;139;114 06:02:16 INFO - PROCESS | 1674 | |2;audio-dft;267;261;216;228;255;182;213;263;194;271 06:02:16 INFO - PROCESS | 1674 | |3;audio-fft;84;78;84;107;50;59;64;50;104;84 06:02:16 INFO - PROCESS | 1674 | |4;audio-oscillator;97;60;82;68;56;74;125;103;102;123 06:02:16 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;90;109;87;86;89;84;89;86;125;86 06:02:16 INFO - PROCESS | 1674 | |6;imaging-darkroom;95;99;96;96;97;122;97;95;95;123 06:02:16 INFO - PROCESS | 1674 | |7;imaging-desaturate;121;99;85;87;87;87;90;92;88;87 06:02:16 INFO - PROCESS | 1674 | |8;json-parse-financial;58;54;58;56;59;64;79;90;57;58 06:02:16 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;68;73;69;73;97;114;105;72;71;70 06:02:16 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;89;91;89;91;92;88;88;90;92;87 06:02:16 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;169;165;169;165;160;150;156;176;176;164 06:02:16 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;208;242;190;183;174;174;177;175;184;174 06:02:16 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;69;71;69;75;80;90;89;70;69;96 06:02:16 INFO - PROCESS | 1674 | __end_tp_report 06:02:16 INFO - PROCESS | 1674 | __start_cc_report 06:02:16 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,966 06:02:16 INFO - PROCESS | 1674 | __end_cc_report 06:02:16 INFO - PROCESS | 1674 | __startTimestamp1477314136654__endTimestamp 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | ------- Summary: start ------- 06:02:16 INFO - PROCESS | 1674 | Number of tests: 14 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.00 stddev:3.05 (2.7%) stddev-sans-first:1.05 06:02:16 INFO - PROCESS | 1674 | Values: 106.0 116.0 116.0 114.0 115.0 114.0 115.0 115.0 117.0 114.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:125.30 Median:121.00 stddev:17.13 (14.2%) stddev-sans-first:13.48 06:02:16 INFO - PROCESS | 1674 | Values: 158.0 113.0 113.0 148.0 114.0 114.0 128.0 112.0 139.0 114.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:235.00 Median:258.00 stddev:32.60 (12.6%) stddev-sans-first:32.45 06:02:16 INFO - PROCESS | 1674 | Values: 267.0 261.0 216.0 228.0 255.0 182.0 213.0 263.0 194.0 271.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:76.40 Median:71.00 stddev:20.34 (28.7%) stddev-sans-first:21.39 06:02:16 INFO - PROCESS | 1674 | Values: 84.0 78.0 84.0 107.0 50.0 59.0 64.0 50.0 104.0 84.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:64.00 stddev:24.78 (38.7%) stddev-sans-first:26.11 06:02:16 INFO - PROCESS | 1674 | Values: 97.0 60.0 82.0 68.0 56.0 74.0 125.0 103.0 102.0 123.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:93.10 Median:86.50 stddev:13.27 (15.3%) stddev-sans-first:14.03 06:02:16 INFO - PROCESS | 1674 | Values: 90.0 109.0 87.0 86.0 89.0 84.0 89.0 86.0 125.0 86.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:101.50 Median:96.00 stddev:11.14 (11.6%) stddev-sans-first:11.56 06:02:16 INFO - PROCESS | 1674 | Values: 95.0 99.0 96.0 96.0 97.0 122.0 97.0 95.0 95.0 123.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:87.50 stddev:10.84 (12.4%) stddev-sans-first:4.23 06:02:16 INFO - PROCESS | 1674 | Values: 121.0 99.0 85.0 87.0 87.0 87.0 90.0 92.0 88.0 87.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:58.50 stddev:11.75 (20.1%) stddev-sans-first:12.30 06:02:16 INFO - PROCESS | 1674 | Values: 58.0 54.0 58.0 56.0 59.0 64.0 79.0 90.0 57.0 58.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:81.20 Median:71.50 stddev:17.20 (24.1%) stddev-sans-first:17.57 06:02:16 INFO - PROCESS | 1674 | Values: 68.0 73.0 69.0 73.0 97.0 114.0 105.0 72.0 71.0 70.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:90.50 stddev:1.77 (2.0%) stddev-sans-first:1.86 06:02:16 INFO - PROCESS | 1674 | Values: 89.0 91.0 89.0 91.0 92.0 88.0 88.0 90.0 92.0 87.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:165.00 Median:167.00 stddev:8.21 (4.9%) stddev-sans-first:8.57 06:02:16 INFO - PROCESS | 1674 | Values: 169.0 165.0 169.0 165.0 160.0 150.0 156.0 176.0 176.0 164.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.10 Median:183.50 stddev:21.70 (11.8%) stddev-sans-first:21.79 06:02:16 INFO - PROCESS | 1674 | Values: 208.0 242.0 190.0 183.0 174.0 174.0 177.0 175.0 184.0 174.0 06:02:16 INFO - PROCESS | 1674 | 06:02:16 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.80 Median:77.50 stddev:10.32 (13.3%) stddev-sans-first:10.44 06:02:16 INFO - PROCESS | 1674 | Values: 69.0 71.0 69.0 75.0 80.0 90.0 89.0 70.0 69.0 96.0 06:02:16 INFO - PROCESS | 1674 | -------- Summary: end -------- 06:02:16 INFO - PROCESS | 1674 | 06:02:17 INFO - TEST-INFO | 1674: exit 0 06:02:17 INFO - TEST-OK | kraken | took 60905ms 06:02:17 INFO - SUITE-END | took 1176s 06:02:17 INFO - Completed test suite (00:19:37) 06:02:17 INFO - javascript benchmark 06:02:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7057.38942689784, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3386.085756845653, 3393.253484233468, 3419.162305235218, 3427.6915077643007, 3431.3651974384725, 5618.971905140479, 5690.800821971232, 5726.1697053927255, 5780.682062486567, 5800.129980502927, 5660.094384898417, 5676.375598684142, 5710.428957104287, 5788.739506722197, 5797.304323481178, 5223.451537588556, 5237.7381130943495, 5242.7378631068295, 5280.207968804691, 5345.465453454643, 5220.008198442284, 5320.707361095138, 5348.117560602496, 5367.382750983647, 5441.020616289066, 4350.36919646651, 5291.232771248147, 5324.148136298193, 5347.358317001966, 5381.327334083239, 4157.605027522381, 4692.741899195543, 5186.092433824066, 5222.373315202162, 5223.085959956992, 5150.459201783796, 5164.948350516484, 5190.0, 5195.792168313263, 5199.6360254782185, 4345.783180709374, 5480.561555075585, 5609.635373700738, 5646.322441307028, 5661.915071273934, 4994.675346102492, 6101.633901965896, 6165.062322903865, 6217.440430361244, 6290.21372328459, 5299.417064122943, 6292.968535157341, 6298.527610429236, 6304.022876454157, 6307.148534947769, 5634.014047541664, 5662.462066103713, 5763.0, 5882.999999999957, 5886.23478947734, 6244.875102498016, 6323.9683801580695, 6340.841478963062, 6352.523560732963, 6375.904361434582, 6170.136180934673, 6172.382761723792, 6177.876442471125, 6185.628862268279, 6194.752209911643, 6130.969345153335, 6145.293291271471, 6178.474829639531, 6259.374062593795, 6263.686815659199, 6131.743695907034, 6217.626942383471, 6224.595401298901, 6228.875422491525, 6239.344868788802, 5647.407022262685, 5818.0, 6228.22147231596, 6279.874402511924, 6312.337204593543, 4851.32081508582, 4903.607711383081, 4905.730184839868, 4908.730019848943, 4965.106280869406, 4373.868783936487, 4394.143142087262, 4413.845515406945, 4451.221036318631, 4519.932201016987, 5887.558433117448, 5899.970500147558, 5938.940610593925, 5962.4633782959745, 5967.0, 6137.34634165833, 6278.937210627927, 6297.149884765589, 6297.96851015742, 6316.9368306317265, 5735.913961290584, 5785.103308987113, 5813.244278243801, 5832.708364581754, 5993.31076926159, 5429.321334833146, 5433.483819037185, 5442.700651464128, 5448.237246785453, 5460.34475862899, 5610.354809196994, 6373.0440433934355, 6395.904061439082, 6414.679266036586, 6454.838629034311, 6135.692037377249, 6189.566730329014, 6203.96898015507, 6206.10011548332, 6240.095186197885, 6440.259370172303, 6460.418562329413, 6464.612123272431, 6472.385123413268, 6479.092926990131, 6127.754889804358, 6163.260408750979, 6246.969250073868, 6252.562320637694, 6265.561410701207, 5071.366079240095, 6158.753649853956, 6225.470834979077, 6246.375362463899, 6264.060390941395, 6139.938600613937, 6172.6913654318005, 6197.0, 6198.349173336735, 6212.192414986023, 4738.573528382462, 6040.909386359121, 6228.813135605939, 6228.8788018157165, 6291.3708629138555, 5774.642958904738, 5974.522038237017, 5996.1005849122985, 6015.0676645120075, 6089.421504957022, 6135.538443123393, 6137.631742095488, 6152.876942461216, 6163.19878415803, 6180.0, 4948.826791062295, 5078.644494885322, 6087.66517841523, 6095.0, 6113.388661133851], "value": 5674.845526867609, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.28885372100103, 472.9219678753006, 482.6259648772201, 496.8658462215204, 512.1498312800752, 35241.82379088104, 41308.17383652325, 41391.793041034754, 41407.79296103519, 41453.378199326995, 42078.94802629928, 42617.147657046844, 42680.0, 42713.4371937684, 42769.0, 9534.903486099101, 9688.854667179998, 9708.563114659857, 9719.902800971971, 9736.659216927408, 7522.360599349062, 9432.245420366355, 9510.191633711109, 9549.236061115129, 9594.808103837919, 9559.904400955971, 9564.282678799083, 9600.32797704161, 9604.327697061208, 9623.518824058805, 9531.761705957371, 9554.904450955471, 9566.904330956671, 9580.61677532898, 9583.904160958371, 9520.000399957973, 9547.331686781929, 9569.904300957041, 9589.90410095904, 9631.373960692532, 40574.79712601448, 40727.16727747259, 40808.18383632311, 40855.0, 40855.1828963422, 3405.1827561385335, 3412.812295323756, 3464.7574669773126, 3633.65480279373, 3916.3733802591732, 29697.851510742523, 29727.25681857949, 30132.397352052838, 30140.54789178164, 30996.845015775, 3206.3587282543353, 3212.3254116635535, 3224.274538228903, 3253.707166355016, 3331.3503866745996, 419258.0, 469369.0, 469961.0, 472960.0, 475736.0, 57890.0, 58114.0, 58163.70918145382, 58251.41748582545, 58368.708156458946, 60602.696986514784, 60651.78696426135, 61100.69449652812, 61144.69427652922, 61199.38800611937, 14425.350859211361, 14832.739217166662, 16116.838831611534, 16120.838791611934, 16163.0, 12481.875181248253, 12663.556775512812, 12664.81002784959, 12673.112882098349, 12693.0, 15193.012454190442, 15627.593516583562, 15629.0, 15649.608759781097, 15679.137647429501, 5067.543921047123, 5187.377514698261, 5987.880242395127, 6175.289841668266, 6175.536834737323, 717.5587013986465, 810.2424233341844, 923.6997975657938, 945.239082538563, 1009.7576581620358, 25346.493070138495, 29062.564061539095, 29614.851925740666, 30290.84854575757, 30340.448932660278, 816.2124946367436, 823.8146417056114, 956.2350119904049, 1054.446415631784, 1073.838924161382, 13506.797398039038, 13638.52265170734, 13705.903527717759, 14054.929725351309, 16083.839161608234, 611886.0, 613183.0, 616533.0, 617753.0, 623481.0, 51941.0, 57665.42334576601, 57904.552386190684, 58125.41874581284, 58127.67489300381, 1312.875276848717, 1386.778115501515, 1397.475946520055, 1402.3479082226813, 1404.108391171593, 25183.49633007293, 28235.870565177163, 29039.98360057388, 30161.396772064876, 30398.088057358316, 47857.539232203206, 49042.50957490522, 49872.25191622201, 49888.0, 49957.25064123968, 572988.0, 573769.0, 574951.0, 576655.0, 577307.0, 38153.84923035466, 38649.80675096607, 39426.21147576975, 39505.604943950195, 39647.40528892127, 45959.3106103402, 47023.52976470192, 47662.76168619135, 47840.7607961958, 48182.75908620434, 39074.0, 39173.0, 39213.80393098016, 39771.60799372008, 40178.99552511211, 38608.0, 38674.80662596669, 39249.0, 39995.400068999574, 40495.392569112075, 36410.0, 37971.240575188895, 38992.83021509359, 39144.217115658095, 39839.80080099581, 31148.688513115485, 33128.1717957053, 34433.655663443045, 35195.824020880755, 35351.93944181679, 25774.742252577234, 25850.482990340464, 26126.954921803674, 26159.607605885547, 26431.0], "value": 19618.614422372346, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.9858256378463, 364.42239051103985, 374.63473113714133, 381.2185020707549, 408.8037741883896, 16375.508734737961, 19376.418707438763, 20322.983850807475, 20857.895710521447, 21065.684014739752, 19298.228070877183, 20060.297889573867, 20397.28609498664, 20870.68693969588, 20875.58248835026, 4687.742174180418, 5118.4113826909825, 5341.599380046502, 5530.0, 5535.418781027995, 5425.159100339434, 5447.373552041507, 5489.0, 5540.473655002768, 5542.974549708297, 5418.783248670068, 5527.972360138214, 5533.031719449101, 5691.573132015094, 6016.39836016397, 5938.821835344904, 6058.454739073482, 6094.664793436359, 6110.297315808694, 6115.938840611604, 4471.262241730111, 5049.0, 5396.460353964611, 5450.991566560199, 5906.0, 13101.672458188526, 13130.803037954438, 13251.933740331333, 13279.203247805162, 13388.665283367896, 2056.311135769519, 2061.165228082629, 2071.078370125295, 2122.6073176462405, 2273.5566564519927, 9269.0, 9838.508074596242, 10142.89857101427, 10261.281710280284, 10359.378437293786, 1785.0, 1789.8926064436046, 1806.349714102922, 1822.6081392500676, 1835.880667756605, 104961.0, 112767.0, 113862.99999999917, 114924.0, 115192.0, 10955.890441095487, 11463.0, 11709.414529273672, 11731.824022639667, 11751.412429378497, 14376.784348234785, 14491.985561010628, 14506.854931450762, 14512.709745805236, 14554.908381871403, 8271.54506502136, 8374.665013399517, 8417.452865563719, 8463.407561470762, 8488.915110848937, 8181.918180818235, 8267.338612911073, 8290.4611200273, 8416.705415310435, 8449.704260350858, 3755.6244375562223, 3830.9808450958126, 4553.453585569753, 4568.223402021665, 4577.130345234395, 1647.5798671338732, 1828.4331857124334, 1831.5238038110188, 1941.3496478679801, 1955.217912834849, 375.8628100743264, 377.5016977589555, 412.40819424126477, 727.294524311417, 806.1817255485687, 11502.194846360842, 11618.186726929034, 11812.940935295268, 11943.343116128523, 12053.216540924812, 420.7286300336266, 427.81603910318864, 432.65387689847995, 439.3629237605485, 445.1941985007147, 4764.499727528629, 5523.1991361253195, 5948.791792287249, 5973.37279585646, 6146.477549408351, 548044.0, 562300.0, 567495.0, 589895.0, 590256.0, 98900.01099988908, 116950.0, 130584.69415305916, 133184.0, 134594.0, 431.85964561517005, 440.7641911577291, 446.79894047678675, 447.57480393626184, 449.94150760401595, 10523.684289471328, 10900.127989760958, 11296.152788540729, 11337.093032557541, 11609.30344179352, 55600.0, 55645.60885977883, 55657.0, 56706.0, 57338.7133064332, 59822.70088649529, 83442.58278708567, 84808.0, 85641.71537426818, 86143.2471077606, 7502.512336698097, 7717.421193410404, 7786.221377862168, 7882.251186137308, 8097.676092956216, 12195.024398048312, 12198.0, 12503.56237531682, 12686.0, 12716.237025778482, 8109.75670729879, 10423.270371073952, 10740.409277489813, 10881.619143329945, 10900.672979810619, 8729.781755456164, 10194.949025254826, 10582.523786429783, 10811.729706757395, 10848.67453976382, 10225.488725563544, 10641.680749577525, 10986.890131098906, 11123.555057797921, 11215.495302711235, 7797.493162944391, 7971.521708697497, 8079.757607271791, 8105.878411823709, 8174.427790054877, 9645.565949532147, 10431.94784026075, 10760.300580462244, 10791.352518848895, 10912.672619821418], "value": 8229.342324789115, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.6051025457743, 1543.282373696231, 1771.8305918094052, 1898.9050547472623, 1915.5402703351192, 7749.612519374037, 8034.758957231293, 8206.138355472678, 8749.912500874989, 8782.736517904457, 7622.923770762291, 8163.346932245421, 8408.537530435824, 8425.747227583168, 8562.44344117632, 1987.4435158155668, 2284.9375040606114, 2313.687652166953, 2316.4787922717337, 2318.2233951626226, 2221.600111979843, 2265.0486795545826, 2304.4584522637165, 2312.6646636237733, 2321.280403075052, 2292.1175347491253, 2303.170858490942, 2306.7577904320055, 2321.4892723600865, 2323.5236776460783, 1668.4410722408008, 2210.5910406574676, 2264.33202205349, 2301.390131615123, 2303.5392921415782, 1917.5397904502959, 2271.806896413807, 2273.886305684709, 2277.487565297811, 2281.6577513372963, 8484.703035393793, 8603.870941935877, 8608.053114157437, 8621.56892155396, 8622.827543449159, 5101.515356041207, 5216.426193118754, 5221.164613661834, 5226.477352264781, 5234.241035049935, 2798.524250877356, 3350.274608576585, 3518.313928783888, 3520.6655367740022, 3544.486049522805, 1041.2138835179392, 1051.8474821150967, 1052.0899421999916, 1063.3035361838008, 1065.5471424644572, 11153.219274650697, 11153.776924461465, 11175.888241117565, 11276.154288428319, 11282.830757538644, 4277.935830962538, 4400.44994375703, 4432.601065904019, 4612.630989520831, 4636.327732478839, 9499.857502137473, 9573.760655983517, 9582.185514231229, 9590.520473976412, 9598.856017159747, 4932.679375840559, 4938.111139994764, 4994.275830004629, 4995.575376093, 5009.824656137018, 4228.894277643083, 4246.681498887534, 4575.24508456101, 4578.793954272066, 4675.13510000651, 3789.5831458539537, 3875.6318149775725, 3886.009067687778, 3905.941410878839, 3985.06351007513, 1245.8255844181822, 1248.5942068827667, 1256.1395444120806, 1256.5413624027165, 1258.572085490919, 907.9546022698971, 913.6756451459769, 928.4846909965034, 944.2540393089382, 946.3233787841726, 5328.92006619901, 5730.069378193633, 5905.43898329658, 5943.673097979567, 5979.252593425822, 868.4789126524036, 934.4860326820221, 977.4330888084894, 993.4238141877694, 994.721477986165, 3103.6275646922063, 3713.665770080706, 3929.528456585228, 3933.869437091043, 3994.6005399460405, 10126.848097278547, 10340.638077667245, 10385.896141038644, 10445.529951152064, 10447.895521044844, 10353.275270731127, 10366.0, 10377.169826413869, 10408.739781505523, 10414.271001030007, 7142.714291428285, 7477.775666730006, 7493.737719179802, 7565.962170189224, 7870.0, 5800.1299805029585, 5831.854203644943, 5950.910736339045, 5994.850128746816, 6053.092036194606, 9505.04949505044, 9579.0, 9657.806843863224, 9675.70972870815, 9679.951600241953, 9439.05609439078, 9584.137427631267, 9728.708138755848, 9732.659356922473, 9785.07041831025, 7268.41852651776, 7284.817879553054, 7329.926700733137, 7348.52234604749, 7382.335589796944, 7725.493528761837, 7930.088039875257, 8023.879641805494, 8039.276465118168, 8039.67841286342, 6330.461910737639, 7311.341979221896, 7327.413806895543, 7389.224131466333, 7391.260873912565, 7159.8926016110845, 7206.012819107032, 7207.0, 7237.203907570269, 7269.7455589054125, 6709.832254193684, 6742.426893713893, 6743.865122697617, 6816.931830681629, 6826.385625293748, 4925.335079764329, 4940.308356830118, 4940.7776650051555, 4977.875553111201, 5051.949480505148, 4203.306454434984, 4832.178529649897, 4843.927341089816, 4894.094592500399, 5066.493350664904], "value": 4449.8277704151105, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.95552731893275, 305.3435114503817, 311.82849432811946, 319.9376121656278, 319.98086095784925, 4840.419149702032, 4875.731834749087, 4938.925916111257, 5456.727163641817, 5576.86057848554, 5073.23901414788, 5164.586833053356, 5313.362396512414, 5348.181618249794, 5383.192521121824, 3757.0463359053274, 3775.509183806101, 3966.9603303967024, 4111.69162312827, 4166.291730405823, 3871.709621778378, 4001.9599804001878, 4137.482814648169, 4160.043190066287, 4161.334186530155, 3897.473841031467, 4058.756474611518, 4081.714260008088, 4092.4270602115726, 4140.606642368985, 3758.830852611639, 3931.0, 4080.6939479539005, 4120.938185927213, 4204.285271503852, 3308.387948229561, 3350.095474221971, 3578.248567800765, 3600.531930848999, 3601.981990090059, 4293.763842988635, 4860.708357498562, 5058.282162610479, 5229.398619158807, 5261.973690131563, 3916.784576848272, 4472.948857018598, 4533.5013148553635, 4685.1800934836265, 4719.834805781816, 5655.123455864306, 5678.517326027292, 5696.287964004499, 5723.284589426322, 5753.942460575383, 3594.26317604893, 3827.282629333177, 3877.4683999820195, 3884.3202439573242, 3930.6658933990643, 5109.233614957786, 5140.511651393112, 5479.4520547945285, 5912.586118971673, 5981.833542459222, 3410.744194185446, 3652.726045546595, 3655.1593133579427, 3882.6893848492055, 3921.8823435296986, 3701.7964011979598, 3932.8762468267387, 3934.7048971327263, 3944.7633142011573, 3960.980195099006, 3882.4176373544196, 4659.4175728034, 4672.761718144694, 4683.1336202802595, 4684.1334353144775, 4774.689645173053, 4943.876403089952, 5584.9388616163615, 5878.267300264363, 5902.930476152376, 1227.8403807505147, 1321.669582604349, 1341.2287934437738, 1377.3319939829175, 1391.373881753215, 762.0, 899.1862364560084, 914.3142643017736, 922.9247926166075, 946.682861241478, 3841.75143078495, 4012.7391719538136, 4405.647548196137, 4528.62247320153, 4568.515232549435, 4467.352233926063, 4616.515265897033, 4968.30443737877, 4985.825496107619, 4987.700737955734, 4009.799510024487, 4516.435445569304, 4547.681662283675, 4580.580020193751, 4586.862394128181, 2164.837637177218, 2302.7582103879186, 2343.093053859781, 2589.080876288928, 2633.841969481837, 4532.119170544253, 4680.040591678698, 4774.594159496482, 4780.713157210578, 5001.874953126201, 4564.425273280713, 5056.772445239973, 5065.670731402447, 5105.0345616937, 5159.819406320761, 4847.515248475124, 5367.033933891937, 5444.50999410055, 6201.441940173969, 6334.809955701244, 4949.505049495022, 5545.2513910623165, 6019.428154325332, 6041.758329666764, 6052.822268308997, 5266.78932842682, 5933.881322373615, 5964.850878728066, 5967.045272756338, 5987.970060149672, 5479.3424789023975, 6179.505639548735, 6186.051393810935, 6215.782447614311, 6221.4089661482085, 3926.0381206604566, 3931.0958479550436, 3933.4069701770827, 3963.740667819315, 3969.9206015878945, 4882.877928051827, 5304.9204261936875, 5641.323041235078, 5649.435056494318, 5707.600467967202, 4782.780391000273, 5309.65487243328, 5570.944290557042, 5693.373728889902, 5759.452851979056, 4949.851504454872, 5750.5112065474905, 5848.561357898089, 5854.565631420159, 5861.805316102649, 4433.445819272591, 4854.073115787549, 5377.821982097257, 5428.83713488596, 5655.802046928337, 5014.0974624567925, 5132.255822905583, 5137.563307118788, 5336.425754402383, 5830.0, 5318.574514038945, 5320.813771517978, 5691.601587888808, 5701.750002498536, 5716.28546431696], "value": 4007.879922927813, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.900053992441, 728.1189760389925, 737.7786664000798, 797.5374282915909, 821.2239433735123, 13565.864341356584, 13853.930730346334, 14171.503997360094, 14208.928955355235, 14229.64425889352, 14555.1994640295, 14583.197924114169, 14652.901032422584, 14756.852431475681, 14839.777403338932, 895.3732387328863, 895.8342706599284, 906.6328137104483, 914.7941713114529, 915.4644532948233, 877.6971944679108, 902.4946030223064, 908.0919080919081, 908.2779190543533, 908.9000209976897, 950.4582388038821, 961.4567769210374, 964.5177411294353, 965.1747755668907, 966.6471737815682, 948.7296120605663, 954.2223088183143, 958.5303201431318, 958.9643185359795, 958.966436174734, 708.8405108850519, 713.2546488919093, 717.8887272472775, 719.8488317453341, 724.4675163754636, 13556.796648050287, 13557.728845423135, 13573.981951353688, 13582.93208533961, 13607.0, 11782.528698852035, 11919.582814601534, 12153.392330383445, 12612.243265404106, 12629.24224546521, 10399.532021058994, 10583.894161058444, 10767.300125491818, 11116.888831111748, 11183.608573699963, 3691.3540130477327, 3693.353663108945, 3709.9443508347395, 3732.6080761520193, 3745.831437585288, 99466.50800238003, 110996.0, 113446.0, 114856.42571787171, 115076.0, 9112.49881256524, 9170.541472926461, 9208.723738287863, 9363.25093992479, 9568.904310956941, 16600.252988615543, 20010.99945002773, 20152.69770953437, 20180.899095504723, 20212.494687632927, 7672.578008209602, 7768.262015108556, 7818.648160832776, 7857.528548287121, 7913.525188488709, 3471.67019133187, 4339.7396156230725, 4377.124575084996, 4382.561743825592, 4511.233090374644, 3543.805090720035, 3644.344018076719, 3667.3215455140885, 3667.7249206309098, 3672.3389789838084, 585.0755805826783, 658.558765627031, 666.6133642487346, 677.1670844860848, 693.993060069403, 482.5102520941247, 500.74462024367836, 565.6947937156106, 678.2098854834162, 681.918169819615, 9292.581833817494, 9314.20829229523, 9321.533923303808, 9414.62341506332, 9496.572654230577, 577.3216470647056, 632.3347092007211, 632.9082283069022, 653.3270731146831, 663.0088018412504, 4173.833046678099, 4200.957990420056, 4407.867763967086, 4443.7111587746695, 4624.237000894882, 115320.42339788248, 123411.0, 152498.0, 153512.23243883933, 155934.2203288999, 86874.69687954686, 90245.0, 95887.04112958921, 135074.0, 136563.0, 108905.0, 109786.45106774574, 112216.43891780652, 113230.86769132368, 113397.4330128344, 9622.807543849223, 9715.854262185932, 9745.853812192965, 9775.75560610962, 9819.607215711578, 41511.377329340685, 54475.72762136164, 54918.725406372716, 55715.721421392635, 55856.72071639616, 50922.49077509178, 51058.0, 71392.0, 81331.78002330087, 81337.0, 13154.877623248862, 13288.003399745252, 13578.86421135776, 13749.175049497062, 13766.93116534451, 14232.786508202593, 14323.355449004612, 14357.56927292183, 14579.343929522985, 14775.96568240213, 13338.93330533341, 13339.626391097056, 13395.062345635712, 13410.463581457023, 13739.862601373858, 13228.735425291632, 13271.734565308447, 13448.798268026183, 13453.394597242952, 13898.436799474064, 10730.785384292114, 13189.340532973505, 13305.401256943655, 13668.043236973317, 13846.584602461942, 11242.831357529807, 11342.149338799458, 11379.772404552028, 11736.823947640964, 11813.586524471602, 8784.033756286932, 10314.535845887103, 10371.377717336984, 10690.946545267223, 11594.594189203337], "value": 7560.899867181929, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 116.0, 114.0, 115.0, 114.0, 115.0, 115.0, 117.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 113.0, 113.0, 148.0, 114.0, 114.0, 128.0, 112.0, 139.0, 114.0], "value": 125.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [267.0, 261.0, 216.0, 228.0, 255.0, 182.0, 213.0, 263.0, 194.0, 271.0], "value": 235.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 78.0, 84.0, 107.0, 50.0, 59.0, 64.0, 50.0, 104.0, 84.0], "value": 76.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 60.0, 82.0, 68.0, 56.0, 74.0, 125.0, 103.0, 102.0, 123.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 109.0, 87.0, 86.0, 89.0, 84.0, 89.0, 86.0, 125.0, 86.0], "value": 93.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 99.0, 96.0, 96.0, 97.0, 122.0, 97.0, 95.0, 95.0, 123.0], "value": 101.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 99.0, 85.0, 87.0, 87.0, 87.0, 90.0, 92.0, 88.0, 87.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 58.0, 56.0, 59.0, 64.0, 79.0, 90.0, 57.0, 58.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 69.0, 73.0, 97.0, 114.0, 105.0, 72.0, 71.0, 70.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 89.0, 91.0, 92.0, 88.0, 88.0, 90.0, 92.0, 87.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 169.0, 165.0, 160.0, 150.0, 156.0, 176.0, 176.0, 164.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 242.0, 190.0, 183.0, 174.0, 174.0, 177.0, 175.0, 184.0, 174.0], "value": 188.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 69.0, 75.0, 80.0, 90.0, 89.0, 70.0, 69.0, 96.0], "value": 77.8, "unit": "score"}]}]} 06:02:17 INFO - Return code: 0 06:02:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:02:17 ERROR - # TBPL SUCCESS # 06:02:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:02:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:02:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:02:17 INFO - 2016-10-24 06:02:17.939 screenresolution[1679:11803] starting screenresolution argv=screenresolution get 06:02:17 INFO - 2016-10-24 06:02:17.942 screenresolution[1679:11803] Display 0: 1600x1200x32@60 06:02:17 INFO - 2016-10-24 06:02:17.948 screenresolution[1680:11806] starting screenresolution argv=screenresolution list 06:02:17 INFO - Available Modes on Display 0 06:02:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:02:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:02:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:02:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:02:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:02:18 INFO - Intel Iris: 06:02:18 INFO - Chipset Model: Intel Iris 06:02:18 INFO - Type: GPU 06:02:18 INFO - Bus: Built-In 06:02:18 INFO - VRAM (Dynamic, Max): 1536 MB 06:02:18 INFO - Vendor: Intel (0x8086) 06:02:18 INFO - Device ID: 0x0a2e 06:02:18 INFO - Revision ID: 0x0009 06:02:18 INFO - Displays: 06:02:18 INFO - Display: 06:02:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:02:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:02:18 INFO - Display Serial Number: 1600x1200 60 06:02:18 INFO - Main Display: Yes 06:02:18 INFO - Mirror: Off 06:02:18 INFO - Online: Yes 06:02:18 INFO - Rotation: Supported 06:02:18 INFO - Return code: 0 06:02:18 INFO - Running post-action listener: _resource_record_post_action 06:02:18 INFO - [mozharness: 2016-10-24 13:02:18.185714Z] Finished run-tests step (success) 06:02:18 INFO - Running post-run listener: _resource_record_post_run 06:02:18 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 33390592; Write bytes: 711620608; Read time: 202; Write time: 2043 06:02:18 INFO - TinderboxPrint: CPU usage
54.4% 06:02:18 INFO - TinderboxPrint: I/O read bytes / time
33,390,592 / 202 06:02:18 INFO - TinderboxPrint: I/O write bytes / time
711,620,608 / 2,043 06:02:18 INFO - TinderboxPrint: CPU idle
2,211.1 (45.6%) 06:02:18 INFO - TinderboxPrint: CPU system
980.7 (20.2%) 06:02:18 INFO - TinderboxPrint: CPU user
1,658.0 (34.2%) 06:02:18 INFO - TinderboxPrint: Swap in / out
434,135,040 / 0 06:02:18 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395639296; Write bytes: 390284288; Read time: 29679; Write time: 840 06:02:18 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31485952; Write bytes: 303010816; Read time: 185; Write time: 1156 06:02:18 INFO - Running post-run listener: _upload_blobber_files 06:02:18 INFO - Blob upload gear active. 06:02:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:02:18 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:02:18 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:02:18 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:02:19 INFO - (blobuploader) - INFO - Open directory for files ... 06:02:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:02:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:02:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:20 INFO - (blobuploader) - INFO - Done attempting. 06:02:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:02:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:02:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:21 INFO - (blobuploader) - INFO - Done attempting. 06:02:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:02:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:02:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:23 INFO - (blobuploader) - INFO - Done attempting. 06:02:23 INFO - (blobuploader) - INFO - Iteration through files over. 06:02:23 INFO - Return code: 0 06:02:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:02:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:02:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/008ccc3cec4fe1338b31cea214c2e7c6adb39a65d441543e7c4652a927672b8b2ab5333489efe8acff497e5032e6f22d5bb4b5cf087a4a211bbe81e606712d49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c576489955190ff87777440775f5c3362059943c3e929bffffa512ce2a4915c06cf64b33202fa80616681a9b1ce2c137573d40f53703e189066618bcd1c116bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe6e36d3252f5d5a9eaea94bba796de7a60d1cefa6d66c2e5f935699d946758f41db5366aaed3445e9005a339ee9d02e5de6171c1d6b2daf4d84e4e231399b9"} 06:02:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:02:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:02:23 INFO - Contents: 06:02:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/008ccc3cec4fe1338b31cea214c2e7c6adb39a65d441543e7c4652a927672b8b2ab5333489efe8acff497e5032e6f22d5bb4b5cf087a4a211bbe81e606712d49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c576489955190ff87777440775f5c3362059943c3e929bffffa512ce2a4915c06cf64b33202fa80616681a9b1ce2c137573d40f53703e189066618bcd1c116bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe6e36d3252f5d5a9eaea94bba796de7a60d1cefa6d66c2e5f935699d946758f41db5366aaed3445e9005a339ee9d02e5de6171c1d6b2daf4d84e4e231399b9"} 06:02:23 INFO - Running post-run listener: copy_logs_to_upload_dir 06:02:23 INFO - Copying logs to upload dir... 06:02:23 INFO - mkdir: /builds/slave/test/build/upload/logs 06:02:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.239729 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-24 06:02:23.922863) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 06:02:23.927145) ========= 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.ufhXp8zVrk/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.aNulkoaU9J/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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/008ccc3cec4fe1338b31cea214c2e7c6adb39a65d441543e7c4652a927672b8b2ab5333489efe8acff497e5032e6f22d5bb4b5cf087a4a211bbe81e606712d49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c576489955190ff87777440775f5c3362059943c3e929bffffa512ce2a4915c06cf64b33202fa80616681a9b1ce2c137573d40f53703e189066618bcd1c116bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe6e36d3252f5d5a9eaea94bba796de7a60d1cefa6d66c2e5f935699d946758f41db5366aaed3445e9005a339ee9d02e5de6171c1d6b2daf4d84e4e231399b9"} build_url:https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/UYJ4jfbyT6yN1nemPqA9rg/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/008ccc3cec4fe1338b31cea214c2e7c6adb39a65d441543e7c4652a927672b8b2ab5333489efe8acff497e5032e6f22d5bb4b5cf087a4a211bbe81e606712d49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c576489955190ff87777440775f5c3362059943c3e929bffffa512ce2a4915c06cf64b33202fa80616681a9b1ce2c137573d40f53703e189066618bcd1c116bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe6e36d3252f5d5a9eaea94bba796de7a60d1cefa6d66c2e5f935699d946758f41db5366aaed3445e9005a339ee9d02e5de6171c1d6b2daf4d84e4e231399b9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 06:02:23.962927) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 06:02:23.963271) ========= 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.ufhXp8zVrk/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.aNulkoaU9J/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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.005179 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 06:02:24.011690) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 06:02:24.012002) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 06:02:24.012357) ========= ========= Total master_lag: 0.38 =========