builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0005 starttime: 1483499677.26 results: success (0) buildid: 20170103183718 builduid: 28a26f3c1ae94c9992937be89966751e revision: 9c825a25d288e346b46b1d306cc4d4ba4004ae40 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:37.259880) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:37.260363) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:37.260663) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:37.285205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:37.285507) ========= 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.r3BwBUISxp/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.fY34jnhyjo/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-01-03 19:14:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.22M=0.001s 2017-01-03 19:14:37 (8.22 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.486721 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:37.796829) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:37.797395) ========= 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.r3BwBUISxp/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.fY34jnhyjo/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.019971 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:37.847200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 19:14:37.847576) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9c825a25d288e346b46b1d306cc4d4ba4004ae40 --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 9c825a25d288e346b46b1d306cc4d4ba4004ae40 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.r3BwBUISxp/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.fY34jnhyjo/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-01-03 19:14:37,923 truncating revision to first 12 chars 2017-01-03 19:14:37,923 Setting DEBUG logging. 2017-01-03 19:14:37,923 attempt 1/10 2017-01-03 19:14:37,923 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9c825a25d288?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-03 19:14:39,326 unpacking tar archive at: mozilla-central-9c825a25d288/testing/mozharness/ program finished with exit code 0 elapsedTime=1.661427 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 19:14:39.533418) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:39.533723) ========= script_repo_revision: 9c825a25d288e346b46b1d306cc4d4ba4004ae40 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:39.534143) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:39.534424) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 19:14:39.554151) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-03 19:14:39.554459) ========= /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.r3BwBUISxp/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.fY34jnhyjo/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:14:39 INFO - MultiFileLogger online at 20170103 19:14:39 in /builds/slave/test 19:14:39 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 19:14:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:14:39 INFO - {'append_to_log': False, 19:14:39 INFO - 'base_work_dir': '/builds/slave/test', 19:14:39 INFO - 'blob_upload_branch': 'Firefox', 19:14:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:14:39 INFO - 'branch': 'Firefox', 19:14:39 INFO - 'buildbot_json_path': 'buildprops.json', 19:14:39 INFO - 'config_files': ('talos/mac_config.py',), 19:14:39 INFO - 'default_actions': ('clobber', 19:14:39 INFO - 'read-buildbot-config', 19:14:39 INFO - 'download-and-extract', 19:14:39 INFO - 'populate-webroot', 19:14:39 INFO - 'create-virtualenv', 19:14:39 INFO - 'install', 19:14:39 INFO - 'run-tests'), 19:14:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:14:39 INFO - 'download_minidump_stackwalk': True, 19:14:39 INFO - 'download_symbols': 'ondemand', 19:14:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:14:39 INFO - 'tooltool.py': '/tools/tooltool.py', 19:14:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:14:39 INFO - '/tools/misc-python/virtualenv.py')}, 19:14:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:14:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:14:39 INFO - 'installer_path': 'installer.exe', 19:14:39 INFO - 'log_level': 'info', 19:14:39 INFO - 'log_name': 'talos', 19:14:39 INFO - 'log_to_console': True, 19:14:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:14:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:14:39 INFO - 'opt_config_files': (), 19:14:39 INFO - 'pip_index': False, 19:14:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:14:39 INFO - 'cmd': ('bash', 19:14:39 INFO - '-c', 19:14:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:14:39 INFO - 'enabled': True, 19:14:39 INFO - 'halt_on_failure': False, 19:14:39 INFO - 'name': 'check_screen_resolution'},), 19:14:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:14:39 INFO - 'cmd': ('bash', 19:14:39 INFO - '-c', 19:14:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:14:39 INFO - 'enabled': True, 19:14:39 INFO - 'halt_on_failure': False, 19:14:39 INFO - 'name': 'check_screen_resolution'},), 19:14:39 INFO - 'run_cmd_checks_enabled': True, 19:14:39 INFO - 'sps_profile': False, 19:14:39 INFO - 'sps_profile_interval': 0, 19:14:39 INFO - 'suite': 'dromaeojs', 19:14:39 INFO - 'system_bits': '32', 19:14:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:14:39 INFO - 'title': 't-yosemite-r7-0005', 19:14:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:14:39 INFO - 'use_talos_json': True, 19:14:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:14:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:14:39 INFO - 'work_dir': 'build'} 19:14:39 INFO - [mozharness: 2017-01-04 03:14:39.750915Z] Running clobber step. 19:14:39 INFO - Running pre-action listener: _resource_record_pre_action 19:14:39 INFO - Running main action method: clobber 19:14:39 INFO - rmtree: /builds/slave/test/build 19:14:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:14:41 INFO - Running post-action listener: _resource_record_post_action 19:14:41 INFO - [mozharness: 2017-01-04 03:14:41.814767Z] Finished clobber step (success) 19:14:41 INFO - [mozharness: 2017-01-04 03:14:41.815048Z] Running read-buildbot-config step. 19:14:41 INFO - Running pre-action listener: _resource_record_pre_action 19:14:41 INFO - Running main action method: read_buildbot_config 19:14:41 INFO - Using buildbot properties: 19:14:41 INFO - { 19:14:41 INFO - "project": "", 19:14:41 INFO - "product": "firefox", 19:14:41 INFO - "script_repo_revision": "production", 19:14:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:14:41 INFO - "repository": "", 19:14:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:14:41 INFO - "buildid": "20170103183718", 19:14:41 INFO - "pgo_build": "False", 19:14:41 INFO - "basedir": "/builds/slave/test", 19:14:41 INFO - "buildnumber": 100, 19:14:41 INFO - "slavename": "t-yosemite-r7-0005", 19:14:41 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:14:41 INFO - "platform": "yosemite_r7", 19:14:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:14:41 INFO - "branch": "mozilla-central", 19:14:41 INFO - "repo_path": "mozilla-central", 19:14:41 INFO - "revision": "9c825a25d288e346b46b1d306cc4d4ba4004ae40", 19:14:41 INFO - "stage_platform": "macosx64", 19:14:41 INFO - "builduid": "28a26f3c1ae94c9992937be89966751e", 19:14:41 INFO - "slavebuilddir": "test" 19:14:41 INFO - } 19:14:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 19:14:41 INFO - Running post-action listener: _resource_record_post_action 19:14:41 INFO - [mozharness: 2017-01-04 03:14:41.818580Z] Finished read-buildbot-config step (success) 19:14:41 INFO - [mozharness: 2017-01-04 03:14:41.818670Z] Running download-and-extract step. 19:14:41 INFO - Running pre-action listener: _resource_record_pre_action 19:14:41 INFO - Running main action method: download_and_extract 19:14:41 INFO - mkdir: /builds/slave/test/build/tests 19:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:41 INFO - trying https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 19:14:41 INFO - Downloading https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:14:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 19:14:42 INFO - Downloaded 1199 bytes. 19:14:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:14:42 INFO - Using the following test package requirements: 19:14:42 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:14:42 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:14:42 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 19:14:42 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:14:42 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 19:14:42 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:14:42 INFO - u'jsshell-mac.zip'], 19:14:42 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:14:42 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 19:14:42 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:14:42 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:14:42 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 19:14:42 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:14:42 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 19:14:42 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:14:42 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 19:14:42 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:14:42 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 19:14:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:14:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:14:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:14:42 INFO - Fetch https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:14:44 INFO - Expected file size: 16300640 19:14:44 INFO - Obtained file size: 16300640 19:14:51 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:14:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:14:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:14:51 INFO - Fetch https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:14:52 INFO - Expected file size: 16300640 19:14:52 INFO - Obtained file size: 16300640 19:14:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 19:14:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:14:58 INFO - Fetch https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 19:15:00 INFO - Expected file size: 14409776 19:15:00 INFO - Obtained file size: 14409776 19:15:00 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')]} 19:15:00 INFO - trying https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:15:00 INFO - Downloading https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 19:15:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 19:15:04 INFO - Downloaded 69755156 bytes. 19:15:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:15:04 INFO - mkdir: /builds/slave/test/properties 19:15:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:15:04 INFO - Writing to file /builds/slave/test/properties/build_url 19:15:04 INFO - Contents: 19:15:04 INFO - build_url:https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:15:06 INFO - Running post-action listener: _resource_record_post_action 19:15:06 INFO - Running post-action listener: set_extra_try_arguments 19:15:06 INFO - [mozharness: 2017-01-04 03:15:06.085354Z] Finished download-and-extract step (success) 19:15:06 INFO - [mozharness: 2017-01-04 03:15:06.085486Z] Running populate-webroot step. 19:15:06 INFO - Running pre-action listener: _resource_record_pre_action 19:15:06 INFO - Running main action method: populate_webroot 19:15:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:15:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:15:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:15:06 INFO - '--tppagecycles', 19:15:06 INFO - '7'], 19:15:06 INFO - 'tests': ['tsvgm']}}, 19:15:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:15:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:15:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:15:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:15:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:15:06 INFO - 'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:15:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:15:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:15:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:15:06 INFO - 'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['damp', 'tps']}, 19:15:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:15:06 INFO - 'tests': ['damp', 'tps']}, 19:15:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['dromaeo_dom']}, 19:15:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:15:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['basic_compositor_video']}, 19:15:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:15:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['a11yr', 19:15:06 INFO - 'ts_paint', 19:15:06 INFO - 'tpaint', 19:15:06 INFO - 'sessionrestore', 19:15:06 INFO - 'sessionrestore_no_auto_restore', 19:15:06 INFO - 'tabpaint']}, 19:15:06 INFO - 'other-e10s': {'tests': ['a11yr', 19:15:06 INFO - 'ts_paint', 19:15:06 INFO - 'tpaint', 19:15:06 INFO - 'sessionrestore', 19:15:06 INFO - 'sessionrestore_no_auto_restore', 19:15:06 INFO - 'tabpaint']}, 19:15:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:15:06 INFO - 'ts_paint', 19:15:06 INFO - 'tpaint', 19:15:06 INFO - 'sessionrestore', 19:15:06 INFO - 'sessionrestore_no_auto_restore', 19:15:06 INFO - 'tabpaint']}, 19:15:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:15:06 INFO - 'ts_paint', 19:15:06 INFO - 'tpaint', 19:15:06 INFO - 'sessionrestore', 19:15:06 INFO - 'sessionrestore_no_auto_restore', 19:15:06 INFO - 'tabpaint']}, 19:15:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['a11yr', 19:15:06 INFO - 'ts_paint', 19:15:06 INFO - 'tpaint', 19:15:06 INFO - 'sessionrestore', 19:15:06 INFO - 'sessionrestore_no_auto_restore', 19:15:06 INFO - 'tabpaint']}, 19:15:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['a11yr', 19:15:06 INFO - 'ts_paint', 19:15:06 INFO - 'tpaint', 19:15:06 INFO - 'sessionrestore', 19:15:06 INFO - 'sessionrestore_no_auto_restore', 19:15:06 INFO - 'tabpaint']}, 19:15:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['tsvgx', 19:15:06 INFO - 'tsvgr_opacity', 19:15:06 INFO - 'tart', 19:15:06 INFO - 'tscrollx', 19:15:06 INFO - 'cart', 19:15:06 INFO - 'tsvg_static']}, 19:15:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:15:06 INFO - 'tsvgr_opacity', 19:15:06 INFO - 'tart', 19:15:06 INFO - 'tscrollx', 19:15:06 INFO - 'cart', 19:15:06 INFO - 'tsvg_static']}, 19:15:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:15:06 INFO - 'talos_options': ['--disable-e10s'], 19:15:06 INFO - 'tests': ['tp5o']}, 19:15:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:15:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:15:06 INFO - 'talos_options': ['--disable-e10s', 19:15:06 INFO - '--xperf_path', 19:15:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:15:06 INFO - 'tests': ['tp5n']}, 19:15:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:15:06 INFO - 'talos_options': ['--xperf_path', 19:15:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:15:06 INFO - 'tests': ['tp5n']}}, 19:15:06 INFO - 'talos.zip': {'path': '', 19:15:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:15:06 INFO - Running post-action listener: _resource_record_post_action 19:15:06 INFO - [mozharness: 2017-01-04 03:15:06.104144Z] Finished populate-webroot step (success) 19:15:06 INFO - [mozharness: 2017-01-04 03:15:06.104254Z] Running create-virtualenv step. 19:15:06 INFO - Running pre-action listener: _resource_record_pre_action 19:15:06 INFO - Running main action method: create_virtualenv 19:15:06 INFO - Creating virtualenv /builds/slave/test/build/venv 19:15:06 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 19:15:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:15:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:15:06 INFO - Using real prefix '/tools/python27' 19:15:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:15:06 INFO - Installing distribute.............................................................................................................................................................................................done. 19:15:09 INFO - Installing pip.................done. 19:15:09 INFO - Return code: 0 19:15:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:15:10 INFO - Reading from file tmpfile_stdout 19:15:10 INFO - Output received: 19:15:10 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) 19:15:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:15:10 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')]} 19:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:10 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')]} 19:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:10 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 0x108fb7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff405e46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, '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.r3BwBUISxp/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.fY34jnhyjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:15:10 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 19:15:10 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 19:15:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:15:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:10 INFO - 'HOME': '/Users/cltbld', 19:15:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:10 INFO - 'LOGNAME': 'cltbld', 19:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:10 INFO - 'MOZ_NO_REMOTE': '1', 19:15:10 INFO - 'NO_EM_RESTART': '1', 19:15:10 INFO - 'PAGER': '/bin/cat', 19:15:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:10 INFO - 'PWD': '/builds/slave/test', 19:15:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:10 INFO - 'SHELL': '/bin/bash', 19:15:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:15:10 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:15:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:10 INFO - 'USER': 'cltbld', 19:15:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:10 INFO - 'XPC_FLAGS': '0x0', 19:15:10 INFO - 'XPC_SERVICE_NAME': '0', 19:15:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:10 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 19:15:10 INFO - Cleaning up... 19:15:10 INFO - Return code: 0 19:15:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:15:10 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')]} 19:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:10 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')]} 19:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:10 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 0x108fb7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff405e46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, '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.r3BwBUISxp/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.fY34jnhyjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:15:10 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 19:15:10 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 19:15:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:15:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:10 INFO - 'HOME': '/Users/cltbld', 19:15:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:10 INFO - 'LOGNAME': 'cltbld', 19:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:10 INFO - 'MOZ_NO_REMOTE': '1', 19:15:10 INFO - 'NO_EM_RESTART': '1', 19:15:10 INFO - 'PAGER': '/bin/cat', 19:15:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:10 INFO - 'PWD': '/builds/slave/test', 19:15:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:10 INFO - 'SHELL': '/bin/bash', 19:15:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:15:10 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:15:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:10 INFO - 'USER': 'cltbld', 19:15:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:10 INFO - 'XPC_FLAGS': '0x0', 19:15:10 INFO - 'XPC_SERVICE_NAME': '0', 19:15:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:10 INFO - Downloading/unpacking psutil>=3.1.1 19:15:10 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 19:15:10 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 19:15:10 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 19:15:10 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 19:15:10 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 19:15:10 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 19:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:15:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:15:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:15:14 INFO - Installing collected packages: psutil 19:15:14 INFO - Running setup.py install for psutil 19:15:14 INFO - building 'psutil._psutil_osx' extension 19:15:14 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 19:15:14 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 19:15:14 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 19:15:15 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 19:15:15 INFO - building 'psutil._psutil_posix' extension 19:15:15 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 19:15:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:15:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:15:15 INFO - ^ 19:15:15 INFO - 1 warning generated. 19:15:15 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 19:15:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:15:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:15:15 INFO - Successfully installed psutil 19:15:15 INFO - Cleaning up... 19:15:15 INFO - Return code: 0 19:15:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:15:15 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')]} 19:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:15 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')]} 19:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:15 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 0x108fb7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff405e46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, '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.r3BwBUISxp/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.fY34jnhyjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:15:15 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 19:15:15 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 19:15:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:15:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:15 INFO - 'HOME': '/Users/cltbld', 19:15:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:15 INFO - 'LOGNAME': 'cltbld', 19:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:15 INFO - 'MOZ_NO_REMOTE': '1', 19:15:15 INFO - 'NO_EM_RESTART': '1', 19:15:15 INFO - 'PAGER': '/bin/cat', 19:15:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:15 INFO - 'PWD': '/builds/slave/test', 19:15:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:15 INFO - 'SHELL': '/bin/bash', 19:15:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:15:15 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:15:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:15 INFO - 'USER': 'cltbld', 19:15:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:15 INFO - 'XPC_FLAGS': '0x0', 19:15:15 INFO - 'XPC_SERVICE_NAME': '0', 19:15:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:15:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:15:18 INFO - Installing collected packages: mozsystemmonitor 19:15:18 INFO - Running setup.py install for mozsystemmonitor 19:15:18 INFO - Successfully installed mozsystemmonitor 19:15:18 INFO - Cleaning up... 19:15:18 INFO - Return code: 0 19:15:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:15:18 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')]} 19:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:18 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')]} 19:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:18 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 0x108fb7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff405e46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, '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.r3BwBUISxp/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.fY34jnhyjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:15:18 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 19:15:18 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 19:15:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:15:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:18 INFO - 'HOME': '/Users/cltbld', 19:15:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:18 INFO - 'LOGNAME': 'cltbld', 19:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:18 INFO - 'MOZ_NO_REMOTE': '1', 19:15:18 INFO - 'NO_EM_RESTART': '1', 19:15:18 INFO - 'PAGER': '/bin/cat', 19:15:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:18 INFO - 'PWD': '/builds/slave/test', 19:15:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:18 INFO - 'SHELL': '/bin/bash', 19:15:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:15:18 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:15:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:18 INFO - 'USER': 'cltbld', 19:15:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:18 INFO - 'XPC_FLAGS': '0x0', 19:15:18 INFO - 'XPC_SERVICE_NAME': '0', 19:15:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:18 INFO - Downloading/unpacking jsonschema==2.5.1 19:15:18 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 19:15:18 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 19:15:18 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 19:15:18 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 19:15:18 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 19:15:18 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 19:15:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:15:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:15:21 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 19:15:21 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 19:15:21 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 19:15:21 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 19:15:21 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 19:15:21 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 19:15:21 INFO - Downloading functools32-3.2.3-2.tar.gz 19:15:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:15:22 INFO - warning: no files found matching '*.txt' 19:15:22 INFO - no previously-included directories found matching 'build' 19:15:22 INFO - no previously-included directories found matching 'dist' 19:15:22 INFO - no previously-included directories found matching '.git*' 19:15:22 INFO - Installing collected packages: jsonschema, functools32 19:15:22 INFO - Running setup.py install for functools32 19:15:22 INFO - warning: no files found matching '*.txt' 19:15:22 INFO - no previously-included directories found matching 'build' 19:15:22 INFO - no previously-included directories found matching 'dist' 19:15:22 INFO - no previously-included directories found matching '.git*' 19:15:22 INFO - Successfully installed jsonschema functools32 19:15:22 INFO - Cleaning up... 19:15:22 INFO - Return code: 0 19:15:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:15:22 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')]} 19:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:22 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')]} 19:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:22 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 0x108fb7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff405e46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, '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.r3BwBUISxp/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.fY34jnhyjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:15:22 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 19:15:22 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 19:15:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:15:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:22 INFO - 'HOME': '/Users/cltbld', 19:15:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:22 INFO - 'LOGNAME': 'cltbld', 19:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:22 INFO - 'MOZ_NO_REMOTE': '1', 19:15:22 INFO - 'NO_EM_RESTART': '1', 19:15:22 INFO - 'PAGER': '/bin/cat', 19:15:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:22 INFO - 'PWD': '/builds/slave/test', 19:15:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:22 INFO - 'SHELL': '/bin/bash', 19:15:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:15:22 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:15:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:22 INFO - 'USER': 'cltbld', 19:15:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:22 INFO - 'XPC_FLAGS': '0x0', 19:15:22 INFO - 'XPC_SERVICE_NAME': '0', 19:15:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:15:22 INFO - Cleaning up... 19:15:22 INFO - Return code: 0 19:15:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:15:22 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')]} 19:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:22 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')]} 19:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:22 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 0x108fb7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff405e46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, '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.r3BwBUISxp/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.fY34jnhyjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:15:22 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 19:15:22 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 19:15:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:15:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:22 INFO - 'HOME': '/Users/cltbld', 19:15:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:22 INFO - 'LOGNAME': 'cltbld', 19:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:22 INFO - 'MOZ_NO_REMOTE': '1', 19:15:22 INFO - 'NO_EM_RESTART': '1', 19:15:22 INFO - 'PAGER': '/bin/cat', 19:15:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:22 INFO - 'PWD': '/builds/slave/test', 19:15:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:22 INFO - 'SHELL': '/bin/bash', 19:15:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:15:22 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:15:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:22 INFO - 'USER': 'cltbld', 19:15:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:22 INFO - 'XPC_FLAGS': '0x0', 19:15:22 INFO - 'XPC_SERVICE_NAME': '0', 19:15:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:22 INFO - Downloading/unpacking blobuploader==1.2.4 19:15:22 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 19:15:22 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 19:15:22 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 19:15:22 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 19:15:22 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 19:15:22 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 19:15:25 INFO - Downloading blobuploader-1.2.4.tar.gz 19:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:15:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:15: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 19:15: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 19:15: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 19:15: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 19:15: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 19:15: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 19:15:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:15:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:15:26 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 19:15:26 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 19:15:26 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 19:15:26 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 19:15:26 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 19:15:26 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 19:15:26 INFO - Downloading docopt-0.6.1.tar.gz 19:15:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:15:26 INFO - Installing collected packages: blobuploader, requests, docopt 19:15:26 INFO - Running setup.py install for blobuploader 19:15:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:15:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:15:26 INFO - Running setup.py install for requests 19:15:27 INFO - Running setup.py install for docopt 19:15:27 INFO - Successfully installed blobuploader requests docopt 19:15:27 INFO - Cleaning up... 19:15:27 INFO - Return code: 0 19:15:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:27 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 0x108fb7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff405e46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, '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.r3BwBUISxp/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.fY34jnhyjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:15:27 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 19:15:27 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 19:15:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:15:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:27 INFO - 'HOME': '/Users/cltbld', 19:15:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:27 INFO - 'LOGNAME': 'cltbld', 19:15:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:27 INFO - 'MOZ_NO_REMOTE': '1', 19:15:27 INFO - 'NO_EM_RESTART': '1', 19:15:27 INFO - 'PAGER': '/bin/cat', 19:15:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:27 INFO - 'PWD': '/builds/slave/test', 19:15:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:27 INFO - 'SHELL': '/bin/bash', 19:15:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:15:27 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:15:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:27 INFO - 'USER': 'cltbld', 19:15:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:27 INFO - 'XPC_FLAGS': '0x0', 19:15:27 INFO - 'XPC_SERVICE_NAME': '0', 19:15:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:15:27 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-WeFLDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:15:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-opbCPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:15:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Oc3X_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:15:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-wOOUj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:15:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-L6M8yU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:15:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-dmldDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:15:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-m1sCES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:15:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-a6Q_lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:15:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-acrOoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:15:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Xi3Q4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-iFjSEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:29 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-w9Yahr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:29 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-t7We1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:29 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-4PILfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:29 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-dIyNN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:29 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-iImI4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:29 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-0PnUsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:15:29 INFO - Running setup.py install for manifestparser 19:15:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:15:29 INFO - Running setup.py install for mozcrash 19:15:29 INFO - Running setup.py install for mozdebug 19:15:30 INFO - Running setup.py install for mozdevice 19:15:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:15:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:15:30 INFO - Running setup.py install for mozfile 19:15:30 INFO - Running setup.py install for mozhttpd 19:15:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:15:30 INFO - Running setup.py install for mozinfo 19:15:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:15:30 INFO - Running setup.py install for mozInstall 19:15:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:15:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:15:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:15:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:15:30 INFO - Running setup.py install for mozleak 19:15:30 INFO - Running setup.py install for mozlog 19:15:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Running setup.py install for moznetwork 19:15:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Running setup.py install for mozprocess 19:15:31 INFO - Running setup.py install for mozprofile 19:15:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Running setup.py install for mozrunner 19:15:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Running setup.py install for mozscreenshot 19:15:31 INFO - Running setup.py install for moztest 19:15:32 INFO - Running setup.py install for mozversion 19:15:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:15:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:15:32 INFO - Cleaning up... 19:15:32 INFO - Return code: 0 19:15:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:32 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')]} 19:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:32 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')]} 19:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:32 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 0x108fb7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff405e46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, '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.r3BwBUISxp/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.fY34jnhyjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:15:32 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 19:15:32 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 19:15:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:15:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:32 INFO - 'HOME': '/Users/cltbld', 19:15:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:32 INFO - 'LOGNAME': 'cltbld', 19:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:32 INFO - 'MOZ_NO_REMOTE': '1', 19:15:32 INFO - 'NO_EM_RESTART': '1', 19:15:32 INFO - 'PAGER': '/bin/cat', 19:15:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:32 INFO - 'PWD': '/builds/slave/test', 19:15:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:32 INFO - 'SHELL': '/bin/bash', 19:15:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:15:32 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:15:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:32 INFO - 'USER': 'cltbld', 19:15:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:32 INFO - 'XPC_FLAGS': '0x0', 19:15:32 INFO - 'XPC_SERVICE_NAME': '0', 19:15:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:15:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-NFnyg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:15:32 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)) 19:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:15:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-LsVVnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:15:32 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)) 19:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:15:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Xn7p3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:15:32 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)) 19:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:15:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-bP1jDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:15:32 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)) 19:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:15:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-xWapfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:15:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-hS6OPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:15:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-jV_AZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:15:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-598huL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:15:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-7hy3YE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:15:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-VAYebO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-9346Br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-BIjv3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-D7gDx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-eAj5_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:34 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)) 19:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-BBWGVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:34 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)) 19:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-giDvFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:34 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)) 19:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-WHCjLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:34 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)) 19:15:34 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)) 19:15:34 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)) 19:15:34 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)) 19:15:34 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)) 19:15:34 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)) 19:15:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:15:34 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 19:15:34 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 19:15:34 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 19:15:34 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 19:15:34 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 19:15:34 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 19:15:36 INFO - Downloading blessings-1.6.tar.gz 19:15:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:15:37 INFO - Installing collected packages: blessings 19:15:37 INFO - Running setup.py install for blessings 19:15:37 INFO - Successfully installed blessings 19:15:37 INFO - Cleaning up... 19:15:37 INFO - Return code: 0 19:15:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:15:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:37 INFO - Reading from file tmpfile_stdout 19:15:37 INFO - Current package versions: 19:15:37 INFO - blessings == 1.6 19:15:37 INFO - blobuploader == 1.2.4 19:15:37 INFO - docopt == 0.6.1 19:15:37 INFO - functools32 == 3.2.3-2 19:15:37 INFO - jsonschema == 2.5.1 19:15:37 INFO - manifestparser == 1.1 19:15:37 INFO - mozInstall == 1.12 19:15:37 INFO - mozcrash == 1.0 19:15:37 INFO - mozdebug == 0.1 19:15:37 INFO - mozdevice == 0.49 19:15:37 INFO - mozfile == 1.2 19:15:37 INFO - mozhttpd == 0.7 19:15:37 INFO - mozinfo == 0.9 19:15:37 INFO - mozleak == 0.1 19:15:37 INFO - mozlog == 3.4 19:15:37 INFO - moznetwork == 0.27 19:15:37 INFO - mozprocess == 0.23 19:15:37 INFO - mozprofile == 0.28 19:15:37 INFO - mozrunner == 6.13 19:15:37 INFO - mozscreenshot == 0.1 19:15:37 INFO - mozsystemmonitor == 0.3 19:15:37 INFO - moztest == 0.8 19:15:37 INFO - mozversion == 1.4 19:15:37 INFO - psutil == 3.1.1 19:15:37 INFO - requests == 1.2.3 19:15:37 INFO - wsgiref == 0.1.2 19:15:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:37 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 0x108fb7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff405e46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, '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.r3BwBUISxp/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.fY34jnhyjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:15:37 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 19:15:37 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 19:15:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:15:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:37 INFO - 'HOME': '/Users/cltbld', 19:15:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:37 INFO - 'LOGNAME': 'cltbld', 19:15:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:37 INFO - 'MOZ_NO_REMOTE': '1', 19:15:37 INFO - 'NO_EM_RESTART': '1', 19:15:37 INFO - 'PAGER': '/bin/cat', 19:15:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:37 INFO - 'PWD': '/builds/slave/test', 19:15:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:37 INFO - 'SHELL': '/bin/bash', 19:15:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:15:37 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:15:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:37 INFO - 'USER': 'cltbld', 19:15:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:37 INFO - 'XPC_FLAGS': '0x0', 19:15:37 INFO - 'XPC_SERVICE_NAME': '0', 19:15:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 INFO - Cleaning up... 19:15:37 INFO - Return code: 0 19:15:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff405e46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, '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.r3BwBUISxp/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.fY34jnhyjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:15:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:15:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:15:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:37 INFO - 'HOME': '/Users/cltbld', 19:15:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:37 INFO - 'LOGNAME': 'cltbld', 19:15:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:37 INFO - 'MOZ_NO_REMOTE': '1', 19:15:37 INFO - 'NO_EM_RESTART': '1', 19:15:37 INFO - 'PAGER': '/bin/cat', 19:15:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:37 INFO - 'PWD': '/builds/slave/test', 19:15:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:37 INFO - 'SHELL': '/bin/bash', 19:15:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:15:37 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:15:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:37 INFO - 'USER': 'cltbld', 19:15:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:37 INFO - 'XPC_FLAGS': '0x0', 19:15:37 INFO - 'XPC_SERVICE_NAME': '0', 19:15:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:15:38 INFO - Cleaning up... 19:15:38 INFO - Return code: 0 19:15:38 INFO - Running post-action listener: _resource_record_post_action 19:15:38 INFO - Running post-action listener: _start_resource_monitoring 19:15:38 INFO - Starting resource monitoring. 19:15:38 INFO - [mozharness: 2017-01-04 03:15:38.175375Z] Finished create-virtualenv step (success) 19:15:38 INFO - [mozharness: 2017-01-04 03:15:38.176361Z] Running install step. 19:15:38 INFO - Running pre-action listener: _resource_record_pre_action 19:15:38 INFO - Running main action method: install 19:15:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:38 INFO - Reading from file tmpfile_stdout 19:15:38 INFO - Detecting whether we're running mozinstall >=1.0... 19:15:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:15:38 INFO - Reading from file tmpfile_stdout 19:15:38 INFO - Output received: 19:15:38 INFO - Usage: mozinstall [options] installer 19:15:38 INFO - Options: 19:15:38 INFO - -h, --help show this help message and exit 19:15:38 INFO - -d DEST, --destination=DEST 19:15:38 INFO - Directory to install application into. [default: 19:15:38 INFO - "/builds/slave/test"] 19:15:38 INFO - --app=APP Application being installed. [default: firefox] 19:15:38 INFO - mkdir: /builds/slave/test/build/application 19:15:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:16:01 INFO - Reading from file tmpfile_stdout 19:16:01 INFO - Output received: 19:16:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:16:01 INFO - Running post-action listener: _resource_record_post_action 19:16:01 INFO - [mozharness: 2017-01-04 03:16:01.305523Z] Finished install step (success) 19:16:01 INFO - [mozharness: 2017-01-04 03:16:01.305653Z] Running run-tests step. 19:16:01 INFO - Running pre-action listener: _resource_record_pre_action 19:16:01 INFO - Running main action method: run_tests 19:16:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:16:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:16:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:16:01 INFO - 2017-01-03 19:16:01.320 screenresolution[1905:18750] starting screenresolution argv=screenresolution get 19:16:01 INFO - 2017-01-03 19:16:01.323 screenresolution[1905:18750] Display 0: 1600x1200x32@60 19:16:01 INFO - 2017-01-03 19:16:01.329 screenresolution[1906:18753] starting screenresolution argv=screenresolution list 19:16:01 INFO - Available Modes on Display 0 19:16:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:16:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:16:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:16:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:16:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:16:01 INFO - Intel Iris: 19:16:01 INFO - Chipset Model: Intel Iris 19:16:01 INFO - Type: GPU 19:16:01 INFO - Bus: Built-In 19:16:01 INFO - VRAM (Dynamic, Max): 1536 MB 19:16:01 INFO - Vendor: Intel (0x8086) 19:16:01 INFO - Device ID: 0x0a2e 19:16:01 INFO - Revision ID: 0x0009 19:16:01 INFO - Displays: 19:16:01 INFO - Display: 19:16:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:16:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:16:01 INFO - Display Serial Number: 1600x1200 60 19:16:01 INFO - Main Display: Yes 19:16:01 INFO - Mirror: Off 19:16:01 INFO - Online: Yes 19:16:01 INFO - Rotation: Supported 19:16:01 INFO - Return code: 0 19:16:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:16:01 INFO - Python 2.7.3 19:16:01 INFO - Return code: 0 19:16:01 INFO - grabbing minidump binary from tooltool 19:16:01 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')]} 19:16:01 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 0x108d95d30>, '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 0x108cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:16:01 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 19:16:01 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 19:16:01 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 19:16:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:16:01 INFO - Return code: 0 19:16:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:16:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:16:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:16:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:16:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:16:01 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/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 19:16:01 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/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0005 --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 19:16:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3BwBUISxp/Render', 19:16:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:01 INFO - 'HOME': '/Users/cltbld', 19:16:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:01 INFO - 'LOGNAME': 'cltbld', 19:16:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:16:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:01 INFO - 'MOZ_NO_REMOTE': '1', 19:16:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:16:01 INFO - 'NO_EM_RESTART': '1', 19:16:01 INFO - 'PAGER': '/bin/cat', 19:16:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:01 INFO - 'PWD': '/builds/slave/test', 19:16:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:16:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:01 INFO - 'SHELL': '/bin/bash', 19:16:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fY34jnhyjo/Listeners', 19:16:01 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:16:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:01 INFO - 'USER': 'cltbld', 19:16:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:01 INFO - 'XPC_FLAGS': '0x0', 19:16:01 INFO - 'XPC_SERVICE_NAME': '0', 19:16:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:01 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/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 19:16:01 INFO - mozversion application_buildid: 20170103183718 19:16:01 INFO - mozversion application_changeset: 9c825a25d288e346b46b1d306cc4d4ba4004ae40 19:16:01 INFO - mozversion application_display_name: Nightly 19:16:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:16:01 INFO - mozversion application_name: Firefox 19:16:01 INFO - mozversion application_remotingname: firefox 19:16:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:16:01 INFO - mozversion application_vendor: Mozilla 19:16:01 INFO - mozversion application_version: 53.0a1 19:16:01 INFO - mozversion platform_buildid: 20170103183718 19:16:01 INFO - mozversion platform_changeset: 9c825a25d288e346b46b1d306cc4d4ba4004ae40 19:16:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:16:01 INFO - mozversion platform_version: 53.0a1 19:16:01 INFO - using testdate: 1483499761 19:16:01 INFO - actual date: 1483499761 19:16:01 INFO - starting webserver on 'localhost:57005' 19:16:01 INFO - SUITE-START | Running 2 tests 19:16:01 INFO - TEST-START | dromaeo_css 19:16:01 INFO - operating with platform_type : mac_ 19:16:01 INFO - Initialising browser for dromaeo_css test... 19:16:01 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp1Cw4ZY/profile http://localhost:57005/getInfo.html) 19:16:02 INFO - PROCESS | 1916 | 1483499762391 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: 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 :: this.install :: line 38" data: no] Stack trace: this.install()@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:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4901 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3756 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2789 < callProvider()@resource://gre/modules/AddonManager.jsm:260 < _startProvider()@resource://gre/modules/AddonManager.jsm:755 < startup()@resource://gre/modules/AddonManager.jsm:937 < startup()@resource://gre/modules/AddonManager.jsm:2886 < observe()@resource://gre/components/addonManager.js:65 19:16:04 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 19:16:04 INFO - PROCESS | 1916 | colorDepth:24 19:16:04 INFO - PROCESS | 1916 | Browser inner width/height: 1024/689 19:16:04 INFO - PROCESS | 1916 | __metrics 19:16:04 INFO - PROCESS | 1916 | 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" 19:16:04 INFO - PROCESS | 1916 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:16:04 INFO - TEST-INFO | 1916: exit 0 19:16:04 INFO - Browser initialized. 19:16:04 INFO - Running cycle 1/1 for dromaeo_css test... 19:16:04 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp1Cw4ZY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:16 INFO - PROCESS | 1918 | RSS: Main: 232927232 19:16:16 INFO - PROCESS | 1918 | 19:17:07 INFO - PROCESS | 1918 | 1483499827980 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:19:20 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:57005/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57005/tests/dromaeo/cssquery-ext.html) 19:19:23 INFO - PROCESS | 1918 | RSS: Main: 378396672 19:19:23 INFO - PROCESS | 1918 | 19:21:05 INFO - PROCESS | 1918 | ************************* 19:21:05 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task. 19:21:05 INFO - PROCESS | 1918 | 19:21:05 INFO - PROCESS | 1918 | Full message: ReferenceError: fetch is not defined 19:21:05 INFO - PROCESS | 1918 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 19:21:05 INFO - PROCESS | 1918 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 19:21:05 INFO - PROCESS | 1918 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 19:21:05 INFO - PROCESS | 1918 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:21:05 INFO - PROCESS | 1918 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:21:05 INFO - PROCESS | 1918 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:21:05 INFO - PROCESS | 1918 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 19:21:05 INFO - PROCESS | 1918 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:21:05 INFO - PROCESS | 1918 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:21:05 INFO - PROCESS | 1918 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:21:05 INFO - PROCESS | 1918 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 19:21:05 INFO - PROCESS | 1918 | 19:21:05 INFO - PROCESS | 1918 | ************************* 19:22:26 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:57005/tests/dromaeo/cssquery-ext.html (next: http://localhost:57005/tests/dromaeo/cssquery-jquery.html) 19:22:27 INFO - PROCESS | 1918 | RSS: Main: 366678016 19:22:27 INFO - PROCESS | 1918 | 19:25:29 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:57005/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57005/tests/dromaeo/cssquery-mootools.html) 19:25:30 INFO - PROCESS | 1918 | RSS: Main: 338481152 19:25:30 INFO - PROCESS | 1918 | 19:28:32 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:57005/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57005/tests/dromaeo/cssquery-prototype.html) 19:28:33 INFO - PROCESS | 1918 | RSS: Main: 343113728 19:28:33 INFO - PROCESS | 1918 | 19:31:37 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:57005/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57005/tests/dromaeo/cssquery-yui.html) 19:31:37 INFO - PROCESS | 1918 | RSS: Main: 322048000 19:31:37 INFO - PROCESS | 1918 | 19:34:41 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:57005/tests/dromaeo/cssquery-yui.html (next: http://localhost:57005/tests/dromaeo/cssquery-dojo.html) 19:34:41 INFO - PROCESS | 1918 | RSS: Main: 379916288 19:34:41 INFO - PROCESS | 1918 | 19:34:41 INFO - PROCESS | 1918 | __start_tp_report 19:34:41 INFO - PROCESS | 1918 | _x_x_mozilla_page_load 19:34:41 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details 19:34:41 INFO - PROCESS | 1918 | |i|pagename|runs| 19:34:41 INFO - PROCESS | 1918 | |0;dojo.html;3274.574305340306;3306.718928891044;3316.1543806329414;3317.9004629861106;3367.730581553476;5603.7478313475885;5649.23735295735;5690.374058853528;5712.714364281785;5726.112452569859;5672.404397538261;5727.799527016556;5731.8280451586415;5779.913301300484;5784.913226301609;5151.918097199593;5164.431912489623;5177.948220517785;5233.921491177616;5259.947400525984;5313.946860531384;5325.4142044375085;5330.626856120073;5331.440198779131;5395.487428694268;5167;5186.429492755794;5218.13900706383;5270.947290527084;5285.894282114375;5179.792808287664;5221.686698798047;5230.764615592308;5264.947350526484;5290.709011004393;5174.197999310113;5184.144616138333;5198.6880787152895;5199.220116982483;5206.062908676437;5703.971480142614;5750.2237197978575;5772.7113644317615;5776.682282474462;5795.3045634523705;6118.558266176196;6166.475849552793;6172.1667574877265;6203.31763506014;6248;5517.393086760453;6259.467945224661;6301.369863013708;6334.3032266450655;6341.841453963688;5846.21076154718;5872.412758724136;5875.236219291465;5883.882322353615;5888.823335299948;6215.751369945242;6230.7819226326865;6273.309935907047;6296.748130074838;6313.40022697843;6077.361877002851;6168.414000669929;6171.907421388682;6241.7191226394925;6247.250329960434;6140.2413129824745;6284.057391391237;6309.526785491106;6310.1481300024825;6315.305316415191;5199.688018718889;5276.81531146408;6079.665618390943;6100.732564372368;6209.130721698965;5179.689218646893;6232.25212974446;6276.058591211355;6299.590526615755;6319.715612797435;4833.226683730657;4881.4630390657;4897.755112244445;4910.361652985089;4918.0901533216465;4073.877783666495;4465.1962646723905;4483.977580112144;4494.483134439516;4496.6177874881005;5717.514011309002;5935.465808077208;5936.347001829795;5941.970290148522;5955;5119.513646203604;6121.928662484048;6185;6189.102580125947;6214.844628884223;5744.425557444222;5749.8562535936935;5798.739056742456;5811.709414529257;5851.180834683148;5420.376656684453;5421.241026256327;5430.185472179205;5443.401225865152;5454.181872719045;6177.227846519185;6267.341929097379;6310.085037669604;6322.620642761449;6344.1118243445035;6065.60573562717;6142.6314421134875;6168.506519478521;6237.064440333987;6247.0629405589525;6298.622082675054;6303.716332765129;6316.842078948063;6317.526185536011;6325.05124231369;5059.797608095782;6240.438360547572;6243.157173781481;6253.343398943228;6265.373462653698;6139.614800607318;6158.753649853956;6200.968995155175;6215.409536094064;6298.433141017331;5192.221166824856;6159.72281247336;6168.228971378578;6187.628742275478;6194.349593292639;6038.426349496791;6046.697665116814;6087.8782424350375;6152.107944347955;6185.8144255672405;5908.881822363614;5937.168796368598;5961.701914904324;5974;5983.34183239852;6106.114613380946;6109.175261339662;6111;6117.296510901303;6121.418465245794;5927.911081333869;5939.703014849327;5947.672877991752;5954.434328738763;5976.880462390641 19:34:41 INFO - PROCESS | 1918 | |1;ext.html;429.22524842659;438.49353996134477;469.0314500556349;498.971680120311;568.7071158353449;36271.455928161064;38635.03412414688;38709.225815483675;38744.22511549774;38822;39330.41004384936;39846.40230396539;39861.20277594446;39881.601183988154;39941;6846.828829279258;8419.115992820758;8434.198751118634;8924.8661270081;9163.816723665523;8360.749177524684;8454.323654107657;10777.299475534104;11048.613298534552;11099.001089901907;11028.50371733274;11087.667369978913;11089.944550277238;11103.333799971988;11128.332300062022;11016.228863979524;11095.83356249661;11116.221864469491;11124.666260012213;11136.443177841076;8152;8382.287505561973;10693.304935179189;10947.178961577834;10980.286281391764;38400.80799595984;38740.41889371662;38794.80602596969;38834;38951;3321.9501707474406;3340.315235376742;3922.901927451808;3924.4701965234613;4016.879493615196;28688;29468.410631787458;30084.69915300841;30167.09498715042;30360.84819575888;3151.77937544372;3183.2041989502623;3295.934081318384;3330.5004249362546;3341;272841.63579182175;310088;310113;310408;312036;57016.71491642515;57319.42680573224;57359;57505.712471438215;57569;56417.71791141018;56559.151612725844;56763;56763.43236567664;56943.14585281224;14924.925375373054;14996.100233985995;15021.399144034334;15538.611534711497;15544.844551554339;10780;11115;11699.473523691284;11726.355050472306;11757.470913808898;14670.266486675837;14776.40894364235;14783.817294616405;14787.377092612163;16002.359905603646;5966.164736936832;6224.408681175281;6231.283402408781;6250.499960003189;6300.621962682254;718.5975853522044;720.4452571519901;838.3293365307729;850.306999795165;860.5181098584812;28525.71474285272;28542.001029964267;28573.57139642907;29162.562561561594;29324.706752932623;804.9154838741848;1011.3476807459294;1016.8983101689772;1022.2077889635443;1059.502034043998;14230;14853.2573371331;14957.70084598302;15039.849601504064;15210;207982.9201707994;208945.9552702257;209159;559140;560836;56520;56769;56806.147907781415;56928.71535642295;57052.71473642605;1358.701085761131;1389.6039628705973;1399.90200685951;1403.1791402029723;1406.9788953165505;27222.863885681236;28256.15231543057;28569.285767855137;28710.425791483634;28819;43543;43830.780846095564;43875.46435874857;47670.80822979453;47761;506887;508793;510157.8397404157;510472;511612;37356.81321593375;38135.42796857994;38223;38376.42435363416;38445.6155438442;43054;43216.78391608022;43434.78282608567;43637.56362436462;44769.552304476536;32060.877869274464;36352.81823590865;36399.27201455903;37148.62851371452;37252;32946.50580241346;36191;36194.91415257547;37159.814200929904;37472.063198420256;34992.65007349894;35104.47343289802;35932.281354372244;35959.82020089883;36223.63776362203;31286.843565782026;32494.963280690587;32795.34409311848;33055.50416743703;33202.833985829915;23600.29199124029;23904.52190956206;24161;24319.51360972806;24493.51012979766 19:34:41 INFO - PROCESS | 1918 | |2;jquery.html;349.79187383506803;367.42864845165775;369.53623202880385;375.9266942946125;378.18312445118534;14463.987520873543;14642.633934151652;15675.686486270268;19866.801331986677;19895;16454.67090658186;18940.81059189408;19034.524136896584;19979;20011.599768004668;5067.2399140129;5316.707581083058;5362.034833729927;5371.355437347524;5372.2210279509445;3878.2825177342143;4617.445906491226;5342.545883599899;5906.173135760997;5950.077737950624;4093.3655283431117;4136.172765446908;5736.942630573703;6028.3067447243475;6054.96972515139;5752.539796816244;5868.149118377854;6078.149059131725;6085.117657939618;6088.573799834014;5722.313322401297;5733.827985160452;5736.282964629421;5742.483176514092;5747.6551406915305;13100.410481528354;18952.81047189524;23935.640965385533;24612.507749844903;24858.627120593206;2889.9133026009254;2943.381889803144;2964.347843474432;2981.686922873089;3007.428588568165;9555.761105972337;11928.522859085626;14106.647333816634;14737.484188053366;14952.40190392383;1767.761352217447;1782.8841125326812;1795.9012254326008;1802.6755184066863;1848.260695721722;97033;100891;104418.95581044244;106370;109724;10882.782344353069;11208.887911120784;11387.259828111146;11406;11531.192816502931;14369.209693466959;14381.352839122264;14399.352029158714;14404.279786010868;14416.351264193136;7375.4099672026105;7485.7754267372065;8278.793030174294;8303.916960830435;8347.624356903954;7989.640466179036;7991.120976692559;8176.059753128347;8176.918230817616;8193.016837979481;3976.701747368901;4236.872893813191;4461.776911154429;4483.439570053743;4551.340055691906;1869.9439016829517;1925.0855843474237;1925.711143328484;1929.8263156316;1931.121339790392;685.2757534167165;787.7514140088025;790.9717367422328;824.4229039672254;909.2226146644656;17340.393086241922;20001.499962501053;20001.59996800085;20069.498262543555;20400.387988360373;902.634433054614;904.8868891388577;928.2573940847295;933.8179055084194;936.6487567162314;10200.642977495752;10321.296985997376;10480.737981550523;10528.736781580521;10542.525586348633;535867;537235;539760;544504;546343;66331;94796;114435;120202;122442;895.2748273898163;915.6612053540233;963.0995019656643;964.9276304277207;968.4044312747609;17526.211320490864;17763.20065597077;17869.46391608254;17900.820991789915;18203.635927281644;51652;51784.22323665072;52807.94384112373;53011.20483192832;53102.468975309755;70875.52941409838;72142.1964450893;72771.63614181895;73891.43845546163;73964.63017684878;6088.786892458743;6831;7084.964575177091;7183.173934997542;7399.297066778648;9116.38855364609;12630.305333206394;12673;13002.739945201232;13036.087473876738;9135.862962055708;11222.382768947786;11378.146639002274;11410.429478526206;11549.6535103947;10230.897691022994;10287.742806429898;10326.173906087344;10534.473276336306;11202.719932001764;11228.88771112311;11249.831252531383;11420;11620.302781833117;11725.296482211095;6294.464970477561;7099.538529995534;7308.817279568053;7327.963360183378;7385.630718464163;9483.28875334339;10886.61896833607;10998.12015038779;11057.170712196457;11312.9434352831 19:34:41 INFO - PROCESS | 1918 | |3;mootools.html;1368.9452421903122;1378.6002059402774;1390.9582712518627;1398.4056775870256;1398.713263780925;7850.254225848543;8241.917580824198;8246.793830154234;8261.173882611736;8264.545450000254;7236.276372362774;7517.285857843496;7800.765977020698;7922.207779222075;8041.155678653745;2232.073689418889;2241.23797908711;2249.201533455616;2271.2277825539313;2281.4296425893526;2189.8831595886054;2277.294038847954;2300.861948283108;2311.295055008222;2314.803241724447;1965.6068786242738;2037.735094437721;2069.4205622425743;2195.5060111474872;2268.7050683411135;2089.529855782452;2097.979020209794;2246.1015593762463;2255.1994042115143;2256.323103069081;2248.190651365507;2264.5923733728087;2269.943251418728;2275.4538910661486;2278.236790675109;8086.110527841933;8099.55452450115;8181.427300088996;8204.343652507785;8218.876716849192;4927.433345165316;5053.36832895888;5056.039352523009;5066.6200034997155;5069.923951140736;3232.3212125453683;3375.274316022067;3417.7778444401033;3420.794752314869;3434.4848272759286;1119.9272047316979;1175.629676651852;1211.3943028485758;1215.8480189976253;1219.7255617486082;10158.542865570993;10456.790864182749;10480.266381353309;10605.57577696899;10624.36253824773;4163.250614889349;4182.832686692559;4363.607275345171;4372.147431250939;4390;8397.160283971554;8403.789905252417;8419;8423.157684231528;8428.367872409593;4211.189188648849;4938.012397520511;4942.777575009133;4942.901141977212;4947.777350019186;4071.633552980246;4107.260693075215;4246.214450326699;4604.953950460519;4623.214053610895;3018;3498.090496470885;3550.7336949728356;3642.2715456908722;3649.50731651229;1111.1222134513805;1123.1913022623698;1127.808272593661;1130.9208355415205;1137.9658610241706;845.51805470881;853.9359548033922;863.1756672377865;908.977275568116;915.624593916491;5000.524950129732;5376.865578360573;5483.506484416342;5551.666899985933;5756.5682573807135;828.0973738624807;875.1030194051149;876.9912300877037;880.7842078690634;893.2987604730256;3555.2178520725397;3562.6437356264164;3657.2685462907525;3678.8160591970295;3723.733930463601;9210.493422861673;9796.118349348593;9919.553620087114;9949.154321882575;9955.900440995498;9686.515674216262;9698.078682525149;9705.805883882284;9862.50687465624;9912.950435247778;7458.067741532309;7712.113106992655;7714.498557593738;7721.45949783521;7748.496347737379;5240.371155461369;5273.288106105627;5336.546393556436;5366.01457460742;5473.17902314656;8564.843746094446;8878.2009619132;9024.413413128126;9071.773205669911;9111.817763644822;8857.955710221408;9198.862017069883;9203.76990575241;9273.165415112402;9292.488913109844;6396.776112836028;6607.471402287902;6617.205935287604;6747.898781518379;7076.893846592202;7521.699132034657;7810.492317999312;7817.921820781719;7884.053913530413;7895.723649672464;6971.7211311549;7074.327938845801;7095.787126386217;7174.820629484305;7226.205117437183;6917.827054323682;6933.618650974043;6948.652567371712;6960.338767817049;7140.892886606809;6141.815745527641;6212.968935155295;6288.213973253343;6289.276733175743;6294.842628934313;4494.73031618104;4500.954990450054;4519.435070616173;4544;4680.742559159279;4140.710150289451;4175.03974085968;4192.748435093904;4196.5593612671455;4293.355996600535 19:34:41 INFO - PROCESS | 1918 | |4;prototype.html;315.306326082618;344.9137715571107;350.2854177477943;352.4990194569529;367.845504887947;4523.801192683937;4815.807367705288;4826.7586620669;5146.665466744659;5281.313429254196;5157.690538567688;5183.92642942823;5212.742036227358;5278.894422111555;5295.682259064449;4121.505419349683;4121.8557350492665;4131.710780245369;4139.751614903115;4156.958430415687;3658.414653655416;3659.9451008234896;3860.1700634363606;3880.4955355803845;4033.4353190553347;4098.569650186732;4137.689673274502;4140.275451795938;4152.294110001292;4187.28816101262;3759.8344513200723;3817.8281977310808;4103.778533850943;4121.010957370239;4141.834326626931;3137.4980003199607;3239.7570182236427;3251.2359595495277;3464.9653503464897;3519.4720791881423;4879.925646842502;5038.496150384969;5085.940194221346;5109.259157422191;5139.537441630271;3819.656230939201;4186.602272784081;4760.857174284777;4781.378420805273;4821.011692603008;5517.834464966058;5557.6109672322955;5562.97085039269;5567.248421463091;5590.636608620467;3500.8949731508096;3641.8346129238653;4019.336809426441;4028.194361127757;4051.4733084699096;5095.490450954875;5196.9740151299;5327.600429967768;5690.28871391072;5829.795957141522;3554.8400321985573;3785.621437856192;4107.712460127762;4112.807285887119;4147.896302592399;3581.8567257309937;3615.0840054771015;3857.264231096018;3883.5339759229073;4177.582241775859;3964.821583028713;4728.770519664912;4730.172219861579;4767.974885399656;4776.47458779534;4601.7345230062;4644.674872758942;5031.949680503221;5621.494065534122;5661.716914154358;1138.1463902073444;1153.9307641541534;1278.2134945818207;1290.3677198172827;1336.7527007503643;786.4809225910872;854.9487030778173;952.9952350238204;956.7799406136628;961.5432669481941;4374.5782620648415;4737.95262047382;4823.1800593899125;4850.375124333317;4966.950330496648;4466;4619.505467047757;4783.689060211075;5341.732913354317;5412.56699464042;4275.5724427557;4446.911061778746;4497.64018878489;5239.895202095937;5362.436944120811;2727.504333534833;2930.58971743956;2950.232939435762;2952.011076289467;2955.70442955707;5115.276624352381;5744.678723893528;5847.473727364558;5849.444302791228;5890.587658863838;5220.791168353299;5571.526420254324;5601;5675.0137451891505;5726.685032323179;5538.833834984876;5706.828795136153;5727.31272247333;5754.539636829044;5770.624909380961;4781.637233388467;4998.750062496933;5193.948060519498;5230.378582639394;5565.526930210977;5169.508896654812;5398.271233383443;5529.198266251451;5532.340297910691;5582.962218790193;5462.962037212917;5720.599558031065;5808.970955145197;5847.853803654943;5861.794837180849;3595.2629710909723;3661.3226553087766;3670.7797532148156;3676.724245681531;3710.7031437485475;5035.647504674637;5229.555487783581;5549.38956714753;5552.028395030934;5552.833414997556;4429.645628349789;4908.435529914105;5138;5158.690478571298;5324.216387510072;4693.6949098308505;4774.498677638798;5551.361593416647;5664.518515926193;5673.943260567342;4577.32226647415;5278.231792349606;5352.197170424468;5456.290682211287;5551.417101204315;4726;5419.674819510842;5682.516986056066;5700.828975130753;5719.8856022880145;4878.463369029333;4878.756062196805;5038.974805125951;5383.5962302826665;5539.639923404966 19:34:41 INFO - PROCESS | 1918 | |5;yui.html;617.7065893700493;658.9670516474176;711.9181294151174;768.1633462186539;817.9059408168059;14401.423943042279;14812.777808332883;15459.45891893784;15471.071735695867;15491.993020453676;13994.790078148835;14199.858001419983;14215.289235538234;14219;14242.572722818306;605.7546693589103;844.3878188313485;850.158343240194;852.8294341131767;852.9658813647446;671.8622682350107;851.1360968616859;854.9059603443616;854.918782715641;855.8031652719878;722.5772922840144;733.3729661139729;882.7837179890904;907.0385391485014;919.2186641354862;755.5013690963992;896.3322324867983;896.4621227263655;900.2258058070055;902.9638814447414;665.0888283052201;667.1460530520941;667.5427332277405;667.9165104361955;668.8729141463107;11864.169508134433;12416.317102559356;14478.638034049127;14996.250187490581;15056.397744090224;13912.304384780822;14027;14044.36800343987;14060.92969535156;14091.084079534798;11501.539938402453;11613.419329033599;11737.647870563897;11743.416448381427;11978.221415608044;3072.2626569623353;3751.718621103401;3917.9216415671526;3942.7831469269177;3945.3490174121234;82964;94270;96352.51823740907;101410;103032;10652.467376631137;10917.563297468012;11019.449027548591;11029.779404411867;11221.214514984036;22124.2256521021;22205.444863878532;22245.888770556045;22274.331770046923;22288.66567001496;7597.521616129383;7604.695812167452;7663.425243106789;7687;7713.382929365637;3191.9680803192136;4063.553009168989;4115.094679170637;4183.832646694159;4241.915161696748;3495.528103705967;3533.5583052118486;3585.7310701696956;3589.6051434342203;3612.151144481045;573.4752701278284;592.9584929054924;595.1905408644241;602.7378090530633;604.6613896218132;603.7735849056604;626.5457543281085;645.9870802583922;652.1489456259532;670.8792417364824;8460.8730869037;8488.915110848813;8762.737117886474;9053.411528250774;10017.499125043865;536.8523655994554;546.368943869831;563.2368141168657;568.0655321995295;581.7324130002864;4206.72656277341;4408.448943882015;4423.668224883147;4510.548945105529;4529.569690879362;91112.08887911036;93028.53485732528;94593;95159;95802;68830.65584672044;82710.75933860998;82736.51790446296;82845.58577207076;85982.71025934616;67501;71904;72599.27400725926;74124.88812667922;74136;7784.494007889469;8108.2297181767635;8339.749807505785;8550.914490855012;8607.148343235327;45855;46714.06571868475;47160;47642.285365720236;48011.5198848021;59511.404885950586;60514.69742651258;60753;60890;61402.692986534785;12447.93776031114;12467.314297713712;12594.3073130975;12787.36063196855;13183.868161318263;14297.190358103471;14586.562403127924;14722.411103555738;14889.702205955604;15201.77197342063;11771.352575608424;13104.410301536263;13398.866011339762;13675.316234188449;14031.368588413341;13448.058635895784;13486.245408890123;13623.978201634718;13649.86350136486;13778;12655.367231638565;12911.418986145834;13092.345382731015;13482;13528.458861645817;10694;11117.98885805001;11128.60577523863;11346.03558697487;11418.942905285421;10343.844842327522;10623.946880265548;10939.016922318502;10983.176261780238;11318.377489238172 19:34:41 INFO - PROCESS | 1918 | __end_tp_report 19:34:41 INFO - PROCESS | 1918 | __start_cc_report 19:34:41 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,2799 19:34:41 INFO - PROCESS | 1918 | __end_cc_report 19:34:41 INFO - PROCESS | 1918 | __startTimestamp1483500881550__endTimestamp 19:34:41 INFO - PROCESS | 1918 | 19:34:41 INFO - PROCESS | 1918 | ------- Summary: start ------- 19:34:41 INFO - PROCESS | 1918 | Number of tests: 6 19:34:41 INFO - PROCESS | 1918 | 19:34:41 INFO - PROCESS | 1918 | [#0] dojo.html Cycles:165 Average:5724.42 Median:5937.17 stddev:646.57 (10.9%) stddev-sans-first:619.33 19:34:41 INFO - PROCESS | 1918 | Values: 3274.6 3306.7 3316.2 3317.9 3367.7 5603.7 5649.2 5690.4 5712.7 5726.1 5672.4 5727.8 5731.8 5779.9 5784.9 5151.9 5164.4 5177.9 5233.9 5259.9 5313.9 5325.4 5330.6 5331.4 5395.5 5167.0 5186.4 5218.1 5270.9 5285.9 5179.8 5221.7 5230.8 5264.9 5290.7 5174.2 5184.1 5198.7 5199.2 5206.1 5704.0 5750.2 5772.7 5776.7 5795.3 6118.6 6166.5 6172.2 6203.3 6248.0 5517.4 6259.5 6301.4 6334.3 6341.8 5846.2 5872.4 5875.2 5883.9 5888.8 6215.8 6230.8 6273.3 6296.7 6313.4 6077.4 6168.4 6171.9 6241.7 6247.3 6140.2 6284.1 6309.5 6310.1 6315.3 5199.7 5276.8 6079.7 6100.7 6209.1 5179.7 6232.3 6276.1 6299.6 6319.7 4833.2 4881.5 4897.8 4910.4 4918.1 4073.9 4465.2 4484.0 4494.5 4496.6 5717.5 5935.5 5936.3 5942.0 5955.0 5119.5 6121.9 6185.0 6189.1 6214.8 5744.4 5749.9 5798.7 5811.7 5851.2 5420.4 5421.2 5430.2 5443.4 5454.2 6177.2 6267.3 6310.1 6322.6 6344.1 6065.6 6142.6 6168.5 6237.1 6247.1 6298.6 6303.7 6316.8 6317.5 6325.1 5059.8 6240.4 6243.2 6253.3 6265.4 6139.6 6158.8 6201.0 6215.4 6298.4 5192.2 6159.7 6168.2 6187.6 6194.3 6038.4 6046.7 6087.9 6152.1 6185.8 5908.9 5937.2 5961.7 5974.0 5983.3 6106.1 6109.2 6111.0 6117.3 6121.4 5927.9 5939.7 5947.7 5954.4 5976.9 19:34:41 INFO - PROCESS | 1918 | 19:34:41 INFO - PROCESS | 1918 | [#1] ext.html Cycles:180 Average:54200.75 Median:36075.41 stddev:109172.30 (302.6%) stddev-sans-first:109403.91 19:34:41 INFO - PROCESS | 1918 | Values: 429.2 438.5 469.0 499.0 568.7 36271.5 38635.0 38709.2 38744.2 38822.0 39330.4 39846.4 39861.2 39881.6 39941.0 6846.8 8419.1 8434.2 8924.9 9163.8 8360.7 8454.3 10777.3 11048.6 11099.0 11028.5 11087.7 11089.9 11103.3 11128.3 11016.2 11095.8 11116.2 11124.7 11136.4 8152.0 8382.3 10693.3 10947.2 10980.3 38400.8 38740.4 38794.8 38834.0 38951.0 3322.0 3340.3 3922.9 3924.5 4016.9 28688.0 29468.4 30084.7 30167.1 30360.8 3151.8 3183.2 3295.9 3330.5 3341.0 272841.6 310088.0 310113.0 310408.0 312036.0 57016.7 57319.4 57359.0 57505.7 57569.0 56417.7 56559.2 56763.0 56763.4 56943.1 14924.9 14996.1 15021.4 15538.6 15544.8 10780.0 11115.0 11699.5 11726.4 11757.5 14670.3 14776.4 14783.8 14787.4 16002.4 5966.2 6224.4 6231.3 6250.5 6300.6 718.6 720.4 838.3 850.3 860.5 28525.7 28542.0 28573.6 29162.6 29324.7 804.9 1011.3 1016.9 1022.2 1059.5 14230.0 14853.3 14957.7 15039.8 15210.0 207982.9 208946.0 209159.0 559140.0 560836.0 56520.0 56769.0 56806.1 56928.7 57052.7 1358.7 1389.6 1399.9 1403.2 1407.0 27222.9 28256.2 28569.3 28710.4 28819.0 43543.0 43830.8 43875.5 47670.8 47761.0 506887.0 508793.0 510157.8 510472.0 511612.0 37356.8 38135.4 38223.0 38376.4 38445.6 43054.0 43216.8 43434.8 43637.6 44769.6 32060.9 36352.8 36399.3 37148.6 37252.0 32946.5 36191.0 36194.9 37159.8 37472.1 34992.7 35104.5 35932.3 35959.8 36223.6 31286.8 32495.0 32795.3 33055.5 33202.8 23600.3 23904.5 24161.0 24319.5 24493.5 19:34:41 INFO - PROCESS | 1918 | 19:34:41 INFO - PROCESS | 1918 | [#2] jquery.html Cycles:180 Average:31775.87 Median:372.73 stddev:89932.83 (24128.0%) stddev-sans-first:90154.16 19:34:41 INFO - PROCESS | 1918 | Values: 349.8 367.4 369.5 375.9 378.2 14464.0 14642.6 15675.7 19866.8 19895.0 16454.7 18940.8 19034.5 19979.0 20011.6 5067.2 5316.7 5362.0 5371.4 5372.2 3878.3 4617.4 5342.5 5906.2 5950.1 4093.4 4136.2 5736.9 6028.3 6055.0 5752.5 5868.1 6078.1 6085.1 6088.6 5722.3 5733.8 5736.3 5742.5 5747.7 13100.4 18952.8 23935.6 24612.5 24858.6 2889.9 2943.4 2964.3 2981.7 3007.4 9555.8 11928.5 14106.6 14737.5 14952.4 1767.8 1782.9 1795.9 1802.7 1848.3 97033.0 100891.0 104419.0 106370.0 109724.0 10882.8 11208.9 11387.3 11406.0 11531.2 14369.2 14381.4 14399.4 14404.3 14416.4 7375.4 7485.8 8278.8 8303.9 8347.6 7989.6 7991.1 8176.1 8176.9 8193.0 3976.7 4236.9 4461.8 4483.4 4551.3 1869.9 1925.1 1925.7 1929.8 1931.1 685.3 787.8 791.0 824.4 909.2 17340.4 20001.5 20001.6 20069.5 20400.4 902.6 904.9 928.3 933.8 936.6 10200.6 10321.3 10480.7 10528.7 10542.5 535867.0 537235.0 539760.0 544504.0 546343.0 66331.0 94796.0 114435.0 120202.0 122442.0 895.3 915.7 963.1 964.9 968.4 17526.2 17763.2 17869.5 17900.8 18203.6 51652.0 51784.2 52807.9 53011.2 53102.5 70875.5 72142.2 72771.6 73891.4 73964.6 6088.8 6831.0 7085.0 7183.2 7399.3 9116.4 12630.3 12673.0 13002.7 13036.1 9135.9 11222.4 11378.1 11410.4 11549.7 10230.9 10287.7 10326.2 10534.5 11202.7 11228.9 11249.8 11420.0 11620.3 11725.3 6294.5 7099.5 7308.8 7328.0 7385.6 9483.3 10886.6 10998.1 11057.2 11312.9 19:34:41 INFO - PROCESS | 1918 | 19:34:41 INFO - PROCESS | 1918 | [#3] mootools.html Cycles:180 Average:5223.22 Median:5155.15 stddev:2885.32 (56.0%) stddev-sans-first:2878.87 19:34:41 INFO - PROCESS | 1918 | Values: 1368.9 1378.6 1391.0 1398.4 1398.7 7850.3 8241.9 8246.8 8261.2 8264.5 7236.3 7517.3 7800.8 7922.2 8041.2 2232.1 2241.2 2249.2 2271.2 2281.4 2189.9 2277.3 2300.9 2311.3 2314.8 1965.6 2037.7 2069.4 2195.5 2268.7 2089.5 2098.0 2246.1 2255.2 2256.3 2248.2 2264.6 2269.9 2275.5 2278.2 8086.1 8099.6 8181.4 8204.3 8218.9 4927.4 5053.4 5056.0 5066.6 5069.9 3232.3 3375.3 3417.8 3420.8 3434.5 1119.9 1175.6 1211.4 1215.8 1219.7 10158.5 10456.8 10480.3 10605.6 10624.4 4163.3 4182.8 4363.6 4372.1 4390.0 8397.2 8403.8 8419.0 8423.2 8428.4 4211.2 4938.0 4942.8 4942.9 4947.8 4071.6 4107.3 4246.2 4605.0 4623.2 3018.0 3498.1 3550.7 3642.3 3649.5 1111.1 1123.2 1127.8 1130.9 1138.0 845.5 853.9 863.2 909.0 915.6 5000.5 5376.9 5483.5 5551.7 5756.6 828.1 875.1 877.0 880.8 893.3 3555.2 3562.6 3657.3 3678.8 3723.7 9210.5 9796.1 9919.6 9949.2 9955.9 9686.5 9698.1 9705.8 9862.5 9913.0 7458.1 7712.1 7714.5 7721.5 7748.5 5240.4 5273.3 5336.5 5366.0 5473.2 8564.8 8878.2 9024.4 9071.8 9111.8 8858.0 9198.9 9203.8 9273.2 9292.5 6396.8 6607.5 6617.2 6747.9 7076.9 7521.7 7810.5 7817.9 7884.1 7895.7 6971.7 7074.3 7095.8 7174.8 7226.2 6917.8 6933.6 6948.7 6960.3 7140.9 6141.8 6213.0 6288.2 6289.3 6294.8 4494.7 4501.0 4519.4 4544.0 4680.7 4140.7 4175.0 4192.7 4196.6 4293.4 19:34:41 INFO - PROCESS | 1918 | 19:34:41 INFO - PROCESS | 1918 | [#4] prototype.html Cycles:180 Average:4434.62 Median:4864.42 stddev:1333.59 (27.4%) stddev-sans-first:1301.00 19:34:41 INFO - PROCESS | 1918 | Values: 315.3 344.9 350.3 352.5 367.8 4523.8 4815.8 4826.8 5146.7 5281.3 5157.7 5183.9 5212.7 5278.9 5295.7 4121.5 4121.9 4131.7 4139.8 4157.0 3658.4 3659.9 3860.2 3880.5 4033.4 4098.6 4137.7 4140.3 4152.3 4187.3 3759.8 3817.8 4103.8 4121.0 4141.8 3137.5 3239.8 3251.2 3465.0 3519.5 4879.9 5038.5 5085.9 5109.3 5139.5 3819.7 4186.6 4760.9 4781.4 4821.0 5517.8 5557.6 5563.0 5567.2 5590.6 3500.9 3641.8 4019.3 4028.2 4051.5 5095.5 5197.0 5327.6 5690.3 5829.8 3554.8 3785.6 4107.7 4112.8 4147.9 3581.9 3615.1 3857.3 3883.5 4177.6 3964.8 4728.8 4730.2 4768.0 4776.5 4601.7 4644.7 5031.9 5621.5 5661.7 1138.1 1153.9 1278.2 1290.4 1336.8 786.5 854.9 953.0 956.8 961.5 4374.6 4738.0 4823.2 4850.4 4967.0 4466.0 4619.5 4783.7 5341.7 5412.6 4275.6 4446.9 4497.6 5239.9 5362.4 2727.5 2930.6 2950.2 2952.0 2955.7 5115.3 5744.7 5847.5 5849.4 5890.6 5220.8 5571.5 5601.0 5675.0 5726.7 5538.8 5706.8 5727.3 5754.5 5770.6 4781.6 4998.8 5193.9 5230.4 5565.5 5169.5 5398.3 5529.2 5532.3 5583.0 5463.0 5720.6 5809.0 5847.9 5861.8 3595.3 3661.3 3670.8 3676.7 3710.7 5035.6 5229.6 5549.4 5552.0 5552.8 4429.6 4908.4 5138.0 5158.7 5324.2 4693.7 4774.5 5551.4 5664.5 5673.9 4577.3 5278.2 5352.2 5456.3 5551.4 4726.0 5419.7 5682.5 5700.8 5719.9 4878.5 4878.8 5039.0 5383.6 5539.6 19:34:41 INFO - PROCESS | 1918 | 19:34:41 INFO - PROCESS | 1918 | [#5] yui.html Cycles:180 Average:19119.65 Median:4467.11 stddev:26678.13 (597.2%) stddev-sans-first:26716.79 19:34:41 INFO - PROCESS | 1918 | Values: 617.7 659.0 711.9 768.2 817.9 14401.4 14812.8 15459.5 15471.1 15492.0 13994.8 14199.9 14215.3 14219.0 14242.6 605.8 844.4 850.2 852.8 853.0 671.9 851.1 854.9 854.9 855.8 722.6 733.4 882.8 907.0 919.2 755.5 896.3 896.5 900.2 903.0 665.1 667.1 667.5 667.9 668.9 11864.2 12416.3 14478.6 14996.3 15056.4 13912.3 14027.0 14044.4 14060.9 14091.1 11501.5 11613.4 11737.6 11743.4 11978.2 3072.3 3751.7 3917.9 3942.8 3945.3 82964.0 94270.0 96352.5 101410.0 103032.0 10652.5 10917.6 11019.4 11029.8 11221.2 22124.2 22205.4 22245.9 22274.3 22288.7 7597.5 7604.7 7663.4 7687.0 7713.4 3192.0 4063.6 4115.1 4183.8 4241.9 3495.5 3533.6 3585.7 3589.6 3612.2 573.5 593.0 595.2 602.7 604.7 603.8 626.5 646.0 652.1 670.9 8460.9 8488.9 8762.7 9053.4 10017.5 536.9 546.4 563.2 568.1 581.7 4206.7 4408.4 4423.7 4510.5 4529.6 91112.1 93028.5 94593.0 95159.0 95802.0 68830.7 82710.8 82736.5 82845.6 85982.7 67501.0 71904.0 72599.3 74124.9 74136.0 7784.5 8108.2 8339.7 8550.9 8607.1 45855.0 46714.1 47160.0 47642.3 48011.5 59511.4 60514.7 60753.0 60890.0 61402.7 12447.9 12467.3 12594.3 12787.4 13183.9 14297.2 14586.6 14722.4 14889.7 15201.8 11771.4 13104.4 13398.9 13675.3 14031.4 13448.1 13486.2 13624.0 13649.9 13778.0 12655.4 12911.4 13092.3 13482.0 13528.5 10694.0 11118.0 11128.6 11346.0 11418.9 10343.8 10623.9 10939.0 10983.2 11318.4 19:34:41 INFO - PROCESS | 1918 | -------- Summary: end -------- 19:34:41 INFO - PROCESS | 1918 | 19:34:42 INFO - TEST-INFO | 1918: exit 0 19:34:42 INFO - TEST-OK | dromaeo_css | took 1120427ms 19:34:42 INFO - TEST-START | kraken 19:34:42 INFO - operating with platform_type : mac_ 19:34:42 INFO - Initialising browser for kraken test... 19:34:42 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp4sbPBh/profile http://localhost:57005/getInfo.html) 19:34:42 INFO - PROCESS | 1922 | 1483500882811 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: 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 :: this.install :: line 38" data: no] Stack trace: this.install()@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:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4901 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3756 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2789 < callProvider()@resource://gre/modules/AddonManager.jsm:260 < _startProvider()@resource://gre/modules/AddonManager.jsm:755 < startup()@resource://gre/modules/AddonManager.jsm:937 < startup()@resource://gre/modules/AddonManager.jsm:2886 < observe()@resource://gre/components/addonManager.js:65 19:34:44 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 19:34:44 INFO - PROCESS | 1922 | colorDepth:24 19:34:44 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 19:34:44 INFO - PROCESS | 1922 | __metrics 19:34:44 INFO - PROCESS | 1922 | 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" 19:34:44 INFO - PROCESS | 1922 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:34:45 INFO - TEST-INFO | 1922: exit 0 19:34:45 INFO - Browser initialized. 19:34:45 INFO - Running cycle 1/1 for kraken test... 19:34:45 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp4sbPBh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:34:57 INFO - PROCESS | 1923 | RSS: Main: 234164224 19:34:57 INFO - PROCESS | 1923 | 19:35:00 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57005/tests/kraken/driver.html?testName=audio-beat-detection) 19:35:01 INFO - PROCESS | 1923 | RSS: Main: 287014912 19:35:01 INFO - PROCESS | 1923 | 19:35:03 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57005/tests/kraken/driver.html?testName=audio-dft) 19:35:04 INFO - PROCESS | 1923 | RSS: Main: 285728768 19:35:04 INFO - PROCESS | 1923 | 19:35:07 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57005/tests/kraken/driver.html?testName=audio-fft) 19:35:08 INFO - PROCESS | 1923 | RSS: Main: 381669376 19:35:08 INFO - PROCESS | 1923 | 19:35:10 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57005/tests/kraken/driver.html?testName=audio-oscillator) 19:35:11 INFO - PROCESS | 1923 | RSS: Main: 283308032 19:35:11 INFO - PROCESS | 1923 | 19:35:13 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57005/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:35:13 INFO - PROCESS | 1923 | RSS: Main: 285757440 19:35:13 INFO - PROCESS | 1923 | 19:35:16 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57005/tests/kraken/driver.html?testName=imaging-darkroom) 19:35:17 INFO - PROCESS | 1923 | RSS: Main: 328245248 19:35:17 INFO - PROCESS | 1923 | 19:35:20 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57005/tests/kraken/driver.html?testName=imaging-desaturate) 19:35:20 INFO - PROCESS | 1923 | RSS: Main: 329400320 19:35:20 INFO - PROCESS | 1923 | 19:35:23 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57005/tests/kraken/driver.html?testName=json-parse-financial) 19:35:24 INFO - PROCESS | 1923 | RSS: Main: 328187904 19:35:24 INFO - PROCESS | 1923 | 19:35:25 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57005/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:35:26 INFO - PROCESS | 1923 | RSS: Main: 303910912 19:35:26 INFO - PROCESS | 1923 | 19:35:28 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57005/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:35:29 INFO - PROCESS | 1923 | RSS: Main: 308613120 19:35:29 INFO - PROCESS | 1923 | 19:35:31 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57005/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:35:32 INFO - PROCESS | 1923 | RSS: Main: 313958400 19:35:32 INFO - PROCESS | 1923 | 19:35:34 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:35:35 INFO - PROCESS | 1923 | RSS: Main: 322101248 19:35:35 INFO - PROCESS | 1923 | 19:35:38 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:35:39 INFO - PROCESS | 1923 | RSS: Main: 320999424 19:35:39 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:57005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57005/tests/kraken/driver.html?testName=ai-astar) 19:35:41 INFO - PROCESS | 1923 | RSS: Main: 365174784 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | __start_tp_report 19:35:41 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 19:35:41 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 19:35:41 INFO - PROCESS | 1923 | |i|pagename|runs| 19:35:41 INFO - PROCESS | 1923 | |0;ai-astar;115;115;116;115;116;114;115;116;114;114 19:35:41 INFO - PROCESS | 1923 | |1;audio-beat-detection;148;133;123;148;111;111;109;110;130;110 19:35:41 INFO - PROCESS | 1923 | |2;audio-dft;188;180;185;185;184;186;183;181;184;185 19:35:41 INFO - PROCESS | 1923 | |3;audio-fft;82;61;59;88;60;59;58;61;77;59 19:35:41 INFO - PROCESS | 1923 | |4;audio-oscillator;91;70;90;70;69;69;84;68;68;83 19:35:41 INFO - PROCESS | 1923 | |5;imaging-gaussian-blur;93;89;93;90;92;89;92;87;92;88 19:35:41 INFO - PROCESS | 1923 | |6;imaging-darkroom;96;97;97;99;96;97;97;97;97;95 19:35:41 INFO - PROCESS | 1923 | |7;imaging-desaturate;85;88;85;87;85;86;110;73;131;138 19:35:41 INFO - PROCESS | 1923 | |8;json-parse-financial;57;57;57;57;57;57;56;57;56;55 19:35:41 INFO - PROCESS | 1923 | |9;json-stringify-tinderbox;66;73;69;75;75;74;70;70;71;72 19:35:41 INFO - PROCESS | 1923 | |10;stanford-crypto-aes;103;105;87;87;90;105;89;88;91;92 19:35:41 INFO - PROCESS | 1923 | |11;stanford-crypto-ccm;139;140;139;140;138;139;139;139;232;151 19:35:41 INFO - PROCESS | 1923 | |12;stanford-crypto-pbkdf2;170;173;174;171;173;190;223;198;174;173 19:35:41 INFO - PROCESS | 1923 | |13;stanford-crypto-sha256-iterative;67;68;69;66;67;68;66;66;67;67 19:35:41 INFO - PROCESS | 1923 | __end_tp_report 19:35:41 INFO - PROCESS | 1923 | __start_cc_report 19:35:41 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,1994 19:35:41 INFO - PROCESS | 1923 | __end_cc_report 19:35:41 INFO - PROCESS | 1923 | __startTimestamp1483500941313__endTimestamp 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | ------- Summary: start ------- 19:35:41 INFO - PROCESS | 1923 | Number of tests: 14 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 19:35:41 INFO - PROCESS | 1923 | Values: 115.0 115.0 116.0 115.0 116.0 114.0 115.0 116.0 114.0 114.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:126.50 stddev:15.71 (12.4%) stddev-sans-first:13.88 19:35:41 INFO - PROCESS | 1923 | Values: 148.0 133.0 123.0 148.0 111.0 111.0 109.0 110.0 130.0 110.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.00 stddev:2.33 (1.3%) stddev-sans-first:2.00 19:35:41 INFO - PROCESS | 1923 | Values: 188.0 180.0 185.0 185.0 184.0 186.0 183.0 181.0 184.0 185.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#3] audio-fft Cycles:10 Average:66.40 Median:61.00 stddev:11.34 (18.6%) stddev-sans-first:10.52 19:35:41 INFO - PROCESS | 1923 | Values: 82.0 61.0 59.0 88.0 60.0 59.0 58.0 61.0 77.0 59.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:76.50 stddev:9.61 (12.6%) stddev-sans-first:8.57 19:35:41 INFO - PROCESS | 1923 | Values: 91.0 70.0 90.0 70.0 69.0 69.0 84.0 68.0 68.0 83.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:92.00 stddev:2.17 (2.4%) stddev-sans-first:2.11 19:35:41 INFO - PROCESS | 1923 | Values: 93.0 89.0 93.0 90.0 92.0 89.0 92.0 87.0 92.0 88.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:97.00 stddev:1.03 (1.1%) stddev-sans-first:1.05 19:35:41 INFO - PROCESS | 1923 | Values: 96.0 97.0 97.0 99.0 96.0 97.0 97.0 97.0 97.0 95.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:85.00 stddev:21.90 (25.8%) stddev-sans-first:22.81 19:35:41 INFO - PROCESS | 1923 | Values: 85.0 88.0 85.0 87.0 85.0 86.0 110.0 73.0 131.0 138.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:0.70 (1.2%) stddev-sans-first:0.73 19:35:41 INFO - PROCESS | 1923 | Values: 57.0 57.0 57.0 57.0 57.0 57.0 56.0 57.0 56.0 55.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.50 stddev:2.88 (4.0%) stddev-sans-first:2.26 19:35:41 INFO - PROCESS | 1923 | Values: 66.0 73.0 69.0 75.0 75.0 74.0 70.0 70.0 71.0 72.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#10] stanford-crypto-aes Cycles:10 Average:93.70 Median:88.50 stddev:7.53 (8.5%) stddev-sans-first:7.19 19:35:41 INFO - PROCESS | 1923 | Values: 103.0 105.0 87.0 87.0 90.0 105.0 89.0 88.0 91.0 92.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:139.50 stddev:29.20 (20.9%) stddev-sans-first:30.72 19:35:41 INFO - PROCESS | 1923 | Values: 139.0 140.0 139.0 140.0 138.0 139.0 139.0 139.0 232.0 151.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:174.00 stddev:17.12 (9.8%) stddev-sans-first:17.61 19:35:41 INFO - PROCESS | 1923 | Values: 170.0 173.0 174.0 171.0 173.0 190.0 223.0 198.0 174.0 173.0 19:35:41 INFO - PROCESS | 1923 | 19:35:41 INFO - PROCESS | 1923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 19:35:41 INFO - PROCESS | 1923 | Values: 67.0 68.0 69.0 66.0 67.0 68.0 66.0 66.0 67.0 67.0 19:35:41 INFO - PROCESS | 1923 | -------- Summary: end -------- 19:35:41 INFO - PROCESS | 1923 | 19:35:42 INFO - TEST-INFO | 1923: exit 0 19:35:42 INFO - TEST-OK | kraken | took 59903ms 19:35:42 INFO - SUITE-END | took 1180s 19:35:42 INFO - Completed test suite (00:19:40) 19:35:42 INFO - javascript benchmark 19:35:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3274.574305340306, 3306.718928891044, 3316.1543806329414, 3317.9004629861106, 3367.730581553476, 5603.7478313475885, 5649.23735295735, 5690.374058853528, 5712.714364281785, 5726.112452569859, 5672.404397538261, 5727.799527016556, 5731.8280451586415, 5779.913301300484, 5784.913226301609, 5151.918097199593, 5164.431912489623, 5177.948220517785, 5233.921491177616, 5259.947400525984, 5313.946860531384, 5325.4142044375085, 5330.626856120073, 5331.440198779131, 5395.487428694268, 5167.0, 5186.429492755794, 5218.13900706383, 5270.947290527084, 5285.894282114375, 5179.792808287664, 5221.686698798047, 5230.764615592308, 5264.947350526484, 5290.709011004393, 5174.197999310113, 5184.144616138333, 5198.6880787152895, 5199.220116982483, 5206.062908676437, 5703.971480142614, 5750.2237197978575, 5772.7113644317615, 5776.682282474462, 5795.3045634523705, 6118.558266176196, 6166.475849552793, 6172.1667574877265, 6203.31763506014, 6248.0, 5517.393086760453, 6259.467945224661, 6301.369863013708, 6334.3032266450655, 6341.841453963688, 5846.21076154718, 5872.412758724136, 5875.236219291465, 5883.882322353615, 5888.823335299948, 6215.751369945242, 6230.7819226326865, 6273.309935907047, 6296.748130074838, 6313.40022697843, 6077.361877002851, 6168.414000669929, 6171.907421388682, 6241.7191226394925, 6247.250329960434, 6140.2413129824745, 6284.057391391237, 6309.526785491106, 6310.1481300024825, 6315.305316415191, 5199.688018718889, 5276.81531146408, 6079.665618390943, 6100.732564372368, 6209.130721698965, 5179.689218646893, 6232.25212974446, 6276.058591211355, 6299.590526615755, 6319.715612797435, 4833.226683730657, 4881.4630390657, 4897.755112244445, 4910.361652985089, 4918.0901533216465, 4073.877783666495, 4465.1962646723905, 4483.977580112144, 4494.483134439516, 4496.6177874881005, 5717.514011309002, 5935.465808077208, 5936.347001829795, 5941.970290148522, 5955.0, 5119.513646203604, 6121.928662484048, 6185.0, 6189.102580125947, 6214.844628884223, 5744.425557444222, 5749.8562535936935, 5798.739056742456, 5811.709414529257, 5851.180834683148, 5420.376656684453, 5421.241026256327, 5430.185472179205, 5443.401225865152, 5454.181872719045, 6177.227846519185, 6267.341929097379, 6310.085037669604, 6322.620642761449, 6344.1118243445035, 6065.60573562717, 6142.6314421134875, 6168.506519478521, 6237.064440333987, 6247.0629405589525, 6298.622082675054, 6303.716332765129, 6316.842078948063, 6317.526185536011, 6325.05124231369, 5059.797608095782, 6240.438360547572, 6243.157173781481, 6253.343398943228, 6265.373462653698, 6139.614800607318, 6158.753649853956, 6200.968995155175, 6215.409536094064, 6298.433141017331, 5192.221166824856, 6159.72281247336, 6168.228971378578, 6187.628742275478, 6194.349593292639, 6038.426349496791, 6046.697665116814, 6087.8782424350375, 6152.107944347955, 6185.8144255672405, 5908.881822363614, 5937.168796368598, 5961.701914904324, 5974.0, 5983.34183239852, 6106.114613380946, 6109.175261339662, 6111.0, 6117.296510901303, 6121.418465245794, 5927.911081333869, 5939.703014849327, 5947.672877991752, 5954.434328738763, 5976.880462390641], "value": 5684.038296863864, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.22524842659, 438.49353996134477, 469.0314500556349, 498.971680120311, 568.7071158353449, 36271.455928161064, 38635.03412414688, 38709.225815483675, 38744.22511549774, 38822.0, 39330.41004384936, 39846.40230396539, 39861.20277594446, 39881.601183988154, 39941.0, 6846.828829279258, 8419.115992820758, 8434.198751118634, 8924.8661270081, 9163.816723665523, 8360.749177524684, 8454.323654107657, 10777.299475534104, 11048.613298534552, 11099.001089901907, 11028.50371733274, 11087.667369978913, 11089.944550277238, 11103.333799971988, 11128.332300062022, 11016.228863979524, 11095.83356249661, 11116.221864469491, 11124.666260012213, 11136.443177841076, 8152.0, 8382.287505561973, 10693.304935179189, 10947.178961577834, 10980.286281391764, 38400.80799595984, 38740.41889371662, 38794.80602596969, 38834.0, 38951.0, 3321.9501707474406, 3340.315235376742, 3922.901927451808, 3924.4701965234613, 4016.879493615196, 28688.0, 29468.410631787458, 30084.69915300841, 30167.09498715042, 30360.84819575888, 3151.77937544372, 3183.2041989502623, 3295.934081318384, 3330.5004249362546, 3341.0, 272841.63579182175, 310088.0, 310113.0, 310408.0, 312036.0, 57016.71491642515, 57319.42680573224, 57359.0, 57505.712471438215, 57569.0, 56417.71791141018, 56559.151612725844, 56763.0, 56763.43236567664, 56943.14585281224, 14924.925375373054, 14996.100233985995, 15021.399144034334, 15538.611534711497, 15544.844551554339, 10780.0, 11115.0, 11699.473523691284, 11726.355050472306, 11757.470913808898, 14670.266486675837, 14776.40894364235, 14783.817294616405, 14787.377092612163, 16002.359905603646, 5966.164736936832, 6224.408681175281, 6231.283402408781, 6250.499960003189, 6300.621962682254, 718.5975853522044, 720.4452571519901, 838.3293365307729, 850.306999795165, 860.5181098584812, 28525.71474285272, 28542.001029964267, 28573.57139642907, 29162.562561561594, 29324.706752932623, 804.9154838741848, 1011.3476807459294, 1016.8983101689772, 1022.2077889635443, 1059.502034043998, 14230.0, 14853.2573371331, 14957.70084598302, 15039.849601504064, 15210.0, 207982.9201707994, 208945.9552702257, 209159.0, 559140.0, 560836.0, 56520.0, 56769.0, 56806.147907781415, 56928.71535642295, 57052.71473642605, 1358.701085761131, 1389.6039628705973, 1399.90200685951, 1403.1791402029723, 1406.9788953165505, 27222.863885681236, 28256.15231543057, 28569.285767855137, 28710.425791483634, 28819.0, 43543.0, 43830.780846095564, 43875.46435874857, 47670.80822979453, 47761.0, 506887.0, 508793.0, 510157.8397404157, 510472.0, 511612.0, 37356.81321593375, 38135.42796857994, 38223.0, 38376.42435363416, 38445.6155438442, 43054.0, 43216.78391608022, 43434.78282608567, 43637.56362436462, 44769.552304476536, 32060.877869274464, 36352.81823590865, 36399.27201455903, 37148.62851371452, 37252.0, 32946.50580241346, 36191.0, 36194.91415257547, 37159.814200929904, 37472.063198420256, 34992.65007349894, 35104.47343289802, 35932.281354372244, 35959.82020089883, 36223.63776362203, 31286.843565782026, 32494.963280690587, 32795.34409311848, 33055.50416743703, 33202.833985829915, 23600.29199124029, 23904.52190956206, 24161.0, 24319.51360972806, 24493.51012979766], "value": 18686.42389228487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.79187383506803, 367.42864845165775, 369.53623202880385, 375.9266942946125, 378.18312445118534, 14463.987520873543, 14642.633934151652, 15675.686486270268, 19866.801331986677, 19895.0, 16454.67090658186, 18940.81059189408, 19034.524136896584, 19979.0, 20011.599768004668, 5067.2399140129, 5316.707581083058, 5362.034833729927, 5371.355437347524, 5372.2210279509445, 3878.2825177342143, 4617.445906491226, 5342.545883599899, 5906.173135760997, 5950.077737950624, 4093.3655283431117, 4136.172765446908, 5736.942630573703, 6028.3067447243475, 6054.96972515139, 5752.539796816244, 5868.149118377854, 6078.149059131725, 6085.117657939618, 6088.573799834014, 5722.313322401297, 5733.827985160452, 5736.282964629421, 5742.483176514092, 5747.6551406915305, 13100.410481528354, 18952.81047189524, 23935.640965385533, 24612.507749844903, 24858.627120593206, 2889.9133026009254, 2943.381889803144, 2964.347843474432, 2981.686922873089, 3007.428588568165, 9555.761105972337, 11928.522859085626, 14106.647333816634, 14737.484188053366, 14952.40190392383, 1767.761352217447, 1782.8841125326812, 1795.9012254326008, 1802.6755184066863, 1848.260695721722, 97033.0, 100891.0, 104418.95581044244, 106370.0, 109724.0, 10882.782344353069, 11208.887911120784, 11387.259828111146, 11406.0, 11531.192816502931, 14369.209693466959, 14381.352839122264, 14399.352029158714, 14404.279786010868, 14416.351264193136, 7375.4099672026105, 7485.7754267372065, 8278.793030174294, 8303.916960830435, 8347.624356903954, 7989.640466179036, 7991.120976692559, 8176.059753128347, 8176.918230817616, 8193.016837979481, 3976.701747368901, 4236.872893813191, 4461.776911154429, 4483.439570053743, 4551.340055691906, 1869.9439016829517, 1925.0855843474237, 1925.711143328484, 1929.8263156316, 1931.121339790392, 685.2757534167165, 787.7514140088025, 790.9717367422328, 824.4229039672254, 909.2226146644656, 17340.393086241922, 20001.499962501053, 20001.59996800085, 20069.498262543555, 20400.387988360373, 902.634433054614, 904.8868891388577, 928.2573940847295, 933.8179055084194, 936.6487567162314, 10200.642977495752, 10321.296985997376, 10480.737981550523, 10528.736781580521, 10542.525586348633, 535867.0, 537235.0, 539760.0, 544504.0, 546343.0, 66331.0, 94796.0, 114435.0, 120202.0, 122442.0, 895.2748273898163, 915.6612053540233, 963.0995019656643, 964.9276304277207, 968.4044312747609, 17526.211320490864, 17763.20065597077, 17869.46391608254, 17900.820991789915, 18203.635927281644, 51652.0, 51784.22323665072, 52807.94384112373, 53011.20483192832, 53102.468975309755, 70875.52941409838, 72142.1964450893, 72771.63614181895, 73891.43845546163, 73964.63017684878, 6088.786892458743, 6831.0, 7084.964575177091, 7183.173934997542, 7399.297066778648, 9116.38855364609, 12630.305333206394, 12673.0, 13002.739945201232, 13036.087473876738, 9135.862962055708, 11222.382768947786, 11378.146639002274, 11410.429478526206, 11549.6535103947, 10230.897691022994, 10287.742806429898, 10326.173906087344, 10534.473276336306, 11202.719932001764, 11228.88771112311, 11249.831252531383, 11420.0, 11620.302781833117, 11725.296482211095, 6294.464970477561, 7099.538529995534, 7308.817279568053, 7327.963360183378, 7385.630718464163, 9483.28875334339, 10886.61896833607, 10998.12015038779, 11057.170712196457, 11312.9434352831], "value": 9106.948713881684, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.9452421903122, 1378.6002059402774, 1390.9582712518627, 1398.4056775870256, 1398.713263780925, 7850.254225848543, 8241.917580824198, 8246.793830154234, 8261.173882611736, 8264.545450000254, 7236.276372362774, 7517.285857843496, 7800.765977020698, 7922.207779222075, 8041.155678653745, 2232.073689418889, 2241.23797908711, 2249.201533455616, 2271.2277825539313, 2281.4296425893526, 2189.8831595886054, 2277.294038847954, 2300.861948283108, 2311.295055008222, 2314.803241724447, 1965.6068786242738, 2037.735094437721, 2069.4205622425743, 2195.5060111474872, 2268.7050683411135, 2089.529855782452, 2097.979020209794, 2246.1015593762463, 2255.1994042115143, 2256.323103069081, 2248.190651365507, 2264.5923733728087, 2269.943251418728, 2275.4538910661486, 2278.236790675109, 8086.110527841933, 8099.55452450115, 8181.427300088996, 8204.343652507785, 8218.876716849192, 4927.433345165316, 5053.36832895888, 5056.039352523009, 5066.6200034997155, 5069.923951140736, 3232.3212125453683, 3375.274316022067, 3417.7778444401033, 3420.794752314869, 3434.4848272759286, 1119.9272047316979, 1175.629676651852, 1211.3943028485758, 1215.8480189976253, 1219.7255617486082, 10158.542865570993, 10456.790864182749, 10480.266381353309, 10605.57577696899, 10624.36253824773, 4163.250614889349, 4182.832686692559, 4363.607275345171, 4372.147431250939, 4390.0, 8397.160283971554, 8403.789905252417, 8419.0, 8423.157684231528, 8428.367872409593, 4211.189188648849, 4938.012397520511, 4942.777575009133, 4942.901141977212, 4947.777350019186, 4071.633552980246, 4107.260693075215, 4246.214450326699, 4604.953950460519, 4623.214053610895, 3018.0, 3498.090496470885, 3550.7336949728356, 3642.2715456908722, 3649.50731651229, 1111.1222134513805, 1123.1913022623698, 1127.808272593661, 1130.9208355415205, 1137.9658610241706, 845.51805470881, 853.9359548033922, 863.1756672377865, 908.977275568116, 915.624593916491, 5000.524950129732, 5376.865578360573, 5483.506484416342, 5551.666899985933, 5756.5682573807135, 828.0973738624807, 875.1030194051149, 876.9912300877037, 880.7842078690634, 893.2987604730256, 3555.2178520725397, 3562.6437356264164, 3657.2685462907525, 3678.8160591970295, 3723.733930463601, 9210.493422861673, 9796.118349348593, 9919.553620087114, 9949.154321882575, 9955.900440995498, 9686.515674216262, 9698.078682525149, 9705.805883882284, 9862.50687465624, 9912.950435247778, 7458.067741532309, 7712.113106992655, 7714.498557593738, 7721.45949783521, 7748.496347737379, 5240.371155461369, 5273.288106105627, 5336.546393556436, 5366.01457460742, 5473.17902314656, 8564.843746094446, 8878.2009619132, 9024.413413128126, 9071.773205669911, 9111.817763644822, 8857.955710221408, 9198.862017069883, 9203.76990575241, 9273.165415112402, 9292.488913109844, 6396.776112836028, 6607.471402287902, 6617.205935287604, 6747.898781518379, 7076.893846592202, 7521.699132034657, 7810.492317999312, 7817.921820781719, 7884.053913530413, 7895.723649672464, 6971.7211311549, 7074.327938845801, 7095.787126386217, 7174.820629484305, 7226.205117437183, 6917.827054323682, 6933.618650974043, 6948.652567371712, 6960.338767817049, 7140.892886606809, 6141.815745527641, 6212.968935155295, 6288.213973253343, 6289.276733175743, 6294.842628934313, 4494.73031618104, 4500.954990450054, 4519.435070616173, 4544.0, 4680.742559159279, 4140.710150289451, 4175.03974085968, 4192.748435093904, 4196.5593612671455, 4293.355996600535], "value": 4230.435478964201, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.306326082618, 344.9137715571107, 350.2854177477943, 352.4990194569529, 367.845504887947, 4523.801192683937, 4815.807367705288, 4826.7586620669, 5146.665466744659, 5281.313429254196, 5157.690538567688, 5183.92642942823, 5212.742036227358, 5278.894422111555, 5295.682259064449, 4121.505419349683, 4121.8557350492665, 4131.710780245369, 4139.751614903115, 4156.958430415687, 3658.414653655416, 3659.9451008234896, 3860.1700634363606, 3880.4955355803845, 4033.4353190553347, 4098.569650186732, 4137.689673274502, 4140.275451795938, 4152.294110001292, 4187.28816101262, 3759.8344513200723, 3817.8281977310808, 4103.778533850943, 4121.010957370239, 4141.834326626931, 3137.4980003199607, 3239.7570182236427, 3251.2359595495277, 3464.9653503464897, 3519.4720791881423, 4879.925646842502, 5038.496150384969, 5085.940194221346, 5109.259157422191, 5139.537441630271, 3819.656230939201, 4186.602272784081, 4760.857174284777, 4781.378420805273, 4821.011692603008, 5517.834464966058, 5557.6109672322955, 5562.97085039269, 5567.248421463091, 5590.636608620467, 3500.8949731508096, 3641.8346129238653, 4019.336809426441, 4028.194361127757, 4051.4733084699096, 5095.490450954875, 5196.9740151299, 5327.600429967768, 5690.28871391072, 5829.795957141522, 3554.8400321985573, 3785.621437856192, 4107.712460127762, 4112.807285887119, 4147.896302592399, 3581.8567257309937, 3615.0840054771015, 3857.264231096018, 3883.5339759229073, 4177.582241775859, 3964.821583028713, 4728.770519664912, 4730.172219861579, 4767.974885399656, 4776.47458779534, 4601.7345230062, 4644.674872758942, 5031.949680503221, 5621.494065534122, 5661.716914154358, 1138.1463902073444, 1153.9307641541534, 1278.2134945818207, 1290.3677198172827, 1336.7527007503643, 786.4809225910872, 854.9487030778173, 952.9952350238204, 956.7799406136628, 961.5432669481941, 4374.5782620648415, 4737.95262047382, 4823.1800593899125, 4850.375124333317, 4966.950330496648, 4466.0, 4619.505467047757, 4783.689060211075, 5341.732913354317, 5412.56699464042, 4275.5724427557, 4446.911061778746, 4497.64018878489, 5239.895202095937, 5362.436944120811, 2727.504333534833, 2930.58971743956, 2950.232939435762, 2952.011076289467, 2955.70442955707, 5115.276624352381, 5744.678723893528, 5847.473727364558, 5849.444302791228, 5890.587658863838, 5220.791168353299, 5571.526420254324, 5601.0, 5675.0137451891505, 5726.685032323179, 5538.833834984876, 5706.828795136153, 5727.31272247333, 5754.539636829044, 5770.624909380961, 4781.637233388467, 4998.750062496933, 5193.948060519498, 5230.378582639394, 5565.526930210977, 5169.508896654812, 5398.271233383443, 5529.198266251451, 5532.340297910691, 5582.962218790193, 5462.962037212917, 5720.599558031065, 5808.970955145197, 5847.853803654943, 5861.794837180849, 3595.2629710909723, 3661.3226553087766, 3670.7797532148156, 3676.724245681531, 3710.7031437485475, 5035.647504674637, 5229.555487783581, 5549.38956714753, 5552.028395030934, 5552.833414997556, 4429.645628349789, 4908.435529914105, 5138.0, 5158.690478571298, 5324.216387510072, 4693.6949098308505, 4774.498677638798, 5551.361593416647, 5664.518515926193, 5673.943260567342, 4577.32226647415, 5278.231792349606, 5352.197170424468, 5456.290682211287, 5551.417101204315, 4726.0, 5419.674819510842, 5682.516986056066, 5700.828975130753, 5719.8856022880145, 4878.463369029333, 4878.756062196805, 5038.974805125951, 5383.5962302826665, 5539.639923404966], "value": 4022.107983789866, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.7065893700493, 658.9670516474176, 711.9181294151174, 768.1633462186539, 817.9059408168059, 14401.423943042279, 14812.777808332883, 15459.45891893784, 15471.071735695867, 15491.993020453676, 13994.790078148835, 14199.858001419983, 14215.289235538234, 14219.0, 14242.572722818306, 605.7546693589103, 844.3878188313485, 850.158343240194, 852.8294341131767, 852.9658813647446, 671.8622682350107, 851.1360968616859, 854.9059603443616, 854.918782715641, 855.8031652719878, 722.5772922840144, 733.3729661139729, 882.7837179890904, 907.0385391485014, 919.2186641354862, 755.5013690963992, 896.3322324867983, 896.4621227263655, 900.2258058070055, 902.9638814447414, 665.0888283052201, 667.1460530520941, 667.5427332277405, 667.9165104361955, 668.8729141463107, 11864.169508134433, 12416.317102559356, 14478.638034049127, 14996.250187490581, 15056.397744090224, 13912.304384780822, 14027.0, 14044.36800343987, 14060.92969535156, 14091.084079534798, 11501.539938402453, 11613.419329033599, 11737.647870563897, 11743.416448381427, 11978.221415608044, 3072.2626569623353, 3751.718621103401, 3917.9216415671526, 3942.7831469269177, 3945.3490174121234, 82964.0, 94270.0, 96352.51823740907, 101410.0, 103032.0, 10652.467376631137, 10917.563297468012, 11019.449027548591, 11029.779404411867, 11221.214514984036, 22124.2256521021, 22205.444863878532, 22245.888770556045, 22274.331770046923, 22288.66567001496, 7597.521616129383, 7604.695812167452, 7663.425243106789, 7687.0, 7713.382929365637, 3191.9680803192136, 4063.553009168989, 4115.094679170637, 4183.832646694159, 4241.915161696748, 3495.528103705967, 3533.5583052118486, 3585.7310701696956, 3589.6051434342203, 3612.151144481045, 573.4752701278284, 592.9584929054924, 595.1905408644241, 602.7378090530633, 604.6613896218132, 603.7735849056604, 626.5457543281085, 645.9870802583922, 652.1489456259532, 670.8792417364824, 8460.8730869037, 8488.915110848813, 8762.737117886474, 9053.411528250774, 10017.499125043865, 536.8523655994554, 546.368943869831, 563.2368141168657, 568.0655321995295, 581.7324130002864, 4206.72656277341, 4408.448943882015, 4423.668224883147, 4510.548945105529, 4529.569690879362, 91112.08887911036, 93028.53485732528, 94593.0, 95159.0, 95802.0, 68830.65584672044, 82710.75933860998, 82736.51790446296, 82845.58577207076, 85982.71025934616, 67501.0, 71904.0, 72599.27400725926, 74124.88812667922, 74136.0, 7784.494007889469, 8108.2297181767635, 8339.749807505785, 8550.914490855012, 8607.148343235327, 45855.0, 46714.06571868475, 47160.0, 47642.285365720236, 48011.5198848021, 59511.404885950586, 60514.69742651258, 60753.0, 60890.0, 61402.692986534785, 12447.93776031114, 12467.314297713712, 12594.3073130975, 12787.36063196855, 13183.868161318263, 14297.190358103471, 14586.562403127924, 14722.411103555738, 14889.702205955604, 15201.77197342063, 11771.352575608424, 13104.410301536263, 13398.866011339762, 13675.316234188449, 14031.368588413341, 13448.058635895784, 13486.245408890123, 13623.978201634718, 13649.86350136486, 13778.0, 12655.367231638565, 12911.418986145834, 13092.345382731015, 13482.0, 13528.458861645817, 10694.0, 11117.98885805001, 11128.60577523863, 11346.03558697487, 11418.942905285421, 10343.844842327522, 10623.946880265548, 10939.016922318502, 10983.176261780238, 11318.377489238172], "value": 7160.828076970274, "unit": "score"}], "name": "dromaeo_css", "value": 7002.193379116749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 116.0, 115.0, 116.0, 114.0, 115.0, 116.0, 114.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 133.0, 123.0, 148.0, 111.0, 111.0, 109.0, 110.0, 130.0, 110.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 180.0, 185.0, 185.0, 184.0, 186.0, 183.0, 181.0, 184.0, 185.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 59.0, 88.0, 60.0, 59.0, 58.0, 61.0, 77.0, 59.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 90.0, 70.0, 69.0, 69.0, 84.0, 68.0, 68.0, 83.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 93.0, 90.0, 92.0, 89.0, 92.0, 87.0, 92.0, 88.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 99.0, 96.0, 97.0, 97.0, 97.0, 97.0, 95.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 85.0, 87.0, 85.0, 86.0, 110.0, 73.0, 131.0, 138.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 57.0, 57.0, 57.0, 56.0, 57.0, 56.0, 55.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 69.0, 75.0, 75.0, 74.0, 70.0, 70.0, 71.0, 72.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 105.0, 87.0, 87.0, 90.0, 105.0, 89.0, 88.0, 91.0, 92.0], "value": 93.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 139.0, 140.0, 138.0, 139.0, 139.0, 139.0, 232.0, 151.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 174.0, 171.0, 173.0, 190.0, 223.0, 198.0, 174.0, 173.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 66.0, 67.0, 68.0, 66.0, 66.0, 67.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1469.4999999999998, "alertThreshold": 2.0}]} 19:35:42 INFO - Return code: 0 19:35:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:35:42 INFO - # TBPL SUCCESS # 19:35:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:35:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:35:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:35:42 INFO - 2017-01-03 19:35:42.595 screenresolution[1927:20948] starting screenresolution argv=screenresolution get 19:35:42 INFO - 2017-01-03 19:35:42.598 screenresolution[1927:20948] Display 0: 1600x1200x32@60 19:35:42 INFO - 2017-01-03 19:35:42.604 screenresolution[1928:20951] starting screenresolution argv=screenresolution list 19:35:42 INFO - Available Modes on Display 0 19:35:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:35:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:35:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:35:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:35:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:35:42 INFO - Intel Iris: 19:35:42 INFO - Chipset Model: Intel Iris 19:35:42 INFO - Type: GPU 19:35:42 INFO - Bus: Built-In 19:35:42 INFO - VRAM (Dynamic, Max): 1536 MB 19:35:42 INFO - Vendor: Intel (0x8086) 19:35:42 INFO - Device ID: 0x0a2e 19:35:42 INFO - Revision ID: 0x0009 19:35:42 INFO - Displays: 19:35:42 INFO - Display: 19:35:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:35:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:35:42 INFO - Display Serial Number: 1600x1200 60 19:35:42 INFO - Main Display: Yes 19:35:42 INFO - Mirror: Off 19:35:42 INFO - Online: Yes 19:35:42 INFO - Rotation: Supported 19:35:42 INFO - Return code: 0 19:35:42 INFO - Running post-action listener: _resource_record_post_action 19:35:42 INFO - [mozharness: 2017-01-04 03:35:42.844891Z] Finished run-tests step (success) 19:35:42 INFO - Running post-run listener: _resource_record_post_run 19:35:42 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 204800; Write bytes: 552402432; Read time: 6; Write time: 1740 19:35:42 INFO - TinderboxPrint: CPU usage
53.9% 19:35:42 INFO - TinderboxPrint: I/O read bytes / time
204,800 / 6 19:35:42 INFO - TinderboxPrint: I/O write bytes / time
552,402,432 / 1,740 19:35:42 INFO - TinderboxPrint: CPU idle
2,222.5 (46.1%) 19:35:42 INFO - TinderboxPrint: CPU system
969.3 (20.1%) 19:35:42 INFO - TinderboxPrint: CPU user
1,625.9 (33.7%) 19:35:42 INFO - TinderboxPrint: Swap in / out
249,622,528 / 0 19:35:42 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 250227200; Write bytes: 252721152; Read time: 19242; Write time: 512 19:35:42 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 147456; Write bytes: 298014208; Read time: 6; Write time: 1220 19:35:43 INFO - Running post-run listener: _upload_blobber_files 19:35:43 INFO - Blob upload gear active. 19:35:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:35:43 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 19:35:43 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'] 19:35:43 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 19:35:43 INFO - (blobuploader) - INFO - Open directory for files ... 19:35:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:35:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:35:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:45 INFO - (blobuploader) - INFO - Done attempting. 19:35:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:35:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:35:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:45 INFO - (blobuploader) - INFO - Done attempting. 19:35:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:35:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:35:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:47 INFO - (blobuploader) - INFO - Done attempting. 19:35:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:35:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:35:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:49 INFO - (blobuploader) - INFO - Done attempting. 19:35:49 INFO - (blobuploader) - INFO - Iteration through files over. 19:35:49 INFO - Return code: 0 19:35:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:35:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:35:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52de18baef585b5754b186f4da3db3ffda556d4b421feae14846eabe4dc456e4077ac1be4d4ad1b94655ce7c1b10960949027236b9c431e344f445ed171e7a1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/535b9b907e2f322ee4709369a18603e0901823b2ab44381d5245bc344c61bfbbaff2808c256c93a81e44167f8dea4ec6b78f71a892b4dc47bd057448480cf1f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d60885c772ae079173dc63db8683e30f7bf0951efb5f1a0137038039774ae6cf2825535b6d9b35a95ac175d57d4de1ab5f46c6d2635d631fe2b6989dfc618857", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f093442be00d210f1388388c9b399c5218b056bc7c59e6b8261a34fcb1b0ae9140bb9a23fff88edfffae137f2b31b973ff12c6e78fcebd76405e71f3c5c24c4"} 19:35:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:35:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:35:49 INFO - Contents: 19:35:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52de18baef585b5754b186f4da3db3ffda556d4b421feae14846eabe4dc456e4077ac1be4d4ad1b94655ce7c1b10960949027236b9c431e344f445ed171e7a1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/535b9b907e2f322ee4709369a18603e0901823b2ab44381d5245bc344c61bfbbaff2808c256c93a81e44167f8dea4ec6b78f71a892b4dc47bd057448480cf1f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d60885c772ae079173dc63db8683e30f7bf0951efb5f1a0137038039774ae6cf2825535b6d9b35a95ac175d57d4de1ab5f46c6d2635d631fe2b6989dfc618857", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f093442be00d210f1388388c9b399c5218b056bc7c59e6b8261a34fcb1b0ae9140bb9a23fff88edfffae137f2b31b973ff12c6e78fcebd76405e71f3c5c24c4"} 19:35:49 INFO - Running post-run listener: copy_logs_to_upload_dir 19:35:49 INFO - Copying logs to upload dir... 19:35:49 INFO - mkdir: /builds/slave/test/build/upload/logs 19:35:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.849870 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-03 19:35:49.460636) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 19:35:49.467843) ========= 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.r3BwBUISxp/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.fY34jnhyjo/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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/52de18baef585b5754b186f4da3db3ffda556d4b421feae14846eabe4dc456e4077ac1be4d4ad1b94655ce7c1b10960949027236b9c431e344f445ed171e7a1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/535b9b907e2f322ee4709369a18603e0901823b2ab44381d5245bc344c61bfbbaff2808c256c93a81e44167f8dea4ec6b78f71a892b4dc47bd057448480cf1f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d60885c772ae079173dc63db8683e30f7bf0951efb5f1a0137038039774ae6cf2825535b6d9b35a95ac175d57d4de1ab5f46c6d2635d631fe2b6989dfc618857", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f093442be00d210f1388388c9b399c5218b056bc7c59e6b8261a34fcb1b0ae9140bb9a23fff88edfffae137f2b31b973ff12c6e78fcebd76405e71f3c5c24c4"} build_url:https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010392 build_url: 'https://queue.taskcluster.net/v1/task/K5bpzGVYTTOvtZuNx48iIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52de18baef585b5754b186f4da3db3ffda556d4b421feae14846eabe4dc456e4077ac1be4d4ad1b94655ce7c1b10960949027236b9c431e344f445ed171e7a1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/535b9b907e2f322ee4709369a18603e0901823b2ab44381d5245bc344c61bfbbaff2808c256c93a81e44167f8dea4ec6b78f71a892b4dc47bd057448480cf1f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d60885c772ae079173dc63db8683e30f7bf0951efb5f1a0137038039774ae6cf2825535b6d9b35a95ac175d57d4de1ab5f46c6d2635d631fe2b6989dfc618857", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f093442be00d210f1388388c9b399c5218b056bc7c59e6b8261a34fcb1b0ae9140bb9a23fff88edfffae137f2b31b973ff12c6e78fcebd76405e71f3c5c24c4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 19:35:49.500430) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:35:49.500921) ========= 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.r3BwBUISxp/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.fY34jnhyjo/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.004892 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:35:49.549193) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 19:35:49.549518) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 19:35:49.549833) ========= ========= Total master_lag: 0.20 =========