builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1478834776.86 results: success (0) buildid: 20161110163420 builduid: 466c067fb17f42d8aaf0d00352dabd78 revision: ea8a661310c1a210f9a09daed46186271cc54991 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:16.865104) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:16.865581) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:16.865923) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:16.880980) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:16.881302) ========= 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=3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-10 19:26:17-- 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% 221M=0s 2016-11-10 19:26:17 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082278 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:16.978484) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:16.978900) ========= 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=3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013846 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:17.028500) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:17.028878) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ea8a661310c1a210f9a09daed46186271cc54991 --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 ea8a661310c1a210f9a09daed46186271cc54991 --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=3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-10 19:26:17,985 truncating revision to first 12 chars 2016-11-10 19:26:17,985 Setting DEBUG logging. 2016-11-10 19:26:17,985 attempt 1/10 2016-11-10 19:26:17,985 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ea8a661310c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-10 19:26:18,381 unpacking tar archive at: mozilla-aurora-ea8a661310c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.742655 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:17.786308) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:17.786634) ========= script_repo_revision: ea8a661310c1a210f9a09daed46186271cc54991 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:17.787103) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:17.787419) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 19:26:17.796524) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-11-10 19:26:17.796890) ========= /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=3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:26:18 INFO - MultiFileLogger online at 20161110 19:26:18 in /builds/slave/test 19:26:18 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 19:26:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:26:18 INFO - {'append_to_log': False, 19:26:18 INFO - 'base_work_dir': '/builds/slave/test', 19:26:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:26:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:26:18 INFO - 'branch': 'Mozilla-Aurora', 19:26:18 INFO - 'buildbot_json_path': 'buildprops.json', 19:26:18 INFO - 'config_files': ('talos/linux_config.py',), 19:26:18 INFO - 'default_actions': ('clobber', 19:26:18 INFO - 'read-buildbot-config', 19:26:18 INFO - 'download-and-extract', 19:26:18 INFO - 'populate-webroot', 19:26:18 INFO - 'create-virtualenv', 19:26:18 INFO - 'install', 19:26:18 INFO - 'run-tests'), 19:26:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:26:18 INFO - 'download_minidump_stackwalk': True, 19:26:18 INFO - 'download_symbols': 'ondemand', 19:26:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:26:18 INFO - 'tooltool.py': '/tools/tooltool.py', 19:26:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:26:18 INFO - '/tools/misc-python/virtualenv.py')}, 19:26:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:26:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:26:18 INFO - 'installer_path': 'installer.exe', 19:26:18 INFO - 'log_level': 'info', 19:26:18 INFO - 'log_name': 'talos', 19:26:18 INFO - 'log_to_console': True, 19:26:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:26:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:26:18 INFO - 'opt_config_files': (), 19:26:18 INFO - 'pip_index': False, 19:26:18 INFO - 'sps_profile': False, 19:26:18 INFO - 'sps_profile_interval': 0, 19:26:18 INFO - 'suite': 'dromaeojs', 19:26:18 INFO - 'system_bits': '32', 19:26:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:26:18 INFO - 'title': 'talos-linux64-ix-059', 19:26:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:26:18 INFO - 'use_talos_json': True, 19:26:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:26:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:26:18 INFO - 'work_dir': 'build'} 19:26:18 INFO - [mozharness: 2016-11-11 03:26:18.852213Z] Running clobber step. 19:26:18 INFO - Running pre-action listener: _resource_record_pre_action 19:26:18 INFO - Running main action method: clobber 19:26:18 INFO - rmtree: /builds/slave/test/build 19:26:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:26:19 INFO - Running post-action listener: _resource_record_post_action 19:26:19 INFO - [mozharness: 2016-11-11 03:26:19.316849Z] Finished clobber step (success) 19:26:19 INFO - [mozharness: 2016-11-11 03:26:19.316950Z] Running read-buildbot-config step. 19:26:19 INFO - Running pre-action listener: _resource_record_pre_action 19:26:19 INFO - Running main action method: read_buildbot_config 19:26:19 INFO - Using buildbot properties: 19:26:19 INFO - { 19:26:19 INFO - "project": "", 19:26:19 INFO - "product": "firefox", 19:26:19 INFO - "script_repo_revision": "production", 19:26:19 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:26:19 INFO - "repository": "", 19:26:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 19:26:19 INFO - "buildid": "20161110163420", 19:26:19 INFO - "pgo_build": "True", 19:26:19 INFO - "basedir": "/builds/slave/test", 19:26:19 INFO - "buildnumber": 882, 19:26:19 INFO - "slavename": "talos-linux64-ix-059", 19:26:19 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:26:19 INFO - "platform": "ubuntu64_hw", 19:26:19 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:26:19 INFO - "branch": "mozilla-aurora", 19:26:19 INFO - "repo_path": "releases/mozilla-aurora", 19:26:19 INFO - "revision": "ea8a661310c1a210f9a09daed46186271cc54991", 19:26:19 INFO - "stage_platform": "linux64-pgo", 19:26:19 INFO - "builduid": "466c067fb17f42d8aaf0d00352dabd78", 19:26:19 INFO - "slavebuilddir": "test" 19:26:19 INFO - } 19:26:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 19:26:19 INFO - Running post-action listener: _resource_record_post_action 19:26:19 INFO - [mozharness: 2016-11-11 03:26:19.319423Z] Finished read-buildbot-config step (success) 19:26:19 INFO - [mozharness: 2016-11-11 03:26:19.319511Z] Running download-and-extract step. 19:26:19 INFO - Running pre-action listener: _resource_record_pre_action 19:26:19 INFO - Running main action method: download_and_extract 19:26:19 INFO - mkdir: /builds/slave/test/build/tests 19:26:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:19 INFO - trying https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:26:19 INFO - Downloading https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/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 19:26:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/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 19:26:19 INFO - Downloaded 1361 bytes. 19:26:19 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:26:19 INFO - Using the following test package requirements: 19:26:19 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 19:26:19 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:26:19 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:26:19 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:26:19 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:26:19 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:26:19 INFO - u'jsshell-linux-x86_64.zip'], 19:26:19 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:26:19 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:26:19 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 19:26:19 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:26:19 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:26:19 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:26:19 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:26:19 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:26:19 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:26:19 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:26:19 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:26:19 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:26:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 19:26:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:26:19 INFO - Fetch https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 19:26:20 INFO - Expected file size: 21897011 19:26:24 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 19:26:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 19:26:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:26:24 INFO - Fetch https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 19:26:25 INFO - Expected file size: 21897011 19:26:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 19:26:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:26:29 INFO - Fetch https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 19:26:30 INFO - Expected file size: 14414089 19:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:31 INFO - trying https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:26:31 INFO - Downloading https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/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 19:26:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/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 19:26:32 INFO - Downloaded 64031530 bytes. 19:26:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:26:32 INFO - mkdir: /builds/slave/test/properties 19:26:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:26:32 INFO - Writing to file /builds/slave/test/properties/build_url 19:26:32 INFO - Contents: 19:26:32 INFO - build_url:https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:26:32 INFO - Running post-action listener: _resource_record_post_action 19:26:32 INFO - Running post-action listener: set_extra_try_arguments 19:26:32 INFO - [mozharness: 2016-11-11 03:26:32.828843Z] Finished download-and-extract step (success) 19:26:32 INFO - [mozharness: 2016-11-11 03:26:32.829143Z] Running populate-webroot step. 19:26:32 INFO - Running pre-action listener: _resource_record_pre_action 19:26:32 INFO - Running main action method: populate_webroot 19:26:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:26:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:26:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:26:32 INFO - '--tppagecycles', 19:26:32 INFO - '7'], 19:26:32 INFO - 'tests': ['tsvgm']}}, 19:26:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:26:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:26:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:26:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:26:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['damp', 'tps']}, 19:26:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:32 INFO - 'tests': ['damp', 'tps']}, 19:26:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['dromaeo_dom']}, 19:26:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:26:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['basic_compositor_video']}, 19:26:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:26:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other-e10s': {'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tsvgx', 19:26:32 INFO - 'tsvgr_opacity', 19:26:32 INFO - 'tart', 19:26:32 INFO - 'tscrollx', 19:26:32 INFO - 'cart']}, 19:26:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:26:32 INFO - 'tsvgr_opacity', 19:26:32 INFO - 'tart', 19:26:32 INFO - 'tscrollx', 19:26:32 INFO - 'cart']}, 19:26:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tp5o']}, 19:26:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:32 INFO - 'tests': ['tp5o']}, 19:26:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s', 19:26:32 INFO - '--xperf_path', 19:26:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:32 INFO - 'tests': ['tp5n']}, 19:26:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:32 INFO - 'talos_options': ['--xperf_path', 19:26:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:32 INFO - 'tests': ['tp5n']}}, 19:26:32 INFO - 'talos.zip': {'path': '', 19:26:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:26:32 INFO - Running post-action listener: _resource_record_post_action 19:26:32 INFO - [mozharness: 2016-11-11 03:26:32.852681Z] Finished populate-webroot step (success) 19:26:32 INFO - [mozharness: 2016-11-11 03:26:32.852775Z] Running create-virtualenv step. 19:26:32 INFO - Running pre-action listener: _resource_record_pre_action 19:26:32 INFO - Running main action method: create_virtualenv 19:26:32 INFO - Creating virtualenv /builds/slave/test/build/venv 19:26:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:26:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:26:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:26:32 INFO - Using real prefix '/usr' 19:26:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:26:33 INFO - Installing distribute.............................................................................................................................................................................................done. 19:26:35 INFO - Installing pip.................done. 19:26:35 INFO - Return code: 0 19:26:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4a03224f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', '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 19:26:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:26:35 INFO - Using env: {'DISPLAY': ':0', 19:26:35 INFO - 'HOME': '/home/cltbld', 19:26:35 INFO - 'LANG': 'en_US.UTF-8', 19:26:35 INFO - 'LANGUAGE': 'en_US:en', 19:26:35 INFO - 'LOGNAME': 'cltbld', 19:26:35 INFO - 'MAIL': '/var/mail/cltbld', 19:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:35 INFO - 'MOZ_NO_REMOTE': '1', 19:26:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:35 INFO - 'NO_EM_RESTART': '1', 19:26:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:35 INFO - 'PWD': '/builds/slave/test', 19:26:35 INFO - 'SHELL': '/bin/bash', 19:26:35 INFO - 'SHLVL': '1', 19:26:35 INFO - 'TERM': 'linux', 19:26:35 INFO - 'TMOUT': '86400', 19:26:35 INFO - 'USER': 'cltbld', 19:26:35 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:35 INFO - '_': '/tools/buildbot/bin/python'} 19:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:26:35 INFO - Cleaning up... 19:26:35 INFO - Return code: 0 19:26:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4a03224f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', '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 19:26:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:26:35 INFO - Using env: {'DISPLAY': ':0', 19:26:35 INFO - 'HOME': '/home/cltbld', 19:26:35 INFO - 'LANG': 'en_US.UTF-8', 19:26:35 INFO - 'LANGUAGE': 'en_US:en', 19:26:35 INFO - 'LOGNAME': 'cltbld', 19:26:35 INFO - 'MAIL': '/var/mail/cltbld', 19:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:35 INFO - 'MOZ_NO_REMOTE': '1', 19:26:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:35 INFO - 'NO_EM_RESTART': '1', 19:26:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:35 INFO - 'PWD': '/builds/slave/test', 19:26:35 INFO - 'SHELL': '/bin/bash', 19:26:35 INFO - 'SHLVL': '1', 19:26:35 INFO - 'TERM': 'linux', 19:26:35 INFO - 'TMOUT': '86400', 19:26:35 INFO - 'USER': 'cltbld', 19:26:35 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:35 INFO - '_': '/tools/buildbot/bin/python'} 19:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:35 INFO - Downloading/unpacking psutil>=3.1.1 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:26:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:37 INFO - Installing collected packages: psutil 19:26:37 INFO - Running setup.py install for psutil 19:26:37 INFO - building 'psutil._psutil_linux' extension 19:26:37 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 19:26:38 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 19:26:38 INFO - building 'psutil._psutil_posix' extension 19:26:38 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 19:26:38 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 19:26:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:38 INFO - Successfully installed psutil 19:26:38 INFO - Cleaning up... 19:26:38 INFO - Return code: 0 19:26:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4a03224f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', '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 19:26:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:26:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:26:38 INFO - Using env: {'DISPLAY': ':0', 19:26:38 INFO - 'HOME': '/home/cltbld', 19:26:38 INFO - 'LANG': 'en_US.UTF-8', 19:26:38 INFO - 'LANGUAGE': 'en_US:en', 19:26:38 INFO - 'LOGNAME': 'cltbld', 19:26:38 INFO - 'MAIL': '/var/mail/cltbld', 19:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:38 INFO - 'MOZ_NO_REMOTE': '1', 19:26:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:38 INFO - 'NO_EM_RESTART': '1', 19:26:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:38 INFO - 'PWD': '/builds/slave/test', 19:26:38 INFO - 'SHELL': '/bin/bash', 19:26:38 INFO - 'SHLVL': '1', 19:26:38 INFO - 'TERM': 'linux', 19:26:38 INFO - 'TMOUT': '86400', 19:26:38 INFO - 'USER': 'cltbld', 19:26:38 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:38 INFO - '_': '/tools/buildbot/bin/python'} 19:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:26:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:26:40 INFO - Installing collected packages: mozsystemmonitor 19:26:40 INFO - Running setup.py install for mozsystemmonitor 19:26:40 INFO - Successfully installed mozsystemmonitor 19:26:40 INFO - Cleaning up... 19:26:40 INFO - Return code: 0 19:26:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:40 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 0x7f4a03224f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', '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 19:26:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:26:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:26:40 INFO - Using env: {'DISPLAY': ':0', 19:26:40 INFO - 'HOME': '/home/cltbld', 19:26:40 INFO - 'LANG': 'en_US.UTF-8', 19:26:40 INFO - 'LANGUAGE': 'en_US:en', 19:26:40 INFO - 'LOGNAME': 'cltbld', 19:26:40 INFO - 'MAIL': '/var/mail/cltbld', 19:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:40 INFO - 'MOZ_NO_REMOTE': '1', 19:26:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:40 INFO - 'NO_EM_RESTART': '1', 19:26:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:40 INFO - 'PWD': '/builds/slave/test', 19:26:40 INFO - 'SHELL': '/bin/bash', 19:26:40 INFO - 'SHLVL': '1', 19:26:40 INFO - 'TERM': 'linux', 19:26:40 INFO - 'TMOUT': '86400', 19:26:40 INFO - 'USER': 'cltbld', 19:26:40 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:40 INFO - '_': '/tools/buildbot/bin/python'} 19:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:40 INFO - Downloading/unpacking jsonschema==2.5.1 19:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:26:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:43 INFO - Downloading functools32-3.2.3-2.tar.gz 19:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:26:43 INFO - warning: no files found matching '*.txt' 19:26:43 INFO - no previously-included directories found matching 'build' 19:26:43 INFO - no previously-included directories found matching 'dist' 19:26:43 INFO - no previously-included directories found matching '.git*' 19:26:43 INFO - Installing collected packages: jsonschema, functools32 19:26:43 INFO - Running setup.py install for functools32 19:26:43 INFO - warning: no files found matching '*.txt' 19:26:43 INFO - no previously-included directories found matching 'build' 19:26:43 INFO - no previously-included directories found matching 'dist' 19:26:43 INFO - no previously-included directories found matching '.git*' 19:26:43 INFO - Successfully installed jsonschema functools32 19:26:43 INFO - Cleaning up... 19:26:43 INFO - Return code: 0 19:26:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:43 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 0x7f4a03224f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', '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 19:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:26:43 INFO - Using env: {'DISPLAY': ':0', 19:26:43 INFO - 'HOME': '/home/cltbld', 19:26:43 INFO - 'LANG': 'en_US.UTF-8', 19:26:43 INFO - 'LANGUAGE': 'en_US:en', 19:26:43 INFO - 'LOGNAME': 'cltbld', 19:26:43 INFO - 'MAIL': '/var/mail/cltbld', 19:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:43 INFO - 'MOZ_NO_REMOTE': '1', 19:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:43 INFO - 'NO_EM_RESTART': '1', 19:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:43 INFO - 'PWD': '/builds/slave/test', 19:26:43 INFO - 'SHELL': '/bin/bash', 19:26:43 INFO - 'SHLVL': '1', 19:26:43 INFO - 'TERM': 'linux', 19:26:43 INFO - 'TMOUT': '86400', 19:26:43 INFO - 'USER': 'cltbld', 19:26:43 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:43 INFO - '_': '/tools/buildbot/bin/python'} 19:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:26:43 INFO - Cleaning up... 19:26:43 INFO - Return code: 0 19:26:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:43 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 0x7f4a03224f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', '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 19:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:26:43 INFO - Using env: {'DISPLAY': ':0', 19:26:43 INFO - 'HOME': '/home/cltbld', 19:26:43 INFO - 'LANG': 'en_US.UTF-8', 19:26:43 INFO - 'LANGUAGE': 'en_US:en', 19:26:43 INFO - 'LOGNAME': 'cltbld', 19:26:43 INFO - 'MAIL': '/var/mail/cltbld', 19:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:43 INFO - 'MOZ_NO_REMOTE': '1', 19:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:43 INFO - 'NO_EM_RESTART': '1', 19:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:43 INFO - 'PWD': '/builds/slave/test', 19:26:43 INFO - 'SHELL': '/bin/bash', 19:26:43 INFO - 'SHLVL': '1', 19:26:43 INFO - 'TERM': 'linux', 19:26:43 INFO - 'TMOUT': '86400', 19:26:43 INFO - 'USER': 'cltbld', 19:26:43 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:43 INFO - '_': '/tools/buildbot/bin/python'} 19:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:43 INFO - Downloading/unpacking blobuploader==1.2.4 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:45 INFO - Downloading blobuploader-1.2.4.tar.gz 19:26:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:26:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:26:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:46 INFO - Downloading docopt-0.6.1.tar.gz 19:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:26:46 INFO - Installing collected packages: blobuploader, requests, docopt 19:26:46 INFO - Running setup.py install for blobuploader 19:26:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:26:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:26:46 INFO - Running setup.py install for requests 19:26:46 INFO - Running setup.py install for docopt 19:26:47 INFO - Successfully installed blobuploader requests docopt 19:26:47 INFO - Cleaning up... 19:26:47 INFO - Return code: 0 19:26:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4a03224f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', '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 19:26:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:26:47 INFO - Using env: {'DISPLAY': ':0', 19:26:47 INFO - 'HOME': '/home/cltbld', 19:26:47 INFO - 'LANG': 'en_US.UTF-8', 19:26:47 INFO - 'LANGUAGE': 'en_US:en', 19:26:47 INFO - 'LOGNAME': 'cltbld', 19:26:47 INFO - 'MAIL': '/var/mail/cltbld', 19:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:47 INFO - 'MOZ_NO_REMOTE': '1', 19:26:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:47 INFO - 'NO_EM_RESTART': '1', 19:26:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:47 INFO - 'PWD': '/builds/slave/test', 19:26:47 INFO - 'SHELL': '/bin/bash', 19:26:47 INFO - 'SHLVL': '1', 19:26:47 INFO - 'TERM': 'linux', 19:26:47 INFO - 'TMOUT': '86400', 19:26:47 INFO - 'USER': 'cltbld', 19:26:47 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:47 INFO - '_': '/tools/buildbot/bin/python'} 19:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:47 INFO - Running setup.py (path:/tmp/pip-BeQACl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:47 INFO - Running setup.py (path:/tmp/pip-Ae3dQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:47 INFO - Running setup.py (path:/tmp/pip-0AF4qA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:47 INFO - Running setup.py (path:/tmp/pip-ytzdge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:47 INFO - Running setup.py (path:/tmp/pip-6Bn3u0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:47 INFO - Running setup.py (path:/tmp/pip-NlDJKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:47 INFO - Running setup.py (path:/tmp/pip-76didT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:47 INFO - Running setup.py (path:/tmp/pip-84uZMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:47 INFO - Running setup.py (path:/tmp/pip-Flqu7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:47 INFO - Running setup.py (path:/tmp/pip-AqcQmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:48 INFO - Running setup.py (path:/tmp/pip-u7Nqrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:48 INFO - Running setup.py (path:/tmp/pip-3Dynj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:48 INFO - Running setup.py (path:/tmp/pip-M_s3dg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:48 INFO - Running setup.py (path:/tmp/pip-YSd28n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:48 INFO - Running setup.py (path:/tmp/pip-oY0okG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:48 INFO - Running setup.py (path:/tmp/pip-qtS2HT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:48 INFO - Running setup.py (path:/tmp/pip-oGxvw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:26:48 INFO - Running setup.py install for manifestparser 19:26:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:26:48 INFO - Running setup.py install for mozcrash 19:26:48 INFO - Running setup.py install for mozdebug 19:26:48 INFO - Running setup.py install for mozdevice 19:26:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:26:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:26:48 INFO - Running setup.py install for mozfile 19:26:49 INFO - Running setup.py install for mozhttpd 19:26:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozinfo 19:26:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozInstall 19:26:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozleak 19:26:49 INFO - Running setup.py install for mozlog 19:26:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for moznetwork 19:26:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozprocess 19:26:49 INFO - Running setup.py install for mozprofile 19:26:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozrunner 19:26:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Running setup.py install for mozscreenshot 19:26:50 INFO - Running setup.py install for moztest 19:26:50 INFO - Running setup.py install for mozversion 19:26:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:26:50 INFO - Cleaning up... 19:26:50 INFO - Return code: 0 19:26:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:50 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 0x7f4a03224f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', '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 19:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:26:50 INFO - Using env: {'DISPLAY': ':0', 19:26:50 INFO - 'HOME': '/home/cltbld', 19:26:50 INFO - 'LANG': 'en_US.UTF-8', 19:26:50 INFO - 'LANGUAGE': 'en_US:en', 19:26:50 INFO - 'LOGNAME': 'cltbld', 19:26:50 INFO - 'MAIL': '/var/mail/cltbld', 19:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:50 INFO - 'MOZ_NO_REMOTE': '1', 19:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:50 INFO - 'NO_EM_RESTART': '1', 19:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:50 INFO - 'PWD': '/builds/slave/test', 19:26:50 INFO - 'SHELL': '/bin/bash', 19:26:50 INFO - 'SHLVL': '1', 19:26:50 INFO - 'TERM': 'linux', 19:26:50 INFO - 'TMOUT': '86400', 19:26:50 INFO - 'USER': 'cltbld', 19:26:50 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:50 INFO - '_': '/tools/buildbot/bin/python'} 19:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:50 INFO - Running setup.py (path:/tmp/pip-lz69kU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:50 INFO - Running setup.py (path:/tmp/pip-WdmCx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:50 INFO - Running setup.py (path:/tmp/pip-DL0SeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:50 INFO - Running setup.py (path:/tmp/pip-Pf4v2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:50 INFO - Running setup.py (path:/tmp/pip-P0R5BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:50 INFO - Running setup.py (path:/tmp/pip-ArguMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:51 INFO - Running setup.py (path:/tmp/pip-mVxnyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:51 INFO - Running setup.py (path:/tmp/pip-4xd_eO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:51 INFO - Running setup.py (path:/tmp/pip-DxyK5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:51 INFO - Running setup.py (path:/tmp/pip-2wDDk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:51 INFO - Running setup.py (path:/tmp/pip-v82wPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:51 INFO - Running setup.py (path:/tmp/pip-FaelSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:51 INFO - Running setup.py (path:/tmp/pip-xmA3zZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:51 INFO - Running setup.py (path:/tmp/pip-lwuhfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:51 INFO - Running setup.py (path:/tmp/pip-Vstvmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:51 INFO - Running setup.py (path:/tmp/pip-IOFp48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:51 INFO - Running setup.py (path:/tmp/pip-3h6hM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:26:51 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)) 19:26:51 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)) 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:26:51 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)) 19:26:51 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)) 19:26:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:53 INFO - Downloading blessings-1.6.tar.gz 19:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:26:53 INFO - Installing collected packages: blessings 19:26:53 INFO - Running setup.py install for blessings 19:26:53 INFO - Successfully installed blessings 19:26:53 INFO - Cleaning up... 19:26:54 INFO - Return code: 0 19:26:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:26:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:54 INFO - Reading from file tmpfile_stdout 19:26:54 INFO - Current package versions: 19:26:54 INFO - argparse == 1.2.1 19:26:54 INFO - blessings == 1.6 19:26:54 INFO - blobuploader == 1.2.4 19:26:54 INFO - docopt == 0.6.1 19:26:54 INFO - functools32 == 3.2.3-2 19:26:54 INFO - jsonschema == 2.5.1 19:26:54 INFO - manifestparser == 1.1 19:26:54 INFO - mozInstall == 1.12 19:26:54 INFO - mozcrash == 0.17 19:26:54 INFO - mozdebug == 0.1 19:26:54 INFO - mozdevice == 0.48 19:26:54 INFO - mozfile == 1.2 19:26:54 INFO - mozhttpd == 0.7 19:26:54 INFO - mozinfo == 0.9 19:26:54 INFO - mozleak == 0.1 19:26:54 INFO - mozlog == 3.4 19:26:54 INFO - moznetwork == 0.27 19:26:54 INFO - mozprocess == 0.23 19:26:54 INFO - mozprofile == 0.28 19:26:54 INFO - mozrunner == 6.12 19:26:54 INFO - mozscreenshot == 0.1 19:26:54 INFO - mozsystemmonitor == 0.3 19:26:54 INFO - moztest == 0.7 19:26:54 INFO - mozversion == 1.4 19:26:54 INFO - psutil == 3.1.1 19:26:54 INFO - requests == 1.2.3 19:26:54 INFO - wsgiref == 0.1.2 19:26:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 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 0x7f4a03224f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', '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 19:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:26:54 INFO - Using env: {'DISPLAY': ':0', 19:26:54 INFO - 'HOME': '/home/cltbld', 19:26:54 INFO - 'LANG': 'en_US.UTF-8', 19:26:54 INFO - 'LANGUAGE': 'en_US:en', 19:26:54 INFO - 'LOGNAME': 'cltbld', 19:26:54 INFO - 'MAIL': '/var/mail/cltbld', 19:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:54 INFO - 'MOZ_NO_REMOTE': '1', 19:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:54 INFO - 'NO_EM_RESTART': '1', 19:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:54 INFO - 'PWD': '/builds/slave/test', 19:26:54 INFO - 'SHELL': '/bin/bash', 19:26:54 INFO - 'SHLVL': '1', 19:26:54 INFO - 'TERM': 'linux', 19:26:54 INFO - 'TMOUT': '86400', 19:26:54 INFO - 'USER': 'cltbld', 19:26:54 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:54 INFO - '_': '/tools/buildbot/bin/python'} 19:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:26:54 INFO - Cleaning up... 19:26:54 INFO - Return code: 0 19:26:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 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 0x7f4a03224f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, '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': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', '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 19:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:26:54 INFO - Using env: {'DISPLAY': ':0', 19:26:54 INFO - 'HOME': '/home/cltbld', 19:26:54 INFO - 'LANG': 'en_US.UTF-8', 19:26:54 INFO - 'LANGUAGE': 'en_US:en', 19:26:54 INFO - 'LOGNAME': 'cltbld', 19:26:54 INFO - 'MAIL': '/var/mail/cltbld', 19:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:54 INFO - 'MOZ_NO_REMOTE': '1', 19:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:54 INFO - 'NO_EM_RESTART': '1', 19:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:54 INFO - 'PWD': '/builds/slave/test', 19:26:54 INFO - 'SHELL': '/bin/bash', 19:26:54 INFO - 'SHLVL': '1', 19:26:54 INFO - 'TERM': 'linux', 19:26:54 INFO - 'TMOUT': '86400', 19:26:54 INFO - 'USER': 'cltbld', 19:26:54 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:54 INFO - '_': '/tools/buildbot/bin/python'} 19:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:26:54 INFO - Cleaning up... 19:26:54 INFO - Return code: 0 19:26:54 INFO - Running post-action listener: _resource_record_post_action 19:26:54 INFO - Running post-action listener: _start_resource_monitoring 19:26:54 INFO - Starting resource monitoring. 19:26:54 INFO - [mozharness: 2016-11-11 03:26:54.720897Z] Finished create-virtualenv step (success) 19:26:54 INFO - [mozharness: 2016-11-11 03:26:54.721402Z] Running install step. 19:26:54 INFO - Running pre-action listener: _resource_record_pre_action 19:26:54 INFO - Running main action method: install 19:26:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:54 INFO - Reading from file tmpfile_stdout 19:26:54 INFO - Detecting whether we're running mozinstall >=1.0... 19:26:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:26:55 INFO - Reading from file tmpfile_stdout 19:26:55 INFO - Output received: 19:26:55 INFO - Usage: mozinstall [options] installer 19:26:55 INFO - Options: 19:26:55 INFO - -h, --help show this help message and exit 19:26:55 INFO - -d DEST, --destination=DEST 19:26:55 INFO - Directory to install application into. [default: 19:26:55 INFO - "/builds/slave/test"] 19:26:55 INFO - --app=APP Application being installed. [default: firefox] 19:26:55 INFO - mkdir: /builds/slave/test/build/application 19:26:55 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'] 19:26:55 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 19:27:11 INFO - Reading from file tmpfile_stdout 19:27:11 INFO - Output received: 19:27:11 INFO - /builds/slave/test/build/application/firefox/firefox 19:27:11 INFO - Running post-action listener: _resource_record_post_action 19:27:11 INFO - [mozharness: 2016-11-11 03:27:11.019796Z] Finished install step (success) 19:27:11 INFO - [mozharness: 2016-11-11 03:27:11.020042Z] Running run-tests step. 19:27:11 INFO - Running pre-action listener: _resource_record_pre_action 19:27:11 INFO - Running main action method: run_tests 19:27:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:27:11 INFO - Python 2.7.3 19:27:11 INFO - Return code: 0 19:27:11 INFO - grabbing minidump binary from tooltool 19:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:11 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 0x131af60>, '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 0x131bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131c1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:27:11 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 19:27:11 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 19:27:11 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 19:27:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:27:11 INFO - Return code: 0 19:27:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:27:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:27:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:27:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:27:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:27:11 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/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:27:11 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/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:27:11 INFO - Using env: {'DISPLAY': ':0', 19:27:11 INFO - 'HOME': '/home/cltbld', 19:27:11 INFO - 'LANG': 'en_US.UTF-8', 19:27:11 INFO - 'LANGUAGE': 'en_US:en', 19:27:11 INFO - 'LOGNAME': 'cltbld', 19:27:11 INFO - 'MAIL': '/var/mail/cltbld', 19:27:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:27:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:11 INFO - 'MOZ_NO_REMOTE': '1', 19:27:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:27:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:11 INFO - 'NO_EM_RESTART': '1', 19:27:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:11 INFO - 'PWD': '/builds/slave/test', 19:27:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:27:11 INFO - 'SHELL': '/bin/bash', 19:27:11 INFO - 'SHLVL': '1', 19:27:11 INFO - 'TERM': 'linux', 19:27:11 INFO - 'TMOUT': '86400', 19:27:11 INFO - 'USER': 'cltbld', 19:27:11 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270', 19:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:11 INFO - '_': '/tools/buildbot/bin/python'} 19:27:11 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/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:27:11 INFO - mozversion application_buildid: 20161110163420 19:27:11 INFO - mozversion application_changeset: ea8a661310c1a210f9a09daed46186271cc54991 19:27:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:27:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:27:11 INFO - mozversion application_name: Firefox 19:27:11 INFO - mozversion application_remotingname: firefox-dev 19:27:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:27:11 INFO - mozversion application_vendor: Mozilla 19:27:11 INFO - mozversion application_version: 51.0a2 19:27:11 INFO - mozversion platform_buildid: 20161110163420 19:27:11 INFO - mozversion platform_changeset: ea8a661310c1a210f9a09daed46186271cc54991 19:27:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:27:11 INFO - mozversion platform_version: 51.0a2 19:27:11 INFO - using testdate: 1478834831 19:27:11 INFO - actual date: 1478834831 19:27:11 INFO - starting webserver on 'localhost:38122' 19:27:11 INFO - SUITE-START | Running 2 tests 19:27:11 INFO - TEST-START | dromaeo_css 19:27:11 INFO - operating with platform_type : linux_ 19:27:11 INFO - Initialising browser for dromaeo_css test... 19:27:11 INFO - TEST-INFO | started process 14920 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBihJBR/profile http://localhost:38122/getInfo.html) 19:27:15 INFO - PROCESS | 14920 | __metrics Screen width/height:1600/1200 19:27:15 INFO - PROCESS | 14920 | colorDepth:24 19:27:15 INFO - PROCESS | 14920 | Browser inner width/height: 1024/697 19:27:15 INFO - PROCESS | 14920 | __metrics 19:27:15 INFO - PROCESS | 14920 | 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" 19:27:15 INFO - PROCESS | 14920 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:27:15 INFO - PROCESS | 14920 | console.error: PushService: 19:27:15 INFO - PROCESS | 14920 | stateChangeProcessEnqueue: Error transitioning state 19:27:15 INFO - PROCESS | 14920 | UnknownError 19:27:16 INFO - TEST-INFO | 14920: exit 0 19:27:16 INFO - Browser initialized. 19:27:16 INFO - Running cycle 1/1 for dromaeo_css test... 19:27:16 INFO - TEST-INFO | started process 15012 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBihJBR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:28 INFO - PROCESS | 15012 | RSS: Main: 144216064 19:27:28 INFO - PROCESS | 15012 | 19:28:19 INFO - PROCESS | 15012 | 1478834899311 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:30:28 INFO - PROCESS | 15012 | Cycle 1(1): loaded http://localhost:38122/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38122/tests/dromaeo/cssquery-ext.html) 19:30:29 INFO - PROCESS | 15012 | RSS: Main: 242970624 19:30:29 INFO - PROCESS | 15012 | 19:33:31 INFO - PROCESS | 15012 | Cycle 1(1): loaded http://localhost:38122/tests/dromaeo/cssquery-ext.html (next: http://localhost:38122/tests/dromaeo/cssquery-jquery.html) 19:33:32 INFO - PROCESS | 15012 | RSS: Main: 228700160 19:33:32 INFO - PROCESS | 15012 | 19:36:34 INFO - PROCESS | 15012 | Cycle 1(1): loaded http://localhost:38122/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38122/tests/dromaeo/cssquery-mootools.html) 19:36:34 INFO - PROCESS | 15012 | RSS: Main: 223891456 19:36:34 INFO - PROCESS | 15012 | 19:39:36 INFO - PROCESS | 15012 | Cycle 1(1): loaded http://localhost:38122/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38122/tests/dromaeo/cssquery-prototype.html) 19:39:37 INFO - PROCESS | 15012 | RSS: Main: 175099904 19:39:37 INFO - PROCESS | 15012 | 19:42:40 INFO - PROCESS | 15012 | Cycle 1(1): loaded http://localhost:38122/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38122/tests/dromaeo/cssquery-yui.html) 19:42:40 INFO - PROCESS | 15012 | RSS: Main: 176570368 19:42:40 INFO - PROCESS | 15012 | 19:45:43 INFO - PROCESS | 15012 | Cycle 1(1): loaded http://localhost:38122/tests/dromaeo/cssquery-yui.html (next: http://localhost:38122/tests/dromaeo/cssquery-dojo.html) 19:45:43 INFO - PROCESS | 15012 | RSS: Main: 265351168 19:45:43 INFO - PROCESS | 15012 | 19:45:43 INFO - PROCESS | 15012 | __start_tp_report 19:45:43 INFO - PROCESS | 15012 | _x_x_mozilla_page_load 19:45:43 INFO - PROCESS | 15012 | _x_x_mozilla_page_load_details 19:45:43 INFO - PROCESS | 15012 | |i|pagename|runs| 19:45:43 INFO - PROCESS | 15012 | |0;dojo.html;3085.1207405889318;3120.141960960736;3125.7811953163286;3149.7952633078835;3169.1918560767;5412.107002344614;5527.5301599364;5531.3362396512475;5542.251796007537;5553.666779993203;5449.509544141037;5536.446355364462;5539.362973258077;5544.889102217953;5553.333599967999;4974.42794078682;5000.849974500771;5027.698338099707;5034.1945288753805;5106.259592359106;4969.279454479099;5035.219540971156;5089.3129427527365;5092.337996060525;5093.567046801027;4943.382077240345;4997.800087996494;5009.899802003958;5047.949520504784;5063.6202284828605;5042.142835717937;5062.721550314731;5066.670666406709;5124.846254612367;5182.844514664566;4971.0803501352;5055.266986287004;5088.720120393377;5110.948890511085;5120.436751957282;5375.973120134413;5418.7019713915715;5434.402215756264;5479.945200547983;5488.506034456878;5945.435183657545;5959.582829201958;5963.970180149072;5968.253968253968;5971.4924231439945;6006.819795406145;6009.188759517453;6020.036794112921;6020.729067191987;6026.035834266497;5361.5978801589645;5415.431379705114;5486.725663716838;5569.387367389584;5575.609707320448;6141.692915354304;6147.508199344042;6158.137860699506;6158.661273629904;6159.260888693386;5907.409259074058;5908.409159084057;5910.940890591039;5911;5938.257717785277;5673.943260567424;5814.651120932757;5819;5960.493358064613;5990.820275391745;5996.640201587918;6032.638041717511;6051.243594550681;6070.6357618543025;6076.058210977306;6060.060690592965;6061.696915154224;6062.939370606326;6070.514358851281;6093.329733729286;4474.105178964221;4507.616852567503;4511.729296242236;4662.7668616569035;4664.137134630104;3999.9000024999027;4056.6957478189256;4091.959080409217;4097.016715988141;4105.938410923838;5657.15142728594;5662.320521537442;5663.801766938137;5665.6034077614995;5668.376478587352;5794.768209271666;5838.036723940587;5911.231539899785;5912.615679980788;6000.03999360109;5652.406497317723;5734.741936612862;5737.827865164052;5745.3105627324985;5787.681677507693;5250.028744682246;5251.632385733038;5258.842234732964;5259.237410575445;5261.026710058575;5989.011813050885;6035.668038257938;6270.2475702915945;6277.748890044439;6282.026285925689;5951.91072133927;5979.820605381846;6036.577439579188;6038.577299589162;6039.6678182698615;6179.783707570214;6179.783707570214;6181.258249010148;6182.752689892533;6183.783567575113;5637.802676906289;5823.767049317979;5835.3581106077445;5874.206982057539;5893.970530147321;5772.220750198838;5855.502282306052;5883.646981180972;5888.352281249145;6020.4280593343565;5848.590598658053;5855.912161317498;5860;5860.0330945395235;5866.2960444745195;5836;5837.620554663933;5839.649621022752;5839.912401313899;5848.970755146197;5657.12314591239;5670.206171136127;5742.253507044057;5765.682887441231;5773.220615216892;5891.646501209942;5941.643501389931;5942.851428714317;5954.166416701752;5962.284525856925;5639;5746.741396637245;5812.564057695774;5826.883462330815;5828.562857785599 19:45:43 INFO - PROCESS | 15012 | |1;ext.html;595.309441048384;606.5966132521871;622.9532785041126;636.4462917261983;652.827000844776;35654.286914261735;39020;39217.41173882394;47726.28410573844;48802.75598622002;50538;50881;50881.23678144831;50938.74530627351;51003;9387.57755900986;9545.761355966122;9549.379290346105;9556.61773529058;9559.522023898811;8630.525321107336;8846.159614836612;9320.81358372832;11016.614418495354;11292.040176585;11426.143039272047;11484.942575287112;11494.655160345203;11500.137489688264;11513.769724605503;11210.103191744682;11297.322160670386;11307.49116289769;11333.829992550036;11353.545858165664;8419.410641255115;8654.78363040929;10763.569457221702;11093.112550995982;11104.555817767361;46110;46492.302615460794;46534.06931861344;46615.766921165516;46632;3886.4753258310047;4025.1748391579654;4290.292101803198;4355.978220108911;4409.074094440171;35680.643193567994;36073.81963090194;36287.274254515025;37934.81032594847;38180.61819381826;4402.1855956647805;4428.601425871687;4451.710638808499;4480.193565158303;4484.304932735431;391755;397320;397753;398159;399073;68253.3174668247;68697;68744.31255687379;68758.65620671865;68808;72720;72792;72831.63584182151;72843.27156728471;72878;19749.40751777449;19994.70007949882;20033.09851056706;20088.799112008986;20095.598088038158;13649;14660.56018319452;14726.042807217496;14742.336594853257;14780.556583302518;18438.907805461156;19208.003334722856;21543.78456215449;21644.675329870064;21683.891580541997;7180.291668123854;7275.927240727525;7398.519096258726;7536.7362142324755;7563.621818909032;956.5982287439292;1043.410473082706;1064.0795711709316;1067.9626213082504;1102.72431892027;34035.31929361399;34507.482387764205;34810.65189348125;35031.82484087563;35470.11324716903;1082.9566817327377;1108.539955918301;1158.2818652435376;1181.6927598824368;1267.714764178071;18296.268149274147;19298.80701192998;19382;19419.673889297057;19657.918814465054;554948;555205;557193;558286;560910;66702;67289.66355168291;67368.66315668488;67417.66291168611;67505.66247168733;1285.2160182288796;1351.4053816320788;1394.4073768648284;1401.3833913078213;1445.0607105381375;33305.50041749327;35727.28545429129;35800.46299305461;37389;37638;62296.68851655713;64204.35795641984;64261;68994.65502672455;69102.65448672725;548958;552576;554669;555585;556254;41046.58953410547;41585.37621935734;46277;47932;48185.75907120442;53410.46589534055;53752.73123634357;54046;54070;54317.72841135769;43665.34501982536;44273.77863110664;44877.326840098074;46331.30503042389;46774.298385523565;44730.77634611806;45468.772656136505;45798.77100614476;46459;46491.53508465007;43758.56241437672;43828.780856095516;44586.3312050313;44946.775266124765;45084.32373514334;38215.80892095522;39164.412533811446;39562;39994.20011599694;40471.59528404678;28741.568876466452;29421.558676620298;29503.26241843971;29627.259318517208;29831.850840745657 19:45:43 INFO - PROCESS | 15012 | |2;jquery.html;408.10217521452813;424.0214888718342;426.8676710219832;431.93952846601474;435.48612637088235;19159.137838797255;19889.801101988956;20301.18795248192;20512.76923384598;20866.165353385866;16750.916245418757;18735.437936861916;20800.791992080074;20951.37145885622;21100;5839.7664093436315;6168.352323006087;6172.5370597205165;6174.9073763893575;6175.41333573312;4454.109178164364;4550.135474259897;4561.543845615429;4564.360989461478;6041.758329666808;6742.595444273335;6790.422814060811;6826.5562738422095;6842.692078856463;6869.828254293658;6544.738210471575;6710.630915299656;6791.422729068036;6792.252852186305;6828.2488926218075;5934.673592952386;6021.488173505258;6059.212302400704;6062.302835173968;6063.605865618721;23930.401739956465;24274.757252427426;24281.878590606935;24333.14833980802;24467.143649972164;2576.0468626608276;2593.312772115391;2699.0283497940727;3018.154916623349;3033.2416895776055;14952.626184345478;15459.922700386425;15692.372305107896;15753.842461575352;16081.758773618405;1985.1563085688524;2007.9498012549802;2075.0199521149225;2080.8023237792536;2105.514181666429;65483.3451665482;68595.65702171507;68671.96992045123;72930.27069729235;84242.15757842404;11606.651800446;11683;11706.70733231676;11808;11889.583864564698;16522;16565.58586035359;16569.25438355277;16585.336586536403;16598.502044938672;9057.139571740674;9271.582778774971;9382.953085234667;9387.436753794795;9559.904400955902;9105.317101217435;9162;9204.907950920538;9428.858567121499;9449.291303152291;7088.255733148048;7114.110736157981;7121.857562848714;7213.819654508679;7273.63631818407;1933.2943475631293;1950.6781381071964;1993.760748710135;2038.622854771872;2081.375587323797;921.2906062331967;974.5224839828575;1004.2267454060333;1011.5903059261011;1039.7244730146517;18923.337683181297;19804.108815103355;19871.801281986995;20740.792592074187;21407.57184856294;1018.5467466977124;1038.8805287391895;1039.2257767962917;1065.4299949526967;1088.6516314779353;10627;11380.203385763083;11384.715382115513;11423.885761142448;11544.191906566628;518819;520152;531771;532094;532233;97266.51366743121;100916;100965.49517252366;101171;103563.48218259012;1081.648464249138;1146.7878442488166;1157.346099453806;1163.8137897936458;1164.91263155262;17622.735658965383;18148.455546333633;18160;18229.54426139357;18588.349407770664;44361;44550.55449445594;45509.544904551854;45604.315935261664;46109.30836037395;62957.37042629515;62979;63855.680721596094;64300.35699642944;64339;9177.571670314053;9199.310051746012;9219.907800921905;9368.953155234452;9612;10829.24195306321;12630.115891887479;13683;13766.65583360424;14100.576982690536;12334.691632709255;12502.812457813323;12599.93700031494;12608;12629.684257893627;12020.939895300467;12092.214006089576;12398;12573.0570207233;12684.746305074032;12068.577599783965;12112.091593130373;12283.75432491363;12505;12797.360131993177;9318.767030824283;9405.43567385959;9451.480168590793;9533.427994320364;9591.616335346787;12147.271163730204;12310.384480776105;12566.623001309976;12952.417141228856;13028.869711302767 19:45:43 INFO - PROCESS | 15012 | |3;mootools.html;1310.4168644952988;1519.8708109810668;1576.3930886608655;1741.7822772153481;1880.8965506897116;8403.705870294541;9127.452352858825;9491.430514169155;9495;9914.355566888147;9715.708528744131;9780.608775648965;9816.263780216477;9817.852732209023;9820.312578119534;2488.091846476041;2507.5486412445753;2512.246326102171;2518.7355327690602;2524.7853932415765;2419.0444774314137;2463.581191197492;2470.1354525915876;2470.481198948223;2471.9741307357426;2429.2712186344115;2439.4389290463205;2527.9747202528015;2529.9241022769256;2535.1127105513015;2393.3537944754994;2412.264259400882;2422.3701837522285;2426.733059363469;2438.6098224284206;2383.4279772854566;2393.2939782764124;2401.735809061002;2409.120670955089;2415.311636183697;9392.389494682824;9439.056094390575;9571.569279382444;9578.712638620853;9579;5491.807786727445;5650.237217975562;5695.886082278331;5704.543636509069;5721.828345149652;3643.107438677541;3670.743047986642;3676.540432445944;3749.1751814600743;3749.193923306475;1195.378403230324;1202.903767698582;1216.4915504774106;1233.5250928392634;1250.7435975624976;12857.549985750547;12870.613881583567;12878.87121128786;12887.420066096955;13027.934860325639;4522.88692782683;4689.554492323292;4804.231322988305;4822.662413631012;4854.368932038883;10329.9483502582;10364.06723394883;10366.429846358373;10377.429241391796;10377.792444151226;5490.231367608538;5490.780368785284;5495.093309603951;5500.834974950758;5503.504684578407;4673.7429441381055;4822.590079843182;5001.274815151783;5079.771410286546;5121.590272778169;4161.897620081261;4181.6236538711055;4254.914901701949;4259.446271984622;4306.633936115402;1222.8043513037871;1230.1143176912608;1236.4683186229827;1245.1844042152404;1249.7812882745484;949.7340744591526;968.7190714692802;973.0072158927773;980.5881529757519;981.9754506137403;5984.850378740479;6042.365551617105;6198.81403557894;6342.5560210785725;6363.331850155667;503;503.55686995444506;512.3518748782793;515.3660996973744;517.1157320981089;3920.5491368492417;4064.20747954152;4079.4492743479846;4088.713790034669;4104.240715467588;11115.277506962022;11870.525178992744;12043.83211022007;12064.819027714591;12080.335581543099;11626.65120046383;11866.228695134789;11953.52185912571;11969.162158648985;11984.101192410604;8904.777380565538;8987.595558199897;8989.595468203946;8989.820203595891;9009.279257659371;6107.4197951194565;6186.4741496973265;6239.594426362272;6260.937390626035;6314.842128946813;10808.783824323627;10867.456627168767;10890.401027943539;10990.285631433931;10998.450077495932;10942.398168100832;10991.230613856953;11072.280301780358;11297.661070167907;11366.829497557708;7185.461090418344;7356.926430735838;7739.3034626883855;8045.9597702011115;8098.7165449209;9087.909120908971;9424.057594240521;9438.764030899283;9445.24438044941;9463.526823658653;8290.37822163328;8364.247217750431;8418.36862235347;8444.028936672115;8554.743357699279;8003;8029.7189598363775;8090.514569125871;8108.472949258279;8362.121977192308;6918.204406493318;7031.578105313698;7173.928260717326;7231.819204519929;7287.380572651384;5388.649737767033;5391.137418013099;5454.781808727606;5482.205080263419;5628.409017053151;5359.330083739533;5475.479829416358;5535.062728045152;5633.6619802811965;5674.205611214516 19:45:43 INFO - PROCESS | 15012 | |4;prototype.html;353.9469079638054;362.37671205526493;362.7315786318124;364.28418158318897;371.153769405755;5573.136163894593;5630.605857589971;5649.915251271234;5654.858628534289;5667.971660141704;5476.58925580581;5507.146392309189;5553.139263414178;5567.805126820562;5568.136938774496;4366.214081465335;4412.8014239359145;4414.8675539733695;4414.97792511037;4415.22733521634;4381.649468042565;4384.934225986613;4387.890302742441;4397.824087036514;4416.536263692315;4365.476142862845;4373.934390984138;4382;4387.9561204387865;4403.669724770639;4312.266914624522;4337.84817531388;4346.6522678185665;4356.259435895921;4365.934510982338;3893.3186692328727;3901.609839016104;3902.570717221103;3904.882853514399;3908.882733517971;5369.758360873731;5398.811041613564;5399.19012148177;5418.29562156921;5424.159255315393;4877.512248775094;4984.77568509418;4995.725235112068;5004.549590536869;5034.773435195425;5710.172025056385;5733.054046082391;5736;5745.942540574582;5766.769329226826;4299.828006879721;4313.568643135693;4314.546972567898;4325.480942286946;4334.436523251989;5633.521150702195;5657.462541058593;5839.21903819335;5946.613470124471;6005.909911351333;4195;4285.164392943346;4299.9570004300185;4307.310830267143;4308.80610372534;4344.391785150082;4346.348047792793;4350.108227813291;4350.695451318441;4393.604575588212;4677.654022434893;4781.258904869681;4953.8018479261145;4960.206366981266;5030;5568.32950114972;5737.397573254748;5769.509591684669;5783.88432231353;5801.419858014165;1508.1403599948144;1515.6514001779653;1516.7194069097252;1523.3525751555544;1530.9693806123814;932.2076235200026;974.7758015656296;977.6480467032005;980.460746589373;993.8360170571775;4793.904121917542;4884.633652476078;4891.682040667345;4908.533689299511;4932.926006109911;5140.794368225229;5151.531813433154;5158.38099428071;5160.400275914479;5189.974050129726;4619.422572178478;4894.846267642226;4895.926561101586;4909.337239472626;4936.777844996984;2739.7534221919723;2746.901239504214;2768.4878297515024;2773.1403264987916;2775.403288293027;5352.6253162279045;5364.544013758796;5372.811951581676;5374.677519348852;5383.546442460513;5217.426083130853;5228.947710522846;5660.632058916157;6067.393260673985;6100.69496525172;6122.326544080148;6122.969385153135;6136.079588061826;6137.84655383619;6139.969300153471;5698.943010569841;5952.5833191676165;5976.820695379146;5992.5617851714405;5993.280806303272;5854.970725146347;5894.263217097863;5902.350741418355;5907.9704601476715;5913.46778789911;5883.2057672215415;5969.880602388015;5986.640801551921;6013.9699301503215;6170.7041521281035;4055.8377664893073;4161.20937021977;4287.721298115613;4339.132173565236;4348.260795664808;5745.610090046893;5919.437653422918;5921.230240068763;5948.137520059654;5965.492933100735;5784.855378615568;5791.218185544897;5801.912971305518;5811.622244554091;5812.360640329645;5721.542276617777;5766.826995190151;5778.855528611818;5828.737706803119;5896.64620122794;5512.779488820403;5706.400827913009;5711.857203569944;5726.942730572641;5840.386759390203;5791.7683292667825;5797.333306669787;5812.534997200299;5814.709264536672;5835.416458354131;5621.095431005106;5633.971830140823;5735.713214339183;5744.310682717934;5756.3380211276235 19:45:43 INFO - PROCESS | 15012 | |5;yui.html;748.7678819565931;773.7562667759655;823.9670413183472;848.0247715127603;870.4733636150125;15095.698086038272;16021.91989040056;16029.438969636065;16033;16033.438829640965;15126.924365378185;15371.461998830044;15474.845251547482;15489.535313940572;15547.611309717235;732.8790749526322;750.5721738608995;752.0298814529278;758.3743411685382;860.9913900861005;880.190224993008;891.2424439226638;894.8255090257402;897.5467388968594;897.5915958238986;935.3592788939599;946.6497395963503;946.820104180207;947.1664934857316;947.469417126408;763.1338430880962;782.0693374883898;889.301898010059;903.0112027330063;946.3186505715908;674.7672053141658;675.5203805298244;676.1750664189681;676.313541755119;676.5872817581271;16066.919665401598;16097.19514024294;16119.677606447805;16122.677546449122;16123.355065797354;14799.70400591982;14827.036242644193;14966.251687415695;15281.235938203044;15304.311305991258;14546.199959002251;14600.853991460162;14662.340194691158;14670.486532971296;14895.331714888342;4647.86056418308;4659.4408670959365;4697.976510117427;4846.466888642247;4910.116179087763;89564.55217723935;91013.54493227557;91103.08896911012;92068;92324;12309.322987235788;12458.377081145907;12477.875221247852;12656.81014784779;12682.175658582162;17745.11274436273;17762.46712598624;17788.822111778976;17820.46538603844;17852.732209016875;9274.629014839466;9334.953325233331;9336.626534938527;9427.104425079606;9543.713688589354;4992.825251116194;5283.841484755463;5309.203619457112;5323.893522129613;5467.917981230285;3700.130469339703;3841.9423708644395;3856.9228615427533;3869.187470631143;3875.399313106473;647.2200997797643;658.4436151452015;660.0330515794384;685.6126288646899;740.4039748002909;693.569986608296;694.9756758513529;756.8334966307403;758.5031804168278;776.5146783260462;9154.67958621445;9598.42409455435;9625.807483850283;9907.752306192402;10248.743781405376;720.7333286683961;741.9674071421839;769.6728890221633;786.598834594361;815.2662603656756;4316.568343165721;4498.8425405110665;4500.852396622138;4510.977445112753;4706.8117275308605;78658.6067069661;103968.48015759872;105707.47146264219;109348.4532577332;121927.39036304761;90602.54698726464;93157;100550.4972475133;102709;104184;88237;88726.55636721775;89366.55316723505;89510;93823.53088234515;10158.843760561233;10408.344100073831;10425.322354046963;10564.683059508227;10583.894161058292;55370.72314638562;55414.72292638511;56452.717736411054;56509.15236271377;57035.71482142563;51723.482765171866;53352;61786.69106654438;81488.59255703921;81874;13660.759797307443;14091.859081409055;14258.358373872994;14288.142711437347;14527.273636318354;14896.255187240811;15387.846121538641;16316.755248671518;16352.182390880645;16439;14737.926310368379;14800.259987000392;15078.472253471076;15259.237038147827;15598.220088995731;14559.92720036393;14762.704745904806;15602.141882196589;15769;15818.683626327178;14558.199299038655;14602.926985365006;15068.773968390264;15569.065856048674;16310.429134980219;11532.654020379403;11858.288502689866;12048.638540843789;12126.393680316125;12739.490420383081;10966.670999870017;10974.890251097388;11092.500837462172;11461.770764584495;11510.424478776195 19:45:43 INFO - PROCESS | 15012 | __end_tp_report 19:45:43 INFO - PROCESS | 15012 | __start_cc_report 19:45:43 INFO - PROCESS | 15012 | _x_x_mozilla_cycle_collect,851 19:45:43 INFO - PROCESS | 15012 | __end_cc_report 19:45:43 INFO - PROCESS | 15012 | __startTimestamp1478835943622__endTimestamp 19:45:43 INFO - PROCESS | 15012 | 19:45:43 INFO - PROCESS | 15012 | ------- Summary: start ------- 19:45:43 INFO - PROCESS | 15012 | Number of tests: 6 19:45:43 INFO - PROCESS | 15012 | 19:45:43 INFO - PROCESS | 15012 | [#0] dojo.html Cycles:165 Average:5537.28 Median:5772.22 stddev:646.14 (11.2%) stddev-sans-first:618.82 19:45:43 INFO - PROCESS | 15012 | Values: 3085.1 3120.1 3125.8 3149.8 3169.2 5412.1 5527.5 5531.3 5542.3 5553.7 5449.5 5536.4 5539.4 5544.9 5553.3 4974.4 5000.8 5027.7 5034.2 5106.3 4969.3 5035.2 5089.3 5092.3 5093.6 4943.4 4997.8 5009.9 5047.9 5063.6 5042.1 5062.7 5066.7 5124.8 5182.8 4971.1 5055.3 5088.7 5110.9 5120.4 5376.0 5418.7 5434.4 5479.9 5488.5 5945.4 5959.6 5964.0 5968.3 5971.5 6006.8 6009.2 6020.0 6020.7 6026.0 5361.6 5415.4 5486.7 5569.4 5575.6 6141.7 6147.5 6158.1 6158.7 6159.3 5907.4 5908.4 5910.9 5911.0 5938.3 5673.9 5814.7 5819.0 5960.5 5990.8 5996.6 6032.6 6051.2 6070.6 6076.1 6060.1 6061.7 6062.9 6070.5 6093.3 4474.1 4507.6 4511.7 4662.8 4664.1 3999.9 4056.7 4092.0 4097.0 4105.9 5657.2 5662.3 5663.8 5665.6 5668.4 5794.8 5838.0 5911.2 5912.6 6000.0 5652.4 5734.7 5737.8 5745.3 5787.7 5250.0 5251.6 5258.8 5259.2 5261.0 5989.0 6035.7 6270.2 6277.7 6282.0 5951.9 5979.8 6036.6 6038.6 6039.7 6179.8 6179.8 6181.3 6182.8 6183.8 5637.8 5823.8 5835.4 5874.2 5894.0 5772.2 5855.5 5883.6 5888.4 6020.4 5848.6 5855.9 5860.0 5860.0 5866.3 5836.0 5837.6 5839.6 5839.9 5849.0 5657.1 5670.2 5742.3 5765.7 5773.2 5891.6 5941.6 5942.9 5954.2 5962.3 5639.0 5746.7 5812.6 5826.9 5828.6 19:45:43 INFO - PROCESS | 15012 | 19:45:43 INFO - PROCESS | 15012 | [#1] ext.html Cycles:180 Average:68862.32 Median:24115.48 stddev:134605.93 (558.2%) stddev-sans-first:134885.94 19:45:43 INFO - PROCESS | 15012 | Values: 595.3 606.6 623.0 636.4 652.8 35654.3 39020.0 39217.4 47726.3 48802.8 50538.0 50881.0 50881.2 50938.7 51003.0 9387.6 9545.8 9549.4 9556.6 9559.5 8630.5 8846.2 9320.8 11016.6 11292.0 11426.1 11484.9 11494.7 11500.1 11513.8 11210.1 11297.3 11307.5 11333.8 11353.5 8419.4 8654.8 10763.6 11093.1 11104.6 46110.0 46492.3 46534.1 46615.8 46632.0 3886.5 4025.2 4290.3 4356.0 4409.1 35680.6 36073.8 36287.3 37934.8 38180.6 4402.2 4428.6 4451.7 4480.2 4484.3 391755.0 397320.0 397753.0 398159.0 399073.0 68253.3 68697.0 68744.3 68758.7 68808.0 72720.0 72792.0 72831.6 72843.3 72878.0 19749.4 19994.7 20033.1 20088.8 20095.6 13649.0 14660.6 14726.0 14742.3 14780.6 18438.9 19208.0 21543.8 21644.7 21683.9 7180.3 7275.9 7398.5 7536.7 7563.6 956.6 1043.4 1064.1 1068.0 1102.7 34035.3 34507.5 34810.7 35031.8 35470.1 1083.0 1108.5 1158.3 1181.7 1267.7 18296.3 19298.8 19382.0 19419.7 19657.9 554948.0 555205.0 557193.0 558286.0 560910.0 66702.0 67289.7 67368.7 67417.7 67505.7 1285.2 1351.4 1394.4 1401.4 1445.1 33305.5 35727.3 35800.5 37389.0 37638.0 62296.7 64204.4 64261.0 68994.7 69102.7 548958.0 552576.0 554669.0 555585.0 556254.0 41046.6 41585.4 46277.0 47932.0 48185.8 53410.5 53752.7 54046.0 54070.0 54317.7 43665.3 44273.8 44877.3 46331.3 46774.3 44730.8 45468.8 45798.8 46459.0 46491.5 43758.6 43828.8 44586.3 44946.8 45084.3 38215.8 39164.4 39562.0 39994.2 40471.6 28741.6 29421.6 29503.3 29627.3 29831.9 19:45:43 INFO - PROCESS | 15012 | 19:45:43 INFO - PROCESS | 15012 | [#2] jquery.html Cycles:180 Average:30960.29 Median:21253.79 stddev:86693.73 (407.9%) stddev-sans-first:86906.58 19:45:43 INFO - PROCESS | 15012 | Values: 408.1 424.0 426.9 431.9 435.5 19159.1 19889.8 20301.2 20512.8 20866.2 16750.9 18735.4 20800.8 20951.4 21100.0 5839.8 6168.4 6172.5 6174.9 6175.4 4454.1 4550.1 4561.5 4564.4 6041.8 6742.6 6790.4 6826.6 6842.7 6869.8 6544.7 6710.6 6791.4 6792.3 6828.2 5934.7 6021.5 6059.2 6062.3 6063.6 23930.4 24274.8 24281.9 24333.1 24467.1 2576.0 2593.3 2699.0 3018.2 3033.2 14952.6 15459.9 15692.4 15753.8 16081.8 1985.2 2007.9 2075.0 2080.8 2105.5 65483.3 68595.7 68672.0 72930.3 84242.2 11606.7 11683.0 11706.7 11808.0 11889.6 16522.0 16565.6 16569.3 16585.3 16598.5 9057.1 9271.6 9383.0 9387.4 9559.9 9105.3 9162.0 9204.9 9428.9 9449.3 7088.3 7114.1 7121.9 7213.8 7273.6 1933.3 1950.7 1993.8 2038.6 2081.4 921.3 974.5 1004.2 1011.6 1039.7 18923.3 19804.1 19871.8 20740.8 21407.6 1018.5 1038.9 1039.2 1065.4 1088.7 10627.0 11380.2 11384.7 11423.9 11544.2 518819.0 520152.0 531771.0 532094.0 532233.0 97266.5 100916.0 100965.5 101171.0 103563.5 1081.6 1146.8 1157.3 1163.8 1164.9 17622.7 18148.5 18160.0 18229.5 18588.3 44361.0 44550.6 45509.5 45604.3 46109.3 62957.4 62979.0 63855.7 64300.4 64339.0 9177.6 9199.3 9219.9 9369.0 9612.0 10829.2 12630.1 13683.0 13766.7 14100.6 12334.7 12502.8 12599.9 12608.0 12629.7 12020.9 12092.2 12398.0 12573.1 12684.7 12068.6 12112.1 12283.8 12505.0 12797.4 9318.8 9405.4 9451.5 9533.4 9591.6 12147.3 12310.4 12566.6 12952.4 13028.9 19:45:43 INFO - PROCESS | 15012 | 19:45:43 INFO - PROCESS | 15012 | [#3] mootools.html Cycles:180 Average:6148.84 Median:4838.52 stddev:3572.63 (73.8%) stddev-sans-first:3564.15 19:45:43 INFO - PROCESS | 15012 | Values: 1310.4 1519.9 1576.4 1741.8 1880.9 8403.7 9127.5 9491.4 9495.0 9914.4 9715.7 9780.6 9816.3 9817.9 9820.3 2488.1 2507.5 2512.2 2518.7 2524.8 2419.0 2463.6 2470.1 2470.5 2472.0 2429.3 2439.4 2528.0 2529.9 2535.1 2393.4 2412.3 2422.4 2426.7 2438.6 2383.4 2393.3 2401.7 2409.1 2415.3 9392.4 9439.1 9571.6 9578.7 9579.0 5491.8 5650.2 5695.9 5704.5 5721.8 3643.1 3670.7 3676.5 3749.2 3749.2 1195.4 1202.9 1216.5 1233.5 1250.7 12857.5 12870.6 12878.9 12887.4 13027.9 4522.9 4689.6 4804.2 4822.7 4854.4 10329.9 10364.1 10366.4 10377.4 10377.8 5490.2 5490.8 5495.1 5500.8 5503.5 4673.7 4822.6 5001.3 5079.8 5121.6 4161.9 4181.6 4254.9 4259.4 4306.6 1222.8 1230.1 1236.5 1245.2 1249.8 949.7 968.7 973.0 980.6 982.0 5984.9 6042.4 6198.8 6342.6 6363.3 503.0 503.6 512.4 515.4 517.1 3920.5 4064.2 4079.4 4088.7 4104.2 11115.3 11870.5 12043.8 12064.8 12080.3 11626.7 11866.2 11953.5 11969.2 11984.1 8904.8 8987.6 8989.6 8989.8 9009.3 6107.4 6186.5 6239.6 6260.9 6314.8 10808.8 10867.5 10890.4 10990.3 10998.5 10942.4 10991.2 11072.3 11297.7 11366.8 7185.5 7356.9 7739.3 8046.0 8098.7 9087.9 9424.1 9438.8 9445.2 9463.5 8290.4 8364.2 8418.4 8444.0 8554.7 8003.0 8029.7 8090.5 8108.5 8362.1 6918.2 7031.6 7173.9 7231.8 7287.4 5388.6 5391.1 5454.8 5482.2 5628.4 5359.3 5475.5 5535.1 5633.7 5674.2 19:45:43 INFO - PROCESS | 15012 | 19:45:43 INFO - PROCESS | 15012 | [#4] prototype.html Cycles:180 Average:4792.58 Median:5373.74 stddev:1390.47 (25.9%) stddev-sans-first:1353.87 19:45:43 INFO - PROCESS | 15012 | Values: 353.9 362.4 362.7 364.3 371.2 5573.1 5630.6 5649.9 5654.9 5668.0 5476.6 5507.1 5553.1 5567.8 5568.1 4366.2 4412.8 4414.9 4415.0 4415.2 4381.6 4384.9 4387.9 4397.8 4416.5 4365.5 4373.9 4382.0 4388.0 4403.7 4312.3 4337.8 4346.7 4356.3 4365.9 3893.3 3901.6 3902.6 3904.9 3908.9 5369.8 5398.8 5399.2 5418.3 5424.2 4877.5 4984.8 4995.7 5004.5 5034.8 5710.2 5733.1 5736.0 5745.9 5766.8 4299.8 4313.6 4314.5 4325.5 4334.4 5633.5 5657.5 5839.2 5946.6 6005.9 4195.0 4285.2 4300.0 4307.3 4308.8 4344.4 4346.3 4350.1 4350.7 4393.6 4677.7 4781.3 4953.8 4960.2 5030.0 5568.3 5737.4 5769.5 5783.9 5801.4 1508.1 1515.7 1516.7 1523.4 1531.0 932.2 974.8 977.6 980.5 993.8 4793.9 4884.6 4891.7 4908.5 4932.9 5140.8 5151.5 5158.4 5160.4 5190.0 4619.4 4894.8 4895.9 4909.3 4936.8 2739.8 2746.9 2768.5 2773.1 2775.4 5352.6 5364.5 5372.8 5374.7 5383.5 5217.4 5228.9 5660.6 6067.4 6100.7 6122.3 6123.0 6136.1 6137.8 6140.0 5698.9 5952.6 5976.8 5992.6 5993.3 5855.0 5894.3 5902.4 5908.0 5913.5 5883.2 5969.9 5986.6 6014.0 6170.7 4055.8 4161.2 4287.7 4339.1 4348.3 5745.6 5919.4 5921.2 5948.1 5965.5 5784.9 5791.2 5801.9 5811.6 5812.4 5721.5 5766.8 5778.9 5828.7 5896.6 5512.8 5706.4 5711.9 5726.9 5840.4 5791.8 5797.3 5812.5 5814.7 5835.4 5621.1 5634.0 5735.7 5744.3 5756.3 19:45:43 INFO - PROCESS | 15012 | 19:45:43 INFO - PROCESS | 15012 | [#5] yui.html Cycles:180 Average:21300.73 Median:4505.91 stddev:29856.50 (662.6%) stddev-sans-first:29900.37 19:45:43 INFO - PROCESS | 15012 | Values: 748.8 773.8 824.0 848.0 870.5 15095.7 16021.9 16029.4 16033.0 16033.4 15126.9 15371.5 15474.8 15489.5 15547.6 732.9 750.6 752.0 758.4 861.0 880.2 891.2 894.8 897.5 897.6 935.4 946.6 946.8 947.2 947.5 763.1 782.1 889.3 903.0 946.3 674.8 675.5 676.2 676.3 676.6 16066.9 16097.2 16119.7 16122.7 16123.4 14799.7 14827.0 14966.3 15281.2 15304.3 14546.2 14600.9 14662.3 14670.5 14895.3 4647.9 4659.4 4698.0 4846.5 4910.1 89564.6 91013.5 91103.1 92068.0 92324.0 12309.3 12458.4 12477.9 12656.8 12682.2 17745.1 17762.5 17788.8 17820.5 17852.7 9274.6 9335.0 9336.6 9427.1 9543.7 4992.8 5283.8 5309.2 5323.9 5467.9 3700.1 3841.9 3856.9 3869.2 3875.4 647.2 658.4 660.0 685.6 740.4 693.6 695.0 756.8 758.5 776.5 9154.7 9598.4 9625.8 9907.8 10248.7 720.7 742.0 769.7 786.6 815.3 4316.6 4498.8 4500.9 4511.0 4706.8 78658.6 103968.5 105707.5 109348.5 121927.4 90602.5 93157.0 100550.5 102709.0 104184.0 88237.0 88726.6 89366.6 89510.0 93823.5 10158.8 10408.3 10425.3 10564.7 10583.9 55370.7 55414.7 56452.7 56509.2 57035.7 51723.5 53352.0 61786.7 81488.6 81874.0 13660.8 14091.9 14258.4 14288.1 14527.3 14896.3 15387.8 16316.8 16352.2 16439.0 14737.9 14800.3 15078.5 15259.2 15598.2 14559.9 14762.7 15602.1 15769.0 15818.7 14558.2 14602.9 15068.8 15569.1 16310.4 11532.7 11858.3 12048.6 12126.4 12739.5 10966.7 10974.9 11092.5 11461.8 11510.4 19:45:43 INFO - PROCESS | 15012 | -------- Summary: end -------- 19:45:43 INFO - PROCESS | 15012 | 19:45:44 INFO - TEST-INFO | 15012: exit 0 19:45:44 INFO - TEST-OK | dromaeo_css | took 1113101ms 19:45:44 INFO - TEST-START | kraken 19:45:44 INFO - operating with platform_type : linux_ 19:45:44 INFO - Initialising browser for kraken test... 19:45:44 INFO - TEST-INFO | started process 15347 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNbkecH/profile http://localhost:38122/getInfo.html) 19:45:48 INFO - PROCESS | 15347 | __metrics Screen width/height:1600/1200 19:45:48 INFO - PROCESS | 15347 | colorDepth:24 19:45:48 INFO - PROCESS | 15347 | Browser inner width/height: 1024/697 19:45:48 INFO - PROCESS | 15347 | __metrics 19:45:48 INFO - PROCESS | 15347 | 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" 19:45:48 INFO - PROCESS | 15347 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:45:49 INFO - PROCESS | 15347 | console.error: PushService: 19:45:49 INFO - PROCESS | 15347 | stateChangeProcessEnqueue: Error transitioning state 19:45:49 INFO - PROCESS | 15347 | UnknownError 19:45:49 INFO - TEST-INFO | 15347: exit 0 19:45:49 INFO - Browser initialized. 19:45:49 INFO - Running cycle 1/1 for kraken test... 19:45:49 INFO - TEST-INFO | started process 15440 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNbkecH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:46:01 INFO - PROCESS | 15440 | RSS: Main: 144564224 19:46:01 INFO - PROCESS | 15440 | 19:46:03 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38122/tests/kraken/driver.html?testName=audio-beat-detection) 19:46:04 INFO - PROCESS | 15440 | RSS: Main: 186601472 19:46:04 INFO - PROCESS | 15440 | 19:46:07 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38122/tests/kraken/driver.html?testName=audio-dft) 19:46:07 INFO - PROCESS | 15440 | RSS: Main: 176062464 19:46:07 INFO - PROCESS | 15440 | 19:46:10 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38122/tests/kraken/driver.html?testName=audio-fft) 19:46:11 INFO - PROCESS | 15440 | RSS: Main: 267915264 19:46:11 INFO - PROCESS | 15440 | 19:46:13 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38122/tests/kraken/driver.html?testName=audio-oscillator) 19:46:14 INFO - PROCESS | 15440 | RSS: Main: 173842432 19:46:14 INFO - PROCESS | 15440 | 19:46:16 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38122/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:46:16 INFO - PROCESS | 15440 | RSS: Main: 175775744 19:46:16 INFO - PROCESS | 15440 | 19:46:19 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38122/tests/kraken/driver.html?testName=imaging-darkroom) 19:46:20 INFO - PROCESS | 15440 | RSS: Main: 211709952 19:46:20 INFO - PROCESS | 15440 | 19:46:23 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38122/tests/kraken/driver.html?testName=imaging-desaturate) 19:46:24 INFO - PROCESS | 15440 | RSS: Main: 210857984 19:46:24 INFO - PROCESS | 15440 | 19:46:27 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38122/tests/kraken/driver.html?testName=json-parse-financial) 19:46:27 INFO - PROCESS | 15440 | RSS: Main: 211222528 19:46:27 INFO - PROCESS | 15440 | 19:46:29 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38122/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:46:29 INFO - PROCESS | 15440 | RSS: Main: 172503040 19:46:29 INFO - PROCESS | 15440 | 19:46:31 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38122/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:46:32 INFO - PROCESS | 15440 | RSS: Main: 179838976 19:46:32 INFO - PROCESS | 15440 | 19:46:33 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38122/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:46:34 INFO - PROCESS | 15440 | RSS: Main: 189734912 19:46:34 INFO - PROCESS | 15440 | 19:46:37 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38122/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:46:37 INFO - PROCESS | 15440 | RSS: Main: 199208960 19:46:37 INFO - PROCESS | 15440 | 19:46:40 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38122/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:46:41 INFO - PROCESS | 15440 | RSS: Main: 194740224 19:46:41 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:38122/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38122/tests/kraken/driver.html?testName=ai-astar) 19:46:42 INFO - PROCESS | 15440 | RSS: Main: 245379072 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | __start_tp_report 19:46:42 INFO - PROCESS | 15440 | _x_x_mozilla_page_load 19:46:42 INFO - PROCESS | 15440 | _x_x_mozilla_page_load_details 19:46:42 INFO - PROCESS | 15440 | |i|pagename|runs| 19:46:42 INFO - PROCESS | 15440 | |0;ai-astar;107;109;110;111;110;110;110;110;109;109 19:46:42 INFO - PROCESS | 15440 | |1;audio-beat-detection;151;116;121;156;123;121;116;117;146;116 19:46:42 INFO - PROCESS | 15440 | |2;audio-dft;162;161;162;161;162;161;162;160;161;161 19:46:42 INFO - PROCESS | 15440 | |3;audio-fft;75;76;75;108;77;76;77;77;102;77 19:46:42 INFO - PROCESS | 15440 | |4;audio-oscillator;80;81;117;81;82;81;102;81;81;110 19:46:42 INFO - PROCESS | 15440 | |5;imaging-gaussian-blur;118;116;117;117;117;117;117;116;117;117 19:46:42 INFO - PROCESS | 15440 | |6;imaging-darkroom;146;146;146;146;146;145;146;146;146;146 19:46:42 INFO - PROCESS | 15440 | |7;imaging-desaturate;95;95;94;95;95;95;100;95;95;95 19:46:42 INFO - PROCESS | 15440 | |8;json-parse-financial;52;48;47;47;50;47;48;48;48;47 19:46:42 INFO - PROCESS | 15440 | |9;json-stringify-tinderbox;59;60;59;59;58;58;58;59;58;59 19:46:42 INFO - PROCESS | 15440 | |10;stanford-crypto-aes;73;74;73;74;73;87;74;72;73;81 19:46:42 INFO - PROCESS | 15440 | |11;stanford-crypto-ccm;123;130;128;121;120;128;119;118;171;125 19:46:42 INFO - PROCESS | 15440 | |12;stanford-crypto-pbkdf2;155;156;154;152;157;155;151;152;152;156 19:46:42 INFO - PROCESS | 15440 | |13;stanford-crypto-sha256-iterative;62;62;64;62;62;62;62;60;61;59 19:46:42 INFO - PROCESS | 15440 | __end_tp_report 19:46:42 INFO - PROCESS | 15440 | __start_cc_report 19:46:42 INFO - PROCESS | 15440 | _x_x_mozilla_cycle_collect,1643 19:46:42 INFO - PROCESS | 15440 | __end_cc_report 19:46:42 INFO - PROCESS | 15440 | __startTimestamp1478836002889__endTimestamp 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | ------- Summary: start ------- 19:46:42 INFO - PROCESS | 15440 | Number of tests: 14 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:1.08 (1.0%) stddev-sans-first:0.67 19:46:42 INFO - PROCESS | 15440 | Values: 107.0 109.0 110.0 111.0 110.0 110.0 110.0 110.0 109.0 109.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:122.00 stddev:16.03 (13.1%) stddev-sans-first:14.75 19:46:42 INFO - PROCESS | 15440 | Values: 151.0 116.0 121.0 156.0 123.0 121.0 116.0 117.0 146.0 116.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.67 (0.4%) stddev-sans-first:0.67 19:46:42 INFO - PROCESS | 15440 | Values: 162.0 161.0 162.0 161.0 162.0 161.0 162.0 160.0 161.0 161.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.50 stddev:12.23 (16.0%) stddev-sans-first:12.71 19:46:42 INFO - PROCESS | 15440 | Values: 75.0 76.0 75.0 108.0 77.0 76.0 77.0 77.0 102.0 77.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:81.00 stddev:14.30 (17.7%) stddev-sans-first:14.74 19:46:42 INFO - PROCESS | 15440 | Values: 80.0 81.0 117.0 81.0 82.0 81.0 102.0 81.0 81.0 110.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 19:46:42 INFO - PROCESS | 15440 | Values: 118.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 19:46:42 INFO - PROCESS | 15440 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:1.65 (1.7%) stddev-sans-first:1.74 19:46:42 INFO - PROCESS | 15440 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 100.0 95.0 95.0 95.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#8] json-parse-financial Cycles:10 Average:48.20 Median:48.00 stddev:1.62 (3.4%) stddev-sans-first:0.97 19:46:42 INFO - PROCESS | 15440 | Values: 52.0 48.0 47.0 47.0 50.0 47.0 48.0 48.0 48.0 47.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 19:46:42 INFO - PROCESS | 15440 | Values: 59.0 60.0 59.0 59.0 58.0 58.0 58.0 59.0 58.0 59.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:74.00 stddev:4.79 (6.5%) stddev-sans-first:5.00 19:46:42 INFO - PROCESS | 15440 | Values: 73.0 74.0 73.0 74.0 73.0 87.0 74.0 72.0 73.0 81.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:126.50 stddev:15.56 (12.3%) stddev-sans-first:16.39 19:46:42 INFO - PROCESS | 15440 | Values: 123.0 130.0 128.0 121.0 120.0 128.0 119.0 118.0 171.0 125.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.00 Median:155.00 stddev:2.11 (1.4%) stddev-sans-first:2.20 19:46:42 INFO - PROCESS | 15440 | Values: 155.0 156.0 154.0 152.0 157.0 155.0 151.0 152.0 152.0 156.0 19:46:42 INFO - PROCESS | 15440 | 19:46:42 INFO - PROCESS | 15440 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:1.35 (2.2%) stddev-sans-first:1.42 19:46:42 INFO - PROCESS | 15440 | Values: 62.0 62.0 64.0 62.0 62.0 62.0 62.0 60.0 61.0 59.0 19:46:42 INFO - PROCESS | 15440 | -------- Summary: end -------- 19:46:42 INFO - PROCESS | 15440 | 19:46:43 INFO - TEST-INFO | 15440: exit 0 19:46:43 INFO - TEST-OK | kraken | took 59502ms 19:46:43 INFO - SUITE-END | took 1172s 19:46:44 INFO - Completed test suite (00:19:32) 19:46:44 INFO - javascript benchmark 19:46:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3085.1207405889318, 3120.141960960736, 3125.7811953163286, 3149.7952633078835, 3169.1918560767, 5412.107002344614, 5527.5301599364, 5531.3362396512475, 5542.251796007537, 5553.666779993203, 5449.509544141037, 5536.446355364462, 5539.362973258077, 5544.889102217953, 5553.333599967999, 4974.42794078682, 5000.849974500771, 5027.698338099707, 5034.1945288753805, 5106.259592359106, 4969.279454479099, 5035.219540971156, 5089.3129427527365, 5092.337996060525, 5093.567046801027, 4943.382077240345, 4997.800087996494, 5009.899802003958, 5047.949520504784, 5063.6202284828605, 5042.142835717937, 5062.721550314731, 5066.670666406709, 5124.846254612367, 5182.844514664566, 4971.0803501352, 5055.266986287004, 5088.720120393377, 5110.948890511085, 5120.436751957282, 5375.973120134413, 5418.7019713915715, 5434.402215756264, 5479.945200547983, 5488.506034456878, 5945.435183657545, 5959.582829201958, 5963.970180149072, 5968.253968253968, 5971.4924231439945, 6006.819795406145, 6009.188759517453, 6020.036794112921, 6020.729067191987, 6026.035834266497, 5361.5978801589645, 5415.431379705114, 5486.725663716838, 5569.387367389584, 5575.609707320448, 6141.692915354304, 6147.508199344042, 6158.137860699506, 6158.661273629904, 6159.260888693386, 5907.409259074058, 5908.409159084057, 5910.940890591039, 5911.0, 5938.257717785277, 5673.943260567424, 5814.651120932757, 5819.0, 5960.493358064613, 5990.820275391745, 5996.640201587918, 6032.638041717511, 6051.243594550681, 6070.6357618543025, 6076.058210977306, 6060.060690592965, 6061.696915154224, 6062.939370606326, 6070.514358851281, 6093.329733729286, 4474.105178964221, 4507.616852567503, 4511.729296242236, 4662.7668616569035, 4664.137134630104, 3999.9000024999027, 4056.6957478189256, 4091.959080409217, 4097.016715988141, 4105.938410923838, 5657.15142728594, 5662.320521537442, 5663.801766938137, 5665.6034077614995, 5668.376478587352, 5794.768209271666, 5838.036723940587, 5911.231539899785, 5912.615679980788, 6000.03999360109, 5652.406497317723, 5734.741936612862, 5737.827865164052, 5745.3105627324985, 5787.681677507693, 5250.028744682246, 5251.632385733038, 5258.842234732964, 5259.237410575445, 5261.026710058575, 5989.011813050885, 6035.668038257938, 6270.2475702915945, 6277.748890044439, 6282.026285925689, 5951.91072133927, 5979.820605381846, 6036.577439579188, 6038.577299589162, 6039.6678182698615, 6179.783707570214, 6179.783707570214, 6181.258249010148, 6182.752689892533, 6183.783567575113, 5637.802676906289, 5823.767049317979, 5835.3581106077445, 5874.206982057539, 5893.970530147321, 5772.220750198838, 5855.502282306052, 5883.646981180972, 5888.352281249145, 6020.4280593343565, 5848.590598658053, 5855.912161317498, 5860.0, 5860.0330945395235, 5866.2960444745195, 5836.0, 5837.620554663933, 5839.649621022752, 5839.912401313899, 5848.970755146197, 5657.12314591239, 5670.206171136127, 5742.253507044057, 5765.682887441231, 5773.220615216892, 5891.646501209942, 5941.643501389931, 5942.851428714317, 5954.166416701752, 5962.284525856925, 5639.0, 5746.741396637245, 5812.564057695774, 5826.883462330815, 5828.562857785599], "value": 5492.006946907151, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.309441048384, 606.5966132521871, 622.9532785041126, 636.4462917261983, 652.827000844776, 35654.286914261735, 39020.0, 39217.41173882394, 47726.28410573844, 48802.75598622002, 50538.0, 50881.0, 50881.23678144831, 50938.74530627351, 51003.0, 9387.57755900986, 9545.761355966122, 9549.379290346105, 9556.61773529058, 9559.522023898811, 8630.525321107336, 8846.159614836612, 9320.81358372832, 11016.614418495354, 11292.040176585, 11426.143039272047, 11484.942575287112, 11494.655160345203, 11500.137489688264, 11513.769724605503, 11210.103191744682, 11297.322160670386, 11307.49116289769, 11333.829992550036, 11353.545858165664, 8419.410641255115, 8654.78363040929, 10763.569457221702, 11093.112550995982, 11104.555817767361, 46110.0, 46492.302615460794, 46534.06931861344, 46615.766921165516, 46632.0, 3886.4753258310047, 4025.1748391579654, 4290.292101803198, 4355.978220108911, 4409.074094440171, 35680.643193567994, 36073.81963090194, 36287.274254515025, 37934.81032594847, 38180.61819381826, 4402.1855956647805, 4428.601425871687, 4451.710638808499, 4480.193565158303, 4484.304932735431, 391755.0, 397320.0, 397753.0, 398159.0, 399073.0, 68253.3174668247, 68697.0, 68744.31255687379, 68758.65620671865, 68808.0, 72720.0, 72792.0, 72831.63584182151, 72843.27156728471, 72878.0, 19749.40751777449, 19994.70007949882, 20033.09851056706, 20088.799112008986, 20095.598088038158, 13649.0, 14660.56018319452, 14726.042807217496, 14742.336594853257, 14780.556583302518, 18438.907805461156, 19208.003334722856, 21543.78456215449, 21644.675329870064, 21683.891580541997, 7180.291668123854, 7275.927240727525, 7398.519096258726, 7536.7362142324755, 7563.621818909032, 956.5982287439292, 1043.410473082706, 1064.0795711709316, 1067.9626213082504, 1102.72431892027, 34035.31929361399, 34507.482387764205, 34810.65189348125, 35031.82484087563, 35470.11324716903, 1082.9566817327377, 1108.539955918301, 1158.2818652435376, 1181.6927598824368, 1267.714764178071, 18296.268149274147, 19298.80701192998, 19382.0, 19419.673889297057, 19657.918814465054, 554948.0, 555205.0, 557193.0, 558286.0, 560910.0, 66702.0, 67289.66355168291, 67368.66315668488, 67417.66291168611, 67505.66247168733, 1285.2160182288796, 1351.4053816320788, 1394.4073768648284, 1401.3833913078213, 1445.0607105381375, 33305.50041749327, 35727.28545429129, 35800.46299305461, 37389.0, 37638.0, 62296.68851655713, 64204.35795641984, 64261.0, 68994.65502672455, 69102.65448672725, 548958.0, 552576.0, 554669.0, 555585.0, 556254.0, 41046.58953410547, 41585.37621935734, 46277.0, 47932.0, 48185.75907120442, 53410.46589534055, 53752.73123634357, 54046.0, 54070.0, 54317.72841135769, 43665.34501982536, 44273.77863110664, 44877.326840098074, 46331.30503042389, 46774.298385523565, 44730.77634611806, 45468.772656136505, 45798.77100614476, 46459.0, 46491.53508465007, 43758.56241437672, 43828.780856095516, 44586.3312050313, 44946.775266124765, 45084.32373514334, 38215.80892095522, 39164.412533811446, 39562.0, 39994.20011599694, 40471.59528404678, 28741.568876466452, 29421.558676620298, 29503.26241843971, 29627.259318517208, 29831.850840745657], "value": 22687.08914405657, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.10217521452813, 424.0214888718342, 426.8676710219832, 431.93952846601474, 435.48612637088235, 19159.137838797255, 19889.801101988956, 20301.18795248192, 20512.76923384598, 20866.165353385866, 16750.916245418757, 18735.437936861916, 20800.791992080074, 20951.37145885622, 21100.0, 5839.7664093436315, 6168.352323006087, 6172.5370597205165, 6174.9073763893575, 6175.41333573312, 4454.109178164364, 4550.135474259897, 4561.543845615429, 4564.360989461478, 6041.758329666808, 6742.595444273335, 6790.422814060811, 6826.5562738422095, 6842.692078856463, 6869.828254293658, 6544.738210471575, 6710.630915299656, 6791.422729068036, 6792.252852186305, 6828.2488926218075, 5934.673592952386, 6021.488173505258, 6059.212302400704, 6062.302835173968, 6063.605865618721, 23930.401739956465, 24274.757252427426, 24281.878590606935, 24333.14833980802, 24467.143649972164, 2576.0468626608276, 2593.312772115391, 2699.0283497940727, 3018.154916623349, 3033.2416895776055, 14952.626184345478, 15459.922700386425, 15692.372305107896, 15753.842461575352, 16081.758773618405, 1985.1563085688524, 2007.9498012549802, 2075.0199521149225, 2080.8023237792536, 2105.514181666429, 65483.3451665482, 68595.65702171507, 68671.96992045123, 72930.27069729235, 84242.15757842404, 11606.651800446, 11683.0, 11706.70733231676, 11808.0, 11889.583864564698, 16522.0, 16565.58586035359, 16569.25438355277, 16585.336586536403, 16598.502044938672, 9057.139571740674, 9271.582778774971, 9382.953085234667, 9387.436753794795, 9559.904400955902, 9105.317101217435, 9162.0, 9204.907950920538, 9428.858567121499, 9449.291303152291, 7088.255733148048, 7114.110736157981, 7121.857562848714, 7213.819654508679, 7273.63631818407, 1933.2943475631293, 1950.6781381071964, 1993.760748710135, 2038.622854771872, 2081.375587323797, 921.2906062331967, 974.5224839828575, 1004.2267454060333, 1011.5903059261011, 1039.7244730146517, 18923.337683181297, 19804.108815103355, 19871.801281986995, 20740.792592074187, 21407.57184856294, 1018.5467466977124, 1038.8805287391895, 1039.2257767962917, 1065.4299949526967, 1088.6516314779353, 10627.0, 11380.203385763083, 11384.715382115513, 11423.885761142448, 11544.191906566628, 518819.0, 520152.0, 531771.0, 532094.0, 532233.0, 97266.51366743121, 100916.0, 100965.49517252366, 101171.0, 103563.48218259012, 1081.648464249138, 1146.7878442488166, 1157.346099453806, 1163.8137897936458, 1164.91263155262, 17622.735658965383, 18148.455546333633, 18160.0, 18229.54426139357, 18588.349407770664, 44361.0, 44550.55449445594, 45509.544904551854, 45604.315935261664, 46109.30836037395, 62957.37042629515, 62979.0, 63855.680721596094, 64300.35699642944, 64339.0, 9177.571670314053, 9199.310051746012, 9219.907800921905, 9368.953155234452, 9612.0, 10829.24195306321, 12630.115891887479, 13683.0, 13766.65583360424, 14100.576982690536, 12334.691632709255, 12502.812457813323, 12599.93700031494, 12608.0, 12629.684257893627, 12020.939895300467, 12092.214006089576, 12398.0, 12573.0570207233, 12684.746305074032, 12068.577599783965, 12112.091593130373, 12283.75432491363, 12505.0, 12797.360131993177, 9318.767030824283, 9405.43567385959, 9451.480168590793, 9533.427994320364, 9591.616335346787, 12147.271163730204, 12310.384480776105, 12566.623001309976, 12952.417141228856, 13028.869711302767], "value": 10019.419179839962, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.4168644952988, 1519.8708109810668, 1576.3930886608655, 1741.7822772153481, 1880.8965506897116, 8403.705870294541, 9127.452352858825, 9491.430514169155, 9495.0, 9914.355566888147, 9715.708528744131, 9780.608775648965, 9816.263780216477, 9817.852732209023, 9820.312578119534, 2488.091846476041, 2507.5486412445753, 2512.246326102171, 2518.7355327690602, 2524.7853932415765, 2419.0444774314137, 2463.581191197492, 2470.1354525915876, 2470.481198948223, 2471.9741307357426, 2429.2712186344115, 2439.4389290463205, 2527.9747202528015, 2529.9241022769256, 2535.1127105513015, 2393.3537944754994, 2412.264259400882, 2422.3701837522285, 2426.733059363469, 2438.6098224284206, 2383.4279772854566, 2393.2939782764124, 2401.735809061002, 2409.120670955089, 2415.311636183697, 9392.389494682824, 9439.056094390575, 9571.569279382444, 9578.712638620853, 9579.0, 5491.807786727445, 5650.237217975562, 5695.886082278331, 5704.543636509069, 5721.828345149652, 3643.107438677541, 3670.743047986642, 3676.540432445944, 3749.1751814600743, 3749.193923306475, 1195.378403230324, 1202.903767698582, 1216.4915504774106, 1233.5250928392634, 1250.7435975624976, 12857.549985750547, 12870.613881583567, 12878.87121128786, 12887.420066096955, 13027.934860325639, 4522.88692782683, 4689.554492323292, 4804.231322988305, 4822.662413631012, 4854.368932038883, 10329.9483502582, 10364.06723394883, 10366.429846358373, 10377.429241391796, 10377.792444151226, 5490.231367608538, 5490.780368785284, 5495.093309603951, 5500.834974950758, 5503.504684578407, 4673.7429441381055, 4822.590079843182, 5001.274815151783, 5079.771410286546, 5121.590272778169, 4161.897620081261, 4181.6236538711055, 4254.914901701949, 4259.446271984622, 4306.633936115402, 1222.8043513037871, 1230.1143176912608, 1236.4683186229827, 1245.1844042152404, 1249.7812882745484, 949.7340744591526, 968.7190714692802, 973.0072158927773, 980.5881529757519, 981.9754506137403, 5984.850378740479, 6042.365551617105, 6198.81403557894, 6342.5560210785725, 6363.331850155667, 503.0, 503.55686995444506, 512.3518748782793, 515.3660996973744, 517.1157320981089, 3920.5491368492417, 4064.20747954152, 4079.4492743479846, 4088.713790034669, 4104.240715467588, 11115.277506962022, 11870.525178992744, 12043.83211022007, 12064.819027714591, 12080.335581543099, 11626.65120046383, 11866.228695134789, 11953.52185912571, 11969.162158648985, 11984.101192410604, 8904.777380565538, 8987.595558199897, 8989.595468203946, 8989.820203595891, 9009.279257659371, 6107.4197951194565, 6186.4741496973265, 6239.594426362272, 6260.937390626035, 6314.842128946813, 10808.783824323627, 10867.456627168767, 10890.401027943539, 10990.285631433931, 10998.450077495932, 10942.398168100832, 10991.230613856953, 11072.280301780358, 11297.661070167907, 11366.829497557708, 7185.461090418344, 7356.926430735838, 7739.3034626883855, 8045.9597702011115, 8098.7165449209, 9087.909120908971, 9424.057594240521, 9438.764030899283, 9445.24438044941, 9463.526823658653, 8290.37822163328, 8364.247217750431, 8418.36862235347, 8444.028936672115, 8554.743357699279, 8003.0, 8029.7189598363775, 8090.514569125871, 8108.472949258279, 8362.121977192308, 6918.204406493318, 7031.578105313698, 7173.928260717326, 7231.819204519929, 7287.380572651384, 5388.649737767033, 5391.137418013099, 5454.781808727606, 5482.205080263419, 5628.409017053151, 5359.330083739533, 5475.479829416358, 5535.062728045152, 5633.6619802811965, 5674.205611214516], "value": 4809.578913274267, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.9469079638054, 362.37671205526493, 362.7315786318124, 364.28418158318897, 371.153769405755, 5573.136163894593, 5630.605857589971, 5649.915251271234, 5654.858628534289, 5667.971660141704, 5476.58925580581, 5507.146392309189, 5553.139263414178, 5567.805126820562, 5568.136938774496, 4366.214081465335, 4412.8014239359145, 4414.8675539733695, 4414.97792511037, 4415.22733521634, 4381.649468042565, 4384.934225986613, 4387.890302742441, 4397.824087036514, 4416.536263692315, 4365.476142862845, 4373.934390984138, 4382.0, 4387.9561204387865, 4403.669724770639, 4312.266914624522, 4337.84817531388, 4346.6522678185665, 4356.259435895921, 4365.934510982338, 3893.3186692328727, 3901.609839016104, 3902.570717221103, 3904.882853514399, 3908.882733517971, 5369.758360873731, 5398.811041613564, 5399.19012148177, 5418.29562156921, 5424.159255315393, 4877.512248775094, 4984.77568509418, 4995.725235112068, 5004.549590536869, 5034.773435195425, 5710.172025056385, 5733.054046082391, 5736.0, 5745.942540574582, 5766.769329226826, 4299.828006879721, 4313.568643135693, 4314.546972567898, 4325.480942286946, 4334.436523251989, 5633.521150702195, 5657.462541058593, 5839.21903819335, 5946.613470124471, 6005.909911351333, 4195.0, 4285.164392943346, 4299.9570004300185, 4307.310830267143, 4308.80610372534, 4344.391785150082, 4346.348047792793, 4350.108227813291, 4350.695451318441, 4393.604575588212, 4677.654022434893, 4781.258904869681, 4953.8018479261145, 4960.206366981266, 5030.0, 5568.32950114972, 5737.397573254748, 5769.509591684669, 5783.88432231353, 5801.419858014165, 1508.1403599948144, 1515.6514001779653, 1516.7194069097252, 1523.3525751555544, 1530.9693806123814, 932.2076235200026, 974.7758015656296, 977.6480467032005, 980.460746589373, 993.8360170571775, 4793.904121917542, 4884.633652476078, 4891.682040667345, 4908.533689299511, 4932.926006109911, 5140.794368225229, 5151.531813433154, 5158.38099428071, 5160.400275914479, 5189.974050129726, 4619.422572178478, 4894.846267642226, 4895.926561101586, 4909.337239472626, 4936.777844996984, 2739.7534221919723, 2746.901239504214, 2768.4878297515024, 2773.1403264987916, 2775.403288293027, 5352.6253162279045, 5364.544013758796, 5372.811951581676, 5374.677519348852, 5383.546442460513, 5217.426083130853, 5228.947710522846, 5660.632058916157, 6067.393260673985, 6100.69496525172, 6122.326544080148, 6122.969385153135, 6136.079588061826, 6137.84655383619, 6139.969300153471, 5698.943010569841, 5952.5833191676165, 5976.820695379146, 5992.5617851714405, 5993.280806303272, 5854.970725146347, 5894.263217097863, 5902.350741418355, 5907.9704601476715, 5913.46778789911, 5883.2057672215415, 5969.880602388015, 5986.640801551921, 6013.9699301503215, 6170.7041521281035, 4055.8377664893073, 4161.20937021977, 4287.721298115613, 4339.132173565236, 4348.260795664808, 5745.610090046893, 5919.437653422918, 5921.230240068763, 5948.137520059654, 5965.492933100735, 5784.855378615568, 5791.218185544897, 5801.912971305518, 5811.622244554091, 5812.360640329645, 5721.542276617777, 5766.826995190151, 5778.855528611818, 5828.737706803119, 5896.64620122794, 5512.779488820403, 5706.400827913009, 5711.857203569944, 5726.942730572641, 5840.386759390203, 5791.7683292667825, 5797.333306669787, 5812.534997200299, 5814.709264536672, 5835.416458354131, 5621.095431005106, 5633.971830140823, 5735.713214339183, 5744.310682717934, 5756.3380211276235], "value": 4356.1870473054205, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.7678819565931, 773.7562667759655, 823.9670413183472, 848.0247715127603, 870.4733636150125, 15095.698086038272, 16021.91989040056, 16029.438969636065, 16033.0, 16033.438829640965, 15126.924365378185, 15371.461998830044, 15474.845251547482, 15489.535313940572, 15547.611309717235, 732.8790749526322, 750.5721738608995, 752.0298814529278, 758.3743411685382, 860.9913900861005, 880.190224993008, 891.2424439226638, 894.8255090257402, 897.5467388968594, 897.5915958238986, 935.3592788939599, 946.6497395963503, 946.820104180207, 947.1664934857316, 947.469417126408, 763.1338430880962, 782.0693374883898, 889.301898010059, 903.0112027330063, 946.3186505715908, 674.7672053141658, 675.5203805298244, 676.1750664189681, 676.313541755119, 676.5872817581271, 16066.919665401598, 16097.19514024294, 16119.677606447805, 16122.677546449122, 16123.355065797354, 14799.70400591982, 14827.036242644193, 14966.251687415695, 15281.235938203044, 15304.311305991258, 14546.199959002251, 14600.853991460162, 14662.340194691158, 14670.486532971296, 14895.331714888342, 4647.86056418308, 4659.4408670959365, 4697.976510117427, 4846.466888642247, 4910.116179087763, 89564.55217723935, 91013.54493227557, 91103.08896911012, 92068.0, 92324.0, 12309.322987235788, 12458.377081145907, 12477.875221247852, 12656.81014784779, 12682.175658582162, 17745.11274436273, 17762.46712598624, 17788.822111778976, 17820.46538603844, 17852.732209016875, 9274.629014839466, 9334.953325233331, 9336.626534938527, 9427.104425079606, 9543.713688589354, 4992.825251116194, 5283.841484755463, 5309.203619457112, 5323.893522129613, 5467.917981230285, 3700.130469339703, 3841.9423708644395, 3856.9228615427533, 3869.187470631143, 3875.399313106473, 647.2200997797643, 658.4436151452015, 660.0330515794384, 685.6126288646899, 740.4039748002909, 693.569986608296, 694.9756758513529, 756.8334966307403, 758.5031804168278, 776.5146783260462, 9154.67958621445, 9598.42409455435, 9625.807483850283, 9907.752306192402, 10248.743781405376, 720.7333286683961, 741.9674071421839, 769.6728890221633, 786.598834594361, 815.2662603656756, 4316.568343165721, 4498.8425405110665, 4500.852396622138, 4510.977445112753, 4706.8117275308605, 78658.6067069661, 103968.48015759872, 105707.47146264219, 109348.4532577332, 121927.39036304761, 90602.54698726464, 93157.0, 100550.4972475133, 102709.0, 104184.0, 88237.0, 88726.55636721775, 89366.55316723505, 89510.0, 93823.53088234515, 10158.843760561233, 10408.344100073831, 10425.322354046963, 10564.683059508227, 10583.894161058292, 55370.72314638562, 55414.72292638511, 56452.717736411054, 56509.15236271377, 57035.71482142563, 51723.482765171866, 53352.0, 61786.69106654438, 81488.59255703921, 81874.0, 13660.759797307443, 14091.859081409055, 14258.358373872994, 14288.142711437347, 14527.273636318354, 14896.255187240811, 15387.846121538641, 16316.755248671518, 16352.182390880645, 16439.0, 14737.926310368379, 14800.259987000392, 15078.472253471076, 15259.237038147827, 15598.220088995731, 14559.92720036393, 14762.704745904806, 15602.141882196589, 15769.0, 15818.683626327178, 14558.199299038655, 14602.926985365006, 15068.773968390264, 15569.065856048674, 16310.429134980219, 11532.654020379403, 11858.288502689866, 12048.638540843789, 12126.393680316125, 12739.490420383081, 10966.670999870017, 10974.890251097388, 11092.500837462172, 11461.770764584495, 11510.424478776195], "value": 7990.834889280315, "unit": "score"}], "name": "dromaeo_css", "value": 7703.728411483004, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 109.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 109.0, 109.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 116.0, 121.0, 156.0, 123.0, 121.0, 116.0, 117.0, 146.0, 116.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 161.0, 162.0, 161.0, 162.0, 160.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 108.0, 77.0, 76.0, 77.0, 77.0, 102.0, 77.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 117.0, 81.0, 82.0, 81.0, 102.0, 81.0, 81.0, 110.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 100.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 48.0, 47.0, 47.0, 50.0, 47.0, 48.0, 48.0, 48.0, 47.0], "value": 48.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 58.0, 58.0, 58.0, 59.0, 58.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 74.0, 73.0, 87.0, 74.0, 72.0, 73.0, 81.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 130.0, 128.0, 121.0, 120.0, 128.0, 119.0, 118.0, 171.0, 125.0], "value": 128.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 156.0, 154.0, 152.0, 157.0, 155.0, 151.0, 152.0, 152.0, 156.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 64.0, 62.0, 62.0, 62.0, 62.0, 60.0, 61.0, 59.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1455.1, "alertThreshold": 2.0}]} 19:46:44 INFO - Return code: 0 19:46:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:46:44 INFO - # TBPL SUCCESS # 19:46:44 INFO - Running post-action listener: _resource_record_post_action 19:46:44 INFO - [mozharness: 2016-11-11 03:46:44.225838Z] Finished run-tests step (success) 19:46:44 INFO - Running post-run listener: _resource_record_post_run 19:46:44 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 370262016; Read time: 12; Write time: 219680 19:46:44 INFO - TinderboxPrint: CPU usage
13.0% 19:46:44 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 19:46:44 INFO - TinderboxPrint: I/O write bytes / time
370,262,016 / 219,680 19:46:44 INFO - TinderboxPrint: CPU idle
8,259.5 (87.0%) 19:46:44 INFO - TinderboxPrint: CPU user
1,187.5 (12.5%) 19:46:44 INFO - TinderboxPrint: Swap in / out
0 / 0 19:46:44 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118947840; Read time: 0; Write time: 73164 19:46:44 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 251314176; Read time: 0; Write time: 146516 19:46:44 INFO - Running post-run listener: _upload_blobber_files 19:46:44 INFO - Blob upload gear active. 19:46:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:46:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:46:44 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'] 19:46:44 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 19:46:45 INFO - (blobuploader) - INFO - Open directory for files ... 19:46:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:46:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:46:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:46:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:46:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:46:48 INFO - (blobuploader) - INFO - Done attempting. 19:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:46:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:46:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:46:49 INFO - (blobuploader) - INFO - Done attempting. 19:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:46:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:46:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:46:49 INFO - (blobuploader) - INFO - Done attempting. 19:46:49 INFO - (blobuploader) - INFO - Iteration through files over. 19:46:49 INFO - Return code: 0 19:46:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:46:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:46:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41654509bb3e0008e63fff0dab549f53bf89e77c05ba6669cb13da6222346452c0614bf7bef0c99aff2b07697aa8c849a049c300aa37d2f00e1f34d1b1b2a7e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b2c36e89990168dfd4a171129cc43e48ce81ccc8811526c0149bccbebaa797e3d5d9b38aca721daceb63fd3922839dcd63afadeddf775f62a892c82185983fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/60505c698890b518db662b267ad2f83ecddd9e21848271dbfd7f123837961384d4b2b34438b23ce39a0fd5fec7cdcd3fb380583fb3cd1b44669a04e3f3626bba"} 19:46:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:46:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:46:49 INFO - Contents: 19:46:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41654509bb3e0008e63fff0dab549f53bf89e77c05ba6669cb13da6222346452c0614bf7bef0c99aff2b07697aa8c849a049c300aa37d2f00e1f34d1b1b2a7e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b2c36e89990168dfd4a171129cc43e48ce81ccc8811526c0149bccbebaa797e3d5d9b38aca721daceb63fd3922839dcd63afadeddf775f62a892c82185983fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/60505c698890b518db662b267ad2f83ecddd9e21848271dbfd7f123837961384d4b2b34438b23ce39a0fd5fec7cdcd3fb380583fb3cd1b44669a04e3f3626bba"} 19:46:49 INFO - Running post-run listener: copy_logs_to_upload_dir 19:46:49 INFO - Copying logs to upload dir... 19:46:49 INFO - mkdir: /builds/slave/test/build/upload/logs 19:46:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.235808 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-11-10 19:46:49.042139) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 19:46:49.045998) ========= 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=3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270 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/41654509bb3e0008e63fff0dab549f53bf89e77c05ba6669cb13da6222346452c0614bf7bef0c99aff2b07697aa8c849a049c300aa37d2f00e1f34d1b1b2a7e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b2c36e89990168dfd4a171129cc43e48ce81ccc8811526c0149bccbebaa797e3d5d9b38aca721daceb63fd3922839dcd63afadeddf775f62a892c82185983fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/60505c698890b518db662b267ad2f83ecddd9e21848271dbfd7f123837961384d4b2b34438b23ce39a0fd5fec7cdcd3fb380583fb3cd1b44669a04e3f3626bba"} build_url:https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011694 build_url: 'https://queue.taskcluster.net/v1/task/GjUzrFcoTr-4YjwpAh7WjQ/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/41654509bb3e0008e63fff0dab549f53bf89e77c05ba6669cb13da6222346452c0614bf7bef0c99aff2b07697aa8c849a049c300aa37d2f00e1f34d1b1b2a7e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b2c36e89990168dfd4a171129cc43e48ce81ccc8811526c0149bccbebaa797e3d5d9b38aca721daceb63fd3922839dcd63afadeddf775f62a892c82185983fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/60505c698890b518db662b267ad2f83ecddd9e21848271dbfd7f123837961384d4b2b34438b23ce39a0fd5fec7cdcd3fb380583fb3cd1b44669a04e3f3626bba"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 19:46:49.078498) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 19:46:49.079105) ========= 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=3a3620fb28aea1604ab609ef000001d2-1478831114.98607-1080281270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 19:46:49.163011) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 19:46:49.163585) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 19:46:49.164105) ========= ========= Total master_lag: 0.18 =========