builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1477601823.01 results: success (0) buildid: 20161027110534 builduid: a02a15a733674a99925532f9793335f7 revision: fccdd9ae8dd76af003b80a8c9e0cd035a1848842 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:03.010953) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:03.011420) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:03.011702) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:03.030953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:03.031338) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-27 13:57:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2016-10-27 13:57:03 (102 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095759 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:03.140885) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:03.141196) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015573 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:03.194373) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-27 13:57:03.194735) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fccdd9ae8dd76af003b80a8c9e0cd035a1848842 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fccdd9ae8dd76af003b80a8c9e0cd035a1848842 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-27 13:57:03,435 truncating revision to first 12 chars 2016-10-27 13:57:03,435 Setting DEBUG logging. 2016-10-27 13:57:03,435 attempt 1/10 2016-10-27 13:57:03,436 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fccdd9ae8dd7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-27 13:57:04,703 unpacking tar archive at: mozilla-aurora-fccdd9ae8dd7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.661228 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-27 13:57:04.872798) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:04.873128) ========= script_repo_revision: fccdd9ae8dd76af003b80a8c9e0cd035a1848842 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:04.873657) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:04.874005) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 13:57:04.885524) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-10-27 13:57:04.885924) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:57:05 INFO - MultiFileLogger online at 20161027 13:57:05 in /builds/slave/test 13:57:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:57:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:57:05 INFO - {'append_to_log': False, 13:57:05 INFO - 'base_work_dir': '/builds/slave/test', 13:57:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:57:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:57:05 INFO - 'branch': 'Mozilla-Aurora', 13:57:05 INFO - 'buildbot_json_path': 'buildprops.json', 13:57:05 INFO - 'config_files': ('talos/linux_config.py',), 13:57:05 INFO - 'default_actions': ('clobber', 13:57:05 INFO - 'read-buildbot-config', 13:57:05 INFO - 'download-and-extract', 13:57:05 INFO - 'populate-webroot', 13:57:05 INFO - 'create-virtualenv', 13:57:05 INFO - 'install', 13:57:05 INFO - 'run-tests'), 13:57:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:57:05 INFO - 'download_minidump_stackwalk': True, 13:57:05 INFO - 'download_symbols': 'ondemand', 13:57:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:57:05 INFO - 'tooltool.py': '/tools/tooltool.py', 13:57:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:57:05 INFO - '/tools/misc-python/virtualenv.py')}, 13:57:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:57:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:57:05 INFO - 'installer_path': 'installer.exe', 13:57:05 INFO - 'log_level': 'info', 13:57:05 INFO - 'log_name': 'talos', 13:57:05 INFO - 'log_to_console': True, 13:57:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:57:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:57:05 INFO - 'opt_config_files': (), 13:57:05 INFO - 'pip_index': False, 13:57:05 INFO - 'sps_profile': False, 13:57:05 INFO - 'sps_profile_interval': 0, 13:57:05 INFO - 'suite': 'dromaeojs', 13:57:05 INFO - 'system_bits': '32', 13:57:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:57:05 INFO - 'title': 'talos-linux64-ix-004', 13:57:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:57:05 INFO - 'use_talos_json': True, 13:57:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:57:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:57:05 INFO - 'work_dir': 'build'} 13:57:05 INFO - [mozharness: 2016-10-27 20:57:05.221064Z] Running clobber step. 13:57:05 INFO - Running pre-action listener: _resource_record_pre_action 13:57:05 INFO - Running main action method: clobber 13:57:05 INFO - rmtree: /builds/slave/test/build 13:57:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:57:05 INFO - Running post-action listener: _resource_record_post_action 13:57:05 INFO - [mozharness: 2016-10-27 20:57:05.690370Z] Finished clobber step (success) 13:57:05 INFO - [mozharness: 2016-10-27 20:57:05.690471Z] Running read-buildbot-config step. 13:57:05 INFO - Running pre-action listener: _resource_record_pre_action 13:57:05 INFO - Running main action method: read_buildbot_config 13:57:05 INFO - Using buildbot properties: 13:57:05 INFO - { 13:57:05 INFO - "project": "", 13:57:05 INFO - "product": "firefox", 13:57:05 INFO - "script_repo_revision": "production", 13:57:05 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 13:57:05 INFO - "repository": "", 13:57:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 13:57:05 INFO - "buildid": "20161027110534", 13:57:05 INFO - "pgo_build": "True", 13:57:05 INFO - "basedir": "/builds/slave/test", 13:57:05 INFO - "buildnumber": 558, 13:57:05 INFO - "slavename": "talos-linux64-ix-004", 13:57:05 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:57:05 INFO - "platform": "ubuntu64_hw", 13:57:05 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:57:05 INFO - "branch": "mozilla-aurora", 13:57:05 INFO - "repo_path": "releases/mozilla-aurora", 13:57:05 INFO - "revision": "fccdd9ae8dd76af003b80a8c9e0cd035a1848842", 13:57:05 INFO - "stage_platform": "linux64-pgo", 13:57:05 INFO - "builduid": "a02a15a733674a99925532f9793335f7", 13:57:05 INFO - "slavebuilddir": "test" 13:57:05 INFO - } 13:57:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 13:57:05 INFO - Running post-action listener: _resource_record_post_action 13:57:05 INFO - [mozharness: 2016-10-27 20:57:05.692908Z] Finished read-buildbot-config step (success) 13:57:05 INFO - [mozharness: 2016-10-27 20:57:05.692998Z] Running download-and-extract step. 13:57:05 INFO - Running pre-action listener: _resource_record_pre_action 13:57:05 INFO - Running main action method: download_and_extract 13:57:05 INFO - mkdir: /builds/slave/test/build/tests 13:57:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:05 INFO - trying https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 13:57:05 INFO - Downloading https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 13:57:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:57:06 INFO - Downloaded 1361 bytes. 13:57:06 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 13:57:06 INFO - Using the following test package requirements: 13:57:06 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 13:57:06 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:57:06 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:57:06 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:57:06 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:57:06 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:57:06 INFO - u'jsshell-linux-x86_64.zip'], 13:57:06 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:57:06 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:57:06 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 13:57:06 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:57:06 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:57:06 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:57:06 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:57:06 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:57:06 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:57:06 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:57:06 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:57:06 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:57:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 13:57:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:57:06 INFO - Fetch https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 13:57:06 INFO - Expected file size: 21893947 13:57:11 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:57:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 13:57:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:57:11 INFO - Fetch https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 13:57:11 INFO - Expected file size: 21893947 13:57:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 13:57:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:57:16 INFO - Fetch https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 13:57:17 INFO - Expected file size: 14414081 13:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:17 INFO - trying https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 13:57:17 INFO - Downloading https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 13:57:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:57:18 INFO - Downloaded 63966205 bytes. 13:57:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 13:57:18 INFO - mkdir: /builds/slave/test/properties 13:57:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:57:18 INFO - Writing to file /builds/slave/test/properties/build_url 13:57:18 INFO - Contents: 13:57:18 INFO - build_url:https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 13:57:19 INFO - Running post-action listener: _resource_record_post_action 13:57:19 INFO - Running post-action listener: set_extra_try_arguments 13:57:19 INFO - [mozharness: 2016-10-27 20:57:19.352161Z] Finished download-and-extract step (success) 13:57:19 INFO - [mozharness: 2016-10-27 20:57:19.352389Z] Running populate-webroot step. 13:57:19 INFO - Running pre-action listener: _resource_record_pre_action 13:57:19 INFO - Running main action method: populate_webroot 13:57:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:57:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:57:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:57:19 INFO - '--tppagecycles', 13:57:19 INFO - '7'], 13:57:19 INFO - 'tests': ['tsvgm']}}, 13:57:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:57:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:57:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:57:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:57:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:57:19 INFO - 'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:57:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:57:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:57:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:57:19 INFO - 'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['damp', 'tps']}, 13:57:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:57:19 INFO - 'tests': ['damp', 'tps']}, 13:57:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['dromaeo_dom']}, 13:57:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:57:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['basic_compositor_video']}, 13:57:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:57:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['a11yr', 13:57:19 INFO - 'ts_paint', 13:57:19 INFO - 'tpaint', 13:57:19 INFO - 'sessionrestore', 13:57:19 INFO - 'sessionrestore_no_auto_restore', 13:57:19 INFO - 'tabpaint']}, 13:57:19 INFO - 'other-e10s': {'tests': ['a11yr', 13:57:19 INFO - 'ts_paint', 13:57:19 INFO - 'tpaint', 13:57:19 INFO - 'sessionrestore', 13:57:19 INFO - 'sessionrestore_no_auto_restore', 13:57:19 INFO - 'tabpaint']}, 13:57:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:57:19 INFO - 'ts_paint', 13:57:19 INFO - 'tpaint', 13:57:19 INFO - 'sessionrestore', 13:57:19 INFO - 'sessionrestore_no_auto_restore', 13:57:19 INFO - 'tabpaint']}, 13:57:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:57:19 INFO - 'ts_paint', 13:57:19 INFO - 'tpaint', 13:57:19 INFO - 'sessionrestore', 13:57:19 INFO - 'sessionrestore_no_auto_restore', 13:57:19 INFO - 'tabpaint']}, 13:57:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['a11yr', 13:57:19 INFO - 'ts_paint', 13:57:19 INFO - 'tpaint', 13:57:19 INFO - 'sessionrestore', 13:57:19 INFO - 'sessionrestore_no_auto_restore', 13:57:19 INFO - 'tabpaint']}, 13:57:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['a11yr', 13:57:19 INFO - 'ts_paint', 13:57:19 INFO - 'tpaint', 13:57:19 INFO - 'sessionrestore', 13:57:19 INFO - 'sessionrestore_no_auto_restore', 13:57:19 INFO - 'tabpaint']}, 13:57:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['tsvgx', 13:57:19 INFO - 'tsvgr_opacity', 13:57:19 INFO - 'tart', 13:57:19 INFO - 'tscrollx', 13:57:19 INFO - 'cart']}, 13:57:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:57:19 INFO - 'tsvgr_opacity', 13:57:19 INFO - 'tart', 13:57:19 INFO - 'tscrollx', 13:57:19 INFO - 'cart']}, 13:57:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:57:19 INFO - 'talos_options': ['--disable-e10s'], 13:57:19 INFO - 'tests': ['tp5o']}, 13:57:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:57:19 INFO - 'tests': ['tp5o']}, 13:57:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:57:19 INFO - 'talos_options': ['--disable-e10s', 13:57:19 INFO - '--xperf_path', 13:57:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:57:19 INFO - 'tests': ['tp5n']}, 13:57:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:57:19 INFO - 'talos_options': ['--xperf_path', 13:57:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:57:19 INFO - 'tests': ['tp5n']}}, 13:57:19 INFO - 'talos.zip': {'path': '', 13:57:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:57:19 INFO - Running post-action listener: _resource_record_post_action 13:57:19 INFO - [mozharness: 2016-10-27 20:57:19.371428Z] Finished populate-webroot step (success) 13:57:19 INFO - [mozharness: 2016-10-27 20:57:19.371517Z] Running create-virtualenv step. 13:57:19 INFO - Running pre-action listener: _resource_record_pre_action 13:57:19 INFO - Running main action method: create_virtualenv 13:57:19 INFO - Creating virtualenv /builds/slave/test/build/venv 13:57:19 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 13:57:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:57:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:57:19 INFO - Using real prefix '/usr' 13:57:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:57:20 INFO - Installing distribute.............................................................................................................................................................................................done. 13:57:22 INFO - Installing pip.................done. 13:57:22 INFO - Return code: 0 13:57:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:57: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')]} 13:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57: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')]} 13:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f560eef8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1792ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:57: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', 'pip>=1.5'] in /builds/slave/test/build 13:57: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 pip>=1.5 13:57:22 INFO - Using env: {'DISPLAY': ':0', 13:57:22 INFO - 'HOME': '/home/cltbld', 13:57:22 INFO - 'LANG': 'en_US.UTF-8', 13:57:22 INFO - 'LANGUAGE': 'en_US:en', 13:57:22 INFO - 'LOGNAME': 'cltbld', 13:57:22 INFO - 'MAIL': '/var/mail/cltbld', 13:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:22 INFO - 'MOZ_NO_REMOTE': '1', 13:57:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:22 INFO - 'NO_EM_RESTART': '1', 13:57:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:22 INFO - 'PWD': '/builds/slave/test', 13:57:22 INFO - 'SHELL': '/bin/bash', 13:57:22 INFO - 'SHLVL': '1', 13:57:22 INFO - 'TERM': 'linux', 13:57:22 INFO - 'TMOUT': '86400', 13:57:22 INFO - 'USER': 'cltbld', 13:57:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:22 INFO - '_': '/tools/buildbot/bin/python'} 13:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:22 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 13:57:22 INFO - Cleaning up... 13:57:22 INFO - Return code: 0 13:57:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:57: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')]} 13:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57: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')]} 13:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57: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', '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 0x7f560eef8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1792ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:57: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', 'psutil>=3.1.1'] in /builds/slave/test/build 13:57: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 psutil>=3.1.1 13:57:22 INFO - Using env: {'DISPLAY': ':0', 13:57:22 INFO - 'HOME': '/home/cltbld', 13:57:22 INFO - 'LANG': 'en_US.UTF-8', 13:57:22 INFO - 'LANGUAGE': 'en_US:en', 13:57:22 INFO - 'LOGNAME': 'cltbld', 13:57:22 INFO - 'MAIL': '/var/mail/cltbld', 13:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:22 INFO - 'MOZ_NO_REMOTE': '1', 13:57:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:22 INFO - 'NO_EM_RESTART': '1', 13:57:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:22 INFO - 'PWD': '/builds/slave/test', 13:57:22 INFO - 'SHELL': '/bin/bash', 13:57:22 INFO - 'SHLVL': '1', 13:57:22 INFO - 'TERM': 'linux', 13:57:22 INFO - 'TMOUT': '86400', 13:57:22 INFO - 'USER': 'cltbld', 13:57:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:22 INFO - '_': '/tools/buildbot/bin/python'} 13:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:22 INFO - Downloading/unpacking psutil>=3.1.1 13:57: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 13:57: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 13:57: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 13:57: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 13:57: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 13:57: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 13:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:57:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:57:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:57:24 INFO - Installing collected packages: psutil 13:57:24 INFO - Running setup.py install for psutil 13:57:24 INFO - building 'psutil._psutil_linux' extension 13:57:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 13:57:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 13:57:24 INFO - building 'psutil._psutil_posix' extension 13:57:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 13:57:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 13:57:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:57:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:57:25 INFO - Successfully installed psutil 13:57:25 INFO - Cleaning up... 13:57:25 INFO - Return code: 0 13:57:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:57:25 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')]} 13:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:25 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')]} 13:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:25 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 0x7f560eef8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1792ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:57:25 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 13:57:25 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 13:57:25 INFO - Using env: {'DISPLAY': ':0', 13:57:25 INFO - 'HOME': '/home/cltbld', 13:57:25 INFO - 'LANG': 'en_US.UTF-8', 13:57:25 INFO - 'LANGUAGE': 'en_US:en', 13:57:25 INFO - 'LOGNAME': 'cltbld', 13:57:25 INFO - 'MAIL': '/var/mail/cltbld', 13:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:25 INFO - 'MOZ_NO_REMOTE': '1', 13:57:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:25 INFO - 'NO_EM_RESTART': '1', 13:57:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:25 INFO - 'PWD': '/builds/slave/test', 13:57:25 INFO - 'SHELL': '/bin/bash', 13:57:25 INFO - 'SHLVL': '1', 13:57:25 INFO - 'TERM': 'linux', 13:57:25 INFO - 'TMOUT': '86400', 13:57:25 INFO - 'USER': 'cltbld', 13:57:25 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:25 INFO - '_': '/tools/buildbot/bin/python'} 13:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:57: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 13:57: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 13:57: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 13:57: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 13:57: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 13:57: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 13:57:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:57:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:57:27 INFO - Installing collected packages: mozsystemmonitor 13:57:27 INFO - Running setup.py install for mozsystemmonitor 13:57:27 INFO - Successfully installed mozsystemmonitor 13:57:27 INFO - Cleaning up... 13:57:27 INFO - Return code: 0 13:57:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:57: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')]} 13:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57: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')]} 13:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f560eef8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1792ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:57:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:57:27 INFO - Using env: {'DISPLAY': ':0', 13:57:27 INFO - 'HOME': '/home/cltbld', 13:57:27 INFO - 'LANG': 'en_US.UTF-8', 13:57:27 INFO - 'LANGUAGE': 'en_US:en', 13:57:27 INFO - 'LOGNAME': 'cltbld', 13:57:27 INFO - 'MAIL': '/var/mail/cltbld', 13:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:27 INFO - 'MOZ_NO_REMOTE': '1', 13:57:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:27 INFO - 'NO_EM_RESTART': '1', 13:57:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:27 INFO - 'PWD': '/builds/slave/test', 13:57:27 INFO - 'SHELL': '/bin/bash', 13:57:27 INFO - 'SHLVL': '1', 13:57:27 INFO - 'TERM': 'linux', 13:57:27 INFO - 'TMOUT': '86400', 13:57:27 INFO - 'USER': 'cltbld', 13:57:27 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:27 INFO - '_': '/tools/buildbot/bin/python'} 13:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:27 INFO - Downloading/unpacking jsonschema==2.5.1 13:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:57:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:29 INFO - Downloading functools32-3.2.3-2.tar.gz 13:57:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:57:29 INFO - warning: no files found matching '*.txt' 13:57:29 INFO - no previously-included directories found matching 'build' 13:57:29 INFO - no previously-included directories found matching 'dist' 13:57:29 INFO - no previously-included directories found matching '.git*' 13:57:29 INFO - Installing collected packages: jsonschema, functools32 13:57:29 INFO - Running setup.py install for functools32 13:57:29 INFO - warning: no files found matching '*.txt' 13:57:29 INFO - no previously-included directories found matching 'build' 13:57:29 INFO - no previously-included directories found matching 'dist' 13:57:29 INFO - no previously-included directories found matching '.git*' 13:57:29 INFO - Successfully installed jsonschema functools32 13:57:29 INFO - Cleaning up... 13:57:30 INFO - Return code: 0 13:57:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:57:30 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')]} 13:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:30 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')]} 13:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:30 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 0x7f560eef8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1792ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:57:30 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 13:57:30 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 13:57:30 INFO - Using env: {'DISPLAY': ':0', 13:57:30 INFO - 'HOME': '/home/cltbld', 13:57:30 INFO - 'LANG': 'en_US.UTF-8', 13:57:30 INFO - 'LANGUAGE': 'en_US:en', 13:57:30 INFO - 'LOGNAME': 'cltbld', 13:57:30 INFO - 'MAIL': '/var/mail/cltbld', 13:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:30 INFO - 'MOZ_NO_REMOTE': '1', 13:57:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:30 INFO - 'NO_EM_RESTART': '1', 13:57:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:30 INFO - 'PWD': '/builds/slave/test', 13:57:30 INFO - 'SHELL': '/bin/bash', 13:57:30 INFO - 'SHLVL': '1', 13:57:30 INFO - 'TERM': 'linux', 13:57:30 INFO - 'TMOUT': '86400', 13:57:30 INFO - 'USER': 'cltbld', 13:57:30 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:30 INFO - '_': '/tools/buildbot/bin/python'} 13:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:57:30 INFO - Cleaning up... 13:57:30 INFO - Return code: 0 13:57:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:57:30 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')]} 13:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:30 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')]} 13:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:30 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 0x7f560eef8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1792ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:57:30 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 13:57:30 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 13:57:30 INFO - Using env: {'DISPLAY': ':0', 13:57:30 INFO - 'HOME': '/home/cltbld', 13:57:30 INFO - 'LANG': 'en_US.UTF-8', 13:57:30 INFO - 'LANGUAGE': 'en_US:en', 13:57:30 INFO - 'LOGNAME': 'cltbld', 13:57:30 INFO - 'MAIL': '/var/mail/cltbld', 13:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:30 INFO - 'MOZ_NO_REMOTE': '1', 13:57:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:30 INFO - 'NO_EM_RESTART': '1', 13:57:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:30 INFO - 'PWD': '/builds/slave/test', 13:57:30 INFO - 'SHELL': '/bin/bash', 13:57:30 INFO - 'SHLVL': '1', 13:57:30 INFO - 'TERM': 'linux', 13:57:30 INFO - 'TMOUT': '86400', 13:57:30 INFO - 'USER': 'cltbld', 13:57:30 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:30 INFO - '_': '/tools/buildbot/bin/python'} 13:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:30 INFO - Downloading/unpacking blobuploader==1.2.4 13:57:30 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 13:57:30 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 13:57:30 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 13:57:30 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 13:57:30 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 13:57:30 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 13:57:32 INFO - Downloading blobuploader-1.2.4.tar.gz 13:57:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:57:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:57:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:33 INFO - Downloading docopt-0.6.1.tar.gz 13:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:57:33 INFO - Installing collected packages: blobuploader, requests, docopt 13:57:33 INFO - Running setup.py install for blobuploader 13:57:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:57:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:57:33 INFO - Running setup.py install for requests 13:57:33 INFO - Running setup.py install for docopt 13:57:33 INFO - Successfully installed blobuploader requests docopt 13:57:33 INFO - Cleaning up... 13:57:33 INFO - Return code: 0 13:57:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:57:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f560eef8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1792ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:57:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:57:33 INFO - Using env: {'DISPLAY': ':0', 13:57:33 INFO - 'HOME': '/home/cltbld', 13:57:33 INFO - 'LANG': 'en_US.UTF-8', 13:57:33 INFO - 'LANGUAGE': 'en_US:en', 13:57:33 INFO - 'LOGNAME': 'cltbld', 13:57:33 INFO - 'MAIL': '/var/mail/cltbld', 13:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:33 INFO - 'MOZ_NO_REMOTE': '1', 13:57:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:33 INFO - 'NO_EM_RESTART': '1', 13:57:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:33 INFO - 'PWD': '/builds/slave/test', 13:57:33 INFO - 'SHELL': '/bin/bash', 13:57:33 INFO - 'SHLVL': '1', 13:57:33 INFO - 'TERM': 'linux', 13:57:33 INFO - 'TMOUT': '86400', 13:57:33 INFO - 'USER': 'cltbld', 13:57:33 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:33 INFO - '_': '/tools/buildbot/bin/python'} 13:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:57:33 INFO - Running setup.py (path:/tmp/pip-XQ1HEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:57:34 INFO - Running setup.py (path:/tmp/pip-JndySt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:57:34 INFO - Running setup.py (path:/tmp/pip-RPl7y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:57:34 INFO - Running setup.py (path:/tmp/pip-ooK5ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:57:34 INFO - Running setup.py (path:/tmp/pip-bW180R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:57:34 INFO - Running setup.py (path:/tmp/pip-9BN606-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:57:34 INFO - Running setup.py (path:/tmp/pip-XDvTeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:57:34 INFO - Running setup.py (path:/tmp/pip-WPDKIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:57:34 INFO - Running setup.py (path:/tmp/pip-iqHTYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:57:34 INFO - Running setup.py (path:/tmp/pip-8NyBBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:57:34 INFO - Running setup.py (path:/tmp/pip-hGyxwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:57:34 INFO - Running setup.py (path:/tmp/pip-VKQqrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:57:34 INFO - Running setup.py (path:/tmp/pip-ifF3Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:57:34 INFO - Running setup.py (path:/tmp/pip-_yofkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:57:35 INFO - Running setup.py (path:/tmp/pip-uqggNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:57:35 INFO - Running setup.py (path:/tmp/pip-MSIlQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:57:35 INFO - Running setup.py (path:/tmp/pip-6wrdjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:57:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:57:35 INFO - Running setup.py install for manifestparser 13:57:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:57:35 INFO - Running setup.py install for mozcrash 13:57:35 INFO - Running setup.py install for mozdebug 13:57:35 INFO - Running setup.py install for mozdevice 13:57:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:57:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:57:35 INFO - Running setup.py install for mozfile 13:57:35 INFO - Running setup.py install for mozhttpd 13:57:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:57:35 INFO - Running setup.py install for mozinfo 13:57:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:57:35 INFO - Running setup.py install for mozInstall 13:57:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:57:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:57:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:57:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:57:36 INFO - Running setup.py install for mozleak 13:57:36 INFO - Running setup.py install for mozlog 13:57:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:57:36 INFO - Running setup.py install for moznetwork 13:57:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:57:36 INFO - Running setup.py install for mozprocess 13:57:36 INFO - Running setup.py install for mozprofile 13:57:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:57:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:57:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:57:36 INFO - Running setup.py install for mozrunner 13:57:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:57:36 INFO - Running setup.py install for mozscreenshot 13:57:36 INFO - Running setup.py install for moztest 13:57:36 INFO - Running setup.py install for mozversion 13:57:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:57:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:57:37 INFO - Cleaning up... 13:57:37 INFO - Return code: 0 13:57:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:57: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')]} 13:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57: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')]} 13:57:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:37 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 0x7f560eef8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1792ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:57:37 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 13:57:37 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 13:57:37 INFO - Using env: {'DISPLAY': ':0', 13:57:37 INFO - 'HOME': '/home/cltbld', 13:57:37 INFO - 'LANG': 'en_US.UTF-8', 13:57:37 INFO - 'LANGUAGE': 'en_US:en', 13:57:37 INFO - 'LOGNAME': 'cltbld', 13:57:37 INFO - 'MAIL': '/var/mail/cltbld', 13:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:37 INFO - 'MOZ_NO_REMOTE': '1', 13:57:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:37 INFO - 'NO_EM_RESTART': '1', 13:57:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:37 INFO - 'PWD': '/builds/slave/test', 13:57:37 INFO - 'SHELL': '/bin/bash', 13:57:37 INFO - 'SHLVL': '1', 13:57:37 INFO - 'TERM': 'linux', 13:57:37 INFO - 'TMOUT': '86400', 13:57:37 INFO - 'USER': 'cltbld', 13:57:37 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:37 INFO - '_': '/tools/buildbot/bin/python'} 13:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:57:37 INFO - Running setup.py (path:/tmp/pip-_jTmfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:57:37 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)) 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:57:37 INFO - Running setup.py (path:/tmp/pip-guPkT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:57:37 INFO - Running setup.py (path:/tmp/pip-GhQ6t6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:57:37 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)) 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:57:37 INFO - Running setup.py (path:/tmp/pip-Cb2ilD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:57:37 INFO - Running setup.py (path:/tmp/pip-OCTGIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:57:37 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)) 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:57:37 INFO - Running setup.py (path:/tmp/pip-Mcy7Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:57:37 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)) 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:57:37 INFO - Running setup.py (path:/tmp/pip-sBs00Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:57:37 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)) 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:57:37 INFO - Running setup.py (path:/tmp/pip-1oRoJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:57:37 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)) 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:57:37 INFO - Running setup.py (path:/tmp/pip-x4Uhkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:57:37 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)) 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:57:37 INFO - Running setup.py (path:/tmp/pip-4qKZQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:57:37 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)) 13:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:57:37 INFO - Running setup.py (path:/tmp/pip-0uuzAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:57:38 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)) 13:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:57:38 INFO - Running setup.py (path:/tmp/pip-OhtoC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:57:38 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)) 13:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:57:38 INFO - Running setup.py (path:/tmp/pip-y4iHw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:57:38 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)) 13:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:57:38 INFO - Running setup.py (path:/tmp/pip-6isMQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:57:38 INFO - Running setup.py (path:/tmp/pip-zsOEQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:57:38 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)) 13:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:57:38 INFO - Running setup.py (path:/tmp/pip-q5iLqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:57:38 INFO - Running setup.py (path:/tmp/pip-a9BNdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:57:38 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)) 13:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:57:38 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)) 13:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:57:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:40 INFO - Downloading blessings-1.6.tar.gz 13:57:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:57:40 INFO - Installing collected packages: blessings 13:57:40 INFO - Running setup.py install for blessings 13:57:40 INFO - Successfully installed blessings 13:57:40 INFO - Cleaning up... 13:57:40 INFO - Return code: 0 13:57:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:57:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:57:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:57:40 INFO - Reading from file tmpfile_stdout 13:57:40 INFO - Current package versions: 13:57:40 INFO - argparse == 1.2.1 13:57:40 INFO - blessings == 1.6 13:57:40 INFO - blobuploader == 1.2.4 13:57:40 INFO - docopt == 0.6.1 13:57:40 INFO - functools32 == 3.2.3-2 13:57:40 INFO - jsonschema == 2.5.1 13:57:40 INFO - manifestparser == 1.1 13:57:40 INFO - mozInstall == 1.12 13:57:40 INFO - mozcrash == 0.17 13:57:40 INFO - mozdebug == 0.1 13:57:40 INFO - mozdevice == 0.48 13:57:40 INFO - mozfile == 1.2 13:57:40 INFO - mozhttpd == 0.7 13:57:40 INFO - mozinfo == 0.9 13:57:40 INFO - mozleak == 0.1 13:57:40 INFO - mozlog == 3.4 13:57:40 INFO - moznetwork == 0.27 13:57:40 INFO - mozprocess == 0.23 13:57:40 INFO - mozprofile == 0.28 13:57:40 INFO - mozrunner == 6.12 13:57:40 INFO - mozscreenshot == 0.1 13:57:40 INFO - mozsystemmonitor == 0.3 13:57:40 INFO - moztest == 0.7 13:57:40 INFO - mozversion == 1.4 13:57:40 INFO - psutil == 3.1.1 13:57:40 INFO - requests == 1.2.3 13:57:40 INFO - wsgiref == 0.1.2 13:57:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:57:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:40 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 0x7f560eef8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1792ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:57:40 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 13:57:40 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 13:57:40 INFO - Using env: {'DISPLAY': ':0', 13:57:40 INFO - 'HOME': '/home/cltbld', 13:57:40 INFO - 'LANG': 'en_US.UTF-8', 13:57:40 INFO - 'LANGUAGE': 'en_US:en', 13:57:40 INFO - 'LOGNAME': 'cltbld', 13:57:40 INFO - 'MAIL': '/var/mail/cltbld', 13:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:40 INFO - 'MOZ_NO_REMOTE': '1', 13:57:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:40 INFO - 'NO_EM_RESTART': '1', 13:57:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:40 INFO - 'PWD': '/builds/slave/test', 13:57:40 INFO - 'SHELL': '/bin/bash', 13:57:40 INFO - 'SHLVL': '1', 13:57:40 INFO - 'TERM': 'linux', 13:57:40 INFO - 'TMOUT': '86400', 13:57:40 INFO - 'USER': 'cltbld', 13:57:40 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:40 INFO - '_': '/tools/buildbot/bin/python'} 13:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:41 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)) 13:57:41 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)) 13:57:41 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)) 13:57:41 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)) 13:57:41 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)) 13:57:41 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)) 13:57:41 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)) 13:57:41 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)) 13:57:41 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)) 13:57:41 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)) 13:57:41 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)) 13:57:41 INFO - Cleaning up... 13:57:41 INFO - Return code: 0 13:57:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:57: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')]} 13:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57: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')]} 13:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f560eef8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1792ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:57:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:57:41 INFO - Using env: {'DISPLAY': ':0', 13:57:41 INFO - 'HOME': '/home/cltbld', 13:57:41 INFO - 'LANG': 'en_US.UTF-8', 13:57:41 INFO - 'LANGUAGE': 'en_US:en', 13:57:41 INFO - 'LOGNAME': 'cltbld', 13:57:41 INFO - 'MAIL': '/var/mail/cltbld', 13:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:41 INFO - 'MOZ_NO_REMOTE': '1', 13:57:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:41 INFO - 'NO_EM_RESTART': '1', 13:57:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:41 INFO - 'PWD': '/builds/slave/test', 13:57:41 INFO - 'SHELL': '/bin/bash', 13:57:41 INFO - 'SHLVL': '1', 13:57:41 INFO - 'TERM': 'linux', 13:57:41 INFO - 'TMOUT': '86400', 13:57:41 INFO - 'USER': 'cltbld', 13:57:41 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:41 INFO - '_': '/tools/buildbot/bin/python'} 13:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:57:41 INFO - Cleaning up... 13:57:41 INFO - Return code: 0 13:57:41 INFO - Running post-action listener: _resource_record_post_action 13:57:41 INFO - Running post-action listener: _start_resource_monitoring 13:57:41 INFO - Starting resource monitoring. 13:57:41 INFO - [mozharness: 2016-10-27 20:57:41.336811Z] Finished create-virtualenv step (success) 13:57:41 INFO - [mozharness: 2016-10-27 20:57:41.337329Z] Running install step. 13:57:41 INFO - Running pre-action listener: _resource_record_pre_action 13:57:41 INFO - Running main action method: install 13:57:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:57:41 INFO - Reading from file tmpfile_stdout 13:57:41 INFO - Detecting whether we're running mozinstall >=1.0... 13:57:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:57:41 INFO - Reading from file tmpfile_stdout 13:57:41 INFO - Output received: 13:57:41 INFO - Usage: mozinstall [options] installer 13:57:41 INFO - Options: 13:57:41 INFO - -h, --help show this help message and exit 13:57:41 INFO - -d DEST, --destination=DEST 13:57:41 INFO - Directory to install application into. [default: 13:57:41 INFO - "/builds/slave/test"] 13:57:41 INFO - --app=APP Application being installed. [default: firefox] 13:57:41 INFO - mkdir: /builds/slave/test/build/application 13:57:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 13:57:57 INFO - Reading from file tmpfile_stdout 13:57:57 INFO - Output received: 13:57:57 INFO - /builds/slave/test/build/application/firefox/firefox 13:57:57 INFO - Running post-action listener: _resource_record_post_action 13:57:57 INFO - [mozharness: 2016-10-27 20:57:57.590876Z] Finished install step (success) 13:57:57 INFO - [mozharness: 2016-10-27 20:57:57.591210Z] Running run-tests step. 13:57:57 INFO - Running pre-action listener: _resource_record_pre_action 13:57:57 INFO - Running main action method: run_tests 13:57:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:57:57 INFO - Python 2.7.3 13:57:57 INFO - Return code: 0 13:57:57 INFO - grabbing minidump binary from tooltool 13:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:57 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/linux64/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 0x178ff60>, '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 0x1790d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17911d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:57:57 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:57:57 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/linux64/releng.manifest -o -c /builds/tooltool_cache 13:57:57 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:57:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:57:57 INFO - Return code: 0 13:57:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:57:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:57:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:57:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:57:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:57:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 13:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 13:57:57 INFO - Using env: {'DISPLAY': ':0', 13:57:57 INFO - 'HOME': '/home/cltbld', 13:57:57 INFO - 'LANG': 'en_US.UTF-8', 13:57:57 INFO - 'LANGUAGE': 'en_US:en', 13:57:57 INFO - 'LOGNAME': 'cltbld', 13:57:57 INFO - 'MAIL': '/var/mail/cltbld', 13:57:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:57:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:57 INFO - 'MOZ_NO_REMOTE': '1', 13:57:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:57:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:57 INFO - 'NO_EM_RESTART': '1', 13:57:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:57 INFO - 'PWD': '/builds/slave/test', 13:57:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:57:57 INFO - 'SHELL': '/bin/bash', 13:57:57 INFO - 'SHLVL': '1', 13:57:57 INFO - 'TERM': 'linux', 13:57:57 INFO - 'TMOUT': '86400', 13:57:57 INFO - 'USER': 'cltbld', 13:57:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638', 13:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:57 INFO - '_': '/tools/buildbot/bin/python'} 13:57:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 13:57:57 INFO - mozversion application_buildid: 20161027110534 13:57:57 INFO - mozversion application_changeset: fccdd9ae8dd76af003b80a8c9e0cd035a1848842 13:57:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:57:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:57:57 INFO - mozversion application_name: Firefox 13:57:57 INFO - mozversion application_remotingname: firefox-dev 13:57:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:57:57 INFO - mozversion application_vendor: Mozilla 13:57:57 INFO - mozversion application_version: 51.0a2 13:57:57 INFO - mozversion platform_buildid: 20161027110534 13:57:57 INFO - mozversion platform_changeset: fccdd9ae8dd76af003b80a8c9e0cd035a1848842 13:57:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:57:57 INFO - mozversion platform_version: 51.0a2 13:57:57 INFO - using testdate: 1477601877 13:57:57 INFO - actual date: 1477601877 13:57:57 INFO - starting webserver on 'localhost:52807' 13:57:57 INFO - SUITE-START | Running 2 tests 13:57:57 INFO - TEST-START | dromaeo_css 13:57:57 INFO - operating with platform_type : linux_ 13:57:57 INFO - Initialising browser for dromaeo_css test... 13:57:57 INFO - TEST-INFO | started process 2574 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGaDq5T/profile http://localhost:52807/getInfo.html) 13:58:01 INFO - PROCESS | 2574 | __metrics Screen width/height:1600/1200 13:58:01 INFO - PROCESS | 2574 | colorDepth:24 13:58:01 INFO - PROCESS | 2574 | Browser inner width/height: 1024/697 13:58:01 INFO - PROCESS | 2574 | __metrics 13:58:02 INFO - PROCESS | 2574 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:58:02 INFO - PROCESS | 2574 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:58:02 INFO - PROCESS | 2574 | console.error: PushService: 13:58:02 INFO - PROCESS | 2574 | stateChangeProcessEnqueue: Error transitioning state 13:58:02 INFO - PROCESS | 2574 | UnknownError 13:58:03 INFO - TEST-INFO | 2574: exit 0 13:58:03 INFO - Browser initialized. 13:58:03 INFO - Running cycle 1/1 for dromaeo_css test... 13:58:03 INFO - TEST-INFO | started process 2668 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGaDq5T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:58:15 INFO - PROCESS | 2668 | RSS: Main: 145145856 13:58:15 INFO - PROCESS | 2668 | 13:59:06 INFO - PROCESS | 2668 | 1477601946517 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] 14:01:15 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:52807/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52807/tests/dromaeo/cssquery-ext.html) 14:01:16 INFO - PROCESS | 2668 | RSS: Main: 240013312 14:01:16 INFO - PROCESS | 2668 | 14:04:18 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:52807/tests/dromaeo/cssquery-ext.html (next: http://localhost:52807/tests/dromaeo/cssquery-jquery.html) 14:04:19 INFO - PROCESS | 2668 | RSS: Main: 237875200 14:04:19 INFO - PROCESS | 2668 | 14:07:21 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:52807/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52807/tests/dromaeo/cssquery-mootools.html) 14:07:22 INFO - PROCESS | 2668 | RSS: Main: 175067136 14:07:22 INFO - PROCESS | 2668 | 14:10:24 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:52807/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52807/tests/dromaeo/cssquery-prototype.html) 14:10:24 INFO - PROCESS | 2668 | RSS: Main: 175620096 14:10:24 INFO - PROCESS | 2668 | 14:13:27 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:52807/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52807/tests/dromaeo/cssquery-yui.html) 14:13:27 INFO - PROCESS | 2668 | RSS: Main: 177545216 14:13:27 INFO - PROCESS | 2668 | 14:16:30 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:52807/tests/dromaeo/cssquery-yui.html (next: http://localhost:52807/tests/dromaeo/cssquery-dojo.html) 14:16:30 INFO - PROCESS | 2668 | RSS: Main: 260763648 14:16:30 INFO - PROCESS | 2668 | 14:16:30 INFO - PROCESS | 2668 | __start_tp_report 14:16:30 INFO - PROCESS | 2668 | _x_x_mozilla_page_load 14:16:30 INFO - PROCESS | 2668 | _x_x_mozilla_page_load_details 14:16:30 INFO - PROCESS | 2668 | |i|pagename|runs| 14:16:30 INFO - PROCESS | 2668 | |0;dojo.html;3128.9687103128967;3175.491921292594;3176.5394017867416;3186.9681303186962;3216.5657636219116;5550.972245138773;5565.471280228372;5614.382417934029;5618.690971996539;5626.521745651614;5553.055980483317;5608.719564021792;5617.775288988446;5659.971700141494;5666.829995100154;5032.5470707636305;5086.059079070365;5101.744912754366;5141.817382002142;5167.767450464738;4962.677425967318;4973.950260497403;5126.231065340192;5181.222816577506;5182.300389447414;4887.486813884544;4906.362172917516;4949.232868905326;4968.70187788732;4994.600431965434;4926.605871530287;4941.530554597308;4998.125328067574;5018.172001619765;5039.118154322979;4780.832670856538;4932.80268789248;4948.331975183341;4983.302337672729;5008.474110218447;5283.049051170788;5323.653962492425;5343.812966546191;5447.727613619343;5457.536109430703;5890.793822216202;5896.145058966469;6005.549583781234;6029.974904266285;6034.24571928509;5840.591158618899;5965;5996.760129594811;6053.364396738367;6056.273247210319;5497.285352904097;5529.3364796224305;5538.944610553883;5567.749451274703;5603.551715862721;6143.354947730513;6145.907811382833;6149.200603921461;6152.261728592597;6153.446189842935;5740.253767010262;5896.469317761422;5909.8227053188475;5916.7337469813965;5918.496927761103;5890.322612899571;5916.5858389913155;5977.701114944322;5982.46157845796;6064.75740970365;5957.672328022;6007.098935159674;6022.759089636453;6024.608400453956;6028.186194863725;5957.046872500379;5958.791442299499;5982.371850955674;6021.789237376671;6029.336772954972;4505.07645932583;4547;4604.194266003436;4637.814487420533;4643.674942754043;4088.550259471456;4110.0752330725345;4116.464859568297;4142.751434913855;4153.418521407006;5741.741621627037;5755.597108202385;5758.74085666146;5763.682997435181;5767.538596912237;5802.796902108406;5834.708264586839;5849.736761845727;6089.117078023662;6089.299730530957;5558.888822223614;5600.971995139998;5604.719764011783;5640.520555752807;5642.210090587321;4953.058918805487;4955.8017679292425;4963.677360971526;4963.975180124076;4989.151844186497;5975.163477113208;6008.8798224036145;6017.338092809699;6031.366706495843;6088.695565221721;5836.678982655994;5954.255718035246;5993.3706960768495;6005.879882402415;6010.038393856962;6119.173911522065;6137.96931015342;6145.600535965148;6151.477124444473;6151.538634602333;5630.211770352072;5832.533397328289;5855.707214639166;5863.442972917566;5864.003119469615;5751.1373294006235;5871.031279838779;5883.970580147072;5906.970465147647;6013.939860601513;5783.826485205451;5800.984827655059;5833.504152147115;5833.562482813721;5839.678817665069;5829.708514574169;5835.533157347487;5842.065269556851;5842.298924129132;5845.035569131046;5671.602987790815;5715.485606295287;5755.625884317506;5809.477147056785;5825.621334613236;5918.141869428995;5933.881322373442;5958.374370691015;5975.641461512323;5991.760329586942;5554.444555544414;5803.7968671098;5807.651540907559;5813.215215945793;5814.622049566765 14:16:30 INFO - PROCESS | 2668 | |1;ext.html;600.345623270635;630.1681780050334;653.5163978655796;658.7200439813079;668.3817468841322;34849.47725784115;38449.23101537968;46451.76774116129;46828;47184.76407617957;44453.110937781224;44567.554324456745;44655.77672111635;44689;44739.10521789562;9339.206167475755;9495.335326527145;9509.476978766199;9510.096540828645;9515.476648784314;8546.529940853281;8737.563121843914;10997.34015959041;11198.328100314007;11198.776024479506;11395.601153959613;11482.48328825205;11498.482568284406;11503.65489035326;11504.482298296554;11237.101031917467;11306.660800176009;11338.319700817978;11346.375949322743;11348.092152627853;11108.555657773679;11175.273607215506;11184.44077796115;11184.608538701106;11196.328220306808;45300;45651;45653.543464565264;45707;45755;3747.850085996557;3785.261873934584;4263.552327005651;4271.444712187395;4421.403110580062;33988.49017264743;34013.14967125817;34073.659263407295;34200.31599368023;34324;4742.644301677354;4779.737114458703;4821.879453013667;4878.536539028786;4880.487548807359;348769;349390;349680;350431;352282.99999999744;62493;62984;62987;62990.37009629845;63038;40378.394324085166;41091;41339;44071.55928440738;66779;19175.712364314546;19424.320148794723;19456.319028833925;19476.123574439185;19477.80522194788;13649.590512284647;13943.233122178377;14789.11265324084;15038.774418383733;15042.849571504365;20218;20771;20803.791962080184;21519.246826361003;21664.783352166276;7225.74709885162;7236.34872861445;7370.77887663371;7425.1832298447125;7675.731349402744;1005.3967619428284;1116.3820840129883;1122.6407549584055;1128.785530749155;1150.1086657840074;34741.652583474344;34759.957201284;35027.82486087604;35145.82427087899;35271.453240569695;1124.3310230413056;1143.4454289669504;1148.4602236948622;1190.4166958190597;1270.8347914771205;18620.16209270586;18654.813451865306;18728;18861;18949;558057;561789;564781;565323;566691;62964.685176573825;63590.36409635845;63602.681986590695;63634;63744.362556373846;1279.7632437999;1355.4781409157545;1416.6812467195005;1423.743726129286;1458.161557104669;32691.836540817145;36192;36250.4562431569;36298.4555231677;36562.45156322711;53250.73374633102;53467;53551.464485356206;53576.464235358704;53822.461775381744;399103;401993;402579;402697;403302;31475.685243148193;32604.184895377806;32779.34441311208;33088.83455582707;33532.32935341328;50982.49017509777;51885.48114518807;52218.738906305225;52548.686282843235;53340;43760.78119609381;44026.559734402246;44557.77721111374;44897;45097.01829289448;43053;44382.3342649854;45433.318500223184;45505.317420239386;45982.77008614936;44109.33835992398;44573.33140002838;44711;44759.55240447554;44759.55240447554;36421.45367819432;38128;38546.421803672405;39317.803410982764;39422.408663870636;29169.436876702573;29202.70797292085;29471.705282946896;29589.2602684926;29646.110616681533 14:16:30 INFO - PROCESS | 2668 | |2;jquery.html;386.9090763670535;428.6634991531647;429.0974457104752;436.2169905020488;436.777243605761;16279.593010174736;19837.504062398428;21046;21182.89408552955;21389.251376201817;19592.90203548984;19721.309754158607;20152.69770953433;20610.38168854933;20775;5753.971230143843;5763.9711801440935;5991.430814072656;5993.910091348612;5994.400559944014;5746.770129194827;5888.381719919411;5898.351181370067;5900.645961242318;5901.940980590203;5819.214406055192;5860.472557469827;5863.442972917566;5865.706714664271;5882.2353094097925;6065.636061836304;6215.347388524185;6232.906506402408;6234.750609975596;6239.594426362295;5830.4461076197695;5920.407959204088;5975.312839023524;5983.521318294526;6063.605865618765;23104.76895231043;23294.65058024131;23658.64512032321;24171.395715107086;24873.751262487323;2398.3524448399007;2533.3919859233833;2625.789936805051;2846.3026558493093;3154.8422578870964;10412.791744165073;10480.895191048068;10704.625338113206;11038.834417483744;11121.110311175087;1956.5695546979641;2039.7450318710162;2078.615456140604;2086.384516567616;2089.686547017944;76719.23280767232;82980.58509707413;86504.56747716284;87662;88107.55946220292;10309.53607087683;10942.890571094347;11333.320000799979;11578.826317605242;11840.585579504848;15085.924570377078;15112.471063512725;15981.60045998836;16029.438969636007;16498.75251871223;8051.677932882619;8056.315213206826;8378.622961966605;8941.821163576693;9122.771930701785;8541.829163416822;8695;8811.867821982676;9020.278377729766;9161.908380916238;4905.773556610848;4906.533879281463;4925.7783399747095;5053.747312634354;5118.667286626357;1969.734085898414;2008.136501304424;2012.5572374077678;2023.1806118522022;2077.1379877350732;951.5955718819613;1001.6293971230546;1012.6810054832852;1023.2581378500528;1090.5310716391868;19750.802491974897;20154.496137596678;20347;20432.080556375;20581.176752930016;1047.711879233217;1060.7083052160563;1061.3737894642197;1082.6156714366443;1102.9779404411868;10775.299605525772;11398.658040258806;11506.76986460266;11613.129015323715;11623.709407264885;473053;474150;476090;483108;485374;92731.53634231785;93599;94236;94539.52730236304;95126.52436737771;1011.2668315471225;1027.5941003303653;1055.8257887448485;1074.5003573338279;1087.0922779479224;17687.38094166698;17760.20079096418;18094.457166285032;18181.636367272844;18395.72406413878;29446;30858.845705771328;40902;45442.31836522521;45795.542044579124;58454.70772646109;58810.70594646999;59205.26357891445;60556;60563;9270.6291748332;9312.813743725223;9341.626334946526;9367.672131475365;9375.953120234355;13258.469661213443;13485.056046076681;13860.376283067084;14056.789148162581;14097.788533172215;11993.520259189534;12253.326067066397;12320.507179712711;12381.690457738629;12499.062570307082;11847.940760296487;12043.156979011384;12255.203411778206;12309.507619695112;12371.319577422968;12304.569340073054;12395.876041239833;12439.564615238423;12606.306653134166;12926.418311175832;7842.568658723825;8148.144444833444;9170.44977301364;9208.953955230181;9239.307051970995;10075.546600403146;12137.150399471951;12255.325957072446;12345.567905123637;12687.873121268669 14:16:30 INFO - PROCESS | 2668 | |3;mootools.html;1362.9454821807128;1491.3438087241614;1520.6350475885788;1668.416054380967;1816.854651627869;8264.504129752218;8939;9288.767780805467;9298.395604285724;9487.620495180185;9303.767405814859;9349;9370;9375.156235938764;9375.76560585984;2523.2304147235086;2549.872506374674;2551.719310875802;2552.3874270175115;2554.6295787110857;2467.3338198686342;2525.734797846218;2528.810339224556;2530.4180038591135;2531.8227724059325;2487.9253622391357;2501.524710305036;2524.911628093017;2531.1773673556077;2543.669322988009;2421.5520128776143;2432.9391765205924;2439.4755127647554;2493.763092506209;2504.198656429943;2463.864487453189;2485.9378515537255;2487.141936032066;2492.513959777844;2500.8999640014376;9079.182873541413;9172.403793753385;9208.953955230181;9284.814303713887;9340.813183736354;5468.972655136698;5599.916001259943;5600.299962504687;5606.103023516259;5607.130894711327;3705;3710.8144592770523;3747.362948298796;3753.2118255166447;3777.9244415111543;1180.356705595444;1195.4500929572407;1211.2732360583668;1226.9877301226963;1233.0998371188994;12281.508739650404;12391.690207744788;12399.690007749788;12416.627501174979;12437.378131093408;4699.9190186256665;4725.756338456908;4733.863872670534;4792.736399497991;4888.120138375056;9945.900540994642;9970.651027214013;9979.650712225182;9982.35114717541;9985.550650220757;5443.5100840923715;5448.9727551362785;5460.044492213847;5470.288862447856;5478.479544443271;4560.977195114003;4714.6464015199;4953.455119936804;5063.696178229318;5094.770735316845;3996.160806230724;4067.8169482373364;4116.403121547359;4171.645410140111;4175.68682348825;1231.4335405713473;1234.4198226833373;1235.8516977962702;1248.5068397982745;1256.9748605027848;659.9637019963852;841.2638940926689;897.7620930453435;899.4962820820361;964.9372790768577;6109.7861574844665;6140.723667434976;6144.41628045335;6155.446009859134;6471.288158302583;938.8497840345512;948.9098535639241;1016.9593216271267;1046.6755305855208;1058.2750815691277;3858.131920317906;3907.6909235406456;4016.156607112539;4047.797610119482;4086.6730661547003;10573.735656608646;11171.776564468664;11240.606578769704;11241.94379028116;11253.718657033476;11045.83431248532;11151.776964460665;11152.163587730953;11157.163212759075;11164.776704465865;8378.748637540884;8844.33667474942;8858.955705221562;8859.06979767115;8859.778505537284;6110.7861224856915;6226;6277.686115694106;6309.873802523832;6340.1440805490665;10535.578576857146;10570.630027949292;10587;10628.202884783517;10664.84002739944;10502.842457363298;10664.89335106639;10777.191710621579;10895.455227238448;10930.125589952944;8386.496810191407;8390.11903750122;8505.36209784281;8507.106753791011;8508;8987.595558199766;8995.775105622412;9083.137101975302;9087.818243634958;9089.136532029446;7607.733729319669;7675.695131827491;7706.961465192639;7746.341560967381;7767.689292428239;7500.324970752658;7569.280918312751;7582.848343032998;7624.237576242332;7688.500247483896;7292.48952573336;7551.886721699069;7637.541747495168;7678.731244406419;7702.691892324245;4761.119192949315;4938.8765280868265;4969.652124351405;4998.500149984972;5117.053345131163;4990.401151861654;5009.8747531310555;5080.796768129233;5099.6685215460875;5176.741162941913 14:16:30 INFO - PROCESS | 2668 | |4;prototype.html;347.3591224191366;349.08365540456305;360.51510718084177;360.9837557309921;362.36405109033643;5525.668459892409;5569.331680198382;5605.943940560594;5616.859578510539;5630.5495560355175;5455.18172274159;5495.972520137394;5515.61390702651;5597.397210297211;5607.7476513556885;4209.5790420957965;4350.673699472536;4365.672574556905;4378.255696531582;4405.691601587891;4320.287152619814;4325.653947684178;4331.177076355482;4334.45819272591;4352.216601011816;4113.239050775601;4319.611234988851;4327.5239723630375;4331.501877284106;4334.349847522865;4202.600752928467;4246.2356775780345;4301.892452688677;4336.544662810423;4383.671224658164;3895.9546238224875;3897.5322961244547;3898.590647981978;3898.7660740355386;3912.56961734209;5019.899602007939;5370.43610420904;5370.516653501165;5383.461653834585;5403.756830942617;4664.673472856902;4947.406311242674;4957.9256311155;4974.054929563371;4989.700617962933;5723.198752174699;5735.741891614887;5736.655800651933;5739.770409183628;5742.913856292159;3926.9214615707524;4126.731762435462;4288.678349123797;4291.077418355068;4292.613664770186;5945.375735547792;5963.972249435169;5972.731227094791;5979.461848433619;6009.034037535206;4283.800535849967;4304.225239456928;4327.285997810389;4345.543717909637;4347.826086956549;4111.588841115924;4287;4402.207602631493;4410.5368936261875;4414.867553973386;4859.975700121548;4883.829065982744;4902.4362198346935;4940.555350018516;4997.575206107522;5776.653400795882;5786.797462088806;5795.217645617788;5863.736131874076;5900.852478688067;1433.6559225785736;1471.771875359321;1490.7763835424555;1504.2403586188905;1522.72590933633;932.911373419524;952.8856537215579;966.6423423333412;968.0513097164677;969.2149359019216;4965.82619608312;4972.3591214899;4978.278149668277;4988.72787439208;5008.273800298936;5191.922121168185;5195.76619052136;5199.55620858196;5213.947860521422;5216.321878155815;4919.2129259318335;4935.050654991258;4959.305697202394;5069.315642388304;5355.598330125178;3067.7699172562147;3094;3107.90676279712;3150.558921750957;3154.463741164008;6037.426444487766;6062.7271772769445;6067.969660151671;6093.177421048191;6106.664133472702;5748.798792042258;6067.393260673897;6070.544709146831;6090.3605121461605;6109.053096770008;6039.365866584033;6085.452309292096;6092.482139018144;6108.6029408089225;6156.445919867055;5655.4627310407095;5917.556183286357;5937.435943585352;5965.313988891159;6059.484943779652;5908.940910590839;5909.592722658719;5912.46787789101;5913.645181289136;5920.37189772816;5837.591368604157;6140.3245642978345;6145.9692701536205;6209.937900620936;6219.906701399392;4235.661147108162;4274.27337352657;4292.7424354538825;4342.609165175149;4354.847580334673;6038.6678732670125;6054.66699331541;6064.4541991221;6076.118962750465;6076.453119219114;5617.382087970238;5818.214541036906;5843.181954526454;5903.321118071477;5908.970455147697;5743.798967036134;5774;5783.306003279633;5783.334916484489;5789.33422656383;5781.82654520365;5788.971055144866;5814.215080964016;5857.209276747755;5865.4134586541;5619.297587801525;5814.883702325845;5833.854153646193;5849;5853.180554722428;5633.492985631149;5644.943550564606;5686.943130568641;5701.059325211294;5724.484796368347 14:16:30 INFO - PROCESS | 2668 | |5;yui.html;817.2807929022456;817.6883621333598;854.141587704357;857.5840717252133;901.7024381953955;16700;17825.910870445663;18001.459956201303;18056.819431805696;18071.186796594153;16241.83758162418;16249.51251462455;16439.753403698953;16468.67062658746;16950.576235594115;860.7675927499587;882.2412725056446;885.6590212768066;885.7254251182122;885.9158379953894;729.5659082845698;744.5309455043315;853.1809462915578;867.2454964181171;879.0637970561364;922.0548937339214;922.4695799915042;923.1184219070772;924.1267002682467;924.6948506992676;911.1071150272737;917.6696389299847;920.3327587499043;920.9953950230273;921.1571412157867;560.7056295444884;573.4809996952764;654.7741029344868;664.9667516624198;678.5250324772679;16245.431409900715;16331.18344082803;16371.263293151718;16381.344746210138;16382.344706211738;14779.92610036943;15221.391144354213;15377.923110384489;15528.689426211413;16148.515544533682;14265.95416504966;14371.13773173613;14467.348969296407;14517.637059073502;14544.418223271057;4908.067467181224;4934.728589927553;4972.453030166679;5005.524475174852;5075.5178258065425;59957.40042599562;60382.39617603811;64139.67930160366;87373.5631321852;88675.55662221648;11492.655220343404;12471.75056498865;12666.80999785004;12730.23618582888;12853.93573032129;21991.560168796535;22283.220087296868;22294.88852555759;22447.551048979254;22453.43866403353;8809.42738721994;8844.646214151491;8931.285497160212;8960.955195223982;8962.86555701665;4549.226631472658;4753.762311884461;4866.0511200316305;4915.0415668944925;4928.334674818853;3722.236941427001;3834.559025712023;3834.884953451401;3838.05967537955;3843.3082045231567;635.7806556737918;648.516854943061;650.5673726971605;662.192125606759;666.6733300682631;628.7045088808253;666.2970566052861;672.2135101930753;708.7944496096178;714.6391072508454;9006.234470069987;9729.367591106555;9846.95076524627;10033.347832391017;10381;650.4861159683796;655.1188651264042;663.8473151175257;668.6456178225549;728.508256926583;4643.117807616542;4759.357486739246;4778.808847646055;4819.638527110481;4821.879453013633;72986;74237.6288118556;99953;130319;134358;107030;112899;113817.43091284657;114061.429692851;115828.84171158349;94111;94241;95824.52087739516;95913.52043239879;98506.50746746364;8915.675897189987;9364.812703746024;9437.858432123661;9439.292053095907;9528.904710952802;55626;56390;56481;56606.43393566012;56984;46538.30192547047;53983;79528;80389.59805200937;80962.59518702369;12587.181833180814;12592.244465332109;12627.81058284108;13096.607101786962;13174.868251317748;13623.318834058457;13733.244671543162;13749.243791591558;14005.579832605039;14191.361388737738;12385.93807030959;12453.688657783629;12651.93674031624;12863.935680321538;13368.732625347633;12200.81698774537;12363.381830908598;12794;12910.870891291343;13030.869691302965;11267.492962816857;11451.083913287084;11538.192326537397;12872.479081475336;12920.22478651284;10094.54574544162;10254.436006019741;10358.430286334324;10497.422641754776;10567.52446139941;9780.80438391214;10410.219233557362;10632.574697012033;10797.730056748644;11010.559577616805 14:16:30 INFO - PROCESS | 2668 | __end_tp_report 14:16:30 INFO - PROCESS | 2668 | __start_cc_report 14:16:30 INFO - PROCESS | 2668 | _x_x_mozilla_cycle_collect,922 14:16:30 INFO - PROCESS | 2668 | __end_cc_report 14:16:30 INFO - PROCESS | 2668 | __startTimestamp1477602990679__endTimestamp 14:16:30 INFO - PROCESS | 2668 | 14:16:30 INFO - PROCESS | 2668 | ------- Summary: start ------- 14:16:30 INFO - PROCESS | 2668 | Number of tests: 6 14:16:30 INFO - PROCESS | 2668 | 14:16:30 INFO - PROCESS | 2668 | [#0] dojo.html Cycles:165 Average:5521.98 Median:5783.83 stddev:640.94 (11.1%) stddev-sans-first:614.80 14:16:30 INFO - PROCESS | 2668 | Values: 3129.0 3175.5 3176.5 3187.0 3216.6 5551.0 5565.5 5614.4 5618.7 5626.5 5553.1 5608.7 5617.8 5660.0 5666.8 5032.5 5086.1 5101.7 5141.8 5167.8 4962.7 4974.0 5126.2 5181.2 5182.3 4887.5 4906.4 4949.2 4968.7 4994.6 4926.6 4941.5 4998.1 5018.2 5039.1 4780.8 4932.8 4948.3 4983.3 5008.5 5283.0 5323.7 5343.8 5447.7 5457.5 5890.8 5896.1 6005.5 6030.0 6034.2 5840.6 5965.0 5996.8 6053.4 6056.3 5497.3 5529.3 5538.9 5567.7 5603.6 6143.4 6145.9 6149.2 6152.3 6153.4 5740.3 5896.5 5909.8 5916.7 5918.5 5890.3 5916.6 5977.7 5982.5 6064.8 5957.7 6007.1 6022.8 6024.6 6028.2 5957.0 5958.8 5982.4 6021.8 6029.3 4505.1 4547.0 4604.2 4637.8 4643.7 4088.6 4110.1 4116.5 4142.8 4153.4 5741.7 5755.6 5758.7 5763.7 5767.5 5802.8 5834.7 5849.7 6089.1 6089.3 5558.9 5601.0 5604.7 5640.5 5642.2 4953.1 4955.8 4963.7 4964.0 4989.2 5975.2 6008.9 6017.3 6031.4 6088.7 5836.7 5954.3 5993.4 6005.9 6010.0 6119.2 6138.0 6145.6 6151.5 6151.5 5630.2 5832.5 5855.7 5863.4 5864.0 5751.1 5871.0 5884.0 5907.0 6013.9 5783.8 5801.0 5833.5 5833.6 5839.7 5829.7 5835.5 5842.1 5842.3 5845.0 5671.6 5715.5 5755.6 5809.5 5825.6 5918.1 5933.9 5958.4 5975.6 5991.8 5554.4 5803.8 5807.7 5813.2 5814.6 14:16:30 INFO - PROCESS | 2668 | 14:16:30 INFO - PROCESS | 2668 | [#1] ext.html Cycles:180 Average:61504.85 Median:39370.11 stddev:118438.88 (300.8%) stddev-sans-first:118682.86 14:16:30 INFO - PROCESS | 2668 | Values: 600.3 630.2 653.5 658.7 668.4 34849.5 38449.2 46451.8 46828.0 47184.8 44453.1 44567.6 44655.8 44689.0 44739.1 9339.2 9495.3 9509.5 9510.1 9515.5 8546.5 8737.6 10997.3 11198.3 11198.8 11395.6 11482.5 11498.5 11503.7 11504.5 11237.1 11306.7 11338.3 11346.4 11348.1 11108.6 11175.3 11184.4 11184.6 11196.3 45300.0 45651.0 45653.5 45707.0 45755.0 3747.9 3785.3 4263.6 4271.4 4421.4 33988.5 34013.1 34073.7 34200.3 34324.0 4742.6 4779.7 4821.9 4878.5 4880.5 348769.0 349390.0 349680.0 350431.0 352283.0 62493.0 62984.0 62987.0 62990.4 63038.0 40378.4 41091.0 41339.0 44071.6 66779.0 19175.7 19424.3 19456.3 19476.1 19477.8 13649.6 13943.2 14789.1 15038.8 15042.8 20218.0 20771.0 20803.8 21519.2 21664.8 7225.7 7236.3 7370.8 7425.2 7675.7 1005.4 1116.4 1122.6 1128.8 1150.1 34741.7 34760.0 35027.8 35145.8 35271.5 1124.3 1143.4 1148.5 1190.4 1270.8 18620.2 18654.8 18728.0 18861.0 18949.0 558057.0 561789.0 564781.0 565323.0 566691.0 62964.7 63590.4 63602.7 63634.0 63744.4 1279.8 1355.5 1416.7 1423.7 1458.2 32691.8 36192.0 36250.5 36298.5 36562.5 53250.7 53467.0 53551.5 53576.5 53822.5 399103.0 401993.0 402579.0 402697.0 403302.0 31475.7 32604.2 32779.3 33088.8 33532.3 50982.5 51885.5 52218.7 52548.7 53340.0 43760.8 44026.6 44557.8 44897.0 45097.0 43053.0 44382.3 45433.3 45505.3 45982.8 44109.3 44573.3 44711.0 44759.6 44759.6 36421.5 38128.0 38546.4 39317.8 39422.4 29169.4 29202.7 29471.7 29589.3 29646.1 14:16:30 INFO - PROCESS | 2668 | 14:16:30 INFO - PROCESS | 2668 | [#2] jquery.html Cycles:180 Average:29145.86 Median:23476.65 stddev:78991.80 (336.5%) stddev-sans-first:79183.88 14:16:30 INFO - PROCESS | 2668 | Values: 386.9 428.7 429.1 436.2 436.8 16279.6 19837.5 21046.0 21182.9 21389.3 19592.9 19721.3 20152.7 20610.4 20775.0 5754.0 5764.0 5991.4 5993.9 5994.4 5746.8 5888.4 5898.4 5900.6 5901.9 5819.2 5860.5 5863.4 5865.7 5882.2 6065.6 6215.3 6232.9 6234.8 6239.6 5830.4 5920.4 5975.3 5983.5 6063.6 23104.8 23294.7 23658.6 24171.4 24873.8 2398.4 2533.4 2625.8 2846.3 3154.8 10412.8 10480.9 10704.6 11038.8 11121.1 1956.6 2039.7 2078.6 2086.4 2089.7 76719.2 82980.6 86504.6 87662.0 88107.6 10309.5 10942.9 11333.3 11578.8 11840.6 15085.9 15112.5 15981.6 16029.4 16498.8 8051.7 8056.3 8378.6 8941.8 9122.8 8541.8 8695.0 8811.9 9020.3 9161.9 4905.8 4906.5 4925.8 5053.7 5118.7 1969.7 2008.1 2012.6 2023.2 2077.1 951.6 1001.6 1012.7 1023.3 1090.5 19750.8 20154.5 20347.0 20432.1 20581.2 1047.7 1060.7 1061.4 1082.6 1103.0 10775.3 11398.7 11506.8 11613.1 11623.7 473053.0 474150.0 476090.0 483108.0 485374.0 92731.5 93599.0 94236.0 94539.5 95126.5 1011.3 1027.6 1055.8 1074.5 1087.1 17687.4 17760.2 18094.5 18181.6 18395.7 29446.0 30858.8 40902.0 45442.3 45795.5 58454.7 58810.7 59205.3 60556.0 60563.0 9270.6 9312.8 9341.6 9367.7 9376.0 13258.5 13485.1 13860.4 14056.8 14097.8 11993.5 12253.3 12320.5 12381.7 12499.1 11847.9 12043.2 12255.2 12309.5 12371.3 12304.6 12395.9 12439.6 12606.3 12926.4 7842.6 8148.1 9170.4 9209.0 9239.3 10075.5 12137.2 12255.3 12345.6 12687.9 14:16:30 INFO - PROCESS | 2668 | 14:16:30 INFO - PROCESS | 2668 | [#3] mootools.html Cycles:180 Average:6002.82 Median:4913.50 stddev:3387.35 (68.9%) stddev-sans-first:3378.90 14:16:30 INFO - PROCESS | 2668 | Values: 1362.9 1491.3 1520.6 1668.4 1816.9 8264.5 8939.0 9288.8 9298.4 9487.6 9303.8 9349.0 9370.0 9375.2 9375.8 2523.2 2549.9 2551.7 2552.4 2554.6 2467.3 2525.7 2528.8 2530.4 2531.8 2487.9 2501.5 2524.9 2531.2 2543.7 2421.6 2432.9 2439.5 2493.8 2504.2 2463.9 2485.9 2487.1 2492.5 2500.9 9079.2 9172.4 9209.0 9284.8 9340.8 5469.0 5599.9 5600.3 5606.1 5607.1 3705.0 3710.8 3747.4 3753.2 3777.9 1180.4 1195.5 1211.3 1227.0 1233.1 12281.5 12391.7 12399.7 12416.6 12437.4 4699.9 4725.8 4733.9 4792.7 4888.1 9945.9 9970.7 9979.7 9982.4 9985.6 5443.5 5449.0 5460.0 5470.3 5478.5 4561.0 4714.6 4953.5 5063.7 5094.8 3996.2 4067.8 4116.4 4171.6 4175.7 1231.4 1234.4 1235.9 1248.5 1257.0 660.0 841.3 897.8 899.5 964.9 6109.8 6140.7 6144.4 6155.4 6471.3 938.8 948.9 1017.0 1046.7 1058.3 3858.1 3907.7 4016.2 4047.8 4086.7 10573.7 11171.8 11240.6 11241.9 11253.7 11045.8 11151.8 11152.2 11157.2 11164.8 8378.7 8844.3 8859.0 8859.1 8859.8 6110.8 6226.0 6277.7 6309.9 6340.1 10535.6 10570.6 10587.0 10628.2 10664.8 10502.8 10664.9 10777.2 10895.5 10930.1 8386.5 8390.1 8505.4 8507.1 8508.0 8987.6 8995.8 9083.1 9087.8 9089.1 7607.7 7675.7 7707.0 7746.3 7767.7 7500.3 7569.3 7582.8 7624.2 7688.5 7292.5 7551.9 7637.5 7678.7 7702.7 4761.1 4938.9 4969.7 4998.5 5117.1 4990.4 5009.9 5080.8 5099.7 5176.7 14:16:30 INFO - PROCESS | 2668 | 14:16:30 INFO - PROCESS | 2668 | [#4] prototype.html Cycles:180 Average:4841.48 Median:5475.58 stddev:1415.06 (25.8%) stddev-sans-first:1378.24 14:16:30 INFO - PROCESS | 2668 | Values: 347.4 349.1 360.5 361.0 362.4 5525.7 5569.3 5605.9 5616.9 5630.5 5455.2 5496.0 5515.6 5597.4 5607.7 4209.6 4350.7 4365.7 4378.3 4405.7 4320.3 4325.7 4331.2 4334.5 4352.2 4113.2 4319.6 4327.5 4331.5 4334.3 4202.6 4246.2 4301.9 4336.5 4383.7 3896.0 3897.5 3898.6 3898.8 3912.6 5019.9 5370.4 5370.5 5383.5 5403.8 4664.7 4947.4 4957.9 4974.1 4989.7 5723.2 5735.7 5736.7 5739.8 5742.9 3926.9 4126.7 4288.7 4291.1 4292.6 5945.4 5964.0 5972.7 5979.5 6009.0 4283.8 4304.2 4327.3 4345.5 4347.8 4111.6 4287.0 4402.2 4410.5 4414.9 4860.0 4883.8 4902.4 4940.6 4997.6 5776.7 5786.8 5795.2 5863.7 5900.9 1433.7 1471.8 1490.8 1504.2 1522.7 932.9 952.9 966.6 968.1 969.2 4965.8 4972.4 4978.3 4988.7 5008.3 5191.9 5195.8 5199.6 5213.9 5216.3 4919.2 4935.1 4959.3 5069.3 5355.6 3067.8 3094.0 3107.9 3150.6 3154.5 6037.4 6062.7 6068.0 6093.2 6106.7 5748.8 6067.4 6070.5 6090.4 6109.1 6039.4 6085.5 6092.5 6108.6 6156.4 5655.5 5917.6 5937.4 5965.3 6059.5 5908.9 5909.6 5912.5 5913.6 5920.4 5837.6 6140.3 6146.0 6209.9 6219.9 4235.7 4274.3 4292.7 4342.6 4354.8 6038.7 6054.7 6064.5 6076.1 6076.5 5617.4 5818.2 5843.2 5903.3 5909.0 5743.8 5774.0 5783.3 5783.3 5789.3 5781.8 5789.0 5814.2 5857.2 5865.4 5619.3 5814.9 5833.9 5849.0 5853.2 5633.5 5644.9 5686.9 5701.1 5724.5 14:16:30 INFO - PROCESS | 2668 | 14:16:30 INFO - PROCESS | 2668 | [#5] yui.html Cycles:180 Average:21142.61 Median:4843.97 stddev:30702.52 (633.8%) stddev-sans-first:30750.72 14:16:30 INFO - PROCESS | 2668 | Values: 817.3 817.7 854.1 857.6 901.7 16700.0 17825.9 18001.5 18056.8 18071.2 16241.8 16249.5 16439.8 16468.7 16950.6 860.8 882.2 885.7 885.7 885.9 729.6 744.5 853.2 867.2 879.1 922.1 922.5 923.1 924.1 924.7 911.1 917.7 920.3 921.0 921.2 560.7 573.5 654.8 665.0 678.5 16245.4 16331.2 16371.3 16381.3 16382.3 14779.9 15221.4 15377.9 15528.7 16148.5 14266.0 14371.1 14467.3 14517.6 14544.4 4908.1 4934.7 4972.5 5005.5 5075.5 59957.4 60382.4 64139.7 87373.6 88675.6 11492.7 12471.8 12666.8 12730.2 12853.9 21991.6 22283.2 22294.9 22447.6 22453.4 8809.4 8844.6 8931.3 8961.0 8962.9 4549.2 4753.8 4866.1 4915.0 4928.3 3722.2 3834.6 3834.9 3838.1 3843.3 635.8 648.5 650.6 662.2 666.7 628.7 666.3 672.2 708.8 714.6 9006.2 9729.4 9847.0 10033.3 10381.0 650.5 655.1 663.8 668.6 728.5 4643.1 4759.4 4778.8 4819.6 4821.9 72986.0 74237.6 99953.0 130319.0 134358.0 107030.0 112899.0 113817.4 114061.4 115828.8 94111.0 94241.0 95824.5 95913.5 98506.5 8915.7 9364.8 9437.9 9439.3 9528.9 55626.0 56390.0 56481.0 56606.4 56984.0 46538.3 53983.0 79528.0 80389.6 80962.6 12587.2 12592.2 12627.8 13096.6 13174.9 13623.3 13733.2 13749.2 14005.6 14191.4 12385.9 12453.7 12651.9 12863.9 13368.7 12200.8 12363.4 12794.0 12910.9 13030.9 11267.5 11451.1 11538.2 12872.5 12920.2 10094.5 10254.4 10358.4 10497.4 10567.5 9780.8 10410.2 10632.6 10797.7 11010.6 14:16:30 INFO - PROCESS | 2668 | -------- Summary: end -------- 14:16:30 INFO - PROCESS | 2668 | 14:16:31 INFO - TEST-INFO | 2668: exit 0 14:16:31 INFO - TEST-OK | dromaeo_css | took 1113525ms 14:16:31 INFO - TEST-START | kraken 14:16:31 INFO - operating with platform_type : linux_ 14:16:31 INFO - Initialising browser for kraken test... 14:16:31 INFO - TEST-INFO | started process 3096 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpru3M0V/profile http://localhost:52807/getInfo.html) 14:16:35 INFO - PROCESS | 3096 | __metrics Screen width/height:1600/1200 14:16:35 INFO - PROCESS | 3096 | colorDepth:24 14:16:35 INFO - PROCESS | 3096 | Browser inner width/height: 1024/697 14:16:35 INFO - PROCESS | 3096 | __metrics 14:16:36 INFO - PROCESS | 3096 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:16:36 INFO - PROCESS | 3096 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:16:36 INFO - PROCESS | 3096 | console.error: PushService: 14:16:36 INFO - PROCESS | 3096 | stateChangeProcessEnqueue: Error transitioning state 14:16:36 INFO - PROCESS | 3096 | UnknownError 14:16:37 INFO - TEST-INFO | 3096: exit 0 14:16:37 INFO - Browser initialized. 14:16:37 INFO - Running cycle 1/1 for kraken test... 14:16:37 INFO - TEST-INFO | started process 3187 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpru3M0V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:16:48 INFO - PROCESS | 3187 | RSS: Main: 144740352 14:16:48 INFO - PROCESS | 3187 | 14:16:51 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52807/tests/kraken/driver.html?testName=audio-beat-detection) 14:16:52 INFO - PROCESS | 3187 | RSS: Main: 186937344 14:16:52 INFO - PROCESS | 3187 | 14:16:54 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52807/tests/kraken/driver.html?testName=audio-dft) 14:16:55 INFO - PROCESS | 3187 | RSS: Main: 175714304 14:16:55 INFO - PROCESS | 3187 | 14:16:58 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52807/tests/kraken/driver.html?testName=audio-fft) 14:16:58 INFO - PROCESS | 3187 | RSS: Main: 267309056 14:16:58 INFO - PROCESS | 3187 | 14:17:00 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52807/tests/kraken/driver.html?testName=audio-oscillator) 14:17:01 INFO - PROCESS | 3187 | RSS: Main: 173625344 14:17:01 INFO - PROCESS | 3187 | 14:17:03 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52807/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:17:04 INFO - PROCESS | 3187 | RSS: Main: 175120384 14:17:04 INFO - PROCESS | 3187 | 14:17:07 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52807/tests/kraken/driver.html?testName=imaging-darkroom) 14:17:07 INFO - PROCESS | 3187 | RSS: Main: 210911232 14:17:07 INFO - PROCESS | 3187 | 14:17:11 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52807/tests/kraken/driver.html?testName=imaging-desaturate) 14:17:11 INFO - PROCESS | 3187 | RSS: Main: 210558976 14:17:11 INFO - PROCESS | 3187 | 14:17:14 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52807/tests/kraken/driver.html?testName=json-parse-financial) 14:17:14 INFO - PROCESS | 3187 | RSS: Main: 209764352 14:17:14 INFO - PROCESS | 3187 | 14:17:16 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52807/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:17:17 INFO - PROCESS | 3187 | RSS: Main: 171757568 14:17:17 INFO - PROCESS | 3187 | 14:17:18 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52807/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:17:19 INFO - PROCESS | 3187 | RSS: Main: 180211712 14:17:19 INFO - PROCESS | 3187 | 14:17:21 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52807/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:17:21 INFO - PROCESS | 3187 | RSS: Main: 188477440 14:17:21 INFO - PROCESS | 3187 | 14:17:24 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52807/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:17:25 INFO - PROCESS | 3187 | RSS: Main: 197656576 14:17:25 INFO - PROCESS | 3187 | 14:17:27 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52807/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:17:28 INFO - PROCESS | 3187 | RSS: Main: 193560576 14:17:28 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | Cycle 1(1): loaded http://localhost:52807/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52807/tests/kraken/driver.html?testName=ai-astar) 14:17:30 INFO - PROCESS | 3187 | RSS: Main: 243732480 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | __start_tp_report 14:17:30 INFO - PROCESS | 3187 | _x_x_mozilla_page_load 14:17:30 INFO - PROCESS | 3187 | _x_x_mozilla_page_load_details 14:17:30 INFO - PROCESS | 3187 | |i|pagename|runs| 14:17:30 INFO - PROCESS | 3187 | |0;ai-astar;109;109;110;110;111;110;110;110;109;111 14:17:30 INFO - PROCESS | 3187 | |1;audio-beat-detection;143;120;118;154;121;124;120;126;164;119 14:17:30 INFO - PROCESS | 3187 | |2;audio-dft;162;162;160;161;160;160;162;160;160;160 14:17:30 INFO - PROCESS | 3187 | |3;audio-fft;75;76;77;124;76;77;78;76;99;75 14:17:30 INFO - PROCESS | 3187 | |4;audio-oscillator;82;82;112;84;82;81;102;81;83;109 14:17:30 INFO - PROCESS | 3187 | |5;imaging-gaussian-blur;117;117;116;117;118;116;118;118;119;117 14:17:30 INFO - PROCESS | 3187 | |6;imaging-darkroom;145;146;145;146;145;144;146;147;146;145 14:17:30 INFO - PROCESS | 3187 | |7;imaging-desaturate;95;95;96;94;95;94;95;95;95;94 14:17:30 INFO - PROCESS | 3187 | |8;json-parse-financial;48;49;48;49;48;49;50;48;49;49 14:17:30 INFO - PROCESS | 3187 | |9;json-stringify-tinderbox;60;59;59;60;59;59;59;59;59;60 14:17:30 INFO - PROCESS | 3187 | |10;stanford-crypto-aes;74;74;79;75;73;81;73;73;82;73 14:17:30 INFO - PROCESS | 3187 | |11;stanford-crypto-ccm;123;121;130;123;120;123;127;130;169;124 14:17:30 INFO - PROCESS | 3187 | |12;stanford-crypto-pbkdf2;152;152;153;150;155;155;161;152;151;156 14:17:30 INFO - PROCESS | 3187 | |13;stanford-crypto-sha256-iterative;63;63;64;64;62;62;61;62;60;60 14:17:30 INFO - PROCESS | 3187 | __end_tp_report 14:17:30 INFO - PROCESS | 3187 | __start_cc_report 14:17:30 INFO - PROCESS | 3187 | _x_x_mozilla_cycle_collect,1614 14:17:30 INFO - PROCESS | 3187 | __end_cc_report 14:17:30 INFO - PROCESS | 3187 | __startTimestamp1477603050249__endTimestamp 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | ------- Summary: start ------- 14:17:30 INFO - PROCESS | 3187 | Number of tests: 14 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.00 stddev:0.74 (0.7%) stddev-sans-first:0.71 14:17:30 INFO - PROCESS | 3187 | Values: 109.0 109.0 110.0 110.0 111.0 110.0 110.0 110.0 109.0 111.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:125.00 stddev:16.64 (13.3%) stddev-sans-first:17.06 14:17:30 INFO - PROCESS | 3187 | Values: 143.0 120.0 118.0 154.0 121.0 124.0 120.0 126.0 164.0 119.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#2] audio-dft Cycles:10 Average:160.70 Median:160.50 stddev:0.95 (0.6%) stddev-sans-first:0.88 14:17:30 INFO - PROCESS | 3187 | Values: 162.0 162.0 160.0 161.0 160.0 160.0 162.0 160.0 160.0 160.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#3] audio-fft Cycles:10 Average:83.30 Median:76.50 stddev:16.01 (20.9%) stddev-sans-first:16.70 14:17:30 INFO - PROCESS | 3187 | Values: 75.0 76.0 77.0 124.0 76.0 77.0 78.0 76.0 99.0 75.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:82.00 stddev:12.59 (15.4%) stddev-sans-first:13.04 14:17:30 INFO - PROCESS | 3187 | Values: 82.0 82.0 112.0 84.0 82.0 81.0 102.0 81.0 83.0 109.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.50 stddev:0.95 (0.8%) stddev-sans-first:1.00 14:17:30 INFO - PROCESS | 3187 | Values: 117.0 117.0 116.0 117.0 118.0 116.0 118.0 118.0 119.0 117.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 14:17:30 INFO - PROCESS | 3187 | Values: 145.0 146.0 145.0 146.0 145.0 144.0 146.0 147.0 146.0 145.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 14:17:30 INFO - PROCESS | 3187 | Values: 95.0 95.0 96.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#8] json-parse-financial Cycles:10 Average:48.70 Median:49.00 stddev:0.67 (1.4%) stddev-sans-first:0.67 14:17:30 INFO - PROCESS | 3187 | Values: 48.0 49.0 48.0 49.0 48.0 49.0 50.0 48.0 49.0 49.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 14:17:30 INFO - PROCESS | 3187 | Values: 60.0 59.0 59.0 60.0 59.0 59.0 59.0 59.0 59.0 60.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:74.50 stddev:3.56 (4.8%) stddev-sans-first:3.72 14:17:30 INFO - PROCESS | 3187 | Values: 74.0 74.0 79.0 75.0 73.0 81.0 73.0 73.0 82.0 73.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#11] stanford-crypto-ccm Cycles:10 Average:129.00 Median:125.50 stddev:14.47 (11.5%) stddev-sans-first:15.18 14:17:30 INFO - PROCESS | 3187 | Values: 123.0 121.0 130.0 123.0 120.0 123.0 127.0 130.0 169.0 124.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:154.00 stddev:3.20 (2.1%) stddev-sans-first:3.33 14:17:30 INFO - PROCESS | 3187 | Values: 152.0 152.0 153.0 150.0 155.0 155.0 161.0 152.0 151.0 156.0 14:17:30 INFO - PROCESS | 3187 | 14:17:30 INFO - PROCESS | 3187 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.50 stddev:1.45 (2.3%) stddev-sans-first:1.50 14:17:30 INFO - PROCESS | 3187 | Values: 63.0 63.0 64.0 64.0 62.0 62.0 61.0 62.0 60.0 60.0 14:17:30 INFO - PROCESS | 3187 | -------- Summary: end -------- 14:17:30 INFO - PROCESS | 3187 | 14:17:31 INFO - TEST-INFO | 3187: exit 0 14:17:31 INFO - TEST-OK | kraken | took 59872ms 14:17:31 INFO - SUITE-END | took 1173s 14:17:31 INFO - Completed test suite (00:19:33) 14:17:31 INFO - javascript benchmark 14:17:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3128.9687103128967, 3175.491921292594, 3176.5394017867416, 3186.9681303186962, 3216.5657636219116, 5550.972245138773, 5565.471280228372, 5614.382417934029, 5618.690971996539, 5626.521745651614, 5553.055980483317, 5608.719564021792, 5617.775288988446, 5659.971700141494, 5666.829995100154, 5032.5470707636305, 5086.059079070365, 5101.744912754366, 5141.817382002142, 5167.767450464738, 4962.677425967318, 4973.950260497403, 5126.231065340192, 5181.222816577506, 5182.300389447414, 4887.486813884544, 4906.362172917516, 4949.232868905326, 4968.70187788732, 4994.600431965434, 4926.605871530287, 4941.530554597308, 4998.125328067574, 5018.172001619765, 5039.118154322979, 4780.832670856538, 4932.80268789248, 4948.331975183341, 4983.302337672729, 5008.474110218447, 5283.049051170788, 5323.653962492425, 5343.812966546191, 5447.727613619343, 5457.536109430703, 5890.793822216202, 5896.145058966469, 6005.549583781234, 6029.974904266285, 6034.24571928509, 5840.591158618899, 5965.0, 5996.760129594811, 6053.364396738367, 6056.273247210319, 5497.285352904097, 5529.3364796224305, 5538.944610553883, 5567.749451274703, 5603.551715862721, 6143.354947730513, 6145.907811382833, 6149.200603921461, 6152.261728592597, 6153.446189842935, 5740.253767010262, 5896.469317761422, 5909.8227053188475, 5916.7337469813965, 5918.496927761103, 5890.322612899571, 5916.5858389913155, 5977.701114944322, 5982.46157845796, 6064.75740970365, 5957.672328022, 6007.098935159674, 6022.759089636453, 6024.608400453956, 6028.186194863725, 5957.046872500379, 5958.791442299499, 5982.371850955674, 6021.789237376671, 6029.336772954972, 4505.07645932583, 4547.0, 4604.194266003436, 4637.814487420533, 4643.674942754043, 4088.550259471456, 4110.0752330725345, 4116.464859568297, 4142.751434913855, 4153.418521407006, 5741.741621627037, 5755.597108202385, 5758.74085666146, 5763.682997435181, 5767.538596912237, 5802.796902108406, 5834.708264586839, 5849.736761845727, 6089.117078023662, 6089.299730530957, 5558.888822223614, 5600.971995139998, 5604.719764011783, 5640.520555752807, 5642.210090587321, 4953.058918805487, 4955.8017679292425, 4963.677360971526, 4963.975180124076, 4989.151844186497, 5975.163477113208, 6008.8798224036145, 6017.338092809699, 6031.366706495843, 6088.695565221721, 5836.678982655994, 5954.255718035246, 5993.3706960768495, 6005.879882402415, 6010.038393856962, 6119.173911522065, 6137.96931015342, 6145.600535965148, 6151.477124444473, 6151.538634602333, 5630.211770352072, 5832.533397328289, 5855.707214639166, 5863.442972917566, 5864.003119469615, 5751.1373294006235, 5871.031279838779, 5883.970580147072, 5906.970465147647, 6013.939860601513, 5783.826485205451, 5800.984827655059, 5833.504152147115, 5833.562482813721, 5839.678817665069, 5829.708514574169, 5835.533157347487, 5842.065269556851, 5842.298924129132, 5845.035569131046, 5671.602987790815, 5715.485606295287, 5755.625884317506, 5809.477147056785, 5825.621334613236, 5918.141869428995, 5933.881322373442, 5958.374370691015, 5975.641461512323, 5991.760329586942, 5554.444555544414, 5803.7968671098, 5807.651540907559, 5813.215215945793, 5814.622049566765], "value": 5477.867408773361, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.345623270635, 630.1681780050334, 653.5163978655796, 658.7200439813079, 668.3817468841322, 34849.47725784115, 38449.23101537968, 46451.76774116129, 46828.0, 47184.76407617957, 44453.110937781224, 44567.554324456745, 44655.77672111635, 44689.0, 44739.10521789562, 9339.206167475755, 9495.335326527145, 9509.476978766199, 9510.096540828645, 9515.476648784314, 8546.529940853281, 8737.563121843914, 10997.34015959041, 11198.328100314007, 11198.776024479506, 11395.601153959613, 11482.48328825205, 11498.482568284406, 11503.65489035326, 11504.482298296554, 11237.101031917467, 11306.660800176009, 11338.319700817978, 11346.375949322743, 11348.092152627853, 11108.555657773679, 11175.273607215506, 11184.44077796115, 11184.608538701106, 11196.328220306808, 45300.0, 45651.0, 45653.543464565264, 45707.0, 45755.0, 3747.850085996557, 3785.261873934584, 4263.552327005651, 4271.444712187395, 4421.403110580062, 33988.49017264743, 34013.14967125817, 34073.659263407295, 34200.31599368023, 34324.0, 4742.644301677354, 4779.737114458703, 4821.879453013667, 4878.536539028786, 4880.487548807359, 348769.0, 349390.0, 349680.0, 350431.0, 352282.99999999744, 62493.0, 62984.0, 62987.0, 62990.37009629845, 63038.0, 40378.394324085166, 41091.0, 41339.0, 44071.55928440738, 66779.0, 19175.712364314546, 19424.320148794723, 19456.319028833925, 19476.123574439185, 19477.80522194788, 13649.590512284647, 13943.233122178377, 14789.11265324084, 15038.774418383733, 15042.849571504365, 20218.0, 20771.0, 20803.791962080184, 21519.246826361003, 21664.783352166276, 7225.74709885162, 7236.34872861445, 7370.77887663371, 7425.1832298447125, 7675.731349402744, 1005.3967619428284, 1116.3820840129883, 1122.6407549584055, 1128.785530749155, 1150.1086657840074, 34741.652583474344, 34759.957201284, 35027.82486087604, 35145.82427087899, 35271.453240569695, 1124.3310230413056, 1143.4454289669504, 1148.4602236948622, 1190.4166958190597, 1270.8347914771205, 18620.16209270586, 18654.813451865306, 18728.0, 18861.0, 18949.0, 558057.0, 561789.0, 564781.0, 565323.0, 566691.0, 62964.685176573825, 63590.36409635845, 63602.681986590695, 63634.0, 63744.362556373846, 1279.7632437999, 1355.4781409157545, 1416.6812467195005, 1423.743726129286, 1458.161557104669, 32691.836540817145, 36192.0, 36250.4562431569, 36298.4555231677, 36562.45156322711, 53250.73374633102, 53467.0, 53551.464485356206, 53576.464235358704, 53822.461775381744, 399103.0, 401993.0, 402579.0, 402697.0, 403302.0, 31475.685243148193, 32604.184895377806, 32779.34441311208, 33088.83455582707, 33532.32935341328, 50982.49017509777, 51885.48114518807, 52218.738906305225, 52548.686282843235, 53340.0, 43760.78119609381, 44026.559734402246, 44557.77721111374, 44897.0, 45097.01829289448, 43053.0, 44382.3342649854, 45433.318500223184, 45505.317420239386, 45982.77008614936, 44109.33835992398, 44573.33140002838, 44711.0, 44759.55240447554, 44759.55240447554, 36421.45367819432, 38128.0, 38546.421803672405, 39317.803410982764, 39422.408663870636, 29169.436876702573, 29202.70797292085, 29471.705282946896, 29589.2602684926, 29646.110616681533], "value": 21707.580969061015, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.9090763670535, 428.6634991531647, 429.0974457104752, 436.2169905020488, 436.777243605761, 16279.593010174736, 19837.504062398428, 21046.0, 21182.89408552955, 21389.251376201817, 19592.90203548984, 19721.309754158607, 20152.69770953433, 20610.38168854933, 20775.0, 5753.971230143843, 5763.9711801440935, 5991.430814072656, 5993.910091348612, 5994.400559944014, 5746.770129194827, 5888.381719919411, 5898.351181370067, 5900.645961242318, 5901.940980590203, 5819.214406055192, 5860.472557469827, 5863.442972917566, 5865.706714664271, 5882.2353094097925, 6065.636061836304, 6215.347388524185, 6232.906506402408, 6234.750609975596, 6239.594426362295, 5830.4461076197695, 5920.407959204088, 5975.312839023524, 5983.521318294526, 6063.605865618765, 23104.76895231043, 23294.65058024131, 23658.64512032321, 24171.395715107086, 24873.751262487323, 2398.3524448399007, 2533.3919859233833, 2625.789936805051, 2846.3026558493093, 3154.8422578870964, 10412.791744165073, 10480.895191048068, 10704.625338113206, 11038.834417483744, 11121.110311175087, 1956.5695546979641, 2039.7450318710162, 2078.615456140604, 2086.384516567616, 2089.686547017944, 76719.23280767232, 82980.58509707413, 86504.56747716284, 87662.0, 88107.55946220292, 10309.53607087683, 10942.890571094347, 11333.320000799979, 11578.826317605242, 11840.585579504848, 15085.924570377078, 15112.471063512725, 15981.60045998836, 16029.438969636007, 16498.75251871223, 8051.677932882619, 8056.315213206826, 8378.622961966605, 8941.821163576693, 9122.771930701785, 8541.829163416822, 8695.0, 8811.867821982676, 9020.278377729766, 9161.908380916238, 4905.773556610848, 4906.533879281463, 4925.7783399747095, 5053.747312634354, 5118.667286626357, 1969.734085898414, 2008.136501304424, 2012.5572374077678, 2023.1806118522022, 2077.1379877350732, 951.5955718819613, 1001.6293971230546, 1012.6810054832852, 1023.2581378500528, 1090.5310716391868, 19750.802491974897, 20154.496137596678, 20347.0, 20432.080556375, 20581.176752930016, 1047.711879233217, 1060.7083052160563, 1061.3737894642197, 1082.6156714366443, 1102.9779404411868, 10775.299605525772, 11398.658040258806, 11506.76986460266, 11613.129015323715, 11623.709407264885, 473053.0, 474150.0, 476090.0, 483108.0, 485374.0, 92731.53634231785, 93599.0, 94236.0, 94539.52730236304, 95126.52436737771, 1011.2668315471225, 1027.5941003303653, 1055.8257887448485, 1074.5003573338279, 1087.0922779479224, 17687.38094166698, 17760.20079096418, 18094.457166285032, 18181.636367272844, 18395.72406413878, 29446.0, 30858.845705771328, 40902.0, 45442.31836522521, 45795.542044579124, 58454.70772646109, 58810.70594646999, 59205.26357891445, 60556.0, 60563.0, 9270.6291748332, 9312.813743725223, 9341.626334946526, 9367.672131475365, 9375.953120234355, 13258.469661213443, 13485.056046076681, 13860.376283067084, 14056.789148162581, 14097.788533172215, 11993.520259189534, 12253.326067066397, 12320.507179712711, 12381.690457738629, 12499.062570307082, 11847.940760296487, 12043.156979011384, 12255.203411778206, 12309.507619695112, 12371.319577422968, 12304.569340073054, 12395.876041239833, 12439.564615238423, 12606.306653134166, 12926.418311175832, 7842.568658723825, 8148.144444833444, 9170.44977301364, 9208.953955230181, 9239.307051970995, 10075.546600403146, 12137.150399471951, 12255.325957072446, 12345.567905123637, 12687.873121268669], "value": 9623.826251366489, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.9454821807128, 1491.3438087241614, 1520.6350475885788, 1668.416054380967, 1816.854651627869, 8264.504129752218, 8939.0, 9288.767780805467, 9298.395604285724, 9487.620495180185, 9303.767405814859, 9349.0, 9370.0, 9375.156235938764, 9375.76560585984, 2523.2304147235086, 2549.872506374674, 2551.719310875802, 2552.3874270175115, 2554.6295787110857, 2467.3338198686342, 2525.734797846218, 2528.810339224556, 2530.4180038591135, 2531.8227724059325, 2487.9253622391357, 2501.524710305036, 2524.911628093017, 2531.1773673556077, 2543.669322988009, 2421.5520128776143, 2432.9391765205924, 2439.4755127647554, 2493.763092506209, 2504.198656429943, 2463.864487453189, 2485.9378515537255, 2487.141936032066, 2492.513959777844, 2500.8999640014376, 9079.182873541413, 9172.403793753385, 9208.953955230181, 9284.814303713887, 9340.813183736354, 5468.972655136698, 5599.916001259943, 5600.299962504687, 5606.103023516259, 5607.130894711327, 3705.0, 3710.8144592770523, 3747.362948298796, 3753.2118255166447, 3777.9244415111543, 1180.356705595444, 1195.4500929572407, 1211.2732360583668, 1226.9877301226963, 1233.0998371188994, 12281.508739650404, 12391.690207744788, 12399.690007749788, 12416.627501174979, 12437.378131093408, 4699.9190186256665, 4725.756338456908, 4733.863872670534, 4792.736399497991, 4888.120138375056, 9945.900540994642, 9970.651027214013, 9979.650712225182, 9982.35114717541, 9985.550650220757, 5443.5100840923715, 5448.9727551362785, 5460.044492213847, 5470.288862447856, 5478.479544443271, 4560.977195114003, 4714.6464015199, 4953.455119936804, 5063.696178229318, 5094.770735316845, 3996.160806230724, 4067.8169482373364, 4116.403121547359, 4171.645410140111, 4175.68682348825, 1231.4335405713473, 1234.4198226833373, 1235.8516977962702, 1248.5068397982745, 1256.9748605027848, 659.9637019963852, 841.2638940926689, 897.7620930453435, 899.4962820820361, 964.9372790768577, 6109.7861574844665, 6140.723667434976, 6144.41628045335, 6155.446009859134, 6471.288158302583, 938.8497840345512, 948.9098535639241, 1016.9593216271267, 1046.6755305855208, 1058.2750815691277, 3858.131920317906, 3907.6909235406456, 4016.156607112539, 4047.797610119482, 4086.6730661547003, 10573.735656608646, 11171.776564468664, 11240.606578769704, 11241.94379028116, 11253.718657033476, 11045.83431248532, 11151.776964460665, 11152.163587730953, 11157.163212759075, 11164.776704465865, 8378.748637540884, 8844.33667474942, 8858.955705221562, 8859.06979767115, 8859.778505537284, 6110.7861224856915, 6226.0, 6277.686115694106, 6309.873802523832, 6340.1440805490665, 10535.578576857146, 10570.630027949292, 10587.0, 10628.202884783517, 10664.84002739944, 10502.842457363298, 10664.89335106639, 10777.191710621579, 10895.455227238448, 10930.125589952944, 8386.496810191407, 8390.11903750122, 8505.36209784281, 8507.106753791011, 8508.0, 8987.595558199766, 8995.775105622412, 9083.137101975302, 9087.818243634958, 9089.136532029446, 7607.733729319669, 7675.695131827491, 7706.961465192639, 7746.341560967381, 7767.689292428239, 7500.324970752658, 7569.280918312751, 7582.848343032998, 7624.237576242332, 7688.500247483896, 7292.48952573336, 7551.886721699069, 7637.541747495168, 7678.731244406419, 7702.691892324245, 4761.119192949315, 4938.8765280868265, 4969.652124351405, 4998.500149984972, 5117.053345131163, 4990.401151861654, 5009.8747531310555, 5080.796768129233, 5099.6685215460875, 5176.741162941913], "value": 4801.915026818639, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.3591224191366, 349.08365540456305, 360.51510718084177, 360.9837557309921, 362.36405109033643, 5525.668459892409, 5569.331680198382, 5605.943940560594, 5616.859578510539, 5630.5495560355175, 5455.18172274159, 5495.972520137394, 5515.61390702651, 5597.397210297211, 5607.7476513556885, 4209.5790420957965, 4350.673699472536, 4365.672574556905, 4378.255696531582, 4405.691601587891, 4320.287152619814, 4325.653947684178, 4331.177076355482, 4334.45819272591, 4352.216601011816, 4113.239050775601, 4319.611234988851, 4327.5239723630375, 4331.501877284106, 4334.349847522865, 4202.600752928467, 4246.2356775780345, 4301.892452688677, 4336.544662810423, 4383.671224658164, 3895.9546238224875, 3897.5322961244547, 3898.590647981978, 3898.7660740355386, 3912.56961734209, 5019.899602007939, 5370.43610420904, 5370.516653501165, 5383.461653834585, 5403.756830942617, 4664.673472856902, 4947.406311242674, 4957.9256311155, 4974.054929563371, 4989.700617962933, 5723.198752174699, 5735.741891614887, 5736.655800651933, 5739.770409183628, 5742.913856292159, 3926.9214615707524, 4126.731762435462, 4288.678349123797, 4291.077418355068, 4292.613664770186, 5945.375735547792, 5963.972249435169, 5972.731227094791, 5979.461848433619, 6009.034037535206, 4283.800535849967, 4304.225239456928, 4327.285997810389, 4345.543717909637, 4347.826086956549, 4111.588841115924, 4287.0, 4402.207602631493, 4410.5368936261875, 4414.867553973386, 4859.975700121548, 4883.829065982744, 4902.4362198346935, 4940.555350018516, 4997.575206107522, 5776.653400795882, 5786.797462088806, 5795.217645617788, 5863.736131874076, 5900.852478688067, 1433.6559225785736, 1471.771875359321, 1490.7763835424555, 1504.2403586188905, 1522.72590933633, 932.911373419524, 952.8856537215579, 966.6423423333412, 968.0513097164677, 969.2149359019216, 4965.82619608312, 4972.3591214899, 4978.278149668277, 4988.72787439208, 5008.273800298936, 5191.922121168185, 5195.76619052136, 5199.55620858196, 5213.947860521422, 5216.321878155815, 4919.2129259318335, 4935.050654991258, 4959.305697202394, 5069.315642388304, 5355.598330125178, 3067.7699172562147, 3094.0, 3107.90676279712, 3150.558921750957, 3154.463741164008, 6037.426444487766, 6062.7271772769445, 6067.969660151671, 6093.177421048191, 6106.664133472702, 5748.798792042258, 6067.393260673897, 6070.544709146831, 6090.3605121461605, 6109.053096770008, 6039.365866584033, 6085.452309292096, 6092.482139018144, 6108.6029408089225, 6156.445919867055, 5655.4627310407095, 5917.556183286357, 5937.435943585352, 5965.313988891159, 6059.484943779652, 5908.940910590839, 5909.592722658719, 5912.46787789101, 5913.645181289136, 5920.37189772816, 5837.591368604157, 6140.3245642978345, 6145.9692701536205, 6209.937900620936, 6219.906701399392, 4235.661147108162, 4274.27337352657, 4292.7424354538825, 4342.609165175149, 4354.847580334673, 6038.6678732670125, 6054.66699331541, 6064.4541991221, 6076.118962750465, 6076.453119219114, 5617.382087970238, 5818.214541036906, 5843.181954526454, 5903.321118071477, 5908.970455147697, 5743.798967036134, 5774.0, 5783.306003279633, 5783.334916484489, 5789.33422656383, 5781.82654520365, 5788.971055144866, 5814.215080964016, 5857.209276747755, 5865.4134586541, 5619.297587801525, 5814.883702325845, 5833.854153646193, 5849.0, 5853.180554722428, 5633.492985631149, 5644.943550564606, 5686.943130568641, 5701.059325211294, 5724.484796368347], "value": 4393.1183886435265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.2807929022456, 817.6883621333598, 854.141587704357, 857.5840717252133, 901.7024381953955, 16700.0, 17825.910870445663, 18001.459956201303, 18056.819431805696, 18071.186796594153, 16241.83758162418, 16249.51251462455, 16439.753403698953, 16468.67062658746, 16950.576235594115, 860.7675927499587, 882.2412725056446, 885.6590212768066, 885.7254251182122, 885.9158379953894, 729.5659082845698, 744.5309455043315, 853.1809462915578, 867.2454964181171, 879.0637970561364, 922.0548937339214, 922.4695799915042, 923.1184219070772, 924.1267002682467, 924.6948506992676, 911.1071150272737, 917.6696389299847, 920.3327587499043, 920.9953950230273, 921.1571412157867, 560.7056295444884, 573.4809996952764, 654.7741029344868, 664.9667516624198, 678.5250324772679, 16245.431409900715, 16331.18344082803, 16371.263293151718, 16381.344746210138, 16382.344706211738, 14779.92610036943, 15221.391144354213, 15377.923110384489, 15528.689426211413, 16148.515544533682, 14265.95416504966, 14371.13773173613, 14467.348969296407, 14517.637059073502, 14544.418223271057, 4908.067467181224, 4934.728589927553, 4972.453030166679, 5005.524475174852, 5075.5178258065425, 59957.40042599562, 60382.39617603811, 64139.67930160366, 87373.5631321852, 88675.55662221648, 11492.655220343404, 12471.75056498865, 12666.80999785004, 12730.23618582888, 12853.93573032129, 21991.560168796535, 22283.220087296868, 22294.88852555759, 22447.551048979254, 22453.43866403353, 8809.42738721994, 8844.646214151491, 8931.285497160212, 8960.955195223982, 8962.86555701665, 4549.226631472658, 4753.762311884461, 4866.0511200316305, 4915.0415668944925, 4928.334674818853, 3722.236941427001, 3834.559025712023, 3834.884953451401, 3838.05967537955, 3843.3082045231567, 635.7806556737918, 648.516854943061, 650.5673726971605, 662.192125606759, 666.6733300682631, 628.7045088808253, 666.2970566052861, 672.2135101930753, 708.7944496096178, 714.6391072508454, 9006.234470069987, 9729.367591106555, 9846.95076524627, 10033.347832391017, 10381.0, 650.4861159683796, 655.1188651264042, 663.8473151175257, 668.6456178225549, 728.508256926583, 4643.117807616542, 4759.357486739246, 4778.808847646055, 4819.638527110481, 4821.879453013633, 72986.0, 74237.6288118556, 99953.0, 130319.0, 134358.0, 107030.0, 112899.0, 113817.43091284657, 114061.429692851, 115828.84171158349, 94111.0, 94241.0, 95824.52087739516, 95913.52043239879, 98506.50746746364, 8915.675897189987, 9364.812703746024, 9437.858432123661, 9439.292053095907, 9528.904710952802, 55626.0, 56390.0, 56481.0, 56606.43393566012, 56984.0, 46538.30192547047, 53983.0, 79528.0, 80389.59805200937, 80962.59518702369, 12587.181833180814, 12592.244465332109, 12627.81058284108, 13096.607101786962, 13174.868251317748, 13623.318834058457, 13733.244671543162, 13749.243791591558, 14005.579832605039, 14191.361388737738, 12385.93807030959, 12453.688657783629, 12651.93674031624, 12863.935680321538, 13368.732625347633, 12200.81698774537, 12363.381830908598, 12794.0, 12910.870891291343, 13030.869691302965, 11267.492962816857, 11451.083913287084, 11538.192326537397, 12872.479081475336, 12920.22478651284, 10094.54574544162, 10254.436006019741, 10358.430286334324, 10497.422641754776, 10567.52446139941, 9780.80438391214, 10410.219233557362, 10632.574697012033, 10797.730056748644, 11010.559577616805], "value": 7776.172485538116, "unit": "score"}], "name": "dromaeo_css", "value": 7567.077755171824, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 110.0, 110.0, 111.0, 110.0, 110.0, 110.0, 109.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 120.0, 118.0, 154.0, 121.0, 124.0, 120.0, 126.0, 164.0, 119.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 161.0, 160.0, 160.0, 162.0, 160.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 124.0, 76.0, 77.0, 78.0, 76.0, 99.0, 75.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 112.0, 84.0, 82.0, 81.0, 102.0, 81.0, 83.0, 109.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 118.0, 116.0, 118.0, 118.0, 119.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 144.0, 146.0, 147.0, 146.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 49.0, 48.0, 49.0, 48.0, 49.0, 50.0, 48.0, 49.0, 49.0], "value": 48.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 60.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 79.0, 75.0, 73.0, 81.0, 73.0, 73.0, 82.0, 73.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 121.0, 130.0, 123.0, 120.0, 123.0, 127.0, 130.0, 169.0, 124.0], "value": 129.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 153.0, 150.0, 155.0, 155.0, 161.0, 152.0, 151.0, 156.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 64.0, 62.0, 62.0, 61.0, 62.0, 60.0, 60.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1460.7, "alertThreshold": 2.0}]} 14:17:31 INFO - Return code: 0 14:17:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:17:31 INFO - # TBPL SUCCESS # 14:17:31 INFO - Running post-action listener: _resource_record_post_action 14:17:31 INFO - [mozharness: 2016-10-27 21:17:31.585516Z] Finished run-tests step (success) 14:17:31 INFO - Running post-run listener: _resource_record_post_run 14:17:31 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 2002944; Write bytes: 360480768; Read time: 80; Write time: 307908 14:17:31 INFO - TinderboxPrint: CPU usage
12.9% 14:17:31 INFO - TinderboxPrint: I/O read bytes / time
2,002,944 / 80 14:17:31 INFO - TinderboxPrint: I/O write bytes / time
360,480,768 / 307,908 14:17:31 INFO - TinderboxPrint: CPU idle
8,268.7 (87.1%) 14:17:31 INFO - TinderboxPrint: CPU user
1,185.4 (12.5%) 14:17:31 INFO - TinderboxPrint: Swap in / out
0 / 0 14:17:31 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110903296; Read time: 0; Write time: 107728 14:17:31 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249004032; Read time: 24; Write time: 200144 14:17:32 INFO - Running post-run listener: _upload_blobber_files 14:17:32 INFO - Blob upload gear active. 14:17:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:17:32 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 14:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:17:32 INFO - (blobuploader) - INFO - Open directory for files ... 14:17:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:17:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:17:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:17:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:17:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:17:33 INFO - (blobuploader) - INFO - Done attempting. 14:17:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:17:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:17:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:17:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:17:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:17:34 INFO - (blobuploader) - INFO - Done attempting. 14:17:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:17:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:17:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:17:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:17:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:17:37 INFO - (blobuploader) - INFO - Done attempting. 14:17:37 INFO - (blobuploader) - INFO - Iteration through files over. 14:17:37 INFO - Return code: 0 14:17:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:17:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:17:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8144cee6442a9e80471b2053e0c5341e22dd55ab7e6565c1b98a0b1a5f7abb0d3bdbe423bff0d5d0173b88dab3859014f50fadd0bba95dff90156a3e4f1a330e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac49446449e42e7cd2054b8b50e03c424d762c123c75cdd62448803d18bde160090e9ee3772ff910495d1c50e212a816c74eca9c6dbb99b32598adaf0b0c6a03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/544a651a26ff51313a80741e1a5419bf9b4a2050731e88d342b92356c351b070d387414e5af91bc29a4800663a3df1c1a4bd8d92f2d2579ec7bb4e57a47d97a5"} 14:17:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:17:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:17:37 INFO - Contents: 14:17:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8144cee6442a9e80471b2053e0c5341e22dd55ab7e6565c1b98a0b1a5f7abb0d3bdbe423bff0d5d0173b88dab3859014f50fadd0bba95dff90156a3e4f1a330e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac49446449e42e7cd2054b8b50e03c424d762c123c75cdd62448803d18bde160090e9ee3772ff910495d1c50e212a816c74eca9c6dbb99b32598adaf0b0c6a03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/544a651a26ff51313a80741e1a5419bf9b4a2050731e88d342b92356c351b070d387414e5af91bc29a4800663a3df1c1a4bd8d92f2d2579ec7bb4e57a47d97a5"} 14:17:37 INFO - Running post-run listener: copy_logs_to_upload_dir 14:17:37 INFO - Copying logs to upload dir... 14:17:37 INFO - mkdir: /builds/slave/test/build/upload/logs 14:17:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.845943 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-10-27 14:17:37.760202) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 14:17:37.763979) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8144cee6442a9e80471b2053e0c5341e22dd55ab7e6565c1b98a0b1a5f7abb0d3bdbe423bff0d5d0173b88dab3859014f50fadd0bba95dff90156a3e4f1a330e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac49446449e42e7cd2054b8b50e03c424d762c123c75cdd62448803d18bde160090e9ee3772ff910495d1c50e212a816c74eca9c6dbb99b32598adaf0b0c6a03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/544a651a26ff51313a80741e1a5419bf9b4a2050731e88d342b92356c351b070d387414e5af91bc29a4800663a3df1c1a4bd8d92f2d2579ec7bb4e57a47d97a5"} build_url:https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011205 build_url: 'https://queue.taskcluster.net/v1/task/O26sSEFjQ9qbNdHdasX1_A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8144cee6442a9e80471b2053e0c5341e22dd55ab7e6565c1b98a0b1a5f7abb0d3bdbe423bff0d5d0173b88dab3859014f50fadd0bba95dff90156a3e4f1a330e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac49446449e42e7cd2054b8b50e03c424d762c123c75cdd62448803d18bde160090e9ee3772ff910495d1c50e212a816c74eca9c6dbb99b32598adaf0b0c6a03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/544a651a26ff51313a80741e1a5419bf9b4a2050731e88d342b92356c351b070d387414e5af91bc29a4800663a3df1c1a4bd8d92f2d2579ec7bb4e57a47d97a5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 14:17:37.793605) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 14:17:37.793930) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1477601822.465494-43600638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004417 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 14:17:37.877271) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 14:17:37.877601) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 14:17:37.877890) ========= ========= Total master_lag: 0.19 =========