builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0004 starttime: 1486402543.13 results: success (0) buildid: 20170206081455 builduid: 87e4d5280cec4f97a54e54cc4680eeac revision: 7f1b358fb17dfd982c5e18c34d5735cd481c7f7c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:43.128456) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:43.128904) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:43.129178) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:43.154144) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:43.154501) ========= 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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 --2017-02-06 09:35:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.94M=0.001s 2017-02-06 09:35:43 (7.94 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:43.281913) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:43.282258) ========= 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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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.019619 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:43.330571) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 09:35:43.330927) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7f1b358fb17dfd982c5e18c34d5735cd481c7f7c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 7f1b358fb17dfd982c5e18c34d5735cd481c7f7c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vzgFqwfKSc/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.qxqZZUcdkE/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 2017-02-06 09:35:43,421 truncating revision to first 12 chars 2017-02-06 09:35:43,421 Setting DEBUG logging. 2017-02-06 09:35:43,421 attempt 1/10 2017-02-06 09:35:43,421 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7f1b358fb17d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 09:35:44,978 unpacking tar archive at: mozilla-central-7f1b358fb17d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.807874 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 09:35:45.162043) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:45.162365) ========= script_repo_revision: 7f1b358fb17dfd982c5e18c34d5735cd481c7f7c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:45.162740) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:45.163041) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 09:35:45.182899) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-06 09:35:45.183283) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vzgFqwfKSc/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.qxqZZUcdkE/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:35:45 INFO - MultiFileLogger online at 20170206 09:35:45 in /builds/slave/test 09:35:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:35:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:35:45 INFO - {'append_to_log': False, 09:35:45 INFO - 'base_work_dir': '/builds/slave/test', 09:35:45 INFO - 'blob_upload_branch': 'Firefox', 09:35:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:35:45 INFO - 'branch': 'Firefox', 09:35:45 INFO - 'buildbot_json_path': 'buildprops.json', 09:35:45 INFO - 'config_files': ('talos/mac_config.py',), 09:35:45 INFO - 'default_actions': ('clobber', 09:35:45 INFO - 'read-buildbot-config', 09:35:45 INFO - 'download-and-extract', 09:35:45 INFO - 'populate-webroot', 09:35:45 INFO - 'create-virtualenv', 09:35:45 INFO - 'install', 09:35:45 INFO - 'run-tests'), 09:35:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:35:45 INFO - 'download_minidump_stackwalk': True, 09:35:45 INFO - 'download_symbols': 'ondemand', 09:35:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:35:45 INFO - 'tooltool.py': '/tools/tooltool.py', 09:35:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:35:45 INFO - '/tools/misc-python/virtualenv.py')}, 09:35:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:35:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:35:45 INFO - 'installer_path': 'installer.exe', 09:35:45 INFO - 'log_level': 'info', 09:35:45 INFO - 'log_name': 'talos', 09:35:45 INFO - 'log_to_console': True, 09:35:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:35:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:35:45 INFO - 'opt_config_files': (), 09:35:45 INFO - 'pip_index': False, 09:35:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:35:45 INFO - 'cmd': ('bash', 09:35:45 INFO - '-c', 09:35:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:35:45 INFO - 'enabled': True, 09:35:45 INFO - 'halt_on_failure': False, 09:35:45 INFO - 'name': 'check_screen_resolution'},), 09:35:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:35:45 INFO - 'cmd': ('bash', 09:35:45 INFO - '-c', 09:35:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:35:45 INFO - 'enabled': True, 09:35:45 INFO - 'halt_on_failure': False, 09:35:45 INFO - 'name': 'check_screen_resolution'},), 09:35:45 INFO - 'run_cmd_checks_enabled': True, 09:35:45 INFO - 'sps_profile': False, 09:35:45 INFO - 'sps_profile_interval': 0, 09:35:45 INFO - 'suite': 'dromaeojs', 09:35:45 INFO - 'system_bits': '32', 09:35:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:35:45 INFO - 'title': 't-yosemite-r7-0004', 09:35:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:35:45 INFO - 'use_talos_json': True, 09:35:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:35:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:35:45 INFO - 'work_dir': 'build'} 09:35:45 INFO - [mozharness: 2017-02-06 17:35:45.396863Z] Running clobber step. 09:35:45 INFO - Running pre-action listener: _resource_record_pre_action 09:35:45 INFO - Running main action method: clobber 09:35:45 INFO - rmtree: /builds/slave/test/build 09:35:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:35:46 INFO - Running post-action listener: _resource_record_post_action 09:35:46 INFO - [mozharness: 2017-02-06 17:35:46.311913Z] Finished clobber step (success) 09:35:46 INFO - [mozharness: 2017-02-06 17:35:46.312040Z] Running read-buildbot-config step. 09:35:46 INFO - Running pre-action listener: _resource_record_pre_action 09:35:46 INFO - Running main action method: read_buildbot_config 09:35:46 INFO - Using buildbot properties: 09:35:46 INFO - { 09:35:46 INFO - "project": "", 09:35:46 INFO - "product": "firefox", 09:35:46 INFO - "script_repo_revision": "production", 09:35:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:35:46 INFO - "repository": "", 09:35:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:35:46 INFO - "buildid": "20170206081455", 09:35:46 INFO - "pgo_build": "False", 09:35:46 INFO - "basedir": "/builds/slave/test", 09:35:46 INFO - "buildnumber": 126, 09:35:46 INFO - "slavename": "t-yosemite-r7-0004", 09:35:46 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:35:46 INFO - "platform": "yosemite_r7", 09:35:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:35:46 INFO - "branch": "mozilla-central", 09:35:46 INFO - "repo_path": "mozilla-central", 09:35:46 INFO - "revision": "7f1b358fb17dfd982c5e18c34d5735cd481c7f7c", 09:35:46 INFO - "stage_platform": "macosx64", 09:35:46 INFO - "builduid": "87e4d5280cec4f97a54e54cc4680eeac", 09:35:46 INFO - "slavebuilddir": "test" 09:35:46 INFO - } 09:35:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 09:35:46 INFO - Running post-action listener: _resource_record_post_action 09:35:46 INFO - [mozharness: 2017-02-06 17:35:46.315585Z] Finished read-buildbot-config step (success) 09:35:46 INFO - [mozharness: 2017-02-06 17:35:46.315725Z] Running download-and-extract step. 09:35:46 INFO - Running pre-action listener: _resource_record_pre_action 09:35:46 INFO - Running main action method: download_and_extract 09:35:46 INFO - mkdir: /builds/slave/test/build/tests 09:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:46 INFO - trying https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 09:35:46 INFO - Downloading https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:35:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 09:35:47 INFO - Downloaded 1199 bytes. 09:35:47 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:35:47 INFO - Using the following test package requirements: 09:35:47 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:35:47 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:35:47 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 09:35:47 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:35:47 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 09:35:47 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:35:47 INFO - u'jsshell-mac.zip'], 09:35:47 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:35:47 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 09:35:47 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:35:47 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:35:47 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 09:35:47 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:35:47 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 09:35:47 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:35:47 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 09:35:47 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:35:47 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 09:35:47 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:35:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:35:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:35:47 INFO - Fetch https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:35:49 INFO - Expected file size: 34851045 09:35:49 INFO - Obtained file size: 34851045 09:35:56 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:35:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:35:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:35:56 INFO - Fetch https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:35:58 INFO - Expected file size: 34851045 09:35:58 INFO - Obtained file size: 34851045 09:36:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 09:36:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:36:04 INFO - Fetch https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 09:36:06 INFO - Expected file size: 14403454 09:36:06 INFO - Obtained file size: 14403454 09:36:06 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')]} 09:36:06 INFO - trying https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:36:06 INFO - Downloading https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 09:36:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 09:36:09 INFO - Downloaded 70256619 bytes. 09:36:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:36:09 INFO - mkdir: /builds/slave/test/properties 09:36:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:09 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:09 INFO - Contents: 09:36:09 INFO - build_url:https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:36:10 INFO - Running post-action listener: _resource_record_post_action 09:36:10 INFO - Running post-action listener: set_extra_try_arguments 09:36:10 INFO - [mozharness: 2017-02-06 17:36:10.374155Z] Finished download-and-extract step (success) 09:36:10 INFO - [mozharness: 2017-02-06 17:36:10.374296Z] Running populate-webroot step. 09:36:10 INFO - Running pre-action listener: _resource_record_pre_action 09:36:10 INFO - Running main action method: populate_webroot 09:36:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:10 INFO - '--tppagecycles', 09:36:10 INFO - '7'], 09:36:10 INFO - 'tests': ['tsvgm']}}, 09:36:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:36:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:36:10 INFO - 'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:36:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:36:10 INFO - 'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['damp', 'tps']}, 09:36:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:36:10 INFO - 'tests': ['damp', 'tps']}, 09:36:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['dromaeo_dom']}, 09:36:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['basic_compositor_video']}, 09:36:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:36:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['a11yr', 09:36:10 INFO - 'ts_paint', 09:36:10 INFO - 'tpaint', 09:36:10 INFO - 'sessionrestore', 09:36:10 INFO - 'sessionrestore_no_auto_restore', 09:36:10 INFO - 'tabpaint']}, 09:36:10 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:10 INFO - 'ts_paint', 09:36:10 INFO - 'tpaint', 09:36:10 INFO - 'sessionrestore', 09:36:10 INFO - 'sessionrestore_no_auto_restore', 09:36:10 INFO - 'tabpaint']}, 09:36:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:10 INFO - 'ts_paint', 09:36:10 INFO - 'tpaint', 09:36:10 INFO - 'sessionrestore', 09:36:10 INFO - 'sessionrestore_no_auto_restore', 09:36:10 INFO - 'tabpaint']}, 09:36:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:10 INFO - 'ts_paint', 09:36:10 INFO - 'tpaint', 09:36:10 INFO - 'sessionrestore', 09:36:10 INFO - 'sessionrestore_no_auto_restore', 09:36:10 INFO - 'tabpaint']}, 09:36:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['a11yr', 09:36:10 INFO - 'ts_paint', 09:36:10 INFO - 'tpaint', 09:36:10 INFO - 'sessionrestore', 09:36:10 INFO - 'sessionrestore_no_auto_restore', 09:36:10 INFO - 'tabpaint']}, 09:36:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['a11yr', 09:36:10 INFO - 'ts_paint', 09:36:10 INFO - 'tpaint', 09:36:10 INFO - 'sessionrestore', 09:36:10 INFO - 'sessionrestore_no_auto_restore', 09:36:10 INFO - 'tabpaint']}, 09:36:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['tsvgx', 09:36:10 INFO - 'tsvgr_opacity', 09:36:10 INFO - 'tart', 09:36:10 INFO - 'tscrollx', 09:36:10 INFO - 'cart', 09:36:10 INFO - 'tsvg_static']}, 09:36:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:10 INFO - 'tsvgr_opacity', 09:36:10 INFO - 'tart', 09:36:10 INFO - 'tscrollx', 09:36:10 INFO - 'cart', 09:36:10 INFO - 'tsvg_static']}, 09:36:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:36:10 INFO - 'talos_options': ['--disable-e10s'], 09:36:10 INFO - 'tests': ['tp5o']}, 09:36:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:36:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:36:10 INFO - 'talos_options': ['--disable-e10s', 09:36:10 INFO - '--xperf_path', 09:36:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:10 INFO - 'tests': ['tp5n']}, 09:36:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:36:10 INFO - 'talos_options': ['--xperf_path', 09:36:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:10 INFO - 'tests': ['tp5n']}}, 09:36:10 INFO - 'talos.zip': {'path': '', 09:36:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:10 INFO - Running post-action listener: _resource_record_post_action 09:36:10 INFO - [mozharness: 2017-02-06 17:36:10.393039Z] Finished populate-webroot step (success) 09:36:10 INFO - [mozharness: 2017-02-06 17:36:10.393176Z] Running create-virtualenv step. 09:36:10 INFO - Running pre-action listener: _resource_record_pre_action 09:36:10 INFO - Running main action method: create_virtualenv 09:36:10 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36:10 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 09:36:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:10 INFO - Using real prefix '/tools/python27' 09:36:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:11 INFO - Installing distribute.............................................................................................................................................................................................done. 09:36:14 INFO - Installing pip.................done. 09:36:14 INFO - Return code: 0 09:36:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:36:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:36:14 INFO - Reading from file tmpfile_stdout 09:36:14 INFO - Output received: 09:36:14 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) 09:36:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:36:14 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')]} 09:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:14 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')]} 09:36:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:14 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 0x10c37bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fadd0060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, '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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:36:14 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 09:36:14 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 09:36:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:36:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:14 INFO - 'HOME': '/Users/cltbld', 09:36:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:14 INFO - 'LOGNAME': 'cltbld', 09:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:14 INFO - 'MOZ_NO_REMOTE': '1', 09:36:14 INFO - 'NO_EM_RESTART': '1', 09:36:14 INFO - 'PAGER': '/bin/cat', 09:36:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:14 INFO - 'PWD': '/builds/slave/test', 09:36:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:14 INFO - 'SHELL': '/bin/bash', 09:36:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:36:14 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:36:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:14 INFO - 'USER': 'cltbld', 09:36:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:14 INFO - 'XPC_FLAGS': '0x0', 09:36:14 INFO - 'XPC_SERVICE_NAME': '0', 09:36:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:14 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 09:36:14 INFO - Cleaning up... 09:36:14 INFO - Return code: 0 09:36:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:36:14 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')]} 09:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:14 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')]} 09:36:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:14 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 0x10c37bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fadd0060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, '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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:36:14 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 09:36:14 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 09:36:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:36:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:14 INFO - 'HOME': '/Users/cltbld', 09:36:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:14 INFO - 'LOGNAME': 'cltbld', 09:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:14 INFO - 'MOZ_NO_REMOTE': '1', 09:36:14 INFO - 'NO_EM_RESTART': '1', 09:36:14 INFO - 'PAGER': '/bin/cat', 09:36:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:14 INFO - 'PWD': '/builds/slave/test', 09:36:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:14 INFO - 'SHELL': '/bin/bash', 09:36:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:36:14 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:36:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:14 INFO - 'USER': 'cltbld', 09:36:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:14 INFO - 'XPC_FLAGS': '0x0', 09:36:14 INFO - 'XPC_SERVICE_NAME': '0', 09:36:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:14 INFO - Downloading/unpacking psutil>=3.1.1 09:36:14 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 09:36:14 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 09:36:14 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 09:36:14 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 09:36:14 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 09:36:14 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 09:36:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:36:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:18 INFO - Installing collected packages: psutil 09:36:18 INFO - Running setup.py install for psutil 09:36:18 INFO - building 'psutil._psutil_osx' extension 09:36:18 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 09:36:18 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 09:36:18 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 09:36:18 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 09:36:18 INFO - building 'psutil._psutil_posix' extension 09:36:18 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 09:36:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:36:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:36:19 INFO - ^ 09:36:19 INFO - 1 warning generated. 09:36:19 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 09:36:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:19 INFO - Successfully installed psutil 09:36:19 INFO - Cleaning up... 09:36:19 INFO - Return code: 0 09:36:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:36:19 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')]} 09:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:19 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')]} 09:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c37bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fadd0060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, '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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:36:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:36:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:36:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:36:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:20 INFO - 'HOME': '/Users/cltbld', 09:36:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:20 INFO - 'LOGNAME': 'cltbld', 09:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:20 INFO - 'MOZ_NO_REMOTE': '1', 09:36:20 INFO - 'NO_EM_RESTART': '1', 09:36:20 INFO - 'PAGER': '/bin/cat', 09:36:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:20 INFO - 'PWD': '/builds/slave/test', 09:36:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:20 INFO - 'SHELL': '/bin/bash', 09:36:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:36:20 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:36:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:20 INFO - 'USER': 'cltbld', 09:36:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:20 INFO - 'XPC_FLAGS': '0x0', 09:36:20 INFO - 'XPC_SERVICE_NAME': '0', 09:36:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:36:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:36:24 INFO - Installing collected packages: mozsystemmonitor 09:36:24 INFO - Running setup.py install for mozsystemmonitor 09:36:24 INFO - Successfully installed mozsystemmonitor 09:36:24 INFO - Cleaning up... 09:36:24 INFO - Return code: 0 09:36:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c37bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fadd0060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, '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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:36:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:36:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:36:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:36:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:24 INFO - 'HOME': '/Users/cltbld', 09:36:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:24 INFO - 'LOGNAME': 'cltbld', 09:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:24 INFO - 'MOZ_NO_REMOTE': '1', 09:36:24 INFO - 'NO_EM_RESTART': '1', 09:36:24 INFO - 'PAGER': '/bin/cat', 09:36:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:24 INFO - 'PWD': '/builds/slave/test', 09:36:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:24 INFO - 'SHELL': '/bin/bash', 09:36:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:36:24 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:36:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:24 INFO - 'USER': 'cltbld', 09:36:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:24 INFO - 'XPC_FLAGS': '0x0', 09:36:24 INFO - 'XPC_SERVICE_NAME': '0', 09:36:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:25 INFO - Downloading/unpacking jsonschema==2.5.1 09:36:25 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 09:36:25 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 09:36:25 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 09:36:25 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 09:36:25 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 09:36:25 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 09:36:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:36:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:28 INFO - Downloading functools32-3.2.3-2.tar.gz 09:36:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:36:28 INFO - warning: no files found matching '*.txt' 09:36:28 INFO - no previously-included directories found matching 'build' 09:36:28 INFO - no previously-included directories found matching 'dist' 09:36:28 INFO - no previously-included directories found matching '.git*' 09:36:28 INFO - Installing collected packages: jsonschema, functools32 09:36:28 INFO - Running setup.py install for functools32 09:36:28 INFO - warning: no files found matching '*.txt' 09:36:28 INFO - no previously-included directories found matching 'build' 09:36:28 INFO - no previously-included directories found matching 'dist' 09:36:28 INFO - no previously-included directories found matching '.git*' 09:36:28 INFO - Successfully installed jsonschema functools32 09:36:28 INFO - Cleaning up... 09:36:28 INFO - Return code: 0 09:36:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c37bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fadd0060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, '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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:36:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:36:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:28 INFO - 'HOME': '/Users/cltbld', 09:36:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:28 INFO - 'LOGNAME': 'cltbld', 09:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:28 INFO - 'MOZ_NO_REMOTE': '1', 09:36:28 INFO - 'NO_EM_RESTART': '1', 09:36:28 INFO - 'PAGER': '/bin/cat', 09:36:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:28 INFO - 'PWD': '/builds/slave/test', 09:36:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:28 INFO - 'SHELL': '/bin/bash', 09:36:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:36:28 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:36:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:28 INFO - 'USER': 'cltbld', 09:36:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:28 INFO - 'XPC_FLAGS': '0x0', 09:36:28 INFO - 'XPC_SERVICE_NAME': '0', 09:36:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:36:28 INFO - Cleaning up... 09:36:28 INFO - Return code: 0 09:36:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c37bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fadd0060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, '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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:36:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:36:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:28 INFO - 'HOME': '/Users/cltbld', 09:36:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:28 INFO - 'LOGNAME': 'cltbld', 09:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:28 INFO - 'MOZ_NO_REMOTE': '1', 09:36:28 INFO - 'NO_EM_RESTART': '1', 09:36:28 INFO - 'PAGER': '/bin/cat', 09:36:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:28 INFO - 'PWD': '/builds/slave/test', 09:36:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:28 INFO - 'SHELL': '/bin/bash', 09:36:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:36:28 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:36:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:28 INFO - 'USER': 'cltbld', 09:36:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:28 INFO - 'XPC_FLAGS': '0x0', 09:36:28 INFO - 'XPC_SERVICE_NAME': '0', 09:36:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:31 INFO - Downloading blobuploader-1.2.4.tar.gz 09:36:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:36:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:36:31 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 09:36:31 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 09:36:31 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 09:36:31 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 09:36:31 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 09:36:31 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 09:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:36:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:36:32 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 09:36:32 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 09:36:32 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 09:36:32 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 09:36:32 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 09:36:32 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 09:36:32 INFO - Downloading docopt-0.6.1.tar.gz 09:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:36:32 INFO - Installing collected packages: blobuploader, requests, docopt 09:36:32 INFO - Running setup.py install for blobuploader 09:36:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:36:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:36:33 INFO - Running setup.py install for requests 09:36:33 INFO - Running setup.py install for docopt 09:36:33 INFO - Successfully installed blobuploader requests docopt 09:36:33 INFO - Cleaning up... 09:36:33 INFO - Return code: 0 09:36:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:33 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 0x10c37bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fadd0060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, '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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:36:33 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 09:36:33 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 09:36:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:36:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:33 INFO - 'HOME': '/Users/cltbld', 09:36:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:33 INFO - 'LOGNAME': 'cltbld', 09:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:33 INFO - 'MOZ_NO_REMOTE': '1', 09:36:33 INFO - 'NO_EM_RESTART': '1', 09:36:33 INFO - 'PAGER': '/bin/cat', 09:36:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:33 INFO - 'PWD': '/builds/slave/test', 09:36:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:33 INFO - 'SHELL': '/bin/bash', 09:36:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:36:33 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:36:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:33 INFO - 'USER': 'cltbld', 09:36:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:33 INFO - 'XPC_FLAGS': '0x0', 09:36:33 INFO - 'XPC_SERVICE_NAME': '0', 09:36:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:34 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-D5GYYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:34 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-o8zw6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:34 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-vU3MTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:34 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-x5P9lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:34 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-pfOW_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:34 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-RV2FEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:34 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-CnTcor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:34 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-CD8mmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:34 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-ZGgeuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:35 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-GKHrrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:35 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-tJHCPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:35 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-dFbb7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:35 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-IZKwgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:35 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-hozg_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:35 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-6H40R2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:35 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-JJ8oTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:35 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-BF_IdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:36:35 INFO - Running setup.py install for manifestparser 09:36:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:36:35 INFO - Running setup.py install for mozcrash 09:36:36 INFO - Running setup.py install for mozdebug 09:36:36 INFO - Running setup.py install for mozdevice 09:36:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:36:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:36:36 INFO - Running setup.py install for mozfile 09:36:36 INFO - Running setup.py install for mozhttpd 09:36:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:36:36 INFO - Running setup.py install for mozinfo 09:36:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:36:36 INFO - Running setup.py install for mozInstall 09:36:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:36:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:36:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:36:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:36:37 INFO - Running setup.py install for mozleak 09:36:37 INFO - Running setup.py install for mozlog 09:36:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:36:37 INFO - Running setup.py install for moznetwork 09:36:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:36:37 INFO - Running setup.py install for mozprocess 09:36:37 INFO - Running setup.py install for mozprofile 09:36:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:36:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:36:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:36:37 INFO - Running setup.py install for mozrunner 09:36:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:36:37 INFO - Running setup.py install for mozscreenshot 09:36:38 INFO - Running setup.py install for moztest 09:36:38 INFO - Running setup.py install for mozversion 09:36:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:36:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:36:38 INFO - Cleaning up... 09:36:38 INFO - Return code: 0 09:36:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:38 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 0x10c37bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fadd0060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, '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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:36:38 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 09:36:38 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 09:36:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:36:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:38 INFO - 'HOME': '/Users/cltbld', 09:36:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:38 INFO - 'LOGNAME': 'cltbld', 09:36:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:38 INFO - 'MOZ_NO_REMOTE': '1', 09:36:38 INFO - 'NO_EM_RESTART': '1', 09:36:38 INFO - 'PAGER': '/bin/cat', 09:36:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:38 INFO - 'PWD': '/builds/slave/test', 09:36:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:38 INFO - 'SHELL': '/bin/bash', 09:36:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:36:38 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:36:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:38 INFO - 'USER': 'cltbld', 09:36:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:38 INFO - 'XPC_FLAGS': '0x0', 09:36:38 INFO - 'XPC_SERVICE_NAME': '0', 09:36:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:38 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-6YMlm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:38 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)) 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:38 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-x5MlN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:38 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)) 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:38 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-rLk7LE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:39 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)) 09:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:39 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-nqZjrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:39 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-9bDw13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:39 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)) 09:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:39 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-91A_xA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:39 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)) 09:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:39 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-pM45ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:39 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)) 09:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:39 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-QojkLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:39 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)) 09:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:39 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-kAY3Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:39 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)) 09:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:39 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-CICcud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:39 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)) 09:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:39 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-NzVKrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:39 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)) 09:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:39 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-4yg2IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 09:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:39 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-Q1RV85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:40 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)) 09:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:40 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-_vZcsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:40 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)) 09:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:40 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-0y7OJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:40 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)) 09:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:40 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-UP2uIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:40 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-a9R8qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:40 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)) 09:36:40 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)) 09:36:40 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)) 09:36:40 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)) 09:36:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:36:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:36:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36:43 INFO - Downloading blessings-1.6.tar.gz 09:36:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:36:43 INFO - Installing collected packages: blessings 09:36:43 INFO - Running setup.py install for blessings 09:36:43 INFO - Successfully installed blessings 09:36:43 INFO - Cleaning up... 09:36:43 INFO - Return code: 0 09:36:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:36:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:43 INFO - Reading from file tmpfile_stdout 09:36:43 INFO - Current package versions: 09:36:43 INFO - blessings == 1.6 09:36:43 INFO - blobuploader == 1.2.4 09:36:43 INFO - docopt == 0.6.1 09:36:43 INFO - functools32 == 3.2.3-2 09:36:43 INFO - jsonschema == 2.5.1 09:36:43 INFO - manifestparser == 1.1 09:36:43 INFO - mozInstall == 1.12 09:36:43 INFO - mozcrash == 1.0 09:36:43 INFO - mozdebug == 0.1 09:36:43 INFO - mozdevice == 0.49 09:36:43 INFO - mozfile == 1.2 09:36:43 INFO - mozhttpd == 0.7 09:36:43 INFO - mozinfo == 0.9 09:36:43 INFO - mozleak == 0.1 09:36:43 INFO - mozlog == 3.4 09:36:43 INFO - moznetwork == 0.27 09:36:43 INFO - mozprocess == 0.24 09:36:43 INFO - mozprofile == 0.28 09:36:43 INFO - mozrunner == 6.13 09:36:43 INFO - mozscreenshot == 0.1 09:36:43 INFO - mozsystemmonitor == 0.3 09:36:43 INFO - moztest == 0.8 09:36:43 INFO - mozversion == 1.4 09:36:43 INFO - psutil == 3.1.1 09:36:43 INFO - requests == 1.2.3 09:36:43 INFO - wsgiref == 0.1.2 09:36:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:43 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 0x10c37bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fadd0060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, '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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:36:43 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 09:36:43 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 09:36:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:36:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:43 INFO - 'HOME': '/Users/cltbld', 09:36:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:43 INFO - 'LOGNAME': 'cltbld', 09:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:43 INFO - 'MOZ_NO_REMOTE': '1', 09:36:43 INFO - 'NO_EM_RESTART': '1', 09:36:43 INFO - 'PAGER': '/bin/cat', 09:36:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:43 INFO - 'PWD': '/builds/slave/test', 09:36:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:43 INFO - 'SHELL': '/bin/bash', 09:36:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:36:43 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:36:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:43 INFO - 'USER': 'cltbld', 09:36:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:43 INFO - 'XPC_FLAGS': '0x0', 09:36:43 INFO - 'XPC_SERVICE_NAME': '0', 09:36:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:44 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)) 09:36:44 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)) 09:36:44 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)) 09:36:44 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)) 09:36:44 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)) 09:36:44 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)) 09:36:44 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)) 09:36:44 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)) 09:36:44 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)) 09:36:44 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)) 09:36:44 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)) 09:36:44 INFO - Cleaning up... 09:36:44 INFO - Return code: 0 09:36:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:44 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 0x10c37bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fadd0060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, '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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 09:36:44 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 09:36:44 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 09:36:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:36:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:44 INFO - 'HOME': '/Users/cltbld', 09:36:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:44 INFO - 'LOGNAME': 'cltbld', 09:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:44 INFO - 'MOZ_NO_REMOTE': '1', 09:36:44 INFO - 'NO_EM_RESTART': '1', 09:36:44 INFO - 'PAGER': '/bin/cat', 09:36:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:44 INFO - 'PWD': '/builds/slave/test', 09:36:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:44 INFO - 'SHELL': '/bin/bash', 09:36:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:36:44 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:36:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:44 INFO - 'USER': 'cltbld', 09:36:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:44 INFO - 'XPC_FLAGS': '0x0', 09:36:44 INFO - 'XPC_SERVICE_NAME': '0', 09:36:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:36:44 INFO - Cleaning up... 09:36:44 INFO - Return code: 0 09:36:44 INFO - Running post-action listener: _resource_record_post_action 09:36:44 INFO - Running post-action listener: _start_resource_monitoring 09:36:44 INFO - Starting resource monitoring. 09:36:44 INFO - [mozharness: 2017-02-06 17:36:44.399965Z] Finished create-virtualenv step (success) 09:36:44 INFO - [mozharness: 2017-02-06 17:36:44.400899Z] Running install step. 09:36:44 INFO - Running pre-action listener: _resource_record_pre_action 09:36:44 INFO - Running main action method: install 09:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:44 INFO - Reading from file tmpfile_stdout 09:36:44 INFO - Detecting whether we're running mozinstall >=1.0... 09:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:36:44 INFO - Reading from file tmpfile_stdout 09:36:44 INFO - Output received: 09:36:44 INFO - Usage: mozinstall [options] installer 09:36:44 INFO - Options: 09:36:44 INFO - -h, --help show this help message and exit 09:36:44 INFO - -d DEST, --destination=DEST 09:36:44 INFO - Directory to install application into. [default: 09:36:44 INFO - "/builds/slave/test"] 09:36:44 INFO - --app=APP Application being installed. [default: firefox] 09:36:44 INFO - mkdir: /builds/slave/test/build/application 09:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:37:06 INFO - Reading from file tmpfile_stdout 09:37:06 INFO - Output received: 09:37:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:37:06 INFO - Running post-action listener: _resource_record_post_action 09:37:06 INFO - [mozharness: 2017-02-06 17:37:06.903174Z] Finished install step (success) 09:37:06 INFO - [mozharness: 2017-02-06 17:37:06.903278Z] Running run-tests step. 09:37:06 INFO - Running pre-action listener: _resource_record_pre_action 09:37:06 INFO - Running main action method: run_tests 09:37:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:06 INFO - 2017-02-06 09:37:06.915 screenresolution[1913:12740] starting screenresolution argv=screenresolution get 09:37:06 INFO - 2017-02-06 09:37:06.918 screenresolution[1913:12740] Display 0: 1600x1200x32@60 09:37:06 INFO - 2017-02-06 09:37:06.923 screenresolution[1914:12743] starting screenresolution argv=screenresolution list 09:37:06 INFO - Available Modes on Display 0 09:37:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:07 INFO - Intel Iris: 09:37:07 INFO - Chipset Model: Intel Iris 09:37:07 INFO - Type: GPU 09:37:07 INFO - Bus: Built-In 09:37:07 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:07 INFO - Vendor: Intel (0x8086) 09:37:07 INFO - Device ID: 0x0a2e 09:37:07 INFO - Revision ID: 0x0009 09:37:07 INFO - Displays: 09:37:07 INFO - Display: 09:37:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:07 INFO - Display Serial Number: 1600x1200 60 09:37:07 INFO - Main Display: Yes 09:37:07 INFO - Mirror: Off 09:37:07 INFO - Online: Yes 09:37:07 INFO - Rotation: Supported 09:37:07 INFO - Return code: 0 09:37:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:37:07 INFO - Python 2.7.3 09:37:07 INFO - Return code: 0 09:37:07 INFO - grabbing minidump binary from tooltool 09:37:07 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')]} 09:37:07 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 0x10c159d30>, '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 0x10c080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c14ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:37:07 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 09:37:07 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 09:37:07 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 09:37:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:37:07 INFO - Return code: 0 09:37:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:37:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:37:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:37:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:37:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:37:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--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 09:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0004 --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 09:37:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzgFqwfKSc/Render', 09:37:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:07 INFO - 'HOME': '/Users/cltbld', 09:37:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:07 INFO - 'LOGNAME': 'cltbld', 09:37:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:37:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:07 INFO - 'MOZ_NO_REMOTE': '1', 09:37:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:37:07 INFO - 'NO_EM_RESTART': '1', 09:37:07 INFO - 'PAGER': '/bin/cat', 09:37:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:07 INFO - 'PWD': '/builds/slave/test', 09:37:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:37:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:07 INFO - 'SHELL': '/bin/bash', 09:37:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qxqZZUcdkE/Listeners', 09:37:07 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 09:37:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:07 INFO - 'USER': 'cltbld', 09:37:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:07 INFO - 'XPC_FLAGS': '0x0', 09:37:07 INFO - 'XPC_SERVICE_NAME': '0', 09:37:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--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 09:37:07 INFO - mozversion application_buildid: 20170206081455 09:37:07 INFO - mozversion application_changeset: 7f1b358fb17dfd982c5e18c34d5735cd481c7f7c 09:37:07 INFO - mozversion application_display_name: Nightly 09:37:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:37:07 INFO - mozversion application_name: Firefox 09:37:07 INFO - mozversion application_remotingname: firefox 09:37:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:37:07 INFO - mozversion application_vendor: Mozilla 09:37:07 INFO - mozversion application_version: 54.0a1 09:37:07 INFO - mozversion platform_buildid: 20170206081455 09:37:07 INFO - mozversion platform_changeset: 7f1b358fb17dfd982c5e18c34d5735cd481c7f7c 09:37:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:37:07 INFO - mozversion platform_version: 54.0a1 09:37:07 INFO - using testdate: 1486402627 09:37:07 INFO - actual date: 1486402627 09:37:07 INFO - starting webserver on 'localhost:52687' 09:37:07 INFO - SUITE-START | Running 2 tests 09:37:07 INFO - TEST-START | dromaeo_css 09:37:07 INFO - operating with platform_type : mac_ 09:37:07 INFO - Initialising browser for dromaeo_css test... 09:37:07 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmpAYJ5Fc/profile http://localhost:52687/getInfo.html) 09:37:09 INFO - PROCESS | 1924 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 09:37:09 INFO - PROCESS | 1924 | __metrics Screen width/height:1600/1200 09:37:09 INFO - PROCESS | 1924 | colorDepth:24 09:37:09 INFO - PROCESS | 1924 | Browser inner width/height: 1024/689 09:37:09 INFO - PROCESS | 1924 | __metrics 09:37:09 INFO - PROCESS | 1924 | 1486402629976 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:37:10 INFO - PROCESS | 1924 | 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" 09:37:10 INFO - PROCESS | 1924 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:37:10 INFO - TEST-INFO | 1924: exit 0 09:37:10 INFO - Browser initialized. 09:37:10 INFO - Running cycle 1/1 for dromaeo_css test... 09:37:10 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmpAYJ5Fc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:22 INFO - PROCESS | 1926 | RSS: Main: 237162496 09:37:22 INFO - PROCESS | 1926 | 09:40:26 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52687/tests/dromaeo/cssquery-ext.html) 09:40:28 INFO - PROCESS | 1926 | RSS: Main: 423505920 09:40:28 INFO - PROCESS | 1926 | 09:42:12 INFO - PROCESS | 1926 | ************************* 09:42:12 INFO - PROCESS | 1926 | A coding exception was thrown and uncaught in a Task. 09:42:12 INFO - PROCESS | 1926 | 09:42:12 ERROR - PROCESS | 1926 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:42:12 INFO - PROCESS | 1926 | Full stack: 09:42:12 INFO - PROCESS | 1926 | ************************* 09:43:31 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-ext.html (next: http://localhost:52687/tests/dromaeo/cssquery-jquery.html) 09:43:32 INFO - PROCESS | 1926 | RSS: Main: 367210496 09:43:32 INFO - PROCESS | 1926 | 09:46:34 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52687/tests/dromaeo/cssquery-mootools.html) 09:46:35 INFO - PROCESS | 1926 | RSS: Main: 347258880 09:46:35 INFO - PROCESS | 1926 | 09:49:37 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52687/tests/dromaeo/cssquery-prototype.html) 09:49:38 INFO - PROCESS | 1926 | RSS: Main: 353890304 09:49:38 INFO - PROCESS | 1926 | 09:52:41 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52687/tests/dromaeo/cssquery-yui.html) 09:52:41 INFO - PROCESS | 1926 | RSS: Main: 347746304 09:52:41 INFO - PROCESS | 1926 | 09:55:45 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-yui.html (next: http://localhost:52687/tests/dromaeo/cssquery-dojo.html) 09:55:45 INFO - PROCESS | 1926 | RSS: Main: 586784768 09:55:45 INFO - PROCESS | 1926 | 09:55:45 INFO - PROCESS | 1926 | __start_tp_report 09:55:45 INFO - PROCESS | 1926 | _x_x_mozilla_page_load 09:55:45 INFO - PROCESS | 1926 | _x_x_mozilla_page_load_details 09:55:45 INFO - PROCESS | 1926 | |i|pagename|runs| 09:55:45 INFO - PROCESS | 1926 | |0;dojo.html;3237.546743455916;3245.594300712411;3254.9023529294113;3281.80309181449;3308.106811160985;5402.594805389602;5466.398696143426;5479.643823151492;5528.530074943629;5536.114221724525;5521.4478552144765;5523.475269849358;5533.031719449091;5571.777128914849;5580.776768929248;4788.592969597588;4990.27640992056;5020.54815066643;5066.265391518227;5182.689038657673;5118.744062796864;5138.665986710852;5163.793448262065;5173.5861131109605;5228.581713462914;4448.043670610834;4625.907481850378;4817.975910120462;5005.47442518534;5030.220315851049;5048.7980480780725;5108.591312694975;5127.1796512558185;5159.5614372778355;5186.688798672092;5040.873978150539;5071.644984851062;5110.8466745997675;5125.026245013472;5126.128558145124;5295.735213239361;5330.200469929503;5562.749676264578;5572.888542229173;5578.44215578443;5817.360090390053;5823.126531020381;5826.242588463472;5837.416258374129;5842.941570584282;5793.292292932484;5921.763129474816;5922.703864806743;5923.792667256668;5932.851678708023;5502.0921547944945;5502.202180683818;5507.476789704971;5512.669239845582;5514.310711161105;6044.274687037496;6240.282367527701;6301.8109456716375;6304.432601065926;6346.460550853137;5750.137479378127;5803;5807.796727114615;6023.6385816851125;6073.696315184223;6114.732076222627;6166.445019948226;6183.598066125687;6234.875302494015;6246.906296405557;6134.631922084689;6144.41628045335;6144.754209831646;6159.3224745277985;6161.630302181883;5835.124731290341;5862.237909071879;5872.559558033165;5999.130126131687;6146.323904370515;4799.0401919615515;4873.19592267279;4887.780049897692;4890.657653964259;4920.75396230187;4308.073764140662;4342.392065110887;4348.63036641889;4362.8036738346855;4373.978130109329;5415.702136382537;5476.890462190813;5569.916451253234;5576.386597474275;5586.692731899784;6097.207363042865;6112.633242005494;6120.051392034242;6136.478399336017;6136.509079273647;5478.369987451414;5517.751701173457;5521.972390138104;5526.50261476469;5538.723063846792;5275.366955965309;5279.155335146358;5287.735613219324;5289.523942845162;5299.019681358961;6250.218722659668;6253.78111766086;6266.467350275187;6267.059941008885;6269.78055768055;6108.938910610837;6111.633302001894;6113.29697084841;6116.908246376397;6120.9693951531735;6244.313125556095;6246.437820596168;6247.750089996349;6253.812385628438;6264.937350626436;5999.45351119067;6105.520399749808;6106.855059071508;6115.94015414234;6125.17440701618;6035.517158627387;6177.073438984188;6186.907196392148;6190.50475961913;6206.813795586139;5717.056685646821;5739.8852022960145;5779.133130030529;5828.417158284307;5829.300483941955;5686.687232202103;5777.133429985368;5843.1235314703135;5846.853828654318;5847;5071.315372424676;5735.3977832327;5769.9711501442225;5801.158831969256;5858.882822343444;5942.227510423617;5943.138244954544;5960.254968128984;5973.1637570741;5989.670568118795;5387.949349876893;5461.1262198048125;5558.277423934863;5764.855878603068;5791.768329266951 09:55:45 INFO - PROCESS | 1926 | |1;ext.html;431.7323259579061;432.1810169728367;449.3888311895821;453.3381263355501;466.30520524418614;29284.85357573212;38479.80760096199;40141.98935079866;40154.39768403476;42700.78649606755;40893.18213635733;41338.793306033505;41432.37851432231;41448.79275603618;41468.17063658725;7901.525908445512;9097.63609455621;9299.814003719921;9315.975242723329;9335.3465257432;7884.842303153933;10926.890731092706;11202.551897924073;11212.719682007933;11331.660050198507;11053.613123540677;11167.609133680322;11223.494942727597;11233.438328083605;11240.718982025433;7869.567173805438;8082.555459449728;11096.722581935475;11187.888121118807;11208.551657933673;10762.838557421645;11043.392613406259;11059.391733454657;11231.326120432801;11249.831252531138;38298.234035319416;38596;38654.226915461535;38675.41986870199;38831.41752873709;3751.249750050001;3911.0417947602737;3924.4113382992496;3949;4093.1813637272667;22562.43593910149;26444.074457393897;27889.58165627517;27986.440271194664;28038.71961280402;3041.726244637971;3243.8702451901895;3244.026791962402;3245.4807230842953;3254;435966.82016590034;437321;437736;438735;442771;45009.87475313144;46632.83417914512;46658.6002419928;53403.46596534063;53755.65610859681;71399;71499.28500714926;71574;71652.64173679202;76816.23183768202;14300;14525.709485810436;14797.408103675732;14860.108393496424;14875.925620372045;10929.945350273198;11010.669679909615;11079.113670906308;11135.944320278346;11139.498722557504;19985.60028799416;20085;20160.193592256437;20195.293164739163;20237;7643.7324693635455;8393.328533717287;8405.41162118658;8456.801231679807;8520.829583408422;835.3359079531787;849.2229609906866;849.9277561407226;851.7827953871723;864.9567521623893;23004.309870703906;23277.719725414925;23411.88294058553;27160.45679086407;29350.853245733633;883.7878909061778;1024.1653052761976;1045.231754660324;1046.7016900183564;1174.4773575758877;14932.776008359884;15014.399424022917;15058;15304.005239659387;15466.072035677671;593808;595108;598357;599067;599344;57212.71393643005;57493.71253143708;57607.711961440764;57686;57698.13452798211;1326.0452474218548;1378.372840357635;1390.1728471559406;1393.9233342166278;1404.2276747788676;25186.74813251844;25578;27764.861175693994;28038.177518461234;29509;48709.756451218935;48865.511344886094;48959.755201223765;48994;49010.50989490059;494144;494737;496082;496900;497660;32616;33438.66561334355;39402.60597394104;40131.1973760529;40191.3971290425;44185.33721994108;44553.331700023875;44766;45013;45667;36610.08474788045;36754.8162259187;37292.692067457836;38029;38155.80922095372;34703.65296347004;36171;37456.063598410256;37710.24579508449;38502.61497385102;35186.296274073866;35256.94229173129;35637.28725425529;35805.92582222538;36224.27551449009;24506.42774440094;24562.63156052625;27014;28942;30963.380732385678;23190.76809231886;23426.79065791272;23682.763172368745;24061.639075414234;24368.51262974766 09:55:45 INFO - PROCESS | 1926 | |2;jquery.html;381.975171613845;387.2216844143271;387.50012483895773;392.5544506984572;414.3825699707437;18278.90860545697;19155.90422047888;20744.48138796529;20986.475338116554;21037.054812326267;11913.285202887811;12265.754684906296;12395.380230988438;12409.503619855217;12427.751444971094;5078.263651770491;5384.219288203209;5389;5393.379761327458;5394.757235924373;5252.3697156341295;5370.221317908901;5669.348024977139;5959.1359252908305;6023.18686977259;5992.49063829575;6063.060225665029;6087.147799308101;6091.11678806575;6096.2074930259005;5918.88162236755;5954.4640982311585;5993.700314984255;6046.637201767908;6046.788362407295;5635.239242702224;5866.120081987736;5871.765129394819;5877.52979761618;5877.67672777997;13260.668483287898;13667.17996920188;13677.521286755014;13682.31588420585;14186.645333866632;2543.6566063581363;2548.9872550637315;2561.3852675357966;2581.716011238781;2584.4055867150473;8620.439671421338;9807;9915.702528924145;10350.63772767957;10455.790884182274;1684.7978242610843;1701.1834319526697;1737.791465024205;1793.3543924187281;1795.1203910084096;89461.94842464145;122691;122774.38612806969;124062;124717;11397.202195846377;11691.356975366436;11718.531258749554;11740.882591173979;11919.70200744971;15679.921600391925;15686.137262450446;15691;15700.900936934306;15707;7801.726939557088;8059.355251579859;8093.757187284391;8200.876986845202;8217.055038670629;7555.962220188864;7600.1639819619795;7610.961945190349;7738.729144479917;7763.72826951054;4073.796310184479;4727.409073865767;4727.64542659302;4740.241561350167;4810.374651295379;1925.8541168005015;2030.715699802029;2030.7969203079574;2031.6850888112367;2032.3699653107287;968.3802366485455;1018.8930162332997;1026.414943482223;1036.4247842447364;1040.1262939131166;19488;21021.68467472989;21026.579468410546;21114.788852111284;21189.788102118782;997.2719914462338;1008.4050410257983;1055.2560444886337;1060.7030031591166;1061.4374381577777;8163.346932245406;8965.596548155349;10884.07485363753;10928.945355273172;11057.612983545538;667520;673807;678943;687174;688073;91113;100657.98348296552;107695.46152269188;127131;160233.97660234116;1042.2756184451732;1066.898644628759;1091.356099901078;1092.464692300767;1098.6703988803326;19403.708944365564;19561.021948902213;19599.216031358585;19690.704639430132;19801.207951681772;52665;54251.45748542464;56538;58714;58719.30245580429;80508.59745701468;81240;82480;82550.58724706338;83973;8194.918050819415;8261.13258107914;8423.241908228289;8450.619722112397;8621.267192288726;9119.224865886474;12058.819117713067;12697.238165710087;13203.471861125447;13233.669158271121;10703.839442408525;10997.34015959045;11460.942695286803;11491.080713543064;11515.654530364103;11014.44927753625;11057.06014988735;11284.774304514029;11304.547818087185;11331;11032.779344413228;11491.54033838637;11565.248258863146;11573.768524629628;11768.411579421167;8093.676252949816;8336.166383361615;8421.747347579583;8483.44857584255;8646.524441155545;9427.733858039577;10187.592496300362;10518.579256829642;10593.735156621146;10612.469376531297 09:55:45 INFO - PROCESS | 1926 | |3;mootools.html;1219.9451024703887;1384.536180379573;1402.3829515013392;1412.3997301147024;1414.6321956291365;7167.928320716791;8074.031116266049;8520.78698032548;8526.70156544521;8532.402731808768;8101.23038311361;8378.91621083789;8388.580570971442;8538;8548.14518548145;2143.560570083133;2289.645105008726;2292.9656055159185;2294.300238427279;2298;1790.489710432527;1907.2180406033528;2257.571061498318;2306.3426923326856;2307.665388518664;1924.6728056230404;2193.6928829963817;2261.3329067925;2269.353234328217;2284.1776960294283;1827.2416946966994;2227.20934068405;2252.054137262354;2254.1659585953303;2256.4922892349255;2223.6998005269406;2283.1666441748807;2284.588774020676;2285.382946604424;2290.3701482092392;8397.454165479225;8477.915220847774;8482.15178482153;8498.065212826585;8499.660013599449;4943.90112197754;5047.621428392885;5196.376434827807;5203.089459344599;5215.869603259949;3270.5094235864813;3461.498082778008;3487;3528.153243221634;3534.8232588370734;1169.859616845984;1227.6808029912197;1233.5867484392738;1240.975180496394;1259.5339724301975;8843.806086178349;9423.40856264526;10815.783684326349;10917.836232456519;10995.175361847814;4128.256913755493;4404.55954404557;4462.799174037176;4483.058557702919;4498.077894031716;8729.21437070654;8742.344324175712;8757.693480728143;8761.123887611315;8785.69250076257;5040.49595040493;5045.571126454219;5058.367704036996;5060.924086138711;5061.9876024795185;4572.565606267399;4611.16998940194;4676.205045142334;4717.50681605606;4766.833160839354;3071.216839705861;3561.394562924309;3599.082234030358;3614.548181477315;3666.32173047987;911.2254583603884;1115.8214685650257;1126.6056880091903;1135.8920902514249;1151.591185129267;658.2857599504496;744.118219909409;822.3749950038015;837.8533756592572;911.6581282019242;5310.707911064928;5555.2778138841695;5562.471565201299;5712.400197979236;5924.555658325643;809.704457872884;939.248601119102;940.2196177173066;974.6978436684509;977.1742877268697;2806.7052959439375;3572.160542272564;3663.9267214656093;3673.779573225615;3703.8702296559363;9820.165285950774;9879.259055570861;9885.901140988499;10163.7459063524;10181.236407269484;9441.15029647335;9838.163756080814;9901.900980990242;10067.295289329822;10100.797984040279;7620.771376858703;7770.8057298566855;7786.57173855432;7792.766217013499;7799.766007019798;5449.3188351456065;5478.780848766005;5547.667139971614;5649.915251271152;5776.191333213437;8855.291576673979;9144.63421463134;9191.172794448532;9261.53692315368;9276.443413395218;8685.435446695945;8917.866232006396;9134.314926380415;9382.718518444184;9412.952935235555;7036.261192574911;7061.682224299816;7195.532290401107;7223.133224013152;7256.528325659026;7815.765527034198;7901.604919754104;7909.9209007909185;7916.802079947817;7918.287354138155;7026.894596581157;7102.644867756695;7123.074000380125;7188.424926006011;7261.019762332229;6844.931550684629;6971.337722916315;6987.475939304471;6989.685464154024;7074.221835598183;6348.936510634834;6384.712687929147;6477.838054048687;6489.7404103835315;6493.026046093124;4817.9518204817505;4854.854354369375;4894.804207831647;4909.459959404534;4996.900061998813;4821.54195351441;4858.514148585114;4894.755262236945;4981.252812078217;4985.850424487271 09:55:45 INFO - PROCESS | 1926 | |4;prototype.html;359.5882714292135;362.6717820372564;369.67653303359555;381.90834199792056;381.93125237457264;5107.876267221215;5146.330976972993;5297.8344764151925;5329.760160792764;5330.920036199451;4670.789814458349;4925.679830811004;5099.668521546097;5175.24958880962;5234.685918844872;3579.8568057277807;3672.4124140137583;3947.9013024674327;3969.4045893116117;3984.7210695251415;3968.8610898618554;3982.8008599569907;3988.5811989741096;4000.2399544086675;4026.6577340926056;3211.710946014858;3294.7034766870975;3798.7340886137845;3937.6259255370837;4009.9975006248437;3215.678432156789;3922.7049278605823;3930.351492003816;3930.351492003816;3953.2488827122756;3048.7103725146076;3407.4718418645148;3422.19578399076;3424.5548078749853;3454.619991800925;5016.824411145629;5072.847814565569;5091.032703786269;5113;5113.667611605234;4338.934915976263;4452.3989261449615;4491.528389519086;4559.310007095829;4564.5435456454425;5509.421510741395;5552.639078459887;5555.749991250404;5555.777768889239;5560.277163968658;3198.7441004719567;3410.7100896423585;3817.3319669058083;3818.6372294631965;3827.674647654953;5192.376914770214;5421.153894205534;5661.971690141523;5837.532997360201;5840.561957853136;3814.48504451901;3839.9232015360094;3844.6732027777953;3848.230353929225;3865.420186971977;3343.732501399882;3902.453656488094;3915.275674000319;3922.666573341298;3934.6065393460426;4261.208908165157;4510.819567217341;4545.0000999779995;4579.335996280521;4586.72479651222;4867.174809446457;5144.871378215575;5507.917381239285;5534.695591742412;5586.24585680936;1153.1293873125737;1254.738987317758;1272.6309370282515;1280.302235281755;1281.1544380708688;756.9962150189324;800.0759123212698;824.6041899888087;863.0247819963527;887.689308741948;4412.624926881251;4480.731156130642;4938.530839570235;4976.129177394014;4999.875003124951;4508.639308855284;5028.748562571856;5456.645318054314;5457.742960490824;5509.5041446270025;4678.345031695565;4803.95196048042;4950.603951683856;4985.5762260208285;5005.399352077774;2475.95048099037;2485.9254222373356;2554.6806649168852;2653.933651658724;2892.0022592205655;4745.549172828576;5662.2639056922335;5664;5686.488216060575;5690.601657883908;4784.497627749036;5233.3310011893445;5455.61810673257;5619.719014049363;5682.494139053154;4914.803407863788;5028.823991160292;5361.51746342831;5610.97194514033;5652.491275785199;4520.389747384061;4897.338859254098;5326.813561525328;5402.72986350673;5426.04662321556;4282.351294751481;4842.927356089732;4969.751512424292;5191.454897235736;5223.582113430993;4771.95228047715;4997.375328083989;5429.592780541554;5528.364238112668;5617.876424714992;3371.983140084284;3602.225521512887;3602.927941441209;3641.289948460025;3657.4638651766854;4599.839005634787;4682.789274482589;5030.3148445271245;5402.540784033244;5454.427285135003;4719.504452032625;4989.351384320015;5267.762950667153;5270.367555893317;5270.472952704698;5055.9494405055475;5254.9799403457155;5258.055488926214;5266.499047771366;5285.7621407036;4764.4282686077895;4801.975990120027;4912.729799861042;4953.3065370848835;5200.6619569727845;4713.525399165465;4802.27965805132;5175.430702622633;5256;5277.551408130198;4506.18888600042;4838.419389673261;4910.459849416633;4987.875303117451;5155.510226528474 09:55:45 INFO - PROCESS | 1926 | |5;yui.html;692.6735301896867;714.4605822603936;758.1395116542726;803.2931020701782;818.4311903227256;11997.520099196032;13857.030007899451;13967.511137110203;14291.356888939996;14466.5660030199;13116.409761560728;13389.129706569067;13401.195928244311;13412.798808017887;13418.932905335483;853.6372041882206;863.4560227056945;868.0841711993844;868.5223127280002;869.2437579306013;822.6956026270259;841.3731769831484;855.2730179347567;858.0647094666812;866.9133086691344;908.4004556992386;909.3225546967485;909.7862002429424;913.8629205619144;914.6661468563959;903.223228023899;905.4431524612316;905.5608030105394;907.3240435875249;908.1917093786535;668.0446960845989;669.2170160911696;670.2258890980925;671.96976136074;672.2806596941275;13520.661983450394;13674.521391751341;14942.775858362133;14967.101973881601;14975.475858345015;13406.128601640861;13687.001072322126;13718.588442346745;13855.099418537864;13904.304784760721;11067.315177026429;11451;11455.942720286428;11468.197226194086;11872.050235981102;3166.366726654678;3608.981955090234;3627.909302267438;3691.9077023074638;3773.2830762155104;85136.57431712863;93736.53131734366;95765;97400.53899191518;99190.01619967632;10820.78358432827;10925.836112458319;10943.398113103696;10957.17821163416;11098.44507774608;21256.574868502543;21457.78542214589;21630.783692163193;21711.782882171294;21727.34817955464;7295.781126566211;7334.9266507335315;7370.373518250902;7434;7467.887981680279;3870.651641352292;3959.2675355059405;3973.264945985002;3985.342418500915;4021.2158629067053;3387.4241378965635;3469;3473.114355839245;3488.89533314001;3490;672.186654148476;676.2358534855582;678.7149397253165;689.7378995981495;689.9068625735562;649.2566011916424;655.1613934164277;673.161913417798;686.4131167851415;715.992840071603;8487.618057187317;9265.166135047879;9565.665201717906;9613.567389467491;10085.848712269322;578.5689661202431;671.9193696756421;676.1615596660106;685.5715178013742;692.7194486233084;4580.473245576734;4782.043591281688;4833;4849.684770489906;4895.662929417847;74602.25397745954;95465;99396;99827;101820.98179018115;43383.56616433858;49806;57262;71448.64275678693;75659.24340756632;45078.647640570176;65478.67260663666;66474.33525664681;66959.33040669726;67680.32319676838;10233.9488302558;10489.318194317346;10567.313124646873;10683.465826708478;10718.303310284806;45832.54167458416;47528;48232.517674822804;48613;49842.501574983784;64267.67866160639;64413.677931610044;65429;65574.34425655683;65633.73380730237;10996.945015274872;11055.226134170853;11055.613053543088;11153;11176.10591152722;12429.689257768629;13867.583972480841;14094.436222550983;14927.029743066665;14942.701145977237;13497.797533037208;13648.317584120952;13812.930935345259;14675.926620367258;14801.703965920837;12889.291088990196;13118.934405327913;14473.421063157355;14504.126908633163;14699.971002029755;12609.873901261237;13295.401706923018;14179.22014289224;14633.122012679274;14636.195009274592;10654.360738355723;11059.668209953714;11388.316700997966;11437.485313160762;11607.76784464289;9606.913330657566;9817.410955342702;11313.7737245253;11560.132990025613;11590.18868679218 09:55:45 INFO - PROCESS | 1926 | __end_tp_report 09:55:45 INFO - PROCESS | 1926 | __start_cc_report 09:55:45 INFO - PROCESS | 1926 | _x_x_mozilla_cycle_collect,1954 09:55:45 INFO - PROCESS | 1926 | __end_cc_report 09:55:45 INFO - PROCESS | 1926 | __startTimestamp1486403745583__endTimestamp 09:55:45 INFO - PROCESS | 1926 | 09:55:45 INFO - PROCESS | 1926 | ------- Summary: start ------- 09:55:45 INFO - PROCESS | 1926 | Number of tests: 6 09:55:45 INFO - PROCESS | 1926 | 09:55:45 INFO - PROCESS | 1926 | [#0] dojo.html Cycles:165 Average:5573.57 Median:5739.89 stddev:634.25 (11.0%) stddev-sans-first:609.15 09:55:45 INFO - PROCESS | 1926 | Values: 3237.5 3245.6 3254.9 3281.8 3308.1 5402.6 5466.4 5479.6 5528.5 5536.1 5521.4 5523.5 5533.0 5571.8 5580.8 4788.6 4990.3 5020.5 5066.3 5182.7 5118.7 5138.7 5163.8 5173.6 5228.6 4448.0 4625.9 4818.0 5005.5 5030.2 5048.8 5108.6 5127.2 5159.6 5186.7 5040.9 5071.6 5110.8 5125.0 5126.1 5295.7 5330.2 5562.7 5572.9 5578.4 5817.4 5823.1 5826.2 5837.4 5842.9 5793.3 5921.8 5922.7 5923.8 5932.9 5502.1 5502.2 5507.5 5512.7 5514.3 6044.3 6240.3 6301.8 6304.4 6346.5 5750.1 5803.0 5807.8 6023.6 6073.7 6114.7 6166.4 6183.6 6234.9 6246.9 6134.6 6144.4 6144.8 6159.3 6161.6 5835.1 5862.2 5872.6 5999.1 6146.3 4799.0 4873.2 4887.8 4890.7 4920.8 4308.1 4342.4 4348.6 4362.8 4374.0 5415.7 5476.9 5569.9 5576.4 5586.7 6097.2 6112.6 6120.1 6136.5 6136.5 5478.4 5517.8 5522.0 5526.5 5538.7 5275.4 5279.2 5287.7 5289.5 5299.0 6250.2 6253.8 6266.5 6267.1 6269.8 6108.9 6111.6 6113.3 6116.9 6121.0 6244.3 6246.4 6247.8 6253.8 6264.9 5999.5 6105.5 6106.9 6115.9 6125.2 6035.5 6177.1 6186.9 6190.5 6206.8 5717.1 5739.9 5779.1 5828.4 5829.3 5686.7 5777.1 5843.1 5846.9 5847.0 5071.3 5735.4 5770.0 5801.2 5858.9 5942.2 5943.1 5960.3 5973.2 5989.7 5387.9 5461.1 5558.3 5764.9 5791.8 09:55:45 INFO - PROCESS | 1926 | 09:55:45 INFO - PROCESS | 1926 | [#1] ext.html Cycles:180 Average:64761.67 Median:20730.75 stddev:137244.92 (662.0%) stddev-sans-first:137544.94 09:55:45 INFO - PROCESS | 1926 | Values: 431.7 432.2 449.4 453.3 466.3 29284.9 38479.8 40142.0 40154.4 42700.8 40893.2 41338.8 41432.4 41448.8 41468.2 7901.5 9097.6 9299.8 9316.0 9335.3 7884.8 10926.9 11202.6 11212.7 11331.7 11053.6 11167.6 11223.5 11233.4 11240.7 7869.6 8082.6 11096.7 11187.9 11208.6 10762.8 11043.4 11059.4 11231.3 11249.8 38298.2 38596.0 38654.2 38675.4 38831.4 3751.2 3911.0 3924.4 3949.0 4093.2 22562.4 26444.1 27889.6 27986.4 28038.7 3041.7 3243.9 3244.0 3245.5 3254.0 435966.8 437321.0 437736.0 438735.0 442771.0 45009.9 46632.8 46658.6 53403.5 53755.7 71399.0 71499.3 71574.0 71652.6 76816.2 14300.0 14525.7 14797.4 14860.1 14875.9 10929.9 11010.7 11079.1 11135.9 11139.5 19985.6 20085.0 20160.2 20195.3 20237.0 7643.7 8393.3 8405.4 8456.8 8520.8 835.3 849.2 849.9 851.8 865.0 23004.3 23277.7 23411.9 27160.5 29350.9 883.8 1024.2 1045.2 1046.7 1174.5 14932.8 15014.4 15058.0 15304.0 15466.1 593808.0 595108.0 598357.0 599067.0 599344.0 57212.7 57493.7 57607.7 57686.0 57698.1 1326.0 1378.4 1390.2 1393.9 1404.2 25186.7 25578.0 27764.9 28038.2 29509.0 48709.8 48865.5 48959.8 48994.0 49010.5 494144.0 494737.0 496082.0 496900.0 497660.0 32616.0 33438.7 39402.6 40131.2 40191.4 44185.3 44553.3 44766.0 45013.0 45667.0 36610.1 36754.8 37292.7 38029.0 38155.8 34703.7 36171.0 37456.1 37710.2 38502.6 35186.3 35256.9 35637.3 35805.9 36224.3 24506.4 24562.6 27014.0 28942.0 30963.4 23190.8 23426.8 23682.8 24061.6 24368.5 09:55:45 INFO - PROCESS | 1926 | 09:55:45 INFO - PROCESS | 1926 | [#2] jquery.html Cycles:180 Average:36590.04 Median:21031.82 stddev:112661.15 (535.7%) stddev-sans-first:112944.39 09:55:45 INFO - PROCESS | 1926 | Values: 382.0 387.2 387.5 392.6 414.4 18278.9 19155.9 20744.5 20986.5 21037.1 11913.3 12265.8 12395.4 12409.5 12427.8 5078.3 5384.2 5389.0 5393.4 5394.8 5252.4 5370.2 5669.3 5959.1 6023.2 5992.5 6063.1 6087.1 6091.1 6096.2 5918.9 5954.5 5993.7 6046.6 6046.8 5635.2 5866.1 5871.8 5877.5 5877.7 13260.7 13667.2 13677.5 13682.3 14186.6 2543.7 2549.0 2561.4 2581.7 2584.4 8620.4 9807.0 9915.7 10350.6 10455.8 1684.8 1701.2 1737.8 1793.4 1795.1 89461.9 122691.0 122774.4 124062.0 124717.0 11397.2 11691.4 11718.5 11740.9 11919.7 15679.9 15686.1 15691.0 15700.9 15707.0 7801.7 8059.4 8093.8 8200.9 8217.1 7556.0 7600.2 7611.0 7738.7 7763.7 4073.8 4727.4 4727.6 4740.2 4810.4 1925.9 2030.7 2030.8 2031.7 2032.4 968.4 1018.9 1026.4 1036.4 1040.1 19488.0 21021.7 21026.6 21114.8 21189.8 997.3 1008.4 1055.3 1060.7 1061.4 8163.3 8965.6 10884.1 10928.9 11057.6 667520.0 673807.0 678943.0 687174.0 688073.0 91113.0 100658.0 107695.5 127131.0 160234.0 1042.3 1066.9 1091.4 1092.5 1098.7 19403.7 19561.0 19599.2 19690.7 19801.2 52665.0 54251.5 56538.0 58714.0 58719.3 80508.6 81240.0 82480.0 82550.6 83973.0 8194.9 8261.1 8423.2 8450.6 8621.3 9119.2 12058.8 12697.2 13203.5 13233.7 10703.8 10997.3 11460.9 11491.1 11515.7 11014.4 11057.1 11284.8 11304.5 11331.0 11032.8 11491.5 11565.2 11573.8 11768.4 8093.7 8336.2 8421.7 8483.4 8646.5 9427.7 10187.6 10518.6 10593.7 10612.5 09:55:45 INFO - PROCESS | 1926 | 09:55:45 INFO - PROCESS | 1926 | [#3] mootools.html Cycles:180 Average:5347.98 Median:5263.29 stddev:2943.49 (55.9%) stddev-sans-first:2935.40 09:55:45 INFO - PROCESS | 1926 | Values: 1219.9 1384.5 1402.4 1412.4 1414.6 7167.9 8074.0 8520.8 8526.7 8532.4 8101.2 8378.9 8388.6 8538.0 8548.1 2143.6 2289.6 2293.0 2294.3 2298.0 1790.5 1907.2 2257.6 2306.3 2307.7 1924.7 2193.7 2261.3 2269.4 2284.2 1827.2 2227.2 2252.1 2254.2 2256.5 2223.7 2283.2 2284.6 2285.4 2290.4 8397.5 8477.9 8482.2 8498.1 8499.7 4943.9 5047.6 5196.4 5203.1 5215.9 3270.5 3461.5 3487.0 3528.2 3534.8 1169.9 1227.7 1233.6 1241.0 1259.5 8843.8 9423.4 10815.8 10917.8 10995.2 4128.3 4404.6 4462.8 4483.1 4498.1 8729.2 8742.3 8757.7 8761.1 8785.7 5040.5 5045.6 5058.4 5060.9 5062.0 4572.6 4611.2 4676.2 4717.5 4766.8 3071.2 3561.4 3599.1 3614.5 3666.3 911.2 1115.8 1126.6 1135.9 1151.6 658.3 744.1 822.4 837.9 911.7 5310.7 5555.3 5562.5 5712.4 5924.6 809.7 939.2 940.2 974.7 977.2 2806.7 3572.2 3663.9 3673.8 3703.9 9820.2 9879.3 9885.9 10163.7 10181.2 9441.2 9838.2 9901.9 10067.3 10100.8 7620.8 7770.8 7786.6 7792.8 7799.8 5449.3 5478.8 5547.7 5649.9 5776.2 8855.3 9144.6 9191.2 9261.5 9276.4 8685.4 8917.9 9134.3 9382.7 9413.0 7036.3 7061.7 7195.5 7223.1 7256.5 7815.8 7901.6 7909.9 7916.8 7918.3 7026.9 7102.6 7123.1 7188.4 7261.0 6844.9 6971.3 6987.5 6989.7 7074.2 6348.9 6384.7 6477.8 6489.7 6493.0 4818.0 4854.9 4894.8 4909.5 4996.9 4821.5 4858.5 4894.8 4981.3 4985.9 09:55:45 INFO - PROCESS | 1926 | 09:55:45 INFO - PROCESS | 1926 | [#4] prototype.html Cycles:180 Average:4327.67 Median:4855.05 stddev:1309.42 (27.0%) stddev-sans-first:1278.77 09:55:45 INFO - PROCESS | 1926 | Values: 359.6 362.7 369.7 381.9 381.9 5107.9 5146.3 5297.8 5329.8 5330.9 4670.8 4925.7 5099.7 5175.2 5234.7 3579.9 3672.4 3947.9 3969.4 3984.7 3968.9 3982.8 3988.6 4000.2 4026.7 3211.7 3294.7 3798.7 3937.6 4010.0 3215.7 3922.7 3930.4 3930.4 3953.2 3048.7 3407.5 3422.2 3424.6 3454.6 5016.8 5072.8 5091.0 5113.0 5113.7 4338.9 4452.4 4491.5 4559.3 4564.5 5509.4 5552.6 5555.7 5555.8 5560.3 3198.7 3410.7 3817.3 3818.6 3827.7 5192.4 5421.2 5662.0 5837.5 5840.6 3814.5 3839.9 3844.7 3848.2 3865.4 3343.7 3902.5 3915.3 3922.7 3934.6 4261.2 4510.8 4545.0 4579.3 4586.7 4867.2 5144.9 5507.9 5534.7 5586.2 1153.1 1254.7 1272.6 1280.3 1281.2 757.0 800.1 824.6 863.0 887.7 4412.6 4480.7 4938.5 4976.1 4999.9 4508.6 5028.7 5456.6 5457.7 5509.5 4678.3 4804.0 4950.6 4985.6 5005.4 2476.0 2485.9 2554.7 2653.9 2892.0 4745.5 5662.3 5664.0 5686.5 5690.6 4784.5 5233.3 5455.6 5619.7 5682.5 4914.8 5028.8 5361.5 5611.0 5652.5 4520.4 4897.3 5326.8 5402.7 5426.0 4282.4 4842.9 4969.8 5191.5 5223.6 4772.0 4997.4 5429.6 5528.4 5617.9 3372.0 3602.2 3602.9 3641.3 3657.5 4599.8 4682.8 5030.3 5402.5 5454.4 4719.5 4989.4 5267.8 5270.4 5270.5 5055.9 5255.0 5258.1 5266.5 5285.8 4764.4 4802.0 4912.7 4953.3 5200.7 4713.5 4802.3 5175.4 5256.0 5277.6 4506.2 4838.4 4910.5 4987.9 5155.5 09:55:45 INFO - PROCESS | 1926 | 09:55:45 INFO - PROCESS | 1926 | [#5] yui.html Cycles:180 Average:18379.93 Median:44231.11 stddev:25276.01 (57.1%) stddev-sans-first:25312.02 09:55:45 INFO - PROCESS | 1926 | Values: 692.7 714.5 758.1 803.3 818.4 11997.5 13857.0 13967.5 14291.4 14466.6 13116.4 13389.1 13401.2 13412.8 13418.9 853.6 863.5 868.1 868.5 869.2 822.7 841.4 855.3 858.1 866.9 908.4 909.3 909.8 913.9 914.7 903.2 905.4 905.6 907.3 908.2 668.0 669.2 670.2 672.0 672.3 13520.7 13674.5 14942.8 14967.1 14975.5 13406.1 13687.0 13718.6 13855.1 13904.3 11067.3 11451.0 11455.9 11468.2 11872.1 3166.4 3609.0 3627.9 3691.9 3773.3 85136.6 93736.5 95765.0 97400.5 99190.0 10820.8 10925.8 10943.4 10957.2 11098.4 21256.6 21457.8 21630.8 21711.8 21727.3 7295.8 7334.9 7370.4 7434.0 7467.9 3870.7 3959.3 3973.3 3985.3 4021.2 3387.4 3469.0 3473.1 3488.9 3490.0 672.2 676.2 678.7 689.7 689.9 649.3 655.2 673.2 686.4 716.0 8487.6 9265.2 9565.7 9613.6 10085.8 578.6 671.9 676.2 685.6 692.7 4580.5 4782.0 4833.0 4849.7 4895.7 74602.3 95465.0 99396.0 99827.0 101821.0 43383.6 49806.0 57262.0 71448.6 75659.2 45078.6 65478.7 66474.3 66959.3 67680.3 10233.9 10489.3 10567.3 10683.5 10718.3 45832.5 47528.0 48232.5 48613.0 49842.5 64267.7 64413.7 65429.0 65574.3 65633.7 10996.9 11055.2 11055.6 11153.0 11176.1 12429.7 13867.6 14094.4 14927.0 14942.7 13497.8 13648.3 13812.9 14675.9 14801.7 12889.3 13118.9 14473.4 14504.1 14700.0 12609.9 13295.4 14179.2 14633.1 14636.2 10654.4 11059.7 11388.3 11437.5 11607.8 9606.9 9817.4 11313.8 11560.1 11590.2 09:55:45 INFO - PROCESS | 1926 | -------- Summary: end -------- 09:55:45 INFO - PROCESS | 1926 | 09:55:46 INFO - TEST-INFO | 1926: exit 0 09:55:46 INFO - TEST-OK | dromaeo_css | took 1118767ms 09:55:46 INFO - TEST-START | kraken 09:55:46 INFO - operating with platform_type : mac_ 09:55:46 INFO - Initialising browser for kraken test... 09:55:46 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmp940lOi/profile http://localhost:52687/getInfo.html) 09:55:48 INFO - PROCESS | 1930 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 09:55:48 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 09:55:48 INFO - PROCESS | 1930 | colorDepth:24 09:55:48 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 09:55:48 INFO - PROCESS | 1930 | __metrics 09:55:48 INFO - PROCESS | 1930 | 1486403748520 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:55:48 INFO - PROCESS | 1930 | 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" 09:55:48 INFO - PROCESS | 1930 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:55:49 INFO - TEST-INFO | 1930: exit 0 09:55:49 INFO - Browser initialized. 09:55:49 INFO - Running cycle 1/1 for kraken test... 09:55:49 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmp940lOi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:01 INFO - PROCESS | 1931 | RSS: Main: 236724224 09:56:01 INFO - PROCESS | 1931 | 09:56:04 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52687/tests/kraken/driver.html?testName=audio-beat-detection) 09:56:05 INFO - PROCESS | 1931 | RSS: Main: 289140736 09:56:05 INFO - PROCESS | 1931 | 09:56:07 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52687/tests/kraken/driver.html?testName=audio-dft) 09:56:08 INFO - PROCESS | 1931 | RSS: Main: 292556800 09:56:08 INFO - PROCESS | 1931 | 09:56:11 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52687/tests/kraken/driver.html?testName=audio-fft) 09:56:12 INFO - PROCESS | 1931 | RSS: Main: 384311296 09:56:12 INFO - PROCESS | 1931 | 09:56:14 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52687/tests/kraken/driver.html?testName=audio-oscillator) 09:56:15 INFO - PROCESS | 1931 | RSS: Main: 284889088 09:56:15 INFO - PROCESS | 1931 | 09:56:17 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52687/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:56:17 INFO - PROCESS | 1931 | RSS: Main: 287744000 09:56:17 INFO - PROCESS | 1931 | 09:56:20 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52687/tests/kraken/driver.html?testName=imaging-darkroom) 09:56:21 INFO - PROCESS | 1931 | RSS: Main: 329236480 09:56:21 INFO - PROCESS | 1931 | 09:56:24 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52687/tests/kraken/driver.html?testName=imaging-desaturate) 09:56:24 INFO - PROCESS | 1931 | RSS: Main: 334118912 09:56:24 INFO - PROCESS | 1931 | 09:56:27 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52687/tests/kraken/driver.html?testName=json-parse-financial) 09:56:28 INFO - PROCESS | 1931 | RSS: Main: 328380416 09:56:28 INFO - PROCESS | 1931 | 09:56:29 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52687/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:56:30 INFO - PROCESS | 1931 | RSS: Main: 305930240 09:56:30 INFO - PROCESS | 1931 | 09:56:32 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:56:33 INFO - PROCESS | 1931 | RSS: Main: 310202368 09:56:33 INFO - PROCESS | 1931 | 09:56:35 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:56:35 INFO - PROCESS | 1931 | RSS: Main: 311865344 09:56:35 INFO - PROCESS | 1931 | 09:56:38 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:56:39 INFO - PROCESS | 1931 | RSS: Main: 329605120 09:56:39 INFO - PROCESS | 1931 | 09:56:42 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:56:43 INFO - PROCESS | 1931 | RSS: Main: 323997696 09:56:43 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52687/tests/kraken/driver.html?testName=ai-astar) 09:56:45 INFO - PROCESS | 1931 | RSS: Main: 369008640 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | __start_tp_report 09:56:45 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 09:56:45 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 09:56:45 INFO - PROCESS | 1931 | |i|pagename|runs| 09:56:45 INFO - PROCESS | 1931 | |0;ai-astar;113;115;114;111;114;116;114;112;112;113 09:56:45 INFO - PROCESS | 1931 | |1;audio-beat-detection;150;112;111;150;194;140;109;110;132;112 09:56:45 INFO - PROCESS | 1931 | |2;audio-dft;186;183;183;185;181;185;181;184;183;186 09:56:45 INFO - PROCESS | 1931 | |3;audio-fft;77;58;61;108;88;85;48;70;90;84 09:56:45 INFO - PROCESS | 1931 | |4;audio-oscillator;92;70;87;70;70;70;86;68;70;83 09:56:45 INFO - PROCESS | 1931 | |5;imaging-gaussian-blur;86;84;87;85;88;83;87;88;136;83 09:56:45 INFO - PROCESS | 1931 | |6;imaging-darkroom;93;94;95;94;93;94;94;93;93;94 09:56:45 INFO - PROCESS | 1931 | |7;imaging-desaturate;87;88;85;88;87;88;87;87;87;89 09:56:45 INFO - PROCESS | 1931 | |8;json-parse-financial;62;57;58;58;59;58;58;59;59;60 09:56:45 INFO - PROCESS | 1931 | |9;json-stringify-tinderbox;70;79;70;72;74;68;72;71;71;75 09:56:45 INFO - PROCESS | 1931 | |10;stanford-crypto-aes;87;87;87;86;86;86;86;87;87;91 09:56:45 INFO - PROCESS | 1931 | |11;stanford-crypto-ccm;144;152;156;138;135;136;139;139;184;158 09:56:45 INFO - PROCESS | 1931 | |12;stanford-crypto-pbkdf2;171;175;236;209;228;193;170;171;170;170 09:56:45 INFO - PROCESS | 1931 | |13;stanford-crypto-sha256-iterative;64;83;80;69;66;67;66;66;64;65 09:56:45 INFO - PROCESS | 1931 | __end_tp_report 09:56:45 INFO - PROCESS | 1931 | __start_cc_report 09:56:45 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,2042 09:56:45 INFO - PROCESS | 1931 | __end_cc_report 09:56:45 INFO - PROCESS | 1931 | __startTimestamp1486403805353__endTimestamp 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | ------- Summary: start ------- 09:56:45 INFO - PROCESS | 1931 | Number of tests: 14 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:1.51 (1.3%) stddev-sans-first:1.59 09:56:45 INFO - PROCESS | 1931 | Values: 113.0 115.0 114.0 111.0 114.0 116.0 114.0 112.0 112.0 113.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:136.00 stddev:27.51 (20.2%) stddev-sans-first:28.39 09:56:45 INFO - PROCESS | 1931 | Values: 150.0 112.0 111.0 150.0 194.0 140.0 109.0 110.0 132.0 112.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:1.83 (1.0%) stddev-sans-first:1.74 09:56:45 INFO - PROCESS | 1931 | Values: 186.0 183.0 183.0 185.0 181.0 185.0 181.0 184.0 183.0 186.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#3] audio-fft Cycles:10 Average:76.90 Median:80.50 stddev:17.86 (22.2%) stddev-sans-first:18.94 09:56:45 INFO - PROCESS | 1931 | Values: 77.0 58.0 61.0 108.0 88.0 85.0 48.0 70.0 90.0 84.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:76.50 stddev:9.23 (12.1%) stddev-sans-first:7.93 09:56:45 INFO - PROCESS | 1931 | Values: 92.0 70.0 87.0 70.0 70.0 70.0 86.0 68.0 70.0 83.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:86.50 stddev:16.03 (18.5%) stddev-sans-first:16.91 09:56:45 INFO - PROCESS | 1931 | Values: 86.0 84.0 87.0 85.0 88.0 83.0 87.0 88.0 136.0 83.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 09:56:45 INFO - PROCESS | 1931 | Values: 93.0 94.0 95.0 94.0 93.0 94.0 94.0 93.0 93.0 94.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#7] imaging-desaturate Cycles:10 Average:87.30 Median:87.50 stddev:1.06 (1.2%) stddev-sans-first:1.12 09:56:45 INFO - PROCESS | 1931 | Values: 87.0 88.0 85.0 88.0 87.0 88.0 87.0 87.0 87.0 89.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:59.00 stddev:1.40 (2.4%) stddev-sans-first:0.88 09:56:45 INFO - PROCESS | 1931 | Values: 62.0 57.0 58.0 58.0 59.0 58.0 58.0 59.0 59.0 60.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.00 stddev:3.12 (4.3%) stddev-sans-first:3.21 09:56:45 INFO - PROCESS | 1931 | Values: 70.0 79.0 70.0 72.0 74.0 68.0 72.0 71.0 71.0 75.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.00 stddev:1.49 (1.7%) stddev-sans-first:1.58 09:56:45 INFO - PROCESS | 1931 | Values: 87.0 87.0 87.0 86.0 86.0 86.0 86.0 87.0 87.0 91.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:148.00 stddev:15.15 (10.2%) stddev-sans-first:16.00 09:56:45 INFO - PROCESS | 1931 | Values: 144.0 152.0 156.0 138.0 135.0 136.0 139.0 139.0 184.0 158.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.30 Median:184.00 stddev:25.97 (14.1%) stddev-sans-first:26.69 09:56:45 INFO - PROCESS | 1931 | Values: 171.0 175.0 236.0 209.0 228.0 193.0 170.0 171.0 170.0 170.0 09:56:45 INFO - PROCESS | 1931 | 09:56:45 INFO - PROCESS | 1931 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:66.50 stddev:6.78 (10.2%) stddev-sans-first:6.95 09:56:45 INFO - PROCESS | 1931 | Values: 64.0 83.0 80.0 69.0 66.0 67.0 66.0 66.0 64.0 65.0 09:56:45 INFO - PROCESS | 1931 | -------- Summary: end -------- 09:56:45 INFO - PROCESS | 1931 | 09:56:46 INFO - TEST-INFO | 1931: exit 0 09:56:46 INFO - TEST-OK | kraken | took 60019ms 09:56:46 INFO - SUITE-END | took 1178s 09:56:46 INFO - Completed test suite (00:19:39) 09:56:46 INFO - javascript benchmark 09:56:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7056.0791545985885, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3237.546743455916, 3245.594300712411, 3254.9023529294113, 3281.80309181449, 3308.106811160985, 5402.594805389602, 5466.398696143426, 5479.643823151492, 5528.530074943629, 5536.114221724525, 5521.4478552144765, 5523.475269849358, 5533.031719449091, 5571.777128914849, 5580.776768929248, 4788.592969597588, 4990.27640992056, 5020.54815066643, 5066.265391518227, 5182.689038657673, 5118.744062796864, 5138.665986710852, 5163.793448262065, 5173.5861131109605, 5228.581713462914, 4448.043670610834, 4625.907481850378, 4817.975910120462, 5005.47442518534, 5030.220315851049, 5048.7980480780725, 5108.591312694975, 5127.1796512558185, 5159.5614372778355, 5186.688798672092, 5040.873978150539, 5071.644984851062, 5110.8466745997675, 5125.026245013472, 5126.128558145124, 5295.735213239361, 5330.200469929503, 5562.749676264578, 5572.888542229173, 5578.44215578443, 5817.360090390053, 5823.126531020381, 5826.242588463472, 5837.416258374129, 5842.941570584282, 5793.292292932484, 5921.763129474816, 5922.703864806743, 5923.792667256668, 5932.851678708023, 5502.0921547944945, 5502.202180683818, 5507.476789704971, 5512.669239845582, 5514.310711161105, 6044.274687037496, 6240.282367527701, 6301.8109456716375, 6304.432601065926, 6346.460550853137, 5750.137479378127, 5803.0, 5807.796727114615, 6023.6385816851125, 6073.696315184223, 6114.732076222627, 6166.445019948226, 6183.598066125687, 6234.875302494015, 6246.906296405557, 6134.631922084689, 6144.41628045335, 6144.754209831646, 6159.3224745277985, 6161.630302181883, 5835.124731290341, 5862.237909071879, 5872.559558033165, 5999.130126131687, 6146.323904370515, 4799.0401919615515, 4873.19592267279, 4887.780049897692, 4890.657653964259, 4920.75396230187, 4308.073764140662, 4342.392065110887, 4348.63036641889, 4362.8036738346855, 4373.978130109329, 5415.702136382537, 5476.890462190813, 5569.916451253234, 5576.386597474275, 5586.692731899784, 6097.207363042865, 6112.633242005494, 6120.051392034242, 6136.478399336017, 6136.509079273647, 5478.369987451414, 5517.751701173457, 5521.972390138104, 5526.50261476469, 5538.723063846792, 5275.366955965309, 5279.155335146358, 5287.735613219324, 5289.523942845162, 5299.019681358961, 6250.218722659668, 6253.78111766086, 6266.467350275187, 6267.059941008885, 6269.78055768055, 6108.938910610837, 6111.633302001894, 6113.29697084841, 6116.908246376397, 6120.9693951531735, 6244.313125556095, 6246.437820596168, 6247.750089996349, 6253.812385628438, 6264.937350626436, 5999.45351119067, 6105.520399749808, 6106.855059071508, 6115.94015414234, 6125.17440701618, 6035.517158627387, 6177.073438984188, 6186.907196392148, 6190.50475961913, 6206.813795586139, 5717.056685646821, 5739.8852022960145, 5779.133130030529, 5828.417158284307, 5829.300483941955, 5686.687232202103, 5777.133429985368, 5843.1235314703135, 5846.853828654318, 5847.0, 5071.315372424676, 5735.3977832327, 5769.9711501442225, 5801.158831969256, 5858.882822343444, 5942.227510423617, 5943.138244954544, 5960.254968128984, 5973.1637570741, 5989.670568118795, 5387.949349876893, 5461.1262198048125, 5558.277423934863, 5764.855878603068, 5791.768329266951], "value": 5532.2097046129975, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.7323259579061, 432.1810169728367, 449.3888311895821, 453.3381263355501, 466.30520524418614, 29284.85357573212, 38479.80760096199, 40141.98935079866, 40154.39768403476, 42700.78649606755, 40893.18213635733, 41338.793306033505, 41432.37851432231, 41448.79275603618, 41468.17063658725, 7901.525908445512, 9097.63609455621, 9299.814003719921, 9315.975242723329, 9335.3465257432, 7884.842303153933, 10926.890731092706, 11202.551897924073, 11212.719682007933, 11331.660050198507, 11053.613123540677, 11167.609133680322, 11223.494942727597, 11233.438328083605, 11240.718982025433, 7869.567173805438, 8082.555459449728, 11096.722581935475, 11187.888121118807, 11208.551657933673, 10762.838557421645, 11043.392613406259, 11059.391733454657, 11231.326120432801, 11249.831252531138, 38298.234035319416, 38596.0, 38654.226915461535, 38675.41986870199, 38831.41752873709, 3751.249750050001, 3911.0417947602737, 3924.4113382992496, 3949.0, 4093.1813637272667, 22562.43593910149, 26444.074457393897, 27889.58165627517, 27986.440271194664, 28038.71961280402, 3041.726244637971, 3243.8702451901895, 3244.026791962402, 3245.4807230842953, 3254.0, 435966.82016590034, 437321.0, 437736.0, 438735.0, 442771.0, 45009.87475313144, 46632.83417914512, 46658.6002419928, 53403.46596534063, 53755.65610859681, 71399.0, 71499.28500714926, 71574.0, 71652.64173679202, 76816.23183768202, 14300.0, 14525.709485810436, 14797.408103675732, 14860.108393496424, 14875.925620372045, 10929.945350273198, 11010.669679909615, 11079.113670906308, 11135.944320278346, 11139.498722557504, 19985.60028799416, 20085.0, 20160.193592256437, 20195.293164739163, 20237.0, 7643.7324693635455, 8393.328533717287, 8405.41162118658, 8456.801231679807, 8520.829583408422, 835.3359079531787, 849.2229609906866, 849.9277561407226, 851.7827953871723, 864.9567521623893, 23004.309870703906, 23277.719725414925, 23411.88294058553, 27160.45679086407, 29350.853245733633, 883.7878909061778, 1024.1653052761976, 1045.231754660324, 1046.7016900183564, 1174.4773575758877, 14932.776008359884, 15014.399424022917, 15058.0, 15304.005239659387, 15466.072035677671, 593808.0, 595108.0, 598357.0, 599067.0, 599344.0, 57212.71393643005, 57493.71253143708, 57607.711961440764, 57686.0, 57698.13452798211, 1326.0452474218548, 1378.372840357635, 1390.1728471559406, 1393.9233342166278, 1404.2276747788676, 25186.74813251844, 25578.0, 27764.861175693994, 28038.177518461234, 29509.0, 48709.756451218935, 48865.511344886094, 48959.755201223765, 48994.0, 49010.50989490059, 494144.0, 494737.0, 496082.0, 496900.0, 497660.0, 32616.0, 33438.66561334355, 39402.60597394104, 40131.1973760529, 40191.3971290425, 44185.33721994108, 44553.331700023875, 44766.0, 45013.0, 45667.0, 36610.08474788045, 36754.8162259187, 37292.692067457836, 38029.0, 38155.80922095372, 34703.65296347004, 36171.0, 37456.063598410256, 37710.24579508449, 38502.61497385102, 35186.296274073866, 35256.94229173129, 35637.28725425529, 35805.92582222538, 36224.27551449009, 24506.42774440094, 24562.63156052625, 27014.0, 28942.0, 30963.380732385678, 23190.76809231886, 23426.79065791272, 23682.763172368745, 24061.639075414234, 24368.51262974766], "value": 19443.39151022515, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.975171613845, 387.2216844143271, 387.50012483895773, 392.5544506984572, 414.3825699707437, 18278.90860545697, 19155.90422047888, 20744.48138796529, 20986.475338116554, 21037.054812326267, 11913.285202887811, 12265.754684906296, 12395.380230988438, 12409.503619855217, 12427.751444971094, 5078.263651770491, 5384.219288203209, 5389.0, 5393.379761327458, 5394.757235924373, 5252.3697156341295, 5370.221317908901, 5669.348024977139, 5959.1359252908305, 6023.18686977259, 5992.49063829575, 6063.060225665029, 6087.147799308101, 6091.11678806575, 6096.2074930259005, 5918.88162236755, 5954.4640982311585, 5993.700314984255, 6046.637201767908, 6046.788362407295, 5635.239242702224, 5866.120081987736, 5871.765129394819, 5877.52979761618, 5877.67672777997, 13260.668483287898, 13667.17996920188, 13677.521286755014, 13682.31588420585, 14186.645333866632, 2543.6566063581363, 2548.9872550637315, 2561.3852675357966, 2581.716011238781, 2584.4055867150473, 8620.439671421338, 9807.0, 9915.702528924145, 10350.63772767957, 10455.790884182274, 1684.7978242610843, 1701.1834319526697, 1737.791465024205, 1793.3543924187281, 1795.1203910084096, 89461.94842464145, 122691.0, 122774.38612806969, 124062.0, 124717.0, 11397.202195846377, 11691.356975366436, 11718.531258749554, 11740.882591173979, 11919.70200744971, 15679.921600391925, 15686.137262450446, 15691.0, 15700.900936934306, 15707.0, 7801.726939557088, 8059.355251579859, 8093.757187284391, 8200.876986845202, 8217.055038670629, 7555.962220188864, 7600.1639819619795, 7610.961945190349, 7738.729144479917, 7763.72826951054, 4073.796310184479, 4727.409073865767, 4727.64542659302, 4740.241561350167, 4810.374651295379, 1925.8541168005015, 2030.715699802029, 2030.7969203079574, 2031.6850888112367, 2032.3699653107287, 968.3802366485455, 1018.8930162332997, 1026.414943482223, 1036.4247842447364, 1040.1262939131166, 19488.0, 21021.68467472989, 21026.579468410546, 21114.788852111284, 21189.788102118782, 997.2719914462338, 1008.4050410257983, 1055.2560444886337, 1060.7030031591166, 1061.4374381577777, 8163.346932245406, 8965.596548155349, 10884.07485363753, 10928.945355273172, 11057.612983545538, 667520.0, 673807.0, 678943.0, 687174.0, 688073.0, 91113.0, 100657.98348296552, 107695.46152269188, 127131.0, 160233.97660234116, 1042.2756184451732, 1066.898644628759, 1091.356099901078, 1092.464692300767, 1098.6703988803326, 19403.708944365564, 19561.021948902213, 19599.216031358585, 19690.704639430132, 19801.207951681772, 52665.0, 54251.45748542464, 56538.0, 58714.0, 58719.30245580429, 80508.59745701468, 81240.0, 82480.0, 82550.58724706338, 83973.0, 8194.918050819415, 8261.13258107914, 8423.241908228289, 8450.619722112397, 8621.267192288726, 9119.224865886474, 12058.819117713067, 12697.238165710087, 13203.471861125447, 13233.669158271121, 10703.839442408525, 10997.34015959045, 11460.942695286803, 11491.080713543064, 11515.654530364103, 11014.44927753625, 11057.06014988735, 11284.774304514029, 11304.547818087185, 11331.0, 11032.779344413228, 11491.54033838637, 11565.248258863146, 11573.768524629628, 11768.411579421167, 8093.676252949816, 8336.166383361615, 8421.747347579583, 8483.44857584255, 8646.524441155545, 9427.733858039577, 10187.592496300362, 10518.579256829642, 10593.735156621146, 10612.469376531297], "value": 9376.525548223368, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1219.9451024703887, 1384.536180379573, 1402.3829515013392, 1412.3997301147024, 1414.6321956291365, 7167.928320716791, 8074.031116266049, 8520.78698032548, 8526.70156544521, 8532.402731808768, 8101.23038311361, 8378.91621083789, 8388.580570971442, 8538.0, 8548.14518548145, 2143.560570083133, 2289.645105008726, 2292.9656055159185, 2294.300238427279, 2298.0, 1790.489710432527, 1907.2180406033528, 2257.571061498318, 2306.3426923326856, 2307.665388518664, 1924.6728056230404, 2193.6928829963817, 2261.3329067925, 2269.353234328217, 2284.1776960294283, 1827.2416946966994, 2227.20934068405, 2252.054137262354, 2254.1659585953303, 2256.4922892349255, 2223.6998005269406, 2283.1666441748807, 2284.588774020676, 2285.382946604424, 2290.3701482092392, 8397.454165479225, 8477.915220847774, 8482.15178482153, 8498.065212826585, 8499.660013599449, 4943.90112197754, 5047.621428392885, 5196.376434827807, 5203.089459344599, 5215.869603259949, 3270.5094235864813, 3461.498082778008, 3487.0, 3528.153243221634, 3534.8232588370734, 1169.859616845984, 1227.6808029912197, 1233.5867484392738, 1240.975180496394, 1259.5339724301975, 8843.806086178349, 9423.40856264526, 10815.783684326349, 10917.836232456519, 10995.175361847814, 4128.256913755493, 4404.55954404557, 4462.799174037176, 4483.058557702919, 4498.077894031716, 8729.21437070654, 8742.344324175712, 8757.693480728143, 8761.123887611315, 8785.69250076257, 5040.49595040493, 5045.571126454219, 5058.367704036996, 5060.924086138711, 5061.9876024795185, 4572.565606267399, 4611.16998940194, 4676.205045142334, 4717.50681605606, 4766.833160839354, 3071.216839705861, 3561.394562924309, 3599.082234030358, 3614.548181477315, 3666.32173047987, 911.2254583603884, 1115.8214685650257, 1126.6056880091903, 1135.8920902514249, 1151.591185129267, 658.2857599504496, 744.118219909409, 822.3749950038015, 837.8533756592572, 911.6581282019242, 5310.707911064928, 5555.2778138841695, 5562.471565201299, 5712.400197979236, 5924.555658325643, 809.704457872884, 939.248601119102, 940.2196177173066, 974.6978436684509, 977.1742877268697, 2806.7052959439375, 3572.160542272564, 3663.9267214656093, 3673.779573225615, 3703.8702296559363, 9820.165285950774, 9879.259055570861, 9885.901140988499, 10163.7459063524, 10181.236407269484, 9441.15029647335, 9838.163756080814, 9901.900980990242, 10067.295289329822, 10100.797984040279, 7620.771376858703, 7770.8057298566855, 7786.57173855432, 7792.766217013499, 7799.766007019798, 5449.3188351456065, 5478.780848766005, 5547.667139971614, 5649.915251271152, 5776.191333213437, 8855.291576673979, 9144.63421463134, 9191.172794448532, 9261.53692315368, 9276.443413395218, 8685.435446695945, 8917.866232006396, 9134.314926380415, 9382.718518444184, 9412.952935235555, 7036.261192574911, 7061.682224299816, 7195.532290401107, 7223.133224013152, 7256.528325659026, 7815.765527034198, 7901.604919754104, 7909.9209007909185, 7916.802079947817, 7918.287354138155, 7026.894596581157, 7102.644867756695, 7123.074000380125, 7188.424926006011, 7261.019762332229, 6844.931550684629, 6971.337722916315, 6987.475939304471, 6989.685464154024, 7074.221835598183, 6348.936510634834, 6384.712687929147, 6477.838054048687, 6489.7404103835315, 6493.026046093124, 4817.9518204817505, 4854.854354369375, 4894.804207831647, 4909.459959404534, 4996.900061998813, 4821.54195351441, 4858.514148585114, 4894.755262236945, 4981.252812078217, 4985.850424487271], "value": 4310.966337628984, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.5882714292135, 362.6717820372564, 369.67653303359555, 381.90834199792056, 381.93125237457264, 5107.876267221215, 5146.330976972993, 5297.8344764151925, 5329.760160792764, 5330.920036199451, 4670.789814458349, 4925.679830811004, 5099.668521546097, 5175.24958880962, 5234.685918844872, 3579.8568057277807, 3672.4124140137583, 3947.9013024674327, 3969.4045893116117, 3984.7210695251415, 3968.8610898618554, 3982.8008599569907, 3988.5811989741096, 4000.2399544086675, 4026.6577340926056, 3211.710946014858, 3294.7034766870975, 3798.7340886137845, 3937.6259255370837, 4009.9975006248437, 3215.678432156789, 3922.7049278605823, 3930.351492003816, 3930.351492003816, 3953.2488827122756, 3048.7103725146076, 3407.4718418645148, 3422.19578399076, 3424.5548078749853, 3454.619991800925, 5016.824411145629, 5072.847814565569, 5091.032703786269, 5113.0, 5113.667611605234, 4338.934915976263, 4452.3989261449615, 4491.528389519086, 4559.310007095829, 4564.5435456454425, 5509.421510741395, 5552.639078459887, 5555.749991250404, 5555.777768889239, 5560.277163968658, 3198.7441004719567, 3410.7100896423585, 3817.3319669058083, 3818.6372294631965, 3827.674647654953, 5192.376914770214, 5421.153894205534, 5661.971690141523, 5837.532997360201, 5840.561957853136, 3814.48504451901, 3839.9232015360094, 3844.6732027777953, 3848.230353929225, 3865.420186971977, 3343.732501399882, 3902.453656488094, 3915.275674000319, 3922.666573341298, 3934.6065393460426, 4261.208908165157, 4510.819567217341, 4545.0000999779995, 4579.335996280521, 4586.72479651222, 4867.174809446457, 5144.871378215575, 5507.917381239285, 5534.695591742412, 5586.24585680936, 1153.1293873125737, 1254.738987317758, 1272.6309370282515, 1280.302235281755, 1281.1544380708688, 756.9962150189324, 800.0759123212698, 824.6041899888087, 863.0247819963527, 887.689308741948, 4412.624926881251, 4480.731156130642, 4938.530839570235, 4976.129177394014, 4999.875003124951, 4508.639308855284, 5028.748562571856, 5456.645318054314, 5457.742960490824, 5509.5041446270025, 4678.345031695565, 4803.95196048042, 4950.603951683856, 4985.5762260208285, 5005.399352077774, 2475.95048099037, 2485.9254222373356, 2554.6806649168852, 2653.933651658724, 2892.0022592205655, 4745.549172828576, 5662.2639056922335, 5664.0, 5686.488216060575, 5690.601657883908, 4784.497627749036, 5233.3310011893445, 5455.61810673257, 5619.719014049363, 5682.494139053154, 4914.803407863788, 5028.823991160292, 5361.51746342831, 5610.97194514033, 5652.491275785199, 4520.389747384061, 4897.338859254098, 5326.813561525328, 5402.72986350673, 5426.04662321556, 4282.351294751481, 4842.927356089732, 4969.751512424292, 5191.454897235736, 5223.582113430993, 4771.95228047715, 4997.375328083989, 5429.592780541554, 5528.364238112668, 5617.876424714992, 3371.983140084284, 3602.225521512887, 3602.927941441209, 3641.289948460025, 3657.4638651766854, 4599.839005634787, 4682.789274482589, 5030.3148445271245, 5402.540784033244, 5454.427285135003, 4719.504452032625, 4989.351384320015, 5267.762950667153, 5270.367555893317, 5270.472952704698, 5055.9494405055475, 5254.9799403457155, 5258.055488926214, 5266.499047771366, 5285.7621407036, 4764.4282686077895, 4801.975990120027, 4912.729799861042, 4953.3065370848835, 5200.6619569727845, 4713.525399165465, 4802.27965805132, 5175.430702622633, 5256.0, 5277.551408130198, 4506.18888600042, 4838.419389673261, 4910.459849416633, 4987.875303117451, 5155.510226528474], "value": 3926.8226379527837, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.6735301896867, 714.4605822603936, 758.1395116542726, 803.2931020701782, 818.4311903227256, 11997.520099196032, 13857.030007899451, 13967.511137110203, 14291.356888939996, 14466.5660030199, 13116.409761560728, 13389.129706569067, 13401.195928244311, 13412.798808017887, 13418.932905335483, 853.6372041882206, 863.4560227056945, 868.0841711993844, 868.5223127280002, 869.2437579306013, 822.6956026270259, 841.3731769831484, 855.2730179347567, 858.0647094666812, 866.9133086691344, 908.4004556992386, 909.3225546967485, 909.7862002429424, 913.8629205619144, 914.6661468563959, 903.223228023899, 905.4431524612316, 905.5608030105394, 907.3240435875249, 908.1917093786535, 668.0446960845989, 669.2170160911696, 670.2258890980925, 671.96976136074, 672.2806596941275, 13520.661983450394, 13674.521391751341, 14942.775858362133, 14967.101973881601, 14975.475858345015, 13406.128601640861, 13687.001072322126, 13718.588442346745, 13855.099418537864, 13904.304784760721, 11067.315177026429, 11451.0, 11455.942720286428, 11468.197226194086, 11872.050235981102, 3166.366726654678, 3608.981955090234, 3627.909302267438, 3691.9077023074638, 3773.2830762155104, 85136.57431712863, 93736.53131734366, 95765.0, 97400.53899191518, 99190.01619967632, 10820.78358432827, 10925.836112458319, 10943.398113103696, 10957.17821163416, 11098.44507774608, 21256.574868502543, 21457.78542214589, 21630.783692163193, 21711.782882171294, 21727.34817955464, 7295.781126566211, 7334.9266507335315, 7370.373518250902, 7434.0, 7467.887981680279, 3870.651641352292, 3959.2675355059405, 3973.264945985002, 3985.342418500915, 4021.2158629067053, 3387.4241378965635, 3469.0, 3473.114355839245, 3488.89533314001, 3490.0, 672.186654148476, 676.2358534855582, 678.7149397253165, 689.7378995981495, 689.9068625735562, 649.2566011916424, 655.1613934164277, 673.161913417798, 686.4131167851415, 715.992840071603, 8487.618057187317, 9265.166135047879, 9565.665201717906, 9613.567389467491, 10085.848712269322, 578.5689661202431, 671.9193696756421, 676.1615596660106, 685.5715178013742, 692.7194486233084, 4580.473245576734, 4782.043591281688, 4833.0, 4849.684770489906, 4895.662929417847, 74602.25397745954, 95465.0, 99396.0, 99827.0, 101820.98179018115, 43383.56616433858, 49806.0, 57262.0, 71448.64275678693, 75659.24340756632, 45078.647640570176, 65478.67260663666, 66474.33525664681, 66959.33040669726, 67680.32319676838, 10233.9488302558, 10489.318194317346, 10567.313124646873, 10683.465826708478, 10718.303310284806, 45832.54167458416, 47528.0, 48232.517674822804, 48613.0, 49842.501574983784, 64267.67866160639, 64413.677931610044, 65429.0, 65574.34425655683, 65633.73380730237, 10996.945015274872, 11055.226134170853, 11055.613053543088, 11153.0, 11176.10591152722, 12429.689257768629, 13867.583972480841, 14094.436222550983, 14927.029743066665, 14942.701145977237, 13497.797533037208, 13648.317584120952, 13812.930935345259, 14675.926620367258, 14801.703965920837, 12889.291088990196, 13118.934405327913, 14473.421063157355, 14504.126908633163, 14699.971002029755, 12609.873901261237, 13295.401706923018, 14179.22014289224, 14633.122012679274, 14636.195009274592, 10654.360738355723, 11059.668209953714, 11388.316700997966, 11437.485313160762, 11607.76784464289, 9606.913330657566, 9817.410955342702, 11313.7737245253, 11560.132990025613, 11590.18868679218], "value": 7227.745889646924, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1478.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 114.0, 111.0, 114.0, 116.0, 114.0, 112.0, 112.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 112.0, 111.0, 150.0, 194.0, 140.0, 109.0, 110.0, 132.0, 112.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 183.0, 185.0, 181.0, 185.0, 181.0, 184.0, 183.0, 186.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 61.0, 108.0, 88.0, 85.0, 48.0, 70.0, 90.0, 84.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 87.0, 70.0, 70.0, 70.0, 86.0, 68.0, 70.0, 83.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 85.0, 88.0, 83.0, 87.0, 88.0, 136.0, 83.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 85.0, 88.0, 87.0, 88.0, 87.0, 87.0, 87.0, 89.0], "value": 87.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 57.0, 58.0, 58.0, 59.0, 58.0, 58.0, 59.0, 59.0, 60.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 79.0, 70.0, 72.0, 74.0, 68.0, 72.0, 71.0, 71.0, 75.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 86.0, 86.0, 86.0, 86.0, 87.0, 87.0, 91.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 152.0, 156.0, 138.0, 135.0, 136.0, 139.0, 139.0, 184.0, 158.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 175.0, 236.0, 209.0, 228.0, 193.0, 170.0, 171.0, 170.0, 170.0], "value": 189.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 83.0, 80.0, 69.0, 66.0, 67.0, 66.0, 66.0, 64.0, 65.0], "value": 69.0, "unit": "score"}]}]} 09:56:46 INFO - Return code: 0 09:56:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:56:46 ERROR - # TBPL SUCCESS # 09:56:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:56:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:56:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:56:46 INFO - 2017-02-06 09:56:46.698 screenresolution[1935:14690] starting screenresolution argv=screenresolution get 09:56:46 INFO - 2017-02-06 09:56:46.701 screenresolution[1935:14690] Display 0: 1600x1200x32@60 09:56:46 INFO - 2017-02-06 09:56:46.707 screenresolution[1936:14693] starting screenresolution argv=screenresolution list 09:56:46 INFO - Available Modes on Display 0 09:56:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:56:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:56:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:56:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:56:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:56:46 INFO - Intel Iris: 09:56:46 INFO - Chipset Model: Intel Iris 09:56:46 INFO - Type: GPU 09:56:46 INFO - Bus: Built-In 09:56:46 INFO - VRAM (Dynamic, Max): 1536 MB 09:56:46 INFO - Vendor: Intel (0x8086) 09:56:46 INFO - Device ID: 0x0a2e 09:56:46 INFO - Revision ID: 0x0009 09:56:46 INFO - Displays: 09:56:46 INFO - Display: 09:56:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:56:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:56:46 INFO - Display Serial Number: 1600x1200 60 09:56:46 INFO - Main Display: Yes 09:56:46 INFO - Mirror: Off 09:56:46 INFO - Online: Yes 09:56:46 INFO - Rotation: Supported 09:56:46 INFO - Return code: 0 09:56:46 INFO - Running post-action listener: _resource_record_post_action 09:56:46 INFO - [mozharness: 2017-02-06 17:56:46.941862Z] Finished run-tests step (success) 09:56:46 INFO - Running post-run listener: _resource_record_post_run 09:56:46 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 802816; Write bytes: 552744960; Read time: 11; Write time: 1773 09:56:46 INFO - TinderboxPrint: CPU usage
53.6% 09:56:46 INFO - TinderboxPrint: I/O read bytes / time
802,816 / 11 09:56:46 INFO - TinderboxPrint: I/O write bytes / time
552,744,960 / 1,773 09:56:47 INFO - TinderboxPrint: CPU idle
2,229.3 (46.4%) 09:56:47 INFO - TinderboxPrint: CPU system
954.8 (19.9%) 09:56:47 INFO - TinderboxPrint: CPU user
1,625.5 (33.8%) 09:56:47 INFO - TinderboxPrint: Swap in / out
252,792,832 / 0 09:56:47 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 241967616; Write bytes: 237520896; Read time: 17653; Write time: 470 09:56:47 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 802816; Write bytes: 295870464; Read time: 11; Write time: 1256 09:56:47 INFO - Running post-run listener: _upload_blobber_files 09:56:47 INFO - Blob upload gear active. 09:56:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:56:47 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 09:56:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:56:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:56:47 INFO - (blobuploader) - INFO - Open directory for files ... 09:56:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:56:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:56:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:48 INFO - (blobuploader) - INFO - Done attempting. 09:56:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:56:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:56:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:49 INFO - (blobuploader) - INFO - Done attempting. 09:56:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:56:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:56:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:50 INFO - (blobuploader) - INFO - Done attempting. 09:56:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:56:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:56:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:52 INFO - (blobuploader) - INFO - Done attempting. 09:56:52 INFO - (blobuploader) - INFO - Iteration through files over. 09:56:52 INFO - Return code: 0 09:56:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:56:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:56:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2057ddb03771ae1085719367323bf5d7a9820242d1e56fe05f35d0f347553aa66a960c42296ee2ec8ea689fd1212711627c714b4ba08a565d145653d880802b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1e1fc7355b3b30db087847020103b7ba0bfcc8c9667072cc61e7857eef27a84a1b8c9dd038bd77de72028010cb570c4a6d631378ecc243c1ec4cf73310b205c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1e5048753cf927bbca903c8b6f2d040fbaf0ecb7d01e7f9d035d77583e2b81e462e2c815ea97183ba1aa9b1424ad39f8dbc1251584bdaede9ab4d2f47a106f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a84273c18de40fc471349a7cf21d2e929c9e951c36ed8609fd69c674d544157929b8e66dd046b8cfaeb91cd8232319b96919baafb13725614a2466b15a87fe27"} 09:56:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:56:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:56:52 INFO - Contents: 09:56:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2057ddb03771ae1085719367323bf5d7a9820242d1e56fe05f35d0f347553aa66a960c42296ee2ec8ea689fd1212711627c714b4ba08a565d145653d880802b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1e1fc7355b3b30db087847020103b7ba0bfcc8c9667072cc61e7857eef27a84a1b8c9dd038bd77de72028010cb570c4a6d631378ecc243c1ec4cf73310b205c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1e5048753cf927bbca903c8b6f2d040fbaf0ecb7d01e7f9d035d77583e2b81e462e2c815ea97183ba1aa9b1424ad39f8dbc1251584bdaede9ab4d2f47a106f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a84273c18de40fc471349a7cf21d2e929c9e951c36ed8609fd69c674d544157929b8e66dd046b8cfaeb91cd8232319b96919baafb13725614a2466b15a87fe27"} 09:56:52 INFO - Running post-run listener: copy_logs_to_upload_dir 09:56:52 INFO - Copying logs to upload dir... 09:56:52 INFO - mkdir: /builds/slave/test/build/upload/logs 09:56:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.537131 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-06 09:56:52.760414) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 09:56:52.764235) ========= 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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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/Firefox/sha512/2057ddb03771ae1085719367323bf5d7a9820242d1e56fe05f35d0f347553aa66a960c42296ee2ec8ea689fd1212711627c714b4ba08a565d145653d880802b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1e1fc7355b3b30db087847020103b7ba0bfcc8c9667072cc61e7857eef27a84a1b8c9dd038bd77de72028010cb570c4a6d631378ecc243c1ec4cf73310b205c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1e5048753cf927bbca903c8b6f2d040fbaf0ecb7d01e7f9d035d77583e2b81e462e2c815ea97183ba1aa9b1424ad39f8dbc1251584bdaede9ab4d2f47a106f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a84273c18de40fc471349a7cf21d2e929c9e951c36ed8609fd69c674d544157929b8e66dd046b8cfaeb91cd8232319b96919baafb13725614a2466b15a87fe27"} build_url:https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011269 build_url: 'https://queue.taskcluster.net/v1/task/KuCoahtmRT2PlZp8C5Kwew/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2057ddb03771ae1085719367323bf5d7a9820242d1e56fe05f35d0f347553aa66a960c42296ee2ec8ea689fd1212711627c714b4ba08a565d145653d880802b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1e1fc7355b3b30db087847020103b7ba0bfcc8c9667072cc61e7857eef27a84a1b8c9dd038bd77de72028010cb570c4a6d631378ecc243c1ec4cf73310b205c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1e5048753cf927bbca903c8b6f2d040fbaf0ecb7d01e7f9d035d77583e2b81e462e2c815ea97183ba1aa9b1424ad39f8dbc1251584bdaede9ab4d2f47a106f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a84273c18de40fc471349a7cf21d2e929c9e951c36ed8609fd69c674d544157929b8e66dd046b8cfaeb91cd8232319b96919baafb13725614a2466b15a87fe27"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 09:56:52.798817) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:56:52.799142) ========= 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.vzgFqwfKSc/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.qxqZZUcdkE/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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.005286 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:56:52.847743) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 09:56:52.848067) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 09:56:52.848403) ========= ========= Total master_lag: 0.18 =========