builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1478428087.46 results: success (0) buildid: 20161106004004 builduid: 92dd89b45bf3443789979e9af66e3713 revision: 070cf059bb70aa991d50b4d266987adf26685915 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:07.463969) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:07.464989) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:07.465703) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:07.481754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:07.482086) ========= 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=8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-06 02:28:07-- 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% 220M=0s 2016-11-06 02:28:08 (220 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082622 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:07.584122) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:07.584624) ========= 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=8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016516 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:07.637647) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:07.638050) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 070cf059bb70aa991d50b4d266987adf26685915 --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 070cf059bb70aa991d50b4d266987adf26685915 --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=8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-06 02:28:08,184 truncating revision to first 12 chars 2016-11-06 02:28:08,184 Setting DEBUG logging. 2016-11-06 02:28:08,184 attempt 1/10 2016-11-06 02:28:08,184 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/070cf059bb70?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-06 02:28:08,613 unpacking tar archive at: mozilla-aurora-070cf059bb70/testing/mozharness/ program finished with exit code 0 elapsedTime=0.797060 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:08.450178) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:08.450530) ========= script_repo_revision: 070cf059bb70aa991d50b4d266987adf26685915 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:08.450995) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:08.451354) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-06 02:28:08.462418) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-11-06 02:28:08.462731) ========= /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=8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:28:09 INFO - MultiFileLogger online at 20161106 02:28:09 in /builds/slave/test 02:28:09 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 02:28:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:28:09 INFO - {'append_to_log': False, 02:28:09 INFO - 'base_work_dir': '/builds/slave/test', 02:28:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:28:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:28:09 INFO - 'branch': 'Mozilla-Aurora', 02:28:09 INFO - 'buildbot_json_path': 'buildprops.json', 02:28:09 INFO - 'config_files': ('talos/linux_config.py',), 02:28:09 INFO - 'default_actions': ('clobber', 02:28:09 INFO - 'read-buildbot-config', 02:28:09 INFO - 'download-and-extract', 02:28:09 INFO - 'populate-webroot', 02:28:09 INFO - 'create-virtualenv', 02:28:09 INFO - 'install', 02:28:09 INFO - 'run-tests'), 02:28:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:28:09 INFO - 'download_minidump_stackwalk': True, 02:28:09 INFO - 'download_symbols': 'ondemand', 02:28:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:28:09 INFO - 'tooltool.py': '/tools/tooltool.py', 02:28:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:28:09 INFO - '/tools/misc-python/virtualenv.py')}, 02:28:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:28:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:28:09 INFO - 'installer_path': 'installer.exe', 02:28:09 INFO - 'log_level': 'info', 02:28:09 INFO - 'log_name': 'talos', 02:28:09 INFO - 'log_to_console': True, 02:28:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:28:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:28:09 INFO - 'opt_config_files': (), 02:28:09 INFO - 'pip_index': False, 02:28:09 INFO - 'sps_profile': False, 02:28:09 INFO - 'sps_profile_interval': 0, 02:28:09 INFO - 'suite': 'dromaeojs', 02:28:09 INFO - 'system_bits': '32', 02:28:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:28:09 INFO - 'title': 'talos-linux64-ix-001', 02:28:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:28:09 INFO - 'use_talos_json': True, 02:28:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:28:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:28:09 INFO - 'work_dir': 'build'} 02:28:09 INFO - [mozharness: 2016-11-06 10:28:09.096169Z] Running clobber step. 02:28:09 INFO - Running pre-action listener: _resource_record_pre_action 02:28:09 INFO - Running main action method: clobber 02:28:09 INFO - rmtree: /builds/slave/test/build 02:28:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:28:09 INFO - Running post-action listener: _resource_record_post_action 02:28:09 INFO - [mozharness: 2016-11-06 10:28:09.552744Z] Finished clobber step (success) 02:28:09 INFO - [mozharness: 2016-11-06 10:28:09.552846Z] Running read-buildbot-config step. 02:28:09 INFO - Running pre-action listener: _resource_record_pre_action 02:28:09 INFO - Running main action method: read_buildbot_config 02:28:09 INFO - Using buildbot properties: 02:28:09 INFO - { 02:28:09 INFO - "project": "", 02:28:09 INFO - "product": "firefox", 02:28:09 INFO - "script_repo_revision": "production", 02:28:09 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 02:28:09 INFO - "repository": "", 02:28:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 02:28:09 INFO - "buildid": "20161106004004", 02:28:09 INFO - "stage_platform": "linux64-pgo", 02:28:09 INFO - "basedir": "/builds/slave/test", 02:28:09 INFO - "nightly_build": "True", 02:28:09 INFO - "buildnumber": 860, 02:28:09 INFO - "slavename": "talos-linux64-ix-001", 02:28:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:28:09 INFO - "platform": "ubuntu64_hw", 02:28:09 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 02:28:09 INFO - "branch": "mozilla-aurora", 02:28:09 INFO - "repo_path": "releases/mozilla-aurora", 02:28:09 INFO - "revision": "070cf059bb70aa991d50b4d266987adf26685915", 02:28:09 INFO - "pgo_build": "True", 02:28:09 INFO - "builduid": "92dd89b45bf3443789979e9af66e3713", 02:28:09 INFO - "slavebuilddir": "test" 02:28:09 INFO - } 02:28:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 02:28:09 INFO - Running post-action listener: _resource_record_post_action 02:28:09 INFO - [mozharness: 2016-11-06 10:28:09.555696Z] Finished read-buildbot-config step (success) 02:28:09 INFO - [mozharness: 2016-11-06 10:28:09.555787Z] Running download-and-extract step. 02:28:09 INFO - Running pre-action listener: _resource_record_pre_action 02:28:09 INFO - Running main action method: download_and_extract 02:28:09 INFO - mkdir: /builds/slave/test/build/tests 02:28:09 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')]} 02:28:09 INFO - trying https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 02:28:09 INFO - Downloading https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/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 02:28:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/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 02:28:10 INFO - Downloaded 1361 bytes. 02:28:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 02:28:10 INFO - Using the following test package requirements: 02:28:10 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 02:28:10 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 02:28:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 02:28:10 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 02:28:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 02:28:10 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 02:28:10 INFO - u'jsshell-linux-x86_64.zip'], 02:28:10 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 02:28:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 02:28:10 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 02:28:10 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 02:28:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 02:28:10 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 02:28:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 02:28:10 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 02:28:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 02:28:10 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 02:28:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 02:28:10 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:28:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 02:28:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:28:10 INFO - Fetch https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 02:28:10 INFO - Expected file size: 21895356 02:28:14 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 02:28:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 02:28:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:28:14 INFO - Fetch https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 02:28:15 INFO - Expected file size: 21895356 02:28:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 02:28:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:28:20 INFO - Fetch https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 02:28:20 INFO - Expected file size: 14414089 02:28:21 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')]} 02:28:21 INFO - trying https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 02:28:21 INFO - Downloading https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/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 02:28:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/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 02:28:23 INFO - Downloaded 63981836 bytes. 02:28:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 02:28:23 INFO - mkdir: /builds/slave/test/properties 02:28:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:28:23 INFO - Writing to file /builds/slave/test/properties/build_url 02:28:23 INFO - Contents: 02:28:23 INFO - build_url:https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 02:28:24 INFO - Running post-action listener: _resource_record_post_action 02:28:24 INFO - Running post-action listener: set_extra_try_arguments 02:28:24 INFO - [mozharness: 2016-11-06 10:28:24.742405Z] Finished download-and-extract step (success) 02:28:24 INFO - [mozharness: 2016-11-06 10:28:24.742633Z] Running populate-webroot step. 02:28:24 INFO - Running pre-action listener: _resource_record_pre_action 02:28:24 INFO - Running main action method: populate_webroot 02:28:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:28:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:28:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:28:24 INFO - '--tppagecycles', 02:28:24 INFO - '7'], 02:28:24 INFO - 'tests': ['tsvgm']}}, 02:28:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:28:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:28:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:28:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:28:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:24 INFO - 'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:24 INFO - 'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['damp', 'tps']}, 02:28:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:24 INFO - 'tests': ['damp', 'tps']}, 02:28:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['dromaeo_dom']}, 02:28:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:28:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['basic_compositor_video']}, 02:28:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:28:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['a11yr', 02:28:24 INFO - 'ts_paint', 02:28:24 INFO - 'tpaint', 02:28:24 INFO - 'sessionrestore', 02:28:24 INFO - 'sessionrestore_no_auto_restore', 02:28:24 INFO - 'tabpaint']}, 02:28:24 INFO - 'other-e10s': {'tests': ['a11yr', 02:28:24 INFO - 'ts_paint', 02:28:24 INFO - 'tpaint', 02:28:24 INFO - 'sessionrestore', 02:28:24 INFO - 'sessionrestore_no_auto_restore', 02:28:24 INFO - 'tabpaint']}, 02:28:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:28:24 INFO - 'ts_paint', 02:28:24 INFO - 'tpaint', 02:28:24 INFO - 'sessionrestore', 02:28:24 INFO - 'sessionrestore_no_auto_restore', 02:28:24 INFO - 'tabpaint']}, 02:28:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:28:24 INFO - 'ts_paint', 02:28:24 INFO - 'tpaint', 02:28:24 INFO - 'sessionrestore', 02:28:24 INFO - 'sessionrestore_no_auto_restore', 02:28:24 INFO - 'tabpaint']}, 02:28:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['a11yr', 02:28:24 INFO - 'ts_paint', 02:28:24 INFO - 'tpaint', 02:28:24 INFO - 'sessionrestore', 02:28:24 INFO - 'sessionrestore_no_auto_restore', 02:28:24 INFO - 'tabpaint']}, 02:28:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['a11yr', 02:28:24 INFO - 'ts_paint', 02:28:24 INFO - 'tpaint', 02:28:24 INFO - 'sessionrestore', 02:28:24 INFO - 'sessionrestore_no_auto_restore', 02:28:24 INFO - 'tabpaint']}, 02:28:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['tsvgx', 02:28:24 INFO - 'tsvgr_opacity', 02:28:24 INFO - 'tart', 02:28:24 INFO - 'tscrollx', 02:28:24 INFO - 'cart']}, 02:28:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:28:24 INFO - 'tsvgr_opacity', 02:28:24 INFO - 'tart', 02:28:24 INFO - 'tscrollx', 02:28:24 INFO - 'cart']}, 02:28:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:24 INFO - 'talos_options': ['--disable-e10s'], 02:28:24 INFO - 'tests': ['tp5o']}, 02:28:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:24 INFO - 'tests': ['tp5o']}, 02:28:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:24 INFO - 'talos_options': ['--disable-e10s', 02:28:24 INFO - '--xperf_path', 02:28:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:24 INFO - 'tests': ['tp5n']}, 02:28:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:24 INFO - 'talos_options': ['--xperf_path', 02:28:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:24 INFO - 'tests': ['tp5n']}}, 02:28:24 INFO - 'talos.zip': {'path': '', 02:28:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:28:24 INFO - Running post-action listener: _resource_record_post_action 02:28:24 INFO - [mozharness: 2016-11-06 10:28:24.762878Z] Finished populate-webroot step (success) 02:28:24 INFO - [mozharness: 2016-11-06 10:28:24.762967Z] Running create-virtualenv step. 02:28:24 INFO - Running pre-action listener: _resource_record_pre_action 02:28:24 INFO - Running main action method: create_virtualenv 02:28:24 INFO - Creating virtualenv /builds/slave/test/build/venv 02:28:24 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 02:28:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:28:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:28:24 INFO - Using real prefix '/usr' 02:28:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:28:25 INFO - Installing distribute.............................................................................................................................................................................................done. 02:28:27 INFO - Installing pip.................done. 02:28:27 INFO - Return code: 0 02:28:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f51422a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', '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 02:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:28:27 INFO - Using env: {'DISPLAY': ':0', 02:28:27 INFO - 'HOME': '/home/cltbld', 02:28:27 INFO - 'LANG': 'en_US.UTF-8', 02:28:27 INFO - 'LANGUAGE': 'en_US:en', 02:28:27 INFO - 'LOGNAME': 'cltbld', 02:28:27 INFO - 'MAIL': '/var/mail/cltbld', 02:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:27 INFO - 'MOZ_NO_REMOTE': '1', 02:28:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:27 INFO - 'NO_EM_RESTART': '1', 02:28:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:27 INFO - 'PWD': '/builds/slave/test', 02:28:27 INFO - 'SHELL': '/bin/bash', 02:28:27 INFO - 'SHLVL': '1', 02:28:27 INFO - 'TERM': 'linux', 02:28:27 INFO - 'TMOUT': '86400', 02:28:27 INFO - 'USER': 'cltbld', 02:28:27 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:27 INFO - '_': '/tools/buildbot/bin/python'} 02:28:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:27 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 02:28:27 INFO - Cleaning up... 02:28:27 INFO - Return code: 0 02:28:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f51422a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', '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 02:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:28:27 INFO - Using env: {'DISPLAY': ':0', 02:28:27 INFO - 'HOME': '/home/cltbld', 02:28:27 INFO - 'LANG': 'en_US.UTF-8', 02:28:27 INFO - 'LANGUAGE': 'en_US:en', 02:28:27 INFO - 'LOGNAME': 'cltbld', 02:28:27 INFO - 'MAIL': '/var/mail/cltbld', 02:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:27 INFO - 'MOZ_NO_REMOTE': '1', 02:28:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:27 INFO - 'NO_EM_RESTART': '1', 02:28:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:27 INFO - 'PWD': '/builds/slave/test', 02:28:27 INFO - 'SHELL': '/bin/bash', 02:28:27 INFO - 'SHLVL': '1', 02:28:27 INFO - 'TERM': 'linux', 02:28:27 INFO - 'TMOUT': '86400', 02:28:27 INFO - 'USER': 'cltbld', 02:28:27 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:27 INFO - '_': '/tools/buildbot/bin/python'} 02:28:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:27 INFO - Downloading/unpacking psutil>=3.1.1 02:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:28:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:29 INFO - Installing collected packages: psutil 02:28:29 INFO - Running setup.py install for psutil 02:28:29 INFO - building 'psutil._psutil_linux' extension 02:28:29 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 02:28:30 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 02:28:30 INFO - building 'psutil._psutil_posix' extension 02:28:30 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 02:28:30 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 02:28:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:30 INFO - Successfully installed psutil 02:28:30 INFO - Cleaning up... 02:28:30 INFO - Return code: 0 02:28:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f51422a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', '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 02:28:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:28:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:28:30 INFO - Using env: {'DISPLAY': ':0', 02:28:30 INFO - 'HOME': '/home/cltbld', 02:28:30 INFO - 'LANG': 'en_US.UTF-8', 02:28:30 INFO - 'LANGUAGE': 'en_US:en', 02:28:30 INFO - 'LOGNAME': 'cltbld', 02:28:30 INFO - 'MAIL': '/var/mail/cltbld', 02:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:30 INFO - 'MOZ_NO_REMOTE': '1', 02:28:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:30 INFO - 'NO_EM_RESTART': '1', 02:28:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:30 INFO - 'PWD': '/builds/slave/test', 02:28:30 INFO - 'SHELL': '/bin/bash', 02:28:30 INFO - 'SHLVL': '1', 02:28:30 INFO - 'TERM': 'linux', 02:28:30 INFO - 'TMOUT': '86400', 02:28:30 INFO - 'USER': 'cltbld', 02:28:30 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:30 INFO - '_': '/tools/buildbot/bin/python'} 02:28:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:28:32 INFO - Installing collected packages: mozsystemmonitor 02:28:32 INFO - Running setup.py install for mozsystemmonitor 02:28:32 INFO - Successfully installed mozsystemmonitor 02:28:32 INFO - Cleaning up... 02:28:32 INFO - Return code: 0 02:28:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:32 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 0x7f51422a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', '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 02:28:32 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 02:28:32 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 02:28:32 INFO - Using env: {'DISPLAY': ':0', 02:28:32 INFO - 'HOME': '/home/cltbld', 02:28:32 INFO - 'LANG': 'en_US.UTF-8', 02:28:32 INFO - 'LANGUAGE': 'en_US:en', 02:28:32 INFO - 'LOGNAME': 'cltbld', 02:28:32 INFO - 'MAIL': '/var/mail/cltbld', 02:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:32 INFO - 'MOZ_NO_REMOTE': '1', 02:28:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:32 INFO - 'NO_EM_RESTART': '1', 02:28:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:32 INFO - 'PWD': '/builds/slave/test', 02:28:32 INFO - 'SHELL': '/bin/bash', 02:28:32 INFO - 'SHLVL': '1', 02:28:32 INFO - 'TERM': 'linux', 02:28:32 INFO - 'TMOUT': '86400', 02:28:32 INFO - 'USER': 'cltbld', 02:28:32 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:32 INFO - '_': '/tools/buildbot/bin/python'} 02:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:32 INFO - Downloading/unpacking jsonschema==2.5.1 02:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:28:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:35 INFO - Downloading functools32-3.2.3-2.tar.gz 02:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:28:35 INFO - warning: no files found matching '*.txt' 02:28:35 INFO - no previously-included directories found matching 'build' 02:28:35 INFO - no previously-included directories found matching 'dist' 02:28:35 INFO - no previously-included directories found matching '.git*' 02:28:35 INFO - Installing collected packages: jsonschema, functools32 02:28:35 INFO - Running setup.py install for functools32 02:28:35 INFO - warning: no files found matching '*.txt' 02:28:35 INFO - no previously-included directories found matching 'build' 02:28:35 INFO - no previously-included directories found matching 'dist' 02:28:35 INFO - no previously-included directories found matching '.git*' 02:28:35 INFO - Successfully installed jsonschema functools32 02:28:35 INFO - Cleaning up... 02:28:35 INFO - Return code: 0 02:28:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f51422a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', '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 02:28:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:28:35 INFO - Using env: {'DISPLAY': ':0', 02:28:35 INFO - 'HOME': '/home/cltbld', 02:28:35 INFO - 'LANG': 'en_US.UTF-8', 02:28:35 INFO - 'LANGUAGE': 'en_US:en', 02:28:35 INFO - 'LOGNAME': 'cltbld', 02:28:35 INFO - 'MAIL': '/var/mail/cltbld', 02:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:35 INFO - 'MOZ_NO_REMOTE': '1', 02:28:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:35 INFO - 'NO_EM_RESTART': '1', 02:28:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:35 INFO - 'PWD': '/builds/slave/test', 02:28:35 INFO - 'SHELL': '/bin/bash', 02:28:35 INFO - 'SHLVL': '1', 02:28:35 INFO - 'TERM': 'linux', 02:28:35 INFO - 'TMOUT': '86400', 02:28:35 INFO - 'USER': 'cltbld', 02:28:35 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:35 INFO - '_': '/tools/buildbot/bin/python'} 02:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:28:35 INFO - Cleaning up... 02:28:35 INFO - Return code: 0 02:28:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f51422a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', '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 02:28:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:28:35 INFO - Using env: {'DISPLAY': ':0', 02:28:35 INFO - 'HOME': '/home/cltbld', 02:28:35 INFO - 'LANG': 'en_US.UTF-8', 02:28:35 INFO - 'LANGUAGE': 'en_US:en', 02:28:35 INFO - 'LOGNAME': 'cltbld', 02:28:35 INFO - 'MAIL': '/var/mail/cltbld', 02:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:35 INFO - 'MOZ_NO_REMOTE': '1', 02:28:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:35 INFO - 'NO_EM_RESTART': '1', 02:28:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:35 INFO - 'PWD': '/builds/slave/test', 02:28:35 INFO - 'SHELL': '/bin/bash', 02:28:35 INFO - 'SHLVL': '1', 02:28:35 INFO - 'TERM': 'linux', 02:28:35 INFO - 'TMOUT': '86400', 02:28:35 INFO - 'USER': 'cltbld', 02:28:35 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:35 INFO - '_': '/tools/buildbot/bin/python'} 02:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:35 INFO - Downloading/unpacking blobuploader==1.2.4 02:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:37 INFO - Downloading blobuploader-1.2.4.tar.gz 02:28:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:28:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:28:37 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 02:28:37 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 02:28:37 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 02:28:37 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 02:28:37 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 02:28:37 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 02:28:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:28:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:38 INFO - Downloading docopt-0.6.1.tar.gz 02:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:28:38 INFO - Installing collected packages: blobuploader, requests, docopt 02:28:38 INFO - Running setup.py install for blobuploader 02:28:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:28:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:28:38 INFO - Running setup.py install for requests 02:28:38 INFO - Running setup.py install for docopt 02:28:38 INFO - Successfully installed blobuploader requests docopt 02:28:38 INFO - Cleaning up... 02:28:39 INFO - Return code: 0 02:28:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:39 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')]} 02:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:39 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')]} 02:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:39 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 0x7f51422a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', '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 02:28:39 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 02:28:39 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 02:28:39 INFO - Using env: {'DISPLAY': ':0', 02:28:39 INFO - 'HOME': '/home/cltbld', 02:28:39 INFO - 'LANG': 'en_US.UTF-8', 02:28:39 INFO - 'LANGUAGE': 'en_US:en', 02:28:39 INFO - 'LOGNAME': 'cltbld', 02:28:39 INFO - 'MAIL': '/var/mail/cltbld', 02:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:39 INFO - 'MOZ_NO_REMOTE': '1', 02:28:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:39 INFO - 'NO_EM_RESTART': '1', 02:28:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:39 INFO - 'PWD': '/builds/slave/test', 02:28:39 INFO - 'SHELL': '/bin/bash', 02:28:39 INFO - 'SHLVL': '1', 02:28:39 INFO - 'TERM': 'linux', 02:28:39 INFO - 'TMOUT': '86400', 02:28:39 INFO - 'USER': 'cltbld', 02:28:39 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:39 INFO - '_': '/tools/buildbot/bin/python'} 02:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:39 INFO - Running setup.py (path:/tmp/pip-kvbKnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:39 INFO - Running setup.py (path:/tmp/pip-_XuZOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:39 INFO - Running setup.py (path:/tmp/pip-5VgZlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:39 INFO - Running setup.py (path:/tmp/pip-NyG6iq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:39 INFO - Running setup.py (path:/tmp/pip-uq3kg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:39 INFO - Running setup.py (path:/tmp/pip-f9o5lM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:39 INFO - Running setup.py (path:/tmp/pip-TlFS6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:39 INFO - Running setup.py (path:/tmp/pip-gmI57T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:39 INFO - Running setup.py (path:/tmp/pip-IV8Rah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:39 INFO - Running setup.py (path:/tmp/pip-mdT3OY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:39 INFO - Running setup.py (path:/tmp/pip-T8JxpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:40 INFO - Running setup.py (path:/tmp/pip-KhGA5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:40 INFO - Running setup.py (path:/tmp/pip-05m_K1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:40 INFO - Running setup.py (path:/tmp/pip-PJkT4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:40 INFO - Running setup.py (path:/tmp/pip-HflSbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:40 INFO - Running setup.py (path:/tmp/pip-ZGuY4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:40 INFO - Running setup.py (path:/tmp/pip-QVfkdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:28:40 INFO - Running setup.py install for manifestparser 02:28:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:28:40 INFO - Running setup.py install for mozcrash 02:28:40 INFO - Running setup.py install for mozdebug 02:28:40 INFO - Running setup.py install for mozdevice 02:28:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:28:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:28:40 INFO - Running setup.py install for mozfile 02:28:40 INFO - Running setup.py install for mozhttpd 02:28:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for mozinfo 02:28:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for mozInstall 02:28:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for mozleak 02:28:41 INFO - Running setup.py install for mozlog 02:28:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for moznetwork 02:28:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for mozprocess 02:28:41 INFO - Running setup.py install for mozprofile 02:28:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for mozrunner 02:28:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for mozscreenshot 02:28:42 INFO - Running setup.py install for moztest 02:28:42 INFO - Running setup.py install for mozversion 02:28:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:28:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:28:42 INFO - Cleaning up... 02:28:42 INFO - Return code: 0 02:28:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:42 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')]} 02:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:42 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')]} 02:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:42 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 0x7f51422a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', '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 02:28:42 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 02:28:42 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 02:28:42 INFO - Using env: {'DISPLAY': ':0', 02:28:42 INFO - 'HOME': '/home/cltbld', 02:28:42 INFO - 'LANG': 'en_US.UTF-8', 02:28:42 INFO - 'LANGUAGE': 'en_US:en', 02:28:42 INFO - 'LOGNAME': 'cltbld', 02:28:42 INFO - 'MAIL': '/var/mail/cltbld', 02:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:42 INFO - 'MOZ_NO_REMOTE': '1', 02:28:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:42 INFO - 'NO_EM_RESTART': '1', 02:28:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:42 INFO - 'PWD': '/builds/slave/test', 02:28:42 INFO - 'SHELL': '/bin/bash', 02:28:42 INFO - 'SHLVL': '1', 02:28:42 INFO - 'TERM': 'linux', 02:28:42 INFO - 'TMOUT': '86400', 02:28:42 INFO - 'USER': 'cltbld', 02:28:42 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:42 INFO - '_': '/tools/buildbot/bin/python'} 02:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:42 INFO - Running setup.py (path:/tmp/pip-D0U2RE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:42 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)) 02:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:42 INFO - Running setup.py (path:/tmp/pip-EgOiOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:42 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)) 02:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:42 INFO - Running setup.py (path:/tmp/pip-ZnH4yX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:42 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)) 02:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:42 INFO - Running setup.py (path:/tmp/pip-LfGTuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:42 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)) 02:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:42 INFO - Running setup.py (path:/tmp/pip-VMJW5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:42 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)) 02:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:42 INFO - Running setup.py (path:/tmp/pip-SUoQLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:42 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)) 02:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:42 INFO - Running setup.py (path:/tmp/pip-8nAdqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:42 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)) 02:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:42 INFO - Running setup.py (path:/tmp/pip-yl8qos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:43 INFO - Running setup.py (path:/tmp/pip-O7f4Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:43 INFO - Running setup.py (path:/tmp/pip-jEleGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:43 INFO - Running setup.py (path:/tmp/pip-k9c1A2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:43 INFO - Running setup.py (path:/tmp/pip-UnPpxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:43 INFO - Running setup.py (path:/tmp/pip-esKxQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:43 INFO - Running setup.py (path:/tmp/pip-86l_MY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:43 INFO - Running setup.py (path:/tmp/pip-1ZYrkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:43 INFO - Running setup.py (path:/tmp/pip-h3rIkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:43 INFO - Running setup.py (path:/tmp/pip-hr3yJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:43 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)) 02:28:43 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)) 02:28:43 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)) 02:28:43 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)) 02:28:43 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)) 02:28:43 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)) 02:28:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:45 INFO - Downloading blessings-1.6.tar.gz 02:28:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:28:45 INFO - Installing collected packages: blessings 02:28:45 INFO - Running setup.py install for blessings 02:28:45 INFO - Successfully installed blessings 02:28:45 INFO - Cleaning up... 02:28:45 INFO - Return code: 0 02:28:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:28:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:46 INFO - Reading from file tmpfile_stdout 02:28:46 INFO - Current package versions: 02:28:46 INFO - argparse == 1.2.1 02:28:46 INFO - blessings == 1.6 02:28:46 INFO - blobuploader == 1.2.4 02:28:46 INFO - docopt == 0.6.1 02:28:46 INFO - functools32 == 3.2.3-2 02:28:46 INFO - jsonschema == 2.5.1 02:28:46 INFO - manifestparser == 1.1 02:28:46 INFO - mozInstall == 1.12 02:28:46 INFO - mozcrash == 0.17 02:28:46 INFO - mozdebug == 0.1 02:28:46 INFO - mozdevice == 0.48 02:28:46 INFO - mozfile == 1.2 02:28:46 INFO - mozhttpd == 0.7 02:28:46 INFO - mozinfo == 0.9 02:28:46 INFO - mozleak == 0.1 02:28:46 INFO - mozlog == 3.4 02:28:46 INFO - moznetwork == 0.27 02:28:46 INFO - mozprocess == 0.23 02:28:46 INFO - mozprofile == 0.28 02:28:46 INFO - mozrunner == 6.12 02:28:46 INFO - mozscreenshot == 0.1 02:28:46 INFO - mozsystemmonitor == 0.3 02:28:46 INFO - moztest == 0.7 02:28:46 INFO - mozversion == 1.4 02:28:46 INFO - psutil == 3.1.1 02:28:46 INFO - requests == 1.2.3 02:28:46 INFO - wsgiref == 0.1.2 02:28:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:46 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 0x7f51422a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', '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 02:28:46 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 02:28:46 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 02:28:46 INFO - Using env: {'DISPLAY': ':0', 02:28:46 INFO - 'HOME': '/home/cltbld', 02:28:46 INFO - 'LANG': 'en_US.UTF-8', 02:28:46 INFO - 'LANGUAGE': 'en_US:en', 02:28:46 INFO - 'LOGNAME': 'cltbld', 02:28:46 INFO - 'MAIL': '/var/mail/cltbld', 02:28:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:46 INFO - 'MOZ_NO_REMOTE': '1', 02:28:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:46 INFO - 'NO_EM_RESTART': '1', 02:28:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:46 INFO - 'PWD': '/builds/slave/test', 02:28:46 INFO - 'SHELL': '/bin/bash', 02:28:46 INFO - 'SHLVL': '1', 02:28:46 INFO - 'TERM': 'linux', 02:28:46 INFO - 'TMOUT': '86400', 02:28:46 INFO - 'USER': 'cltbld', 02:28:46 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:28:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:46 INFO - '_': '/tools/buildbot/bin/python'} 02:28:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:46 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)) 02:28:46 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)) 02:28:46 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)) 02:28:46 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)) 02:28:46 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)) 02:28:46 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)) 02:28:46 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)) 02:28:46 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)) 02:28:46 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)) 02:28:46 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)) 02:28:46 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)) 02:28:46 INFO - Cleaning up... 02:28:46 INFO - Return code: 0 02:28:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:28:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:46 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 0x7f51422a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', '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 02:28:46 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 02:28:46 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 02:28:46 INFO - Using env: {'DISPLAY': ':0', 02:28:46 INFO - 'HOME': '/home/cltbld', 02:28:46 INFO - 'LANG': 'en_US.UTF-8', 02:28:46 INFO - 'LANGUAGE': 'en_US:en', 02:28:46 INFO - 'LOGNAME': 'cltbld', 02:28:46 INFO - 'MAIL': '/var/mail/cltbld', 02:28:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:46 INFO - 'MOZ_NO_REMOTE': '1', 02:28:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:46 INFO - 'NO_EM_RESTART': '1', 02:28:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:46 INFO - 'PWD': '/builds/slave/test', 02:28:46 INFO - 'SHELL': '/bin/bash', 02:28:46 INFO - 'SHLVL': '1', 02:28:46 INFO - 'TERM': 'linux', 02:28:46 INFO - 'TMOUT': '86400', 02:28:46 INFO - 'USER': 'cltbld', 02:28:46 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:28:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:46 INFO - '_': '/tools/buildbot/bin/python'} 02:28:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:28:46 INFO - Cleaning up... 02:28:46 INFO - Return code: 0 02:28:46 INFO - Running post-action listener: _resource_record_post_action 02:28:46 INFO - Running post-action listener: _start_resource_monitoring 02:28:46 INFO - Starting resource monitoring. 02:28:46 INFO - [mozharness: 2016-11-06 10:28:46.597066Z] Finished create-virtualenv step (success) 02:28:46 INFO - [mozharness: 2016-11-06 10:28:46.597615Z] Running install step. 02:28:46 INFO - Running pre-action listener: _resource_record_pre_action 02:28:46 INFO - Running main action method: install 02:28:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:46 INFO - Reading from file tmpfile_stdout 02:28:46 INFO - Detecting whether we're running mozinstall >=1.0... 02:28:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:28:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:28:46 INFO - Reading from file tmpfile_stdout 02:28:46 INFO - Output received: 02:28:46 INFO - Usage: mozinstall [options] installer 02:28:46 INFO - Options: 02:28:46 INFO - -h, --help show this help message and exit 02:28:46 INFO - -d DEST, --destination=DEST 02:28:46 INFO - Directory to install application into. [default: 02:28:46 INFO - "/builds/slave/test"] 02:28:46 INFO - --app=APP Application being installed. [default: firefox] 02:28:46 INFO - mkdir: /builds/slave/test/build/application 02:28:46 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'] 02:28:46 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 02:29:02 INFO - Reading from file tmpfile_stdout 02:29:02 INFO - Output received: 02:29:02 INFO - /builds/slave/test/build/application/firefox/firefox 02:29:02 INFO - Running post-action listener: _resource_record_post_action 02:29:02 INFO - [mozharness: 2016-11-06 10:29:02.834248Z] Finished install step (success) 02:29:02 INFO - [mozharness: 2016-11-06 10:29:02.834494Z] Running run-tests step. 02:29:02 INFO - Running pre-action listener: _resource_record_pre_action 02:29:02 INFO - Running main action method: run_tests 02:29:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:02 INFO - Python 2.7.3 02:29:02 INFO - Return code: 0 02:29:02 INFO - grabbing minidump binary from tooltool 02:29:02 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')]} 02:29:02 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 0x245cf60>, '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 0x245dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245e1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:02 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 02:29:02 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 02:29:02 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 02:29:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:02 INFO - Return code: 0 02:29:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:29:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:29:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:02 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/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 02:29:02 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/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 02:29:02 INFO - Using env: {'DISPLAY': ':0', 02:29:02 INFO - 'HOME': '/home/cltbld', 02:29:02 INFO - 'LANG': 'en_US.UTF-8', 02:29:02 INFO - 'LANGUAGE': 'en_US:en', 02:29:02 INFO - 'LOGNAME': 'cltbld', 02:29:02 INFO - 'MAIL': '/var/mail/cltbld', 02:29:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:02 INFO - 'MOZ_NO_REMOTE': '1', 02:29:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:02 INFO - 'NO_EM_RESTART': '1', 02:29:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:02 INFO - 'PWD': '/builds/slave/test', 02:29:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:02 INFO - 'SHELL': '/bin/bash', 02:29:02 INFO - 'SHLVL': '1', 02:29:02 INFO - 'TERM': 'linux', 02:29:02 INFO - 'TMOUT': '86400', 02:29:02 INFO - 'USER': 'cltbld', 02:29:02 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588', 02:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:02 INFO - '_': '/tools/buildbot/bin/python'} 02:29:02 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/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 02:29:03 INFO - mozversion application_buildid: 20161106004004 02:29:03 INFO - mozversion application_changeset: 070cf059bb70aa991d50b4d266987adf26685915 02:29:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:29:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:03 INFO - mozversion application_name: Firefox 02:29:03 INFO - mozversion application_remotingname: firefox-dev 02:29:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:03 INFO - mozversion application_vendor: Mozilla 02:29:03 INFO - mozversion application_version: 51.0a2 02:29:03 INFO - mozversion platform_buildid: 20161106004004 02:29:03 INFO - mozversion platform_changeset: 070cf059bb70aa991d50b4d266987adf26685915 02:29:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:03 INFO - mozversion platform_version: 51.0a2 02:29:03 INFO - using testdate: 1478428143 02:29:03 INFO - actual date: 1478428143 02:29:03 INFO - starting webserver on 'localhost:51682' 02:29:03 INFO - SUITE-START | Running 2 tests 02:29:03 INFO - TEST-START | dromaeo_css 02:29:03 INFO - operating with platform_type : linux_ 02:29:03 INFO - Initialising browser for dromaeo_css test... 02:29:03 INFO - TEST-INFO | started process 24808 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHCohH_/profile http://localhost:51682/getInfo.html) 02:29:07 INFO - PROCESS | 24808 | __metrics Screen width/height:1600/1200 02:29:07 INFO - PROCESS | 24808 | colorDepth:24 02:29:07 INFO - PROCESS | 24808 | Browser inner width/height: 1024/697 02:29:07 INFO - PROCESS | 24808 | __metrics 02:29:07 INFO - PROCESS | 24808 | 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" 02:29:07 INFO - PROCESS | 24808 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:29:08 INFO - PROCESS | 24808 | console.error: PushService: 02:29:08 INFO - PROCESS | 24808 | stateChangeProcessEnqueue: Error transitioning state 02:29:08 INFO - PROCESS | 24808 | UnknownError 02:29:08 INFO - TEST-INFO | 24808: exit 0 02:29:08 INFO - Browser initialized. 02:29:08 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:08 INFO - TEST-INFO | started process 24899 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHCohH_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:20 INFO - PROCESS | 24899 | RSS: Main: 145285120 02:29:20 INFO - PROCESS | 24899 | 02:30:11 INFO - PROCESS | 24899 | 1478428211636 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] 02:32:20 INFO - PROCESS | 24899 | Cycle 1(1): loaded http://localhost:51682/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51682/tests/dromaeo/cssquery-ext.html) 02:32:21 INFO - PROCESS | 24899 | RSS: Main: 248119296 02:32:21 INFO - PROCESS | 24899 | 02:35:24 INFO - PROCESS | 24899 | Cycle 1(1): loaded http://localhost:51682/tests/dromaeo/cssquery-ext.html (next: http://localhost:51682/tests/dromaeo/cssquery-jquery.html) 02:35:24 INFO - PROCESS | 24899 | RSS: Main: 229535744 02:35:24 INFO - PROCESS | 24899 | 02:38:26 INFO - PROCESS | 24899 | Cycle 1(1): loaded http://localhost:51682/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51682/tests/dromaeo/cssquery-mootools.html) 02:38:27 INFO - PROCESS | 24899 | RSS: Main: 231886848 02:38:27 INFO - PROCESS | 24899 | 02:41:29 INFO - PROCESS | 24899 | Cycle 1(1): loaded http://localhost:51682/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51682/tests/dromaeo/cssquery-prototype.html) 02:41:29 INFO - PROCESS | 24899 | RSS: Main: 221786112 02:41:29 INFO - PROCESS | 24899 | 02:44:32 INFO - PROCESS | 24899 | Cycle 1(1): loaded http://localhost:51682/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51682/tests/dromaeo/cssquery-yui.html) 02:44:33 INFO - PROCESS | 24899 | RSS: Main: 176877568 02:44:33 INFO - PROCESS | 24899 | 02:47:35 INFO - PROCESS | 24899 | Cycle 1(1): loaded http://localhost:51682/tests/dromaeo/cssquery-yui.html (next: http://localhost:51682/tests/dromaeo/cssquery-dojo.html) 02:47:35 INFO - PROCESS | 24899 | RSS: Main: 252309504 02:47:35 INFO - PROCESS | 24899 | 02:47:35 INFO - PROCESS | 24899 | __start_tp_report 02:47:35 INFO - PROCESS | 24899 | _x_x_mozilla_page_load 02:47:35 INFO - PROCESS | 24899 | _x_x_mozilla_page_load_details 02:47:35 INFO - PROCESS | 24899 | |i|pagename|runs| 02:47:35 INFO - PROCESS | 24899 | |0;dojo.html;3110.6733792951745;3149.8582563784626;3166.9683303166976;3189.4737368334195;3204;5508.201310809935;5574.86062848429;5599.608027438081;5623.381428042922;5634.774609015644;5472.644278121919;5507.779688812453;5516.586256030793;5531.474509921561;5545.667259964405;4980.352554167962;5042.596592272609;5069.467705890884;5073.365829271341;5144.279800827887;4987.326710894037;5071.898562028758;5128.179491281396;5131.281620573122;5133.871653208662;4973.726445045521;5007.849764507052;5036.219385995177;5048.343715317003;5079.949200507985;5113.846584602468;5157.277981082651;5164.6642968207325;5216.686998780047;5231.790728370861;5047.9747601262125;5111.182210846247;5171.5862730981435;5179.119549676564;5212.061828870802;5405.054115529767;5430.185472179165;5462;5483.067878460672;5542.75057622408;5830.4461076197695;5854.034084376115;5854.443827836349;5941.287045554518;5945.197398351211;5968.940310596882;5972.850678733023;5988.9101663475085;5991.880162396771;5992.370801065869;5426.778974730654;5449;5482.314710661168;5527.640703354269;5552.5280351169795;6079.088136779518;6117.357677443804;6123.663198524035;6124.173236613;6128.632282063001;5849.356570777211;5857.648541087463;5858.736356863951;5859.853503662443;5860.853478663067;5804.361520232771;5827.562932780059;5837.591368604242;5846.590738648254;5907.645541267451;5865.824025279248;5866.472017518444;5867.941320586825;5873.353931067579;5891.263592050994;5848.619839710405;5883.02930016551;5899.941000590025;5918.585699001114;5936.5547583931375;4515.864524064284;4527.09458108373;4538.160440318552;4552.817887284472;4580.908381832344;4029.516458024997;4047.473828402348;4048.8582899598373;4074.714769966074;4083.632473077437;5623.0721930880945;5625.690587017753;5629.408912064257;5634.887302253932;5663.405342439067;5776.740046697909;5824.417558244142;5907.172995780594;5919;5945.970270148708;5616.775328986876;5621.100623900156;5621.943780562224;5662.150677398423;5662.7734890603915;5146.819861304836;5152.046871328816;5155.097857874857;5157.226416037624;5158.509941555546;6005.249343832021;6022.247219097613;6064.151018857364;6149;6164.7842325518395;5862.970685146547;5960.284765828128;5991.370906054975;5994.880102398015;5997.430244126801;6152.78465253714;6207.565470417027;6218.409251121137;6229.159063526366;6229.283632382334;5735.168400581976;5746.224259725051;5805.65166090019;5875.735591898289;5881.32364778056;5870.913880932041;5880.852978675396;5915.201447804663;5961.552883533665;6015.939840601713;5822.73797679097;5826.621269617462;5828.6211396259105;5831.096180092092;5831.970840145772;5654.434556544313;5802.82591522255;5809.9419005809405;5811.593188476766;5812.680302583399;5597.80407685729;5759.942400576108;5786.3056433228285;5817.76728930838;5907.940920590739;5901.085331773582;5942.881142377215;5945.64326140433;5946.375630558729;5956.017257152695;5506.2291279221745;5534.640248383842;5792.160136780228;5802.854928626818;5805.245318108618 02:47:35 INFO - PROCESS | 24899 | |1;ext.html;579.6435192356699;624.0825985800872;627.9780207692731;645.9450946669534;658.9308122647125;36311.818440907795;47559.76220118899;48549;48566.27150592744;48710;49663;49897.25154122691;50155.49844501563;50180.24729629058;50206.74896625521;8619.61211745473;9493.667721629745;9642.325037247396;9659.855102173473;9663.613455461807;8772.736817895473;11438.5424583017;11845.111616628745;11854.229475084092;11859.110566707488;11630.418479076056;11681.357525336103;11696.122790790681;11709.414529273545;11735.29588224705;11486;11557.422128893522;11565.479553420117;11569.710757231136;11577.131715121357;11257.774844503065;11261.887381126166;11298.548058077668;11309.151813614011;11357.886421135765;45889.54110458886;46242.30636540455;46270.76864615689;46318.07363852738;46380;4026.396040593905;4091.7954102295066;4233.936490952638;4492.348609451611;4708.321290103946;32660.836695816608;34699.306013879584;35346.11634709127;35508.467372989166;37093;4647.86056418308;4690.812367505296;4739.194336962724;4807.567318941277;4870.07468580972;386175.0691246554;389455;389823;392119;394332;65933.67033164899;66305.33694663088;66347.66826165839;66437.6678116616;66559.33440665629;41614.791926040176;42372.364414533804;42639.7868010658;45257.09485810265;45312.77343613261;19503.707444388347;19698.606027879363;19712.70430943537;19712.802871971384;19717;13948.302584870717;14306;14988.925055374653;15055.322510487054;15225.162616056223;20631.69052464214;21569.78430215709;21588.244411445838;21710.240141594968;21851.453713656967;7625.275598818103;7721.53670779755;7857.253560911704;7860.135385107634;7869.803254918673;1016.9389836609827;1018.9184865210765;1069.6042464288107;1111.5220455204178;1135.3755434510986;35022.649773502446;35526.822365888525;35691.28617427637;36067.86846236094;38067.42898856464;1136.085451211766;1144.7653231087775;1149.2242736153064;1154.276768817691;1159.1885680024018;18072.09639518019;18538.536536586693;18550.814491854908;18570.81429185718;18620;601892;604640;605404;606595;609677;64756;65483;65515.67242163759;65528.672356637915;65564.34435655677;1295.5077070713069;1339.604816579092;1390.4438224709995;1411.943522259098;1472.749632562467;32769;35154;35604.261552537646;35687.28625427529;35935.2268011855;56678.7166064167;58171.4182858166;58242;58260.41739582719;58275.417245827;476104;476938;478260;478487;479740;31947.520787187746;32009.67990320067;32624.673753262163;32965.17587060342;33257.66742332546;52463.21305180349;53163.202551962524;53178;53227.201591976926;53374.73312633412;44937.32594011158;46258.537414625425;46692.53307466882;47119.05761884811;47849;44579;44868.55131448644;46090;46503.44231067188;46515.53484465247;45470.545294547956;45742.542574575156;46593.76703116463;46674.29988550107;46707;37583.43624845684;39964.800175998935;39997.60002400055;40245.79877100596;40470.392944105275;30549;30662.233444164074;30791.84604076965;31034.68965310318;31066.378672425973 02:47:35 INFO - PROCESS | 24899 | |2;jquery.html;398.77867783380213;409.7070594524914;419.2767476103721;431.8272690923631;443.1491536250399;14846.40614375425;16500.669986600275;20428;21193.894030529864;21893.452663683416;20657.483562910933;20761.584768304623;20944.790552094473;21121.260755873547;21476.4630884228;5938.168656388109;5992.820215393545;6211.968940155293;6249.625022498664;6340.904886426707;6199.156522955774;6252.249730032402;6282.371762823726;6284.7486100555925;6778.694958726869;6844.1444819397575;6856.2800905904905;6858.965705171468;6859.485538584601;6862.107925969642;6237.157983672214;6301.873962520724;6302.023186406114;6377.202849643794;6840.110785597865;5939.46544810969;6141.109539116803;6145.231846019247;6146.385361463863;6146.385361463863;15157.772633410508;15498.457553985556;15568.455104071416;15575.22123893812;15581.766273505906;2450.473148273112;2473.6660550825586;2558.398776287571;2571.9485610287875;2969.6436427628823;10521.947390263;11379.374134422604;11406.54373825046;15366.154861482613;15764.527064188092;2036.1346427768287;2097.9895100524554;2115.153938424627;2116.544942837297;2130.488682716137;90874.54562727144;93702;95246;95525;95598;11635.650930472098;11826;12031.398430078461;12140.150189486827;12154.392280386122;17327.740083898752;17428.738568921475;17452.95282283067;17469.47591572255;17610.2075406607;9094.954525227464;9148.954255228682;9226.169644732006;9270.814583708423;9477.00491448388;9030.148819491988;9070.501122438329;9103.90896091044;9246.445213287225;9303.162715355651;5099.286099946011;5182.948170518246;5192.506711862367;5229.973850130725;5319.069162896478;2032.217596225464;2038.4824741444304;2123.4730670502363;2149.3122200895564;2155.838312126597;920.6087412849512;996.1084829077935;1000.5397517142051;1090.5692251560747;1116.0290547223967;19955.6298374419;20423.897880510503;21118.155273788878;21159.78840211609;21233.469163271042;1010.2675560218929;1063.5161001744189;1074.5164675895878;1074.7420619051372;1103.2773533335678;10804.1896857736;11727.237729547553;11761.176717629683;11828.23116497425;11889.87046230624;511522;531293;536821;538305;538491;74051;80217.59891200507;86926;102164.48917755364;103914.48042759889;881.6605606841413;909.0863681999548;920.5397301349325;932.38462614674;943.6649989253716;18160.091995400442;18738;18883.339083132025;18919.905400472908;19123.521911952314;45336.31995520004;45368;46195.3070703933;46237.76881115573;46734;58489.4151058484;62377;63297;64417.35582644113;64515.67742161259;9086.22767064807;9332.39339442934;9462.621495140118;9478.668246611336;9757.756056098655;10527.684039495063;14034.087784293988;14495.275236238356;14678.04592701471;15088.924555377153;12190.512379505075;12453.315067671365;12480;12672.493100276255;12684.112112152061;11666.183367164216;11959.94020029929;12416.441260143134;12760.808587871375;12850.228986260854;12196.451159697657;12517.687057823627;12534.874651253736;12613.306268154973;12773.978081753252;9300.906990930003;9403.4828084456;9538.508875974614;9734.269929755439;9764.267679923893;9390.81218375615;12084.274943503418;12368;12402.503899843905;12807.871921280668 02:47:35 INFO - PROCESS | 24899 | |3;mootools.html;1532.4559781277649;1575.2596279748518;1777.3068503283714;1824.7901491328485;1903.4384856467332;8531.744047678563;8878.334124940622;9180.816383672323;9935.05616966388;9942.850857237147;9063.818723625523;9481.620735170602;9492.288078394113;9771.951140244306;9775.853362199572;2560.615907613859;2593.623924530951;2595.4160313929406;2598.909038183664;2600.362911086777;2192.5614877024577;2262.6945362376114;2434.720007199177;2560.4495033567778;2597.688277406714;2504.8998040078377;2537.8223524353302;2568.858712770797;2571.100114959768;2572.356910772307;2535.619657051448;2597.779188768957;2604.804639652024;2606.843589384624;2608.100205429124;2512.798976081909;2525.267672375009;2533.5946248600135;2534.3284029732135;2536.048981631888;9380.155785979226;9532.809343813155;9544.379615324982;9547.665831695856;9566.186874115709;5555.166724991243;5689.886202275973;5694.487496125369;5695.572832037614;5699.202111704365;3510.7366947478786;3576.8742683442733;3598.8560457581666;3609.5848977367677;3679.9448008279896;1176.1884299833089;1198.3169593331804;1201.85577730672;1209.2079687804503;1237.2638280223252;13316.334183290797;13511.121777084458;13693.657658558515;13716.245606491739;13755.449782008707;4934.506549345037;4972.204447288489;5163.122269214243;5166.844994650167;5189.532942035235;11462.541498339973;11475.426228688531;11509.309441433541;11511.366874821964;11513.251638643462;5785.710714464344;5788.739506722208;5789.421057894261;5796.565257605696;5797.304323481209;5068.670536415121;5261.894762104736;5316.840494785162;5326.707031113326;5327.760250788724;4208.810603522848;4254.085371645049;4320.481542214954;4356.433663623772;4386;1285.1325355385077;1319.920804751718;1330.4611632288938;1365.7094046126513;1421.4243231491262;1019.4240254256322;1040.9011143941314;1049.6955882794057;1051.9737006574896;1060.7613287010515;6376.39424254695;6438.935610643927;6515.12045873801;6515.7067931943175;6660.134182556237;950.1401231885155;970.1220395542045;1010.2776514791959;1024.99487502562;1106.546316010448;4260.6378457830815;4389.31965545341;4391.165678521071;4395.604395604411;4427.5351088135285;11491.712707182387;11875.228110172811;11882.584109556297;11892.821607675893;11911.702207444883;11193.608223712294;11756.882431175578;11778.293302401884;11805.704857378634;11823.231489953125;8623.611937462829;8997.415168014059;9020.954895225483;9028.548572571344;9050.547472626342;6506.707198175999;6558.934410655832;6620.3710647488415;6673.799786006428;6840.213375461686;10640.574377024832;10980.890191097988;11088.390138542458;11128.387938663127;11246.550137994389;11044.116470682487;11247.88752112501;11267.549298027987;11270.605528806453;11385.373804440504;8625.353098517511;8741.213290803858;8765.211130997986;8875.689350872688;8880.023197448405;9369;9417.811643766949;9450.480223587769;9457.668981585612;9459.621615135318;8146.266835984789;8305.626246818787;8323.334133269445;8501.532415717194;8559.186877246651;7878.133405325524;7913.881291780743;8064.233897779936;8098.473599216032;8139.10469848328;7521.811954701177;7577.924220757722;7737.226277372218;7814.882776758467;7956.124826269222;5535.501804837584;5657.2928383952;5663.631863928831;5706.9714651426475;5807.186993820953;5540.113581826889;5542.808296216333;5572.888542229214;5681.806820567727;5744.425557444222 02:47:35 INFO - PROCESS | 24899 | |4;prototype.html;337.9357921994821;344.27701826165054;349.7237182625725;350.9298140371926;358.0743777335587;5510.6969116698565;5526.364468086172;5537.889242215158;5544.16837474379;5555.250041244431;5219.173289348726;5290.497402746742;5354.22363757255;5405.891882162355;5489.039418101836;4335.739855608658;4348.608625223729;4351.26028575143;4358.4987726411555;4359.825606975717;4336.804843782037;4364.105358401536;4369.431973843396;4370.416258667894;4383.232934236512;4134.4831896013;4261.701680882339;4290.485141782975;4312.28847240205;4335.154644844257;4286.614204721559;4294.506131794837;4297.570242975708;4304.438858878962;4308.116836048634;3829.8468061277517;3833;3836.175222327185;3838.5201849768778;3839.827207775657;5152.793888244466;5233.11607821184;5286.788528458896;5392.1911713242935;5407.945920540823;4893.363862697826;4905;4913.8771530711665;4923.950760492385;4926.428339453903;5675.772969081232;5688.800891968761;5720.599558030939;5737.598368114233;5754.136879468113;3938.468306778577;4141.7100802943805;4161.75029498228;4198.790060496963;4203.453551038376;5922.496587790043;5940.257637688272;5958.612690175168;5958.910616340759;5997.340292567814;4294.76378799169;4296.5273819879785;4296.570342965679;4302.118065796504;4306.267934451151;4172.979135104305;4256.61690447855;4332.241857674894;4346.847860324873;4359.542248063907;4884.4871288514905;4895.67816689499;4903.730294833818;4904.362432883702;4906.852794416173;5729.312482502043;5736.651886806597;5768.567357448208;5774.307083150049;5774.682392468455;1426.7217892511069;1452.6586252230718;1480.6230205908394;1491.7091167222288;1503.7218114648824;941.9497260554432;952.9237660987243;957.5834511987168;959.1990687775645;962.4273557233435;4939.87650308738;4946.3817022872145;4965;4969.527894849984;4986.750662466862;5141.820036298712;5157.974210128926;5176.197689358156;5189.197833170121;5191.454897235812;4875.219964805614;4894.828680996148;4933.827316043993;4935.777889994959;5231.267622532849;3113.2839446926882;3153.369326134782;3154.9369012620077;3162.2094476380903;3166.920826979344;6159.3224745277985;6172.753089876354;6179.907301390393;6199.535034872402;6210.627362358273;5909.763609455659;5984.222051133412;6005;6069.302030266487;6069.449372117361;6098.603590766586;6144.262688477412;6156.230471191101;6158.938410615837;6160.322364539987;5663.545237405097;5918.940810592011;5939.3963629818845;5945.940540594539;5949.405059494016;5840.561957853093;5853.824385268449;5882.823515294548;5899.292084949662;5915.526757859447;6152.692365381624;6156.815295541141;6207.0137555981655;6209.627422354673;6223.222097237845;4085.7344272622186;4086.0806318578084;4262.595053469915;4289.5495972923645;4315.4605674290715;5769.769209231584;5800.694843660142;5959.463648271503;5968.432998865101;5970.58205925581;5818.883622327615;5821.854453638523;5824.922389357982;5845.18167456548;5855.414458554111;5675.432456754292;5740.598158128891;5765.827025189251;5786.5081468074;5815.447532484407;5726.9713651431475;5733.455321744427;5736.799212027726;5737.454941780524;5755.8561035974435;5614.6631202128;5841.357450680507;5844.824655260349;5876.588638795243;5878.823635290948;5613.298337707786;5697.259356283656;5716.428357164416;5725.599208055396;5729.197912292366 02:47:35 INFO - PROCESS | 24899 | |5;yui.html;827.3587969323773;831.1065604475187;870.3689824876965;885.9335549833764;888.5779254853941;14007.719845603082;15017.32422041008;15053.62365940852;15077.246137693126;15083.924580377097;13350.198988060723;13456.596302110927;13471.865281347184;13491.055626106176;13524.729505409887;714.4748609771814;729.2998721227601;732.3847967707125;734.0347443112317;825.154216927651;850.336737344872;861.67687117331;868.2402897464718;868.917452841979;869.0874581689233;909.3316412436853;911.4258017448998;912.0970239462918;912.5756523216703;912.95891684874;740.4631642059518;904;905.7509184974152;906.4697152165991;907.918287354138;557.4898967444783;647.1748520636179;659.2221179008768;666.0375757031044;666.0542030316913;14142.292885355793;14195.858041419557;14197.716045679028;14202.360893759806;14209.502667406694;13093.869061309455;13146.60560183196;13202.603921882359;13764.862351376458;13895.58313250604;12569.748605027848;12740.299283539402;12793.61619151427;12926.87073129266;13065.869341306656;4278.978605106986;4325.199838029975;4385.254506733831;4389.209942210401;4424.677044551636;88144;88234;90650.54674726583;92084.07915920822;93828.53085734596;10942.124630029579;11167.944160279147;11176.161787865942;11377.203595748219;11436.599719009795;21943.561128777335;21975.23086691958;21975.780242197692;22002;22012.229571964905;8158;8207.55421656751;8253;8327.20891515305;8354;4950.653454258239;5010.69935803853;5078.5937125029905;5083.898322033612;5142.100132476801;3610.4042832932796;3686.078480379905;3701.3152566774693;3714.3499887519574;3726.888193354204;600.2976517474565;606.8452544601187;610.2219669920797;616.9691515424211;637.5282291104642;626.3673689573473;669.9147381242321;697.7941507255372;741.0699572037059;755.2824816424419;8600.354973377021;8702.47785132894;8847.432398908459;9197.574375971735;9201.539923003824;676.2020815437733;715.3132992327425;724.9166345870292;724.9347558719741;743.8140464883779;4221.092465120014;4614.523352527159;4627.930581041287;4650.837220697259;4762.238041913277;83844.16155838486;126435;131260;132557;132843;63023;63982.68008659927;65488.67255663691;93796;111929.44035279771;100243;101227.49386253169;101652.49173754232;102664.48667756614;102743.4862825681;8392.496450213006;8600.483970961512;8733.956330218309;8785.560721963748;8786.84412236075;47523.524764751906;56725.43274567201;56871.71564142319;57716.4228357711;57962.4203757957;51101.488985109674;52615.94768104693;64884.026739598;76487.61756191183;78606;12577.182483138567;12679.746405072032;12685.365731713562;13216.273104979318;13350.599482015556;14548.781768273273;14759.778603321174;14790.778138328149;14914.552563423114;15186.772198416811;15423.460178893685;15456.613584660474;16484.917575412048;16520.66958660844;17118.572035699206;14961.925190373979;15886.52340429789;15937.442189523312;16510.587235319214;17062.146892655564;13834.169949803045;14192;14808.925955370154;15478.767818482956;16397.18014099314;10819.783604328028;10920.563177472812;11356.943215284202;11541.480633371686;11816.40917954116;10584.841227381441;11347.659570212907;11745.354005529778;11968.446010766656;12046.939765301116 02:47:35 INFO - PROCESS | 24899 | __end_tp_report 02:47:35 INFO - PROCESS | 24899 | __start_cc_report 02:47:35 INFO - PROCESS | 24899 | _x_x_mozilla_cycle_collect,937 02:47:35 INFO - PROCESS | 24899 | __end_cc_report 02:47:35 INFO - PROCESS | 24899 | __startTimestamp1478429255916__endTimestamp 02:47:35 INFO - PROCESS | 24899 | 02:47:35 INFO - PROCESS | 24899 | ------- Summary: start ------- 02:47:35 INFO - PROCESS | 24899 | Number of tests: 6 02:47:35 INFO - PROCESS | 24899 | 02:47:35 INFO - PROCESS | 24899 | [#0] dojo.html Cycles:165 Average:5517.36 Median:5776.74 stddev:623.80 (10.8%) stddev-sans-first:596.45 02:47:35 INFO - PROCESS | 24899 | Values: 3110.7 3149.9 3167.0 3189.5 3204.0 5508.2 5574.9 5599.6 5623.4 5634.8 5472.6 5507.8 5516.6 5531.5 5545.7 4980.4 5042.6 5069.5 5073.4 5144.3 4987.3 5071.9 5128.2 5131.3 5133.9 4973.7 5007.8 5036.2 5048.3 5079.9 5113.8 5157.3 5164.7 5216.7 5231.8 5048.0 5111.2 5171.6 5179.1 5212.1 5405.1 5430.2 5462.0 5483.1 5542.8 5830.4 5854.0 5854.4 5941.3 5945.2 5968.9 5972.9 5988.9 5991.9 5992.4 5426.8 5449.0 5482.3 5527.6 5552.5 6079.1 6117.4 6123.7 6124.2 6128.6 5849.4 5857.6 5858.7 5859.9 5860.9 5804.4 5827.6 5837.6 5846.6 5907.6 5865.8 5866.5 5867.9 5873.4 5891.3 5848.6 5883.0 5899.9 5918.6 5936.6 4515.9 4527.1 4538.2 4552.8 4580.9 4029.5 4047.5 4048.9 4074.7 4083.6 5623.1 5625.7 5629.4 5634.9 5663.4 5776.7 5824.4 5907.2 5919.0 5946.0 5616.8 5621.1 5621.9 5662.2 5662.8 5146.8 5152.0 5155.1 5157.2 5158.5 6005.2 6022.2 6064.2 6149.0 6164.8 5863.0 5960.3 5991.4 5994.9 5997.4 6152.8 6207.6 6218.4 6229.2 6229.3 5735.2 5746.2 5805.7 5875.7 5881.3 5870.9 5880.9 5915.2 5961.6 6015.9 5822.7 5826.6 5828.6 5831.1 5832.0 5654.4 5802.8 5809.9 5811.6 5812.7 5597.8 5759.9 5786.3 5817.8 5907.9 5901.1 5942.9 5945.6 5946.4 5956.0 5506.2 5534.6 5792.2 5802.9 5805.2 02:47:35 INFO - PROCESS | 24899 | 02:47:35 INFO - PROCESS | 24899 | [#1] ext.html Cycles:180 Average:66679.49 Median:22281.09 stddev:132332.14 (593.9%) stddev-sans-first:132610.31 02:47:35 INFO - PROCESS | 24899 | Values: 579.6 624.1 628.0 645.9 658.9 36311.8 47559.8 48549.0 48566.3 48710.0 49663.0 49897.3 50155.5 50180.2 50206.7 8619.6 9493.7 9642.3 9659.9 9663.6 8772.7 11438.5 11845.1 11854.2 11859.1 11630.4 11681.4 11696.1 11709.4 11735.3 11486.0 11557.4 11565.5 11569.7 11577.1 11257.8 11261.9 11298.5 11309.2 11357.9 45889.5 46242.3 46270.8 46318.1 46380.0 4026.4 4091.8 4233.9 4492.3 4708.3 32660.8 34699.3 35346.1 35508.5 37093.0 4647.9 4690.8 4739.2 4807.6 4870.1 386175.1 389455.0 389823.0 392119.0 394332.0 65933.7 66305.3 66347.7 66437.7 66559.3 41614.8 42372.4 42639.8 45257.1 45312.8 19503.7 19698.6 19712.7 19712.8 19717.0 13948.3 14306.0 14988.9 15055.3 15225.2 20631.7 21569.8 21588.2 21710.2 21851.5 7625.3 7721.5 7857.3 7860.1 7869.8 1016.9 1018.9 1069.6 1111.5 1135.4 35022.6 35526.8 35691.3 36067.9 38067.4 1136.1 1144.8 1149.2 1154.3 1159.2 18072.1 18538.5 18550.8 18570.8 18620.0 601892.0 604640.0 605404.0 606595.0 609677.0 64756.0 65483.0 65515.7 65528.7 65564.3 1295.5 1339.6 1390.4 1411.9 1472.7 32769.0 35154.0 35604.3 35687.3 35935.2 56678.7 58171.4 58242.0 58260.4 58275.4 476104.0 476938.0 478260.0 478487.0 479740.0 31947.5 32009.7 32624.7 32965.2 33257.7 52463.2 53163.2 53178.0 53227.2 53374.7 44937.3 46258.5 46692.5 47119.1 47849.0 44579.0 44868.6 46090.0 46503.4 46515.5 45470.5 45742.5 46593.8 46674.3 46707.0 37583.4 39964.8 39997.6 40245.8 40470.4 30549.0 30662.2 30791.8 31034.7 31066.4 02:47:35 INFO - PROCESS | 24899 | 02:47:35 INFO - PROCESS | 24899 | [#2] jquery.html Cycles:180 Average:31111.77 Median:2516.03 stddev:87575.03 (3480.7%) stddev-sans-first:87790.34 02:47:35 INFO - PROCESS | 24899 | Values: 398.8 409.7 419.3 431.8 443.1 14846.4 16500.7 20428.0 21193.9 21893.5 20657.5 20761.6 20944.8 21121.3 21476.5 5938.2 5992.8 6212.0 6249.6 6340.9 6199.2 6252.2 6282.4 6284.7 6778.7 6844.1 6856.3 6859.0 6859.5 6862.1 6237.2 6301.9 6302.0 6377.2 6840.1 5939.5 6141.1 6145.2 6146.4 6146.4 15157.8 15498.5 15568.5 15575.2 15581.8 2450.5 2473.7 2558.4 2571.9 2969.6 10521.9 11379.4 11406.5 15366.2 15764.5 2036.1 2098.0 2115.2 2116.5 2130.5 90874.5 93702.0 95246.0 95525.0 95598.0 11635.7 11826.0 12031.4 12140.2 12154.4 17327.7 17428.7 17453.0 17469.5 17610.2 9095.0 9149.0 9226.2 9270.8 9477.0 9030.1 9070.5 9103.9 9246.4 9303.2 5099.3 5182.9 5192.5 5230.0 5319.1 2032.2 2038.5 2123.5 2149.3 2155.8 920.6 996.1 1000.5 1090.6 1116.0 19955.6 20423.9 21118.2 21159.8 21233.5 1010.3 1063.5 1074.5 1074.7 1103.3 10804.2 11727.2 11761.2 11828.2 11889.9 511522.0 531293.0 536821.0 538305.0 538491.0 74051.0 80217.6 86926.0 102164.5 103914.5 881.7 909.1 920.5 932.4 943.7 18160.1 18738.0 18883.3 18919.9 19123.5 45336.3 45368.0 46195.3 46237.8 46734.0 58489.4 62377.0 63297.0 64417.4 64515.7 9086.2 9332.4 9462.6 9478.7 9757.8 10527.7 14034.1 14495.3 14678.0 15088.9 12190.5 12453.3 12480.0 12672.5 12684.1 11666.2 11959.9 12416.4 12760.8 12850.2 12196.5 12517.7 12534.9 12613.3 12774.0 9300.9 9403.5 9538.5 9734.3 9764.3 9390.8 12084.3 12368.0 12402.5 12807.9 02:47:35 INFO - PROCESS | 24899 | 02:47:35 INFO - PROCESS | 24899 | [#3] mootools.html Cycles:180 Average:6331.33 Median:4393.39 stddev:3607.70 (82.1%) stddev-sans-first:3599.79 02:47:35 INFO - PROCESS | 24899 | Values: 1532.5 1575.3 1777.3 1824.8 1903.4 8531.7 8878.3 9180.8 9935.1 9942.9 9063.8 9481.6 9492.3 9772.0 9775.9 2560.6 2593.6 2595.4 2598.9 2600.4 2192.6 2262.7 2434.7 2560.4 2597.7 2504.9 2537.8 2568.9 2571.1 2572.4 2535.6 2597.8 2604.8 2606.8 2608.1 2512.8 2525.3 2533.6 2534.3 2536.0 9380.2 9532.8 9544.4 9547.7 9566.2 5555.2 5689.9 5694.5 5695.6 5699.2 3510.7 3576.9 3598.9 3609.6 3679.9 1176.2 1198.3 1201.9 1209.2 1237.3 13316.3 13511.1 13693.7 13716.2 13755.4 4934.5 4972.2 5163.1 5166.8 5189.5 11462.5 11475.4 11509.3 11511.4 11513.3 5785.7 5788.7 5789.4 5796.6 5797.3 5068.7 5261.9 5316.8 5326.7 5327.8 4208.8 4254.1 4320.5 4356.4 4386.0 1285.1 1319.9 1330.5 1365.7 1421.4 1019.4 1040.9 1049.7 1052.0 1060.8 6376.4 6438.9 6515.1 6515.7 6660.1 950.1 970.1 1010.3 1025.0 1106.5 4260.6 4389.3 4391.2 4395.6 4427.5 11491.7 11875.2 11882.6 11892.8 11911.7 11193.6 11756.9 11778.3 11805.7 11823.2 8623.6 8997.4 9021.0 9028.5 9050.5 6506.7 6558.9 6620.4 6673.8 6840.2 10640.6 10980.9 11088.4 11128.4 11246.6 11044.1 11247.9 11267.5 11270.6 11385.4 8625.4 8741.2 8765.2 8875.7 8880.0 9369.0 9417.8 9450.5 9457.7 9459.6 8146.3 8305.6 8323.3 8501.5 8559.2 7878.1 7913.9 8064.2 8098.5 8139.1 7521.8 7577.9 7737.2 7814.9 7956.1 5535.5 5657.3 5663.6 5707.0 5807.2 5540.1 5542.8 5572.9 5681.8 5744.4 02:47:35 INFO - PROCESS | 24899 | 02:47:35 INFO - PROCESS | 24899 | [#4] prototype.html Cycles:180 Average:4819.84 Median:5373.21 stddev:1412.21 (26.3%) stddev-sans-first:1375.53 02:47:35 INFO - PROCESS | 24899 | Values: 337.9 344.3 349.7 350.9 358.1 5510.7 5526.4 5537.9 5544.2 5555.3 5219.2 5290.5 5354.2 5405.9 5489.0 4335.7 4348.6 4351.3 4358.5 4359.8 4336.8 4364.1 4369.4 4370.4 4383.2 4134.5 4261.7 4290.5 4312.3 4335.2 4286.6 4294.5 4297.6 4304.4 4308.1 3829.8 3833.0 3836.2 3838.5 3839.8 5152.8 5233.1 5286.8 5392.2 5407.9 4893.4 4905.0 4913.9 4924.0 4926.4 5675.8 5688.8 5720.6 5737.6 5754.1 3938.5 4141.7 4161.8 4198.8 4203.5 5922.5 5940.3 5958.6 5958.9 5997.3 4294.8 4296.5 4296.6 4302.1 4306.3 4173.0 4256.6 4332.2 4346.8 4359.5 4884.5 4895.7 4903.7 4904.4 4906.9 5729.3 5736.7 5768.6 5774.3 5774.7 1426.7 1452.7 1480.6 1491.7 1503.7 941.9 952.9 957.6 959.2 962.4 4939.9 4946.4 4965.0 4969.5 4986.8 5141.8 5158.0 5176.2 5189.2 5191.5 4875.2 4894.8 4933.8 4935.8 5231.3 3113.3 3153.4 3154.9 3162.2 3166.9 6159.3 6172.8 6179.9 6199.5 6210.6 5909.8 5984.2 6005.0 6069.3 6069.4 6098.6 6144.3 6156.2 6158.9 6160.3 5663.5 5918.9 5939.4 5945.9 5949.4 5840.6 5853.8 5882.8 5899.3 5915.5 6152.7 6156.8 6207.0 6209.6 6223.2 4085.7 4086.1 4262.6 4289.5 4315.5 5769.8 5800.7 5959.5 5968.4 5970.6 5818.9 5821.9 5824.9 5845.2 5855.4 5675.4 5740.6 5765.8 5786.5 5815.4 5727.0 5733.5 5736.8 5737.5 5755.9 5614.7 5841.4 5844.8 5876.6 5878.8 5613.3 5697.3 5716.4 5725.6 5729.2 02:47:35 INFO - PROCESS | 24899 | 02:47:35 INFO - PROCESS | 24899 | [#5] yui.html Cycles:180 Average:21210.59 Median:4519.60 stddev:31200.71 (690.3%) stddev-sans-first:31250.70 02:47:35 INFO - PROCESS | 24899 | Values: 827.4 831.1 870.4 885.9 888.6 14007.7 15017.3 15053.6 15077.2 15083.9 13350.2 13456.6 13471.9 13491.1 13524.7 714.5 729.3 732.4 734.0 825.2 850.3 861.7 868.2 868.9 869.1 909.3 911.4 912.1 912.6 913.0 740.5 904.0 905.8 906.5 907.9 557.5 647.2 659.2 666.0 666.1 14142.3 14195.9 14197.7 14202.4 14209.5 13093.9 13146.6 13202.6 13764.9 13895.6 12569.7 12740.3 12793.6 12926.9 13065.9 4279.0 4325.2 4385.3 4389.2 4424.7 88144.0 88234.0 90650.5 92084.1 93828.5 10942.1 11167.9 11176.2 11377.2 11436.6 21943.6 21975.2 21975.8 22002.0 22012.2 8158.0 8207.6 8253.0 8327.2 8354.0 4950.7 5010.7 5078.6 5083.9 5142.1 3610.4 3686.1 3701.3 3714.3 3726.9 600.3 606.8 610.2 617.0 637.5 626.4 669.9 697.8 741.1 755.3 8600.4 8702.5 8847.4 9197.6 9201.5 676.2 715.3 724.9 724.9 743.8 4221.1 4614.5 4627.9 4650.8 4762.2 83844.2 126435.0 131260.0 132557.0 132843.0 63023.0 63982.7 65488.7 93796.0 111929.4 100243.0 101227.5 101652.5 102664.5 102743.5 8392.5 8600.5 8734.0 8785.6 8786.8 47523.5 56725.4 56871.7 57716.4 57962.4 51101.5 52615.9 64884.0 76487.6 78606.0 12577.2 12679.7 12685.4 13216.3 13350.6 14548.8 14759.8 14790.8 14914.6 15186.8 15423.5 15456.6 16484.9 16520.7 17118.6 14961.9 15886.5 15937.4 16510.6 17062.1 13834.2 14192.0 14808.9 15478.8 16397.2 10819.8 10920.6 11356.9 11541.5 11816.4 10584.8 11347.7 11745.4 11968.4 12046.9 02:47:35 INFO - PROCESS | 24899 | -------- Summary: end -------- 02:47:35 INFO - PROCESS | 24899 | 02:47:36 INFO - TEST-INFO | 24899: exit 0 02:47:36 INFO - TEST-OK | dromaeo_css | took 1113548ms 02:47:36 INFO - TEST-START | kraken 02:47:36 INFO - operating with platform_type : linux_ 02:47:36 INFO - Initialising browser for kraken test... 02:47:36 INFO - TEST-INFO | started process 25218 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYssDck/profile http://localhost:51682/getInfo.html) 02:47:40 INFO - PROCESS | 25218 | __metrics Screen width/height:1600/1200 02:47:40 INFO - PROCESS | 25218 | colorDepth:24 02:47:40 INFO - PROCESS | 25218 | Browser inner width/height: 1024/697 02:47:40 INFO - PROCESS | 25218 | __metrics 02:47:41 INFO - PROCESS | 25218 | 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" 02:47:41 INFO - PROCESS | 25218 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:47:41 INFO - PROCESS | 25218 | console.error: PushService: 02:47:41 INFO - PROCESS | 25218 | stateChangeProcessEnqueue: Error transitioning state 02:47:41 INFO - PROCESS | 25218 | UnknownError 02:47:42 INFO - TEST-INFO | 25218: exit 0 02:47:42 INFO - Browser initialized. 02:47:42 INFO - Running cycle 1/1 for kraken test... 02:47:42 INFO - TEST-INFO | started process 25308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYssDck/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:47:54 INFO - PROCESS | 25308 | RSS: Main: 145182720 02:47:54 INFO - PROCESS | 25308 | 02:47:56 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51682/tests/kraken/driver.html?testName=audio-beat-detection) 02:47:57 INFO - PROCESS | 25308 | RSS: Main: 187039744 02:47:57 INFO - PROCESS | 25308 | 02:47:59 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51682/tests/kraken/driver.html?testName=audio-dft) 02:48:00 INFO - PROCESS | 25308 | RSS: Main: 176209920 02:48:00 INFO - PROCESS | 25308 | 02:48:03 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51682/tests/kraken/driver.html?testName=audio-fft) 02:48:03 INFO - PROCESS | 25308 | RSS: Main: 266981376 02:48:03 INFO - PROCESS | 25308 | 02:48:06 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51682/tests/kraken/driver.html?testName=audio-oscillator) 02:48:06 INFO - PROCESS | 25308 | RSS: Main: 173821952 02:48:06 INFO - PROCESS | 25308 | 02:48:08 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51682/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:09 INFO - PROCESS | 25308 | RSS: Main: 175644672 02:48:09 INFO - PROCESS | 25308 | 02:48:12 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51682/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:12 INFO - PROCESS | 25308 | RSS: Main: 211591168 02:48:12 INFO - PROCESS | 25308 | 02:48:16 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51682/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:16 INFO - PROCESS | 25308 | RSS: Main: 210120704 02:48:16 INFO - PROCESS | 25308 | 02:48:19 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51682/tests/kraken/driver.html?testName=json-parse-financial) 02:48:20 INFO - PROCESS | 25308 | RSS: Main: 210284544 02:48:20 INFO - PROCESS | 25308 | 02:48:21 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51682/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:48:22 INFO - PROCESS | 25308 | RSS: Main: 171995136 02:48:22 INFO - PROCESS | 25308 | 02:48:23 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51682/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:48:24 INFO - PROCESS | 25308 | RSS: Main: 179068928 02:48:24 INFO - PROCESS | 25308 | 02:48:26 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51682/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:48:27 INFO - PROCESS | 25308 | RSS: Main: 188362752 02:48:27 INFO - PROCESS | 25308 | 02:48:29 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:48:30 INFO - PROCESS | 25308 | RSS: Main: 197394432 02:48:30 INFO - PROCESS | 25308 | 02:48:32 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:48:33 INFO - PROCESS | 25308 | RSS: Main: 194179072 02:48:33 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | Cycle 1(1): loaded http://localhost:51682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51682/tests/kraken/driver.html?testName=ai-astar) 02:48:35 INFO - PROCESS | 25308 | RSS: Main: 245338112 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | __start_tp_report 02:48:35 INFO - PROCESS | 25308 | _x_x_mozilla_page_load 02:48:35 INFO - PROCESS | 25308 | _x_x_mozilla_page_load_details 02:48:35 INFO - PROCESS | 25308 | |i|pagename|runs| 02:48:35 INFO - PROCESS | 25308 | |0;ai-astar;108;110;108;111;112;110;110;109;110;109 02:48:35 INFO - PROCESS | 25308 | |1;audio-beat-detection;140;116;118;153;117;120;120;119;150;117 02:48:35 INFO - PROCESS | 25308 | |2;audio-dft;161;160;160;161;161;161;163;167;159;160 02:48:35 INFO - PROCESS | 25308 | |3;audio-fft;75;76;76;112;86;75;75;76;98;76 02:48:35 INFO - PROCESS | 25308 | |4;audio-oscillator;82;82;115;82;82;82;100;82;81;109 02:48:35 INFO - PROCESS | 25308 | |5;imaging-gaussian-blur;117;117;117;116;117;116;117;117;117;116 02:48:35 INFO - PROCESS | 25308 | |6;imaging-darkroom;146;146;145;146;147;147;146;146;146;146 02:48:35 INFO - PROCESS | 25308 | |7;imaging-desaturate;95;94;95;95;95;95;94;94;95;94 02:48:35 INFO - PROCESS | 25308 | |8;json-parse-financial;49;48;50;49;48;49;49;50;49;49 02:48:35 INFO - PROCESS | 25308 | |9;json-stringify-tinderbox;59;59;59;61;59;59;60;59;61;60 02:48:35 INFO - PROCESS | 25308 | |10;stanford-crypto-aes;73;72;79;74;73;73;72;77;74;73 02:48:35 INFO - PROCESS | 25308 | |11;stanford-crypto-ccm;122;124;122;121;122;124;120;122;153;121 02:48:35 INFO - PROCESS | 25308 | |12;stanford-crypto-pbkdf2;152;152;152;152;149;157;151;153;151;153 02:48:35 INFO - PROCESS | 25308 | |13;stanford-crypto-sha256-iterative;61;63;62;61;62;63;61;61;60;60 02:48:35 INFO - PROCESS | 25308 | __end_tp_report 02:48:35 INFO - PROCESS | 25308 | __start_cc_report 02:48:35 INFO - PROCESS | 25308 | _x_x_mozilla_cycle_collect,1572 02:48:35 INFO - PROCESS | 25308 | __end_cc_report 02:48:35 INFO - PROCESS | 25308 | __startTimestamp1478429315307__endTimestamp 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | ------- Summary: start ------- 02:48:35 INFO - PROCESS | 25308 | Number of tests: 14 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:1.25 (1.1%) stddev-sans-first:1.17 02:48:35 INFO - PROCESS | 25308 | Values: 108.0 110.0 108.0 111.0 112.0 110.0 110.0 109.0 110.0 109.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#1] audio-beat-detection Cycles:10 Average:127.00 Median:120.00 stddev:14.67 (12.2%) stddev-sans-first:14.79 02:48:35 INFO - PROCESS | 25308 | Values: 140.0 116.0 118.0 153.0 117.0 120.0 120.0 119.0 150.0 117.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:2.26 (1.4%) stddev-sans-first:2.40 02:48:35 INFO - PROCESS | 25308 | Values: 161.0 160.0 160.0 161.0 161.0 161.0 163.0 167.0 159.0 160.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#3] audio-fft Cycles:10 Average:82.50 Median:76.00 stddev:12.74 (16.8%) stddev-sans-first:13.22 02:48:35 INFO - PROCESS | 25308 | Values: 75.0 76.0 76.0 112.0 86.0 75.0 75.0 76.0 98.0 76.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:82.00 stddev:13.12 (16.0%) stddev-sans-first:13.62 02:48:35 INFO - PROCESS | 25308 | Values: 82.0 82.0 115.0 82.0 82.0 82.0 100.0 82.0 81.0 109.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 02:48:35 INFO - PROCESS | 25308 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 116.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 02:48:35 INFO - PROCESS | 25308 | Values: 146.0 146.0 145.0 146.0 147.0 147.0 146.0 146.0 146.0 146.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 02:48:35 INFO - PROCESS | 25308 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 94.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#8] json-parse-financial Cycles:10 Average:49.00 Median:49.00 stddev:0.67 (1.4%) stddev-sans-first:0.71 02:48:35 INFO - PROCESS | 25308 | Values: 49.0 48.0 50.0 49.0 48.0 49.0 49.0 50.0 49.0 49.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.50 stddev:0.84 (1.4%) stddev-sans-first:0.87 02:48:35 INFO - PROCESS | 25308 | Values: 59.0 59.0 59.0 61.0 59.0 59.0 60.0 59.0 61.0 60.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:73.50 stddev:2.26 (3.1%) stddev-sans-first:2.37 02:48:35 INFO - PROCESS | 25308 | Values: 73.0 72.0 79.0 74.0 73.0 73.0 72.0 77.0 74.0 73.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#11] stanford-crypto-ccm Cycles:10 Average:125.10 Median:122.00 stddev:9.88 (8.1%) stddev-sans-first:10.42 02:48:35 INFO - PROCESS | 25308 | Values: 122.0 124.0 122.0 121.0 122.0 124.0 120.0 122.0 153.0 121.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:152.00 stddev:2.04 (1.3%) stddev-sans-first:2.17 02:48:35 INFO - PROCESS | 25308 | Values: 152.0 152.0 152.0 152.0 149.0 157.0 151.0 153.0 151.0 153.0 02:48:35 INFO - PROCESS | 25308 | 02:48:35 INFO - PROCESS | 25308 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.50 stddev:1.07 (1.7%) stddev-sans-first:1.13 02:48:35 INFO - PROCESS | 25308 | Values: 61.0 63.0 62.0 61.0 62.0 63.0 61.0 61.0 60.0 60.0 02:48:35 INFO - PROCESS | 25308 | -------- Summary: end -------- 02:48:35 INFO - PROCESS | 25308 | 02:48:36 INFO - TEST-INFO | 25308: exit 0 02:48:36 INFO - TEST-OK | kraken | took 59818ms 02:48:36 INFO - SUITE-END | took 1173s 02:48:36 INFO - Completed test suite (00:19:33) 02:48:36 INFO - javascript benchmark 02:48:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3110.6733792951745, 3149.8582563784626, 3166.9683303166976, 3189.4737368334195, 3204.0, 5508.201310809935, 5574.86062848429, 5599.608027438081, 5623.381428042922, 5634.774609015644, 5472.644278121919, 5507.779688812453, 5516.586256030793, 5531.474509921561, 5545.667259964405, 4980.352554167962, 5042.596592272609, 5069.467705890884, 5073.365829271341, 5144.279800827887, 4987.326710894037, 5071.898562028758, 5128.179491281396, 5131.281620573122, 5133.871653208662, 4973.726445045521, 5007.849764507052, 5036.219385995177, 5048.343715317003, 5079.949200507985, 5113.846584602468, 5157.277981082651, 5164.6642968207325, 5216.686998780047, 5231.790728370861, 5047.9747601262125, 5111.182210846247, 5171.5862730981435, 5179.119549676564, 5212.061828870802, 5405.054115529767, 5430.185472179165, 5462.0, 5483.067878460672, 5542.75057622408, 5830.4461076197695, 5854.034084376115, 5854.443827836349, 5941.287045554518, 5945.197398351211, 5968.940310596882, 5972.850678733023, 5988.9101663475085, 5991.880162396771, 5992.370801065869, 5426.778974730654, 5449.0, 5482.314710661168, 5527.640703354269, 5552.5280351169795, 6079.088136779518, 6117.357677443804, 6123.663198524035, 6124.173236613, 6128.632282063001, 5849.356570777211, 5857.648541087463, 5858.736356863951, 5859.853503662443, 5860.853478663067, 5804.361520232771, 5827.562932780059, 5837.591368604242, 5846.590738648254, 5907.645541267451, 5865.824025279248, 5866.472017518444, 5867.941320586825, 5873.353931067579, 5891.263592050994, 5848.619839710405, 5883.02930016551, 5899.941000590025, 5918.585699001114, 5936.5547583931375, 4515.864524064284, 4527.09458108373, 4538.160440318552, 4552.817887284472, 4580.908381832344, 4029.516458024997, 4047.473828402348, 4048.8582899598373, 4074.714769966074, 4083.632473077437, 5623.0721930880945, 5625.690587017753, 5629.408912064257, 5634.887302253932, 5663.405342439067, 5776.740046697909, 5824.417558244142, 5907.172995780594, 5919.0, 5945.970270148708, 5616.775328986876, 5621.100623900156, 5621.943780562224, 5662.150677398423, 5662.7734890603915, 5146.819861304836, 5152.046871328816, 5155.097857874857, 5157.226416037624, 5158.509941555546, 6005.249343832021, 6022.247219097613, 6064.151018857364, 6149.0, 6164.7842325518395, 5862.970685146547, 5960.284765828128, 5991.370906054975, 5994.880102398015, 5997.430244126801, 6152.78465253714, 6207.565470417027, 6218.409251121137, 6229.159063526366, 6229.283632382334, 5735.168400581976, 5746.224259725051, 5805.65166090019, 5875.735591898289, 5881.32364778056, 5870.913880932041, 5880.852978675396, 5915.201447804663, 5961.552883533665, 6015.939840601713, 5822.73797679097, 5826.621269617462, 5828.6211396259105, 5831.096180092092, 5831.970840145772, 5654.434556544313, 5802.82591522255, 5809.9419005809405, 5811.593188476766, 5812.680302583399, 5597.80407685729, 5759.942400576108, 5786.3056433228285, 5817.76728930838, 5907.940920590739, 5901.085331773582, 5942.881142377215, 5945.64326140433, 5946.375630558729, 5956.017257152695, 5506.2291279221745, 5534.640248383842, 5792.160136780228, 5802.854928626818, 5805.245318108618], "value": 5474.895434359754, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.6435192356699, 624.0825985800872, 627.9780207692731, 645.9450946669534, 658.9308122647125, 36311.818440907795, 47559.76220118899, 48549.0, 48566.27150592744, 48710.0, 49663.0, 49897.25154122691, 50155.49844501563, 50180.24729629058, 50206.74896625521, 8619.61211745473, 9493.667721629745, 9642.325037247396, 9659.855102173473, 9663.613455461807, 8772.736817895473, 11438.5424583017, 11845.111616628745, 11854.229475084092, 11859.110566707488, 11630.418479076056, 11681.357525336103, 11696.122790790681, 11709.414529273545, 11735.29588224705, 11486.0, 11557.422128893522, 11565.479553420117, 11569.710757231136, 11577.131715121357, 11257.774844503065, 11261.887381126166, 11298.548058077668, 11309.151813614011, 11357.886421135765, 45889.54110458886, 46242.30636540455, 46270.76864615689, 46318.07363852738, 46380.0, 4026.396040593905, 4091.7954102295066, 4233.936490952638, 4492.348609451611, 4708.321290103946, 32660.836695816608, 34699.306013879584, 35346.11634709127, 35508.467372989166, 37093.0, 4647.86056418308, 4690.812367505296, 4739.194336962724, 4807.567318941277, 4870.07468580972, 386175.0691246554, 389455.0, 389823.0, 392119.0, 394332.0, 65933.67033164899, 66305.33694663088, 66347.66826165839, 66437.6678116616, 66559.33440665629, 41614.791926040176, 42372.364414533804, 42639.7868010658, 45257.09485810265, 45312.77343613261, 19503.707444388347, 19698.606027879363, 19712.70430943537, 19712.802871971384, 19717.0, 13948.302584870717, 14306.0, 14988.925055374653, 15055.322510487054, 15225.162616056223, 20631.69052464214, 21569.78430215709, 21588.244411445838, 21710.240141594968, 21851.453713656967, 7625.275598818103, 7721.53670779755, 7857.253560911704, 7860.135385107634, 7869.803254918673, 1016.9389836609827, 1018.9184865210765, 1069.6042464288107, 1111.5220455204178, 1135.3755434510986, 35022.649773502446, 35526.822365888525, 35691.28617427637, 36067.86846236094, 38067.42898856464, 1136.085451211766, 1144.7653231087775, 1149.2242736153064, 1154.276768817691, 1159.1885680024018, 18072.09639518019, 18538.536536586693, 18550.814491854908, 18570.81429185718, 18620.0, 601892.0, 604640.0, 605404.0, 606595.0, 609677.0, 64756.0, 65483.0, 65515.67242163759, 65528.672356637915, 65564.34435655677, 1295.5077070713069, 1339.604816579092, 1390.4438224709995, 1411.943522259098, 1472.749632562467, 32769.0, 35154.0, 35604.261552537646, 35687.28625427529, 35935.2268011855, 56678.7166064167, 58171.4182858166, 58242.0, 58260.41739582719, 58275.417245827, 476104.0, 476938.0, 478260.0, 478487.0, 479740.0, 31947.520787187746, 32009.67990320067, 32624.673753262163, 32965.17587060342, 33257.66742332546, 52463.21305180349, 53163.202551962524, 53178.0, 53227.201591976926, 53374.73312633412, 44937.32594011158, 46258.537414625425, 46692.53307466882, 47119.05761884811, 47849.0, 44579.0, 44868.55131448644, 46090.0, 46503.44231067188, 46515.53484465247, 45470.545294547956, 45742.542574575156, 46593.76703116463, 46674.29988550107, 46707.0, 37583.43624845684, 39964.800175998935, 39997.60002400055, 40245.79877100596, 40470.392944105275, 30549.0, 30662.233444164074, 30791.84604076965, 31034.68965310318, 31066.378672425973], "value": 22355.565418228052, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.77867783380213, 409.7070594524914, 419.2767476103721, 431.8272690923631, 443.1491536250399, 14846.40614375425, 16500.669986600275, 20428.0, 21193.894030529864, 21893.452663683416, 20657.483562910933, 20761.584768304623, 20944.790552094473, 21121.260755873547, 21476.4630884228, 5938.168656388109, 5992.820215393545, 6211.968940155293, 6249.625022498664, 6340.904886426707, 6199.156522955774, 6252.249730032402, 6282.371762823726, 6284.7486100555925, 6778.694958726869, 6844.1444819397575, 6856.2800905904905, 6858.965705171468, 6859.485538584601, 6862.107925969642, 6237.157983672214, 6301.873962520724, 6302.023186406114, 6377.202849643794, 6840.110785597865, 5939.46544810969, 6141.109539116803, 6145.231846019247, 6146.385361463863, 6146.385361463863, 15157.772633410508, 15498.457553985556, 15568.455104071416, 15575.22123893812, 15581.766273505906, 2450.473148273112, 2473.6660550825586, 2558.398776287571, 2571.9485610287875, 2969.6436427628823, 10521.947390263, 11379.374134422604, 11406.54373825046, 15366.154861482613, 15764.527064188092, 2036.1346427768287, 2097.9895100524554, 2115.153938424627, 2116.544942837297, 2130.488682716137, 90874.54562727144, 93702.0, 95246.0, 95525.0, 95598.0, 11635.650930472098, 11826.0, 12031.398430078461, 12140.150189486827, 12154.392280386122, 17327.740083898752, 17428.738568921475, 17452.95282283067, 17469.47591572255, 17610.2075406607, 9094.954525227464, 9148.954255228682, 9226.169644732006, 9270.814583708423, 9477.00491448388, 9030.148819491988, 9070.501122438329, 9103.90896091044, 9246.445213287225, 9303.162715355651, 5099.286099946011, 5182.948170518246, 5192.506711862367, 5229.973850130725, 5319.069162896478, 2032.217596225464, 2038.4824741444304, 2123.4730670502363, 2149.3122200895564, 2155.838312126597, 920.6087412849512, 996.1084829077935, 1000.5397517142051, 1090.5692251560747, 1116.0290547223967, 19955.6298374419, 20423.897880510503, 21118.155273788878, 21159.78840211609, 21233.469163271042, 1010.2675560218929, 1063.5161001744189, 1074.5164675895878, 1074.7420619051372, 1103.2773533335678, 10804.1896857736, 11727.237729547553, 11761.176717629683, 11828.23116497425, 11889.87046230624, 511522.0, 531293.0, 536821.0, 538305.0, 538491.0, 74051.0, 80217.59891200507, 86926.0, 102164.48917755364, 103914.48042759889, 881.6605606841413, 909.0863681999548, 920.5397301349325, 932.38462614674, 943.6649989253716, 18160.091995400442, 18738.0, 18883.339083132025, 18919.905400472908, 19123.521911952314, 45336.31995520004, 45368.0, 46195.3070703933, 46237.76881115573, 46734.0, 58489.4151058484, 62377.0, 63297.0, 64417.35582644113, 64515.67742161259, 9086.22767064807, 9332.39339442934, 9462.621495140118, 9478.668246611336, 9757.756056098655, 10527.684039495063, 14034.087784293988, 14495.275236238356, 14678.04592701471, 15088.924555377153, 12190.512379505075, 12453.315067671365, 12480.0, 12672.493100276255, 12684.112112152061, 11666.183367164216, 11959.94020029929, 12416.441260143134, 12760.808587871375, 12850.228986260854, 12196.451159697657, 12517.687057823627, 12534.874651253736, 12613.306268154973, 12773.978081753252, 9300.906990930003, 9403.4828084456, 9538.508875974614, 9734.269929755439, 9764.267679923893, 9390.81218375615, 12084.274943503418, 12368.0, 12402.503899843905, 12807.871921280668], "value": 9864.964863546084, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1532.4559781277649, 1575.2596279748518, 1777.3068503283714, 1824.7901491328485, 1903.4384856467332, 8531.744047678563, 8878.334124940622, 9180.816383672323, 9935.05616966388, 9942.850857237147, 9063.818723625523, 9481.620735170602, 9492.288078394113, 9771.951140244306, 9775.853362199572, 2560.615907613859, 2593.623924530951, 2595.4160313929406, 2598.909038183664, 2600.362911086777, 2192.5614877024577, 2262.6945362376114, 2434.720007199177, 2560.4495033567778, 2597.688277406714, 2504.8998040078377, 2537.8223524353302, 2568.858712770797, 2571.100114959768, 2572.356910772307, 2535.619657051448, 2597.779188768957, 2604.804639652024, 2606.843589384624, 2608.100205429124, 2512.798976081909, 2525.267672375009, 2533.5946248600135, 2534.3284029732135, 2536.048981631888, 9380.155785979226, 9532.809343813155, 9544.379615324982, 9547.665831695856, 9566.186874115709, 5555.166724991243, 5689.886202275973, 5694.487496125369, 5695.572832037614, 5699.202111704365, 3510.7366947478786, 3576.8742683442733, 3598.8560457581666, 3609.5848977367677, 3679.9448008279896, 1176.1884299833089, 1198.3169593331804, 1201.85577730672, 1209.2079687804503, 1237.2638280223252, 13316.334183290797, 13511.121777084458, 13693.657658558515, 13716.245606491739, 13755.449782008707, 4934.506549345037, 4972.204447288489, 5163.122269214243, 5166.844994650167, 5189.532942035235, 11462.541498339973, 11475.426228688531, 11509.309441433541, 11511.366874821964, 11513.251638643462, 5785.710714464344, 5788.739506722208, 5789.421057894261, 5796.565257605696, 5797.304323481209, 5068.670536415121, 5261.894762104736, 5316.840494785162, 5326.707031113326, 5327.760250788724, 4208.810603522848, 4254.085371645049, 4320.481542214954, 4356.433663623772, 4386.0, 1285.1325355385077, 1319.920804751718, 1330.4611632288938, 1365.7094046126513, 1421.4243231491262, 1019.4240254256322, 1040.9011143941314, 1049.6955882794057, 1051.9737006574896, 1060.7613287010515, 6376.39424254695, 6438.935610643927, 6515.12045873801, 6515.7067931943175, 6660.134182556237, 950.1401231885155, 970.1220395542045, 1010.2776514791959, 1024.99487502562, 1106.546316010448, 4260.6378457830815, 4389.31965545341, 4391.165678521071, 4395.604395604411, 4427.5351088135285, 11491.712707182387, 11875.228110172811, 11882.584109556297, 11892.821607675893, 11911.702207444883, 11193.608223712294, 11756.882431175578, 11778.293302401884, 11805.704857378634, 11823.231489953125, 8623.611937462829, 8997.415168014059, 9020.954895225483, 9028.548572571344, 9050.547472626342, 6506.707198175999, 6558.934410655832, 6620.3710647488415, 6673.799786006428, 6840.213375461686, 10640.574377024832, 10980.890191097988, 11088.390138542458, 11128.387938663127, 11246.550137994389, 11044.116470682487, 11247.88752112501, 11267.549298027987, 11270.605528806453, 11385.373804440504, 8625.353098517511, 8741.213290803858, 8765.211130997986, 8875.689350872688, 8880.023197448405, 9369.0, 9417.811643766949, 9450.480223587769, 9457.668981585612, 9459.621615135318, 8146.266835984789, 8305.626246818787, 8323.334133269445, 8501.532415717194, 8559.186877246651, 7878.133405325524, 7913.881291780743, 8064.233897779936, 8098.473599216032, 8139.10469848328, 7521.811954701177, 7577.924220757722, 7737.226277372218, 7814.882776758467, 7956.124826269222, 5535.501804837584, 5657.2928383952, 5663.631863928831, 5706.9714651426475, 5807.186993820953, 5540.113581826889, 5542.808296216333, 5572.888542229214, 5681.806820567727, 5744.425557444222], "value": 5055.960971094154, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.9357921994821, 344.27701826165054, 349.7237182625725, 350.9298140371926, 358.0743777335587, 5510.6969116698565, 5526.364468086172, 5537.889242215158, 5544.16837474379, 5555.250041244431, 5219.173289348726, 5290.497402746742, 5354.22363757255, 5405.891882162355, 5489.039418101836, 4335.739855608658, 4348.608625223729, 4351.26028575143, 4358.4987726411555, 4359.825606975717, 4336.804843782037, 4364.105358401536, 4369.431973843396, 4370.416258667894, 4383.232934236512, 4134.4831896013, 4261.701680882339, 4290.485141782975, 4312.28847240205, 4335.154644844257, 4286.614204721559, 4294.506131794837, 4297.570242975708, 4304.438858878962, 4308.116836048634, 3829.8468061277517, 3833.0, 3836.175222327185, 3838.5201849768778, 3839.827207775657, 5152.793888244466, 5233.11607821184, 5286.788528458896, 5392.1911713242935, 5407.945920540823, 4893.363862697826, 4905.0, 4913.8771530711665, 4923.950760492385, 4926.428339453903, 5675.772969081232, 5688.800891968761, 5720.599558030939, 5737.598368114233, 5754.136879468113, 3938.468306778577, 4141.7100802943805, 4161.75029498228, 4198.790060496963, 4203.453551038376, 5922.496587790043, 5940.257637688272, 5958.612690175168, 5958.910616340759, 5997.340292567814, 4294.76378799169, 4296.5273819879785, 4296.570342965679, 4302.118065796504, 4306.267934451151, 4172.979135104305, 4256.61690447855, 4332.241857674894, 4346.847860324873, 4359.542248063907, 4884.4871288514905, 4895.67816689499, 4903.730294833818, 4904.362432883702, 4906.852794416173, 5729.312482502043, 5736.651886806597, 5768.567357448208, 5774.307083150049, 5774.682392468455, 1426.7217892511069, 1452.6586252230718, 1480.6230205908394, 1491.7091167222288, 1503.7218114648824, 941.9497260554432, 952.9237660987243, 957.5834511987168, 959.1990687775645, 962.4273557233435, 4939.87650308738, 4946.3817022872145, 4965.0, 4969.527894849984, 4986.750662466862, 5141.820036298712, 5157.974210128926, 5176.197689358156, 5189.197833170121, 5191.454897235812, 4875.219964805614, 4894.828680996148, 4933.827316043993, 4935.777889994959, 5231.267622532849, 3113.2839446926882, 3153.369326134782, 3154.9369012620077, 3162.2094476380903, 3166.920826979344, 6159.3224745277985, 6172.753089876354, 6179.907301390393, 6199.535034872402, 6210.627362358273, 5909.763609455659, 5984.222051133412, 6005.0, 6069.302030266487, 6069.449372117361, 6098.603590766586, 6144.262688477412, 6156.230471191101, 6158.938410615837, 6160.322364539987, 5663.545237405097, 5918.940810592011, 5939.3963629818845, 5945.940540594539, 5949.405059494016, 5840.561957853093, 5853.824385268449, 5882.823515294548, 5899.292084949662, 5915.526757859447, 6152.692365381624, 6156.815295541141, 6207.0137555981655, 6209.627422354673, 6223.222097237845, 4085.7344272622186, 4086.0806318578084, 4262.595053469915, 4289.5495972923645, 4315.4605674290715, 5769.769209231584, 5800.694843660142, 5959.463648271503, 5968.432998865101, 5970.58205925581, 5818.883622327615, 5821.854453638523, 5824.922389357982, 5845.18167456548, 5855.414458554111, 5675.432456754292, 5740.598158128891, 5765.827025189251, 5786.5081468074, 5815.447532484407, 5726.9713651431475, 5733.455321744427, 5736.799212027726, 5737.454941780524, 5755.8561035974435, 5614.6631202128, 5841.357450680507, 5844.824655260349, 5876.588638795243, 5878.823635290948, 5613.298337707786, 5697.259356283656, 5716.428357164416, 5725.599208055396, 5729.197912292366], "value": 4370.7090420642935, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.3587969323773, 831.1065604475187, 870.3689824876965, 885.9335549833764, 888.5779254853941, 14007.719845603082, 15017.32422041008, 15053.62365940852, 15077.246137693126, 15083.924580377097, 13350.198988060723, 13456.596302110927, 13471.865281347184, 13491.055626106176, 13524.729505409887, 714.4748609771814, 729.2998721227601, 732.3847967707125, 734.0347443112317, 825.154216927651, 850.336737344872, 861.67687117331, 868.2402897464718, 868.917452841979, 869.0874581689233, 909.3316412436853, 911.4258017448998, 912.0970239462918, 912.5756523216703, 912.95891684874, 740.4631642059518, 904.0, 905.7509184974152, 906.4697152165991, 907.918287354138, 557.4898967444783, 647.1748520636179, 659.2221179008768, 666.0375757031044, 666.0542030316913, 14142.292885355793, 14195.858041419557, 14197.716045679028, 14202.360893759806, 14209.502667406694, 13093.869061309455, 13146.60560183196, 13202.603921882359, 13764.862351376458, 13895.58313250604, 12569.748605027848, 12740.299283539402, 12793.61619151427, 12926.87073129266, 13065.869341306656, 4278.978605106986, 4325.199838029975, 4385.254506733831, 4389.209942210401, 4424.677044551636, 88144.0, 88234.0, 90650.54674726583, 92084.07915920822, 93828.53085734596, 10942.124630029579, 11167.944160279147, 11176.161787865942, 11377.203595748219, 11436.599719009795, 21943.561128777335, 21975.23086691958, 21975.780242197692, 22002.0, 22012.229571964905, 8158.0, 8207.55421656751, 8253.0, 8327.20891515305, 8354.0, 4950.653454258239, 5010.69935803853, 5078.5937125029905, 5083.898322033612, 5142.100132476801, 3610.4042832932796, 3686.078480379905, 3701.3152566774693, 3714.3499887519574, 3726.888193354204, 600.2976517474565, 606.8452544601187, 610.2219669920797, 616.9691515424211, 637.5282291104642, 626.3673689573473, 669.9147381242321, 697.7941507255372, 741.0699572037059, 755.2824816424419, 8600.354973377021, 8702.47785132894, 8847.432398908459, 9197.574375971735, 9201.539923003824, 676.2020815437733, 715.3132992327425, 724.9166345870292, 724.9347558719741, 743.8140464883779, 4221.092465120014, 4614.523352527159, 4627.930581041287, 4650.837220697259, 4762.238041913277, 83844.16155838486, 126435.0, 131260.0, 132557.0, 132843.0, 63023.0, 63982.68008659927, 65488.67255663691, 93796.0, 111929.44035279771, 100243.0, 101227.49386253169, 101652.49173754232, 102664.48667756614, 102743.4862825681, 8392.496450213006, 8600.483970961512, 8733.956330218309, 8785.560721963748, 8786.84412236075, 47523.524764751906, 56725.43274567201, 56871.71564142319, 57716.4228357711, 57962.4203757957, 51101.488985109674, 52615.94768104693, 64884.026739598, 76487.61756191183, 78606.0, 12577.182483138567, 12679.746405072032, 12685.365731713562, 13216.273104979318, 13350.599482015556, 14548.781768273273, 14759.778603321174, 14790.778138328149, 14914.552563423114, 15186.772198416811, 15423.460178893685, 15456.613584660474, 16484.917575412048, 16520.66958660844, 17118.572035699206, 14961.925190373979, 15886.52340429789, 15937.442189523312, 16510.587235319214, 17062.146892655564, 13834.169949803045, 14192.0, 14808.925955370154, 15478.767818482956, 16397.18014099314, 10819.783604328028, 10920.563177472812, 11356.943215284202, 11541.480633371686, 11816.40917954116, 10584.841227381441, 11347.659570212907, 11745.354005529778, 11968.446010766656, 12046.939765301116], "value": 7687.92939133365, "unit": "score"}], "name": "dromaeo_css", "value": 7679.705028344591, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 108.0, 111.0, 112.0, 110.0, 110.0, 109.0, 110.0, 109.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 118.0, 153.0, 117.0, 120.0, 120.0, 119.0, 150.0, 117.0], "value": 127.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 161.0, 161.0, 163.0, 167.0, 159.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 112.0, 86.0, 75.0, 75.0, 76.0, 98.0, 76.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 115.0, 82.0, 82.0, 82.0, 100.0, 82.0, 81.0, 109.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 50.0, 49.0, 48.0, 49.0, 49.0, 50.0, 49.0, 49.0], "value": 49.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 61.0, 59.0, 59.0, 60.0, 59.0, 61.0, 60.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 79.0, 74.0, 73.0, 73.0, 72.0, 77.0, 74.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 124.0, 122.0, 121.0, 122.0, 124.0, 120.0, 122.0, 153.0, 121.0], "value": 125.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 152.0, 152.0, 149.0, 157.0, 151.0, 153.0, 151.0, 153.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 61.0, 62.0, 63.0, 61.0, 61.0, 60.0, 60.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1448.9, "alertThreshold": 2.0}]} 02:48:37 INFO - Return code: 0 02:48:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:48:37 INFO - # TBPL SUCCESS # 02:48:37 INFO - Running post-action listener: _resource_record_post_action 02:48:37 INFO - [mozharness: 2016-11-06 10:48:37.145650Z] Finished run-tests step (success) 02:48:37 INFO - Running post-run listener: _resource_record_post_run 02:48:37 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 368570368; Read time: 12; Write time: 222840 02:48:37 INFO - TinderboxPrint: CPU usage
12.9% 02:48:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 02:48:37 INFO - TinderboxPrint: I/O write bytes / time
368,570,368 / 222,840 02:48:37 INFO - TinderboxPrint: CPU idle
8,268.5 (87.0%) 02:48:37 INFO - TinderboxPrint: CPU user
1,185.1 (12.5%) 02:48:37 INFO - TinderboxPrint: Swap in / out
0 / 0 02:48:37 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118722560; Read time: 0; Write time: 82804 02:48:37 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 248856576; Read time: 0; Write time: 139960 02:48:37 INFO - Running post-run listener: _upload_blobber_files 02:48:37 INFO - Blob upload gear active. 02:48:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:48:37 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 02:48:37 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'] 02:48:37 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 02:48:38 INFO - (blobuploader) - INFO - Open directory for files ... 02:48:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:48:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:48:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:41 INFO - (blobuploader) - INFO - Done attempting. 02:48:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:48:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:48:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:42 INFO - (blobuploader) - INFO - Done attempting. 02:48:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:48:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:48:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:43 INFO - (blobuploader) - INFO - Done attempting. 02:48:43 INFO - (blobuploader) - INFO - Iteration through files over. 02:48:43 INFO - Return code: 0 02:48:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:48:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:48:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e450313c5973789edfb66bb8f9ddd896adf9c586c2363891dd0ad3c332387ed02ec78dbf9fe66d43a768adf1b1be4577bb77dd6480cccb0489b9d94491e3a710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4271b61afd6cc2b4d53cc360e46bc3d4cc4bb8f37cf7c9b51d198b05fe86a5fee6e3b80ac28d0dcf615ce3c91a3ba3f17fc08882b56fbd2d2e326d5cf7849c8c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10336b521ddd4cadfe049031e52ecb7a12aaf960ed340aac297a51b206b92d44f56643ad1b0c629ee3bb4c57196dc641c7fb24eafc0c7af8104d87cdd8d56da4"} 02:48:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:48:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:48:43 INFO - Contents: 02:48:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e450313c5973789edfb66bb8f9ddd896adf9c586c2363891dd0ad3c332387ed02ec78dbf9fe66d43a768adf1b1be4577bb77dd6480cccb0489b9d94491e3a710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4271b61afd6cc2b4d53cc360e46bc3d4cc4bb8f37cf7c9b51d198b05fe86a5fee6e3b80ac28d0dcf615ce3c91a3ba3f17fc08882b56fbd2d2e326d5cf7849c8c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10336b521ddd4cadfe049031e52ecb7a12aaf960ed340aac297a51b206b92d44f56643ad1b0c629ee3bb4c57196dc641c7fb24eafc0c7af8104d87cdd8d56da4"} 02:48:43 INFO - Running post-run listener: copy_logs_to_upload_dir 02:48:43 INFO - Copying logs to upload dir... 02:48:43 INFO - mkdir: /builds/slave/test/build/upload/logs 02:48:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.431558 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-11-06 02:48:42.910062) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-06 02:48:42.913649) ========= 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=8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588 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/e450313c5973789edfb66bb8f9ddd896adf9c586c2363891dd0ad3c332387ed02ec78dbf9fe66d43a768adf1b1be4577bb77dd6480cccb0489b9d94491e3a710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4271b61afd6cc2b4d53cc360e46bc3d4cc4bb8f37cf7c9b51d198b05fe86a5fee6e3b80ac28d0dcf615ce3c91a3ba3f17fc08882b56fbd2d2e326d5cf7849c8c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10336b521ddd4cadfe049031e52ecb7a12aaf960ed340aac297a51b206b92d44f56643ad1b0c629ee3bb4c57196dc641c7fb24eafc0c7af8104d87cdd8d56da4"} build_url:https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/KU9ydiAhSxKuENJo96tWSA/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/e450313c5973789edfb66bb8f9ddd896adf9c586c2363891dd0ad3c332387ed02ec78dbf9fe66d43a768adf1b1be4577bb77dd6480cccb0489b9d94491e3a710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4271b61afd6cc2b4d53cc360e46bc3d4cc4bb8f37cf7c9b51d198b05fe86a5fee6e3b80ac28d0dcf615ce3c91a3ba3f17fc08882b56fbd2d2e326d5cf7849c8c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10336b521ddd4cadfe049031e52ecb7a12aaf960ed340aac297a51b206b92d44f56643ad1b0c629ee3bb4c57196dc641c7fb24eafc0c7af8104d87cdd8d56da4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-06 02:48:42.941360) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 02:48:42.942279) ========= 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=8887e36bb8e381bfd44124ac000001a8-1478427540.777163-351107588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004545 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 02:48:43.026587) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-06 02:48:43.027208) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-06 02:48:43.027887) ========= ========= Total master_lag: 0.18 =========