builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0118 starttime: 1480511278.65 results: success (0) buildid: 20161130031744 builduid: 8ed20d6666f94d7c89a2f9a84369bc73 revision: d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:58.646104) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:58.646530) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:58.646805) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:58.691157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:58.691513) ========= 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.ZRi916VAp2/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.PIKCIwqLC5/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-30 05:07:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2016-11-30 05:07:59 (14.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.624895 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:59.342952) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:59.343613) ========= 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.ZRi916VAp2/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.PIKCIwqLC5/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.026647 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:59.396065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-30 05:07:59.396373) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 --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 d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZRi916VAp2/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.PIKCIwqLC5/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-30 05:07:59,490 truncating revision to first 12 chars 2016-11-30 05:07:59,490 Setting DEBUG logging. 2016-11-30 05:07:59,490 attempt 1/10 2016-11-30 05:07:59,490 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d27c9c1d6beb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 05:08:02,046 unpacking tar archive at: mozilla-aurora-d27c9c1d6beb/testing/mozharness/ program finished with exit code 0 elapsedTime=2.826887 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-30 05:08:02.247753) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 05:08:02.248197) ========= script_repo_revision: d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 05:08:02.248660) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 05:08:02.248981) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 05:08:02.269517) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-30 05:08:02.269947) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.ZRi916VAp2/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.PIKCIwqLC5/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:08:02 INFO - MultiFileLogger online at 20161130 05:08:02 in /builds/slave/test 05:08:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:08:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:08:02 INFO - {'append_to_log': False, 05:08:02 INFO - 'base_work_dir': '/builds/slave/test', 05:08:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:08:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:08:02 INFO - 'branch': 'Mozilla-Aurora', 05:08:02 INFO - 'buildbot_json_path': 'buildprops.json', 05:08:02 INFO - 'config_files': ('talos/mac_config.py',), 05:08:02 INFO - 'default_actions': ('clobber', 05:08:02 INFO - 'read-buildbot-config', 05:08:02 INFO - 'download-and-extract', 05:08:02 INFO - 'populate-webroot', 05:08:02 INFO - 'create-virtualenv', 05:08:02 INFO - 'install', 05:08:02 INFO - 'run-tests'), 05:08:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:08:02 INFO - 'download_minidump_stackwalk': True, 05:08:02 INFO - 'download_symbols': 'ondemand', 05:08:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:08:02 INFO - 'tooltool.py': '/tools/tooltool.py', 05:08:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:08:02 INFO - '/tools/misc-python/virtualenv.py')}, 05:08:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:08:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:08:02 INFO - 'installer_path': 'installer.exe', 05:08:02 INFO - 'log_level': 'info', 05:08:02 INFO - 'log_name': 'talos', 05:08:02 INFO - 'log_to_console': True, 05:08:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:08:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:08:02 INFO - 'opt_config_files': (), 05:08:02 INFO - 'pip_index': False, 05:08:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:08:02 INFO - 'cmd': ('bash', 05:08:02 INFO - '-c', 05:08:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:08:02 INFO - 'enabled': True, 05:08:02 INFO - 'halt_on_failure': False, 05:08:02 INFO - 'name': 'check_screen_resolution'},), 05:08:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:08:02 INFO - 'cmd': ('bash', 05:08:02 INFO - '-c', 05:08:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:08:02 INFO - 'enabled': True, 05:08:02 INFO - 'halt_on_failure': False, 05:08:02 INFO - 'name': 'check_screen_resolution'},), 05:08:02 INFO - 'run_cmd_checks_enabled': True, 05:08:02 INFO - 'sps_profile': False, 05:08:02 INFO - 'sps_profile_interval': 0, 05:08:02 INFO - 'suite': 'dromaeojs', 05:08:02 INFO - 'system_bits': '32', 05:08:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:08:02 INFO - 'title': 't-yosemite-r7-0118', 05:08:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:08:02 INFO - 'use_talos_json': True, 05:08:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:08:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:08:02 INFO - 'work_dir': 'build'} 05:08:02 INFO - [mozharness: 2016-11-30 13:08:02.479336Z] Running clobber step. 05:08:02 INFO - Running pre-action listener: _resource_record_pre_action 05:08:02 INFO - Running main action method: clobber 05:08:02 INFO - rmtree: /builds/slave/test/build 05:08:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:08:04 INFO - Running post-action listener: _resource_record_post_action 05:08:04 INFO - [mozharness: 2016-11-30 13:08:04.806204Z] Finished clobber step (success) 05:08:04 INFO - [mozharness: 2016-11-30 13:08:04.806342Z] Running read-buildbot-config step. 05:08:04 INFO - Running pre-action listener: _resource_record_pre_action 05:08:04 INFO - Running main action method: read_buildbot_config 05:08:04 INFO - Using buildbot properties: 05:08:04 INFO - { 05:08:04 INFO - "project": "", 05:08:04 INFO - "product": "firefox", 05:08:04 INFO - "script_repo_revision": "production", 05:08:04 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:08:04 INFO - "repository": "", 05:08:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:08:04 INFO - "buildid": "20161130031744", 05:08:04 INFO - "pgo_build": "False", 05:08:04 INFO - "basedir": "/builds/slave/test", 05:08:04 INFO - "buildnumber": 10, 05:08:04 INFO - "slavename": "t-yosemite-r7-0118", 05:08:04 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:08:04 INFO - "platform": "yosemite_r7", 05:08:04 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:08:04 INFO - "branch": "mozilla-aurora", 05:08:04 INFO - "repo_path": "releases/mozilla-aurora", 05:08:04 INFO - "revision": "d27c9c1d6beb652d9efb39c60efb8806c9b27ae0", 05:08:04 INFO - "stage_platform": "macosx64", 05:08:04 INFO - "builduid": "8ed20d6666f94d7c89a2f9a84369bc73", 05:08:04 INFO - "slavebuilddir": "test" 05:08:04 INFO - } 05:08:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 05:08:04 INFO - Running post-action listener: _resource_record_post_action 05:08:04 INFO - [mozharness: 2016-11-30 13:08:04.809696Z] Finished read-buildbot-config step (success) 05:08:04 INFO - [mozharness: 2016-11-30 13:08:04.809826Z] Running download-and-extract step. 05:08:04 INFO - Running pre-action listener: _resource_record_pre_action 05:08:04 INFO - Running main action method: download_and_extract 05:08:04 INFO - mkdir: /builds/slave/test/build/tests 05:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:04 INFO - trying https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 05:08:04 INFO - Downloading https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/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 05:08:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/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 05:08:05 INFO - Downloaded 1199 bytes. 05:08:05 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 05:08:05 INFO - Using the following test package requirements: 05:08:05 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 05:08:05 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:08:05 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 05:08:05 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:08:05 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 05:08:05 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:08:05 INFO - u'jsshell-mac.zip'], 05:08:05 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:08:05 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 05:08:05 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 05:08:05 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:08:05 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 05:08:05 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:08:05 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 05:08:05 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:08:05 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 05:08:05 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:08:05 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 05:08:05 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:08:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 05:08:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:08:05 INFO - Fetch https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 05:08:06 INFO - Http code: 200 05:08:06 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 05:08:06 INFO - x-amz-cf-id: rN6sIPyl0qNAFWbltEyp9WxAq_LE2dFaRwojDzuOohD2gcYSnPby2w== 05:08:06 INFO - x-amz-version-id: G7L9Q5OcnBcIt9w4tegcVeJBFykX99hs 05:08:06 INFO - Expected file size: 16285027 05:08:06 INFO - Obtained file size: 16285027 05:08:13 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 05:08:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 05:08:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:08:13 INFO - Fetch https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 05:08:14 INFO - Http code: 200 05:08:14 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 05:08:14 INFO - x-amz-cf-id: mE8wTu9KvARNo6Cx58_Y_synuOnvNiaihFcgw_1dm9m4OiDbVHuryA== 05:08:14 INFO - x-amz-version-id: G7L9Q5OcnBcIt9w4tegcVeJBFykX99hs 05:08:14 INFO - Expected file size: 16285027 05:08:14 INFO - Obtained file size: 16285027 05:08:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 05:08:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:08:20 INFO - Fetch https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 05:08:21 INFO - Http code: 200 05:08:21 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 05:08:21 INFO - x-amz-cf-id: REvqfURnBGTuJhVVEWVJbW2qxcjaTrFlduUMzzal0STHNvfrYV2nNw== 05:08:21 INFO - x-amz-version-id: 7cmeIhi1.6LNoSoSfKaUpM2iEWr0Ycpx 05:08:21 INFO - Expected file size: 14409038 05:08:21 INFO - Obtained file size: 14409038 05:08: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')]} 05:08:21 INFO - trying https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:08:21 INFO - Downloading https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 05:08:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/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 05:08:24 INFO - Downloaded 109713190 bytes. 05:08:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:08:24 INFO - mkdir: /builds/slave/test/properties 05:08:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:08:24 INFO - Writing to file /builds/slave/test/properties/build_url 05:08:24 INFO - Contents: 05:08:24 INFO - build_url:https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:08:25 INFO - Running post-action listener: _resource_record_post_action 05:08:25 INFO - Running post-action listener: set_extra_try_arguments 05:08:25 INFO - [mozharness: 2016-11-30 13:08:25.377363Z] Finished download-and-extract step (success) 05:08:25 INFO - [mozharness: 2016-11-30 13:08:25.377505Z] Running populate-webroot step. 05:08:25 INFO - Running pre-action listener: _resource_record_pre_action 05:08:25 INFO - Running main action method: populate_webroot 05:08:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:08:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:08:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:08:25 INFO - '--tppagecycles', 05:08:25 INFO - '7'], 05:08:25 INFO - 'tests': ['tsvgm']}}, 05:08:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:08:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:08:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:08:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:08:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:25 INFO - 'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:25 INFO - 'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['damp', 'tps']}, 05:08:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:25 INFO - 'tests': ['damp', 'tps']}, 05:08:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['dromaeo_dom']}, 05:08:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:08:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['basic_compositor_video']}, 05:08:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:08:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['a11yr', 05:08:25 INFO - 'ts_paint', 05:08:25 INFO - 'tpaint', 05:08:25 INFO - 'sessionrestore', 05:08:25 INFO - 'sessionrestore_no_auto_restore', 05:08:25 INFO - 'tabpaint']}, 05:08:25 INFO - 'other-e10s': {'tests': ['a11yr', 05:08:25 INFO - 'ts_paint', 05:08:25 INFO - 'tpaint', 05:08:25 INFO - 'sessionrestore', 05:08:25 INFO - 'sessionrestore_no_auto_restore', 05:08:25 INFO - 'tabpaint']}, 05:08:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:08:25 INFO - 'ts_paint', 05:08:25 INFO - 'tpaint', 05:08:25 INFO - 'sessionrestore', 05:08:25 INFO - 'sessionrestore_no_auto_restore', 05:08:25 INFO - 'tabpaint']}, 05:08:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:08:25 INFO - 'ts_paint', 05:08:25 INFO - 'tpaint', 05:08:25 INFO - 'sessionrestore', 05:08:25 INFO - 'sessionrestore_no_auto_restore', 05:08:25 INFO - 'tabpaint']}, 05:08:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['a11yr', 05:08:25 INFO - 'ts_paint', 05:08:25 INFO - 'tpaint', 05:08:25 INFO - 'sessionrestore', 05:08:25 INFO - 'sessionrestore_no_auto_restore', 05:08:25 INFO - 'tabpaint']}, 05:08:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['a11yr', 05:08:25 INFO - 'ts_paint', 05:08:25 INFO - 'tpaint', 05:08:25 INFO - 'sessionrestore', 05:08:25 INFO - 'sessionrestore_no_auto_restore', 05:08:25 INFO - 'tabpaint']}, 05:08:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['tsvgx', 05:08:25 INFO - 'tsvgr_opacity', 05:08:25 INFO - 'tart', 05:08:25 INFO - 'tscrollx', 05:08:25 INFO - 'cart']}, 05:08:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:08:25 INFO - 'tsvgr_opacity', 05:08:25 INFO - 'tart', 05:08:25 INFO - 'tscrollx', 05:08:25 INFO - 'cart']}, 05:08:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:25 INFO - 'talos_options': ['--disable-e10s'], 05:08:25 INFO - 'tests': ['tp5o']}, 05:08:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:25 INFO - 'tests': ['tp5o']}, 05:08:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:25 INFO - 'talos_options': ['--disable-e10s', 05:08:25 INFO - '--xperf_path', 05:08:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:25 INFO - 'tests': ['tp5n']}, 05:08:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:25 INFO - 'talos_options': ['--xperf_path', 05:08:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:25 INFO - 'tests': ['tp5n']}}, 05:08:25 INFO - 'talos.zip': {'path': '', 05:08:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:08:25 INFO - Running post-action listener: _resource_record_post_action 05:08:25 INFO - [mozharness: 2016-11-30 13:08:25.396398Z] Finished populate-webroot step (success) 05:08:25 INFO - [mozharness: 2016-11-30 13:08:25.396527Z] Running create-virtualenv step. 05:08:25 INFO - Running pre-action listener: _resource_record_pre_action 05:08:25 INFO - Running main action method: create_virtualenv 05:08:25 INFO - Creating virtualenv /builds/slave/test/build/venv 05:08:25 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:08:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:08:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:08:25 INFO - Using real prefix '/tools/python27' 05:08:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:08:26 INFO - Installing distribute.............................................................................................................................................................................................done. 05:08:29 INFO - Installing pip.................done. 05:08:29 INFO - Return code: 0 05:08:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:08:29 INFO - Reading from file tmpfile_stdout 05:08:29 INFO - Output received: 05:08:29 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:08:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:29 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 0x103d44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd246fc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, '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.ZRi916VAp2/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.PIKCIwqLC5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:08:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:08:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:29 INFO - 'HOME': '/Users/cltbld', 05:08:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:29 INFO - 'LOGNAME': 'cltbld', 05:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:29 INFO - 'MOZ_NO_REMOTE': '1', 05:08:29 INFO - 'NO_EM_RESTART': '1', 05:08:29 INFO - 'PAGER': '/bin/cat', 05:08:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:29 INFO - 'PWD': '/builds/slave/test', 05:08:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:29 INFO - 'SHELL': '/bin/bash', 05:08:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:08:29 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:08:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:29 INFO - 'USER': 'cltbld', 05:08:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:29 INFO - 'XPC_FLAGS': '0x0', 05:08:29 INFO - 'XPC_SERVICE_NAME': '0', 05:08:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:08:29 INFO - Cleaning up... 05:08:29 INFO - Return code: 0 05:08:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:29 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 0x103d44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd246fc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, '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.ZRi916VAp2/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.PIKCIwqLC5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:08:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:08:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:29 INFO - 'HOME': '/Users/cltbld', 05:08:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:29 INFO - 'LOGNAME': 'cltbld', 05:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:29 INFO - 'MOZ_NO_REMOTE': '1', 05:08:29 INFO - 'NO_EM_RESTART': '1', 05:08:29 INFO - 'PAGER': '/bin/cat', 05:08:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:29 INFO - 'PWD': '/builds/slave/test', 05:08:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:29 INFO - 'SHELL': '/bin/bash', 05:08:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:08:29 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:08:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:29 INFO - 'USER': 'cltbld', 05:08:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:29 INFO - 'XPC_FLAGS': '0x0', 05:08:29 INFO - 'XPC_SERVICE_NAME': '0', 05:08:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:29 INFO - Downloading/unpacking psutil>=3.1.1 05:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:08:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:33 INFO - Installing collected packages: psutil 05:08:33 INFO - Running setup.py install for psutil 05:08:33 INFO - building 'psutil._psutil_osx' extension 05:08:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:08:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:08:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:08:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:08:34 INFO - building 'psutil._psutil_posix' extension 05:08:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:08:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:08:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:08:34 INFO - ^ 05:08:34 INFO - 1 warning generated. 05:08:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:08:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:34 INFO - Successfully installed psutil 05:08:34 INFO - Cleaning up... 05:08:35 INFO - Return code: 0 05:08:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:35 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 0x103d44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd246fc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, '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.ZRi916VAp2/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.PIKCIwqLC5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:08:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:08:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:35 INFO - 'HOME': '/Users/cltbld', 05:08:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:35 INFO - 'LOGNAME': 'cltbld', 05:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:35 INFO - 'MOZ_NO_REMOTE': '1', 05:08:35 INFO - 'NO_EM_RESTART': '1', 05:08:35 INFO - 'PAGER': '/bin/cat', 05:08:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:35 INFO - 'PWD': '/builds/slave/test', 05:08:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:35 INFO - 'SHELL': '/bin/bash', 05:08:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:08:35 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:08:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:35 INFO - 'USER': 'cltbld', 05:08:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:35 INFO - 'XPC_FLAGS': '0x0', 05:08:35 INFO - 'XPC_SERVICE_NAME': '0', 05:08:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:08:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:08:37 INFO - Installing collected packages: mozsystemmonitor 05:08:37 INFO - Running setup.py install for mozsystemmonitor 05:08:37 INFO - Successfully installed mozsystemmonitor 05:08:37 INFO - Cleaning up... 05:08:38 INFO - Return code: 0 05:08:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:38 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 0x103d44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd246fc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, '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.ZRi916VAp2/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.PIKCIwqLC5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:38 INFO - 'HOME': '/Users/cltbld', 05:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:38 INFO - 'LOGNAME': 'cltbld', 05:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:38 INFO - 'MOZ_NO_REMOTE': '1', 05:08:38 INFO - 'NO_EM_RESTART': '1', 05:08:38 INFO - 'PAGER': '/bin/cat', 05:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:38 INFO - 'PWD': '/builds/slave/test', 05:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:38 INFO - 'SHELL': '/bin/bash', 05:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:08:38 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:38 INFO - 'USER': 'cltbld', 05:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:38 INFO - 'XPC_FLAGS': '0x0', 05:08:38 INFO - 'XPC_SERVICE_NAME': '0', 05:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:38 INFO - Downloading/unpacking jsonschema==2.5.1 05:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:08:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:41 INFO - Downloading functools32-3.2.3-2.tar.gz 05:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:08:41 INFO - warning: no files found matching '*.txt' 05:08:41 INFO - no previously-included directories found matching 'build' 05:08:41 INFO - no previously-included directories found matching 'dist' 05:08:41 INFO - no previously-included directories found matching '.git*' 05:08:41 INFO - Installing collected packages: jsonschema, functools32 05:08:41 INFO - Running setup.py install for functools32 05:08:41 INFO - warning: no files found matching '*.txt' 05:08:41 INFO - no previously-included directories found matching 'build' 05:08:41 INFO - no previously-included directories found matching 'dist' 05:08:41 INFO - no previously-included directories found matching '.git*' 05:08:41 INFO - Successfully installed jsonschema functools32 05:08:41 INFO - Cleaning up... 05:08:41 INFO - Return code: 0 05:08:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:41 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 0x103d44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd246fc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, '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.ZRi916VAp2/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.PIKCIwqLC5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:41 INFO - 'HOME': '/Users/cltbld', 05:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:41 INFO - 'LOGNAME': 'cltbld', 05:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:41 INFO - 'MOZ_NO_REMOTE': '1', 05:08:41 INFO - 'NO_EM_RESTART': '1', 05:08:41 INFO - 'PAGER': '/bin/cat', 05:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:41 INFO - 'PWD': '/builds/slave/test', 05:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:41 INFO - 'SHELL': '/bin/bash', 05:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:08:41 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:41 INFO - 'USER': 'cltbld', 05:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:41 INFO - 'XPC_FLAGS': '0x0', 05:08:41 INFO - 'XPC_SERVICE_NAME': '0', 05:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:08:41 INFO - Cleaning up... 05:08:41 INFO - Return code: 0 05:08:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:41 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 0x103d44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd246fc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, '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.ZRi916VAp2/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.PIKCIwqLC5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:41 INFO - 'HOME': '/Users/cltbld', 05:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:41 INFO - 'LOGNAME': 'cltbld', 05:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:41 INFO - 'MOZ_NO_REMOTE': '1', 05:08:41 INFO - 'NO_EM_RESTART': '1', 05:08:41 INFO - 'PAGER': '/bin/cat', 05:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:41 INFO - 'PWD': '/builds/slave/test', 05:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:41 INFO - 'SHELL': '/bin/bash', 05:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:08:41 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:41 INFO - 'USER': 'cltbld', 05:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:41 INFO - 'XPC_FLAGS': '0x0', 05:08:41 INFO - 'XPC_SERVICE_NAME': '0', 05:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:42 INFO - Downloading/unpacking blobuploader==1.2.4 05:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:44 INFO - Downloading blobuploader-1.2.4.tar.gz 05:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:08:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:08:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:46 INFO - Downloading docopt-0.6.1.tar.gz 05:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:08:46 INFO - Installing collected packages: blobuploader, requests, docopt 05:08:46 INFO - Running setup.py install for blobuploader 05:08:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:08:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:08:46 INFO - Running setup.py install for requests 05:08:46 INFO - Running setup.py install for docopt 05:08:46 INFO - Successfully installed blobuploader requests docopt 05:08:46 INFO - Cleaning up... 05:08:47 INFO - Return code: 0 05:08:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:47 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 0x103d44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd246fc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, '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.ZRi916VAp2/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.PIKCIwqLC5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:47 INFO - 'HOME': '/Users/cltbld', 05:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:47 INFO - 'LOGNAME': 'cltbld', 05:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:47 INFO - 'MOZ_NO_REMOTE': '1', 05:08:47 INFO - 'NO_EM_RESTART': '1', 05:08:47 INFO - 'PAGER': '/bin/cat', 05:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:47 INFO - 'PWD': '/builds/slave/test', 05:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:47 INFO - 'SHELL': '/bin/bash', 05:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:08:47 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:47 INFO - 'USER': 'cltbld', 05:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:47 INFO - 'XPC_FLAGS': '0x0', 05:08:47 INFO - 'XPC_SERVICE_NAME': '0', 05:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:08:47 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-bdoVjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:08:47 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-keTjRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:08:47 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-zzBHMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:08:47 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-h1sJc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:08:47 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-5m9y7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:08:47 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-kjxCTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:08:47 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-LzFzaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:08:48 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-qLCNlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:08:48 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-nnL9Mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:08:48 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-5Jeogm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:08:48 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-AqRdif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:08:48 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-CHU6p4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:08:48 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-yLR1qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:08:48 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-ENOjoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:48 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-nWVcwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:08:48 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-gGIWnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:08:48 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-C3v3nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:08:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:08:49 INFO - Running setup.py install for manifestparser 05:08:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:08:49 INFO - Running setup.py install for mozcrash 05:08:49 INFO - Running setup.py install for mozdebug 05:08:49 INFO - Running setup.py install for mozdevice 05:08:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:08:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:08:49 INFO - Running setup.py install for mozfile 05:08:49 INFO - Running setup.py install for mozhttpd 05:08:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:08:49 INFO - Running setup.py install for mozinfo 05:08:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:08:50 INFO - Running setup.py install for mozInstall 05:08:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:08:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:08:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:08:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:08:50 INFO - Running setup.py install for mozleak 05:08:50 INFO - Running setup.py install for mozlog 05:08:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:08:50 INFO - Running setup.py install for moznetwork 05:08:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:08:50 INFO - Running setup.py install for mozprocess 05:08:50 INFO - Running setup.py install for mozprofile 05:08:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:08:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:08:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:08:50 INFO - Running setup.py install for mozrunner 05:08:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:08:51 INFO - Running setup.py install for mozscreenshot 05:08:51 INFO - Running setup.py install for moztest 05:08:51 INFO - Running setup.py install for mozversion 05:08:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:08:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:08:51 INFO - Cleaning up... 05:08:51 INFO - Return code: 0 05:08:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08: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')]} 05:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08: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')]} 05:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:51 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 0x103d44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd246fc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, '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.ZRi916VAp2/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.PIKCIwqLC5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:51 INFO - 'HOME': '/Users/cltbld', 05:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:51 INFO - 'LOGNAME': 'cltbld', 05:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:51 INFO - 'MOZ_NO_REMOTE': '1', 05:08:51 INFO - 'NO_EM_RESTART': '1', 05:08:51 INFO - 'PAGER': '/bin/cat', 05:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:51 INFO - 'PWD': '/builds/slave/test', 05:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:51 INFO - 'SHELL': '/bin/bash', 05:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:08:51 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:51 INFO - 'USER': 'cltbld', 05:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:51 INFO - 'XPC_FLAGS': '0x0', 05:08:51 INFO - 'XPC_SERVICE_NAME': '0', 05:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:08:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-r7S0LZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:08:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:08:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-LyJDdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:08:52 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)) 05:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:08:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-SrKP38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:08:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-uzyozG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:08:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-1US56V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:08:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-NhsLod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:08:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-u_CmSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:08:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-Pcs499-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:08:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-aW7Zxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:08:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-BQoult-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:08:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-MakoQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:08:53 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-zix7bU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:08:53 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-aiSgE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:08:53 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-fk5uZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:08:53 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)) 05:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:53 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-GQdKmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:08:53 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-wqmbCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:08:53 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-HDPAgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:08:53 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)) 05:08:53 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)) 05:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:08:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:56 INFO - Downloading blessings-1.6.tar.gz 05:08:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:08:56 INFO - Installing collected packages: blessings 05:08:56 INFO - Running setup.py install for blessings 05:08:56 INFO - Successfully installed blessings 05:08:56 INFO - Cleaning up... 05:08:56 INFO - Return code: 0 05:08:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:08:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:08:56 INFO - Reading from file tmpfile_stdout 05:08:56 INFO - Current package versions: 05:08:56 INFO - blessings == 1.6 05:08:56 INFO - blobuploader == 1.2.4 05:08:56 INFO - docopt == 0.6.1 05:08:56 INFO - functools32 == 3.2.3-2 05:08:56 INFO - jsonschema == 2.5.1 05:08:56 INFO - manifestparser == 1.1 05:08:56 INFO - mozInstall == 1.12 05:08:56 INFO - mozcrash == 1.0 05:08:56 INFO - mozdebug == 0.1 05:08:56 INFO - mozdevice == 0.48 05:08:56 INFO - mozfile == 1.2 05:08:56 INFO - mozhttpd == 0.7 05:08:56 INFO - mozinfo == 0.9 05:08:56 INFO - mozleak == 0.1 05:08:56 INFO - mozlog == 3.4 05:08:56 INFO - moznetwork == 0.27 05:08:56 INFO - mozprocess == 0.23 05:08:56 INFO - mozprofile == 0.28 05:08:56 INFO - mozrunner == 6.13 05:08:56 INFO - mozscreenshot == 0.1 05:08:56 INFO - mozsystemmonitor == 0.3 05:08:56 INFO - moztest == 0.7 05:08:56 INFO - mozversion == 1.4 05:08:56 INFO - psutil == 3.1.1 05:08:56 INFO - requests == 1.2.3 05:08:56 INFO - wsgiref == 0.1.2 05:08:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:56 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 0x103d44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd246fc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, '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.ZRi916VAp2/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.PIKCIwqLC5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:08:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:08:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:56 INFO - 'HOME': '/Users/cltbld', 05:08:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:56 INFO - 'LOGNAME': 'cltbld', 05:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:56 INFO - 'MOZ_NO_REMOTE': '1', 05:08:56 INFO - 'NO_EM_RESTART': '1', 05:08:56 INFO - 'PAGER': '/bin/cat', 05:08:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:56 INFO - 'PWD': '/builds/slave/test', 05:08:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:56 INFO - 'SHELL': '/bin/bash', 05:08:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:08:56 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:08:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:56 INFO - 'USER': 'cltbld', 05:08:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:56 INFO - 'XPC_FLAGS': '0x0', 05:08:56 INFO - 'XPC_SERVICE_NAME': '0', 05:08:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:08:57 INFO - Cleaning up... 05:08:57 INFO - Return code: 0 05:08:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:57 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 0x103d44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd246fc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, '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.ZRi916VAp2/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.PIKCIwqLC5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:57 INFO - 'HOME': '/Users/cltbld', 05:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:57 INFO - 'LOGNAME': 'cltbld', 05:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:57 INFO - 'MOZ_NO_REMOTE': '1', 05:08:57 INFO - 'NO_EM_RESTART': '1', 05:08:57 INFO - 'PAGER': '/bin/cat', 05:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:57 INFO - 'PWD': '/builds/slave/test', 05:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:57 INFO - 'SHELL': '/bin/bash', 05:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:08:57 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:57 INFO - 'USER': 'cltbld', 05:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:57 INFO - 'XPC_FLAGS': '0x0', 05:08:57 INFO - 'XPC_SERVICE_NAME': '0', 05:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:08:57 INFO - Cleaning up... 05:08:57 INFO - Return code: 0 05:08:57 INFO - Running post-action listener: _resource_record_post_action 05:08:57 INFO - Running post-action listener: _start_resource_monitoring 05:08:57 INFO - Starting resource monitoring. 05:08:57 INFO - [mozharness: 2016-11-30 13:08:57.499669Z] Finished create-virtualenv step (success) 05:08:57 INFO - [mozharness: 2016-11-30 13:08:57.500668Z] Running install step. 05:08:57 INFO - Running pre-action listener: _resource_record_pre_action 05:08:57 INFO - Running main action method: install 05:08:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:08:57 INFO - Reading from file tmpfile_stdout 05:08:57 INFO - Detecting whether we're running mozinstall >=1.0... 05:08:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:08:57 INFO - Reading from file tmpfile_stdout 05:08:57 INFO - Output received: 05:08:57 INFO - Usage: mozinstall [options] installer 05:08:57 INFO - Options: 05:08:57 INFO - -h, --help show this help message and exit 05:08:57 INFO - -d DEST, --destination=DEST 05:08:57 INFO - Directory to install application into. [default: 05:08:57 INFO - "/builds/slave/test"] 05:08:57 INFO - --app=APP Application being installed. [default: firefox] 05:08:57 INFO - mkdir: /builds/slave/test/build/application 05:08:57 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'] 05:08:57 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 05:09:28 INFO - Reading from file tmpfile_stdout 05:09:28 INFO - Output received: 05:09:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:09:28 INFO - Running post-action listener: _resource_record_post_action 05:09:28 INFO - [mozharness: 2016-11-30 13:09:28.745413Z] Finished install step (success) 05:09:28 INFO - [mozharness: 2016-11-30 13:09:28.745566Z] Running run-tests step. 05:09:28 INFO - Running pre-action listener: _resource_record_pre_action 05:09:28 INFO - Running main action method: run_tests 05:09:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:09:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:09:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:09:28 INFO - 2016-11-30 05:09:28.759 screenresolution[1636:8188] starting screenresolution argv=screenresolution get 05:09:28 INFO - 2016-11-30 05:09:28.762 screenresolution[1636:8188] Display 0: 1600x1200x32@60 05:09:28 INFO - 2016-11-30 05:09:28.768 screenresolution[1637:8190] starting screenresolution argv=screenresolution list 05:09:28 INFO - Available Modes on Display 0 05:09:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:09:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:09:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:09:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:09:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:09:28 INFO - Intel Iris: 05:09:28 INFO - Chipset Model: Intel Iris 05:09:28 INFO - Type: GPU 05:09:28 INFO - Bus: Built-In 05:09:28 INFO - VRAM (Dynamic, Max): 1536 MB 05:09:28 INFO - Vendor: Intel (0x8086) 05:09:28 INFO - Device ID: 0x0a2e 05:09:28 INFO - Revision ID: 0x0009 05:09:28 INFO - Displays: 05:09:28 INFO - Display: 05:09:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:09:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:09:28 INFO - Display Serial Number: 1600x1200 60 05:09:28 INFO - Main Display: Yes 05:09:28 INFO - Mirror: Off 05:09:28 INFO - Online: Yes 05:09:28 INFO - Rotation: Supported 05:09:29 INFO - Return code: 0 05:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:29 INFO - Python 2.7.3 05:09:29 INFO - Return code: 0 05:09:29 INFO - grabbing minidump binary from tooltool 05:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:29 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 0x103b58d30>, '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 0x1039bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b50b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:09:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:09:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:09:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:29 INFO - Return code: 0 05:09:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:09:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:09:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:29 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:09:29 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0118 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZRi916VAp2/Render', 05:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:29 INFO - 'HOME': '/Users/cltbld', 05:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:29 INFO - 'LOGNAME': 'cltbld', 05:09:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:09:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:29 INFO - 'MOZ_NO_REMOTE': '1', 05:09:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:09:29 INFO - 'NO_EM_RESTART': '1', 05:09:29 INFO - 'PAGER': '/bin/cat', 05:09:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:29 INFO - 'PWD': '/builds/slave/test', 05:09:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:29 INFO - 'SHELL': '/bin/bash', 05:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PIKCIwqLC5/Listeners', 05:09:29 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:29 INFO - 'USER': 'cltbld', 05:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:29 INFO - 'XPC_FLAGS': '0x0', 05:09:29 INFO - 'XPC_SERVICE_NAME': '0', 05:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:09:29 INFO - mozversion application_buildid: 20161130031744 05:09:29 INFO - mozversion application_changeset: d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 05:09:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:09:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:09:29 INFO - mozversion application_name: Firefox 05:09:29 INFO - mozversion application_remotingname: firefox-dev 05:09:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:09:29 INFO - mozversion application_vendor: Mozilla 05:09:29 INFO - mozversion application_version: 52.0a2 05:09:29 INFO - mozversion platform_buildid: 20161130031744 05:09:29 INFO - mozversion platform_changeset: d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 05:09:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:09:29 INFO - mozversion platform_version: 52.0a2 05:09:29 INFO - using testdate: 1480511369 05:09:29 INFO - actual date: 1480511369 05:09:29 INFO - starting webserver on 'localhost:49245' 05:09:29 INFO - SUITE-START | Running 2 tests 05:09:29 INFO - TEST-START | dromaeo_css 05:09:29 INFO - operating with platform_type : mac_ 05:09:29 INFO - Initialising browser for dromaeo_css test... 05:09:29 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpqJF6J6/profile http://localhost:49245/getInfo.html) 05:09:32 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 05:09:32 INFO - PROCESS | 1647 | colorDepth:24 05:09:32 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 05:09:32 INFO - PROCESS | 1647 | __metrics 05:09:32 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:09:32 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:09:33 INFO - TEST-INFO | 1647: exit 0 05:09:33 INFO - Browser initialized. 05:09:33 INFO - Running cycle 1/1 for dromaeo_css test... 05:09:33 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpqJF6J6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:09:45 INFO - PROCESS | 1650 | RSS: Main: 222765056 05:09:45 INFO - PROCESS | 1650 | 05:10:37 INFO - PROCESS | 1650 | 1480511437093 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:12:50 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 05:12:52 INFO - PROCESS | 1650 | RSS: Main: 368410624 05:12:52 INFO - PROCESS | 1650 | 05:15:55 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 05:15:55 INFO - PROCESS | 1650 | RSS: Main: 350613504 05:15:55 INFO - PROCESS | 1650 | 05:18:58 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 05:18:58 INFO - PROCESS | 1650 | RSS: Main: 348905472 05:18:58 INFO - PROCESS | 1650 | 05:22:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 05:22:01 INFO - PROCESS | 1650 | RSS: Main: 331501568 05:22:01 INFO - PROCESS | 1650 | 05:25:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 05:25:05 INFO - PROCESS | 1650 | RSS: Main: 312864768 05:25:05 INFO - PROCESS | 1650 | 05:28:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 05:28:09 INFO - PROCESS | 1650 | RSS: Main: 442933248 05:28:09 INFO - PROCESS | 1650 | 05:28:09 INFO - PROCESS | 1650 | __start_tp_report 05:28:09 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 05:28:09 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 05:28:09 INFO - PROCESS | 1650 | |i|pagename|runs| 05:28:09 INFO - PROCESS | 1650 | |0;dojo.html;3306.6362700102986;3310.635830058694;3373.2578832656823;3385.830708464576;3399.898003059909;5656.604037717361;5672.943270567293;5677.233573467591;5683.516901063409;5699.430056994299;5576.721163941807;5649.265595472593;5654.180143879145;5669.461401166893;5694.658320500773;5148.5623721983675;5152.330197074384;5174.094533456649;5201.947980520203;5223.660462069973;5180.740962951837;5181.222816577525;5193.45468725784;5257.132573125449;5328.893422131555;5204.895902081956;5216.58267338612;5217.452167522412;5237.423883372826;5239.580833533309;5216.217567364906;5270.9736451317885;5276.604254680876;5280.973595132038;5315.867103322448;5091.23631455281;5122.487751224885;5176.611754118456;5178.14560597501;5219.973900130513;5562.276904002494;5569.192467092249;5580.609357344987;5605.971970140164;5642.971785141089;6123;6179.907301390483;6196.690165491707;6257.43683068526;6267.780627678009;6267.4986001119805;6288.811335659938;6298.842528936768;6318.399752023551;6328.905066424008;5636.85907852307;5694.032014557535;5694.316681998187;5697.4587414195585;5754.74103665336;6299.5275354348605;6338.651374174465;6341.302456729756;6352.14246076783;6371;6126.877462450726;6130.233720784902;6136.9079463808075;6149.2313460817395;6156.984097623931;5168.483151684802;6108.450239478469;6117.877642447215;6125.846853828601;6145.0475176346845;6123.38766123393;6170.136180934673;6185.103160041769;6189.381061893865;6197.25632924052;6142.6314421134875;6169.660668663177;6199.721012554446;6204.503639708812;6210.378962103753;4803.519648035169;4818.638602104857;4836.105320515716;4903.607711383081;4918.852434426973;4385.035292235767;4399.560043995639;4406.405135306757;4407.184670835905;4423.60187583119;5624.775008999676;5629.802956896489;5633.774649014075;5648.661080335193;5656.91514627281;6108.706347747029;6163.7842675506145;6169.784057558054;6187.597806142587;6190.535709821781;5596.496315331558;5660.462256085665;5664.801731939445;5703.686297253608;5755.395683453177;5350.5987050971335;5357.8392648220615;5360.678359298377;5380.973095134577;5383.623146379794;6214.5028397727165;6278.246610406781;6290.433860952537;6311.305756366704;6314;6131.5601757859595;6190.50475961931;6194.535409844189;6206.348333425106;6209.906851397323;6109.222480666452;6285.151504546828;6289.150964619901;6291.496680265659;6300;6121.418465245794;6143.757528502154;6159.907601385893;6191.071339299141;6229.626222426669;6048.546359023179;6268.216472940882;6280.685965701605;6286.591371560834;6291.245050593775;6062.787802426894;6065.8180254592435;6076.088586712029;6082.087686847009;6102.664353460602;5873.089671100986;5893.852653683521;5895.322037965689;5923.940760592339;6002.669853158118;5830.562707797017;5832.591718579659;5862.589618726648;5881.470667639933;5938.020226662552;6075.210222671025;6079.452849243412;6082.574219804571;6095.664738439428;6105.114758360101;5746.080627099644;5890.4993075589055;5922.378150294329;5925.822225333247;5964.194833697396 05:28:09 INFO - PROCESS | 1650 | |1;ext.html;431.7582153993763;468.98464823656786;523.706724234429;526.2395838014071;529.0926061804007;29744.55383169254;31259.374812503764;36252.637473625255;39888.002799930015;40067.599324006755;40120.99697507556;40432;40476.39285410721;40576.594234057724;40745.59254407455;9162.221211197057;9331;9344.205742511895;9358.859617105749;9370;9285.303602229826;9382.85925711115;9386.10831970965;9391.20174785144;9395.013523580028;9330.953345233263;9393.107654772786;9405.247580193569;9428.434293942351;9453.905460945405;9312.25501959845;9348.392354496937;9372.95313523435;9378.437293762396;9383.24934005278;9228.907710922873;9301.209397201246;9302.53487325631;9331.813363732756;9338.393004454689;38173.23653526914;38512.807435962925;38535.42196867049;38610.841674749805;38621.841344759705;3460.2387474755515;3777.1501412182038;3793.2982398256413;3827.27281816454;3834.6740527055235;27712.307192320153;27747.583786243224;27754.444911101866;28861.711382886322;29632.6665300062;3073.83093929836;3133.138386943586;3219.7263232625255;3306.057773534555;3315.419801534722;436018;437458;437725;440221;440492;55140.72429637826;55325.72337138288;55419.44580554224;55475;55504.72247638817;56696.43303566994;56715.86568268694;56929.71535142381;56961;56962.43037569654;15579.688406231811;15801.683966320608;15834.76247856283;15836.208189590474;15847.128407937675;12295;12360.072994525446;12391.75216495665;12393.132480726443;12417.875821241852;12620.558280460324;14794.186319752302;14994.400223990919;15003;15175.468858589897;6127.354224236572;6268.592541484789;6286.18279623646;6313.2424109107205;6314.368563143649;862.8921384826897;890.8396488632109;1010.9090181883519;1015.3146626027402;1039.9844002339971;28511.57232641512;28536.286592835346;29087.7091229085;29160.56259156114;29231;1015.9796804063877;1039.6361273554196;1092.6503518874042;1092.6612750047511;1137.4540220694114;14315.57053288403;14749.115053096848;14820.072680739613;15139;15186.924065379602;659338;659980;661073;664122;665068;55616.887662246525;55619.44380556142;55638.72180639152;55707.44292557023;55824.72087639536;1206.7767463019195;1256.6481385212155;1311.2591385866954;1351.6215459671307;1375.339836878304;28824.135275941757;29516.704832951396;29625.85187074051;29683;30414.84792576023;42836.78581607072;44137.89655258644;46613;47218.29172562346;47364.52635473601;549004;552684;552784;553449;553692.2315388398;32517.837410812794;33338.499922501665;37312;38220;38412.847614571605;42657;43627.127457450035;43628.12743745171;43645;44375.33436998383;34440.48339274963;36952.81523592365;36999.260014799016;37027.25945481021;37909.62090379061;36302.34811182208;36387.90836274916;36752.816235918646;37055;37484.43773343456;35059.474107887894;36477;36725.265494689425;36948;37107;27837.860810695816;31120.533192001687;32806.34387312288;32858.34283314368;33038.00885973425;24579.385515361544;24637.50724985526;24926.501469970863;25155.748442516073;25204.873975630006 05:28:09 INFO - PROCESS | 1650 | |2;jquery.html;335.99160020999454;346.0690741904277;346.4232053630705;360.46111063959387;361.1963381476214;15077.321520531576;15788;15967.281472333743;19812.910289934047;19855.205791768312;18322.541936451562;19667.901660491676;19671.409857704293;19672.606547869033;19719.40841774749;5512.255845460862;5768.5385169186575;5776.59563830532;5794.5943783935145;5805.390434004411;3995.320795464778;4254.936175957348;6124.693765311739;6467.579607325532;6483.092367068614;6286.182796236483;6362.459190968773;6444.516661250402;6459;6467.83830404239;6343.619382837044;6460.644664543448;6463.903041454382;6479.578827376253;6486.124373209604;5297.973510132425;5506.504414602665;5535.612507124503;5557.472040156179;5581.413951535071;23829;24546.87726561356;24654;24899.875500622562;25008.499830003297;2213.1700612270397;2368.0764501844296;2386.3437554672605;2424.854508729482;2580.87095645217;10740.838887416696;14894.925525372411;15179.16514591697;15258.771118433233;15570.532884013497;1774.4942691333038;1844.7786265648208;1846.621442604263;1876.0525934403177;1882.2565086790642;90929;101802;108380.91619083787;111287;120031.99999999913;10695.893041069645;11048.502817373237;11061;11141.777164456666;11220.495077721358;14227.430902763981;14237.857621423653;14238.28808559568;14240.359183836752;14251.501197458247;7986.7603971880935;8082.515049096955;8145.5927203639585;8299.29455996235;8313.293370063491;7966.721164759206;7993.200679931961;8097.676092956333;8098.595070246464;8235.29999950011;3613.7831730096277;4401.581849724271;4418.652311045098;4478.193925093515;4530.818767249273;1876.634056358997;1877.9530511737044;1882.1059996501551;1883.2561138350275;1909.980900191008;801.8276070644723;870.7537089038593;882.9028806831243;923.0400383600979;1024.3904876598413;20478.385648430572;20683.06926188325;20880.895595521924;20935.581288374146;21239;898.5866501409295;906.5467266366817;910.2854259406466;963.5760265483165;975.6926568131016;10093.899061009442;10821.729456763644;10838.837417438745;10852.782944341227;10886.67339979802;606038;609279.9536002381;611746;624100;636468;125314;126761;127255.3637231808;131121.34439327742;133009;974.848898420746;978.1099199728355;1001.3491230700104;1008.7931973945324;1009.5103874620803;17708.468745937644;17899.105044747972;17942.461726148238;18118.45644630663;18153.546161346072;46183.076338473715;46587.76706116448;51271;52472.688182794205;53539.8996875773;55975.720121399136;71589.91485723564;73594.63202683952;74771.62614186894;76365;6799.184097908283;7697.538147711155;7742.109657389472;7753.10839253493;8137.715179968673;9986.300958932805;12247.69380765488;12351.69120771988;12451.82478467985;12544.623661290176;10503.42231177262;10589.629362972259;11033.44832758375;11089.22375433712;11129.499172537417;10490;10602;10633.468326583485;10939.398333091756;11024.393658348547;9000.36997410175;10644.095251903675;11212.887871121511;11216.83174752363;11219.831702524305;7073.610951397723;7196.712131514891;7250.456215783732;7261.854762904818;7493.213212612597;7754.379649627904;10611.681649550526;10613.31013484152;11012.348147777897;11079.833802492807 05:28:09 INFO - PROCESS | 1650 | |3;mootools.html;1313.763522565939;1371.8353797544296;1439.8776104031158;1473.801036860024;1806.9457916262509;7299.489035767492;7673.347765439938;7904.367650587954;8173.346132309416;8197.754067377973;8092.231238032392;8132.5120492770475;8140.5929703514885;8151.673933042686;8169.550674712888;2231.0518029837335;2267.0478399072344;2268.2288022072494;2274.624686926655;2276.7950884420397;2220.9888950555223;2263.6151854184745;2271.7046783918067;2272.1820144747876;2279.0655831109248;2233.2665727072654;2233.810126139272;2255.052877791323;2262.049939025613;2265.7734226577295;2193.7696541863115;2215.235743668448;2252.974896422124;2253.954920901589;2254.8083412909923;2238.0823862216575;2266.7279926408937;2267.0931627349028;2268.2854900706225;2274.715660542432;7985.441019128663;8111.553864537448;8146.959265203695;8161.63272652732;8202.794930126734;4918.754062296871;5108.3359163308905;5112.696262453051;5117.923231151536;5126.846194614131;3240.1918717415897;3278.327942771726;3301.570795796531;3356.748243881694;3358.9160270993175;959.8464245720721;984.7931934293807;988.6539711101129;995.4276291132628;1011.8735158105237;10533.367997920073;10539.57841686324;10613.628523001658;10761.085307748772;10771.461426928701;4098.631123198867;4175.97912010438;4293.0984493256765;4294.742315461082;4351.608355248043;8530.445521041112;8565.271951884035;8578.528180950108;8581.184787445307;8599.742007739778;4953.405591328991;4957.7769000395065;4993.625478089085;4996.875078123004;5002.974985125051;3959.742616729903;4210.621044106045;4314.870553883388;4337.110892267077;4678.6491013174145;3519.894403167909;3549.8580056797437;3612.3497770401054;3617.4935509028755;3628;998.7802683409639;1018.032868774667;1081.561967403203;1096.4956120184804;1105.0938230650788;790.4112733405896;818.0428898189133;891.3537685178323;895.7715782475427;924.8797656304772;5388.137897936312;5533.501984821385;5604.6356986796545;5765.769369225268;5941.405859414024;970;979.4025644356936;981.9607215711292;1003.6888564545014;1031.0102301790216;3636.381815091441;3643.6720695137565;3743.15520204641;3832.846686132579;3836.1943991761486;9598.856017159747;9829.557669904729;9951.004899510135;9956.551955162035;9966.80066398668;9809.313348065709;9887.209023278121;9897.208223342115;9908.405495670282;9923.900760992297;7564.741035856574;7582.165961744204;7731.536107833548;7777;7792.532448053135;5306.9203961941375;5368.1679339702405;5428.348598168245;5586.16207568868;5604.943950560442;9132.726018219464;9219.539023048686;9220.677276295566;9284.35009549352;9301.813963720553;8467.49195048299;8815.691450799191;9225.815483690423;9278.025807290138;9312.860307095534;6586.901196481961;6786.626735529396;6792.456603471809;7086.822829429306;7097.254788247366;7727.729529466442;7733.419993500397;7750.263724946121;7827.608619569112;7836.568988705676;6841.213260475109;6872.450203983769;6895.965520172367;6897.55165914234;6973.058637084132;6783.253842077466;6889.483288753464;6919.965400172967;6931.306869313028;7004.194517630334;6516.315786842313;6607.933920660732;6628.370304821035;6630.9668451657435;6659.134312539339;4657.67396282257;4747.264174053027;4753.738544380092;4759.76201189946;4821.975890120527;4718.457377401643;4777.402824646919;4806.975965120152;4950.257461380822;4970.25446183061 05:28:09 INFO - PROCESS | 1650 | |4;prototype.html;283.11867639535274;286.1715334107758;294.92184571088666;298.2305651419338;302.164515115705;4935.470004298665;4965.9751701241485;5001.42483614384;5028.296038554606;5028.798848046083;4317.740935543869;4566.817327306912;4803.351547541081;4920.852374428764;4946.332245146904;3201.4237437261286;3633;3835.846566137351;3853.9229215415676;3872.244912242114;3065.7700672449546;3711.795851228181;3842.865499707511;3883.864064757734;3898.5126859142606;3543.273628906081;3601.758417424326;3642.963570364302;3759.642833930786;3800.9474883562843;3504.28417976068;3607.3745885385283;3696.815159242027;3826.4642949987024;3845.250176215639;2907.636545431821;3317.5023746437987;3329.766916315859;3333.4666453367345;3334.9333013339597;4835.9032819343765;4856.975715121402;4859.562639362474;4860.368152140234;4865.951340486586;3959.802009899522;4230.576942305775;4265.466816647919;4282.528921818597;4303.913921721548;5191.896162076775;5218.321618189649;5219.416388887487;5230.267762513251;5236.502532259429;3122.703343182394;3199.8240096794666;3285.014495651295;3588.085038315239;3593.3711600469815;5306.416294207634;5439.510444060014;5506.697131657757;5534.667919924857;5550.807431064707;3089.9536506952413;3261.423475454412;3308.8180150091976;3621.203335266237;3635.636436356343;3118.766092543068;3509.859605615798;3676.558812942464;3701.3152566775234;3704.8888533344043;3821.503204583442;4286.485621725351;4288.678349123766;4294.947737804196;4298.978505107517;4205.957940420557;4592.357070010201;4961.330220420197;5287.101192797234;5324.361076670824;1094.316052467208;1179.775842589905;1191.713988642737;1191.7308066908888;1196.8444102266824;681.6046692918096;765.7434759355679;776.3633820267327;818.3862103422433;826.6569373709963;4451.866444006685;4641.930371044437;4653.86038418848;4692.155412025868;4715.858524244278;4362.978185109054;4783.617310615143;5085.669431486942;5171.663841850268;5188.558972487305;4092.6112019358116;4246.957530424718;4429.822807087745;4542.318652202196;4659.184642687516;2312.138728323682;2492.9750702493106;2656.220332377312;2679.8928042878065;2680.9356795267536;4664.836730714409;4732.053589282157;4820.39745031871;5003.5997120230295;5242.757242757243;4345.630621397154;4783.617310615143;5147.56245719118;5180.378354597397;5181.4300426953005;4834.492378300298;4888.291197776373;5210.348706411699;5525.309336332958;5597.972010140004;5185.455527169593;5413.377461591967;5415.613353183933;5439.918401223906;5441.5102640762525;5164.638475306692;5348.839534813962;5353.4646535346155;5419.160030195326;5464.262324586289;4460.420145381209;5238.528532432099;5599.132134519156;5609.467100625434;5615.6630602164005;3115.766317526149;3617.656322649344;3629.2378600493134;3631.4734363516613;3636.381815091388;5236.522773029005;5377.354717433933;5436.864078398072;5446.012205544868;5473.67157970523;4547.3633691282585;4633.686913856609;5267.9736601316745;5280.313559237275;5344.225087362389;5185.688858668492;5211;5224.660397074178;5230.855349306766;5284.709340986283;4757.738324392191;4845.95154048455;5056.91276375581;5123.666961647481;5167.793288268427;4667.906641867212;5302.549283310807;5306.761195746123;5323.387810401853;5331.493508116722;4587.541245875519;5207.359681700253;5223.649357788934;5239.738013099253;5240.71176085319 05:28:09 INFO - PROCESS | 1650 | |5;yui.html;706.1667232665453;739.4971419434786;752.1049950558835;752.3905636434489;781.8084569280527;14069.366878490468;14117.717645647093;14430;14450.132992020486;14455.783163252547;14195;14756.704865902702;14783.852161478382;15103.471378501754;15130.470433534829;665.1486097794806;681.9045333653268;816.8039670479102;830.4228561150003;858.7380848841101;834.9206825351582;836.9037560680508;845.4800297816829;845.5096044294295;850.3027517435675;895.5387975192781;900.3607438718518;900.8153328567628;901.0178904993555;902.8058967322024;699.9745373028537;722.949393542447;895.4269267668665;896.3098414221046;900.9189172974399;535.8794271288967;538.2195816066719;544.6432586655746;643.906633538139;664.1598378051768;13876.375563099684;14093.859061409357;14319;14342.569722908329;14344.354504047344;13170.473181072746;13351.532696355582;13384.488465246694;13461.932690336485;13490.79763803534;12091.637250882488;12302;12313.876861231452;12404.56584019564;12751.74496510074;4392.187445322594;4403.3174857897075;4443.7111587746695;4443.911121777546;4449.755263460508;71806;85916.7112493313;86487.56756216178;91578.33686652526;91717;10328.896711032794;10470.947645261725;10566.471676416148;10628.681139565826;10670.57317707283;19768.90115549413;20075;20215.696764548542;20241.595168096555;20323.695144572845;7174.533655312388;7297.1608265049135;7398.4081273497995;7571.432142589328;7710.228977102357;4116.485439320085;4140.979295103505;4143.91712165755;4184.309588917856;4353.847615333448;3245.7078862902454;3310.6095439915293;3356.0267522418712;3356.9328613427597;3641.8361173747244;684.2439104789236;689.1764341611726;690.1752405875031;691.1188235000415;703.022798310349;605.4732382826969;636.2110982381813;654.6203202142841;658.2726087673148;682.7951614515625;8960.417572857743;9124.361294709306;9164.90835091654;9332.95333523328;9476.60824556833;635.5074817016849;637.6461064109463;665.8368699668612;668.2248591633681;671.828683685657;3527.664871837171;4501.707389019703;4550;4583.747893865802;4648.883777905579;67744.6612766933;68145;68760;69403.65298173476;112692.43653781679;63733.68133159397;74261.6286918562;80138.19861801424;84284.73572896411;87629;76099.23900760921;79679.20320796718;80624.59687701524;82150.35699286101;84606;9150.084991500797;9650.276229282692;9679.867393605415;9718.289461199096;9719.368241064309;52513.47486525086;53134;54304.72847635736;54452;55996.440035599124;69006.9648955256;70391.29608704052;72044;73374.63312683403;73634.63182684052;11690.649280521598;11698.532058717556;11993.940030299793;12128.454219559964;12209.33719884049;13450.730985380042;13827;13940.651483712989;14013.859861401255;14049.789253161007;12683.80974285368;12787.680307992374;12819.16675416095;13045.021623378474;13512.391942362421;12354.691132721753;12782.61652150437;12802.423890925116;13117.868821312046;13337.571266811037;12316.938415307866;12484.438200280827;12916.160449570747;12999.41502632365;13300.268485233406;10634.208840486855;10672.679819605113;10705.678829635124;11034.337939723642;11296.717582060186;11121.999020088231;11213.327200368005;11350.886491134983;11365.829512557484;11411.543538258376 05:28:09 INFO - PROCESS | 1650 | __end_tp_report 05:28:09 INFO - PROCESS | 1650 | __start_cc_report 05:28:09 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1701 05:28:09 INFO - PROCESS | 1650 | __end_cc_report 05:28:09 INFO - PROCESS | 1650 | __startTimestamp1480512489192__endTimestamp 05:28:09 INFO - PROCESS | 1650 | 05:28:09 INFO - PROCESS | 1650 | ------- Summary: start ------- 05:28:09 INFO - PROCESS | 1650 | Number of tests: 6 05:28:09 INFO - PROCESS | 1650 | 05:28:09 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5708.63 Median:5890.50 stddev:633.30 (10.8%) stddev-sans-first:606.56 05:28:09 INFO - PROCESS | 1650 | Values: 3306.6 3310.6 3373.3 3385.8 3399.9 5656.6 5672.9 5677.2 5683.5 5699.4 5576.7 5649.3 5654.2 5669.5 5694.7 5148.6 5152.3 5174.1 5201.9 5223.7 5180.7 5181.2 5193.5 5257.1 5328.9 5204.9 5216.6 5217.5 5237.4 5239.6 5216.2 5271.0 5276.6 5281.0 5315.9 5091.2 5122.5 5176.6 5178.1 5220.0 5562.3 5569.2 5580.6 5606.0 5643.0 6123.0 6179.9 6196.7 6257.4 6267.8 6267.5 6288.8 6298.8 6318.4 6328.9 5636.9 5694.0 5694.3 5697.5 5754.7 6299.5 6338.7 6341.3 6352.1 6371.0 6126.9 6130.2 6136.9 6149.2 6157.0 5168.5 6108.5 6117.9 6125.8 6145.0 6123.4 6170.1 6185.1 6189.4 6197.3 6142.6 6169.7 6199.7 6204.5 6210.4 4803.5 4818.6 4836.1 4903.6 4918.9 4385.0 4399.6 4406.4 4407.2 4423.6 5624.8 5629.8 5633.8 5648.7 5656.9 6108.7 6163.8 6169.8 6187.6 6190.5 5596.5 5660.5 5664.8 5703.7 5755.4 5350.6 5357.8 5360.7 5381.0 5383.6 6214.5 6278.2 6290.4 6311.3 6314.0 6131.6 6190.5 6194.5 6206.3 6209.9 6109.2 6285.2 6289.2 6291.5 6300.0 6121.4 6143.8 6159.9 6191.1 6229.6 6048.5 6268.2 6280.7 6286.6 6291.2 6062.8 6065.8 6076.1 6082.1 6102.7 5873.1 5893.9 5895.3 5923.9 6002.7 5830.6 5832.6 5862.6 5881.5 5938.0 6075.2 6079.5 6082.6 6095.7 6105.1 5746.1 5890.5 5922.4 5925.8 5964.2 05:28:09 INFO - PROCESS | 1650 | 05:28:09 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:67628.70 Median:21123.76 stddev:149422.98 (707.4%) stddev-sans-first:149756.97 05:28:09 INFO - PROCESS | 1650 | Values: 431.8 469.0 523.7 526.2 529.1 29744.6 31259.4 36252.6 39888.0 40067.6 40121.0 40432.0 40476.4 40576.6 40745.6 9162.2 9331.0 9344.2 9358.9 9370.0 9285.3 9382.9 9386.1 9391.2 9395.0 9331.0 9393.1 9405.2 9428.4 9453.9 9312.3 9348.4 9373.0 9378.4 9383.2 9228.9 9301.2 9302.5 9331.8 9338.4 38173.2 38512.8 38535.4 38610.8 38621.8 3460.2 3777.2 3793.3 3827.3 3834.7 27712.3 27747.6 27754.4 28861.7 29632.7 3073.8 3133.1 3219.7 3306.1 3315.4 436018.0 437458.0 437725.0 440221.0 440492.0 55140.7 55325.7 55419.4 55475.0 55504.7 56696.4 56715.9 56929.7 56961.0 56962.4 15579.7 15801.7 15834.8 15836.2 15847.1 12295.0 12360.1 12391.8 12393.1 12417.9 12620.6 14794.2 14994.4 15003.0 15175.5 6127.4 6268.6 6286.2 6313.2 6314.4 862.9 890.8 1010.9 1015.3 1040.0 28511.6 28536.3 29087.7 29160.6 29231.0 1016.0 1039.6 1092.7 1092.7 1137.5 14315.6 14749.1 14820.1 15139.0 15186.9 659338.0 659980.0 661073.0 664122.0 665068.0 55616.9 55619.4 55638.7 55707.4 55824.7 1206.8 1256.6 1311.3 1351.6 1375.3 28824.1 29516.7 29625.9 29683.0 30414.8 42836.8 44137.9 46613.0 47218.3 47364.5 549004.0 552684.0 552784.0 553449.0 553692.2 32517.8 33338.5 37312.0 38220.0 38412.8 42657.0 43627.1 43628.1 43645.0 44375.3 34440.5 36952.8 36999.3 37027.3 37909.6 36302.3 36387.9 36752.8 37055.0 37484.4 35059.5 36477.0 36725.3 36948.0 37107.0 27837.9 31120.5 32806.3 32858.3 33038.0 24579.4 24637.5 24926.5 25155.7 25204.9 05:28:09 INFO - PROCESS | 1650 | 05:28:09 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:34784.34 Median:24600.44 stddev:102644.16 (417.2%) stddev-sans-first:102899.51 05:28:09 INFO - PROCESS | 1650 | Values: 336.0 346.1 346.4 360.5 361.2 15077.3 15788.0 15967.3 19812.9 19855.2 18322.5 19667.9 19671.4 19672.6 19719.4 5512.3 5768.5 5776.6 5794.6 5805.4 3995.3 4254.9 6124.7 6467.6 6483.1 6286.2 6362.5 6444.5 6459.0 6467.8 6343.6 6460.6 6463.9 6479.6 6486.1 5298.0 5506.5 5535.6 5557.5 5581.4 23829.0 24546.9 24654.0 24899.9 25008.5 2213.2 2368.1 2386.3 2424.9 2580.9 10740.8 14894.9 15179.2 15258.8 15570.5 1774.5 1844.8 1846.6 1876.1 1882.3 90929.0 101802.0 108380.9 111287.0 120032.0 10695.9 11048.5 11061.0 11141.8 11220.5 14227.4 14237.9 14238.3 14240.4 14251.5 7986.8 8082.5 8145.6 8299.3 8313.3 7966.7 7993.2 8097.7 8098.6 8235.3 3613.8 4401.6 4418.7 4478.2 4530.8 1876.6 1878.0 1882.1 1883.3 1910.0 801.8 870.8 882.9 923.0 1024.4 20478.4 20683.1 20880.9 20935.6 21239.0 898.6 906.5 910.3 963.6 975.7 10093.9 10821.7 10838.8 10852.8 10886.7 606038.0 609280.0 611746.0 624100.0 636468.0 125314.0 126761.0 127255.4 131121.3 133009.0 974.8 978.1 1001.3 1008.8 1009.5 17708.5 17899.1 17942.5 18118.5 18153.5 46183.1 46587.8 51271.0 52472.7 53539.9 55975.7 71589.9 73594.6 74771.6 76365.0 6799.2 7697.5 7742.1 7753.1 8137.7 9986.3 12247.7 12351.7 12451.8 12544.6 10503.4 10589.6 11033.4 11089.2 11129.5 10490.0 10602.0 10633.5 10939.4 11024.4 9000.4 10644.1 11212.9 11216.8 11219.8 7073.6 7196.7 7250.5 7261.9 7493.2 7754.4 10611.7 10613.3 11012.3 11079.8 05:28:09 INFO - PROCESS | 1650 | 05:28:09 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5295.98 Median:5480.93 stddev:2907.14 (53.0%) stddev-sans-first:2899.89 05:28:09 INFO - PROCESS | 1650 | Values: 1313.8 1371.8 1439.9 1473.8 1806.9 7299.5 7673.3 7904.4 8173.3 8197.8 8092.2 8132.5 8140.6 8151.7 8169.6 2231.1 2267.0 2268.2 2274.6 2276.8 2221.0 2263.6 2271.7 2272.2 2279.1 2233.3 2233.8 2255.1 2262.0 2265.8 2193.8 2215.2 2253.0 2254.0 2254.8 2238.1 2266.7 2267.1 2268.3 2274.7 7985.4 8111.6 8147.0 8161.6 8202.8 4918.8 5108.3 5112.7 5117.9 5126.8 3240.2 3278.3 3301.6 3356.7 3358.9 959.8 984.8 988.7 995.4 1011.9 10533.4 10539.6 10613.6 10761.1 10771.5 4098.6 4176.0 4293.1 4294.7 4351.6 8530.4 8565.3 8578.5 8581.2 8599.7 4953.4 4957.8 4993.6 4996.9 5003.0 3959.7 4210.6 4314.9 4337.1 4678.6 3519.9 3549.9 3612.3 3617.5 3628.0 998.8 1018.0 1081.6 1096.5 1105.1 790.4 818.0 891.4 895.8 924.9 5388.1 5533.5 5604.6 5765.8 5941.4 970.0 979.4 982.0 1003.7 1031.0 3636.4 3643.7 3743.2 3832.8 3836.2 9598.9 9829.6 9951.0 9956.6 9966.8 9809.3 9887.2 9897.2 9908.4 9923.9 7564.7 7582.2 7731.5 7777.0 7792.5 5306.9 5368.2 5428.3 5586.2 5604.9 9132.7 9219.5 9220.7 9284.4 9301.8 8467.5 8815.7 9225.8 9278.0 9312.9 6586.9 6786.6 6792.5 7086.8 7097.3 7727.7 7733.4 7750.3 7827.6 7836.6 6841.2 6872.5 6896.0 6897.6 6973.1 6783.3 6889.5 6920.0 6931.3 7004.2 6516.3 6607.9 6628.4 6631.0 6659.1 4657.7 4747.3 4753.7 4759.8 4822.0 4718.5 4777.4 4807.0 4950.3 4970.3 05:28:09 INFO - PROCESS | 1650 | 05:28:09 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4195.29 Median:4704.01 stddev:1309.68 (27.8%) stddev-sans-first:1280.01 05:28:09 INFO - PROCESS | 1650 | Values: 283.1 286.2 294.9 298.2 302.2 4935.5 4966.0 5001.4 5028.3 5028.8 4317.7 4566.8 4803.4 4920.9 4946.3 3201.4 3633.0 3835.8 3853.9 3872.2 3065.8 3711.8 3842.9 3883.9 3898.5 3543.3 3601.8 3643.0 3759.6 3800.9 3504.3 3607.4 3696.8 3826.5 3845.3 2907.6 3317.5 3329.8 3333.5 3334.9 4835.9 4857.0 4859.6 4860.4 4866.0 3959.8 4230.6 4265.5 4282.5 4303.9 5191.9 5218.3 5219.4 5230.3 5236.5 3122.7 3199.8 3285.0 3588.1 3593.4 5306.4 5439.5 5506.7 5534.7 5550.8 3090.0 3261.4 3308.8 3621.2 3635.6 3118.8 3509.9 3676.6 3701.3 3704.9 3821.5 4286.5 4288.7 4294.9 4299.0 4206.0 4592.4 4961.3 5287.1 5324.4 1094.3 1179.8 1191.7 1191.7 1196.8 681.6 765.7 776.4 818.4 826.7 4451.9 4641.9 4653.9 4692.2 4715.9 4363.0 4783.6 5085.7 5171.7 5188.6 4092.6 4247.0 4429.8 4542.3 4659.2 2312.1 2493.0 2656.2 2679.9 2680.9 4664.8 4732.1 4820.4 5003.6 5242.8 4345.6 4783.6 5147.6 5180.4 5181.4 4834.5 4888.3 5210.3 5525.3 5598.0 5185.5 5413.4 5415.6 5439.9 5441.5 5164.6 5348.8 5353.5 5419.2 5464.3 4460.4 5238.5 5599.1 5609.5 5615.7 3115.8 3617.7 3629.2 3631.5 3636.4 5236.5 5377.4 5436.9 5446.0 5473.7 4547.4 4633.7 5268.0 5280.3 5344.2 5185.7 5211.0 5224.7 5230.9 5284.7 4757.7 4846.0 5056.9 5123.7 5167.8 4667.9 5302.5 5306.8 5323.4 5331.5 4587.5 5207.4 5223.6 5239.7 5240.7 05:28:09 INFO - PROCESS | 1650 | 05:28:09 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:18967.24 Median:28581.18 stddev:26052.63 (91.2%) stddev-sans-first:26089.63 05:28:09 INFO - PROCESS | 1650 | Values: 706.2 739.5 752.1 752.4 781.8 14069.4 14117.7 14430.0 14450.1 14455.8 14195.0 14756.7 14783.9 15103.5 15130.5 665.1 681.9 816.8 830.4 858.7 834.9 836.9 845.5 845.5 850.3 895.5 900.4 900.8 901.0 902.8 700.0 722.9 895.4 896.3 900.9 535.9 538.2 544.6 643.9 664.2 13876.4 14093.9 14319.0 14342.6 14344.4 13170.5 13351.5 13384.5 13461.9 13490.8 12091.6 12302.0 12313.9 12404.6 12751.7 4392.2 4403.3 4443.7 4443.9 4449.8 71806.0 85916.7 86487.6 91578.3 91717.0 10328.9 10470.9 10566.5 10628.7 10670.6 19768.9 20075.0 20215.7 20241.6 20323.7 7174.5 7297.2 7398.4 7571.4 7710.2 4116.5 4141.0 4143.9 4184.3 4353.8 3245.7 3310.6 3356.0 3356.9 3641.8 684.2 689.2 690.2 691.1 703.0 605.5 636.2 654.6 658.3 682.8 8960.4 9124.4 9164.9 9333.0 9476.6 635.5 637.6 665.8 668.2 671.8 3527.7 4501.7 4550.0 4583.7 4648.9 67744.7 68145.0 68760.0 69403.7 112692.4 63733.7 74261.6 80138.2 84284.7 87629.0 76099.2 79679.2 80624.6 82150.4 84606.0 9150.1 9650.3 9679.9 9718.3 9719.4 52513.5 53134.0 54304.7 54452.0 55996.4 69007.0 70391.3 72044.0 73374.6 73634.6 11690.6 11698.5 11993.9 12128.5 12209.3 13450.7 13827.0 13940.7 14013.9 14049.8 12683.8 12787.7 12819.2 13045.0 13512.4 12354.7 12782.6 12802.4 13117.9 13337.6 12316.9 12484.4 12916.2 12999.4 13300.3 10634.2 10672.7 10705.7 11034.3 11296.7 11122.0 11213.3 11350.9 11365.8 11411.5 05:28:09 INFO - PROCESS | 1650 | -------- Summary: end -------- 05:28:09 INFO - PROCESS | 1650 | 05:28:09 INFO - TEST-INFO | 1650: exit 0 05:28:09 INFO - TEST-OK | dromaeo_css | took 1120620ms 05:28:09 INFO - TEST-START | kraken 05:28:09 INFO - operating with platform_type : mac_ 05:28:09 INFO - Initialising browser for kraken test... 05:28:09 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpXJZEqq/profile http://localhost:49245/getInfo.html) 05:28:12 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 05:28:12 INFO - PROCESS | 1654 | colorDepth:24 05:28:12 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 05:28:12 INFO - PROCESS | 1654 | __metrics 05:28:12 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:28:12 INFO - PROCESS | 1654 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:28:13 INFO - TEST-INFO | 1654: exit 0 05:28:13 INFO - Browser initialized. 05:28:13 INFO - Running cycle 1/1 for kraken test... 05:28:13 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpXJZEqq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:26 INFO - PROCESS | 1655 | RSS: Main: 222674944 05:28:26 INFO - PROCESS | 1655 | 05:28:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:29 INFO - PROCESS | 1655 | RSS: Main: 273588224 05:28:29 INFO - PROCESS | 1655 | 05:28:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 05:28:33 INFO - PROCESS | 1655 | RSS: Main: 284733440 05:28:33 INFO - PROCESS | 1655 | 05:28:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 05:28:36 INFO - PROCESS | 1655 | RSS: Main: 381435904 05:28:36 INFO - PROCESS | 1655 | 05:28:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 05:28:39 INFO - PROCESS | 1655 | RSS: Main: 282775552 05:28:39 INFO - PROCESS | 1655 | 05:28:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:28:42 INFO - PROCESS | 1655 | RSS: Main: 284659712 05:28:42 INFO - PROCESS | 1655 | 05:28:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 05:28:45 INFO - PROCESS | 1655 | RSS: Main: 325492736 05:28:45 INFO - PROCESS | 1655 | 05:28:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 05:28:49 INFO - PROCESS | 1655 | RSS: Main: 326488064 05:28:49 INFO - PROCESS | 1655 | 05:28:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 05:28:52 INFO - PROCESS | 1655 | RSS: Main: 322379776 05:28:52 INFO - PROCESS | 1655 | 05:28:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:28:54 INFO - PROCESS | 1655 | RSS: Main: 300269568 05:28:54 INFO - PROCESS | 1655 | 05:28:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:28:57 INFO - PROCESS | 1655 | RSS: Main: 299601920 05:28:57 INFO - PROCESS | 1655 | 05:28:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:28:59 INFO - PROCESS | 1655 | RSS: Main: 303362048 05:28:59 INFO - PROCESS | 1655 | 05:29:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:03 INFO - PROCESS | 1655 | RSS: Main: 320401408 05:29:03 INFO - PROCESS | 1655 | 05:29:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:07 INFO - PROCESS | 1655 | RSS: Main: 313978880 05:29:07 INFO - PROCESS | 1655 | 05:29:08 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 05:29:09 INFO - PROCESS | 1655 | RSS: Main: 358260736 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | __start_tp_report 05:29:09 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 05:29:09 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 05:29:09 INFO - PROCESS | 1655 | |i|pagename|runs| 05:29:09 INFO - PROCESS | 1655 | |0;ai-astar;116;116;116;115;115;117;125;170;145;119 05:29:09 INFO - PROCESS | 1655 | |1;audio-beat-detection;147;114;113;146;114;112;113;114;162;143 05:29:09 INFO - PROCESS | 1655 | |2;audio-dft;183;184;177;184;182;182;188;184;185;184 05:29:09 INFO - PROCESS | 1655 | |3;audio-fft;79;58;61;98;87;113;71;59;81;58 05:29:09 INFO - PROCESS | 1655 | |4;audio-oscillator;89;72;95;69;68;68;89;68;69;90 05:29:09 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;86;84;87;84;86;84;88;84;86;84 05:29:09 INFO - PROCESS | 1655 | |6;imaging-darkroom;92;93;93;93;109;151;93;92;91;91 05:29:09 INFO - PROCESS | 1655 | |7;imaging-desaturate;84;83;80;79;83;81;84;80;76;81 05:29:09 INFO - PROCESS | 1655 | |8;json-parse-financial;56;56;54;53;57;58;59;58;56;57 05:29:09 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;65;72;67;74;72;70;71;70;69;72 05:29:09 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;85;85;89;84;85;84;85;84;89;85 05:29:09 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;148;163;170;147;151;156;166;153;209;171 05:29:09 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;165;166;166;164;168;173;166;165;168;169 05:29:09 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;68;67;66;68;67;67;68;66;66 05:29:09 INFO - PROCESS | 1655 | __end_tp_report 05:29:09 INFO - PROCESS | 1655 | __start_cc_report 05:29:09 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2000 05:29:09 INFO - PROCESS | 1655 | __end_cc_report 05:29:09 INFO - PROCESS | 1655 | __startTimestamp1480512549173__endTimestamp 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | ------- Summary: start ------- 05:29:09 INFO - PROCESS | 1655 | Number of tests: 14 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:125.40 Median:118.00 stddev:18.15 (15.4%) stddev-sans-first:18.93 05:29:09 INFO - PROCESS | 1655 | Values: 116.0 116.0 116.0 115.0 115.0 117.0 125.0 170.0 145.0 119.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:127.80 Median:128.50 stddev:19.32 (15.0%) stddev-sans-first:19.20 05:29:09 INFO - PROCESS | 1655 | Values: 147.0 114.0 113.0 146.0 114.0 112.0 113.0 114.0 162.0 143.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:2.79 (1.5%) stddev-sans-first:2.96 05:29:09 INFO - PROCESS | 1655 | Values: 183.0 184.0 177.0 184.0 182.0 182.0 188.0 184.0 185.0 184.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:76.50 Median:75.00 stddev:18.83 (25.1%) stddev-sans-first:19.95 05:29:09 INFO - PROCESS | 1655 | Values: 79.0 58.0 61.0 98.0 87.0 113.0 71.0 59.0 81.0 58.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:80.50 stddev:11.41 (14.2%) stddev-sans-first:11.35 05:29:09 INFO - PROCESS | 1655 | Values: 89.0 72.0 95.0 69.0 68.0 68.0 89.0 68.0 69.0 90.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:1.49 (1.7%) stddev-sans-first:1.56 05:29:09 INFO - PROCESS | 1655 | Values: 86.0 84.0 87.0 84.0 86.0 84.0 88.0 84.0 86.0 84.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:99.80 Median:92.50 stddev:18.76 (20.3%) stddev-sans-first:19.69 05:29:09 INFO - PROCESS | 1655 | Values: 92.0 93.0 93.0 93.0 109.0 151.0 93.0 92.0 91.0 91.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:81.10 Median:82.00 stddev:2.51 (3.1%) stddev-sans-first:2.44 05:29:09 INFO - PROCESS | 1655 | Values: 84.0 83.0 80.0 79.0 83.0 81.0 84.0 80.0 76.0 81.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:57.00 stddev:1.84 (3.2%) stddev-sans-first:1.94 05:29:09 INFO - PROCESS | 1655 | Values: 56.0 56.0 54.0 53.0 57.0 58.0 59.0 58.0 56.0 57.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:71.50 stddev:2.66 (3.7%) stddev-sans-first:2.05 05:29:09 INFO - PROCESS | 1655 | Values: 65.0 72.0 67.0 74.0 72.0 70.0 71.0 70.0 69.0 72.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:85.00 stddev:1.90 (2.2%) stddev-sans-first:2.01 05:29:09 INFO - PROCESS | 1655 | Values: 85.0 85.0 89.0 84.0 85.0 84.0 85.0 84.0 89.0 85.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:163.40 Median:164.50 stddev:18.29 (11.1%) stddev-sans-first:18.53 05:29:09 INFO - PROCESS | 1655 | Values: 148.0 163.0 170.0 147.0 151.0 156.0 166.0 153.0 209.0 171.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.00 stddev:2.62 (1.6%) stddev-sans-first:2.68 05:29:09 INFO - PROCESS | 1655 | Values: 165.0 166.0 166.0 164.0 168.0 173.0 166.0 165.0 168.0 169.0 05:29:09 INFO - PROCESS | 1655 | 05:29:09 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:0.88 (1.3%) stddev-sans-first:0.87 05:29:09 INFO - PROCESS | 1655 | Values: 68.0 68.0 67.0 66.0 68.0 67.0 67.0 68.0 66.0 66.0 05:29:09 INFO - PROCESS | 1655 | -------- Summary: end -------- 05:29:09 INFO - PROCESS | 1655 | 05:29:10 INFO - TEST-INFO | 1655: exit 0 05:29:10 INFO - TEST-OK | kraken | took 60352ms 05:29:10 INFO - SUITE-END | took 1180s 05:29:10 INFO - Completed test suite (00:19:41) 05:29:10 INFO - javascript benchmark 05:29:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.6362700102986, 3310.635830058694, 3373.2578832656823, 3385.830708464576, 3399.898003059909, 5656.604037717361, 5672.943270567293, 5677.233573467591, 5683.516901063409, 5699.430056994299, 5576.721163941807, 5649.265595472593, 5654.180143879145, 5669.461401166893, 5694.658320500773, 5148.5623721983675, 5152.330197074384, 5174.094533456649, 5201.947980520203, 5223.660462069973, 5180.740962951837, 5181.222816577525, 5193.45468725784, 5257.132573125449, 5328.893422131555, 5204.895902081956, 5216.58267338612, 5217.452167522412, 5237.423883372826, 5239.580833533309, 5216.217567364906, 5270.9736451317885, 5276.604254680876, 5280.973595132038, 5315.867103322448, 5091.23631455281, 5122.487751224885, 5176.611754118456, 5178.14560597501, 5219.973900130513, 5562.276904002494, 5569.192467092249, 5580.609357344987, 5605.971970140164, 5642.971785141089, 6123.0, 6179.907301390483, 6196.690165491707, 6257.43683068526, 6267.780627678009, 6267.4986001119805, 6288.811335659938, 6298.842528936768, 6318.399752023551, 6328.905066424008, 5636.85907852307, 5694.032014557535, 5694.316681998187, 5697.4587414195585, 5754.74103665336, 6299.5275354348605, 6338.651374174465, 6341.302456729756, 6352.14246076783, 6371.0, 6126.877462450726, 6130.233720784902, 6136.9079463808075, 6149.2313460817395, 6156.984097623931, 5168.483151684802, 6108.450239478469, 6117.877642447215, 6125.846853828601, 6145.0475176346845, 6123.38766123393, 6170.136180934673, 6185.103160041769, 6189.381061893865, 6197.25632924052, 6142.6314421134875, 6169.660668663177, 6199.721012554446, 6204.503639708812, 6210.378962103753, 4803.519648035169, 4818.638602104857, 4836.105320515716, 4903.607711383081, 4918.852434426973, 4385.035292235767, 4399.560043995639, 4406.405135306757, 4407.184670835905, 4423.60187583119, 5624.775008999676, 5629.802956896489, 5633.774649014075, 5648.661080335193, 5656.91514627281, 6108.706347747029, 6163.7842675506145, 6169.784057558054, 6187.597806142587, 6190.535709821781, 5596.496315331558, 5660.462256085665, 5664.801731939445, 5703.686297253608, 5755.395683453177, 5350.5987050971335, 5357.8392648220615, 5360.678359298377, 5380.973095134577, 5383.623146379794, 6214.5028397727165, 6278.246610406781, 6290.433860952537, 6311.305756366704, 6314.0, 6131.5601757859595, 6190.50475961931, 6194.535409844189, 6206.348333425106, 6209.906851397323, 6109.222480666452, 6285.151504546828, 6289.150964619901, 6291.496680265659, 6300.0, 6121.418465245794, 6143.757528502154, 6159.907601385893, 6191.071339299141, 6229.626222426669, 6048.546359023179, 6268.216472940882, 6280.685965701605, 6286.591371560834, 6291.245050593775, 6062.787802426894, 6065.8180254592435, 6076.088586712029, 6082.087686847009, 6102.664353460602, 5873.089671100986, 5893.852653683521, 5895.322037965689, 5923.940760592339, 6002.669853158118, 5830.562707797017, 5832.591718579659, 5862.589618726648, 5881.470667639933, 5938.020226662552, 6075.210222671025, 6079.452849243412, 6082.574219804571, 6095.664738439428, 6105.114758360101, 5746.080627099644, 5890.4993075589055, 5922.378150294329, 5925.822225333247, 5964.194833697396], "value": 5667.725528030709, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.7582153993763, 468.98464823656786, 523.706724234429, 526.2395838014071, 529.0926061804007, 29744.55383169254, 31259.374812503764, 36252.637473625255, 39888.002799930015, 40067.599324006755, 40120.99697507556, 40432.0, 40476.39285410721, 40576.594234057724, 40745.59254407455, 9162.221211197057, 9331.0, 9344.205742511895, 9358.859617105749, 9370.0, 9285.303602229826, 9382.85925711115, 9386.10831970965, 9391.20174785144, 9395.013523580028, 9330.953345233263, 9393.107654772786, 9405.247580193569, 9428.434293942351, 9453.905460945405, 9312.25501959845, 9348.392354496937, 9372.95313523435, 9378.437293762396, 9383.24934005278, 9228.907710922873, 9301.209397201246, 9302.53487325631, 9331.813363732756, 9338.393004454689, 38173.23653526914, 38512.807435962925, 38535.42196867049, 38610.841674749805, 38621.841344759705, 3460.2387474755515, 3777.1501412182038, 3793.2982398256413, 3827.27281816454, 3834.6740527055235, 27712.307192320153, 27747.583786243224, 27754.444911101866, 28861.711382886322, 29632.6665300062, 3073.83093929836, 3133.138386943586, 3219.7263232625255, 3306.057773534555, 3315.419801534722, 436018.0, 437458.0, 437725.0, 440221.0, 440492.0, 55140.72429637826, 55325.72337138288, 55419.44580554224, 55475.0, 55504.72247638817, 56696.43303566994, 56715.86568268694, 56929.71535142381, 56961.0, 56962.43037569654, 15579.688406231811, 15801.683966320608, 15834.76247856283, 15836.208189590474, 15847.128407937675, 12295.0, 12360.072994525446, 12391.75216495665, 12393.132480726443, 12417.875821241852, 12620.558280460324, 14794.186319752302, 14994.400223990919, 15003.0, 15175.468858589897, 6127.354224236572, 6268.592541484789, 6286.18279623646, 6313.2424109107205, 6314.368563143649, 862.8921384826897, 890.8396488632109, 1010.9090181883519, 1015.3146626027402, 1039.9844002339971, 28511.57232641512, 28536.286592835346, 29087.7091229085, 29160.56259156114, 29231.0, 1015.9796804063877, 1039.6361273554196, 1092.6503518874042, 1092.6612750047511, 1137.4540220694114, 14315.57053288403, 14749.115053096848, 14820.072680739613, 15139.0, 15186.924065379602, 659338.0, 659980.0, 661073.0, 664122.0, 665068.0, 55616.887662246525, 55619.44380556142, 55638.72180639152, 55707.44292557023, 55824.72087639536, 1206.7767463019195, 1256.6481385212155, 1311.2591385866954, 1351.6215459671307, 1375.339836878304, 28824.135275941757, 29516.704832951396, 29625.85187074051, 29683.0, 30414.84792576023, 42836.78581607072, 44137.89655258644, 46613.0, 47218.29172562346, 47364.52635473601, 549004.0, 552684.0, 552784.0, 553449.0, 553692.2315388398, 32517.837410812794, 33338.499922501665, 37312.0, 38220.0, 38412.847614571605, 42657.0, 43627.127457450035, 43628.12743745171, 43645.0, 44375.33436998383, 34440.48339274963, 36952.81523592365, 36999.260014799016, 37027.25945481021, 37909.62090379061, 36302.34811182208, 36387.90836274916, 36752.816235918646, 37055.0, 37484.43773343456, 35059.474107887894, 36477.0, 36725.265494689425, 36948.0, 37107.0, 27837.860810695816, 31120.533192001687, 32806.34387312288, 32858.34283314368, 33038.00885973425, 24579.385515361544, 24637.50724985526, 24926.501469970863, 25155.748442516073, 25204.873975630006], "value": 19198.674056421034, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.99160020999454, 346.0690741904277, 346.4232053630705, 360.46111063959387, 361.1963381476214, 15077.321520531576, 15788.0, 15967.281472333743, 19812.910289934047, 19855.205791768312, 18322.541936451562, 19667.901660491676, 19671.409857704293, 19672.606547869033, 19719.40841774749, 5512.255845460862, 5768.5385169186575, 5776.59563830532, 5794.5943783935145, 5805.390434004411, 3995.320795464778, 4254.936175957348, 6124.693765311739, 6467.579607325532, 6483.092367068614, 6286.182796236483, 6362.459190968773, 6444.516661250402, 6459.0, 6467.83830404239, 6343.619382837044, 6460.644664543448, 6463.903041454382, 6479.578827376253, 6486.124373209604, 5297.973510132425, 5506.504414602665, 5535.612507124503, 5557.472040156179, 5581.413951535071, 23829.0, 24546.87726561356, 24654.0, 24899.875500622562, 25008.499830003297, 2213.1700612270397, 2368.0764501844296, 2386.3437554672605, 2424.854508729482, 2580.87095645217, 10740.838887416696, 14894.925525372411, 15179.16514591697, 15258.771118433233, 15570.532884013497, 1774.4942691333038, 1844.7786265648208, 1846.621442604263, 1876.0525934403177, 1882.2565086790642, 90929.0, 101802.0, 108380.91619083787, 111287.0, 120031.99999999913, 10695.893041069645, 11048.502817373237, 11061.0, 11141.777164456666, 11220.495077721358, 14227.430902763981, 14237.857621423653, 14238.28808559568, 14240.359183836752, 14251.501197458247, 7986.7603971880935, 8082.515049096955, 8145.5927203639585, 8299.29455996235, 8313.293370063491, 7966.721164759206, 7993.200679931961, 8097.676092956333, 8098.595070246464, 8235.29999950011, 3613.7831730096277, 4401.581849724271, 4418.652311045098, 4478.193925093515, 4530.818767249273, 1876.634056358997, 1877.9530511737044, 1882.1059996501551, 1883.2561138350275, 1909.980900191008, 801.8276070644723, 870.7537089038593, 882.9028806831243, 923.0400383600979, 1024.3904876598413, 20478.385648430572, 20683.06926188325, 20880.895595521924, 20935.581288374146, 21239.0, 898.5866501409295, 906.5467266366817, 910.2854259406466, 963.5760265483165, 975.6926568131016, 10093.899061009442, 10821.729456763644, 10838.837417438745, 10852.782944341227, 10886.67339979802, 606038.0, 609279.9536002381, 611746.0, 624100.0, 636468.0, 125314.0, 126761.0, 127255.3637231808, 131121.34439327742, 133009.0, 974.848898420746, 978.1099199728355, 1001.3491230700104, 1008.7931973945324, 1009.5103874620803, 17708.468745937644, 17899.105044747972, 17942.461726148238, 18118.45644630663, 18153.546161346072, 46183.076338473715, 46587.76706116448, 51271.0, 52472.688182794205, 53539.8996875773, 55975.720121399136, 71589.91485723564, 73594.63202683952, 74771.62614186894, 76365.0, 6799.184097908283, 7697.538147711155, 7742.109657389472, 7753.10839253493, 8137.715179968673, 9986.300958932805, 12247.69380765488, 12351.69120771988, 12451.82478467985, 12544.623661290176, 10503.42231177262, 10589.629362972259, 11033.44832758375, 11089.22375433712, 11129.499172537417, 10490.0, 10602.0, 10633.468326583485, 10939.398333091756, 11024.393658348547, 9000.36997410175, 10644.095251903675, 11212.887871121511, 11216.83174752363, 11219.831702524305, 7073.610951397723, 7196.712131514891, 7250.456215783732, 7261.854762904818, 7493.213212612597, 7754.379649627904, 10611.681649550526, 10613.31013484152, 11012.348147777897, 11079.833802492807], "value": 9357.366587288636, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.763522565939, 1371.8353797544296, 1439.8776104031158, 1473.801036860024, 1806.9457916262509, 7299.489035767492, 7673.347765439938, 7904.367650587954, 8173.346132309416, 8197.754067377973, 8092.231238032392, 8132.5120492770475, 8140.5929703514885, 8151.673933042686, 8169.550674712888, 2231.0518029837335, 2267.0478399072344, 2268.2288022072494, 2274.624686926655, 2276.7950884420397, 2220.9888950555223, 2263.6151854184745, 2271.7046783918067, 2272.1820144747876, 2279.0655831109248, 2233.2665727072654, 2233.810126139272, 2255.052877791323, 2262.049939025613, 2265.7734226577295, 2193.7696541863115, 2215.235743668448, 2252.974896422124, 2253.954920901589, 2254.8083412909923, 2238.0823862216575, 2266.7279926408937, 2267.0931627349028, 2268.2854900706225, 2274.715660542432, 7985.441019128663, 8111.553864537448, 8146.959265203695, 8161.63272652732, 8202.794930126734, 4918.754062296871, 5108.3359163308905, 5112.696262453051, 5117.923231151536, 5126.846194614131, 3240.1918717415897, 3278.327942771726, 3301.570795796531, 3356.748243881694, 3358.9160270993175, 959.8464245720721, 984.7931934293807, 988.6539711101129, 995.4276291132628, 1011.8735158105237, 10533.367997920073, 10539.57841686324, 10613.628523001658, 10761.085307748772, 10771.461426928701, 4098.631123198867, 4175.97912010438, 4293.0984493256765, 4294.742315461082, 4351.608355248043, 8530.445521041112, 8565.271951884035, 8578.528180950108, 8581.184787445307, 8599.742007739778, 4953.405591328991, 4957.7769000395065, 4993.625478089085, 4996.875078123004, 5002.974985125051, 3959.742616729903, 4210.621044106045, 4314.870553883388, 4337.110892267077, 4678.6491013174145, 3519.894403167909, 3549.8580056797437, 3612.3497770401054, 3617.4935509028755, 3628.0, 998.7802683409639, 1018.032868774667, 1081.561967403203, 1096.4956120184804, 1105.0938230650788, 790.4112733405896, 818.0428898189133, 891.3537685178323, 895.7715782475427, 924.8797656304772, 5388.137897936312, 5533.501984821385, 5604.6356986796545, 5765.769369225268, 5941.405859414024, 970.0, 979.4025644356936, 981.9607215711292, 1003.6888564545014, 1031.0102301790216, 3636.381815091441, 3643.6720695137565, 3743.15520204641, 3832.846686132579, 3836.1943991761486, 9598.856017159747, 9829.557669904729, 9951.004899510135, 9956.551955162035, 9966.80066398668, 9809.313348065709, 9887.209023278121, 9897.208223342115, 9908.405495670282, 9923.900760992297, 7564.741035856574, 7582.165961744204, 7731.536107833548, 7777.0, 7792.532448053135, 5306.9203961941375, 5368.1679339702405, 5428.348598168245, 5586.16207568868, 5604.943950560442, 9132.726018219464, 9219.539023048686, 9220.677276295566, 9284.35009549352, 9301.813963720553, 8467.49195048299, 8815.691450799191, 9225.815483690423, 9278.025807290138, 9312.860307095534, 6586.901196481961, 6786.626735529396, 6792.456603471809, 7086.822829429306, 7097.254788247366, 7727.729529466442, 7733.419993500397, 7750.263724946121, 7827.608619569112, 7836.568988705676, 6841.213260475109, 6872.450203983769, 6895.965520172367, 6897.55165914234, 6973.058637084132, 6783.253842077466, 6889.483288753464, 6919.965400172967, 6931.306869313028, 7004.194517630334, 6516.315786842313, 6607.933920660732, 6628.370304821035, 6630.9668451657435, 6659.134312539339, 4657.67396282257, 4747.264174053027, 4753.738544380092, 4759.76201189946, 4821.975890120527, 4718.457377401643, 4777.402824646919, 4806.975965120152, 4950.257461380822, 4970.25446183061], "value": 4284.439335975059, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.11867639535274, 286.1715334107758, 294.92184571088666, 298.2305651419338, 302.164515115705, 4935.470004298665, 4965.9751701241485, 5001.42483614384, 5028.296038554606, 5028.798848046083, 4317.740935543869, 4566.817327306912, 4803.351547541081, 4920.852374428764, 4946.332245146904, 3201.4237437261286, 3633.0, 3835.846566137351, 3853.9229215415676, 3872.244912242114, 3065.7700672449546, 3711.795851228181, 3842.865499707511, 3883.864064757734, 3898.5126859142606, 3543.273628906081, 3601.758417424326, 3642.963570364302, 3759.642833930786, 3800.9474883562843, 3504.28417976068, 3607.3745885385283, 3696.815159242027, 3826.4642949987024, 3845.250176215639, 2907.636545431821, 3317.5023746437987, 3329.766916315859, 3333.4666453367345, 3334.9333013339597, 4835.9032819343765, 4856.975715121402, 4859.562639362474, 4860.368152140234, 4865.951340486586, 3959.802009899522, 4230.576942305775, 4265.466816647919, 4282.528921818597, 4303.913921721548, 5191.896162076775, 5218.321618189649, 5219.416388887487, 5230.267762513251, 5236.502532259429, 3122.703343182394, 3199.8240096794666, 3285.014495651295, 3588.085038315239, 3593.3711600469815, 5306.416294207634, 5439.510444060014, 5506.697131657757, 5534.667919924857, 5550.807431064707, 3089.9536506952413, 3261.423475454412, 3308.8180150091976, 3621.203335266237, 3635.636436356343, 3118.766092543068, 3509.859605615798, 3676.558812942464, 3701.3152566775234, 3704.8888533344043, 3821.503204583442, 4286.485621725351, 4288.678349123766, 4294.947737804196, 4298.978505107517, 4205.957940420557, 4592.357070010201, 4961.330220420197, 5287.101192797234, 5324.361076670824, 1094.316052467208, 1179.775842589905, 1191.713988642737, 1191.7308066908888, 1196.8444102266824, 681.6046692918096, 765.7434759355679, 776.3633820267327, 818.3862103422433, 826.6569373709963, 4451.866444006685, 4641.930371044437, 4653.86038418848, 4692.155412025868, 4715.858524244278, 4362.978185109054, 4783.617310615143, 5085.669431486942, 5171.663841850268, 5188.558972487305, 4092.6112019358116, 4246.957530424718, 4429.822807087745, 4542.318652202196, 4659.184642687516, 2312.138728323682, 2492.9750702493106, 2656.220332377312, 2679.8928042878065, 2680.9356795267536, 4664.836730714409, 4732.053589282157, 4820.39745031871, 5003.5997120230295, 5242.757242757243, 4345.630621397154, 4783.617310615143, 5147.56245719118, 5180.378354597397, 5181.4300426953005, 4834.492378300298, 4888.291197776373, 5210.348706411699, 5525.309336332958, 5597.972010140004, 5185.455527169593, 5413.377461591967, 5415.613353183933, 5439.918401223906, 5441.5102640762525, 5164.638475306692, 5348.839534813962, 5353.4646535346155, 5419.160030195326, 5464.262324586289, 4460.420145381209, 5238.528532432099, 5599.132134519156, 5609.467100625434, 5615.6630602164005, 3115.766317526149, 3617.656322649344, 3629.2378600493134, 3631.4734363516613, 3636.381815091388, 5236.522773029005, 5377.354717433933, 5436.864078398072, 5446.012205544868, 5473.67157970523, 4547.3633691282585, 4633.686913856609, 5267.9736601316745, 5280.313559237275, 5344.225087362389, 5185.688858668492, 5211.0, 5224.660397074178, 5230.855349306766, 5284.709340986283, 4757.738324392191, 4845.95154048455, 5056.91276375581, 5123.666961647481, 5167.793288268427, 4667.906641867212, 5302.549283310807, 5306.761195746123, 5323.387810401853, 5331.493508116722, 4587.541245875519, 5207.359681700253, 5223.649357788934, 5239.738013099253, 5240.71176085319], "value": 3773.7993139340333, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.1667232665453, 739.4971419434786, 752.1049950558835, 752.3905636434489, 781.8084569280527, 14069.366878490468, 14117.717645647093, 14430.0, 14450.132992020486, 14455.783163252547, 14195.0, 14756.704865902702, 14783.852161478382, 15103.471378501754, 15130.470433534829, 665.1486097794806, 681.9045333653268, 816.8039670479102, 830.4228561150003, 858.7380848841101, 834.9206825351582, 836.9037560680508, 845.4800297816829, 845.5096044294295, 850.3027517435675, 895.5387975192781, 900.3607438718518, 900.8153328567628, 901.0178904993555, 902.8058967322024, 699.9745373028537, 722.949393542447, 895.4269267668665, 896.3098414221046, 900.9189172974399, 535.8794271288967, 538.2195816066719, 544.6432586655746, 643.906633538139, 664.1598378051768, 13876.375563099684, 14093.859061409357, 14319.0, 14342.569722908329, 14344.354504047344, 13170.473181072746, 13351.532696355582, 13384.488465246694, 13461.932690336485, 13490.79763803534, 12091.637250882488, 12302.0, 12313.876861231452, 12404.56584019564, 12751.74496510074, 4392.187445322594, 4403.3174857897075, 4443.7111587746695, 4443.911121777546, 4449.755263460508, 71806.0, 85916.7112493313, 86487.56756216178, 91578.33686652526, 91717.0, 10328.896711032794, 10470.947645261725, 10566.471676416148, 10628.681139565826, 10670.57317707283, 19768.90115549413, 20075.0, 20215.696764548542, 20241.595168096555, 20323.695144572845, 7174.533655312388, 7297.1608265049135, 7398.4081273497995, 7571.432142589328, 7710.228977102357, 4116.485439320085, 4140.979295103505, 4143.91712165755, 4184.309588917856, 4353.847615333448, 3245.7078862902454, 3310.6095439915293, 3356.0267522418712, 3356.9328613427597, 3641.8361173747244, 684.2439104789236, 689.1764341611726, 690.1752405875031, 691.1188235000415, 703.022798310349, 605.4732382826969, 636.2110982381813, 654.6203202142841, 658.2726087673148, 682.7951614515625, 8960.417572857743, 9124.361294709306, 9164.90835091654, 9332.95333523328, 9476.60824556833, 635.5074817016849, 637.6461064109463, 665.8368699668612, 668.2248591633681, 671.828683685657, 3527.664871837171, 4501.707389019703, 4550.0, 4583.747893865802, 4648.883777905579, 67744.6612766933, 68145.0, 68760.0, 69403.65298173476, 112692.43653781679, 63733.68133159397, 74261.6286918562, 80138.19861801424, 84284.73572896411, 87629.0, 76099.23900760921, 79679.20320796718, 80624.59687701524, 82150.35699286101, 84606.0, 9150.084991500797, 9650.276229282692, 9679.867393605415, 9718.289461199096, 9719.368241064309, 52513.47486525086, 53134.0, 54304.72847635736, 54452.0, 55996.440035599124, 69006.9648955256, 70391.29608704052, 72044.0, 73374.63312683403, 73634.63182684052, 11690.649280521598, 11698.532058717556, 11993.940030299793, 12128.454219559964, 12209.33719884049, 13450.730985380042, 13827.0, 13940.651483712989, 14013.859861401255, 14049.789253161007, 12683.80974285368, 12787.680307992374, 12819.16675416095, 13045.021623378474, 13512.391942362421, 12354.691132721753, 12782.61652150437, 12802.423890925116, 13117.868821312046, 13337.571266811037, 12316.938415307866, 12484.438200280827, 12916.160449570747, 12999.41502632365, 13300.268485233406, 10634.208840486855, 10672.679819605113, 10705.678829635124, 11034.337939723642, 11296.717582060186, 11121.999020088231, 11213.327200368005, 11350.886491134983, 11365.829512557484, 11411.543538258376], "value": 7224.348705084999, "unit": "score"}], "name": "dromaeo_css", "value": 7012.819119601395, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 115.0, 115.0, 117.0, 125.0, 170.0, 145.0, 119.0], "value": 125.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 113.0, 146.0, 114.0, 112.0, 113.0, 114.0, 162.0, 143.0], "value": 127.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 177.0, 184.0, 182.0, 182.0, 188.0, 184.0, 185.0, 184.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 61.0, 98.0, 87.0, 113.0, 71.0, 59.0, 81.0, 58.0], "value": 76.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 95.0, 69.0, 68.0, 68.0, 89.0, 68.0, 69.0, 90.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 86.0, 84.0, 88.0, 84.0, 86.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 109.0, 151.0, 93.0, 92.0, 91.0, 91.0], "value": 99.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 80.0, 79.0, 83.0, 81.0, 84.0, 80.0, 76.0, 81.0], "value": 81.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 53.0, 57.0, 58.0, 59.0, 58.0, 56.0, 57.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 67.0, 74.0, 72.0, 70.0, 71.0, 70.0, 69.0, 72.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 89.0, 84.0, 85.0, 84.0, 85.0, 84.0, 89.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 163.0, 170.0, 147.0, 151.0, 156.0, 166.0, 153.0, 209.0, 171.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 166.0, 164.0, 168.0, 173.0, 166.0, 165.0, 168.0, 169.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 66.0, 68.0, 67.0, 67.0, 68.0, 66.0, 66.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1466.5, "alertThreshold": 2.0}]} 05:29:10 INFO - Return code: 0 05:29:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:29:10 INFO - # TBPL SUCCESS # 05:29:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:29:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:29:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:29:10 INFO - 2016-11-30 05:29:10.970 screenresolution[1659:10539] starting screenresolution argv=screenresolution get 05:29:10 INFO - 2016-11-30 05:29:10.973 screenresolution[1659:10539] Display 0: 1600x1200x32@60 05:29:10 INFO - 2016-11-30 05:29:10.979 screenresolution[1660:10542] starting screenresolution argv=screenresolution list 05:29:10 INFO - Available Modes on Display 0 05:29:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:29:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:29:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:29:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:29:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:29:11 INFO - Intel Iris: 05:29:11 INFO - Chipset Model: Intel Iris 05:29:11 INFO - Type: GPU 05:29:11 INFO - Bus: Built-In 05:29:11 INFO - VRAM (Dynamic, Max): 1536 MB 05:29:11 INFO - Vendor: Intel (0x8086) 05:29:11 INFO - Device ID: 0x0a2e 05:29:11 INFO - Revision ID: 0x0009 05:29:11 INFO - Displays: 05:29:11 INFO - Display: 05:29:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:29:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:29:11 INFO - Display Serial Number: 1600x1200 60 05:29:11 INFO - Main Display: Yes 05:29:11 INFO - Mirror: Off 05:29:11 INFO - Online: Yes 05:29:11 INFO - Rotation: Supported 05:29:11 INFO - Return code: 0 05:29:11 INFO - Running post-action listener: _resource_record_post_action 05:29:11 INFO - [mozharness: 2016-11-30 13:29:11.222571Z] Finished run-tests step (success) 05:29:11 INFO - Running post-run listener: _resource_record_post_run 05:29:11 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 35290112; Write bytes: 660653568; Read time: 326; Write time: 2235 05:29:11 INFO - TinderboxPrint: CPU usage
53.5% 05:29:11 INFO - TinderboxPrint: I/O read bytes / time
35,290,112 / 326 05:29:11 INFO - TinderboxPrint: I/O write bytes / time
660,653,568 / 2,235 05:29:11 INFO - TinderboxPrint: CPU idle
2,255.1 (46.5%) 05:29:11 INFO - TinderboxPrint: CPU system
971.7 (20.0%) 05:29:11 INFO - TinderboxPrint: CPU user
1,626.9 (33.5%) 05:29:11 INFO - TinderboxPrint: Swap in / out
376,987,648 / 0 05:29:11 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348082688; Write bytes: 345994752; Read time: 27130; Write time: 872 05:29:11 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 30739456; Write bytes: 307339264; Read time: 291; Write time: 1333 05:29:11 INFO - Running post-run listener: _upload_blobber_files 05:29:11 INFO - Blob upload gear active. 05:29:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:11 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 05:29:11 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'] 05:29:11 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 05:29:12 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:29:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:29:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:12 INFO - (blobuploader) - INFO - Done attempting. 05:29:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:29:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:29:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:13 INFO - (blobuploader) - INFO - Done attempting. 05:29:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:29:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:29:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:16 INFO - (blobuploader) - INFO - Done attempting. 05:29:16 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:16 INFO - Return code: 0 05:29:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac6b54d6110d6bdceabaa475ee8241648b214f66c7f9e0d95f9bd6bdc75ff43151e9c48d718d4a455c66fa1a630112467f01456744e02d97cb3591214aa0a004", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59db3f0702c0185340e2e9da899d5db7830befc6a418b37c288b079f104e838239015ca13078e040042979b41301cbcdb073c9e1658f6c2c6929f2f6e9c73f7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6186e48f9af61c9ad48baefedf538d84c672f27d4a350bae6decf808a85d4ba6a8816cf7a5090d32d6e797be5eab38faed6fd8eefaa9856a7dee3af23ee0c51"} 05:29:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:16 INFO - Contents: 05:29:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac6b54d6110d6bdceabaa475ee8241648b214f66c7f9e0d95f9bd6bdc75ff43151e9c48d718d4a455c66fa1a630112467f01456744e02d97cb3591214aa0a004", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59db3f0702c0185340e2e9da899d5db7830befc6a418b37c288b079f104e838239015ca13078e040042979b41301cbcdb073c9e1658f6c2c6929f2f6e9c73f7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6186e48f9af61c9ad48baefedf538d84c672f27d4a350bae6decf808a85d4ba6a8816cf7a5090d32d6e797be5eab38faed6fd8eefaa9856a7dee3af23ee0c51"} 05:29:16 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:16 INFO - Copying logs to upload dir... 05:29:16 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.904667 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-30 05:29:16.223835) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 05:29:16.229497) ========= 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.ZRi916VAp2/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.PIKCIwqLC5/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac6b54d6110d6bdceabaa475ee8241648b214f66c7f9e0d95f9bd6bdc75ff43151e9c48d718d4a455c66fa1a630112467f01456744e02d97cb3591214aa0a004", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59db3f0702c0185340e2e9da899d5db7830befc6a418b37c288b079f104e838239015ca13078e040042979b41301cbcdb073c9e1658f6c2c6929f2f6e9c73f7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6186e48f9af61c9ad48baefedf538d84c672f27d4a350bae6decf808a85d4ba6a8816cf7a5090d32d6e797be5eab38faed6fd8eefaa9856a7dee3af23ee0c51"} build_url:https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011212 build_url: 'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac6b54d6110d6bdceabaa475ee8241648b214f66c7f9e0d95f9bd6bdc75ff43151e9c48d718d4a455c66fa1a630112467f01456744e02d97cb3591214aa0a004", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59db3f0702c0185340e2e9da899d5db7830befc6a418b37c288b079f104e838239015ca13078e040042979b41301cbcdb073c9e1658f6c2c6929f2f6e9c73f7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6186e48f9af61c9ad48baefedf538d84c672f27d4a350bae6decf808a85d4ba6a8816cf7a5090d32d6e797be5eab38faed6fd8eefaa9856a7dee3af23ee0c51"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 05:29:16.267941) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:29:16.268292) ========= 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.ZRi916VAp2/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.PIKCIwqLC5/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.004916 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:29:16.317133) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 05:29:16.317500) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 05:29:16.317948) ========= ========= Total master_lag: 0.20 =========