builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-043 starttime: 1479136769.84 results: success (0) buildid: 20161114015620 builduid: 7575ab475a3f4481a1ee0dc2e232252d revision: 0f867a8846778f927cfb3871e58cc61f6193ad5d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:29.837125) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:29.837622) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:29.837936) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:29.852835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:29.853148) ========= 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=d2a8c569965ab8367867b893000001a1-1479136770.875093-448316840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-14 07:19:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 56.4M=0s 2016-11-14 07:19:31 (56.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103972 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:29.971208) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:29.971629) ========= 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=d2a8c569965ab8367867b893000001a1-1479136770.875093-448316840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015110 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:30.020948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:30.021313) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0f867a8846778f927cfb3871e58cc61f6193ad5d --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 0f867a8846778f927cfb3871e58cc61f6193ad5d --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=d2a8c569965ab8367867b893000001a1-1479136770.875093-448316840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-14 07:19:31,857 truncating revision to first 12 chars 2016-11-14 07:19:31,857 Setting DEBUG logging. 2016-11-14 07:19:31,857 attempt 1/10 2016-11-14 07:19:31,857 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0f867a884677?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 07:19:32,153 unpacking tar archive at: mozilla-aurora-0f867a884677/testing/mozharness/ program finished with exit code 0 elapsedTime=0.659054 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:30.694402) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:30.694807) ========= script_repo_revision: 0f867a8846778f927cfb3871e58cc61f6193ad5d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:30.695282) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:30.695590) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 07:19:30.704718) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-11-14 07:19:30.705182) ========= /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=d2a8c569965ab8367867b893000001a1-1479136770.875093-448316840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:19:32 INFO - MultiFileLogger online at 20161114 07:19:32 in /builds/slave/test 07:19:32 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 07:19:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:19:32 INFO - {'append_to_log': False, 07:19:32 INFO - 'base_work_dir': '/builds/slave/test', 07:19:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:19:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:19:32 INFO - 'branch': 'Mozilla-Aurora', 07:19:32 INFO - 'buildbot_json_path': 'buildprops.json', 07:19:32 INFO - 'config_files': ('talos/linux_config.py',), 07:19:32 INFO - 'default_actions': ('clobber', 07:19:32 INFO - 'read-buildbot-config', 07:19:32 INFO - 'download-and-extract', 07:19:32 INFO - 'populate-webroot', 07:19:32 INFO - 'create-virtualenv', 07:19:32 INFO - 'install', 07:19:32 INFO - 'run-tests'), 07:19:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:19:32 INFO - 'download_minidump_stackwalk': True, 07:19:32 INFO - 'download_symbols': 'ondemand', 07:19:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:19:32 INFO - 'tooltool.py': '/tools/tooltool.py', 07:19:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:19:32 INFO - '/tools/misc-python/virtualenv.py')}, 07:19:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:19:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:19:32 INFO - 'installer_path': 'installer.exe', 07:19:32 INFO - 'log_level': 'info', 07:19:32 INFO - 'log_name': 'talos', 07:19:32 INFO - 'log_to_console': True, 07:19:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:19:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:19:32 INFO - 'opt_config_files': (), 07:19:32 INFO - 'pip_index': False, 07:19:32 INFO - 'sps_profile': False, 07:19:32 INFO - 'sps_profile_interval': 0, 07:19:32 INFO - 'suite': 'dromaeojs', 07:19:32 INFO - 'system_bits': '32', 07:19:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:19:32 INFO - 'title': 'talos-linux64-ix-043', 07:19:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:19:32 INFO - 'use_talos_json': True, 07:19:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:19:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:19:32 INFO - 'work_dir': 'build'} 07:19:32 INFO - [mozharness: 2016-11-14 15:19:32.631012Z] Running clobber step. 07:19:32 INFO - Running pre-action listener: _resource_record_pre_action 07:19:32 INFO - Running main action method: clobber 07:19:32 INFO - rmtree: /builds/slave/test/build 07:19:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:19:33 INFO - Running post-action listener: _resource_record_post_action 07:19:33 INFO - [mozharness: 2016-11-14 15:19:33.608667Z] Finished clobber step (success) 07:19:33 INFO - [mozharness: 2016-11-14 15:19:33.608766Z] Running read-buildbot-config step. 07:19:33 INFO - Running pre-action listener: _resource_record_pre_action 07:19:33 INFO - Running main action method: read_buildbot_config 07:19:33 INFO - Using buildbot properties: 07:19:33 INFO - { 07:19:33 INFO - "project": "", 07:19:33 INFO - "product": "firefox", 07:19:33 INFO - "script_repo_revision": "production", 07:19:33 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:19:33 INFO - "repository": "", 07:19:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:19:33 INFO - "buildid": "20161114015620", 07:19:33 INFO - "pgo_build": "True", 07:19:33 INFO - "basedir": "/builds/slave/test", 07:19:33 INFO - "buildnumber": 589, 07:19:33 INFO - "slavename": "talos-linux64-ix-043", 07:19:33 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:19:33 INFO - "platform": "ubuntu64_hw", 07:19:33 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:19:33 INFO - "branch": "mozilla-aurora", 07:19:33 INFO - "repo_path": "releases/mozilla-aurora", 07:19:33 INFO - "revision": "0f867a8846778f927cfb3871e58cc61f6193ad5d", 07:19:33 INFO - "stage_platform": "linux64-pgo", 07:19:33 INFO - "builduid": "7575ab475a3f4481a1ee0dc2e232252d", 07:19:33 INFO - "slavebuilddir": "test" 07:19:33 INFO - } 07:19:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 07:19:33 INFO - Running post-action listener: _resource_record_post_action 07:19:33 INFO - [mozharness: 2016-11-14 15:19:33.611197Z] Finished read-buildbot-config step (success) 07:19:33 INFO - [mozharness: 2016-11-14 15:19:33.611305Z] Running download-and-extract step. 07:19:33 INFO - Running pre-action listener: _resource_record_pre_action 07:19:33 INFO - Running main action method: download_and_extract 07:19:33 INFO - mkdir: /builds/slave/test/build/tests 07:19:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:19:33 INFO - trying https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:19:33 INFO - Downloading https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/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 07:19:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/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 07:19:34 INFO - Downloaded 1361 bytes. 07:19:34 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:19:34 INFO - Using the following test package requirements: 07:19:34 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 07:19:34 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:19:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:19:34 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:19:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:19:34 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:19:34 INFO - u'jsshell-linux-x86_64.zip'], 07:19:34 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:19:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:19:34 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 07:19:34 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:19:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:19:34 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:19:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:19:34 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:19:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:19:34 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:19:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:19:34 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:19:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 07:19:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:19:34 INFO - Fetch https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 07:19:34 INFO - Expected file size: 21962623 07:19:39 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 07:19:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 07:19:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:19:39 INFO - Fetch https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 07:19:39 INFO - Expected file size: 21962623 07:19:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 07:19:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:19:44 INFO - Fetch https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 07:19:44 INFO - Expected file size: 14414089 07:19:45 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')]} 07:19:45 INFO - trying https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:19:45 INFO - Downloading https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/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 07:19:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/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 07:19:46 INFO - Downloaded 64070902 bytes. 07:19:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:19:46 INFO - mkdir: /builds/slave/test/properties 07:19:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:19:46 INFO - Writing to file /builds/slave/test/properties/build_url 07:19:46 INFO - Contents: 07:19:46 INFO - build_url:https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:19:47 INFO - Running post-action listener: _resource_record_post_action 07:19:47 INFO - Running post-action listener: set_extra_try_arguments 07:19:47 INFO - [mozharness: 2016-11-14 15:19:47.245302Z] Finished download-and-extract step (success) 07:19:47 INFO - [mozharness: 2016-11-14 15:19:47.245530Z] Running populate-webroot step. 07:19:47 INFO - Running pre-action listener: _resource_record_pre_action 07:19:47 INFO - Running main action method: populate_webroot 07:19:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:19:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:19:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:19:47 INFO - '--tppagecycles', 07:19:47 INFO - '7'], 07:19:47 INFO - 'tests': ['tsvgm']}}, 07:19:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:19:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:19:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:19:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:19:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:47 INFO - 'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:19:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:19:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:47 INFO - 'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['damp', 'tps']}, 07:19:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:47 INFO - 'tests': ['damp', 'tps']}, 07:19:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['dromaeo_dom']}, 07:19:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:19:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['basic_compositor_video']}, 07:19:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:19:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['a11yr', 07:19:47 INFO - 'ts_paint', 07:19:47 INFO - 'tpaint', 07:19:47 INFO - 'sessionrestore', 07:19:47 INFO - 'sessionrestore_no_auto_restore', 07:19:47 INFO - 'tabpaint']}, 07:19:47 INFO - 'other-e10s': {'tests': ['a11yr', 07:19:47 INFO - 'ts_paint', 07:19:47 INFO - 'tpaint', 07:19:47 INFO - 'sessionrestore', 07:19:47 INFO - 'sessionrestore_no_auto_restore', 07:19:47 INFO - 'tabpaint']}, 07:19:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:19:47 INFO - 'ts_paint', 07:19:47 INFO - 'tpaint', 07:19:47 INFO - 'sessionrestore', 07:19:47 INFO - 'sessionrestore_no_auto_restore', 07:19:47 INFO - 'tabpaint']}, 07:19:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:19:47 INFO - 'ts_paint', 07:19:47 INFO - 'tpaint', 07:19:47 INFO - 'sessionrestore', 07:19:47 INFO - 'sessionrestore_no_auto_restore', 07:19:47 INFO - 'tabpaint']}, 07:19:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['a11yr', 07:19:47 INFO - 'ts_paint', 07:19:47 INFO - 'tpaint', 07:19:47 INFO - 'sessionrestore', 07:19:47 INFO - 'sessionrestore_no_auto_restore', 07:19:47 INFO - 'tabpaint']}, 07:19:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['a11yr', 07:19:47 INFO - 'ts_paint', 07:19:47 INFO - 'tpaint', 07:19:47 INFO - 'sessionrestore', 07:19:47 INFO - 'sessionrestore_no_auto_restore', 07:19:47 INFO - 'tabpaint']}, 07:19:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['tsvgx', 07:19:47 INFO - 'tsvgr_opacity', 07:19:47 INFO - 'tart', 07:19:47 INFO - 'tscrollx', 07:19:47 INFO - 'cart']}, 07:19:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:19:47 INFO - 'tsvgr_opacity', 07:19:47 INFO - 'tart', 07:19:47 INFO - 'tscrollx', 07:19:47 INFO - 'cart']}, 07:19:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:47 INFO - 'talos_options': ['--disable-e10s'], 07:19:47 INFO - 'tests': ['tp5o']}, 07:19:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:47 INFO - 'tests': ['tp5o']}, 07:19:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:47 INFO - 'talos_options': ['--disable-e10s', 07:19:47 INFO - '--xperf_path', 07:19:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:19:47 INFO - 'tests': ['tp5n']}, 07:19:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:47 INFO - 'talos_options': ['--xperf_path', 07:19:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:19:47 INFO - 'tests': ['tp5n']}}, 07:19:47 INFO - 'talos.zip': {'path': '', 07:19:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:19:47 INFO - Running post-action listener: _resource_record_post_action 07:19:47 INFO - [mozharness: 2016-11-14 15:19:47.265143Z] Finished populate-webroot step (success) 07:19:47 INFO - [mozharness: 2016-11-14 15:19:47.265235Z] Running create-virtualenv step. 07:19:47 INFO - Running pre-action listener: _resource_record_pre_action 07:19:47 INFO - Running main action method: create_virtualenv 07:19:47 INFO - Creating virtualenv /builds/slave/test/build/venv 07:19:47 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 07:19:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:19:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:19:47 INFO - Using real prefix '/usr' 07:19:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:19:47 INFO - Installing distribute.............................................................................................................................................................................................done. 07:19:49 INFO - Installing pip.................done. 07:19:49 INFO - Return code: 0 07:19:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:19:49 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')]} 07:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:49 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')]} 07:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:49 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 0x7fa0a83faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2199ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, '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': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', '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 07:19:49 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 07:19:49 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 07:19:49 INFO - Using env: {'DISPLAY': ':0', 07:19:49 INFO - 'HOME': '/home/cltbld', 07:19:49 INFO - 'LANG': 'en_US.UTF-8', 07:19:49 INFO - 'LANGUAGE': 'en_US:en', 07:19:49 INFO - 'LOGNAME': 'cltbld', 07:19:49 INFO - 'MAIL': '/var/mail/cltbld', 07:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:49 INFO - 'MOZ_NO_REMOTE': '1', 07:19:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:19:49 INFO - 'NO_EM_RESTART': '1', 07:19:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:49 INFO - 'PWD': '/builds/slave/test', 07:19:49 INFO - 'SHELL': '/bin/bash', 07:19:49 INFO - 'SHLVL': '1', 07:19:49 INFO - 'TERM': 'linux', 07:19:49 INFO - 'TMOUT': '86400', 07:19:49 INFO - 'USER': 'cltbld', 07:19:49 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:49 INFO - '_': '/tools/buildbot/bin/python'} 07:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:50 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 07:19:50 INFO - Cleaning up... 07:19:50 INFO - Return code: 0 07:19:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:19: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')]} 07:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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')]} 07:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:50 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 0x7fa0a83faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2199ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, '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': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', '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 07:19:50 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 07:19:50 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 07:19:50 INFO - Using env: {'DISPLAY': ':0', 07:19:50 INFO - 'HOME': '/home/cltbld', 07:19:50 INFO - 'LANG': 'en_US.UTF-8', 07:19:50 INFO - 'LANGUAGE': 'en_US:en', 07:19:50 INFO - 'LOGNAME': 'cltbld', 07:19:50 INFO - 'MAIL': '/var/mail/cltbld', 07:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:50 INFO - 'MOZ_NO_REMOTE': '1', 07:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:19:50 INFO - 'NO_EM_RESTART': '1', 07:19:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:50 INFO - 'PWD': '/builds/slave/test', 07:19:50 INFO - 'SHELL': '/bin/bash', 07:19:50 INFO - 'SHLVL': '1', 07:19:50 INFO - 'TERM': 'linux', 07:19:50 INFO - 'TMOUT': '86400', 07:19:50 INFO - 'USER': 'cltbld', 07:19:50 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:50 INFO - '_': '/tools/buildbot/bin/python'} 07:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:50 INFO - Downloading/unpacking psutil>=3.1.1 07:19:50 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 07:19:50 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 07:19:50 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 07:19:50 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 07:19:50 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 07:19:50 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 07:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:19:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:19:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:19:52 INFO - Installing collected packages: psutil 07:19:52 INFO - Running setup.py install for psutil 07:19:52 INFO - building 'psutil._psutil_linux' extension 07:19:52 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 07:19:52 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 07:19:52 INFO - building 'psutil._psutil_posix' extension 07:19:52 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 07:19:52 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 07:19:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:19:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:19:52 INFO - Successfully installed psutil 07:19:52 INFO - Cleaning up... 07:19:52 INFO - Return code: 0 07:19:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:19:52 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')]} 07:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:52 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')]} 07:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:52 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 0x7fa0a83faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2199ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, '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': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', '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 07:19:52 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 07:19:52 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 07:19:52 INFO - Using env: {'DISPLAY': ':0', 07:19:52 INFO - 'HOME': '/home/cltbld', 07:19:52 INFO - 'LANG': 'en_US.UTF-8', 07:19:52 INFO - 'LANGUAGE': 'en_US:en', 07:19:52 INFO - 'LOGNAME': 'cltbld', 07:19:52 INFO - 'MAIL': '/var/mail/cltbld', 07:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:52 INFO - 'MOZ_NO_REMOTE': '1', 07:19:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:19:52 INFO - 'NO_EM_RESTART': '1', 07:19:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:52 INFO - 'PWD': '/builds/slave/test', 07:19:52 INFO - 'SHELL': '/bin/bash', 07:19:52 INFO - 'SHLVL': '1', 07:19:52 INFO - 'TERM': 'linux', 07:19:52 INFO - 'TMOUT': '86400', 07:19:52 INFO - 'USER': 'cltbld', 07:19:52 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:52 INFO - '_': '/tools/buildbot/bin/python'} 07:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:19:55 INFO - Installing collected packages: mozsystemmonitor 07:19:55 INFO - Running setup.py install for mozsystemmonitor 07:19:55 INFO - Successfully installed mozsystemmonitor 07:19:55 INFO - Cleaning up... 07:19:55 INFO - Return code: 0 07:19:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:19:55 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')]} 07:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:55 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')]} 07:19:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:55 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 0x7fa0a83faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2199ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, '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': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', '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 07:19:55 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 07:19:55 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 07:19:55 INFO - Using env: {'DISPLAY': ':0', 07:19:55 INFO - 'HOME': '/home/cltbld', 07:19:55 INFO - 'LANG': 'en_US.UTF-8', 07:19:55 INFO - 'LANGUAGE': 'en_US:en', 07:19:55 INFO - 'LOGNAME': 'cltbld', 07:19:55 INFO - 'MAIL': '/var/mail/cltbld', 07:19:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:55 INFO - 'MOZ_NO_REMOTE': '1', 07:19:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:19:55 INFO - 'NO_EM_RESTART': '1', 07:19:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:19:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:55 INFO - 'PWD': '/builds/slave/test', 07:19:55 INFO - 'SHELL': '/bin/bash', 07:19:55 INFO - 'SHLVL': '1', 07:19:55 INFO - 'TERM': 'linux', 07:19:55 INFO - 'TMOUT': '86400', 07:19:55 INFO - 'USER': 'cltbld', 07:19:55 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:19:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:55 INFO - '_': '/tools/buildbot/bin/python'} 07:19:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:55 INFO - Downloading/unpacking jsonschema==2.5.1 07:19:55 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 07:19:55 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 07:19:55 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 07:19:55 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 07:19:55 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 07:19:55 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 07:19:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:19:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:19:57 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 07:19:57 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 07:19:57 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 07:19:57 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 07:19:57 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 07:19:57 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 07:19:57 INFO - Downloading functools32-3.2.3-2.tar.gz 07:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:19:57 INFO - warning: no files found matching '*.txt' 07:19:57 INFO - no previously-included directories found matching 'build' 07:19:57 INFO - no previously-included directories found matching 'dist' 07:19:57 INFO - no previously-included directories found matching '.git*' 07:19:57 INFO - Installing collected packages: jsonschema, functools32 07:19:57 INFO - Running setup.py install for functools32 07:19:57 INFO - warning: no files found matching '*.txt' 07:19:57 INFO - no previously-included directories found matching 'build' 07:19:57 INFO - no previously-included directories found matching 'dist' 07:19:57 INFO - no previously-included directories found matching '.git*' 07:19:57 INFO - Successfully installed jsonschema functools32 07:19:57 INFO - Cleaning up... 07:19:57 INFO - Return code: 0 07:19:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:19:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa0a83faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2199ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, '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': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', '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 07:19:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:19:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:19:57 INFO - Using env: {'DISPLAY': ':0', 07:19:57 INFO - 'HOME': '/home/cltbld', 07:19:57 INFO - 'LANG': 'en_US.UTF-8', 07:19:57 INFO - 'LANGUAGE': 'en_US:en', 07:19:57 INFO - 'LOGNAME': 'cltbld', 07:19:57 INFO - 'MAIL': '/var/mail/cltbld', 07:19:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:57 INFO - 'MOZ_NO_REMOTE': '1', 07:19:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:19:57 INFO - 'NO_EM_RESTART': '1', 07:19:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:19:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:57 INFO - 'PWD': '/builds/slave/test', 07:19:57 INFO - 'SHELL': '/bin/bash', 07:19:57 INFO - 'SHLVL': '1', 07:19:57 INFO - 'TERM': 'linux', 07:19:57 INFO - 'TMOUT': '86400', 07:19:57 INFO - 'USER': 'cltbld', 07:19:57 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:19:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:57 INFO - '_': '/tools/buildbot/bin/python'} 07:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:19:58 INFO - Cleaning up... 07:19:58 INFO - Return code: 0 07:19:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:19:58 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')]} 07:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:58 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')]} 07:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:58 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 0x7fa0a83faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2199ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, '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': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', '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 07:19:58 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 07:19:58 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 07:19:58 INFO - Using env: {'DISPLAY': ':0', 07:19:58 INFO - 'HOME': '/home/cltbld', 07:19:58 INFO - 'LANG': 'en_US.UTF-8', 07:19:58 INFO - 'LANGUAGE': 'en_US:en', 07:19:58 INFO - 'LOGNAME': 'cltbld', 07:19:58 INFO - 'MAIL': '/var/mail/cltbld', 07:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:58 INFO - 'MOZ_NO_REMOTE': '1', 07:19:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:19:58 INFO - 'NO_EM_RESTART': '1', 07:19:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:58 INFO - 'PWD': '/builds/slave/test', 07:19:58 INFO - 'SHELL': '/bin/bash', 07:19:58 INFO - 'SHLVL': '1', 07:19:58 INFO - 'TERM': 'linux', 07:19:58 INFO - 'TMOUT': '86400', 07:19:58 INFO - 'USER': 'cltbld', 07:19:58 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:58 INFO - '_': '/tools/buildbot/bin/python'} 07:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:58 INFO - Downloading/unpacking blobuploader==1.2.4 07:19:58 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 07:19:58 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 07:19:58 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 07:19:58 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 07:19:58 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 07:19:58 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 07:20:00 INFO - Downloading blobuploader-1.2.4.tar.gz 07:20:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:20:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:20:00 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 07:20:00 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 07:20:00 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 07:20:00 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 07:20:00 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 07:20:00 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 07:20:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:20:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:20:00 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 07:20:00 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 07:20:00 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 07:20:00 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 07:20:00 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 07:20:00 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 07:20:00 INFO - Downloading docopt-0.6.1.tar.gz 07:20:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:20:00 INFO - Installing collected packages: blobuploader, requests, docopt 07:20:00 INFO - Running setup.py install for blobuploader 07:20:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:20:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:20:01 INFO - Running setup.py install for requests 07:20:01 INFO - Running setup.py install for docopt 07:20:01 INFO - Successfully installed blobuploader requests docopt 07:20:01 INFO - Cleaning up... 07:20:01 INFO - Return code: 0 07:20:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:01 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 0x7fa0a83faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2199ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, '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': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', '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 07:20:01 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 07:20:01 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 07:20:01 INFO - Using env: {'DISPLAY': ':0', 07:20:01 INFO - 'HOME': '/home/cltbld', 07:20:01 INFO - 'LANG': 'en_US.UTF-8', 07:20:01 INFO - 'LANGUAGE': 'en_US:en', 07:20:01 INFO - 'LOGNAME': 'cltbld', 07:20:01 INFO - 'MAIL': '/var/mail/cltbld', 07:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:01 INFO - 'MOZ_NO_REMOTE': '1', 07:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:01 INFO - 'NO_EM_RESTART': '1', 07:20:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:01 INFO - 'PWD': '/builds/slave/test', 07:20:01 INFO - 'SHELL': '/bin/bash', 07:20:01 INFO - 'SHLVL': '1', 07:20:01 INFO - 'TERM': 'linux', 07:20:01 INFO - 'TMOUT': '86400', 07:20:01 INFO - 'USER': 'cltbld', 07:20:01 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:01 INFO - '_': '/tools/buildbot/bin/python'} 07:20:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:20:01 INFO - Running setup.py (path:/tmp/pip-EM7cEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:20:01 INFO - Running setup.py (path:/tmp/pip-yq_8zQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:20:01 INFO - Running setup.py (path:/tmp/pip-WPNhF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:20:02 INFO - Running setup.py (path:/tmp/pip-biUOYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:20:02 INFO - Running setup.py (path:/tmp/pip-iGkmS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:20:02 INFO - Running setup.py (path:/tmp/pip-UgpwuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:20:02 INFO - Running setup.py (path:/tmp/pip-BWlXPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:20:02 INFO - Running setup.py (path:/tmp/pip-UgfzJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:20:02 INFO - Running setup.py (path:/tmp/pip-Kb1Pkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:20:02 INFO - Running setup.py (path:/tmp/pip-vW5htr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:20:02 INFO - Running setup.py (path:/tmp/pip-Tr5gEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:20:02 INFO - Running setup.py (path:/tmp/pip-eh2rve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:20:02 INFO - Running setup.py (path:/tmp/pip-71le0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:20:02 INFO - Running setup.py (path:/tmp/pip-oeEaii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:02 INFO - Running setup.py (path:/tmp/pip-QK9DFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:20:02 INFO - Running setup.py (path:/tmp/pip-pPRGdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:20:02 INFO - Running setup.py (path:/tmp/pip-UiJ0kE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:20:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:20:03 INFO - Running setup.py install for manifestparser 07:20:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:20:03 INFO - Running setup.py install for mozcrash 07:20:03 INFO - Running setup.py install for mozdebug 07:20:03 INFO - Running setup.py install for mozdevice 07:20:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:20:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:20:03 INFO - Running setup.py install for mozfile 07:20:03 INFO - Running setup.py install for mozhttpd 07:20:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:20:03 INFO - Running setup.py install for mozinfo 07:20:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:20:03 INFO - Running setup.py install for mozInstall 07:20:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:20:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:20:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:20:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:20:03 INFO - Running setup.py install for mozleak 07:20:03 INFO - Running setup.py install for mozlog 07:20:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:20:04 INFO - Running setup.py install for moznetwork 07:20:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:20:04 INFO - Running setup.py install for mozprocess 07:20:04 INFO - Running setup.py install for mozprofile 07:20:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:20:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:20:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:20:04 INFO - Running setup.py install for mozrunner 07:20:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:20:04 INFO - Running setup.py install for mozscreenshot 07:20:04 INFO - Running setup.py install for moztest 07:20:04 INFO - Running setup.py install for mozversion 07:20:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:20:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:20:04 INFO - Cleaning up... 07:20:04 INFO - Return code: 0 07:20:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:04 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 0x7fa0a83faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2199ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, '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': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', '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 07:20:04 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 07:20:04 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 07:20:04 INFO - Using env: {'DISPLAY': ':0', 07:20:04 INFO - 'HOME': '/home/cltbld', 07:20:04 INFO - 'LANG': 'en_US.UTF-8', 07:20:04 INFO - 'LANGUAGE': 'en_US:en', 07:20:04 INFO - 'LOGNAME': 'cltbld', 07:20:04 INFO - 'MAIL': '/var/mail/cltbld', 07:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:04 INFO - 'MOZ_NO_REMOTE': '1', 07:20:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:04 INFO - 'NO_EM_RESTART': '1', 07:20:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:04 INFO - 'PWD': '/builds/slave/test', 07:20:04 INFO - 'SHELL': '/bin/bash', 07:20:04 INFO - 'SHLVL': '1', 07:20:04 INFO - 'TERM': 'linux', 07:20:04 INFO - 'TMOUT': '86400', 07:20:04 INFO - 'USER': 'cltbld', 07:20:04 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:04 INFO - '_': '/tools/buildbot/bin/python'} 07:20:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:20:05 INFO - Running setup.py (path:/tmp/pip-VvEjnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:20:05 INFO - Running setup.py (path:/tmp/pip-vuf0sT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:20:05 INFO - Running setup.py (path:/tmp/pip-LJDS2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:20:05 INFO - Running setup.py (path:/tmp/pip-yQx61Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:20:05 INFO - Running setup.py (path:/tmp/pip-3ZOc6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:20:05 INFO - Running setup.py (path:/tmp/pip-3k7AiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:20:05 INFO - Running setup.py (path:/tmp/pip-R7ZsTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:20:05 INFO - Running setup.py (path:/tmp/pip-W5WDqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:20:05 INFO - Running setup.py (path:/tmp/pip-lrfr1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:20:05 INFO - Running setup.py (path:/tmp/pip-iC8Kgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:20:05 INFO - Running setup.py (path:/tmp/pip-CjEcvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:20:05 INFO - Running setup.py (path:/tmp/pip-0WQroU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:20:05 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)) 07:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:20:05 INFO - Running setup.py (path:/tmp/pip-RDA0WF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:20:06 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)) 07:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:20:06 INFO - Running setup.py (path:/tmp/pip-IamAxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:20:06 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)) 07:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:06 INFO - Running setup.py (path:/tmp/pip-ocYK5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:06 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)) 07:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:20:06 INFO - Running setup.py (path:/tmp/pip-YVgGtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:20:06 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)) 07:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:20:06 INFO - Running setup.py (path:/tmp/pip-8a3lnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:20:06 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)) 07:20:06 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)) 07:20:06 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)) 07:20:06 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)) 07:20:06 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)) 07:20:06 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)) 07:20:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:20:06 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 07:20:06 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 07:20:06 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 07:20:06 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 07:20:06 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 07:20:06 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 07:20:08 INFO - Downloading blessings-1.6.tar.gz 07:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:20:08 INFO - Installing collected packages: blessings 07:20:08 INFO - Running setup.py install for blessings 07:20:08 INFO - Successfully installed blessings 07:20:08 INFO - Cleaning up... 07:20:08 INFO - Return code: 0 07:20:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:20:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:20:08 INFO - Reading from file tmpfile_stdout 07:20:08 INFO - Current package versions: 07:20:08 INFO - argparse == 1.2.1 07:20:08 INFO - blessings == 1.6 07:20:08 INFO - blobuploader == 1.2.4 07:20:08 INFO - docopt == 0.6.1 07:20:08 INFO - functools32 == 3.2.3-2 07:20:08 INFO - jsonschema == 2.5.1 07:20:08 INFO - manifestparser == 1.1 07:20:08 INFO - mozInstall == 1.12 07:20:08 INFO - mozcrash == 0.17 07:20:08 INFO - mozdebug == 0.1 07:20:08 INFO - mozdevice == 0.48 07:20:08 INFO - mozfile == 1.2 07:20:08 INFO - mozhttpd == 0.7 07:20:08 INFO - mozinfo == 0.9 07:20:08 INFO - mozleak == 0.1 07:20:08 INFO - mozlog == 3.4 07:20:08 INFO - moznetwork == 0.27 07:20:08 INFO - mozprocess == 0.23 07:20:08 INFO - mozprofile == 0.28 07:20:08 INFO - mozrunner == 6.12 07:20:08 INFO - mozscreenshot == 0.1 07:20:08 INFO - mozsystemmonitor == 0.3 07:20:08 INFO - moztest == 0.7 07:20:08 INFO - mozversion == 1.4 07:20:08 INFO - psutil == 3.1.1 07:20:08 INFO - requests == 1.2.3 07:20:08 INFO - wsgiref == 0.1.2 07:20:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:20:08 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')]} 07:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:08 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')]} 07:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:08 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 0x7fa0a83faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2199ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, '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': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', '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 07:20:08 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 07:20:08 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 07:20:08 INFO - Using env: {'DISPLAY': ':0', 07:20:08 INFO - 'HOME': '/home/cltbld', 07:20:08 INFO - 'LANG': 'en_US.UTF-8', 07:20:08 INFO - 'LANGUAGE': 'en_US:en', 07:20:08 INFO - 'LOGNAME': 'cltbld', 07:20:08 INFO - 'MAIL': '/var/mail/cltbld', 07:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:08 INFO - 'MOZ_NO_REMOTE': '1', 07:20:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:08 INFO - 'NO_EM_RESTART': '1', 07:20:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:08 INFO - 'PWD': '/builds/slave/test', 07:20:08 INFO - 'SHELL': '/bin/bash', 07:20:08 INFO - 'SHLVL': '1', 07:20:08 INFO - 'TERM': 'linux', 07:20:08 INFO - 'TMOUT': '86400', 07:20:08 INFO - 'USER': 'cltbld', 07:20:08 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:08 INFO - '_': '/tools/buildbot/bin/python'} 07:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:08 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)) 07:20:08 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)) 07:20:08 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)) 07:20:08 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)) 07:20:08 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)) 07:20:08 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)) 07:20:08 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)) 07:20:08 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)) 07:20:08 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)) 07:20:08 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)) 07:20:08 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)) 07:20:08 INFO - Cleaning up... 07:20:08 INFO - Return code: 0 07:20:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:20:08 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')]} 07:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:08 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')]} 07:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:08 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 0x7fa0a83faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2199ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, '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': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', '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 07:20:08 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 07:20:08 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 07:20:08 INFO - Using env: {'DISPLAY': ':0', 07:20:08 INFO - 'HOME': '/home/cltbld', 07:20:08 INFO - 'LANG': 'en_US.UTF-8', 07:20:08 INFO - 'LANGUAGE': 'en_US:en', 07:20:08 INFO - 'LOGNAME': 'cltbld', 07:20:08 INFO - 'MAIL': '/var/mail/cltbld', 07:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:08 INFO - 'MOZ_NO_REMOTE': '1', 07:20:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:08 INFO - 'NO_EM_RESTART': '1', 07:20:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:08 INFO - 'PWD': '/builds/slave/test', 07:20:08 INFO - 'SHELL': '/bin/bash', 07:20:08 INFO - 'SHLVL': '1', 07:20:08 INFO - 'TERM': 'linux', 07:20:08 INFO - 'TMOUT': '86400', 07:20:08 INFO - 'USER': 'cltbld', 07:20:08 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:08 INFO - '_': '/tools/buildbot/bin/python'} 07:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:20:09 INFO - Cleaning up... 07:20:09 INFO - Return code: 0 07:20:09 INFO - Running post-action listener: _resource_record_post_action 07:20:09 INFO - Running post-action listener: _start_resource_monitoring 07:20:09 INFO - Starting resource monitoring. 07:20:09 INFO - [mozharness: 2016-11-14 15:20:09.184200Z] Finished create-virtualenv step (success) 07:20:09 INFO - [mozharness: 2016-11-14 15:20:09.184655Z] Running install step. 07:20:09 INFO - Running pre-action listener: _resource_record_pre_action 07:20:09 INFO - Running main action method: install 07:20:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:20:09 INFO - Reading from file tmpfile_stdout 07:20:09 INFO - Detecting whether we're running mozinstall >=1.0... 07:20:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:20:09 INFO - Reading from file tmpfile_stdout 07:20:09 INFO - Output received: 07:20:09 INFO - Usage: mozinstall [options] installer 07:20:09 INFO - Options: 07:20:09 INFO - -h, --help show this help message and exit 07:20:09 INFO - -d DEST, --destination=DEST 07:20:09 INFO - Directory to install application into. [default: 07:20:09 INFO - "/builds/slave/test"] 07:20:09 INFO - --app=APP Application being installed. [default: firefox] 07:20:09 INFO - mkdir: /builds/slave/test/build/application 07:20:09 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'] 07:20:09 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 07:20:25 INFO - Reading from file tmpfile_stdout 07:20:25 INFO - Output received: 07:20:25 INFO - /builds/slave/test/build/application/firefox/firefox 07:20:25 INFO - Running post-action listener: _resource_record_post_action 07:20:25 INFO - [mozharness: 2016-11-14 15:20:25.450629Z] Finished install step (success) 07:20:25 INFO - [mozharness: 2016-11-14 15:20:25.450874Z] Running run-tests step. 07:20:25 INFO - Running pre-action listener: _resource_record_pre_action 07:20:25 INFO - Running main action method: run_tests 07:20:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:20:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:20:25 INFO - Python 2.7.3 07:20:25 INFO - Return code: 0 07:20:25 INFO - grabbing minidump binary from tooltool 07:20:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:25 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 0x2196f60>, '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 0x2197d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21981d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:20:25 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 07:20:25 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 07:20:25 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 07:20:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:20:25 INFO - Return code: 0 07:20:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:20:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:20:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:20:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:20:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:20:25 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/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 07:20:25 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/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 07:20:25 INFO - Using env: {'DISPLAY': ':0', 07:20:25 INFO - 'HOME': '/home/cltbld', 07:20:25 INFO - 'LANG': 'en_US.UTF-8', 07:20:25 INFO - 'LANGUAGE': 'en_US:en', 07:20:25 INFO - 'LOGNAME': 'cltbld', 07:20:25 INFO - 'MAIL': '/var/mail/cltbld', 07:20:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:20:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:25 INFO - 'MOZ_NO_REMOTE': '1', 07:20:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:20:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:25 INFO - 'NO_EM_RESTART': '1', 07:20:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:25 INFO - 'PWD': '/builds/slave/test', 07:20:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:20:25 INFO - 'SHELL': '/bin/bash', 07:20:25 INFO - 'SHLVL': '1', 07:20:25 INFO - 'TERM': 'linux', 07:20:25 INFO - 'TMOUT': '86400', 07:20:25 INFO - 'USER': 'cltbld', 07:20:25 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1479136770.875093-448316840', 07:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:25 INFO - '_': '/tools/buildbot/bin/python'} 07:20:25 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/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 07:20:25 INFO - mozversion application_buildid: 20161114015620 07:20:25 INFO - mozversion application_changeset: 0f867a8846778f927cfb3871e58cc61f6193ad5d 07:20:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:20:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:20:25 INFO - mozversion application_name: Firefox 07:20:25 INFO - mozversion application_remotingname: firefox-dev 07:20:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:20:25 INFO - mozversion application_vendor: Mozilla 07:20:25 INFO - mozversion application_version: 51.0a2 07:20:25 INFO - mozversion platform_buildid: 20161114015620 07:20:25 INFO - mozversion platform_changeset: 0f867a8846778f927cfb3871e58cc61f6193ad5d 07:20:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:20:25 INFO - mozversion platform_version: 51.0a2 07:20:25 INFO - using testdate: 1479136825 07:20:25 INFO - actual date: 1479136825 07:20:25 INFO - starting webserver on 'localhost:48901' 07:20:25 INFO - SUITE-START | Running 2 tests 07:20:25 INFO - TEST-START | dromaeo_css 07:20:25 INFO - operating with platform_type : linux_ 07:20:25 INFO - Initialising browser for dromaeo_css test... 07:20:25 INFO - TEST-INFO | started process 9094 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu7YijF/profile http://localhost:48901/getInfo.html) 07:20:29 INFO - PROCESS | 9094 | __metrics Screen width/height:1600/1200 07:20:29 INFO - PROCESS | 9094 | colorDepth:24 07:20:29 INFO - PROCESS | 9094 | Browser inner width/height: 1024/697 07:20:29 INFO - PROCESS | 9094 | __metrics 07:20:30 INFO - PROCESS | 9094 | 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" 07:20:30 INFO - PROCESS | 9094 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:20:31 INFO - PROCESS | 9094 | console.error: PushService: 07:20:31 INFO - PROCESS | 9094 | stateChangeProcessEnqueue: Error transitioning state 07:20:31 INFO - PROCESS | 9094 | UnknownError 07:20:31 INFO - TEST-INFO | 9094: exit 0 07:20:31 INFO - Browser initialized. 07:20:31 INFO - Running cycle 1/1 for dromaeo_css test... 07:20:31 INFO - TEST-INFO | started process 9186 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu7YijF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:20:43 INFO - PROCESS | 9186 | RSS: Main: 148242432 07:20:43 INFO - PROCESS | 9186 | 07:21:34 INFO - PROCESS | 9186 | 1479136894464 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] 07:23:43 INFO - PROCESS | 9186 | Cycle 1(1): loaded http://localhost:48901/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48901/tests/dromaeo/cssquery-ext.html) 07:23:44 INFO - PROCESS | 9186 | RSS: Main: 237350912 07:23:44 INFO - PROCESS | 9186 | 07:26:47 INFO - PROCESS | 9186 | Cycle 1(1): loaded http://localhost:48901/tests/dromaeo/cssquery-ext.html (next: http://localhost:48901/tests/dromaeo/cssquery-jquery.html) 07:26:47 INFO - PROCESS | 9186 | RSS: Main: 230682624 07:26:47 INFO - PROCESS | 9186 | 07:29:49 INFO - PROCESS | 9186 | Cycle 1(1): loaded http://localhost:48901/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48901/tests/dromaeo/cssquery-mootools.html) 07:29:50 INFO - PROCESS | 9186 | RSS: Main: 174170112 07:29:50 INFO - PROCESS | 9186 | 07:32:52 INFO - PROCESS | 9186 | Cycle 1(1): loaded http://localhost:48901/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48901/tests/dromaeo/cssquery-prototype.html) 07:32:52 INFO - PROCESS | 9186 | RSS: Main: 218427392 07:32:52 INFO - PROCESS | 9186 | 07:35:55 INFO - PROCESS | 9186 | Cycle 1(1): loaded http://localhost:48901/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48901/tests/dromaeo/cssquery-yui.html) 07:35:56 INFO - PROCESS | 9186 | RSS: Main: 175067136 07:35:56 INFO - PROCESS | 9186 | 07:38:58 INFO - PROCESS | 9186 | Cycle 1(1): loaded http://localhost:48901/tests/dromaeo/cssquery-yui.html (next: http://localhost:48901/tests/dromaeo/cssquery-dojo.html) 07:38:59 INFO - PROCESS | 9186 | RSS: Main: 263229440 07:38:59 INFO - PROCESS | 9186 | 07:38:59 INFO - PROCESS | 9186 | __start_tp_report 07:38:59 INFO - PROCESS | 9186 | _x_x_mozilla_page_load 07:38:59 INFO - PROCESS | 9186 | _x_x_mozilla_page_load_details 07:38:59 INFO - PROCESS | 9186 | |i|pagename|runs| 07:38:59 INFO - PROCESS | 9186 | |0;dojo.html;3069.4014667139904;3112.4242015227182;3122.687731226876;3151.4484965131096;3164.9367012659745;5399.109146990747;5455.590830687699;5483.067878460662;5499.532539734122;5506.174073888918;5572.3870374258795;5589.329280486337;5589.441055894408;5598.1602759586085;5608.130739735338;5046.041252162096;5057.924131138017;5085.237214417848;5136.306598609177;5156.226566015109;4982.227754698009;5093.286939828427;5151.4333423323405;5153.3815942087;5154.536091751742;4953.801847926079;4976.626752993522;5007.599392048627;5017.222330538772;5026.29631851541;5014.1225285575065;5036.9926014797;5056.191009438472;5127.307813445174;5159.484051594848;4974.77613507393;5057.519535644108;5103.106956282636;5114.616403769732;5127.589792816566;5327.627066105374;5363.865903352447;5400.540954018913;5432.483914028161;5481.369642491085;5703.885922281573;5722.027255366597;5723.799667011676;5807.88384232313;5812.476877081083;5851.38560451155;5856.707164641751;5863.5309175265875;5874.206982057453;5875.059990401558;5406.945930540684;5452.182172674091;5467.863303417406;5500.4499550044675;5504.4770746778995;6056.939430605726;6064.545159113084;6070.878582428415;6082.2093127893095;6082.817515474543;5829.15477255804;5841.386654401312;5844.620099693507;5848.064309710511;5850.561207909339;5735.483806457439;5747.568932330092;5768.480836724715;5784.132380142928;5806.506446952057;5804.564657650693;5805.216295800013;5805.970970145122;5812.418758124154;5834.708264586754;5762.7694892203845;5818.5345172386105;5818.912716309259;5819.970900145472;5846.970765146147;4415.359772832985;4439.311906654474;4477.552244775561;4495.168393847149;4515.029268707243;4032.2338755636333;4056.9797151014054;4072.2669919414197;4078.530968938551;4103.199876024206;5617.21359009739;5625;5626.549876009909;5633.63381380209;5637.9718101409235;5828.970855145697;5862.267216597926;5877.941220587825;5974.761009559656;5977.970110149421;5547.611667183259;5598.188262701967;5599.8600034998635;5629.296337957755;5640.182173584889;5149.665271757323;5154.819581314636;5154.845354639367;5156.871078222999;5169.147090729987;5856.912146317723;5865.970670146622;6127.448529632355;6142.078688196806;6145.5698101133385;5792.797252096157;5861.032929566744;5872.588918775645;5980.192673988956;5980.940190598212;6124.356942520971;6131.110988906673;6133.785317513865;6140.478059364826;6145.354737752472;5827.300723912988;5835.095560188178;5839.328477225174;5840.8247752567495;5849.444302791228;5670.914936275876;5784.768609255582;5896.6756828374855;5929.318128415288;5955.255593050869;5847.766089356548;5896.852578685395;5897.734601942837;5901.380355062656;5923.674197919156;5875.500582450369;5883.294004719461;5886;5887.558433117448;5895.4399332063385;5517.144842549411;5626.831195064155;5712.200291959046;5716.028275193144;5822.650640961556;5910.202122713378;5911.852203694942;5917.6745279010065;5950.940490595039;5962.9105563415715;5525.834224973257;5758.568107392046;5760.222369979999;5767.8558036049435;5767.913481297868 07:38:59 INFO - PROCESS | 9186 | |1;ext.html;598.8892054969831;640.3052687833701;640.3500447046249;659.3011407907616;660.2802944790178;37814.24371512568;38524.22951540967;47324;47541.762291188585;47602;49201.753991230085;49712.50287497124;49775.75112124444;49798.50201497984;49813;9480.525973701322;9605.375650582724;9631.566579503939;9635.325527213097;9638.518074096268;8489.702860399888;8722.258608018294;10975.670729878077;11259.718507037307;11267.26762760418;11523.308601483895;11621.941890290578;11632.825507617392;11633.243839150471;11636.941815290911;11371.545138194504;11395.088392928546;11434.771304573946;11454.427278636034;11471.139664525193;11142.665720028332;11198.832017519744;11215.214934954556;11218.102551795919;11227.157963152797;45750.54249457496;46048.53951460476;46097;46205.76897115493;46233;3435;3490.5287786148797;3509.2279698466295;3573.8927832165077;3600.3519366514015;34459.4831077534;34706.82646586776;35176;35218;35354.29291414183;4662.533746625344;4671.743054132022;4730.521894004796;4770.904581908377;4869.853904382874;364163;365449.0730556175;366609;367030;368537;66698;67055;67404.99999999903;67430;67471.3252867465;42405.36391954123;43229.567704323184;43352.56647433549;43394.34908476375;43663.56336436595;19784.406467805988;19820.801791982183;19938.30215942435;19955.301564445173;19994.70007949882;14038.649033774238;14289.92855035739;14846.777298340534;14937.925310373595;14964.40142394292;18864.434066978014;19064.71402928957;20167;20870.58258834815;20918.372448826558;7514.286142816423;7533.935182178399;7573.810654733566;7688.231176882267;7826.4130190235965;944.3153713557753;1072.753266748652;1100.9174311926477;1133.7959167349957;1187.9308622240053;34760.82619586936;35353;35622.64377356245;35781.28437431237;35905.28189436249;1149.9655010349702;1156.19066653343;1159.4087015622094;1172.5192671004847;1174.9060075193963;18595.163217655238;18724.34464793753;18878.339258126176;18888.905555472134;19072;549754;556465;559431;560782;562398;65043.67478162579;65621;65651;65656.67171664207;65662.34337656562;1222.9816552751715;1335.8396992360786;1343.2007955266602;1381.8065470834092;1394.1356359057247;34160.82919585386;36325.273494529436;36843;37140.6285937148;37195.25609487849;56099.71950140223;56754.716226418604;56798;56828.71585642045;56921.43078569161;389214;393375;399396;528757;545788;48247.75876120597;48503.51496485131;48890.266645999625;48946.75526622344;49223.507764921895;53505;53910.730446347516;54434.45565544294;54481;54733.72633136809;44077.34690717242;49347.25979110388;49576;49761.50238497713;50910;44251.11497770091;45829.77085114553;45973.08053838972;46278.76860615675;46558.30162547632;45959;46821.53178468308;46940.765296173304;46968.530314696414;47050.82372940704;37546;39938;40762.38856417097;40923.7953810229;40934.79532602318;30329;30372.39255214839;30990.69009309878;31236.531452028692;31315.53026704647 07:38:59 INFO - PROCESS | 9186 | |2;jquery.html;394.39066642038046;405.3554847792012;426.889008857697;430.3394289765211;440.5440369217858;14820.851791482082;16499.01005939643;19842.90078549607;20485.38543843687;21593;19987.200511979503;20444.693329600068;20541;20664.380068597966;20754;5882.117682347639;5896.321922978858;6024.457798798107;6057.303410107828;6058.121572372003;4385.51759306476;5919.852003699899;6012.518998480111;6018.15745795589;6023.6385816851125;4562.726236425809;4602.171609110359;6667.633280169589;6771.762988295411;6793.7961861143995;6475.838104047437;6625.9668701656665;6767.255601883789;6782.966085169592;6787.830304242409;5904.291485021825;5904.498117659961;5905.320888097838;5908.881822363572;5923.703814809242;24407.877960610083;24744.876275618688;24847;24987.625185622048;25099.247022589352;2463.4210960424284;2468.7654672806057;2529.7723204911463;2540.2633236361617;2655.402534429757;14292.356843942047;14594.197319147443;15295.388184472607;15480.690386192213;15545.455909043128;1962.6663467210608;1988.204718112752;2026.3313106674761;2039.2964427272568;2054.476108592299;63552.682236588524;74970;76599;84963;86709;9621.470819105016;10245.180385569136;10268.07587317145;11187.944060279646;11477.885221147848;13957.720845583031;14001.579952601438;14062;14149.5047673333;14168.149911005372;6941.236463988957;7072.115985501812;8343;8427.747167584983;8721.738347849574;8112.269895709414;8166;8652.264557512683;8691.782705432415;8909.955450222837;4245.27830268855;4347.130573885172;4386.934195987063;4457.175422546839;4501.437320334958;1951.6096780643645;1981.5343394302326;2050.2311633137574;2079.792020797908;2108.917508803806;945.9006804285451;971.2375785008734;1000.0099701891438;1057.4924036462542;1097.9286346387619;20430.38708838737;20698.275560355316;20732.792672073087;20759.065842037144;20774.58450831005;1041;1065.9680209593726;1074.3500182389698;1079.3308148947704;1102.7684186320803;10675.83986240207;11604.593839215588;11775.175737698279;11821.408929553489;11889.524419023315;487052;494465;503295;509008;510259;74812.62593686997;92672.53663731772;97751;99729.50135249422;101686.49156754317;1028.6296933104077;1043.2332235806675;1064.8881867403966;1071.2180108520824;1075.7203127186988;18535.44393668192;18691.252349906394;18886;18925.43223703291;18967.69365099164;45661;45890;46074.53925460837;46473.53526464827;47080.7645961768;65278.673606631666;65540.34459655533;65946.01080983884;66390.66804665946;66861;8616.956915215635;8709.520976346363;8890.511021893857;8947.150020748019;9071.228945539438;12186.756264874475;13607.659808504866;13625.931870340584;13784.862151378358;13978;11324.490397932277;11897.286162830258;12244.265344079384;12296.139270250997;12335.259884406963;11795.882041179479;11906.642800715992;12002.579909703118;12188.634340969786;12522.686932826387;12059.819102713642;12106.455209515418;12171.8090743667;12222.877771222174;12369.876301237233;7402.29678180572;8554.657813687632;8726.956365218388;8948.060453652273;8985.685501007434;10875.401852898167;12279.631611051684;12497.625071247878;12505;12576.874231257936 07:38:59 INFO - PROCESS | 9186 | |3;mootools.html;1519.2176029344887;1558.4856997190927;1716.1590820497959;1810.936617218396;1841.2542920117357;8498.575071246436;9363.595460680901;9379.624815007392;9386.953065234682;9421.387609805366;7761.495502792314;7952.6421311040995;8702.303815694746;8977.461352318865;8992.8651070234;2434.6226334918074;2470.110760126353;2471.4315707387314;2472.3942634054592;2474.40614252579;2015.8891260980713;2416.335507735378;2501.499700059986;2506.1729629222314;2509.8619575923317;2208.624533829253;2420.165043060141;2486.987565062181;2488.365466805962;2500.862452565108;2400.195934361991;2407.5666380051584;2409.0604664180896;2415.130553000918;2416.1905761569897;2376.750441203666;2381.321323422816;2381.666566680666;2382.916597919082;2384.5350156719446;9079.727608171765;9251.814963700755;9259.6296148154;9261.351705380626;9288.535573221312;5530.5299049580835;5634.323881134249;5639.238702775114;5662.858428539278;5675.063614503602;3427.3488037273087;3470.0630829676097;3606.459031145323;3614.4759009943673;3630.7276954228273;1233.9383030848512;1255.171586752748;1287.9742405151844;1315.7105436803888;1327.9734405311935;12865.614031579067;12895;12896.742065158644;12909.67725806853;13034.348282585928;4854.732989685531;4976.7262800545595;5005.3242812220615;5082.313887625123;5104.9744751276;10844.13246940243;10925.945370273257;10932.289401188897;10935.617253396093;10944.616938407118;5682.40334764852;5688.089905615082;5692.0323544997445;5718.79984200551;5733.655980641175;5007.248912663057;5136.075506408883;5180.559652429586;5224.373075230997;5234.92147617786;4303.536797488869;4358.128374325148;4424.867253982386;4474.888127796831;4490.955090449053;1291.2704322058007;1447.9203643799692;1471.0952764050246;1491.865732084096;1492.723846088477;729.3873146556917;814.0069115678981;882.9514376709215;885.3802338363172;896.5203616065373;5747.885042299131;5998.130271110752;6019.969900150559;6037.698115094316;6117.696443920233;1004.5881188712593;1011.261778901406;1032.3238278927315;1039.599754094664;1041.5625437316346;3751.5873253942045;3815.5421349438243;3962.663173630216;3979.243943650697;4080.6939479539155;11223;11620.76758464826;11625.534978600761;11636.53453861836;11649.59226427088;11529.596464123882;11535.884641153481;11538.88461115395;11542.769144617061;11543.711407214887;8020.639071241808;8340.457870238415;8349.206825351583;8537.615807288812;8538.103499132625;5802.216700745345;5872.442117998783;5876.823695289148;5988.940110598838;5997.070454079625;10312.123469505175;10322.793544128925;10346.534405951901;10426.582936682447;10462.63380781669;10239.795204096024;10471.162307015267;10503;10572.20708446885;10633.627823026158;7863.252990965849;8119.75640730779;8128.878066828884;8130.67477300925;8142.67429302845;8706.651733930572;8716.3462740296;8741.388102832994;8790.560471976247;8871.423357481743;6315.747370105144;7505.362044226302;7524.05949256343;7528.397728181842;7725.22747725223;7596.544207347577;7709.80725481845;7749.92250077492;7765.145833958373;7842.333401660694;6826;7017.614031228331;7019.859602808017;7098.112735908012;7261.5280006799385;5211.843644690665;5212.113940630026;5213.661112027706;5302.39022512416;5326.41409444953;4994.101061808909;5096.184610462308;5112.463191365002;5195.350581177353;5206.140986737146 07:38:59 INFO - PROCESS | 9186 | |4;prototype.html;344.10532615200464;351.509644046555;357.19987228607926;360.624950051946;367.8436664417622;5395.73021348932;5577.274954255941;5582.302212223472;5622;5627.690477023767;5335.439778823216;5433.239346491485;5459.3994660587305;5462.688954650885;5492;4353.41228934093;4357.564243575633;4361.978190109045;4364.67264955128;4370.6503479721705;4120;4289.571042895716;4328.63206627437;4409.699138754059;4411.4265145531035;4271.914561708764;4272.850450234242;4277.422547956033;4278.657707383401;4303.26844436445;4293.592108749664;4312.78436078198;4337.436133302682;4345.717528360647;4360.956390436087;3724.2923844469465;3821.636944490269;3827.674647654953;3834.69322454203;3853.5183102112237;5397.21891775717;5405.37838148614;5407.351117865842;5424.267723857268;5424.972875135599;4984.152694042022;5006.649534532584;5007.4491805901325;5011.423686276052;5026.5727413169925;5595.4964053235;5744.678723893528;5769.509591684712;5780.393058728857;5824.097264923944;4172.996961458487;4175.8956026099595;4179.435776170239;4188.120494696117;4197.9370309445385;5803.854903627401;6068.514518838482;6087.908681369739;6112.938870611281;6122.693865306717;4240.936385954213;4251.4685664291965;4263.275243208662;4264.488261408651;4295.625399872073;4363;4373.759443230653;4383.408239887574;4384.8903777405185;4391.692581519263;4978.228374601942;4982.128127577732;4993.251012348104;5014.52362025607;5024.346834911511;5527;5699.5155411790465;5842.8539286517325;5895.793647222326;5917.970410148007;1408.8940181957114;1451.7314296854904;1467.1930438259128;1487.256371814093;1502.241368109098;993.3940296419179;1028.285341687535;1031.9329243599143;1041.4896700616796;1041.7499800047933;5538.307711536058;5549.833504994856;5553.861153471195;5554.750036248379;5565.220869078333;5891.204687367151;5896.557758168155;5897.675627840425;5914.467697907209;5914.526837852962;5302.893942121136;5559.360673522597;5568.16477528374;5601.383847776741;5655.915161272585;3149.637791653989;3168.952465713016;3184.5510292817175;3186.4423725847882;3194.1535493094348;5895.75961134766;6144.477719393902;6164.938350616526;6171.13604095427;6175.96912015437;6123.357047510036;6134.5092392608485;6141;6145.631262124287;6150.200473938359;6181.474574661114;6181.567290289637;6206.596571222856;6212.161358216673;6228.221472316051;5765.336986246635;5993.550483713651;6020.39796020412;6027.849303767265;6058.757649694139;5906.106008056107;5914.497267732291;5924.377940316377;5960.254968128984;5969.880602388015;5931.822045338647;6193.504519638508;6196.1635179250225;6200.56596038273;6209.379062093754;4311.202427550913;4324.426965955252;4327.545607711145;4333.8699839004885;4350.651947844101;5856.355800861987;6069.726862291119;6098.75604975796;6142.539309551891;6157.815265542041;5745.339285982165;5791.160281759206;5864.824055278348;5877.265341832271;5897.056470964625;5845.795397161079;5858.531317494505;5868.823935281948;5874.236349274567;5890.381509941566;5615.831525054255;5784.884302314014;5815.621984570989;5835.649861008353;5841.240638716939;5850.356460789395;5892.996381230402;5899.321578018583;5902.173695682522;5903.321118071477;5456.380937150111;5762.4525670061275;5765.336986246635;5768.682722450306;5771.769129234784 07:38:59 INFO - PROCESS | 9186 | |5;yui.html;788.6569342336085;814.8125931035862;841.4404421059995;869.2741560796733;885.6413152673169;15400.30698618562;16332.673346533062;16371.91814040931;16431.178441077944;16459.423920162797;15294.082355058705;15364.539063828075;15657.765133523006;15658.686826263467;15676.764848527282;721.8123287945119;738.2396131984065;847.8855354527137;850.8255807559435;877.4209022045451;871.0897112517434;871.7602659268689;872.3326655108847;872.7992561710811;872.9781755456132;920.9723708288763;921.1479381572052;921.2261700171854;921.4655499810127;921.6313474610176;755.9206283340253;772.382094324541;883.8320719063357;897.4660077254024;908.5684299957534;667.6661669165418;672.2168673495372;672.8048865828904;672.8586996730693;673.8113328268092;12454.875451245462;12519.061070419664;12547;12552.874471255353;12619.747605047847;14186.929065354607;14201.928990354982;14305;14401.567952961428;14766.114033158045;13358.265295408748;13483.66290842727;13530.18818870871;13551.186928784304;13838.377273022637;4632.513586073481;4664.300354946751;4694.647901407408;4694.671373003891;4762.833300834454;71747.28252717458;72508.27491725067;75028.62485687537;81368.59315703443;81452.59273703652;11790.23363481371;12079.335636540074;12349.382530873421;12677.936610317074;12863.42114604845;24314.878425608113;24587;24607.753922460903;24645.753542464703;24656.87671561631;9664.420134791935;9787.314887957917;9850.359726617748;10007;10372.844407333896;5120.948790512121;5136.640435169502;5156.4070131935305;5281.313429254173;5334.626576139633;3915.236528876899;4025.879223623296;4027.677785777131;4038.192361527706;4041.211963667057;585.844751140948;643.5430844100647;653.9673016349163;658.2660333727858;676.3980057748607;647.2362612117746;689.8171984424132;702.9472789540703;712.5795780489536;718.4683334332557;9000.684976025808;9260.49067301305;9271.490068046187;9469.952650236843;9471.431714097176;631.7069039386996;761.4022991951402;815.7144999250215;817.7138001699476;827.6896163938524;4467.173572889026;4766.523347665206;4777.474477807438;4787.42550893888;4796.25658023007;73948;99624;116981;120268;121652;104547.4772626132;106623.46688266509;109240.4537977305;109298;109496;57025.14462283069;85024.57487712523;89506.10493894978;92177;92210.53894730483;7865.449418540876;8003.91996080055;8120.228578285053;8181.100078991425;8333.541655209021;47101.528984709716;51668;53039.469605305;53178.73410632922;53378;46841.53158468508;46991;48671.75664121657;57107;83012.58493707493;11775.411229438665;11842;12033.518659253881;12041.036717062789;13081.411336489691;12384.75230495367;12685.858272755497;13600.387982540611;14008.579742607739;15160.469383571522;13446.193228406328;13774.449022039005;13855.515056972958;14258.500952466615;14610.78083828722;13429.395677194745;13618.659533511345;13911.791323129959;13990.580282591538;14374.425022998963;12333.691657708629;12766.553170638983;13140.802887956497;13396.665083372995;13879.236641984788;10102.494875256061;10265.486725663837;11161.665150045512;11690.4739286734;12088.576899808464;11437.714057148638;11850.111241657083;12020.158588899043;12096.395180241128;12204.206726562745 07:38:59 INFO - PROCESS | 9186 | __end_tp_report 07:38:59 INFO - PROCESS | 9186 | __start_cc_report 07:38:59 INFO - PROCESS | 9186 | _x_x_mozilla_cycle_collect,825 07:38:59 INFO - PROCESS | 9186 | __end_cc_report 07:38:59 INFO - PROCESS | 9186 | __startTimestamp1479137939054__endTimestamp 07:38:59 INFO - PROCESS | 9186 | 07:38:59 INFO - PROCESS | 9186 | ------- Summary: start ------- 07:38:59 INFO - PROCESS | 9186 | Number of tests: 6 07:38:59 INFO - PROCESS | 9186 | 07:38:59 INFO - PROCESS | 9186 | [#0] dojo.html Cycles:165 Average:5485.25 Median:5722.03 stddev:620.91 (10.9%) stddev-sans-first:593.19 07:38:59 INFO - PROCESS | 9186 | Values: 3069.4 3112.4 3122.7 3151.4 3164.9 5399.1 5455.6 5483.1 5499.5 5506.2 5572.4 5589.3 5589.4 5598.2 5608.1 5046.0 5057.9 5085.2 5136.3 5156.2 4982.2 5093.3 5151.4 5153.4 5154.5 4953.8 4976.6 5007.6 5017.2 5026.3 5014.1 5037.0 5056.2 5127.3 5159.5 4974.8 5057.5 5103.1 5114.6 5127.6 5327.6 5363.9 5400.5 5432.5 5481.4 5703.9 5722.0 5723.8 5807.9 5812.5 5851.4 5856.7 5863.5 5874.2 5875.1 5406.9 5452.2 5467.9 5500.4 5504.5 6056.9 6064.5 6070.9 6082.2 6082.8 5829.2 5841.4 5844.6 5848.1 5850.6 5735.5 5747.6 5768.5 5784.1 5806.5 5804.6 5805.2 5806.0 5812.4 5834.7 5762.8 5818.5 5818.9 5820.0 5847.0 4415.4 4439.3 4477.6 4495.2 4515.0 4032.2 4057.0 4072.3 4078.5 4103.2 5617.2 5625.0 5626.5 5633.6 5638.0 5829.0 5862.3 5877.9 5974.8 5978.0 5547.6 5598.2 5599.9 5629.3 5640.2 5149.7 5154.8 5154.8 5156.9 5169.1 5856.9 5866.0 6127.4 6142.1 6145.6 5792.8 5861.0 5872.6 5980.2 5980.9 6124.4 6131.1 6133.8 6140.5 6145.4 5827.3 5835.1 5839.3 5840.8 5849.4 5670.9 5784.8 5896.7 5929.3 5955.3 5847.8 5896.9 5897.7 5901.4 5923.7 5875.5 5883.3 5886.0 5887.6 5895.4 5517.1 5626.8 5712.2 5716.0 5822.7 5910.2 5911.9 5917.7 5950.9 5962.9 5525.8 5758.6 5760.2 5767.9 5767.9 07:38:59 INFO - PROCESS | 9186 | 07:38:59 INFO - PROCESS | 9186 | [#1] ext.html Cycles:180 Average:64357.47 Median:40929.30 stddev:123460.70 (301.6%) stddev-sans-first:123714.23 07:38:59 INFO - PROCESS | 9186 | Values: 598.9 640.3 640.4 659.3 660.3 37814.2 38524.2 47324.0 47541.8 47602.0 49201.8 49712.5 49775.8 49798.5 49813.0 9480.5 9605.4 9631.6 9635.3 9638.5 8489.7 8722.3 10975.7 11259.7 11267.3 11523.3 11621.9 11632.8 11633.2 11636.9 11371.5 11395.1 11434.8 11454.4 11471.1 11142.7 11198.8 11215.2 11218.1 11227.2 45750.5 46048.5 46097.0 46205.8 46233.0 3435.0 3490.5 3509.2 3573.9 3600.4 34459.5 34706.8 35176.0 35218.0 35354.3 4662.5 4671.7 4730.5 4770.9 4869.9 364163.0 365449.1 366609.0 367030.0 368537.0 66698.0 67055.0 67405.0 67430.0 67471.3 42405.4 43229.6 43352.6 43394.3 43663.6 19784.4 19820.8 19938.3 19955.3 19994.7 14038.6 14289.9 14846.8 14937.9 14964.4 18864.4 19064.7 20167.0 20870.6 20918.4 7514.3 7533.9 7573.8 7688.2 7826.4 944.3 1072.8 1100.9 1133.8 1187.9 34760.8 35353.0 35622.6 35781.3 35905.3 1150.0 1156.2 1159.4 1172.5 1174.9 18595.2 18724.3 18878.3 18888.9 19072.0 549754.0 556465.0 559431.0 560782.0 562398.0 65043.7 65621.0 65651.0 65656.7 65662.3 1223.0 1335.8 1343.2 1381.8 1394.1 34160.8 36325.3 36843.0 37140.6 37195.3 56099.7 56754.7 56798.0 56828.7 56921.4 389214.0 393375.0 399396.0 528757.0 545788.0 48247.8 48503.5 48890.3 48946.8 49223.5 53505.0 53910.7 54434.5 54481.0 54733.7 44077.3 49347.3 49576.0 49761.5 50910.0 44251.1 45829.8 45973.1 46278.8 46558.3 45959.0 46821.5 46940.8 46968.5 47050.8 37546.0 39938.0 40762.4 40923.8 40934.8 30329.0 30372.4 30990.7 31236.5 31315.5 07:38:59 INFO - PROCESS | 9186 | 07:38:59 INFO - PROCESS | 9186 | [#2] jquery.html Cycles:180 Average:29964.04 Median:13606.82 stddev:82564.05 (606.8%) stddev-sans-first:82765.81 07:38:59 INFO - PROCESS | 9186 | Values: 394.4 405.4 426.9 430.3 440.5 14820.9 16499.0 19842.9 20485.4 21593.0 19987.2 20444.7 20541.0 20664.4 20754.0 5882.1 5896.3 6024.5 6057.3 6058.1 4385.5 5919.9 6012.5 6018.2 6023.6 4562.7 4602.2 6667.6 6771.8 6793.8 6475.8 6626.0 6767.3 6783.0 6787.8 5904.3 5904.5 5905.3 5908.9 5923.7 24407.9 24744.9 24847.0 24987.6 25099.2 2463.4 2468.8 2529.8 2540.3 2655.4 14292.4 14594.2 15295.4 15480.7 15545.5 1962.7 1988.2 2026.3 2039.3 2054.5 63552.7 74970.0 76599.0 84963.0 86709.0 9621.5 10245.2 10268.1 11187.9 11477.9 13957.7 14001.6 14062.0 14149.5 14168.1 6941.2 7072.1 8343.0 8427.7 8721.7 8112.3 8166.0 8652.3 8691.8 8910.0 4245.3 4347.1 4386.9 4457.2 4501.4 1951.6 1981.5 2050.2 2079.8 2108.9 945.9 971.2 1000.0 1057.5 1097.9 20430.4 20698.3 20732.8 20759.1 20774.6 1041.0 1066.0 1074.4 1079.3 1102.8 10675.8 11604.6 11775.2 11821.4 11889.5 487052.0 494465.0 503295.0 509008.0 510259.0 74812.6 92672.5 97751.0 99729.5 101686.5 1028.6 1043.2 1064.9 1071.2 1075.7 18535.4 18691.3 18886.0 18925.4 18967.7 45661.0 45890.0 46074.5 46473.5 47080.8 65278.7 65540.3 65946.0 66390.7 66861.0 8617.0 8709.5 8890.5 8947.2 9071.2 12186.8 13607.7 13625.9 13784.9 13978.0 11324.5 11897.3 12244.3 12296.1 12335.3 11795.9 11906.6 12002.6 12188.6 12522.7 12059.8 12106.5 12171.8 12222.9 12369.9 7402.3 8554.7 8727.0 8948.1 8985.7 10875.4 12279.6 12497.6 12505.0 12576.9 07:38:59 INFO - PROCESS | 9186 | 07:38:59 INFO - PROCESS | 9186 | [#3] mootools.html Cycles:180 Average:5999.44 Median:4915.73 stddev:3414.47 (69.5%) stddev-sans-first:3407.45 07:38:59 INFO - PROCESS | 9186 | Values: 1519.2 1558.5 1716.2 1810.9 1841.3 8498.6 9363.6 9379.6 9387.0 9421.4 7761.5 7952.6 8702.3 8977.5 8992.9 2434.6 2470.1 2471.4 2472.4 2474.4 2015.9 2416.3 2501.5 2506.2 2509.9 2208.6 2420.2 2487.0 2488.4 2500.9 2400.2 2407.6 2409.1 2415.1 2416.2 2376.8 2381.3 2381.7 2382.9 2384.5 9079.7 9251.8 9259.6 9261.4 9288.5 5530.5 5634.3 5639.2 5662.9 5675.1 3427.3 3470.1 3606.5 3614.5 3630.7 1233.9 1255.2 1288.0 1315.7 1328.0 12865.6 12895.0 12896.7 12909.7 13034.3 4854.7 4976.7 5005.3 5082.3 5105.0 10844.1 10925.9 10932.3 10935.6 10944.6 5682.4 5688.1 5692.0 5718.8 5733.7 5007.2 5136.1 5180.6 5224.4 5234.9 4303.5 4358.1 4424.9 4474.9 4491.0 1291.3 1447.9 1471.1 1491.9 1492.7 729.4 814.0 883.0 885.4 896.5 5747.9 5998.1 6020.0 6037.7 6117.7 1004.6 1011.3 1032.3 1039.6 1041.6 3751.6 3815.5 3962.7 3979.2 4080.7 11223.0 11620.8 11625.5 11636.5 11649.6 11529.6 11535.9 11538.9 11542.8 11543.7 8020.6 8340.5 8349.2 8537.6 8538.1 5802.2 5872.4 5876.8 5988.9 5997.1 10312.1 10322.8 10346.5 10426.6 10462.6 10239.8 10471.2 10503.0 10572.2 10633.6 7863.3 8119.8 8128.9 8130.7 8142.7 8706.7 8716.3 8741.4 8790.6 8871.4 6315.7 7505.4 7524.1 7528.4 7725.2 7596.5 7709.8 7749.9 7765.1 7842.3 6826.0 7017.6 7019.9 7098.1 7261.5 5211.8 5212.1 5213.7 5302.4 5326.4 4994.1 5096.2 5112.5 5195.4 5206.1 07:38:59 INFO - PROCESS | 9186 | 07:38:59 INFO - PROCESS | 9186 | [#4] prototype.html Cycles:180 Average:4913.58 Median:5554.31 stddev:1440.42 (25.9%) stddev-sans-first:1403.03 07:38:59 INFO - PROCESS | 9186 | Values: 344.1 351.5 357.2 360.6 367.8 5395.7 5577.3 5582.3 5622.0 5627.7 5335.4 5433.2 5459.4 5462.7 5492.0 4353.4 4357.6 4362.0 4364.7 4370.7 4120.0 4289.6 4328.6 4409.7 4411.4 4271.9 4272.9 4277.4 4278.7 4303.3 4293.6 4312.8 4337.4 4345.7 4361.0 3724.3 3821.6 3827.7 3834.7 3853.5 5397.2 5405.4 5407.4 5424.3 5425.0 4984.2 5006.6 5007.4 5011.4 5026.6 5595.5 5744.7 5769.5 5780.4 5824.1 4173.0 4175.9 4179.4 4188.1 4197.9 5803.9 6068.5 6087.9 6112.9 6122.7 4240.9 4251.5 4263.3 4264.5 4295.6 4363.0 4373.8 4383.4 4384.9 4391.7 4978.2 4982.1 4993.3 5014.5 5024.3 5527.0 5699.5 5842.9 5895.8 5918.0 1408.9 1451.7 1467.2 1487.3 1502.2 993.4 1028.3 1031.9 1041.5 1041.7 5538.3 5549.8 5553.9 5554.8 5565.2 5891.2 5896.6 5897.7 5914.5 5914.5 5302.9 5559.4 5568.2 5601.4 5655.9 3149.6 3169.0 3184.6 3186.4 3194.2 5895.8 6144.5 6164.9 6171.1 6176.0 6123.4 6134.5 6141.0 6145.6 6150.2 6181.5 6181.6 6206.6 6212.2 6228.2 5765.3 5993.6 6020.4 6027.8 6058.8 5906.1 5914.5 5924.4 5960.3 5969.9 5931.8 6193.5 6196.2 6200.6 6209.4 4311.2 4324.4 4327.5 4333.9 4350.7 5856.4 6069.7 6098.8 6142.5 6157.8 5745.3 5791.2 5864.8 5877.3 5897.1 5845.8 5858.5 5868.8 5874.2 5890.4 5615.8 5784.9 5815.6 5835.6 5841.2 5850.4 5893.0 5899.3 5902.2 5903.3 5456.4 5762.5 5765.3 5768.7 5771.8 07:38:59 INFO - PROCESS | 9186 | 07:38:59 INFO - PROCESS | 9186 | [#5] yui.html Cycles:180 Average:20387.10 Median:25842.84 stddev:29267.32 (113.3%) stddev-sans-first:29312.43 07:38:59 INFO - PROCESS | 9186 | Values: 788.7 814.8 841.4 869.3 885.6 15400.3 16332.7 16371.9 16431.2 16459.4 15294.1 15364.5 15657.8 15658.7 15676.8 721.8 738.2 847.9 850.8 877.4 871.1 871.8 872.3 872.8 873.0 921.0 921.1 921.2 921.5 921.6 755.9 772.4 883.8 897.5 908.6 667.7 672.2 672.8 672.9 673.8 12454.9 12519.1 12547.0 12552.9 12619.7 14186.9 14201.9 14305.0 14401.6 14766.1 13358.3 13483.7 13530.2 13551.2 13838.4 4632.5 4664.3 4694.6 4694.7 4762.8 71747.3 72508.3 75028.6 81368.6 81452.6 11790.2 12079.3 12349.4 12677.9 12863.4 24314.9 24587.0 24607.8 24645.8 24656.9 9664.4 9787.3 9850.4 10007.0 10372.8 5120.9 5136.6 5156.4 5281.3 5334.6 3915.2 4025.9 4027.7 4038.2 4041.2 585.8 643.5 654.0 658.3 676.4 647.2 689.8 702.9 712.6 718.5 9000.7 9260.5 9271.5 9470.0 9471.4 631.7 761.4 815.7 817.7 827.7 4467.2 4766.5 4777.5 4787.4 4796.3 73948.0 99624.0 116981.0 120268.0 121652.0 104547.5 106623.5 109240.5 109298.0 109496.0 57025.1 85024.6 89506.1 92177.0 92210.5 7865.4 8003.9 8120.2 8181.1 8333.5 47101.5 51668.0 53039.5 53178.7 53378.0 46841.5 46991.0 48671.8 57107.0 83012.6 11775.4 11842.0 12033.5 12041.0 13081.4 12384.8 12685.9 13600.4 14008.6 15160.5 13446.2 13774.4 13855.5 14258.5 14610.8 13429.4 13618.7 13911.8 13990.6 14374.4 12333.7 12766.6 13140.8 13396.7 13879.2 10102.5 10265.5 11161.7 11690.5 12088.6 11437.7 11850.1 12020.2 12096.4 12204.2 07:38:59 INFO - PROCESS | 9186 | -------- Summary: end -------- 07:38:59 INFO - PROCESS | 9186 | 07:38:59 INFO - TEST-INFO | 9186: exit 0 07:38:59 INFO - TEST-OK | dromaeo_css | took 1114116ms 07:38:59 INFO - TEST-START | kraken 07:38:59 INFO - operating with platform_type : linux_ 07:38:59 INFO - Initialising browser for kraken test... 07:38:59 INFO - TEST-INFO | started process 9530 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMD_Euu/profile http://localhost:48901/getInfo.html) 07:39:03 INFO - PROCESS | 9530 | __metrics Screen width/height:1600/1200 07:39:03 INFO - PROCESS | 9530 | colorDepth:24 07:39:03 INFO - PROCESS | 9530 | Browser inner width/height: 1024/697 07:39:03 INFO - PROCESS | 9530 | __metrics 07:39:04 INFO - PROCESS | 9530 | 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" 07:39:04 INFO - PROCESS | 9530 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:39:05 INFO - PROCESS | 9530 | console.error: PushService: 07:39:05 INFO - PROCESS | 9530 | stateChangeProcessEnqueue: Error transitioning state 07:39:05 INFO - PROCESS | 9530 | UnknownError 07:39:05 INFO - TEST-INFO | 9530: exit 0 07:39:05 INFO - Browser initialized. 07:39:05 INFO - Running cycle 1/1 for kraken test... 07:39:05 INFO - TEST-INFO | started process 9621 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMD_Euu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:39:17 INFO - PROCESS | 9621 | RSS: Main: 145559552 07:39:17 INFO - PROCESS | 9621 | 07:39:19 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48901/tests/kraken/driver.html?testName=audio-beat-detection) 07:39:20 INFO - PROCESS | 9621 | RSS: Main: 186261504 07:39:20 INFO - PROCESS | 9621 | 07:39:23 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48901/tests/kraken/driver.html?testName=audio-dft) 07:39:23 INFO - PROCESS | 9621 | RSS: Main: 175886336 07:39:23 INFO - PROCESS | 9621 | 07:39:26 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48901/tests/kraken/driver.html?testName=audio-fft) 07:39:27 INFO - PROCESS | 9621 | RSS: Main: 268210176 07:39:27 INFO - PROCESS | 9621 | 07:39:29 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48901/tests/kraken/driver.html?testName=audio-oscillator) 07:39:29 INFO - PROCESS | 9621 | RSS: Main: 174215168 07:39:29 INFO - PROCESS | 9621 | 07:39:31 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48901/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:39:32 INFO - PROCESS | 9621 | RSS: Main: 175980544 07:39:32 INFO - PROCESS | 9621 | 07:39:35 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48901/tests/kraken/driver.html?testName=imaging-darkroom) 07:39:36 INFO - PROCESS | 9621 | RSS: Main: 211746816 07:39:36 INFO - PROCESS | 9621 | 07:39:39 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48901/tests/kraken/driver.html?testName=imaging-desaturate) 07:39:40 INFO - PROCESS | 9621 | RSS: Main: 210907136 07:39:40 INFO - PROCESS | 9621 | 07:39:42 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48901/tests/kraken/driver.html?testName=json-parse-financial) 07:39:43 INFO - PROCESS | 9621 | RSS: Main: 210223104 07:39:43 INFO - PROCESS | 9621 | 07:39:44 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48901/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:39:45 INFO - PROCESS | 9621 | RSS: Main: 172040192 07:39:45 INFO - PROCESS | 9621 | 07:39:47 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48901/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:39:47 INFO - PROCESS | 9621 | RSS: Main: 178774016 07:39:47 INFO - PROCESS | 9621 | 07:39:49 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48901/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:39:50 INFO - PROCESS | 9621 | RSS: Main: 188604416 07:39:50 INFO - PROCESS | 9621 | 07:39:52 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48901/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:39:53 INFO - PROCESS | 9621 | RSS: Main: 190586880 07:39:53 INFO - PROCESS | 9621 | 07:39:56 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48901/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:39:56 INFO - PROCESS | 9621 | RSS: Main: 192634880 07:39:56 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | Cycle 1(1): loaded http://localhost:48901/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48901/tests/kraken/driver.html?testName=ai-astar) 07:39:58 INFO - PROCESS | 9621 | RSS: Main: 242851840 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | __start_tp_report 07:39:58 INFO - PROCESS | 9621 | _x_x_mozilla_page_load 07:39:58 INFO - PROCESS | 9621 | _x_x_mozilla_page_load_details 07:39:58 INFO - PROCESS | 9621 | |i|pagename|runs| 07:39:58 INFO - PROCESS | 9621 | |0;ai-astar;108;112;109;110;113;110;110;110;110;111 07:39:58 INFO - PROCESS | 9621 | |1;audio-beat-detection;143;133;120;156;119;118;117;115;156;115 07:39:58 INFO - PROCESS | 9621 | |2;audio-dft;160;160;160;161;160;160;161;160;160;160 07:39:58 INFO - PROCESS | 9621 | |3;audio-fft;76;75;76;105;76;76;75;78;96;75 07:39:58 INFO - PROCESS | 9621 | |4;audio-oscillator;82;82;108;82;81;81;109;81;83;108 07:39:58 INFO - PROCESS | 9621 | |5;imaging-gaussian-blur;116;118;117;117;117;117;117;118;117;116 07:39:58 INFO - PROCESS | 9621 | |6;imaging-darkroom;146;146;146;146;146;146;147;146;146;146 07:39:58 INFO - PROCESS | 9621 | |7;imaging-desaturate;95;96;95;96;98;95;95;95;96;95 07:39:58 INFO - PROCESS | 9621 | |8;json-parse-financial;50;50;48;49;48;49;49;50;49;51 07:39:58 INFO - PROCESS | 9621 | |9;json-stringify-tinderbox;59;60;58;59;60;59;59;59;60;59 07:39:58 INFO - PROCESS | 9621 | |10;stanford-crypto-aes;74;73;75;80;74;75;74;78;75;73 07:39:58 INFO - PROCESS | 9621 | |11;stanford-crypto-ccm;123;124;128;128;122;123;130;128;200;120 07:39:58 INFO - PROCESS | 9621 | |12;stanford-crypto-pbkdf2;151;151;151;152;153;152;151;156;153;152 07:39:58 INFO - PROCESS | 9621 | |13;stanford-crypto-sha256-iterative;67;61;62;62;61;61;63;64;61;61 07:39:58 INFO - PROCESS | 9621 | __end_tp_report 07:39:58 INFO - PROCESS | 9621 | __start_cc_report 07:39:58 INFO - PROCESS | 9621 | _x_x_mozilla_cycle_collect,1585 07:39:58 INFO - PROCESS | 9621 | __end_cc_report 07:39:58 INFO - PROCESS | 9621 | __startTimestamp1479137998734__endTimestamp 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | ------- Summary: start ------- 07:39:58 INFO - PROCESS | 9621 | Number of tests: 14 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.00 stddev:1.42 (1.3%) stddev-sans-first:1.24 07:39:58 INFO - PROCESS | 9621 | Values: 108.0 112.0 109.0 110.0 113.0 110.0 110.0 110.0 110.0 111.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:126.50 stddev:16.69 (13.2%) stddev-sans-first:16.94 07:39:58 INFO - PROCESS | 9621 | Values: 143.0 133.0 120.0 156.0 119.0 118.0 117.0 115.0 156.0 115.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 07:39:58 INFO - PROCESS | 9621 | Values: 160.0 160.0 160.0 161.0 160.0 160.0 161.0 160.0 160.0 160.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.00 stddev:10.63 (14.0%) stddev-sans-first:11.14 07:39:58 INFO - PROCESS | 9621 | Values: 76.0 75.0 76.0 105.0 76.0 76.0 75.0 78.0 96.0 75.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:81.50 stddev:12.88 (15.8%) stddev-sans-first:13.35 07:39:58 INFO - PROCESS | 9621 | Values: 82.0 82.0 108.0 82.0 81.0 81.0 109.0 81.0 83.0 108.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 07:39:58 INFO - PROCESS | 9621 | Values: 116.0 118.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 116.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 07:39:58 INFO - PROCESS | 9621 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:95.50 stddev:0.97 (1.0%) stddev-sans-first:1.00 07:39:58 INFO - PROCESS | 9621 | Values: 95.0 96.0 95.0 96.0 98.0 95.0 95.0 95.0 96.0 95.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#8] json-parse-financial Cycles:10 Average:49.30 Median:49.50 stddev:0.95 (1.9%) stddev-sans-first:0.97 07:39:58 INFO - PROCESS | 9621 | Values: 50.0 50.0 48.0 49.0 48.0 49.0 49.0 50.0 49.0 51.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 07:39:58 INFO - PROCESS | 9621 | Values: 59.0 60.0 58.0 59.0 60.0 59.0 59.0 59.0 60.0 59.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:75.00 stddev:2.23 (3.0%) stddev-sans-first:2.33 07:39:58 INFO - PROCESS | 9621 | Values: 74.0 73.0 75.0 80.0 74.0 75.0 74.0 78.0 75.0 73.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:128.00 stddev:23.90 (18.7%) stddev-sans-first:25.10 07:39:58 INFO - PROCESS | 9621 | Values: 123.0 124.0 128.0 128.0 122.0 123.0 130.0 128.0 200.0 120.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:152.00 stddev:1.55 (1.0%) stddev-sans-first:1.58 07:39:58 INFO - PROCESS | 9621 | Values: 151.0 151.0 151.0 152.0 153.0 152.0 151.0 156.0 153.0 152.0 07:39:58 INFO - PROCESS | 9621 | 07:39:58 INFO - PROCESS | 9621 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.00 stddev:1.95 (3.1%) stddev-sans-first:1.09 07:39:58 INFO - PROCESS | 9621 | Values: 67.0 61.0 62.0 62.0 61.0 61.0 63.0 64.0 61.0 61.0 07:39:58 INFO - PROCESS | 9621 | -------- Summary: end -------- 07:39:58 INFO - PROCESS | 9621 | 07:39:59 INFO - TEST-INFO | 9621: exit 0 07:39:59 INFO - TEST-OK | kraken | took 59998ms 07:39:59 INFO - SUITE-END | took 1174s 07:39:59 INFO - Completed test suite (00:19:34) 07:39:59 INFO - javascript benchmark 07:39:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3069.4014667139904, 3112.4242015227182, 3122.687731226876, 3151.4484965131096, 3164.9367012659745, 5399.109146990747, 5455.590830687699, 5483.067878460662, 5499.532539734122, 5506.174073888918, 5572.3870374258795, 5589.329280486337, 5589.441055894408, 5598.1602759586085, 5608.130739735338, 5046.041252162096, 5057.924131138017, 5085.237214417848, 5136.306598609177, 5156.226566015109, 4982.227754698009, 5093.286939828427, 5151.4333423323405, 5153.3815942087, 5154.536091751742, 4953.801847926079, 4976.626752993522, 5007.599392048627, 5017.222330538772, 5026.29631851541, 5014.1225285575065, 5036.9926014797, 5056.191009438472, 5127.307813445174, 5159.484051594848, 4974.77613507393, 5057.519535644108, 5103.106956282636, 5114.616403769732, 5127.589792816566, 5327.627066105374, 5363.865903352447, 5400.540954018913, 5432.483914028161, 5481.369642491085, 5703.885922281573, 5722.027255366597, 5723.799667011676, 5807.88384232313, 5812.476877081083, 5851.38560451155, 5856.707164641751, 5863.5309175265875, 5874.206982057453, 5875.059990401558, 5406.945930540684, 5452.182172674091, 5467.863303417406, 5500.4499550044675, 5504.4770746778995, 6056.939430605726, 6064.545159113084, 6070.878582428415, 6082.2093127893095, 6082.817515474543, 5829.15477255804, 5841.386654401312, 5844.620099693507, 5848.064309710511, 5850.561207909339, 5735.483806457439, 5747.568932330092, 5768.480836724715, 5784.132380142928, 5806.506446952057, 5804.564657650693, 5805.216295800013, 5805.970970145122, 5812.418758124154, 5834.708264586754, 5762.7694892203845, 5818.5345172386105, 5818.912716309259, 5819.970900145472, 5846.970765146147, 4415.359772832985, 4439.311906654474, 4477.552244775561, 4495.168393847149, 4515.029268707243, 4032.2338755636333, 4056.9797151014054, 4072.2669919414197, 4078.530968938551, 4103.199876024206, 5617.21359009739, 5625.0, 5626.549876009909, 5633.63381380209, 5637.9718101409235, 5828.970855145697, 5862.267216597926, 5877.941220587825, 5974.761009559656, 5977.970110149421, 5547.611667183259, 5598.188262701967, 5599.8600034998635, 5629.296337957755, 5640.182173584889, 5149.665271757323, 5154.819581314636, 5154.845354639367, 5156.871078222999, 5169.147090729987, 5856.912146317723, 5865.970670146622, 6127.448529632355, 6142.078688196806, 6145.5698101133385, 5792.797252096157, 5861.032929566744, 5872.588918775645, 5980.192673988956, 5980.940190598212, 6124.356942520971, 6131.110988906673, 6133.785317513865, 6140.478059364826, 6145.354737752472, 5827.300723912988, 5835.095560188178, 5839.328477225174, 5840.8247752567495, 5849.444302791228, 5670.914936275876, 5784.768609255582, 5896.6756828374855, 5929.318128415288, 5955.255593050869, 5847.766089356548, 5896.852578685395, 5897.734601942837, 5901.380355062656, 5923.674197919156, 5875.500582450369, 5883.294004719461, 5886.0, 5887.558433117448, 5895.4399332063385, 5517.144842549411, 5626.831195064155, 5712.200291959046, 5716.028275193144, 5822.650640961556, 5910.202122713378, 5911.852203694942, 5917.6745279010065, 5950.940490595039, 5962.9105563415715, 5525.834224973257, 5758.568107392046, 5760.222369979999, 5767.8558036049435, 5767.913481297868], "value": 5442.791152056887, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.8892054969831, 640.3052687833701, 640.3500447046249, 659.3011407907616, 660.2802944790178, 37814.24371512568, 38524.22951540967, 47324.0, 47541.762291188585, 47602.0, 49201.753991230085, 49712.50287497124, 49775.75112124444, 49798.50201497984, 49813.0, 9480.525973701322, 9605.375650582724, 9631.566579503939, 9635.325527213097, 9638.518074096268, 8489.702860399888, 8722.258608018294, 10975.670729878077, 11259.718507037307, 11267.26762760418, 11523.308601483895, 11621.941890290578, 11632.825507617392, 11633.243839150471, 11636.941815290911, 11371.545138194504, 11395.088392928546, 11434.771304573946, 11454.427278636034, 11471.139664525193, 11142.665720028332, 11198.832017519744, 11215.214934954556, 11218.102551795919, 11227.157963152797, 45750.54249457496, 46048.53951460476, 46097.0, 46205.76897115493, 46233.0, 3435.0, 3490.5287786148797, 3509.2279698466295, 3573.8927832165077, 3600.3519366514015, 34459.4831077534, 34706.82646586776, 35176.0, 35218.0, 35354.29291414183, 4662.533746625344, 4671.743054132022, 4730.521894004796, 4770.904581908377, 4869.853904382874, 364163.0, 365449.0730556175, 366609.0, 367030.0, 368537.0, 66698.0, 67055.0, 67404.99999999903, 67430.0, 67471.3252867465, 42405.36391954123, 43229.567704323184, 43352.56647433549, 43394.34908476375, 43663.56336436595, 19784.406467805988, 19820.801791982183, 19938.30215942435, 19955.301564445173, 19994.70007949882, 14038.649033774238, 14289.92855035739, 14846.777298340534, 14937.925310373595, 14964.40142394292, 18864.434066978014, 19064.71402928957, 20167.0, 20870.58258834815, 20918.372448826558, 7514.286142816423, 7533.935182178399, 7573.810654733566, 7688.231176882267, 7826.4130190235965, 944.3153713557753, 1072.753266748652, 1100.9174311926477, 1133.7959167349957, 1187.9308622240053, 34760.82619586936, 35353.0, 35622.64377356245, 35781.28437431237, 35905.28189436249, 1149.9655010349702, 1156.19066653343, 1159.4087015622094, 1172.5192671004847, 1174.9060075193963, 18595.163217655238, 18724.34464793753, 18878.339258126176, 18888.905555472134, 19072.0, 549754.0, 556465.0, 559431.0, 560782.0, 562398.0, 65043.67478162579, 65621.0, 65651.0, 65656.67171664207, 65662.34337656562, 1222.9816552751715, 1335.8396992360786, 1343.2007955266602, 1381.8065470834092, 1394.1356359057247, 34160.82919585386, 36325.273494529436, 36843.0, 37140.6285937148, 37195.25609487849, 56099.71950140223, 56754.716226418604, 56798.0, 56828.71585642045, 56921.43078569161, 389214.0, 393375.0, 399396.0, 528757.0, 545788.0, 48247.75876120597, 48503.51496485131, 48890.266645999625, 48946.75526622344, 49223.507764921895, 53505.0, 53910.730446347516, 54434.45565544294, 54481.0, 54733.72633136809, 44077.34690717242, 49347.25979110388, 49576.0, 49761.50238497713, 50910.0, 44251.11497770091, 45829.77085114553, 45973.08053838972, 46278.76860615675, 46558.30162547632, 45959.0, 46821.53178468308, 46940.765296173304, 46968.530314696414, 47050.82372940704, 37546.0, 39938.0, 40762.38856417097, 40923.7953810229, 40934.79532602318, 30329.0, 30372.39255214839, 30990.69009309878, 31236.531452028692, 31315.53026704647], "value": 22279.084009098184, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.39066642038046, 405.3554847792012, 426.889008857697, 430.3394289765211, 440.5440369217858, 14820.851791482082, 16499.01005939643, 19842.90078549607, 20485.38543843687, 21593.0, 19987.200511979503, 20444.693329600068, 20541.0, 20664.380068597966, 20754.0, 5882.117682347639, 5896.321922978858, 6024.457798798107, 6057.303410107828, 6058.121572372003, 4385.51759306476, 5919.852003699899, 6012.518998480111, 6018.15745795589, 6023.6385816851125, 4562.726236425809, 4602.171609110359, 6667.633280169589, 6771.762988295411, 6793.7961861143995, 6475.838104047437, 6625.9668701656665, 6767.255601883789, 6782.966085169592, 6787.830304242409, 5904.291485021825, 5904.498117659961, 5905.320888097838, 5908.881822363572, 5923.703814809242, 24407.877960610083, 24744.876275618688, 24847.0, 24987.625185622048, 25099.247022589352, 2463.4210960424284, 2468.7654672806057, 2529.7723204911463, 2540.2633236361617, 2655.402534429757, 14292.356843942047, 14594.197319147443, 15295.388184472607, 15480.690386192213, 15545.455909043128, 1962.6663467210608, 1988.204718112752, 2026.3313106674761, 2039.2964427272568, 2054.476108592299, 63552.682236588524, 74970.0, 76599.0, 84963.0, 86709.0, 9621.470819105016, 10245.180385569136, 10268.07587317145, 11187.944060279646, 11477.885221147848, 13957.720845583031, 14001.579952601438, 14062.0, 14149.5047673333, 14168.149911005372, 6941.236463988957, 7072.115985501812, 8343.0, 8427.747167584983, 8721.738347849574, 8112.269895709414, 8166.0, 8652.264557512683, 8691.782705432415, 8909.955450222837, 4245.27830268855, 4347.130573885172, 4386.934195987063, 4457.175422546839, 4501.437320334958, 1951.6096780643645, 1981.5343394302326, 2050.2311633137574, 2079.792020797908, 2108.917508803806, 945.9006804285451, 971.2375785008734, 1000.0099701891438, 1057.4924036462542, 1097.9286346387619, 20430.38708838737, 20698.275560355316, 20732.792672073087, 20759.065842037144, 20774.58450831005, 1041.0, 1065.9680209593726, 1074.3500182389698, 1079.3308148947704, 1102.7684186320803, 10675.83986240207, 11604.593839215588, 11775.175737698279, 11821.408929553489, 11889.524419023315, 487052.0, 494465.0, 503295.0, 509008.0, 510259.0, 74812.62593686997, 92672.53663731772, 97751.0, 99729.50135249422, 101686.49156754317, 1028.6296933104077, 1043.2332235806675, 1064.8881867403966, 1071.2180108520824, 1075.7203127186988, 18535.44393668192, 18691.252349906394, 18886.0, 18925.43223703291, 18967.69365099164, 45661.0, 45890.0, 46074.53925460837, 46473.53526464827, 47080.7645961768, 65278.673606631666, 65540.34459655533, 65946.01080983884, 66390.66804665946, 66861.0, 8616.956915215635, 8709.520976346363, 8890.511021893857, 8947.150020748019, 9071.228945539438, 12186.756264874475, 13607.659808504866, 13625.931870340584, 13784.862151378358, 13978.0, 11324.490397932277, 11897.286162830258, 12244.265344079384, 12296.139270250997, 12335.259884406963, 11795.882041179479, 11906.642800715992, 12002.579909703118, 12188.634340969786, 12522.686932826387, 12059.819102713642, 12106.455209515418, 12171.8090743667, 12222.877771222174, 12369.876301237233, 7402.29678180572, 8554.657813687632, 8726.956365218388, 8948.060453652273, 8985.685501007434, 10875.401852898167, 12279.631611051684, 12497.625071247878, 12505.0, 12576.874231257936], "value": 9640.410732758311, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.2176029344887, 1558.4856997190927, 1716.1590820497959, 1810.936617218396, 1841.2542920117357, 8498.575071246436, 9363.595460680901, 9379.624815007392, 9386.953065234682, 9421.387609805366, 7761.495502792314, 7952.6421311040995, 8702.303815694746, 8977.461352318865, 8992.8651070234, 2434.6226334918074, 2470.110760126353, 2471.4315707387314, 2472.3942634054592, 2474.40614252579, 2015.8891260980713, 2416.335507735378, 2501.499700059986, 2506.1729629222314, 2509.8619575923317, 2208.624533829253, 2420.165043060141, 2486.987565062181, 2488.365466805962, 2500.862452565108, 2400.195934361991, 2407.5666380051584, 2409.0604664180896, 2415.130553000918, 2416.1905761569897, 2376.750441203666, 2381.321323422816, 2381.666566680666, 2382.916597919082, 2384.5350156719446, 9079.727608171765, 9251.814963700755, 9259.6296148154, 9261.351705380626, 9288.535573221312, 5530.5299049580835, 5634.323881134249, 5639.238702775114, 5662.858428539278, 5675.063614503602, 3427.3488037273087, 3470.0630829676097, 3606.459031145323, 3614.4759009943673, 3630.7276954228273, 1233.9383030848512, 1255.171586752748, 1287.9742405151844, 1315.7105436803888, 1327.9734405311935, 12865.614031579067, 12895.0, 12896.742065158644, 12909.67725806853, 13034.348282585928, 4854.732989685531, 4976.7262800545595, 5005.3242812220615, 5082.313887625123, 5104.9744751276, 10844.13246940243, 10925.945370273257, 10932.289401188897, 10935.617253396093, 10944.616938407118, 5682.40334764852, 5688.089905615082, 5692.0323544997445, 5718.79984200551, 5733.655980641175, 5007.248912663057, 5136.075506408883, 5180.559652429586, 5224.373075230997, 5234.92147617786, 4303.536797488869, 4358.128374325148, 4424.867253982386, 4474.888127796831, 4490.955090449053, 1291.2704322058007, 1447.9203643799692, 1471.0952764050246, 1491.865732084096, 1492.723846088477, 729.3873146556917, 814.0069115678981, 882.9514376709215, 885.3802338363172, 896.5203616065373, 5747.885042299131, 5998.130271110752, 6019.969900150559, 6037.698115094316, 6117.696443920233, 1004.5881188712593, 1011.261778901406, 1032.3238278927315, 1039.599754094664, 1041.5625437316346, 3751.5873253942045, 3815.5421349438243, 3962.663173630216, 3979.243943650697, 4080.6939479539155, 11223.0, 11620.76758464826, 11625.534978600761, 11636.53453861836, 11649.59226427088, 11529.596464123882, 11535.884641153481, 11538.88461115395, 11542.769144617061, 11543.711407214887, 8020.639071241808, 8340.457870238415, 8349.206825351583, 8537.615807288812, 8538.103499132625, 5802.216700745345, 5872.442117998783, 5876.823695289148, 5988.940110598838, 5997.070454079625, 10312.123469505175, 10322.793544128925, 10346.534405951901, 10426.582936682447, 10462.63380781669, 10239.795204096024, 10471.162307015267, 10503.0, 10572.20708446885, 10633.627823026158, 7863.252990965849, 8119.75640730779, 8128.878066828884, 8130.67477300925, 8142.67429302845, 8706.651733930572, 8716.3462740296, 8741.388102832994, 8790.560471976247, 8871.423357481743, 6315.747370105144, 7505.362044226302, 7524.05949256343, 7528.397728181842, 7725.22747725223, 7596.544207347577, 7709.80725481845, 7749.92250077492, 7765.145833958373, 7842.333401660694, 6826.0, 7017.614031228331, 7019.859602808017, 7098.112735908012, 7261.5280006799385, 5211.843644690665, 5212.113940630026, 5213.661112027706, 5302.39022512416, 5326.41409444953, 4994.101061808909, 5096.184610462308, 5112.463191365002, 5195.350581177353, 5206.140986737146], "value": 4813.88384365444, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.10532615200464, 351.509644046555, 357.19987228607926, 360.624950051946, 367.8436664417622, 5395.73021348932, 5577.274954255941, 5582.302212223472, 5622.0, 5627.690477023767, 5335.439778823216, 5433.239346491485, 5459.3994660587305, 5462.688954650885, 5492.0, 4353.41228934093, 4357.564243575633, 4361.978190109045, 4364.67264955128, 4370.6503479721705, 4120.0, 4289.571042895716, 4328.63206627437, 4409.699138754059, 4411.4265145531035, 4271.914561708764, 4272.850450234242, 4277.422547956033, 4278.657707383401, 4303.26844436445, 4293.592108749664, 4312.78436078198, 4337.436133302682, 4345.717528360647, 4360.956390436087, 3724.2923844469465, 3821.636944490269, 3827.674647654953, 3834.69322454203, 3853.5183102112237, 5397.21891775717, 5405.37838148614, 5407.351117865842, 5424.267723857268, 5424.972875135599, 4984.152694042022, 5006.649534532584, 5007.4491805901325, 5011.423686276052, 5026.5727413169925, 5595.4964053235, 5744.678723893528, 5769.509591684712, 5780.393058728857, 5824.097264923944, 4172.996961458487, 4175.8956026099595, 4179.435776170239, 4188.120494696117, 4197.9370309445385, 5803.854903627401, 6068.514518838482, 6087.908681369739, 6112.938870611281, 6122.693865306717, 4240.936385954213, 4251.4685664291965, 4263.275243208662, 4264.488261408651, 4295.625399872073, 4363.0, 4373.759443230653, 4383.408239887574, 4384.8903777405185, 4391.692581519263, 4978.228374601942, 4982.128127577732, 4993.251012348104, 5014.52362025607, 5024.346834911511, 5527.0, 5699.5155411790465, 5842.8539286517325, 5895.793647222326, 5917.970410148007, 1408.8940181957114, 1451.7314296854904, 1467.1930438259128, 1487.256371814093, 1502.241368109098, 993.3940296419179, 1028.285341687535, 1031.9329243599143, 1041.4896700616796, 1041.7499800047933, 5538.307711536058, 5549.833504994856, 5553.861153471195, 5554.750036248379, 5565.220869078333, 5891.204687367151, 5896.557758168155, 5897.675627840425, 5914.467697907209, 5914.526837852962, 5302.893942121136, 5559.360673522597, 5568.16477528374, 5601.383847776741, 5655.915161272585, 3149.637791653989, 3168.952465713016, 3184.5510292817175, 3186.4423725847882, 3194.1535493094348, 5895.75961134766, 6144.477719393902, 6164.938350616526, 6171.13604095427, 6175.96912015437, 6123.357047510036, 6134.5092392608485, 6141.0, 6145.631262124287, 6150.200473938359, 6181.474574661114, 6181.567290289637, 6206.596571222856, 6212.161358216673, 6228.221472316051, 5765.336986246635, 5993.550483713651, 6020.39796020412, 6027.849303767265, 6058.757649694139, 5906.106008056107, 5914.497267732291, 5924.377940316377, 5960.254968128984, 5969.880602388015, 5931.822045338647, 6193.504519638508, 6196.1635179250225, 6200.56596038273, 6209.379062093754, 4311.202427550913, 4324.426965955252, 4327.545607711145, 4333.8699839004885, 4350.651947844101, 5856.355800861987, 6069.726862291119, 6098.75604975796, 6142.539309551891, 6157.815265542041, 5745.339285982165, 5791.160281759206, 5864.824055278348, 5877.265341832271, 5897.056470964625, 5845.795397161079, 5858.531317494505, 5868.823935281948, 5874.236349274567, 5890.381509941566, 5615.831525054255, 5784.884302314014, 5815.621984570989, 5835.649861008353, 5841.240638716939, 5850.356460789395, 5892.996381230402, 5899.321578018583, 5902.173695682522, 5903.321118071477, 5456.380937150111, 5762.4525670061275, 5765.336986246635, 5768.682722450306, 5771.769129234784], "value": 4457.7083560554765, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.6569342336085, 814.8125931035862, 841.4404421059995, 869.2741560796733, 885.6413152673169, 15400.30698618562, 16332.673346533062, 16371.91814040931, 16431.178441077944, 16459.423920162797, 15294.082355058705, 15364.539063828075, 15657.765133523006, 15658.686826263467, 15676.764848527282, 721.8123287945119, 738.2396131984065, 847.8855354527137, 850.8255807559435, 877.4209022045451, 871.0897112517434, 871.7602659268689, 872.3326655108847, 872.7992561710811, 872.9781755456132, 920.9723708288763, 921.1479381572052, 921.2261700171854, 921.4655499810127, 921.6313474610176, 755.9206283340253, 772.382094324541, 883.8320719063357, 897.4660077254024, 908.5684299957534, 667.6661669165418, 672.2168673495372, 672.8048865828904, 672.8586996730693, 673.8113328268092, 12454.875451245462, 12519.061070419664, 12547.0, 12552.874471255353, 12619.747605047847, 14186.929065354607, 14201.928990354982, 14305.0, 14401.567952961428, 14766.114033158045, 13358.265295408748, 13483.66290842727, 13530.18818870871, 13551.186928784304, 13838.377273022637, 4632.513586073481, 4664.300354946751, 4694.647901407408, 4694.671373003891, 4762.833300834454, 71747.28252717458, 72508.27491725067, 75028.62485687537, 81368.59315703443, 81452.59273703652, 11790.23363481371, 12079.335636540074, 12349.382530873421, 12677.936610317074, 12863.42114604845, 24314.878425608113, 24587.0, 24607.753922460903, 24645.753542464703, 24656.87671561631, 9664.420134791935, 9787.314887957917, 9850.359726617748, 10007.0, 10372.844407333896, 5120.948790512121, 5136.640435169502, 5156.4070131935305, 5281.313429254173, 5334.626576139633, 3915.236528876899, 4025.879223623296, 4027.677785777131, 4038.192361527706, 4041.211963667057, 585.844751140948, 643.5430844100647, 653.9673016349163, 658.2660333727858, 676.3980057748607, 647.2362612117746, 689.8171984424132, 702.9472789540703, 712.5795780489536, 718.4683334332557, 9000.684976025808, 9260.49067301305, 9271.490068046187, 9469.952650236843, 9471.431714097176, 631.7069039386996, 761.4022991951402, 815.7144999250215, 817.7138001699476, 827.6896163938524, 4467.173572889026, 4766.523347665206, 4777.474477807438, 4787.42550893888, 4796.25658023007, 73948.0, 99624.0, 116981.0, 120268.0, 121652.0, 104547.4772626132, 106623.46688266509, 109240.4537977305, 109298.0, 109496.0, 57025.14462283069, 85024.57487712523, 89506.10493894978, 92177.0, 92210.53894730483, 7865.449418540876, 8003.91996080055, 8120.228578285053, 8181.100078991425, 8333.541655209021, 47101.528984709716, 51668.0, 53039.469605305, 53178.73410632922, 53378.0, 46841.53158468508, 46991.0, 48671.75664121657, 57107.0, 83012.58493707493, 11775.411229438665, 11842.0, 12033.518659253881, 12041.036717062789, 13081.411336489691, 12384.75230495367, 12685.858272755497, 13600.387982540611, 14008.579742607739, 15160.469383571522, 13446.193228406328, 13774.449022039005, 13855.515056972958, 14258.500952466615, 14610.78083828722, 13429.395677194745, 13618.659533511345, 13911.791323129959, 13990.580282591538, 14374.425022998963, 12333.691657708629, 12766.553170638983, 13140.802887956497, 13396.665083372995, 13879.236641984788, 10102.494875256061, 10265.486725663837, 11161.665150045512, 11690.4739286734, 12088.576899808464, 11437.714057148638, 11850.111241657083, 12020.158588899043, 12096.395180241128, 12204.206726562745], "value": 7698.8319396336, "unit": "score"}], "name": "dromaeo_css", "value": 7602.948470418782, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 109.0, 110.0, 113.0, 110.0, 110.0, 110.0, 110.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 133.0, 120.0, 156.0, 119.0, 118.0, 117.0, 115.0, 156.0, 115.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 161.0, 160.0, 160.0, 161.0, 160.0, 160.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 105.0, 76.0, 76.0, 75.0, 78.0, 96.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 108.0, 82.0, 81.0, 81.0, 109.0, 81.0, 83.0, 108.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 98.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 48.0, 49.0, 48.0, 49.0, 49.0, 50.0, 49.0, 51.0], "value": 49.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 58.0, 59.0, 60.0, 59.0, 59.0, 59.0, 60.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 75.0, 80.0, 74.0, 75.0, 74.0, 78.0, 75.0, 73.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 124.0, 128.0, 128.0, 122.0, 123.0, 130.0, 128.0, 200.0, 120.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 152.0, 153.0, 152.0, 151.0, 156.0, 153.0, 152.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 61.0, 62.0, 62.0, 61.0, 61.0, 63.0, 64.0, 61.0, 61.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1459.6, "alertThreshold": 2.0}]} 07:39:59 INFO - Return code: 0 07:40:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:40:00 INFO - # TBPL SUCCESS # 07:40:00 INFO - Running post-action listener: _resource_record_post_action 07:40:00 INFO - [mozharness: 2016-11-14 15:40:00.054303Z] Finished run-tests step (success) 07:40:00 INFO - Running post-run listener: _resource_record_post_run 07:40:00 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 20480; Write bytes: 365215744; Read time: 108; Write time: 237716 07:40:00 INFO - TinderboxPrint: CPU usage
12.9% 07:40:00 INFO - TinderboxPrint: I/O read bytes / time
20,480 / 108 07:40:00 INFO - TinderboxPrint: I/O write bytes / time
365,215,744 / 237,716 07:40:00 INFO - TinderboxPrint: CPU idle
8,267.9 (87.0%) 07:40:00 INFO - TinderboxPrint: CPU user
1,184.2 (12.5%) 07:40:00 INFO - TinderboxPrint: Swap in / out
0 / 0 07:40:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 116076544; Read time: 0; Write time: 91680 07:40:00 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 16384; Write bytes: 248524800; Read time: 92; Write time: 146036 07:40:00 INFO - Running post-run listener: _upload_blobber_files 07:40:00 INFO - Blob upload gear active. 07:40:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:40:00 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 07:40:00 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'] 07:40:00 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 07:40:01 INFO - (blobuploader) - INFO - Open directory for files ... 07:40:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:40:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:40:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:40:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:40:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:40:04 INFO - (blobuploader) - INFO - Done attempting. 07:40:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:40:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:40:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:40:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:40:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:40:05 INFO - (blobuploader) - INFO - Done attempting. 07:40:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:40:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:40:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:40:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:40:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:40:06 INFO - (blobuploader) - INFO - Done attempting. 07:40:06 INFO - (blobuploader) - INFO - Iteration through files over. 07:40:06 INFO - Return code: 0 07:40:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:40:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:40:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1dce707aecacff161298943fc088e906f5bf9c41718b135b490ea1333d9e009125550ecf056edaf2c1b76632116ecd700b908e4be0901a0e8d7129dc6234840b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bdaa5568ac4281d49c1f5f7ef07967be0c86dd63889a862930d4dd87820b72efd497b4f6d2a39222f816483199f993d58abf5da2c529816c3679018f72c166bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66bf883d772b0a25069eee17361f5c84a389fddefaae187551e5bf179f52cc15310e5000ba5f7975c4a476993c69583476794c842d8497e9cc2d97dc211dffc3"} 07:40:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:40:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:40:06 INFO - Contents: 07:40:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1dce707aecacff161298943fc088e906f5bf9c41718b135b490ea1333d9e009125550ecf056edaf2c1b76632116ecd700b908e4be0901a0e8d7129dc6234840b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bdaa5568ac4281d49c1f5f7ef07967be0c86dd63889a862930d4dd87820b72efd497b4f6d2a39222f816483199f993d58abf5da2c529816c3679018f72c166bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66bf883d772b0a25069eee17361f5c84a389fddefaae187551e5bf179f52cc15310e5000ba5f7975c4a476993c69583476794c842d8497e9cc2d97dc211dffc3"} 07:40:06 INFO - Running post-run listener: copy_logs_to_upload_dir 07:40:06 INFO - Copying logs to upload dir... 07:40:06 INFO - mkdir: /builds/slave/test/build/upload/logs 07:40:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.888941 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-11-14 07:40:04.609173) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 07:40:04.612546) ========= 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=d2a8c569965ab8367867b893000001a1-1479136770.875093-448316840 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/1dce707aecacff161298943fc088e906f5bf9c41718b135b490ea1333d9e009125550ecf056edaf2c1b76632116ecd700b908e4be0901a0e8d7129dc6234840b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bdaa5568ac4281d49c1f5f7ef07967be0c86dd63889a862930d4dd87820b72efd497b4f6d2a39222f816483199f993d58abf5da2c529816c3679018f72c166bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66bf883d772b0a25069eee17361f5c84a389fddefaae187551e5bf179f52cc15310e5000ba5f7975c4a476993c69583476794c842d8497e9cc2d97dc211dffc3"} build_url:https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011463 build_url: 'https://queue.taskcluster.net/v1/task/eflXNszJSt6vq_ouj2mDKA/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/1dce707aecacff161298943fc088e906f5bf9c41718b135b490ea1333d9e009125550ecf056edaf2c1b76632116ecd700b908e4be0901a0e8d7129dc6234840b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bdaa5568ac4281d49c1f5f7ef07967be0c86dd63889a862930d4dd87820b72efd497b4f6d2a39222f816483199f993d58abf5da2c529816c3679018f72c166bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66bf883d772b0a25069eee17361f5c84a389fddefaae187551e5bf179f52cc15310e5000ba5f7975c4a476993c69583476794c842d8497e9cc2d97dc211dffc3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 07:40:04.638165) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:40:04.638511) ========= 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=d2a8c569965ab8367867b893000001a1-1479136770.875093-448316840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:40:04.721289) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 07:40:04.721659) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 07:40:04.721991) ========= ========= Total master_lag: 0.17 =========