builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0065 starttime: 1480847189.74 results: success (0) buildid: 20161204004007 builduid: 255856919f434e07bccf62bf2af683de revision: c21f4124a5b79b9989579efa33edc20795692af2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:29.739245) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:29.739682) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:29.739956) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:29.801079) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:29.801412) ========= 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.TstoRKI7rB/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.bhMhycaQo5/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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-12-04 02:26:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.8M=0s 2016-12-04 02:26:30 (63.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.598847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:30.424739) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:30.425122) ========= 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.TstoRKI7rB/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.bhMhycaQo5/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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.027813 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:30.476031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-04 02:26:30.476377) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c21f4124a5b79b9989579efa33edc20795692af2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c21f4124a5b79b9989579efa33edc20795692af2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TstoRKI7rB/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.bhMhycaQo5/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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-12-04 02:26:30,569 truncating revision to first 12 chars 2016-12-04 02:26:30,569 Setting DEBUG logging. 2016-12-04 02:26:30,569 attempt 1/10 2016-12-04 02:26:30,569 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c21f4124a5b7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-04 02:26:31,378 unpacking tar archive at: mozilla-aurora-c21f4124a5b7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.068720 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-04 02:26:31.570101) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:31.570559) ========= script_repo_revision: c21f4124a5b79b9989579efa33edc20795692af2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:31.571066) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:31.571338) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 02:26:31.591144) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-04 02:26:31.591517) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TstoRKI7rB/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.bhMhycaQo5/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:26:31 INFO - MultiFileLogger online at 20161204 02:26:31 in /builds/slave/test 02:26:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:26:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:26:31 INFO - {'append_to_log': False, 02:26:31 INFO - 'base_work_dir': '/builds/slave/test', 02:26:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:26:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:26:31 INFO - 'branch': 'Mozilla-Aurora', 02:26:31 INFO - 'buildbot_json_path': 'buildprops.json', 02:26:31 INFO - 'config_files': ('talos/mac_config.py',), 02:26:31 INFO - 'default_actions': ('clobber', 02:26:31 INFO - 'read-buildbot-config', 02:26:31 INFO - 'download-and-extract', 02:26:31 INFO - 'populate-webroot', 02:26:31 INFO - 'create-virtualenv', 02:26:31 INFO - 'install', 02:26:31 INFO - 'run-tests'), 02:26:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:26:31 INFO - 'download_minidump_stackwalk': True, 02:26:31 INFO - 'download_symbols': 'ondemand', 02:26:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:26:31 INFO - 'tooltool.py': '/tools/tooltool.py', 02:26:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:26:31 INFO - '/tools/misc-python/virtualenv.py')}, 02:26:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:26:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:26:31 INFO - 'installer_path': 'installer.exe', 02:26:31 INFO - 'log_level': 'info', 02:26:31 INFO - 'log_name': 'talos', 02:26:31 INFO - 'log_to_console': True, 02:26:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:26:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:26:31 INFO - 'opt_config_files': (), 02:26:31 INFO - 'pip_index': False, 02:26:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:26:31 INFO - 'cmd': ('bash', 02:26:31 INFO - '-c', 02:26:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:26:31 INFO - 'enabled': True, 02:26:31 INFO - 'halt_on_failure': False, 02:26:31 INFO - 'name': 'check_screen_resolution'},), 02:26:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:26:31 INFO - 'cmd': ('bash', 02:26:31 INFO - '-c', 02:26:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:26:31 INFO - 'enabled': True, 02:26:31 INFO - 'halt_on_failure': False, 02:26:31 INFO - 'name': 'check_screen_resolution'},), 02:26:31 INFO - 'run_cmd_checks_enabled': True, 02:26:31 INFO - 'sps_profile': False, 02:26:31 INFO - 'sps_profile_interval': 0, 02:26:31 INFO - 'suite': 'dromaeojs-e10s', 02:26:31 INFO - 'system_bits': '32', 02:26:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:26:31 INFO - 'title': 't-yosemite-r7-0065', 02:26:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:26:31 INFO - 'use_talos_json': True, 02:26:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:26:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:26:31 INFO - 'work_dir': 'build'} 02:26:31 INFO - [mozharness: 2016-12-04 10:26:31.799971Z] Running clobber step. 02:26:31 INFO - Running pre-action listener: _resource_record_pre_action 02:26:31 INFO - Running main action method: clobber 02:26:31 INFO - rmtree: /builds/slave/test/build 02:26:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:26:34 INFO - Running post-action listener: _resource_record_post_action 02:26:34 INFO - [mozharness: 2016-12-04 10:26:34.668602Z] Finished clobber step (success) 02:26:34 INFO - [mozharness: 2016-12-04 10:26:34.668815Z] Running read-buildbot-config step. 02:26:34 INFO - Running pre-action listener: _resource_record_pre_action 02:26:34 INFO - Running main action method: read_buildbot_config 02:26:34 INFO - Using buildbot properties: 02:26:34 INFO - { 02:26:34 INFO - "project": "", 02:26:34 INFO - "product": "firefox", 02:26:34 INFO - "script_repo_revision": "production", 02:26:34 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:26:34 INFO - "repository": "", 02:26:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:26:34 INFO - "buildid": "20161204004007", 02:26:34 INFO - "stage_platform": "macosx64", 02:26:34 INFO - "basedir": "/builds/slave/test", 02:26:34 INFO - "nightly_build": "True", 02:26:34 INFO - "buildnumber": 10, 02:26:34 INFO - "slavename": "t-yosemite-r7-0065", 02:26:34 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:26:34 INFO - "platform": "yosemite_r7", 02:26:34 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:26:34 INFO - "branch": "mozilla-aurora", 02:26:34 INFO - "repo_path": "releases/mozilla-aurora", 02:26:34 INFO - "revision": "c21f4124a5b79b9989579efa33edc20795692af2", 02:26:34 INFO - "pgo_build": "False", 02:26:34 INFO - "builduid": "255856919f434e07bccf62bf2af683de", 02:26:34 INFO - "slavebuilddir": "test" 02:26:34 INFO - } 02:26:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:26:34 INFO - Running post-action listener: _resource_record_post_action 02:26:34 INFO - [mozharness: 2016-12-04 10:26:34.674928Z] Finished read-buildbot-config step (success) 02:26:34 INFO - [mozharness: 2016-12-04 10:26:34.675164Z] Running download-and-extract step. 02:26:34 INFO - Running pre-action listener: _resource_record_pre_action 02:26:34 INFO - Running main action method: download_and_extract 02:26:34 INFO - mkdir: /builds/slave/test/build/tests 02:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:34 INFO - trying https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:26:34 INFO - Downloading https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:26:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:26:35 INFO - Downloaded 1199 bytes. 02:26:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:26:35 INFO - Using the following test package requirements: 02:26:35 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:26:35 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:35 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:26:35 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:35 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:26:35 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:35 INFO - u'jsshell-mac.zip'], 02:26:35 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:35 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:26:35 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:26:35 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:35 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:26:35 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:35 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:26:35 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:35 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:26:35 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:35 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:26:35 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:26:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:26:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:26:35 INFO - Fetch https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:26:36 INFO - Http code: 200 02:26:36 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 02:26:36 INFO - x-amz-cf-id: IA1wP4koY28iulppwcm8PQ2BhgmUDSd_Sjf_4yi5aJYtfyhsTh7PxA== 02:26:36 INFO - x-amz-version-id: SBVdLJ4AWGceIjG3yi8w3J0.Mo69A40J 02:26:36 INFO - Expected file size: 16281997 02:26:36 INFO - Obtained file size: 16281997 02:26:43 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:26:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:26:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:26:43 INFO - Fetch https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:26:43 INFO - Http code: 200 02:26:43 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 02:26:43 INFO - x-amz-cf-id: k2QSedFlJeH8tHCZNSmvj-lOaQZ97RhKGVfJGuhlRVfZW3GY7Z-yDg== 02:26:43 INFO - x-amz-version-id: SBVdLJ4AWGceIjG3yi8w3J0.Mo69A40J 02:26:44 INFO - Expected file size: 16281997 02:26:44 INFO - Obtained file size: 16281997 02:26:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:26:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:26:50 INFO - Fetch https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:26:51 INFO - Http code: 200 02:26:51 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 02:26:51 INFO - x-amz-cf-id: BucjTbJb8sZdJYjsh_6J54eiI7UtgCuE4kdKs0HVA7KoUyCqD2xWJA== 02:26:51 INFO - x-amz-version-id: yWozL1Mj6rm58QVJADmu1dxmLKcxQb5Y 02:26:51 INFO - Expected file size: 14409038 02:26:51 INFO - Obtained file size: 14409038 02:26:51 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')]} 02:26:51 INFO - trying https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:26:51 INFO - Downloading https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:26:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:26:54 INFO - Downloaded 109860330 bytes. 02:26:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:26:54 INFO - mkdir: /builds/slave/test/properties 02:26:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:26:54 INFO - Writing to file /builds/slave/test/properties/build_url 02:26:54 INFO - Contents: 02:26:54 INFO - build_url:https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:26:55 INFO - Running post-action listener: _resource_record_post_action 02:26:55 INFO - Running post-action listener: set_extra_try_arguments 02:26:55 INFO - [mozharness: 2016-12-04 10:26:55.499220Z] Finished download-and-extract step (success) 02:26:55 INFO - [mozharness: 2016-12-04 10:26:55.499353Z] Running populate-webroot step. 02:26:55 INFO - Running pre-action listener: _resource_record_pre_action 02:26:55 INFO - Running main action method: populate_webroot 02:26:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:26:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:26:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:26:55 INFO - '--tppagecycles', 02:26:55 INFO - '7'], 02:26:55 INFO - 'tests': ['tsvgm']}}, 02:26:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:26:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:26:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:26:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:26:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:55 INFO - 'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:26:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:26:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:55 INFO - 'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['damp', 'tps']}, 02:26:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:55 INFO - 'tests': ['damp', 'tps']}, 02:26:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['dromaeo_dom']}, 02:26:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:26:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['basic_compositor_video']}, 02:26:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:26:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['a11yr', 02:26:55 INFO - 'ts_paint', 02:26:55 INFO - 'tpaint', 02:26:55 INFO - 'sessionrestore', 02:26:55 INFO - 'sessionrestore_no_auto_restore', 02:26:55 INFO - 'tabpaint']}, 02:26:55 INFO - 'other-e10s': {'tests': ['a11yr', 02:26:55 INFO - 'ts_paint', 02:26:55 INFO - 'tpaint', 02:26:55 INFO - 'sessionrestore', 02:26:55 INFO - 'sessionrestore_no_auto_restore', 02:26:55 INFO - 'tabpaint']}, 02:26:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:26:55 INFO - 'ts_paint', 02:26:55 INFO - 'tpaint', 02:26:55 INFO - 'sessionrestore', 02:26:55 INFO - 'sessionrestore_no_auto_restore', 02:26:55 INFO - 'tabpaint']}, 02:26:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:26:55 INFO - 'ts_paint', 02:26:55 INFO - 'tpaint', 02:26:55 INFO - 'sessionrestore', 02:26:55 INFO - 'sessionrestore_no_auto_restore', 02:26:55 INFO - 'tabpaint']}, 02:26:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['a11yr', 02:26:55 INFO - 'ts_paint', 02:26:55 INFO - 'tpaint', 02:26:55 INFO - 'sessionrestore', 02:26:55 INFO - 'sessionrestore_no_auto_restore', 02:26:55 INFO - 'tabpaint']}, 02:26:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['a11yr', 02:26:55 INFO - 'ts_paint', 02:26:55 INFO - 'tpaint', 02:26:55 INFO - 'sessionrestore', 02:26:55 INFO - 'sessionrestore_no_auto_restore', 02:26:55 INFO - 'tabpaint']}, 02:26:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['tsvgx', 02:26:55 INFO - 'tsvgr_opacity', 02:26:55 INFO - 'tart', 02:26:55 INFO - 'tscrollx', 02:26:55 INFO - 'cart']}, 02:26:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:26:55 INFO - 'tsvgr_opacity', 02:26:55 INFO - 'tart', 02:26:55 INFO - 'tscrollx', 02:26:55 INFO - 'cart']}, 02:26:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:55 INFO - 'talos_options': ['--disable-e10s'], 02:26:55 INFO - 'tests': ['tp5o']}, 02:26:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:55 INFO - 'tests': ['tp5o']}, 02:26:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:55 INFO - 'talos_options': ['--disable-e10s', 02:26:55 INFO - '--xperf_path', 02:26:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:26:55 INFO - 'tests': ['tp5n']}, 02:26:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:55 INFO - 'talos_options': ['--xperf_path', 02:26:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:26:55 INFO - 'tests': ['tp5n']}}, 02:26:55 INFO - 'talos.zip': {'path': '', 02:26:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:26:55 INFO - Running post-action listener: _resource_record_post_action 02:26:55 INFO - [mozharness: 2016-12-04 10:26:55.518584Z] Finished populate-webroot step (success) 02:26:55 INFO - [mozharness: 2016-12-04 10:26:55.518717Z] Running create-virtualenv step. 02:26:55 INFO - Running pre-action listener: _resource_record_pre_action 02:26:55 INFO - Running main action method: create_virtualenv 02:26:55 INFO - Creating virtualenv /builds/slave/test/build/venv 02:26:55 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 02:26:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:26:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:26:55 INFO - Using real prefix '/tools/python27' 02:26:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:26:56 INFO - Installing distribute.............................................................................................................................................................................................done. 02:26:59 INFO - Installing pip.................done. 02:26:59 INFO - Return code: 0 02:26:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:26:59 INFO - Reading from file tmpfile_stdout 02:26:59 INFO - Output received: 02:26:59 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) 02:26:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:26: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')]} 02:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26: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')]} 02:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:59 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 0x10b474490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad1575500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, '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.TstoRKI7rB/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.bhMhycaQo5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:26:59 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 02:26:59 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 02:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:59 INFO - 'HOME': '/Users/cltbld', 02:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:59 INFO - 'LOGNAME': 'cltbld', 02:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:59 INFO - 'MOZ_NO_REMOTE': '1', 02:26:59 INFO - 'NO_EM_RESTART': '1', 02:26:59 INFO - 'PAGER': '/bin/cat', 02:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:59 INFO - 'PWD': '/builds/slave/test', 02:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:59 INFO - 'SHELL': '/bin/bash', 02:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:26:59 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:59 INFO - 'USER': 'cltbld', 02:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:59 INFO - 'XPC_FLAGS': '0x0', 02:26:59 INFO - 'XPC_SERVICE_NAME': '0', 02:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:59 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 02:26:59 INFO - Cleaning up... 02:26:59 INFO - Return code: 0 02:26:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:26: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')]} 02:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26: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')]} 02:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:59 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 0x10b474490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad1575500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, '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.TstoRKI7rB/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.bhMhycaQo5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:26:59 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 02:26:59 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 02:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:59 INFO - 'HOME': '/Users/cltbld', 02:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:59 INFO - 'LOGNAME': 'cltbld', 02:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:59 INFO - 'MOZ_NO_REMOTE': '1', 02:26:59 INFO - 'NO_EM_RESTART': '1', 02:26:59 INFO - 'PAGER': '/bin/cat', 02:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:59 INFO - 'PWD': '/builds/slave/test', 02:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:59 INFO - 'SHELL': '/bin/bash', 02:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:26:59 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:59 INFO - 'USER': 'cltbld', 02:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:59 INFO - 'XPC_FLAGS': '0x0', 02:26:59 INFO - 'XPC_SERVICE_NAME': '0', 02:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:00 INFO - Downloading/unpacking psutil>=3.1.1 02:27:00 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 02:27:00 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 02:27:00 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 02:27:00 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 02:27:00 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 02:27:00 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 02:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:27:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:27:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:27:03 INFO - Installing collected packages: psutil 02:27:03 INFO - Running setup.py install for psutil 02:27:04 INFO - building 'psutil._psutil_osx' extension 02:27:04 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 02:27:04 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 02:27:04 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 02:27:04 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 02:27:04 INFO - building 'psutil._psutil_posix' extension 02:27:04 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 02:27:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:27:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:27:04 INFO - ^ 02:27:04 INFO - 1 warning generated. 02:27:04 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 02:27:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:27:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:27:05 INFO - Successfully installed psutil 02:27:05 INFO - Cleaning up... 02:27:05 INFO - Return code: 0 02:27:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:27: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')]} 02:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27: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')]} 02:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b474490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad1575500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, '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.TstoRKI7rB/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.bhMhycaQo5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:27: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:27: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 mozsystemmonitor==0.3 02:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:05 INFO - 'HOME': '/Users/cltbld', 02:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:05 INFO - 'LOGNAME': 'cltbld', 02:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:05 INFO - 'MOZ_NO_REMOTE': '1', 02:27:05 INFO - 'NO_EM_RESTART': '1', 02:27:05 INFO - 'PAGER': '/bin/cat', 02:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:05 INFO - 'PWD': '/builds/slave/test', 02:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:05 INFO - 'SHELL': '/bin/bash', 02:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:27:05 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:05 INFO - 'USER': 'cltbld', 02:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:05 INFO - 'XPC_FLAGS': '0x0', 02:27:05 INFO - 'XPC_SERVICE_NAME': '0', 02:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:27:05 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 02:27:05 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 02:27:05 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 02:27:05 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 02:27:05 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 02:27:05 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 02:27:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:27:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:27:08 INFO - Installing collected packages: mozsystemmonitor 02:27:08 INFO - Running setup.py install for mozsystemmonitor 02:27:08 INFO - Successfully installed mozsystemmonitor 02:27:08 INFO - Cleaning up... 02:27:08 INFO - Return code: 0 02:27:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:27:08 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')]} 02:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:08 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')]} 02:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:08 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 0x10b474490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad1575500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, '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.TstoRKI7rB/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.bhMhycaQo5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:27:08 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 02:27:08 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 02:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:08 INFO - 'HOME': '/Users/cltbld', 02:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:08 INFO - 'LOGNAME': 'cltbld', 02:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:08 INFO - 'MOZ_NO_REMOTE': '1', 02:27:08 INFO - 'NO_EM_RESTART': '1', 02:27:08 INFO - 'PAGER': '/bin/cat', 02:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:08 INFO - 'PWD': '/builds/slave/test', 02:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:08 INFO - 'SHELL': '/bin/bash', 02:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:27:08 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:08 INFO - 'USER': 'cltbld', 02:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:08 INFO - 'XPC_FLAGS': '0x0', 02:27:08 INFO - 'XPC_SERVICE_NAME': '0', 02:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:08 INFO - Downloading/unpacking jsonschema==2.5.1 02:27:08 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 02:27:08 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 02:27:08 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 02:27:08 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 02:27:08 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 02:27:08 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 02:27:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:27:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:27:11 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 02:27:11 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 02:27:11 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 02:27:11 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 02:27:11 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 02:27:11 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 02:27:11 INFO - Downloading functools32-3.2.3-2.tar.gz 02:27:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:27:11 INFO - warning: no files found matching '*.txt' 02:27:11 INFO - no previously-included directories found matching 'build' 02:27:11 INFO - no previously-included directories found matching 'dist' 02:27:11 INFO - no previously-included directories found matching '.git*' 02:27:11 INFO - Installing collected packages: jsonschema, functools32 02:27:11 INFO - Running setup.py install for functools32 02:27:11 INFO - warning: no files found matching '*.txt' 02:27:11 INFO - no previously-included directories found matching 'build' 02:27:11 INFO - no previously-included directories found matching 'dist' 02:27:11 INFO - no previously-included directories found matching '.git*' 02:27:11 INFO - Successfully installed jsonschema functools32 02:27:11 INFO - Cleaning up... 02:27:12 INFO - Return code: 0 02:27:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b474490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad1575500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, '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.TstoRKI7rB/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.bhMhycaQo5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:27:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:27:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:27:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:27:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:12 INFO - 'HOME': '/Users/cltbld', 02:27:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:12 INFO - 'LOGNAME': 'cltbld', 02:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:12 INFO - 'MOZ_NO_REMOTE': '1', 02:27:12 INFO - 'NO_EM_RESTART': '1', 02:27:12 INFO - 'PAGER': '/bin/cat', 02:27:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:12 INFO - 'PWD': '/builds/slave/test', 02:27:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:12 INFO - 'SHELL': '/bin/bash', 02:27:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:27:12 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:27:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:12 INFO - 'USER': 'cltbld', 02:27:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:12 INFO - 'XPC_FLAGS': '0x0', 02:27:12 INFO - 'XPC_SERVICE_NAME': '0', 02:27:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:27:12 INFO - Cleaning up... 02:27:12 INFO - Return code: 0 02:27:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b474490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad1575500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, '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.TstoRKI7rB/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.bhMhycaQo5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:27:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:27:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:27:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:27:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:12 INFO - 'HOME': '/Users/cltbld', 02:27:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:12 INFO - 'LOGNAME': 'cltbld', 02:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:12 INFO - 'MOZ_NO_REMOTE': '1', 02:27:12 INFO - 'NO_EM_RESTART': '1', 02:27:12 INFO - 'PAGER': '/bin/cat', 02:27:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:12 INFO - 'PWD': '/builds/slave/test', 02:27:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:12 INFO - 'SHELL': '/bin/bash', 02:27:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:27:12 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:27:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:12 INFO - 'USER': 'cltbld', 02:27:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:12 INFO - 'XPC_FLAGS': '0x0', 02:27:12 INFO - 'XPC_SERVICE_NAME': '0', 02:27:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:12 INFO - Downloading/unpacking blobuploader==1.2.4 02:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:15 INFO - Downloading blobuploader-1.2.4.tar.gz 02:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:27:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:27:15 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 02:27:15 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 02:27:15 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 02:27:15 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 02:27:15 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 02:27:15 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 02:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:27:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:27:15 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 02:27:15 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 02:27:15 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 02:27:15 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 02:27:15 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 02:27:15 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 02:27:16 INFO - Downloading docopt-0.6.1.tar.gz 02:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:27:16 INFO - Installing collected packages: blobuploader, requests, docopt 02:27:16 INFO - Running setup.py install for blobuploader 02:27:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:27:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:27:16 INFO - Running setup.py install for requests 02:27:17 INFO - Running setup.py install for docopt 02:27:17 INFO - Successfully installed blobuploader requests docopt 02:27:17 INFO - Cleaning up... 02:27:17 INFO - Return code: 0 02:27:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:17 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 0x10b474490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad1575500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, '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.TstoRKI7rB/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.bhMhycaQo5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:27:17 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 02:27:17 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 02:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:17 INFO - 'HOME': '/Users/cltbld', 02:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:17 INFO - 'LOGNAME': 'cltbld', 02:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:17 INFO - 'MOZ_NO_REMOTE': '1', 02:27:17 INFO - 'NO_EM_RESTART': '1', 02:27:17 INFO - 'PAGER': '/bin/cat', 02:27:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:17 INFO - 'PWD': '/builds/slave/test', 02:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:17 INFO - 'SHELL': '/bin/bash', 02:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:27:17 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:17 INFO - 'USER': 'cltbld', 02:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:17 INFO - 'XPC_FLAGS': '0x0', 02:27:17 INFO - 'XPC_SERVICE_NAME': '0', 02:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:27:17 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-j_7TY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:27:17 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-YwRSOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:27:17 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-_QlTg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:27:17 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Dk1zrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:27:17 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-E2MW39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:27:18 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-C7Ronz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:27:18 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-uLxLSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:27:18 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-_Wqgty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:27:18 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-IoHU8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:27:18 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-3tTcZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:27:18 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-aWwzPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:27:18 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-jeHOnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:27:18 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-ysq2ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:27:18 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-OGE0FR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:18 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-1SAuZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:27:19 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-9gfka7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:27:19 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-QFz2X3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:27:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:27:19 INFO - Running setup.py install for manifestparser 02:27:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:27:19 INFO - Running setup.py install for mozcrash 02:27:19 INFO - Running setup.py install for mozdebug 02:27:19 INFO - Running setup.py install for mozdevice 02:27:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:27:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:27:19 INFO - Running setup.py install for mozfile 02:27:20 INFO - Running setup.py install for mozhttpd 02:27:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:27:20 INFO - Running setup.py install for mozinfo 02:27:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:27:20 INFO - Running setup.py install for mozInstall 02:27:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:27:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:27:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:27:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:27:20 INFO - Running setup.py install for mozleak 02:27:20 INFO - Running setup.py install for mozlog 02:27:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:27:20 INFO - Running setup.py install for moznetwork 02:27:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:27:20 INFO - Running setup.py install for mozprocess 02:27:21 INFO - Running setup.py install for mozprofile 02:27:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:27:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:27:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:27:21 INFO - Running setup.py install for mozrunner 02:27:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:27:21 INFO - Running setup.py install for mozscreenshot 02:27:21 INFO - Running setup.py install for moztest 02:27:21 INFO - Running setup.py install for mozversion 02:27:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:27:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:27:21 INFO - Cleaning up... 02:27:21 INFO - Return code: 0 02:27:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:21 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 0x10b474490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad1575500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, '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.TstoRKI7rB/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.bhMhycaQo5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:27:21 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 02:27:21 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 02:27:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:27:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:21 INFO - 'HOME': '/Users/cltbld', 02:27:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:21 INFO - 'LOGNAME': 'cltbld', 02:27:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:21 INFO - 'MOZ_NO_REMOTE': '1', 02:27:21 INFO - 'NO_EM_RESTART': '1', 02:27:21 INFO - 'PAGER': '/bin/cat', 02:27:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:21 INFO - 'PWD': '/builds/slave/test', 02:27:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:21 INFO - 'SHELL': '/bin/bash', 02:27:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:27:21 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:27:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:21 INFO - 'USER': 'cltbld', 02:27:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:21 INFO - 'XPC_FLAGS': '0x0', 02:27:21 INFO - 'XPC_SERVICE_NAME': '0', 02:27:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:27:22 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-TFdX5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:27:22 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)) 02:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:27:22 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-ItyiXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:27:22 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-2a5hvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:27:22 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)) 02:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:27:22 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-71oM6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:27:22 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)) 02:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:27:22 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-nWkEfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:27:22 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)) 02:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:27:22 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-igFI7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:27:22 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)) 02:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:27:22 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-lzob1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:27:22 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)) 02:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:27:22 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-EeROyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:27:22 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)) 02:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:27:22 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-PP2wgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:27:23 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)) 02:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:27:23 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-QZ7z1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:27:23 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)) 02:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:27:23 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-o7gRSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:27:23 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)) 02:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:27:23 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-S1fy3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:27:23 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)) 02:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:27:23 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-F1BMjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:27:23 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)) 02:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:27:23 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-7oTHlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:27:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:23 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-rDv7zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:23 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)) 02:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:27:23 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-jlPq81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:27:23 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)) 02:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:27:23 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-F0DB5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:27:23 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)) 02:27:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:27:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:27:23 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)) 02:27:23 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)) 02:27:23 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)) 02:27:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:26 INFO - Downloading blessings-1.6.tar.gz 02:27:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:27:26 INFO - Installing collected packages: blessings 02:27:26 INFO - Running setup.py install for blessings 02:27:26 INFO - Successfully installed blessings 02:27:26 INFO - Cleaning up... 02:27:26 INFO - Return code: 0 02:27:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:27:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:27:27 INFO - Reading from file tmpfile_stdout 02:27:27 INFO - Current package versions: 02:27:27 INFO - blessings == 1.6 02:27:27 INFO - blobuploader == 1.2.4 02:27:27 INFO - docopt == 0.6.1 02:27:27 INFO - functools32 == 3.2.3-2 02:27:27 INFO - jsonschema == 2.5.1 02:27:27 INFO - manifestparser == 1.1 02:27:27 INFO - mozInstall == 1.12 02:27:27 INFO - mozcrash == 1.0 02:27:27 INFO - mozdebug == 0.1 02:27:27 INFO - mozdevice == 0.48 02:27:27 INFO - mozfile == 1.2 02:27:27 INFO - mozhttpd == 0.7 02:27:27 INFO - mozinfo == 0.9 02:27:27 INFO - mozleak == 0.1 02:27:27 INFO - mozlog == 3.4 02:27:27 INFO - moznetwork == 0.27 02:27:27 INFO - mozprocess == 0.23 02:27:27 INFO - mozprofile == 0.28 02:27:27 INFO - mozrunner == 6.13 02:27:27 INFO - mozscreenshot == 0.1 02:27:27 INFO - mozsystemmonitor == 0.3 02:27:27 INFO - moztest == 0.7 02:27:27 INFO - mozversion == 1.4 02:27:27 INFO - psutil == 3.1.1 02:27:27 INFO - requests == 1.2.3 02:27:27 INFO - wsgiref == 0.1.2 02:27:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:27 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 0x10b474490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad1575500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, '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.TstoRKI7rB/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.bhMhycaQo5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:27:27 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 02:27:27 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 02:27:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:27:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:27 INFO - 'HOME': '/Users/cltbld', 02:27:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:27 INFO - 'LOGNAME': 'cltbld', 02:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:27 INFO - 'MOZ_NO_REMOTE': '1', 02:27:27 INFO - 'NO_EM_RESTART': '1', 02:27:27 INFO - 'PAGER': '/bin/cat', 02:27:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:27 INFO - 'PWD': '/builds/slave/test', 02:27:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:27 INFO - 'SHELL': '/bin/bash', 02:27:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:27:27 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:27:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:27 INFO - 'USER': 'cltbld', 02:27:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:27 INFO - 'XPC_FLAGS': '0x0', 02:27:27 INFO - 'XPC_SERVICE_NAME': '0', 02:27:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:27 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)) 02:27:27 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)) 02:27:27 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)) 02:27:27 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)) 02:27:27 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)) 02:27:27 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)) 02:27:27 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)) 02:27:27 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)) 02:27:27 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)) 02:27:27 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)) 02:27:27 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)) 02:27:27 INFO - Cleaning up... 02:27:27 INFO - Return code: 0 02:27:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b474490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad1575500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, '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.TstoRKI7rB/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.bhMhycaQo5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 02:27:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:27:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:27:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:27 INFO - 'HOME': '/Users/cltbld', 02:27:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:27 INFO - 'LOGNAME': 'cltbld', 02:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:27 INFO - 'MOZ_NO_REMOTE': '1', 02:27:27 INFO - 'NO_EM_RESTART': '1', 02:27:27 INFO - 'PAGER': '/bin/cat', 02:27:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:27 INFO - 'PWD': '/builds/slave/test', 02:27:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:27 INFO - 'SHELL': '/bin/bash', 02:27:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:27:27 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:27:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:27 INFO - 'USER': 'cltbld', 02:27:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:27 INFO - 'XPC_FLAGS': '0x0', 02:27:27 INFO - 'XPC_SERVICE_NAME': '0', 02:27:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:27:27 INFO - Cleaning up... 02:27:27 INFO - Return code: 0 02:27:27 INFO - Running post-action listener: _resource_record_post_action 02:27:27 INFO - Running post-action listener: _start_resource_monitoring 02:27:27 INFO - Starting resource monitoring. 02:27:27 INFO - [mozharness: 2016-12-04 10:27:27.835205Z] Finished create-virtualenv step (success) 02:27:27 INFO - [mozharness: 2016-12-04 10:27:27.836146Z] Running install step. 02:27:27 INFO - Running pre-action listener: _resource_record_pre_action 02:27:27 INFO - Running main action method: install 02:27:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:27:28 INFO - Reading from file tmpfile_stdout 02:27:28 INFO - Detecting whether we're running mozinstall >=1.0... 02:27:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:27:28 INFO - Reading from file tmpfile_stdout 02:27:28 INFO - Output received: 02:27:28 INFO - Usage: mozinstall [options] installer 02:27:28 INFO - Options: 02:27:28 INFO - -h, --help show this help message and exit 02:27:28 INFO - -d DEST, --destination=DEST 02:27:28 INFO - Directory to install application into. [default: 02:27:28 INFO - "/builds/slave/test"] 02:27:28 INFO - --app=APP Application being installed. [default: firefox] 02:27:28 INFO - mkdir: /builds/slave/test/build/application 02:27:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:27:58 INFO - Reading from file tmpfile_stdout 02:27:58 INFO - Output received: 02:27:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:27:58 INFO - Running post-action listener: _resource_record_post_action 02:27:58 INFO - [mozharness: 2016-12-04 10:27:58.976894Z] Finished install step (success) 02:27:58 INFO - [mozharness: 2016-12-04 10:27:58.977051Z] Running run-tests step. 02:27:58 INFO - Running pre-action listener: _resource_record_pre_action 02:27:58 INFO - Running main action method: run_tests 02:27:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:27:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:27:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:27:58 INFO - 2016-12-04 02:27:58.989 screenresolution[1679:17667] starting screenresolution argv=screenresolution get 02:27:58 INFO - 2016-12-04 02:27:58.991 screenresolution[1679:17667] Display 0: 1600x1200x32@60 02:27:58 INFO - 2016-12-04 02:27:58.996 screenresolution[1680:17669] starting screenresolution argv=screenresolution list 02:27:58 INFO - Available Modes on Display 0 02:27:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:27:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:27:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:27:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:27:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:27:59 INFO - Intel Iris: 02:27:59 INFO - Chipset Model: Intel Iris 02:27:59 INFO - Type: GPU 02:27:59 INFO - Bus: Built-In 02:27:59 INFO - VRAM (Dynamic, Max): 1536 MB 02:27:59 INFO - Vendor: Intel (0x8086) 02:27:59 INFO - Device ID: 0x0a2e 02:27:59 INFO - Revision ID: 0x0009 02:27:59 INFO - Displays: 02:27:59 INFO - Display: 02:27:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:27:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:27:59 INFO - Display Serial Number: 1600x1200 60 02:27:59 INFO - Main Display: Yes 02:27:59 INFO - Mirror: Off 02:27:59 INFO - Online: Yes 02:27:59 INFO - Rotation: Supported 02:27:59 INFO - Return code: 0 02:27:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:27:59 INFO - Python 2.7.3 02:27:59 INFO - Return code: 0 02:27:59 INFO - grabbing minidump binary from tooltool 02:27: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')]} 02:27:59 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 0x10b288d30>, '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 0x10b0eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b280b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:27:59 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 02:27:59 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 02:27:59 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 02:27:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:27:59 INFO - Return code: 0 02:27:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:27:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:27:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:27:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:27:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:27:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0065', '--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 02:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0065 --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 02:27:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TstoRKI7rB/Render', 02:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:59 INFO - 'HOME': '/Users/cltbld', 02:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:59 INFO - 'LOGNAME': 'cltbld', 02:27:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:27:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:59 INFO - 'MOZ_NO_REMOTE': '1', 02:27:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:27:59 INFO - 'NO_EM_RESTART': '1', 02:27:59 INFO - 'PAGER': '/bin/cat', 02:27:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:59 INFO - 'PWD': '/builds/slave/test', 02:27:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:59 INFO - 'SHELL': '/bin/bash', 02:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bhMhycaQo5/Listeners', 02:27:59 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 02:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:59 INFO - 'USER': 'cltbld', 02:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:59 INFO - 'XPC_FLAGS': '0x0', 02:27:59 INFO - 'XPC_SERVICE_NAME': '0', 02:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0065', '--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 02:27:59 INFO - mozversion application_buildid: 20161204004007 02:27:59 INFO - mozversion application_changeset: c21f4124a5b79b9989579efa33edc20795692af2 02:27:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:27:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:27:59 INFO - mozversion application_name: Firefox 02:27:59 INFO - mozversion application_remotingname: firefox-dev 02:27:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:27:59 INFO - mozversion application_vendor: Mozilla 02:27:59 INFO - mozversion application_version: 52.0a2 02:27:59 INFO - mozversion platform_buildid: 20161204004007 02:27:59 INFO - mozversion platform_changeset: c21f4124a5b79b9989579efa33edc20795692af2 02:27:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:27:59 INFO - mozversion platform_version: 52.0a2 02:27:59 INFO - using testdate: 1480847279 02:27:59 INFO - actual date: 1480847279 02:27:59 INFO - starting webserver on 'localhost:49246' 02:27:59 INFO - SUITE-START | Running 2 tests 02:27:59 INFO - TEST-START | dromaeo_css 02:27:59 INFO - operating with platform_type : mac_ 02:27:59 INFO - Initialising browser for dromaeo_css test... 02:27:59 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpoRJm9X/profile http://localhost:49246/getInfo.html) 02:28:02 INFO - PROCESS | 1690 | 2016-12-04 02:28:02.701 plugin-container[1693:17852] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b53, name = 'com.apple.tsm.portname' 02:28:02 INFO - PROCESS | 1690 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:02 INFO - PROCESS | 1690 | 2016-12-04 02:28:02.702 plugin-container[1693:17852] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 02:28:02 INFO - PROCESS | 1690 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:02 INFO - PROCESS | 1690 | 2016-12-04 02:28:02.702 plugin-container[1693:17852] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:02 INFO - PROCESS | 1690 | __metrics Screen width/height:1600/1200 02:28:02 INFO - PROCESS | 1690 | colorDepth:24 02:28:02 INFO - PROCESS | 1690 | Browser inner width/height: 1024/689 02:28:02 INFO - PROCESS | 1690 | __metrics 02:28:02 ERROR - PROCESS | 1690 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:28:03 INFO - TEST-INFO | 1690: exit 0 02:28:03 INFO - Browser initialized. 02:28:03 INFO - Running cycle 1/1 for dromaeo_css test... 02:28:03 INFO - TEST-INFO | started process 1695 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpoRJm9X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:06 INFO - PROCESS | 1695 | 2016-12-04 02:28:06.197 plugin-container[1696:17989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 02:28:06 INFO - PROCESS | 1695 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:06 INFO - PROCESS | 1695 | 2016-12-04 02:28:06.198 plugin-container[1696:17989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 02:28:06 INFO - PROCESS | 1695 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:06 INFO - PROCESS | 1695 | 2016-12-04 02:28:06.198 plugin-container[1696:17989] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:15 INFO - PROCESS | 1695 | RSS: Main: 229629952 02:28:15 INFO - PROCESS | 1695 | 02:29:05 INFO - PROCESS | 1695 | 1480847345535 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] 02:31:19 INFO - PROCESS | 1695 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 02:31:21 INFO - PROCESS | 1695 | RSS: Main: 233930752 02:31:21 INFO - PROCESS | 1695 | 02:34:24 INFO - PROCESS | 1695 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 02:34:24 INFO - PROCESS | 1695 | RSS: Main: 243388416 02:34:24 INFO - PROCESS | 1695 | 02:37:26 INFO - PROCESS | 1695 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 02:37:27 INFO - PROCESS | 1695 | RSS: Main: 246149120 02:37:27 INFO - PROCESS | 1695 | 02:40:29 INFO - PROCESS | 1695 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 02:40:29 INFO - PROCESS | 1695 | RSS: Main: 246063104 02:40:29 INFO - PROCESS | 1695 | 02:43:32 INFO - PROCESS | 1695 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 02:43:33 INFO - PROCESS | 1695 | RSS: Main: 249860096 02:43:33 INFO - PROCESS | 1695 | 02:46:36 INFO - PROCESS | 1695 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 02:46:36 INFO - PROCESS | 1695 | RSS: Main: 251686912 02:46:36 INFO - PROCESS | 1695 | 02:46:36 INFO - PROCESS | 1695 | __start_tp_report 02:46:36 INFO - PROCESS | 1695 | _x_x_mozilla_page_load 02:46:36 INFO - PROCESS | 1695 | _x_x_mozilla_page_load_details 02:46:36 INFO - PROCESS | 1695 | |i|pagename|runs| 02:46:36 INFO - PROCESS | 1695 | |0;dojo.html;2991.5961345218393;3316.2041110133546;3334.4498157803964;3345.096823857557;3392.6098498672654;4711.90576188476;5611.242482264893;5629.493345598895;5634.464725851048;5642.971785141069;5052.9747351263195;5380.139177731564;5704.828855134342;5713.971430142843;5753.367129615739;5182.6631268967585;5184.507471790174;5240.318758561381;5280.313559237294;5348.91976620351;5180.4042535108565;5194.974025129869;5227.581793456515;5250.842474725745;5251.291075704769;5082.1106306396605;5139.58883289336;5171.4311425743135;5222.895542089175;5227.634065615371;4990.4011518617635;5073.873153171164;5121.28302037715;5148.2535032420465;5197.714125723083;5086.21163719624;5103.617228707824;5104.05574968632;5209.166533354645;5216.113260745682;5559.972200138974;5607.018771714934;5674.120511320711;5677.489025987681;5716.228309178249;6137.254412462224;6170.321264660884;6203.534734894901;6223.253209614875;6229.813105606839;6228.564000519966;6243.65659888706;6246.843828904268;6271.122042913996;6282.863826299034;5611.130274807411;5649.491545760901;5676.375598684142;5750.07998720207;5777.566682498829;6157;6212.81361559154;6225.408586184305;6231.345708700612;6238.376162383816;5536.197251398525;6106.633601983806;6113.081058500009;6131.478824299896;6139.171211886338;5022.397312322545;6121.387861213843;6216.098665693539;6227;6236.220472440945;5772.942270577324;5789.218455508537;5803.65178089316;5854.356020837705;6139.47814435769;5940.019896716994;5949.881002379928;5950.9107363389585;5952.255968003999;6134.294556126102;3707.1288247261873;4843.75781210938;4898.632602554822;4905.386826646669;4916.655834091579;4328.978355108205;4358.150160718693;4398.362237475548;4417.447819022622;4448.532904045029;5670.376258611549;5699.116636921284;5752.36723960364;5867.941320586825;5887.970560147171;6158.414950579688;6168.784092556829;6217.222847144107;6250.249970003629;6310.5898116622275;5806.477417032487;5850.970745146247;5852.356240813507;5871.156296055771;5907.232059832194;5244.816431424882;5268.078086334953;5368.624196306299;5370.113931201387;5398.325209348832;6191;6203;6208.81373558794;6233.844153896189;6245.28179259391;6259.499240060693;6259.593126446766;6262.968685156545;6265.5927364722975;6296.5592408530965;6283.246010478589;6287.874242515032;6292.867283888944;6299.118123462627;6306.495480361468;5921.082232254007;5951.732172052355;5953;5960.76156953717;6105.572609917263;6116.031445915052;6117.949202904554;6122.204113465221;6122.9471041527795;6128.503123582109;6051.667158306335;6073.423024812636;6111.652512877479;6117.522930757332;6142.815715528542;5920.822375328747;6020.608660437058;6083.148359229623;6090.390960903874;6120.54095942815;5753.453421924911;5819.214406055128;5834.912476312944;5877.617954832922;5907.763689452374;5903.940960590511;5969.73136208863;6044.848878778066;6049.243844519435;6145.340757995227;4805.303231031551;5008.549230569266;5845.736941837543;5903;5916.142159386951 02:46:36 INFO - PROCESS | 1695 | |1;ext.html;475.4342332624177;481.8289507224934;499.36829910163647;515.9835124804137;556.0796881161679;36086.819565902166;39065.80467097664;39162.020949476275;39217;39359.80320098395;39679.801600992025;40039.79980100103;40125.598744012554;40159.59840401595;40220.99447513813;7801.219878012182;7865.2134786521465;8818.559072046372;9464.716058518256;9496.145346918776;9179.908200917973;9235.861462078074;9307.86038209424;9426.057394260588;9426.575804088798;9424.575894084783;9436.811263774754;9438.528073596328;9441.716748497556;9476.00501947296;8369.62336694844;9147.588358523883;9383.483908385037;9435.19800816928;9461.911880133805;9389.201917836992;9390.812183756287;9481.33630645855;9485.762855928657;9492.288078394147;38820;39216.607833921866;39240;39267;39357.80321098405;3313.5526703894907;3326.750493712957;3401.42175830107;3875.961240387588;3883.9029024274337;28455.57316640252;29910.401791964257;30902.38195236105;30908.227294317596;31201.687983120333;3208.246062175387;3212.8072315661134;3227.402930457873;3232.8545215465124;3298.09302441828;507329;509948;512391;512922;514450;60123.69938150281;60154.69922650359;60391.69804150951;60429.697851510464;60493;63896.041559376645;64706.67646661736;64731.70536589242;64973.67513162404;65027.349726503075;15874.365025399085;16092.758608620647;16102.355905763872;16120.355185792672;16135.919320403558;12189.085818563643;12635.684107897376;12643.93678031604;12658.556950506689;12670.049746268882;17895.386157508467;18675.71986420205;18920.905395472935;19039.52401189981;19180.232790688216;2717.7961652876115;3288.079337785424;6441.710123044474;7571.939928409917;7797.571133587594;837.5560952695081;983.695054533097;985.3348989432104;994.9104580587782;1011.1041796631316;29263.268418289714;30026.84986575097;30166.793328267064;30574.847125764674;30881.382372352426;868.3617541107282;963.5278713430368;975.3124047546463;997.3567049253336;1176.129664048598;12584;15373.9231303845;15566.9221653891;15674;15687.921560392126;509353;638149;641196;642468;644899;57534.13698794522;57783.13325300124;57850;57895.13157302644;58122;1215.2040413529153;1364.1542243808703;1368.0286996232787;1393.191948669769;1393.8606139385981;28413.005544805834;29027.274318142216;29291.41417171602;29538.704612954454;29897.70102298949;51214.74392628013;51553.22670159875;51565.48434515607;51811;51973.480265196864;619425;620037;622537;623054;624791;26273.868630656725;26303.342416438976;26650;26673.46653066889;26690.866545667148;44696.776516117214;45351.319730204734;45392;46342.7682861597;46785;35299.82350088233;37318.81340593279;37996;39194.80402597969;40863.7956810214;29921.55117673193;31467;37426.625733742316;38260.30671180995;39157.825265242085;37776.866693999225;37840.053998650255;38230.808845955595;38246.80876595599;38272.42591361076;32975.34049318952;33816.1545961353;34446.311073777884;34466.82766586151;34675.13312167115;25778.61332079983;25898.61152082758;25981.610275846255;26074.696265186285;26401.735982639926 02:46:36 INFO - PROCESS | 1695 | |2;jquery.html;371.6692143991847;375.8571742737759;385.47960253657567;389.0817670298098;403.3586597310276;15684.659508751296;18334.358297459592;18969.430917072474;19245.807541924576;19423.805761942378;19374.806251937476;19587.314443994463;19882.304119355824;19903.701444478345;20058.09448102428;5628.943710562903;5721.542276617871;5990.700464976756;6008.3390827009;6019.036954087347;4898.53463920927;5000.449950505442;5069.619778516608;5509.696966666832;5911.320198177222;5597.44025597439;5812.389699081599;5863.091220860773;5865.090910908816;5866.589338746268;5823.883522329551;5976.551758618121;5984.910226346608;6000.399960003986;6001.399860013986;4781.784819683088;5599.972000140014;5804.216430781875;5810.4189581041555;5859.53123750099;12799.232046077172;12954.546590869368;13009.609711708665;13016.41426135826;22322.21872234464;2206.5035367042447;2244.9887750561143;2254.222293308806;2301.493671392291;2867.7562407195414;9672.56473458696;9765.267604929588;9851.655192068314;10166.847497287547;10352.275340726152;1834.110456428631;1899.4016884681282;1932.439592518168;1932.439592518168;1942.7183058456585;87674.68487972686;113781.43109284401;114847.99999999917;115405;115792;11268.718282043015;11395.715107122389;11537.307761534335;11567.88432115668;11648.475818588015;14340.713185736227;14343.067700599428;14365.281735913162;14374.137551746928;14394.352254148589;8076.4750291230885;8364.916350836535;8430.873536896952;8489.193526614961;8508.702195423131;6880.2431732509385;7355.668994895243;8033.915421418151;8347.44319443969;8376.371772117116;4446.888827779331;4497.572730590588;4500.482444518857;4502.729836209838;4576.199165146086;1892.3755160796904;1937.9127939242485;1951.541387773872;1953.8534609904314;1958.265650381107;813.2193094629222;879.7536689726886;886.3352485635774;898.7887846356101;993.5082134343546;19365.4190374289;20750.688739668916;20766.688499672517;20795.480112997295;21118.47203819917;879.1779685993616;904.7285814255697;930.8138372325562;938.1041105743998;992.6972273456665;8734.519601421984;10408.47957602132;11246.04408625276;11247.718807029725;11296.717582060513;505110;507282;510500;579566;633570;122587.38706306412;123430;125518;132864;143652;927.6057675487891;952.1097773581723;966.0580933589835;967.1585720423277;993.5280741647804;16079.954802937771;17284.395046173322;19036.23855045838;19057.71413428772;19116;53573;53710.65723356791;54580.181297281364;55503.72248138733;56131;79475;80572.59713701395;83457.16542834495;83623.16376836154;83969;7940.444168908352;8159.83680326402;8230.794230144294;8264.669413223404;8380.664773408995;10611.469426528489;13015.804762928754;13018.2839943804;13052;13121.803172952605;9656.420614763138;9926.511023346557;10485.737856553647;10558.260921735711;11535.36555489456;9684.322097453392;10901.454927253448;11061.446927653746;11176.664700059011;11473.139514536277;11554.884451155716;11572.421378931189;11615.477303521193;11856.407179641155;11918.165728398928;8138.348932085537;8142.67429302845;8145.470544414594;8360.08039115709;8383.161683831568;8175.7138500152205;11119.498867652763;11264.718382040515;11526.481308340977;11598.072154227473 02:46:36 INFO - PROCESS | 1695 | |3;mootools.html;1263.4756576020952;1422.9573112806615;1558.9064656120634;1710.2902295547349;1759.6744602248577;6572.572782769117;7402.333789958902;7944.086430060537;8380.91619083809;8403.5378054207;7973.282404583586;8274.379421543379;8276.62755176017;8277.710280140196;8291.792705182374;2106.6096376391583;2271.318604418672;2313.849599776009;2315.270689732737;2315.305408377488;2257.7403598586206;2282.0985710644286;2351.988240058806;2352.5177338645535;2356.5522550715395;1929.60443109163;2192.6930229767845;2224.9777502225015;2277.032261288954;2305.4121199094293;2257.4356410897276;2262.502249505106;2279.863208207513;2281.4638559938485;2282.954340913189;2269.693591365161;2329.77867102625;2330.347502699247;2334.743178250391;2338.695969523968;8311.33509319253;8362.205590468895;8372.665093396257;8381.706640267623;8393.286570641503;4990.625703072248;5153.123968925292;5160.56135228506;5162.586993040548;5171.560417364529;2833.6741274753463;3266.5137362500004;3335.4663253879507;3575.1419659281587;3596.7841929484316;1038.9116925061378;1052.9105026072793;1069.6470164845582;1087.4345340423015;1088.3089238333637;10663.253572249945;10800.297980631234;10886.510107045202;10892.40091794951;10916.563337466492;3883.4369016493015;4582.427196600425;4655.115528049728;4665.740250132445;4683.227267500893;9274.721758347261;9303.023182565868;9344;9345.532723363805;9346.439213647203;5118.181091025418;5125.79496820123;5137.794488220429;5148.639595228373;5155.587552995751;4192.413062171298;4412.227860124465;4670.6497012724185;4735.597474214662;4738.360321356573;3249.0902547286796;3340.883069092619;3802.676772474371;3821.5987321331418;3906.4140378942816;1066.3388699006666;1186.7389174381622;1273.9108262421541;1281.2824818101892;1304.2826445454962;713.9000539924415;785.7132146766389;829.4235506323048;876.9868451973225;899.2311573604604;5521.1442226454965;5654.123610840326;5712.714364281769;5798.971005144947;5808.419158084158;771.340503869195;851.9989012909806;867.8611422172422;869.2046777198918;876.9473831570126;3375.578052743407;3794.6964242860504;3902.804859757058;3922.5489068757456;3927.1752931884625;9426.245900327956;9916.652917147865;9920.107190352903;9927.007299270015;9941.005899410002;9813.705588832347;9866.851997220047;9890.653827116017;9893.060159284856;9900.504974751233;7630.923690763132;7767.37860971121;7770.41721870862;7784.805379865548;7792.454528182972;4937.839607692261;5193.636445448782;5379.596530260168;5490.615656903979;5690.0611399120335;9163.404378715362;9246.306527010636;9288.674896378594;9394.201492873182;9408.858867116862;9416.482093484925;9431.952840235756;9530.285228607743;9535.856962145712;9542.284328675238;6249.25008998923;6425.389587989133;6458.22501299847;6721.193456785217;6830;7739.883901741366;8011.39914506426;8012.118666946515;8020.438569300093;8061.032676078674;5644.187693934827;6258.405451482102;7147.142342918883;7164.068671072727;7171.103612048494;7001.124859392576;7003.5797852129035;7043.964780176067;7062.15254169503;7069.893951590628;5362.865928351823;6577.50668699859;6638.53530252897;6728.629925354153;6736;4875.219964805614;4879.121758083579;4927.728974906415;4968.105740966661;4974.850754477371;4521.846929033076;4734.431868175841;4818.397700287464;4836.830710925242;5043.117454445531 02:46:36 INFO - PROCESS | 1695 | |4;prototype.html;252.73967813152453;294.51258167732396;302.9712177343152;304.53102222577235;313.7897608602237;5406.59450541209;5470.781168753245;5505.944940550593;5649.762677991506;5661.905095114803;5282.728426776484;5479.835604931848;5513.2005859150395;5549;5552.583556233289;3465.3762322781954;4185.665146788265;4254.595813397737;4259.893502662443;4281.336392859112;3409.778364406318;3428.520760776924;3854.3191252353936;4284.378765079062;4292.463442069741;3105.798123122001;3182.347618738165;3716.6469185427477;4056.249593825152;4177.624013838754;3289.4572395554706;4182.184474027566;4195.160967806436;4202.600752928467;4284.614384705392;3026.895183258117;3083.97999310452;3663.285659296438;3664.2305115925687;3669.94495082574;4505.977470112629;4513.458384993789;5067.391912970467;5387.353517577876;5413.83758487246;3603.4054381027363;3995.5604883462797;4719.646026548023;4744.810207591692;4758.547937945889;4850.708957462563;5482.629342664334;5831.650100993911;5867.559933004999;5902.32123305821;3324.335132973391;3810.923781524354;3880.5149356330458;4018.557958624549;4031.1937612477327;5033.748312584393;5948.553858460589;6091.482224010964;6108.816735497942;6149.200603921551;3389.4407422775457;3714.925701485955;4025.618480476414;4035.858744943913;4049.825550590355;3233.66046565112;3774.339490589191;4139.254934111888;4152.709310348247;4172.958270417318;4109.876703698894;4329.567043295709;4731.455882573479;4768.785404656799;4771.498992605796;5152.716600587004;5317.494837990384;5438.592105592096;5897.469227769521;5913.01392796635;1149.781541507111;1233.9506419743109;1248.862625111237;1259.93535462534;1271.7790920716031;710.5097482736895;858.7805316450655;879.8020445399734;883;894.7226359828474;4556.633010096958;4638.79125439356;5222.112240919053;5336.866578335495;5343.70609616467;5154.768035438415;5205.687658740488;5418.8595504776185;5770.653760774367;5778.913316300259;4563.201439748097;4694.6713730039255;4801.423829140455;5226.398964119099;5246.711430871339;2918.1391489510647;3022.9848850756043;3027.1826606816035;3064.322812624859;3068.3890957247177;4988.256458947564;5650.835532102036;5740.282464691913;5889.4699477046415;6055.576109672369;5531.806386776524;5561.4716601922755;5865.765369385177;5901.878643057806;5965.642061476326;5777.45114214149;6005.579609427386;6016.067509536029;6140.723667434976;6144.969275153685;5065.670731402447;5182.300389447376;5772.220750198838;5840.883182336244;5897.823065308047;4992.001599680151;5052.823151189691;5296.523312901857;5655.406182350958;5819.5635327351465;4863.48933362006;6025.397460254115;6321.491572177486;6423.828817801715;6432.581882177644;3326.7172290355593;3776.5845756966164;4009.919801603893;4030.7984600770433;4057.553669096456;5283.656562323436;5500.257465242301;6037.184980027816;6047.213862197887;6080.604760690541;5306.230596563554;5466.180072989084;5724.711939813508;5730.965507244868;5907.3501914790195;5210.322658054429;5237.685738855681;5372.743447752032;5590.630295577609;5987.228046330958;4634.698744581591;5474.671519708831;5615.971920140373;5908.261467316585;5939.613925094855;4931.48219436954;5923.348431672426;5944.078667806497;5952.73212705438;5980.365216956893;5214.634975551675;5350.154196802097;5404.297441332602;5735.447725762163;5745.443277741365 02:46:36 INFO - PROCESS | 1695 | |5;yui.html;675.6587923098831;724.0551080839502;758.4463341760514;765.6171914042978;792.3621484704812;14545.636359091015;15201.011934224267;15269.923650381746;15311.69376612467;15315.693686126271;12614.81077783834;12666.796654317848;12674.873251267485;12682.048846336516;12688.429020694068;808.2806302390877;815.3355015662246;827.1810907201857;827.8716798896181;832.4048305461624;802.7591722483261;814.8044469327349;815.2784785464877;815.6329651656748;820.2412768189431;815.6900377856435;852.735651947895;856.5032281276875;856.8286342731448;857.4340934982913;674.8043067510416;812.8699408094706;823.069930977994;823.9176082391713;826.1614461321733;630.0517720830167;635.4344633276341;635.8728254349148;639.1339734659563;641.0896526931768;12422.689432764162;12543.24740515572;12593.244405335709;12645.810312845222;12683.492660293578;11087.390193539351;11352.716182095432;11471.035061767918;11472.4837382318;11574.594889178923;9967.80064398708;10097.091261786474;10232.641857535025;10374.118199953013;10558.947205264001;3522.9119272018147;3524.22467057247;3574.195805943668;3642.326169658595;3704.407294832814;95239.52380238124;97938;98151.52772708415;98218;98243.01756982411;8625.64291511283;8727.083656216133;8743;8836.77908052291;8871.778205544913;18367.72448413275;18378.448646540626;18401.81598184001;18439.631207376045;18442.446726598224;7354.632268386559;7486.887696684554;7582.734604288823;7785.493942893694;7787.922120778833;3120.8595613197463;3652.7625704329134;4117.382392641128;4221.430106935586;4242.193983143193;2801.8458984756035;3442.8278586070596;3457.152997515625;3458.4812278157974;3487.616362200156;607.7842365960109;631.3465563142217;631.5105793010454;649.4836604899023;655.3086493749045;570.1988705868262;611.8531552427474;622.8847663182326;654.482958462809;657.9539432239793;8156.796080097926;8322.916770832335;8354.45696029756;8645.870311945326;8813.647454101892;592.2123575644382;607.5959486941223;646.5183438338374;656.9277379488252;662.4799532367165;3926.2736393767245;4237.894052648647;4288.335308027261;4458.7324760514475;4517.81928722854;115977;117822;118211.2268315976;118630;121264;59571.70214148902;62895.42761430909;64823.351766481734;67625.3237467629;96581.0341896572;79797.60101199457;85916.57041714876;86428;86829.82925426788;86912;9252.306077044383;9294.25645948336;9540;9578.185854202471;9668.274879384215;48824.51175488342;49534.75232623814;50173;51166;51522.74238628783;50913.745431272604;56189.71905140448;71515;77566;78488.60755696414;11514.136439766884;12333.876661233273;12345.444454999726;12359.32023738703;12567.434465448894;11525.481353338952;11575.594854180063;12140.817887731515;13714.51999180024;13869.167849929037;11489.368084755419;11646.068314534987;12833;13082.869171308546;13098.934505327412;12858.935705321413;12945.223286602833;13090.476380944656;13345.86654133485;13787.448502060206;12915.35423228816;13067.542636007694;13124.343782811013;13378.799318010042;13400.865991339962;10677.305975111592;10727.892721073002;11083.889161108285;11146.777064458503;11291.04026157753;10711.678649640524;11228.382438965935;11318.09455243595;11343.489542970421;11382.260153090023 02:46:36 INFO - PROCESS | 1695 | __end_tp_report 02:46:36 INFO - PROCESS | 1695 | __start_cc_report 02:46:36 INFO - PROCESS | 1695 | _x_x_mozilla_cycle_collect,373 02:46:36 INFO - PROCESS | 1695 | __end_cc_report 02:46:36 INFO - PROCESS | 1695 | __startTimestamp1480848396337__endTimestamp 02:46:36 INFO - PROCESS | 1695 | 02:46:36 INFO - PROCESS | 1695 | ------- Summary: start ------- 02:46:36 INFO - PROCESS | 1695 | Number of tests: 6 02:46:36 INFO - PROCESS | 1695 | 02:46:36 INFO - PROCESS | 1695 | [#0] dojo.html Cycles:165 Average:5653.62 Median:5854.36 stddev:660.67 (11.3%) stddev-sans-first:628.83 02:46:36 INFO - PROCESS | 1695 | Values: 2991.6 3316.2 3334.4 3345.1 3392.6 4711.9 5611.2 5629.5 5634.5 5643.0 5053.0 5380.1 5704.8 5714.0 5753.4 5182.7 5184.5 5240.3 5280.3 5348.9 5180.4 5195.0 5227.6 5250.8 5251.3 5082.1 5139.6 5171.4 5222.9 5227.6 4990.4 5073.9 5121.3 5148.3 5197.7 5086.2 5103.6 5104.1 5209.2 5216.1 5560.0 5607.0 5674.1 5677.5 5716.2 6137.3 6170.3 6203.5 6223.3 6229.8 6228.6 6243.7 6246.8 6271.1 6282.9 5611.1 5649.5 5676.4 5750.1 5777.6 6157.0 6212.8 6225.4 6231.3 6238.4 5536.2 6106.6 6113.1 6131.5 6139.2 5022.4 6121.4 6216.1 6227.0 6236.2 5772.9 5789.2 5803.7 5854.4 6139.5 5940.0 5949.9 5950.9 5952.3 6134.3 3707.1 4843.8 4898.6 4905.4 4916.7 4329.0 4358.2 4398.4 4417.4 4448.5 5670.4 5699.1 5752.4 5867.9 5888.0 6158.4 6168.8 6217.2 6250.2 6310.6 5806.5 5851.0 5852.4 5871.2 5907.2 5244.8 5268.1 5368.6 5370.1 5398.3 6191.0 6203.0 6208.8 6233.8 6245.3 6259.5 6259.6 6263.0 6265.6 6296.6 6283.2 6287.9 6292.9 6299.1 6306.5 5921.1 5951.7 5953.0 5960.8 6105.6 6116.0 6117.9 6122.2 6122.9 6128.5 6051.7 6073.4 6111.7 6117.5 6142.8 5920.8 6020.6 6083.1 6090.4 6120.5 5753.5 5819.2 5834.9 5877.6 5907.8 5903.9 5969.7 6044.8 6049.2 6145.3 4805.3 5008.5 5845.7 5903.0 5916.1 02:46:36 INFO - PROCESS | 1695 | 02:46:36 INFO - PROCESS | 1695 | [#1] ext.html Cycles:180 Average:71080.33 Median:39312.40 stddev:156713.88 (398.6%) stddev-sans-first:157063.85 02:46:36 INFO - PROCESS | 1695 | Values: 475.4 481.8 499.4 516.0 556.1 36086.8 39065.8 39162.0 39217.0 39359.8 39679.8 40039.8 40125.6 40159.6 40221.0 7801.2 7865.2 8818.6 9464.7 9496.1 9179.9 9235.9 9307.9 9426.1 9426.6 9424.6 9436.8 9438.5 9441.7 9476.0 8369.6 9147.6 9383.5 9435.2 9461.9 9389.2 9390.8 9481.3 9485.8 9492.3 38820.0 39216.6 39240.0 39267.0 39357.8 3313.6 3326.8 3401.4 3876.0 3883.9 28455.6 29910.4 30902.4 30908.2 31201.7 3208.2 3212.8 3227.4 3232.9 3298.1 507329.0 509948.0 512391.0 512922.0 514450.0 60123.7 60154.7 60391.7 60429.7 60493.0 63896.0 64706.7 64731.7 64973.7 65027.3 15874.4 16092.8 16102.4 16120.4 16135.9 12189.1 12635.7 12643.9 12658.6 12670.0 17895.4 18675.7 18920.9 19039.5 19180.2 2717.8 3288.1 6441.7 7571.9 7797.6 837.6 983.7 985.3 994.9 1011.1 29263.3 30026.8 30166.8 30574.8 30881.4 868.4 963.5 975.3 997.4 1176.1 12584.0 15373.9 15566.9 15674.0 15687.9 509353.0 638149.0 641196.0 642468.0 644899.0 57534.1 57783.1 57850.0 57895.1 58122.0 1215.2 1364.2 1368.0 1393.2 1393.9 28413.0 29027.3 29291.4 29538.7 29897.7 51214.7 51553.2 51565.5 51811.0 51973.5 619425.0 620037.0 622537.0 623054.0 624791.0 26273.9 26303.3 26650.0 26673.5 26690.9 44696.8 45351.3 45392.0 46342.8 46785.0 35299.8 37318.8 37996.0 39194.8 40863.8 29921.6 31467.0 37426.6 38260.3 39157.8 37776.9 37840.1 38230.8 38246.8 38272.4 32975.3 33816.2 34446.3 34466.8 34675.1 25778.6 25898.6 25981.6 26074.7 26401.7 02:46:36 INFO - PROCESS | 1695 | 02:46:36 INFO - PROCESS | 1695 | [#2] jquery.html Cycles:180 Average:33124.84 Median:2425.12 stddev:92252.28 (3804.0%) stddev-sans-first:92478.29 02:46:36 INFO - PROCESS | 1695 | Values: 371.7 375.9 385.5 389.1 403.4 15684.7 18334.4 18969.4 19245.8 19423.8 19374.8 19587.3 19882.3 19903.7 20058.1 5628.9 5721.5 5990.7 6008.3 6019.0 4898.5 5000.4 5069.6 5509.7 5911.3 5597.4 5812.4 5863.1 5865.1 5866.6 5823.9 5976.6 5984.9 6000.4 6001.4 4781.8 5600.0 5804.2 5810.4 5859.5 12799.2 12954.5 13009.6 13016.4 22322.2 2206.5 2245.0 2254.2 2301.5 2867.8 9672.6 9765.3 9851.7 10166.8 10352.3 1834.1 1899.4 1932.4 1932.4 1942.7 87674.7 113781.4 114848.0 115405.0 115792.0 11268.7 11395.7 11537.3 11567.9 11648.5 14340.7 14343.1 14365.3 14374.1 14394.4 8076.5 8364.9 8430.9 8489.2 8508.7 6880.2 7355.7 8033.9 8347.4 8376.4 4446.9 4497.6 4500.5 4502.7 4576.2 1892.4 1937.9 1951.5 1953.9 1958.3 813.2 879.8 886.3 898.8 993.5 19365.4 20750.7 20766.7 20795.5 21118.5 879.2 904.7 930.8 938.1 992.7 8734.5 10408.5 11246.0 11247.7 11296.7 505110.0 507282.0 510500.0 579566.0 633570.0 122587.4 123430.0 125518.0 132864.0 143652.0 927.6 952.1 966.1 967.2 993.5 16080.0 17284.4 19036.2 19057.7 19116.0 53573.0 53710.7 54580.2 55503.7 56131.0 79475.0 80572.6 83457.2 83623.2 83969.0 7940.4 8159.8 8230.8 8264.7 8380.7 10611.5 13015.8 13018.3 13052.0 13121.8 9656.4 9926.5 10485.7 10558.3 11535.4 9684.3 10901.5 11061.4 11176.7 11473.1 11554.9 11572.4 11615.5 11856.4 11918.2 8138.3 8142.7 8145.5 8360.1 8383.2 8175.7 11119.5 11264.7 11526.5 11598.1 02:46:36 INFO - PROCESS | 1695 | 02:46:36 INFO - PROCESS | 1695 | [#3] mootools.html Cycles:180 Average:5361.22 Median:5182.60 stddev:2958.26 (57.1%) stddev-sans-first:2950.53 02:46:36 INFO - PROCESS | 1695 | Values: 1263.5 1423.0 1558.9 1710.3 1759.7 6572.6 7402.3 7944.1 8380.9 8403.5 7973.3 8274.4 8276.6 8277.7 8291.8 2106.6 2271.3 2313.8 2315.3 2315.3 2257.7 2282.1 2352.0 2352.5 2356.6 1929.6 2192.7 2225.0 2277.0 2305.4 2257.4 2262.5 2279.9 2281.5 2283.0 2269.7 2329.8 2330.3 2334.7 2338.7 8311.3 8362.2 8372.7 8381.7 8393.3 4990.6 5153.1 5160.6 5162.6 5171.6 2833.7 3266.5 3335.5 3575.1 3596.8 1038.9 1052.9 1069.6 1087.4 1088.3 10663.3 10800.3 10886.5 10892.4 10916.6 3883.4 4582.4 4655.1 4665.7 4683.2 9274.7 9303.0 9344.0 9345.5 9346.4 5118.2 5125.8 5137.8 5148.6 5155.6 4192.4 4412.2 4670.6 4735.6 4738.4 3249.1 3340.9 3802.7 3821.6 3906.4 1066.3 1186.7 1273.9 1281.3 1304.3 713.9 785.7 829.4 877.0 899.2 5521.1 5654.1 5712.7 5799.0 5808.4 771.3 852.0 867.9 869.2 876.9 3375.6 3794.7 3902.8 3922.5 3927.2 9426.2 9916.7 9920.1 9927.0 9941.0 9813.7 9866.9 9890.7 9893.1 9900.5 7630.9 7767.4 7770.4 7784.8 7792.5 4937.8 5193.6 5379.6 5490.6 5690.1 9163.4 9246.3 9288.7 9394.2 9408.9 9416.5 9432.0 9530.3 9535.9 9542.3 6249.3 6425.4 6458.2 6721.2 6830.0 7739.9 8011.4 8012.1 8020.4 8061.0 5644.2 6258.4 7147.1 7164.1 7171.1 7001.1 7003.6 7044.0 7062.2 7069.9 5362.9 6577.5 6638.5 6728.6 6736.0 4875.2 4879.1 4927.7 4968.1 4974.9 4521.8 4734.4 4818.4 4836.8 5043.1 02:46:36 INFO - PROCESS | 1695 | 02:46:36 INFO - PROCESS | 1695 | [#4] prototype.html Cycles:180 Average:4587.16 Median:5208.01 stddev:1460.21 (28.0%) stddev-sans-first:1427.61 02:46:36 INFO - PROCESS | 1695 | Values: 252.7 294.5 303.0 304.5 313.8 5406.6 5470.8 5505.9 5649.8 5661.9 5282.7 5479.8 5513.2 5549.0 5552.6 3465.4 4185.7 4254.6 4259.9 4281.3 3409.8 3428.5 3854.3 4284.4 4292.5 3105.8 3182.3 3716.6 4056.2 4177.6 3289.5 4182.2 4195.2 4202.6 4284.6 3026.9 3084.0 3663.3 3664.2 3669.9 4506.0 4513.5 5067.4 5387.4 5413.8 3603.4 3995.6 4719.6 4744.8 4758.5 4850.7 5482.6 5831.7 5867.6 5902.3 3324.3 3810.9 3880.5 4018.6 4031.2 5033.7 5948.6 6091.5 6108.8 6149.2 3389.4 3714.9 4025.6 4035.9 4049.8 3233.7 3774.3 4139.3 4152.7 4173.0 4109.9 4329.6 4731.5 4768.8 4771.5 5152.7 5317.5 5438.6 5897.5 5913.0 1149.8 1234.0 1248.9 1259.9 1271.8 710.5 858.8 879.8 883.0 894.7 4556.6 4638.8 5222.1 5336.9 5343.7 5154.8 5205.7 5418.9 5770.7 5778.9 4563.2 4694.7 4801.4 5226.4 5246.7 2918.1 3023.0 3027.2 3064.3 3068.4 4988.3 5650.8 5740.3 5889.5 6055.6 5531.8 5561.5 5865.8 5901.9 5965.6 5777.5 6005.6 6016.1 6140.7 6145.0 5065.7 5182.3 5772.2 5840.9 5897.8 4992.0 5052.8 5296.5 5655.4 5819.6 4863.5 6025.4 6321.5 6423.8 6432.6 3326.7 3776.6 4009.9 4030.8 4057.6 5283.7 5500.3 6037.2 6047.2 6080.6 5306.2 5466.2 5724.7 5731.0 5907.4 5210.3 5237.7 5372.7 5590.6 5987.2 4634.7 5474.7 5616.0 5908.3 5939.6 4931.5 5923.3 5944.1 5952.7 5980.4 5214.6 5350.2 5404.3 5735.4 5745.4 02:46:36 INFO - PROCESS | 1695 | 02:46:36 INFO - PROCESS | 1695 | [#5] yui.html Cycles:180 Average:19741.85 Median:49853.88 stddev:29691.22 (59.6%) stddev-sans-first:29740.00 02:46:36 INFO - PROCESS | 1695 | Values: 675.7 724.1 758.4 765.6 792.4 14545.6 15201.0 15269.9 15311.7 15315.7 12614.8 12666.8 12674.9 12682.0 12688.4 808.3 815.3 827.2 827.9 832.4 802.8 814.8 815.3 815.6 820.2 815.7 852.7 856.5 856.8 857.4 674.8 812.9 823.1 823.9 826.2 630.1 635.4 635.9 639.1 641.1 12422.7 12543.2 12593.2 12645.8 12683.5 11087.4 11352.7 11471.0 11472.5 11574.6 9967.8 10097.1 10232.6 10374.1 10558.9 3522.9 3524.2 3574.2 3642.3 3704.4 95239.5 97938.0 98151.5 98218.0 98243.0 8625.6 8727.1 8743.0 8836.8 8871.8 18367.7 18378.4 18401.8 18439.6 18442.4 7354.6 7486.9 7582.7 7785.5 7787.9 3120.9 3652.8 4117.4 4221.4 4242.2 2801.8 3442.8 3457.2 3458.5 3487.6 607.8 631.3 631.5 649.5 655.3 570.2 611.9 622.9 654.5 658.0 8156.8 8322.9 8354.5 8645.9 8813.6 592.2 607.6 646.5 656.9 662.5 3926.3 4237.9 4288.3 4458.7 4517.8 115977.0 117822.0 118211.2 118630.0 121264.0 59571.7 62895.4 64823.4 67625.3 96581.0 79797.6 85916.6 86428.0 86829.8 86912.0 9252.3 9294.3 9540.0 9578.2 9668.3 48824.5 49534.8 50173.0 51166.0 51522.7 50913.7 56189.7 71515.0 77566.0 78488.6 11514.1 12333.9 12345.4 12359.3 12567.4 11525.5 11575.6 12140.8 13714.5 13869.2 11489.4 11646.1 12833.0 13082.9 13098.9 12858.9 12945.2 13090.5 13345.9 13787.4 12915.4 13067.5 13124.3 13378.8 13400.9 10677.3 10727.9 11083.9 11146.8 11291.0 10711.7 11228.4 11318.1 11343.5 11382.3 02:46:36 INFO - PROCESS | 1695 | -------- Summary: end -------- 02:46:36 INFO - PROCESS | 1695 | 02:46:37 INFO - TEST-INFO | 1695: exit 0 02:46:37 INFO - TEST-OK | dromaeo_css | took 1117525ms 02:46:37 INFO - TEST-START | kraken 02:46:37 INFO - operating with platform_type : mac_ 02:46:37 INFO - Initialising browser for kraken test... 02:46:37 INFO - TEST-INFO | started process 1703 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpPhE5Ls/profile http://localhost:49246/getInfo.html) 02:46:40 INFO - PROCESS | 1703 | 2016-12-04 02:46:40.173 plugin-container[1705:20225] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 02:46:40 INFO - PROCESS | 1703 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:46:40 INFO - PROCESS | 1703 | 2016-12-04 02:46:40.174 plugin-container[1705:20225] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 02:46:40 INFO - PROCESS | 1703 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:46:40 INFO - PROCESS | 1703 | 2016-12-04 02:46:40.174 plugin-container[1705:20225] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:46:40 INFO - PROCESS | 1703 | __metrics Screen width/height:1600/1200 02:46:40 INFO - PROCESS | 1703 | colorDepth:24 02:46:40 INFO - PROCESS | 1703 | Browser inner width/height: 1024/689 02:46:40 INFO - PROCESS | 1703 | __metrics 02:46:40 ERROR - PROCESS | 1703 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:46:41 INFO - TEST-INFO | 1703: exit 0 02:46:41 INFO - Browser initialized. 02:46:41 INFO - Running cycle 1/1 for kraken test... 02:46:41 INFO - TEST-INFO | started process 1706 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpPhE5Ls/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:46:43 INFO - PROCESS | 1706 | 2016-12-04 02:46:43.675 plugin-container[1707:20354] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 02:46:43 INFO - PROCESS | 1706 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:46:43 INFO - PROCESS | 1706 | 2016-12-04 02:46:43.676 plugin-container[1707:20354] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 02:46:43 INFO - PROCESS | 1706 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:46:43 INFO - PROCESS | 1706 | 2016-12-04 02:46:43.676 plugin-container[1707:20354] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:46:53 INFO - PROCESS | 1706 | RSS: Main: 229617664 02:46:53 INFO - PROCESS | 1706 | 02:46:56 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 02:46:57 INFO - PROCESS | 1706 | RSS: Main: 241565696 02:46:57 INFO - PROCESS | 1706 | 02:47:00 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 02:47:00 INFO - PROCESS | 1706 | RSS: Main: 249307136 02:47:00 INFO - PROCESS | 1706 | 02:47:03 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 02:47:04 INFO - PROCESS | 1706 | RSS: Main: 252911616 02:47:04 INFO - PROCESS | 1706 | 02:47:06 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 02:47:07 INFO - PROCESS | 1706 | RSS: Main: 253247488 02:47:07 INFO - PROCESS | 1706 | 02:47:08 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:47:09 INFO - PROCESS | 1706 | RSS: Main: 253329408 02:47:09 INFO - PROCESS | 1706 | 02:47:12 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 02:47:12 INFO - PROCESS | 1706 | RSS: Main: 259284992 02:47:12 INFO - PROCESS | 1706 | 02:47:15 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 02:47:16 INFO - PROCESS | 1706 | RSS: Main: 258920448 02:47:16 INFO - PROCESS | 1706 | 02:47:19 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 02:47:20 INFO - PROCESS | 1706 | RSS: Main: 258351104 02:47:20 INFO - PROCESS | 1706 | 02:47:21 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:47:22 INFO - PROCESS | 1706 | RSS: Main: 257429504 02:47:22 INFO - PROCESS | 1706 | 02:47:24 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:47:24 INFO - PROCESS | 1706 | RSS: Main: 257249280 02:47:24 INFO - PROCESS | 1706 | 02:47:27 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:47:27 INFO - PROCESS | 1706 | RSS: Main: 257273856 02:47:27 INFO - PROCESS | 1706 | 02:47:30 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:47:31 INFO - PROCESS | 1706 | RSS: Main: 257282048 02:47:31 INFO - PROCESS | 1706 | 02:47:34 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:47:35 INFO - PROCESS | 1706 | RSS: Main: 257646592 02:47:35 INFO - PROCESS | 1706 | 02:47:36 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 02:47:37 INFO - PROCESS | 1706 | RSS: Main: 259293184 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | __start_tp_report 02:47:37 INFO - PROCESS | 1706 | _x_x_mozilla_page_load 02:47:37 INFO - PROCESS | 1706 | _x_x_mozilla_page_load_details 02:47:37 INFO - PROCESS | 1706 | |i|pagename|runs| 02:47:37 INFO - PROCESS | 1706 | |0;ai-astar;135;155;172;153;158;104;171;172;169;120 02:47:37 INFO - PROCESS | 1706 | |1;audio-beat-detection;147;117;119;147;120;150;157;131;145;119 02:47:37 INFO - PROCESS | 1706 | |2;audio-dft;201;199;197;198;196;199;194;198;199;198 02:47:37 INFO - PROCESS | 1706 | |3;audio-fft;82;57;58;86;65;59;58;59;78;58 02:47:37 INFO - PROCESS | 1706 | |4;audio-oscillator;87;70;92;73;69;71;112;68;69;85 02:47:37 INFO - PROCESS | 1706 | |5;imaging-gaussian-blur;89;93;88;86;90;93;102;87;89;87 02:47:37 INFO - PROCESS | 1706 | |6;imaging-darkroom;95;97;95;95;108;134;95;95;95;96 02:47:37 INFO - PROCESS | 1706 | |7;imaging-desaturate;97;136;85;87;88;135;91;143;135;77 02:47:37 INFO - PROCESS | 1706 | |8;json-parse-financial;54;56;59;63;92;60;58;58;59;60 02:47:37 INFO - PROCESS | 1706 | |9;json-stringify-tinderbox;66;73;75;79;79;72;71;72;74;72 02:47:37 INFO - PROCESS | 1706 | |10;stanford-crypto-aes;85;87;86;88;87;87;84;91;88;87 02:47:37 INFO - PROCESS | 1706 | |11;stanford-crypto-ccm;148;151;148;164;167;163;151;178;225;184 02:47:37 INFO - PROCESS | 1706 | |12;stanford-crypto-pbkdf2;170;174;170;170;172;171;171;170;171;174 02:47:37 INFO - PROCESS | 1706 | |13;stanford-crypto-sha256-iterative;69;73;72;62;91;65;61;62;62;81 02:47:37 INFO - PROCESS | 1706 | __end_tp_report 02:47:37 INFO - PROCESS | 1706 | __start_cc_report 02:47:37 INFO - PROCESS | 1706 | _x_x_mozilla_cycle_collect,973 02:47:37 INFO - PROCESS | 1706 | __end_cc_report 02:47:37 INFO - PROCESS | 1706 | __startTimestamp1480848457079__endTimestamp 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | ------- Summary: start ------- 02:47:37 INFO - PROCESS | 1706 | Number of tests: 14 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#0] ai-astar Cycles:10 Average:150.90 Median:163.50 stddev:23.81 (14.6%) stddev-sans-first:24.55 02:47:37 INFO - PROCESS | 1706 | Values: 135.0 155.0 172.0 153.0 158.0 104.0 171.0 172.0 169.0 120.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:146.00 stddev:15.54 (10.6%) stddev-sans-first:15.89 02:47:37 INFO - PROCESS | 1706 | Values: 147.0 117.0 119.0 147.0 120.0 150.0 157.0 131.0 145.0 119.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#2] audio-dft Cycles:10 Average:197.90 Median:198.50 stddev:1.91 (1.0%) stddev-sans-first:1.67 02:47:37 INFO - PROCESS | 1706 | Values: 201.0 199.0 197.0 198.0 196.0 199.0 194.0 198.0 199.0 198.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#3] audio-fft Cycles:10 Average:66.00 Median:62.00 stddev:11.41 (18.4%) stddev-sans-first:10.53 02:47:37 INFO - PROCESS | 1706 | Values: 82.0 57.0 58.0 86.0 65.0 59.0 58.0 59.0 78.0 58.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:72.00 stddev:14.36 (19.9%) stddev-sans-first:14.98 02:47:37 INFO - PROCESS | 1706 | Values: 87.0 70.0 92.0 73.0 69.0 71.0 112.0 68.0 69.0 85.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:89.00 stddev:4.72 (5.3%) stddev-sans-first:4.98 02:47:37 INFO - PROCESS | 1706 | Values: 89.0 93.0 88.0 86.0 90.0 93.0 102.0 87.0 89.0 87.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:95.00 stddev:12.44 (13.1%) stddev-sans-first:13.03 02:47:37 INFO - PROCESS | 1706 | Values: 95.0 97.0 95.0 95.0 108.0 134.0 95.0 95.0 95.0 96.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#7] imaging-desaturate Cycles:10 Average:107.40 Median:86.00 stddev:26.26 (30.5%) stddev-sans-first:27.58 02:47:37 INFO - PROCESS | 1706 | Values: 97.0 136.0 85.0 87.0 88.0 135.0 91.0 143.0 135.0 77.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:59.50 stddev:10.85 (18.2%) stddev-sans-first:11.12 02:47:37 INFO - PROCESS | 1706 | Values: 54.0 56.0 59.0 63.0 92.0 60.0 58.0 58.0 59.0 60.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:73.50 stddev:3.83 (5.2%) stddev-sans-first:3.02 02:47:37 INFO - PROCESS | 1706 | Values: 66.0 73.0 75.0 79.0 79.0 72.0 71.0 72.0 74.0 72.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.00 stddev:1.89 (2.2%) stddev-sans-first:1.86 02:47:37 INFO - PROCESS | 1706 | Values: 85.0 87.0 86.0 88.0 87.0 87.0 84.0 91.0 88.0 87.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#11] stanford-crypto-ccm Cycles:10 Average:167.90 Median:165.50 stddev:23.63 (14.3%) stddev-sans-first:23.94 02:47:37 INFO - PROCESS | 1706 | Values: 148.0 151.0 148.0 164.0 167.0 163.0 151.0 178.0 225.0 184.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:171.00 stddev:1.57 (0.9%) stddev-sans-first:1.59 02:47:37 INFO - PROCESS | 1706 | Values: 170.0 174.0 170.0 170.0 172.0 171.0 171.0 170.0 171.0 174.0 02:47:37 INFO - PROCESS | 1706 | 02:47:37 INFO - PROCESS | 1706 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:70.50 stddev:9.85 (14.0%) stddev-sans-first:10.45 02:47:37 INFO - PROCESS | 1706 | Values: 69.0 73.0 72.0 62.0 91.0 65.0 61.0 62.0 62.0 81.0 02:47:37 INFO - PROCESS | 1706 | -------- Summary: end -------- 02:47:37 INFO - PROCESS | 1706 | 02:47:38 INFO - TEST-INFO | 1706: exit 0 02:47:38 INFO - TEST-OK | kraken | took 61036ms 02:47:38 INFO - SUITE-END | took 1178s 02:47:38 INFO - Completed test suite (00:19:38) 02:47:38 INFO - javascript benchmark 02:47:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7094.494648695184, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2991.5961345218393, 3316.2041110133546, 3334.4498157803964, 3345.096823857557, 3392.6098498672654, 4711.90576188476, 5611.242482264893, 5629.493345598895, 5634.464725851048, 5642.971785141069, 5052.9747351263195, 5380.139177731564, 5704.828855134342, 5713.971430142843, 5753.367129615739, 5182.6631268967585, 5184.507471790174, 5240.318758561381, 5280.313559237294, 5348.91976620351, 5180.4042535108565, 5194.974025129869, 5227.581793456515, 5250.842474725745, 5251.291075704769, 5082.1106306396605, 5139.58883289336, 5171.4311425743135, 5222.895542089175, 5227.634065615371, 4990.4011518617635, 5073.873153171164, 5121.28302037715, 5148.2535032420465, 5197.714125723083, 5086.21163719624, 5103.617228707824, 5104.05574968632, 5209.166533354645, 5216.113260745682, 5559.972200138974, 5607.018771714934, 5674.120511320711, 5677.489025987681, 5716.228309178249, 6137.254412462224, 6170.321264660884, 6203.534734894901, 6223.253209614875, 6229.813105606839, 6228.564000519966, 6243.65659888706, 6246.843828904268, 6271.122042913996, 6282.863826299034, 5611.130274807411, 5649.491545760901, 5676.375598684142, 5750.07998720207, 5777.566682498829, 6157.0, 6212.81361559154, 6225.408586184305, 6231.345708700612, 6238.376162383816, 5536.197251398525, 6106.633601983806, 6113.081058500009, 6131.478824299896, 6139.171211886338, 5022.397312322545, 6121.387861213843, 6216.098665693539, 6227.0, 6236.220472440945, 5772.942270577324, 5789.218455508537, 5803.65178089316, 5854.356020837705, 6139.47814435769, 5940.019896716994, 5949.881002379928, 5950.9107363389585, 5952.255968003999, 6134.294556126102, 3707.1288247261873, 4843.75781210938, 4898.632602554822, 4905.386826646669, 4916.655834091579, 4328.978355108205, 4358.150160718693, 4398.362237475548, 4417.447819022622, 4448.532904045029, 5670.376258611549, 5699.116636921284, 5752.36723960364, 5867.941320586825, 5887.970560147171, 6158.414950579688, 6168.784092556829, 6217.222847144107, 6250.249970003629, 6310.5898116622275, 5806.477417032487, 5850.970745146247, 5852.356240813507, 5871.156296055771, 5907.232059832194, 5244.816431424882, 5268.078086334953, 5368.624196306299, 5370.113931201387, 5398.325209348832, 6191.0, 6203.0, 6208.81373558794, 6233.844153896189, 6245.28179259391, 6259.499240060693, 6259.593126446766, 6262.968685156545, 6265.5927364722975, 6296.5592408530965, 6283.246010478589, 6287.874242515032, 6292.867283888944, 6299.118123462627, 6306.495480361468, 5921.082232254007, 5951.732172052355, 5953.0, 5960.76156953717, 6105.572609917263, 6116.031445915052, 6117.949202904554, 6122.204113465221, 6122.9471041527795, 6128.503123582109, 6051.667158306335, 6073.423024812636, 6111.652512877479, 6117.522930757332, 6142.815715528542, 5920.822375328747, 6020.608660437058, 6083.148359229623, 6090.390960903874, 6120.54095942815, 5753.453421924911, 5819.214406055128, 5834.912476312944, 5877.617954832922, 5907.763689452374, 5903.940960590511, 5969.73136208863, 6044.848878778066, 6049.243844519435, 6145.340757995227, 4805.303231031551, 5008.549230569266, 5845.736941837543, 5903.0, 5916.142159386951], "value": 5611.178304187436, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.4342332624177, 481.8289507224934, 499.36829910163647, 515.9835124804137, 556.0796881161679, 36086.819565902166, 39065.80467097664, 39162.020949476275, 39217.0, 39359.80320098395, 39679.801600992025, 40039.79980100103, 40125.598744012554, 40159.59840401595, 40220.99447513813, 7801.219878012182, 7865.2134786521465, 8818.559072046372, 9464.716058518256, 9496.145346918776, 9179.908200917973, 9235.861462078074, 9307.86038209424, 9426.057394260588, 9426.575804088798, 9424.575894084783, 9436.811263774754, 9438.528073596328, 9441.716748497556, 9476.00501947296, 8369.62336694844, 9147.588358523883, 9383.483908385037, 9435.19800816928, 9461.911880133805, 9389.201917836992, 9390.812183756287, 9481.33630645855, 9485.762855928657, 9492.288078394147, 38820.0, 39216.607833921866, 39240.0, 39267.0, 39357.80321098405, 3313.5526703894907, 3326.750493712957, 3401.42175830107, 3875.961240387588, 3883.9029024274337, 28455.57316640252, 29910.401791964257, 30902.38195236105, 30908.227294317596, 31201.687983120333, 3208.246062175387, 3212.8072315661134, 3227.402930457873, 3232.8545215465124, 3298.09302441828, 507329.0, 509948.0, 512391.0, 512922.0, 514450.0, 60123.69938150281, 60154.69922650359, 60391.69804150951, 60429.697851510464, 60493.0, 63896.041559376645, 64706.67646661736, 64731.70536589242, 64973.67513162404, 65027.349726503075, 15874.365025399085, 16092.758608620647, 16102.355905763872, 16120.355185792672, 16135.919320403558, 12189.085818563643, 12635.684107897376, 12643.93678031604, 12658.556950506689, 12670.049746268882, 17895.386157508467, 18675.71986420205, 18920.905395472935, 19039.52401189981, 19180.232790688216, 2717.7961652876115, 3288.079337785424, 6441.710123044474, 7571.939928409917, 7797.571133587594, 837.5560952695081, 983.695054533097, 985.3348989432104, 994.9104580587782, 1011.1041796631316, 29263.268418289714, 30026.84986575097, 30166.793328267064, 30574.847125764674, 30881.382372352426, 868.3617541107282, 963.5278713430368, 975.3124047546463, 997.3567049253336, 1176.129664048598, 12584.0, 15373.9231303845, 15566.9221653891, 15674.0, 15687.921560392126, 509353.0, 638149.0, 641196.0, 642468.0, 644899.0, 57534.13698794522, 57783.13325300124, 57850.0, 57895.13157302644, 58122.0, 1215.2040413529153, 1364.1542243808703, 1368.0286996232787, 1393.191948669769, 1393.8606139385981, 28413.005544805834, 29027.274318142216, 29291.41417171602, 29538.704612954454, 29897.70102298949, 51214.74392628013, 51553.22670159875, 51565.48434515607, 51811.0, 51973.480265196864, 619425.0, 620037.0, 622537.0, 623054.0, 624791.0, 26273.868630656725, 26303.342416438976, 26650.0, 26673.46653066889, 26690.866545667148, 44696.776516117214, 45351.319730204734, 45392.0, 46342.7682861597, 46785.0, 35299.82350088233, 37318.81340593279, 37996.0, 39194.80402597969, 40863.7956810214, 29921.55117673193, 31467.0, 37426.625733742316, 38260.30671180995, 39157.825265242085, 37776.866693999225, 37840.053998650255, 38230.808845955595, 38246.80876595599, 38272.42591361076, 32975.34049318952, 33816.1545961353, 34446.311073777884, 34466.82766586151, 34675.13312167115, 25778.61332079983, 25898.61152082758, 25981.610275846255, 26074.696265186285, 26401.735982639926], "value": 19579.126306993032, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.6692143991847, 375.8571742737759, 385.47960253657567, 389.0817670298098, 403.3586597310276, 15684.659508751296, 18334.358297459592, 18969.430917072474, 19245.807541924576, 19423.805761942378, 19374.806251937476, 19587.314443994463, 19882.304119355824, 19903.701444478345, 20058.09448102428, 5628.943710562903, 5721.542276617871, 5990.700464976756, 6008.3390827009, 6019.036954087347, 4898.53463920927, 5000.449950505442, 5069.619778516608, 5509.696966666832, 5911.320198177222, 5597.44025597439, 5812.389699081599, 5863.091220860773, 5865.090910908816, 5866.589338746268, 5823.883522329551, 5976.551758618121, 5984.910226346608, 6000.399960003986, 6001.399860013986, 4781.784819683088, 5599.972000140014, 5804.216430781875, 5810.4189581041555, 5859.53123750099, 12799.232046077172, 12954.546590869368, 13009.609711708665, 13016.41426135826, 22322.21872234464, 2206.5035367042447, 2244.9887750561143, 2254.222293308806, 2301.493671392291, 2867.7562407195414, 9672.56473458696, 9765.267604929588, 9851.655192068314, 10166.847497287547, 10352.275340726152, 1834.110456428631, 1899.4016884681282, 1932.439592518168, 1932.439592518168, 1942.7183058456585, 87674.68487972686, 113781.43109284401, 114847.99999999917, 115405.0, 115792.0, 11268.718282043015, 11395.715107122389, 11537.307761534335, 11567.88432115668, 11648.475818588015, 14340.713185736227, 14343.067700599428, 14365.281735913162, 14374.137551746928, 14394.352254148589, 8076.4750291230885, 8364.916350836535, 8430.873536896952, 8489.193526614961, 8508.702195423131, 6880.2431732509385, 7355.668994895243, 8033.915421418151, 8347.44319443969, 8376.371772117116, 4446.888827779331, 4497.572730590588, 4500.482444518857, 4502.729836209838, 4576.199165146086, 1892.3755160796904, 1937.9127939242485, 1951.541387773872, 1953.8534609904314, 1958.265650381107, 813.2193094629222, 879.7536689726886, 886.3352485635774, 898.7887846356101, 993.5082134343546, 19365.4190374289, 20750.688739668916, 20766.688499672517, 20795.480112997295, 21118.47203819917, 879.1779685993616, 904.7285814255697, 930.8138372325562, 938.1041105743998, 992.6972273456665, 8734.519601421984, 10408.47957602132, 11246.04408625276, 11247.718807029725, 11296.717582060513, 505110.0, 507282.0, 510500.0, 579566.0, 633570.0, 122587.38706306412, 123430.0, 125518.0, 132864.0, 143652.0, 927.6057675487891, 952.1097773581723, 966.0580933589835, 967.1585720423277, 993.5280741647804, 16079.954802937771, 17284.395046173322, 19036.23855045838, 19057.71413428772, 19116.0, 53573.0, 53710.65723356791, 54580.181297281364, 55503.72248138733, 56131.0, 79475.0, 80572.59713701395, 83457.16542834495, 83623.16376836154, 83969.0, 7940.444168908352, 8159.83680326402, 8230.794230144294, 8264.669413223404, 8380.664773408995, 10611.469426528489, 13015.804762928754, 13018.2839943804, 13052.0, 13121.803172952605, 9656.420614763138, 9926.511023346557, 10485.737856553647, 10558.260921735711, 11535.36555489456, 9684.322097453392, 10901.454927253448, 11061.446927653746, 11176.664700059011, 11473.139514536277, 11554.884451155716, 11572.421378931189, 11615.477303521193, 11856.407179641155, 11918.165728398928, 8138.348932085537, 8142.67429302845, 8145.470544414594, 8360.08039115709, 8383.161683831568, 8175.7138500152205, 11119.498867652763, 11264.718382040515, 11526.481308340977, 11598.072154227473], "value": 9287.981176481704, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1263.4756576020952, 1422.9573112806615, 1558.9064656120634, 1710.2902295547349, 1759.6744602248577, 6572.572782769117, 7402.333789958902, 7944.086430060537, 8380.91619083809, 8403.5378054207, 7973.282404583586, 8274.379421543379, 8276.62755176017, 8277.710280140196, 8291.792705182374, 2106.6096376391583, 2271.318604418672, 2313.849599776009, 2315.270689732737, 2315.305408377488, 2257.7403598586206, 2282.0985710644286, 2351.988240058806, 2352.5177338645535, 2356.5522550715395, 1929.60443109163, 2192.6930229767845, 2224.9777502225015, 2277.032261288954, 2305.4121199094293, 2257.4356410897276, 2262.502249505106, 2279.863208207513, 2281.4638559938485, 2282.954340913189, 2269.693591365161, 2329.77867102625, 2330.347502699247, 2334.743178250391, 2338.695969523968, 8311.33509319253, 8362.205590468895, 8372.665093396257, 8381.706640267623, 8393.286570641503, 4990.625703072248, 5153.123968925292, 5160.56135228506, 5162.586993040548, 5171.560417364529, 2833.6741274753463, 3266.5137362500004, 3335.4663253879507, 3575.1419659281587, 3596.7841929484316, 1038.9116925061378, 1052.9105026072793, 1069.6470164845582, 1087.4345340423015, 1088.3089238333637, 10663.253572249945, 10800.297980631234, 10886.510107045202, 10892.40091794951, 10916.563337466492, 3883.4369016493015, 4582.427196600425, 4655.115528049728, 4665.740250132445, 4683.227267500893, 9274.721758347261, 9303.023182565868, 9344.0, 9345.532723363805, 9346.439213647203, 5118.181091025418, 5125.79496820123, 5137.794488220429, 5148.639595228373, 5155.587552995751, 4192.413062171298, 4412.227860124465, 4670.6497012724185, 4735.597474214662, 4738.360321356573, 3249.0902547286796, 3340.883069092619, 3802.676772474371, 3821.5987321331418, 3906.4140378942816, 1066.3388699006666, 1186.7389174381622, 1273.9108262421541, 1281.2824818101892, 1304.2826445454962, 713.9000539924415, 785.7132146766389, 829.4235506323048, 876.9868451973225, 899.2311573604604, 5521.1442226454965, 5654.123610840326, 5712.714364281769, 5798.971005144947, 5808.419158084158, 771.340503869195, 851.9989012909806, 867.8611422172422, 869.2046777198918, 876.9473831570126, 3375.578052743407, 3794.6964242860504, 3902.804859757058, 3922.5489068757456, 3927.1752931884625, 9426.245900327956, 9916.652917147865, 9920.107190352903, 9927.007299270015, 9941.005899410002, 9813.705588832347, 9866.851997220047, 9890.653827116017, 9893.060159284856, 9900.504974751233, 7630.923690763132, 7767.37860971121, 7770.41721870862, 7784.805379865548, 7792.454528182972, 4937.839607692261, 5193.636445448782, 5379.596530260168, 5490.615656903979, 5690.0611399120335, 9163.404378715362, 9246.306527010636, 9288.674896378594, 9394.201492873182, 9408.858867116862, 9416.482093484925, 9431.952840235756, 9530.285228607743, 9535.856962145712, 9542.284328675238, 6249.25008998923, 6425.389587989133, 6458.22501299847, 6721.193456785217, 6830.0, 7739.883901741366, 8011.39914506426, 8012.118666946515, 8020.438569300093, 8061.032676078674, 5644.187693934827, 6258.405451482102, 7147.142342918883, 7164.068671072727, 7171.103612048494, 7001.124859392576, 7003.5797852129035, 7043.964780176067, 7062.15254169503, 7069.893951590628, 5362.865928351823, 6577.50668699859, 6638.53530252897, 6728.629925354153, 6736.0, 4875.219964805614, 4879.121758083579, 4927.728974906415, 4968.105740966661, 4974.850754477371, 4521.846929033076, 4734.431868175841, 4818.397700287464, 4836.830710925242, 5043.117454445531], "value": 4332.772640746149, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.73967813152453, 294.51258167732396, 302.9712177343152, 304.53102222577235, 313.7897608602237, 5406.59450541209, 5470.781168753245, 5505.944940550593, 5649.762677991506, 5661.905095114803, 5282.728426776484, 5479.835604931848, 5513.2005859150395, 5549.0, 5552.583556233289, 3465.3762322781954, 4185.665146788265, 4254.595813397737, 4259.893502662443, 4281.336392859112, 3409.778364406318, 3428.520760776924, 3854.3191252353936, 4284.378765079062, 4292.463442069741, 3105.798123122001, 3182.347618738165, 3716.6469185427477, 4056.249593825152, 4177.624013838754, 3289.4572395554706, 4182.184474027566, 4195.160967806436, 4202.600752928467, 4284.614384705392, 3026.895183258117, 3083.97999310452, 3663.285659296438, 3664.2305115925687, 3669.94495082574, 4505.977470112629, 4513.458384993789, 5067.391912970467, 5387.353517577876, 5413.83758487246, 3603.4054381027363, 3995.5604883462797, 4719.646026548023, 4744.810207591692, 4758.547937945889, 4850.708957462563, 5482.629342664334, 5831.650100993911, 5867.559933004999, 5902.32123305821, 3324.335132973391, 3810.923781524354, 3880.5149356330458, 4018.557958624549, 4031.1937612477327, 5033.748312584393, 5948.553858460589, 6091.482224010964, 6108.816735497942, 6149.200603921551, 3389.4407422775457, 3714.925701485955, 4025.618480476414, 4035.858744943913, 4049.825550590355, 3233.66046565112, 3774.339490589191, 4139.254934111888, 4152.709310348247, 4172.958270417318, 4109.876703698894, 4329.567043295709, 4731.455882573479, 4768.785404656799, 4771.498992605796, 5152.716600587004, 5317.494837990384, 5438.592105592096, 5897.469227769521, 5913.01392796635, 1149.781541507111, 1233.9506419743109, 1248.862625111237, 1259.93535462534, 1271.7790920716031, 710.5097482736895, 858.7805316450655, 879.8020445399734, 883.0, 894.7226359828474, 4556.633010096958, 4638.79125439356, 5222.112240919053, 5336.866578335495, 5343.70609616467, 5154.768035438415, 5205.687658740488, 5418.8595504776185, 5770.653760774367, 5778.913316300259, 4563.201439748097, 4694.6713730039255, 4801.423829140455, 5226.398964119099, 5246.711430871339, 2918.1391489510647, 3022.9848850756043, 3027.1826606816035, 3064.322812624859, 3068.3890957247177, 4988.256458947564, 5650.835532102036, 5740.282464691913, 5889.4699477046415, 6055.576109672369, 5531.806386776524, 5561.4716601922755, 5865.765369385177, 5901.878643057806, 5965.642061476326, 5777.45114214149, 6005.579609427386, 6016.067509536029, 6140.723667434976, 6144.969275153685, 5065.670731402447, 5182.300389447376, 5772.220750198838, 5840.883182336244, 5897.823065308047, 4992.001599680151, 5052.823151189691, 5296.523312901857, 5655.406182350958, 5819.5635327351465, 4863.48933362006, 6025.397460254115, 6321.491572177486, 6423.828817801715, 6432.581882177644, 3326.7172290355593, 3776.5845756966164, 4009.919801603893, 4030.7984600770433, 4057.553669096456, 5283.656562323436, 5500.257465242301, 6037.184980027816, 6047.213862197887, 6080.604760690541, 5306.230596563554, 5466.180072989084, 5724.711939813508, 5730.965507244868, 5907.3501914790195, 5210.322658054429, 5237.685738855681, 5372.743447752032, 5590.630295577609, 5987.228046330958, 4634.698744581591, 5474.671519708831, 5615.971920140373, 5908.261467316585, 5939.613925094855, 4931.48219436954, 5923.348431672426, 5944.078667806497, 5952.73212705438, 5980.365216956893, 5214.634975551675, 5350.154196802097, 5404.297441332602, 5735.447725762163, 5745.443277741365], "value": 4113.217972909208, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.6587923098831, 724.0551080839502, 758.4463341760514, 765.6171914042978, 792.3621484704812, 14545.636359091015, 15201.011934224267, 15269.923650381746, 15311.69376612467, 15315.693686126271, 12614.81077783834, 12666.796654317848, 12674.873251267485, 12682.048846336516, 12688.429020694068, 808.2806302390877, 815.3355015662246, 827.1810907201857, 827.8716798896181, 832.4048305461624, 802.7591722483261, 814.8044469327349, 815.2784785464877, 815.6329651656748, 820.2412768189431, 815.6900377856435, 852.735651947895, 856.5032281276875, 856.8286342731448, 857.4340934982913, 674.8043067510416, 812.8699408094706, 823.069930977994, 823.9176082391713, 826.1614461321733, 630.0517720830167, 635.4344633276341, 635.8728254349148, 639.1339734659563, 641.0896526931768, 12422.689432764162, 12543.24740515572, 12593.244405335709, 12645.810312845222, 12683.492660293578, 11087.390193539351, 11352.716182095432, 11471.035061767918, 11472.4837382318, 11574.594889178923, 9967.80064398708, 10097.091261786474, 10232.641857535025, 10374.118199953013, 10558.947205264001, 3522.9119272018147, 3524.22467057247, 3574.195805943668, 3642.326169658595, 3704.407294832814, 95239.52380238124, 97938.0, 98151.52772708415, 98218.0, 98243.01756982411, 8625.64291511283, 8727.083656216133, 8743.0, 8836.77908052291, 8871.778205544913, 18367.72448413275, 18378.448646540626, 18401.81598184001, 18439.631207376045, 18442.446726598224, 7354.632268386559, 7486.887696684554, 7582.734604288823, 7785.493942893694, 7787.922120778833, 3120.8595613197463, 3652.7625704329134, 4117.382392641128, 4221.430106935586, 4242.193983143193, 2801.8458984756035, 3442.8278586070596, 3457.152997515625, 3458.4812278157974, 3487.616362200156, 607.7842365960109, 631.3465563142217, 631.5105793010454, 649.4836604899023, 655.3086493749045, 570.1988705868262, 611.8531552427474, 622.8847663182326, 654.482958462809, 657.9539432239793, 8156.796080097926, 8322.916770832335, 8354.45696029756, 8645.870311945326, 8813.647454101892, 592.2123575644382, 607.5959486941223, 646.5183438338374, 656.9277379488252, 662.4799532367165, 3926.2736393767245, 4237.894052648647, 4288.335308027261, 4458.7324760514475, 4517.81928722854, 115977.0, 117822.0, 118211.2268315976, 118630.0, 121264.0, 59571.70214148902, 62895.42761430909, 64823.351766481734, 67625.3237467629, 96581.0341896572, 79797.60101199457, 85916.57041714876, 86428.0, 86829.82925426788, 86912.0, 9252.306077044383, 9294.25645948336, 9540.0, 9578.185854202471, 9668.274879384215, 48824.51175488342, 49534.75232623814, 50173.0, 51166.0, 51522.74238628783, 50913.745431272604, 56189.71905140448, 71515.0, 77566.0, 78488.60755696414, 11514.136439766884, 12333.876661233273, 12345.444454999726, 12359.32023738703, 12567.434465448894, 11525.481353338952, 11575.594854180063, 12140.817887731515, 13714.51999180024, 13869.167849929037, 11489.368084755419, 11646.068314534987, 12833.0, 13082.869171308546, 13098.934505327412, 12858.935705321413, 12945.223286602833, 13090.476380944656, 13345.86654133485, 13787.448502060206, 12915.35423228816, 13067.542636007694, 13124.343782811013, 13378.799318010042, 13400.865991339962, 10677.305975111592, 10727.892721073002, 11083.889161108285, 11146.777064458503, 11291.04026157753, 10711.678649640524, 11228.382438965935, 11318.09455243595, 11343.489542970421, 11382.260153090023], "value": 7010.769131722956, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 155.0, 172.0, 153.0, 158.0, 104.0, 171.0, 172.0, 169.0, 120.0], "value": 150.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 119.0, 147.0, 120.0, 150.0, 157.0, 131.0, 145.0, 119.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 199.0, 197.0, 198.0, 196.0, 199.0, 194.0, 198.0, 199.0, 198.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 58.0, 86.0, 65.0, 59.0, 58.0, 59.0, 78.0, 58.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 92.0, 73.0, 69.0, 71.0, 112.0, 68.0, 69.0, 85.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 88.0, 86.0, 90.0, 93.0, 102.0, 87.0, 89.0, 87.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 95.0, 108.0, 134.0, 95.0, 95.0, 95.0, 96.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 136.0, 85.0, 87.0, 88.0, 135.0, 91.0, 143.0, 135.0, 77.0], "value": 107.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 59.0, 63.0, 92.0, 60.0, 58.0, 58.0, 59.0, 60.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 75.0, 79.0, 79.0, 72.0, 71.0, 72.0, 74.0, 72.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 88.0, 87.0, 87.0, 84.0, 91.0, 88.0, 87.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 151.0, 148.0, 164.0, 167.0, 163.0, 151.0, 178.0, 225.0, 184.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 174.0, 170.0, 170.0, 172.0, 171.0, 171.0, 170.0, 171.0, 174.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 72.0, 62.0, 91.0, 65.0, 61.0, 62.0, 62.0, 81.0], "value": 69.8, "unit": "score"}]}]} 02:47:38 INFO - Return code: 0 02:47:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:47:38 ERROR - # TBPL SUCCESS # 02:47:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:47:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:47:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:47:38 INFO - 2016-12-04 02:47:38.415 screenresolution[1711:20746] starting screenresolution argv=screenresolution get 02:47:38 INFO - 2016-12-04 02:47:38.419 screenresolution[1711:20746] Display 0: 1600x1200x32@60 02:47:38 INFO - 2016-12-04 02:47:38.425 screenresolution[1712:20749] starting screenresolution argv=screenresolution list 02:47:38 INFO - Available Modes on Display 0 02:47:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:47:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:47:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:47:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:47:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:47:38 INFO - Intel Iris: 02:47:38 INFO - Chipset Model: Intel Iris 02:47:38 INFO - Type: GPU 02:47:38 INFO - Bus: Built-In 02:47:38 INFO - VRAM (Dynamic, Max): 1536 MB 02:47:38 INFO - Vendor: Intel (0x8086) 02:47:38 INFO - Device ID: 0x0a2e 02:47:38 INFO - Revision ID: 0x0009 02:47:38 INFO - Displays: 02:47:38 INFO - Display: 02:47:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:47:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:47:38 INFO - Display Serial Number: 1600x1200 60 02:47:38 INFO - Main Display: Yes 02:47:38 INFO - Mirror: Off 02:47:38 INFO - Online: Yes 02:47:38 INFO - Rotation: Supported 02:47:38 INFO - Return code: 0 02:47:38 INFO - Running post-action listener: _resource_record_post_action 02:47:38 INFO - [mozharness: 2016-12-04 10:47:38.676914Z] Finished run-tests step (success) 02:47:38 INFO - Running post-run listener: _resource_record_post_run 02:47:38 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 34566144; Write bytes: 663422976; Read time: 322; Write time: 1875 02:47:38 INFO - TinderboxPrint: CPU usage
54.3% 02:47:38 INFO - TinderboxPrint: I/O read bytes / time
34,566,144 / 322 02:47:38 INFO - TinderboxPrint: I/O write bytes / time
663,422,976 / 1,875 02:47:38 INFO - TinderboxPrint: CPU idle
2,213.5 (45.7%) 02:47:38 INFO - TinderboxPrint: CPU system
975.4 (20.1%) 02:47:38 INFO - TinderboxPrint: CPU user
1,652.7 (34.1%) 02:47:38 INFO - TinderboxPrint: Swap in / out
379,297,792 / 0 02:47:38 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349573632; Write bytes: 351909888; Read time: 26757; Write time: 668 02:47:38 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30330880; Write bytes: 310136832; Read time: 288; Write time: 1203 02:47:39 INFO - Running post-run listener: _upload_blobber_files 02:47:39 INFO - Blob upload gear active. 02:47:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:47:39 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 02:47:39 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:47:39 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:47:39 INFO - (blobuploader) - INFO - Open directory for files ... 02:47:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:47:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:47:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:47:40 INFO - (blobuploader) - INFO - Done attempting. 02:47:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:47:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:47:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:47:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:47:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:47:41 INFO - (blobuploader) - INFO - Done attempting. 02:47:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:47:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:47:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:47:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:47:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:47:43 INFO - (blobuploader) - INFO - Done attempting. 02:47:43 INFO - (blobuploader) - INFO - Iteration through files over. 02:47:43 INFO - Return code: 0 02:47:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:47:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:47:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52f7008d55f30f5ecc80f767d56195672ab2e7a1bc7d762028d26bed9a1b408c042ce790dce60ad7b1e590e5d7c675566c50f69da552b13cfdd0e87d02b7248d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08a5ab7e72042f6a4ccb1fc2916822e9643d1bc9a58daa5934277bdea9a0d2c9efdff4d0053cc62f3084ecf4676864f2bf5823850757d8a6de7405f42c682f36", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92a183c1c3bb2bda37b1bdd5c1bbd92f7bbb64026e7058da4a60c3b652c768619623c858604b9df13a9ec0516407e51acfe3bd2f3710ac9906812a7bfac9b278"} 02:47:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:47:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:47:43 INFO - Contents: 02:47:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52f7008d55f30f5ecc80f767d56195672ab2e7a1bc7d762028d26bed9a1b408c042ce790dce60ad7b1e590e5d7c675566c50f69da552b13cfdd0e87d02b7248d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08a5ab7e72042f6a4ccb1fc2916822e9643d1bc9a58daa5934277bdea9a0d2c9efdff4d0053cc62f3084ecf4676864f2bf5823850757d8a6de7405f42c682f36", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92a183c1c3bb2bda37b1bdd5c1bbd92f7bbb64026e7058da4a60c3b652c768619623c858604b9df13a9ec0516407e51acfe3bd2f3710ac9906812a7bfac9b278"} 02:47:43 INFO - Running post-run listener: copy_logs_to_upload_dir 02:47:43 INFO - Copying logs to upload dir... 02:47:43 INFO - mkdir: /builds/slave/test/build/upload/logs 02:47:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.342804 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-04 02:47:43.972944) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 02:47:43.976433) ========= 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.TstoRKI7rB/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.bhMhycaQo5/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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/Mozilla-Aurora/sha512/52f7008d55f30f5ecc80f767d56195672ab2e7a1bc7d762028d26bed9a1b408c042ce790dce60ad7b1e590e5d7c675566c50f69da552b13cfdd0e87d02b7248d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08a5ab7e72042f6a4ccb1fc2916822e9643d1bc9a58daa5934277bdea9a0d2c9efdff4d0053cc62f3084ecf4676864f2bf5823850757d8a6de7405f42c682f36", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92a183c1c3bb2bda37b1bdd5c1bbd92f7bbb64026e7058da4a60c3b652c768619623c858604b9df13a9ec0516407e51acfe3bd2f3710ac9906812a7bfac9b278"} build_url:https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011100 build_url: 'https://queue.taskcluster.net/v1/task/b2EmoQSySmaBynREdpFgjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52f7008d55f30f5ecc80f767d56195672ab2e7a1bc7d762028d26bed9a1b408c042ce790dce60ad7b1e590e5d7c675566c50f69da552b13cfdd0e87d02b7248d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08a5ab7e72042f6a4ccb1fc2916822e9643d1bc9a58daa5934277bdea9a0d2c9efdff4d0053cc62f3084ecf4676864f2bf5823850757d8a6de7405f42c682f36", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92a183c1c3bb2bda37b1bdd5c1bbd92f7bbb64026e7058da4a60c3b652c768619623c858604b9df13a9ec0516407e51acfe3bd2f3710ac9906812a7bfac9b278"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 02:47:44.010668) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 02:47:44.010951) ========= 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.TstoRKI7rB/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.bhMhycaQo5/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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.005308 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 02:47:44.059953) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 02:47:44.060274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 02:47:44.060630) ========= ========= Total master_lag: 0.18 =========