builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1477544874.14 results: success (0) buildid: 20161026185134 builduid: 032f876391db4af6897faa34273bfcbb revision: 88cbb2726969ee5a4204ae9df3025c39c864de15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:54.138405) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:54.139024) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:54.139340) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:54.153969) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:54.154300) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-26 22:07:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 51.3M=0s 2016-10-26 22:07:54 (51.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118095 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:54.286794) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:54.287171) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013842 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:54.336833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:54.337253) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 88cbb2726969ee5a4204ae9df3025c39c864de15 --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 88cbb2726969ee5a4204ae9df3025c39c864de15 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-26 22:07:55,061 truncating revision to first 12 chars 2016-10-26 22:07:55,061 Setting DEBUG logging. 2016-10-26 22:07:55,061 attempt 1/10 2016-10-26 22:07:55,061 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/88cbb2726969?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 22:07:55,377 unpacking tar archive at: mozilla-aurora-88cbb2726969/testing/mozharness/ program finished with exit code 0 elapsedTime=0.687940 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:55.039118) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:55.039500) ========= script_repo_revision: 88cbb2726969ee5a4204ae9df3025c39c864de15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:55.039997) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:55.040299) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 22:07:55.050717) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-26 22:07:55.051025) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:07:55 INFO - MultiFileLogger online at 20161026 22:07:55 in /builds/slave/test 22:07:55 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 22:07:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:07:55 INFO - {'append_to_log': False, 22:07:55 INFO - 'base_work_dir': '/builds/slave/test', 22:07:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:07:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:07:55 INFO - 'branch': 'Mozilla-Aurora', 22:07:55 INFO - 'buildbot_json_path': 'buildprops.json', 22:07:55 INFO - 'config_files': ('talos/linux_config.py',), 22:07:55 INFO - 'default_actions': ('clobber', 22:07:55 INFO - 'read-buildbot-config', 22:07:55 INFO - 'download-and-extract', 22:07:55 INFO - 'populate-webroot', 22:07:55 INFO - 'create-virtualenv', 22:07:55 INFO - 'install', 22:07:55 INFO - 'run-tests'), 22:07:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:07:55 INFO - 'download_minidump_stackwalk': True, 22:07:55 INFO - 'download_symbols': 'ondemand', 22:07:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:07:55 INFO - 'tooltool.py': '/tools/tooltool.py', 22:07:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:07:55 INFO - '/tools/misc-python/virtualenv.py')}, 22:07:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:07:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:07:55 INFO - 'installer_path': 'installer.exe', 22:07:55 INFO - 'log_level': 'info', 22:07:55 INFO - 'log_name': 'talos', 22:07:55 INFO - 'log_to_console': True, 22:07:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:07:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:07:55 INFO - 'opt_config_files': (), 22:07:55 INFO - 'pip_index': False, 22:07:55 INFO - 'sps_profile': False, 22:07:55 INFO - 'sps_profile_interval': 0, 22:07:55 INFO - 'suite': 'dromaeojs', 22:07:55 INFO - 'system_bits': '32', 22:07:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:07:55 INFO - 'title': 'talos-linux64-ix-069', 22:07:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:07:55 INFO - 'use_talos_json': True, 22:07:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:07:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:07:55 INFO - 'work_dir': 'build'} 22:07:55 INFO - [mozharness: 2016-10-27 05:07:55.869096Z] Running clobber step. 22:07:55 INFO - Running pre-action listener: _resource_record_pre_action 22:07:55 INFO - Running main action method: clobber 22:07:55 INFO - rmtree: /builds/slave/test/build 22:07:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:07:56 INFO - Running post-action listener: _resource_record_post_action 22:07:56 INFO - [mozharness: 2016-10-27 05:07:56.873798Z] Finished clobber step (success) 22:07:56 INFO - [mozharness: 2016-10-27 05:07:56.873897Z] Running read-buildbot-config step. 22:07:56 INFO - Running pre-action listener: _resource_record_pre_action 22:07:56 INFO - Running main action method: read_buildbot_config 22:07:56 INFO - Using buildbot properties: 22:07:56 INFO - { 22:07:56 INFO - "project": "", 22:07:56 INFO - "product": "firefox", 22:07:56 INFO - "script_repo_revision": "production", 22:07:56 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:07:56 INFO - "repository": "", 22:07:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:07:56 INFO - "buildid": "20161026185134", 22:07:56 INFO - "pgo_build": "True", 22:07:56 INFO - "basedir": "/builds/slave/test", 22:07:56 INFO - "buildnumber": 594, 22:07:56 INFO - "slavename": "talos-linux64-ix-069", 22:07:56 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:07:56 INFO - "platform": "ubuntu64_hw", 22:07:56 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:07:56 INFO - "branch": "mozilla-aurora", 22:07:56 INFO - "repo_path": "releases/mozilla-aurora", 22:07:56 INFO - "revision": "88cbb2726969ee5a4204ae9df3025c39c864de15", 22:07:56 INFO - "stage_platform": "linux64-pgo", 22:07:56 INFO - "builduid": "032f876391db4af6897faa34273bfcbb", 22:07:56 INFO - "slavebuilddir": "test" 22:07:56 INFO - } 22:07:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 22:07:56 INFO - Running post-action listener: _resource_record_post_action 22:07:56 INFO - [mozharness: 2016-10-27 05:07:56.876592Z] Finished read-buildbot-config step (success) 22:07:56 INFO - [mozharness: 2016-10-27 05:07:56.876682Z] Running download-and-extract step. 22:07:56 INFO - Running pre-action listener: _resource_record_pre_action 22:07:56 INFO - Running main action method: download_and_extract 22:07:56 INFO - mkdir: /builds/slave/test/build/tests 22:07:56 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')]} 22:07:56 INFO - trying https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 22:07:56 INFO - Downloading https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/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 22:07:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/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 22:07:57 INFO - Downloaded 1361 bytes. 22:07:57 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 22:07:57 INFO - Using the following test package requirements: 22:07:57 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 22:07:57 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:07:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:07:57 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:07:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:07:57 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:07:57 INFO - u'jsshell-linux-x86_64.zip'], 22:07:57 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:07:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:07:57 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 22:07:57 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:07:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:07:57 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:07:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:07:57 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:07:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:07:57 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:07:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:07:57 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:07:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 22:07:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:07:57 INFO - Fetch https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 22:07:58 INFO - Expected file size: 21894602 22:08:02 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 22:08:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 22:08:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:08:02 INFO - Fetch https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 22:08:02 INFO - Expected file size: 21894602 22:08:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 22:08:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:08:07 INFO - Fetch https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 22:08:08 INFO - Expected file size: 14414081 22:08:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:08 INFO - trying https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:08:08 INFO - Downloading https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/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 22:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/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 22:08:09 INFO - Downloaded 63972727 bytes. 22:08:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:08:09 INFO - mkdir: /builds/slave/test/properties 22:08:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:08:09 INFO - Writing to file /builds/slave/test/properties/build_url 22:08:09 INFO - Contents: 22:08:09 INFO - build_url:https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:08:10 INFO - Running post-action listener: _resource_record_post_action 22:08:10 INFO - Running post-action listener: set_extra_try_arguments 22:08:10 INFO - [mozharness: 2016-10-27 05:08:10.571316Z] Finished download-and-extract step (success) 22:08:10 INFO - [mozharness: 2016-10-27 05:08:10.571544Z] Running populate-webroot step. 22:08:10 INFO - Running pre-action listener: _resource_record_pre_action 22:08:10 INFO - Running main action method: populate_webroot 22:08:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:08:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:08:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:08:10 INFO - '--tppagecycles', 22:08:10 INFO - '7'], 22:08:10 INFO - 'tests': ['tsvgm']}}, 22:08:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:08:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:08:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:08:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:08:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:10 INFO - 'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:10 INFO - 'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['damp', 'tps']}, 22:08:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:10 INFO - 'tests': ['damp', 'tps']}, 22:08:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['dromaeo_dom']}, 22:08:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:08:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['basic_compositor_video']}, 22:08:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:08:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['a11yr', 22:08:10 INFO - 'ts_paint', 22:08:10 INFO - 'tpaint', 22:08:10 INFO - 'sessionrestore', 22:08:10 INFO - 'sessionrestore_no_auto_restore', 22:08:10 INFO - 'tabpaint']}, 22:08:10 INFO - 'other-e10s': {'tests': ['a11yr', 22:08:10 INFO - 'ts_paint', 22:08:10 INFO - 'tpaint', 22:08:10 INFO - 'sessionrestore', 22:08:10 INFO - 'sessionrestore_no_auto_restore', 22:08:10 INFO - 'tabpaint']}, 22:08:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:08:10 INFO - 'ts_paint', 22:08:10 INFO - 'tpaint', 22:08:10 INFO - 'sessionrestore', 22:08:10 INFO - 'sessionrestore_no_auto_restore', 22:08:10 INFO - 'tabpaint']}, 22:08:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:08:10 INFO - 'ts_paint', 22:08:10 INFO - 'tpaint', 22:08:10 INFO - 'sessionrestore', 22:08:10 INFO - 'sessionrestore_no_auto_restore', 22:08:10 INFO - 'tabpaint']}, 22:08:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['a11yr', 22:08:10 INFO - 'ts_paint', 22:08:10 INFO - 'tpaint', 22:08:10 INFO - 'sessionrestore', 22:08:10 INFO - 'sessionrestore_no_auto_restore', 22:08:10 INFO - 'tabpaint']}, 22:08:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['a11yr', 22:08:10 INFO - 'ts_paint', 22:08:10 INFO - 'tpaint', 22:08:10 INFO - 'sessionrestore', 22:08:10 INFO - 'sessionrestore_no_auto_restore', 22:08:10 INFO - 'tabpaint']}, 22:08:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['tsvgx', 22:08:10 INFO - 'tsvgr_opacity', 22:08:10 INFO - 'tart', 22:08:10 INFO - 'tscrollx', 22:08:10 INFO - 'cart']}, 22:08:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:08:10 INFO - 'tsvgr_opacity', 22:08:10 INFO - 'tart', 22:08:10 INFO - 'tscrollx', 22:08:10 INFO - 'cart']}, 22:08:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:10 INFO - 'talos_options': ['--disable-e10s'], 22:08:10 INFO - 'tests': ['tp5o']}, 22:08:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:10 INFO - 'tests': ['tp5o']}, 22:08:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:10 INFO - 'talos_options': ['--disable-e10s', 22:08:10 INFO - '--xperf_path', 22:08:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:10 INFO - 'tests': ['tp5n']}, 22:08:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:10 INFO - 'talos_options': ['--xperf_path', 22:08:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:10 INFO - 'tests': ['tp5n']}}, 22:08:10 INFO - 'talos.zip': {'path': '', 22:08:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:08:10 INFO - Running post-action listener: _resource_record_post_action 22:08:10 INFO - [mozharness: 2016-10-27 05:08:10.589219Z] Finished populate-webroot step (success) 22:08:10 INFO - [mozharness: 2016-10-27 05:08:10.589307Z] Running create-virtualenv step. 22:08:10 INFO - Running pre-action listener: _resource_record_pre_action 22:08:10 INFO - Running main action method: create_virtualenv 22:08:10 INFO - Creating virtualenv /builds/slave/test/build/venv 22:08:10 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 22:08:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:08:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:08:10 INFO - Using real prefix '/usr' 22:08:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:08:11 INFO - Installing distribute.............................................................................................................................................................................................done. 22:08:13 INFO - Installing pip.................done. 22:08:13 INFO - Return code: 0 22:08:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:08:13 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')]} 22:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:13 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')]} 22:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:13 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 0x7f791abc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', '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 22:08:13 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 22:08:13 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 22:08:13 INFO - Using env: {'DISPLAY': ':0', 22:08:13 INFO - 'HOME': '/home/cltbld', 22:08:13 INFO - 'LANG': 'en_US.UTF-8', 22:08:13 INFO - 'LANGUAGE': 'en_US:en', 22:08:13 INFO - 'LOGNAME': 'cltbld', 22:08:13 INFO - 'MAIL': '/var/mail/cltbld', 22:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:13 INFO - 'MOZ_NO_REMOTE': '1', 22:08:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:13 INFO - 'NO_EM_RESTART': '1', 22:08:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:13 INFO - 'PWD': '/builds/slave/test', 22:08:13 INFO - 'SHELL': '/bin/bash', 22:08:13 INFO - 'SHLVL': '1', 22:08:13 INFO - 'TERM': 'linux', 22:08:13 INFO - 'TMOUT': '86400', 22:08:13 INFO - 'USER': 'cltbld', 22:08:13 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:13 INFO - '_': '/tools/buildbot/bin/python'} 22:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:13 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 22:08:13 INFO - Cleaning up... 22:08:13 INFO - Return code: 0 22:08:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:08:13 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')]} 22:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:13 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')]} 22:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:13 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 0x7f791abc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', '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 22:08:13 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 22:08:13 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 22:08:13 INFO - Using env: {'DISPLAY': ':0', 22:08:13 INFO - 'HOME': '/home/cltbld', 22:08:13 INFO - 'LANG': 'en_US.UTF-8', 22:08:13 INFO - 'LANGUAGE': 'en_US:en', 22:08:13 INFO - 'LOGNAME': 'cltbld', 22:08:13 INFO - 'MAIL': '/var/mail/cltbld', 22:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:13 INFO - 'MOZ_NO_REMOTE': '1', 22:08:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:13 INFO - 'NO_EM_RESTART': '1', 22:08:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:13 INFO - 'PWD': '/builds/slave/test', 22:08:13 INFO - 'SHELL': '/bin/bash', 22:08:13 INFO - 'SHLVL': '1', 22:08:13 INFO - 'TERM': 'linux', 22:08:13 INFO - 'TMOUT': '86400', 22:08:13 INFO - 'USER': 'cltbld', 22:08:13 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:13 INFO - '_': '/tools/buildbot/bin/python'} 22:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:13 INFO - Downloading/unpacking psutil>=3.1.1 22:08:13 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 22:08:13 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 22:08:13 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 22:08:13 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 22:08:13 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 22:08:13 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 22:08:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:08:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:15 INFO - Installing collected packages: psutil 22:08:15 INFO - Running setup.py install for psutil 22:08:15 INFO - building 'psutil._psutil_linux' extension 22:08:15 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 22:08:15 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 22:08:15 INFO - building 'psutil._psutil_posix' extension 22:08:15 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 22:08:16 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 22:08:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:16 INFO - Successfully installed psutil 22:08:16 INFO - Cleaning up... 22:08:16 INFO - Return code: 0 22:08:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:08:16 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')]} 22:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:16 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')]} 22:08:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:16 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 0x7f791abc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', '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 22:08:16 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 22:08:16 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 22:08:16 INFO - Using env: {'DISPLAY': ':0', 22:08:16 INFO - 'HOME': '/home/cltbld', 22:08:16 INFO - 'LANG': 'en_US.UTF-8', 22:08:16 INFO - 'LANGUAGE': 'en_US:en', 22:08:16 INFO - 'LOGNAME': 'cltbld', 22:08:16 INFO - 'MAIL': '/var/mail/cltbld', 22:08:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:16 INFO - 'MOZ_NO_REMOTE': '1', 22:08:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:16 INFO - 'NO_EM_RESTART': '1', 22:08:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:16 INFO - 'PWD': '/builds/slave/test', 22:08:16 INFO - 'SHELL': '/bin/bash', 22:08:16 INFO - 'SHLVL': '1', 22:08:16 INFO - 'TERM': 'linux', 22:08:16 INFO - 'TMOUT': '86400', 22:08:16 INFO - 'USER': 'cltbld', 22:08:16 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:16 INFO - '_': '/tools/buildbot/bin/python'} 22:08:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:08:16 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 22:08:16 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 22:08:16 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 22:08:16 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 22:08:16 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 22:08:16 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 22:08:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:08:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:08:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:08:18 INFO - Installing collected packages: mozsystemmonitor 22:08:18 INFO - Running setup.py install for mozsystemmonitor 22:08:18 INFO - Successfully installed mozsystemmonitor 22:08:18 INFO - Cleaning up... 22:08:18 INFO - Return code: 0 22:08:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f791abc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', '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 22:08:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:08:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:08:18 INFO - Using env: {'DISPLAY': ':0', 22:08:18 INFO - 'HOME': '/home/cltbld', 22:08:18 INFO - 'LANG': 'en_US.UTF-8', 22:08:18 INFO - 'LANGUAGE': 'en_US:en', 22:08:18 INFO - 'LOGNAME': 'cltbld', 22:08:18 INFO - 'MAIL': '/var/mail/cltbld', 22:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:18 INFO - 'MOZ_NO_REMOTE': '1', 22:08:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:18 INFO - 'NO_EM_RESTART': '1', 22:08:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:18 INFO - 'PWD': '/builds/slave/test', 22:08:18 INFO - 'SHELL': '/bin/bash', 22:08:18 INFO - 'SHLVL': '1', 22:08:18 INFO - 'TERM': 'linux', 22:08:18 INFO - 'TMOUT': '86400', 22:08:18 INFO - 'USER': 'cltbld', 22:08:18 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:18 INFO - '_': '/tools/buildbot/bin/python'} 22:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:18 INFO - Downloading/unpacking jsonschema==2.5.1 22:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:08:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:08:20 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 22:08:20 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 22:08:20 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 22:08:20 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 22:08:20 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 22:08:20 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 22:08:20 INFO - Downloading functools32-3.2.3-2.tar.gz 22:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:08:20 INFO - warning: no files found matching '*.txt' 22:08:20 INFO - no previously-included directories found matching 'build' 22:08:20 INFO - no previously-included directories found matching 'dist' 22:08:20 INFO - no previously-included directories found matching '.git*' 22:08:20 INFO - Installing collected packages: jsonschema, functools32 22:08:20 INFO - Running setup.py install for functools32 22:08:21 INFO - warning: no files found matching '*.txt' 22:08:21 INFO - no previously-included directories found matching 'build' 22:08:21 INFO - no previously-included directories found matching 'dist' 22:08:21 INFO - no previously-included directories found matching '.git*' 22:08:21 INFO - Successfully installed jsonschema functools32 22:08:21 INFO - Cleaning up... 22:08:21 INFO - Return code: 0 22:08:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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')]} 22:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:21 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 0x7f791abc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', '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 22:08:21 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 22:08:21 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 22:08:21 INFO - Using env: {'DISPLAY': ':0', 22:08:21 INFO - 'HOME': '/home/cltbld', 22:08:21 INFO - 'LANG': 'en_US.UTF-8', 22:08:21 INFO - 'LANGUAGE': 'en_US:en', 22:08:21 INFO - 'LOGNAME': 'cltbld', 22:08:21 INFO - 'MAIL': '/var/mail/cltbld', 22:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:21 INFO - 'MOZ_NO_REMOTE': '1', 22:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:21 INFO - 'NO_EM_RESTART': '1', 22:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:21 INFO - 'PWD': '/builds/slave/test', 22:08:21 INFO - 'SHELL': '/bin/bash', 22:08:21 INFO - 'SHLVL': '1', 22:08:21 INFO - 'TERM': 'linux', 22:08:21 INFO - 'TMOUT': '86400', 22:08:21 INFO - 'USER': 'cltbld', 22:08:21 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:21 INFO - '_': '/tools/buildbot/bin/python'} 22:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:08:21 INFO - Cleaning up... 22:08:21 INFO - Return code: 0 22:08:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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')]} 22:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:21 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 0x7f791abc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', '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 22:08:21 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 22:08:21 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 22:08:21 INFO - Using env: {'DISPLAY': ':0', 22:08:21 INFO - 'HOME': '/home/cltbld', 22:08:21 INFO - 'LANG': 'en_US.UTF-8', 22:08:21 INFO - 'LANGUAGE': 'en_US:en', 22:08:21 INFO - 'LOGNAME': 'cltbld', 22:08:21 INFO - 'MAIL': '/var/mail/cltbld', 22:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:21 INFO - 'MOZ_NO_REMOTE': '1', 22:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:21 INFO - 'NO_EM_RESTART': '1', 22:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:21 INFO - 'PWD': '/builds/slave/test', 22:08:21 INFO - 'SHELL': '/bin/bash', 22:08:21 INFO - 'SHLVL': '1', 22:08:21 INFO - 'TERM': 'linux', 22:08:21 INFO - 'TMOUT': '86400', 22:08:21 INFO - 'USER': 'cltbld', 22:08:21 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:21 INFO - '_': '/tools/buildbot/bin/python'} 22:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:21 INFO - Downloading/unpacking blobuploader==1.2.4 22:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:23 INFO - Downloading blobuploader-1.2.4.tar.gz 22:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:08:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:08:23 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 22:08:23 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 22:08:23 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 22:08:23 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 22:08:23 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 22:08:23 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 22:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:08:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:08:24 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 22:08:24 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 22:08:24 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 22:08:24 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 22:08:24 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 22:08:24 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 22:08:24 INFO - Downloading docopt-0.6.1.tar.gz 22:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:08:24 INFO - Installing collected packages: blobuploader, requests, docopt 22:08:24 INFO - Running setup.py install for blobuploader 22:08:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:08:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:08:24 INFO - Running setup.py install for requests 22:08:24 INFO - Running setup.py install for docopt 22:08:24 INFO - Successfully installed blobuploader requests docopt 22:08:24 INFO - Cleaning up... 22:08:24 INFO - Return code: 0 22:08:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08:24 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')]} 22:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:24 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')]} 22:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:24 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 0x7f791abc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', '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 22:08:24 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 22:08:24 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 22:08:24 INFO - Using env: {'DISPLAY': ':0', 22:08:24 INFO - 'HOME': '/home/cltbld', 22:08:24 INFO - 'LANG': 'en_US.UTF-8', 22:08:24 INFO - 'LANGUAGE': 'en_US:en', 22:08:24 INFO - 'LOGNAME': 'cltbld', 22:08:24 INFO - 'MAIL': '/var/mail/cltbld', 22:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:24 INFO - 'MOZ_NO_REMOTE': '1', 22:08:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:24 INFO - 'NO_EM_RESTART': '1', 22:08:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:24 INFO - 'PWD': '/builds/slave/test', 22:08:24 INFO - 'SHELL': '/bin/bash', 22:08:24 INFO - 'SHLVL': '1', 22:08:24 INFO - 'TERM': 'linux', 22:08:24 INFO - 'TMOUT': '86400', 22:08:24 INFO - 'USER': 'cltbld', 22:08:24 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:24 INFO - '_': '/tools/buildbot/bin/python'} 22:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:08:25 INFO - Running setup.py (path:/tmp/pip-3ViQ16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:08:25 INFO - Running setup.py (path:/tmp/pip-IUDAoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:08:25 INFO - Running setup.py (path:/tmp/pip-ONtKfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:08:25 INFO - Running setup.py (path:/tmp/pip-Pfv8vX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:08:25 INFO - Running setup.py (path:/tmp/pip-oDlO_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:08:25 INFO - Running setup.py (path:/tmp/pip-LPE2YE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:08:25 INFO - Running setup.py (path:/tmp/pip-ngXdll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:08:25 INFO - Running setup.py (path:/tmp/pip-sNFjkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:08:25 INFO - Running setup.py (path:/tmp/pip-BmI9lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:08:25 INFO - Running setup.py (path:/tmp/pip-EDbn4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:08:25 INFO - Running setup.py (path:/tmp/pip-U0lgRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:08:25 INFO - Running setup.py (path:/tmp/pip-_OiEep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:08:26 INFO - Running setup.py (path:/tmp/pip-rm9l44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:08:26 INFO - Running setup.py (path:/tmp/pip-jE8LbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:26 INFO - Running setup.py (path:/tmp/pip-S8f8_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:08:26 INFO - Running setup.py (path:/tmp/pip-uhPgTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:08:26 INFO - Running setup.py (path:/tmp/pip-KNpqZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:08:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:08:26 INFO - Running setup.py install for manifestparser 22:08:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:08:26 INFO - Running setup.py install for mozcrash 22:08:26 INFO - Running setup.py install for mozdebug 22:08:26 INFO - Running setup.py install for mozdevice 22:08:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:08:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:08:26 INFO - Running setup.py install for mozfile 22:08:26 INFO - Running setup.py install for mozhttpd 22:08:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:08:26 INFO - Running setup.py install for mozinfo 22:08:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Running setup.py install for mozInstall 22:08:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Running setup.py install for mozleak 22:08:27 INFO - Running setup.py install for mozlog 22:08:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Running setup.py install for moznetwork 22:08:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Running setup.py install for mozprocess 22:08:27 INFO - Running setup.py install for mozprofile 22:08:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Running setup.py install for mozrunner 22:08:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:08:27 INFO - Running setup.py install for mozscreenshot 22:08:27 INFO - Running setup.py install for moztest 22:08:28 INFO - Running setup.py install for mozversion 22:08:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:08:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:08:28 INFO - Cleaning up... 22:08:28 INFO - Return code: 0 22:08:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08:28 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')]} 22:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:28 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')]} 22:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:28 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 0x7f791abc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', '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 22:08:28 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 22:08:28 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 22:08:28 INFO - Using env: {'DISPLAY': ':0', 22:08:28 INFO - 'HOME': '/home/cltbld', 22:08:28 INFO - 'LANG': 'en_US.UTF-8', 22:08:28 INFO - 'LANGUAGE': 'en_US:en', 22:08:28 INFO - 'LOGNAME': 'cltbld', 22:08:28 INFO - 'MAIL': '/var/mail/cltbld', 22:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:28 INFO - 'MOZ_NO_REMOTE': '1', 22:08:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:28 INFO - 'NO_EM_RESTART': '1', 22:08:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:28 INFO - 'PWD': '/builds/slave/test', 22:08:28 INFO - 'SHELL': '/bin/bash', 22:08:28 INFO - 'SHLVL': '1', 22:08:28 INFO - 'TERM': 'linux', 22:08:28 INFO - 'TMOUT': '86400', 22:08:28 INFO - 'USER': 'cltbld', 22:08:28 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:28 INFO - '_': '/tools/buildbot/bin/python'} 22:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:08:28 INFO - Running setup.py (path:/tmp/pip-aXWrBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:08:28 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)) 22:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:08:28 INFO - Running setup.py (path:/tmp/pip-lGvcdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:08:28 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)) 22:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:08:28 INFO - Running setup.py (path:/tmp/pip-R5u1xC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:08:28 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)) 22:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:08:28 INFO - Running setup.py (path:/tmp/pip-v7nJYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:08:28 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)) 22:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:08:28 INFO - Running setup.py (path:/tmp/pip-x1EAQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:08:28 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)) 22:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:08:28 INFO - Running setup.py (path:/tmp/pip-uKVqod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:08:28 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)) 22:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:08:28 INFO - Running setup.py (path:/tmp/pip-GfnNpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:08:28 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)) 22:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:08:28 INFO - Running setup.py (path:/tmp/pip-uprHAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:08:28 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)) 22:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:08:28 INFO - Running setup.py (path:/tmp/pip-yzC81f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:08:29 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)) 22:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:08:29 INFO - Running setup.py (path:/tmp/pip-ZCMAP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:08:29 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)) 22:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:08:29 INFO - Running setup.py (path:/tmp/pip-TCOieB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:08:29 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)) 22:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:08:29 INFO - Running setup.py (path:/tmp/pip-oWRKPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:08:29 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)) 22:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:08:29 INFO - Running setup.py (path:/tmp/pip-h8n_B3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:08:29 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)) 22:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:08:29 INFO - Running setup.py (path:/tmp/pip-e3fQZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:08:29 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)) 22:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:29 INFO - Running setup.py (path:/tmp/pip-6zo9ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:29 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)) 22:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:08:29 INFO - Running setup.py (path:/tmp/pip-_0U7Gr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:08:29 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)) 22:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:08:29 INFO - Running setup.py (path:/tmp/pip-PAJhHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:08:29 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)) 22:08:29 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)) 22:08:29 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)) 22:08:29 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)) 22:08:29 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)) 22:08:29 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)) 22:08:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:31 INFO - Downloading blessings-1.6.tar.gz 22:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:08:31 INFO - Installing collected packages: blessings 22:08:31 INFO - Running setup.py install for blessings 22:08:31 INFO - Successfully installed blessings 22:08:31 INFO - Cleaning up... 22:08:31 INFO - Return code: 0 22:08:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:08:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:08:32 INFO - Reading from file tmpfile_stdout 22:08:32 INFO - Current package versions: 22:08:32 INFO - argparse == 1.2.1 22:08:32 INFO - blessings == 1.6 22:08:32 INFO - blobuploader == 1.2.4 22:08:32 INFO - docopt == 0.6.1 22:08:32 INFO - functools32 == 3.2.3-2 22:08:32 INFO - jsonschema == 2.5.1 22:08:32 INFO - manifestparser == 1.1 22:08:32 INFO - mozInstall == 1.12 22:08:32 INFO - mozcrash == 0.17 22:08:32 INFO - mozdebug == 0.1 22:08:32 INFO - mozdevice == 0.48 22:08:32 INFO - mozfile == 1.2 22:08:32 INFO - mozhttpd == 0.7 22:08:32 INFO - mozinfo == 0.9 22:08:32 INFO - mozleak == 0.1 22:08:32 INFO - mozlog == 3.4 22:08:32 INFO - moznetwork == 0.27 22:08:32 INFO - mozprocess == 0.23 22:08:32 INFO - mozprofile == 0.28 22:08:32 INFO - mozrunner == 6.12 22:08:32 INFO - mozscreenshot == 0.1 22:08:32 INFO - mozsystemmonitor == 0.3 22:08:32 INFO - moztest == 0.7 22:08:32 INFO - mozversion == 1.4 22:08:32 INFO - psutil == 3.1.1 22:08:32 INFO - requests == 1.2.3 22:08:32 INFO - wsgiref == 0.1.2 22:08:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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')]} 22:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:32 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 0x7f791abc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', '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 22:08:32 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 22:08:32 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 22:08:32 INFO - Using env: {'DISPLAY': ':0', 22:08:32 INFO - 'HOME': '/home/cltbld', 22:08:32 INFO - 'LANG': 'en_US.UTF-8', 22:08:32 INFO - 'LANGUAGE': 'en_US:en', 22:08:32 INFO - 'LOGNAME': 'cltbld', 22:08:32 INFO - 'MAIL': '/var/mail/cltbld', 22:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:32 INFO - 'MOZ_NO_REMOTE': '1', 22:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:32 INFO - 'NO_EM_RESTART': '1', 22:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:32 INFO - 'PWD': '/builds/slave/test', 22:08:32 INFO - 'SHELL': '/bin/bash', 22:08:32 INFO - 'SHLVL': '1', 22:08:32 INFO - 'TERM': 'linux', 22:08:32 INFO - 'TMOUT': '86400', 22:08:32 INFO - 'USER': 'cltbld', 22:08:32 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:32 INFO - '_': '/tools/buildbot/bin/python'} 22:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:32 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)) 22:08:32 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)) 22:08:32 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)) 22:08:32 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)) 22:08:32 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)) 22:08:32 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)) 22:08:32 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)) 22:08:32 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)) 22:08:32 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)) 22:08:32 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)) 22:08:32 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)) 22:08:32 INFO - Cleaning up... 22:08:32 INFO - Return code: 0 22:08:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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')]} 22:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f791abc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', '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 22:08: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'] in /builds/slave/test/build 22:08: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 22:08:32 INFO - Using env: {'DISPLAY': ':0', 22:08:32 INFO - 'HOME': '/home/cltbld', 22:08:32 INFO - 'LANG': 'en_US.UTF-8', 22:08:32 INFO - 'LANGUAGE': 'en_US:en', 22:08:32 INFO - 'LOGNAME': 'cltbld', 22:08:32 INFO - 'MAIL': '/var/mail/cltbld', 22:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:32 INFO - 'MOZ_NO_REMOTE': '1', 22:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:32 INFO - 'NO_EM_RESTART': '1', 22:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:32 INFO - 'PWD': '/builds/slave/test', 22:08:32 INFO - 'SHELL': '/bin/bash', 22:08:32 INFO - 'SHLVL': '1', 22:08:32 INFO - 'TERM': 'linux', 22:08:32 INFO - 'TMOUT': '86400', 22:08:32 INFO - 'USER': 'cltbld', 22:08:32 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:32 INFO - '_': '/tools/buildbot/bin/python'} 22:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:08:32 INFO - Cleaning up... 22:08:32 INFO - Return code: 0 22:08:32 INFO - Running post-action listener: _resource_record_post_action 22:08:32 INFO - Running post-action listener: _start_resource_monitoring 22:08:32 INFO - Starting resource monitoring. 22:08:32 INFO - [mozharness: 2016-10-27 05:08:32.483874Z] Finished create-virtualenv step (success) 22:08:32 INFO - [mozharness: 2016-10-27 05:08:32.484335Z] Running install step. 22:08:32 INFO - Running pre-action listener: _resource_record_pre_action 22:08:32 INFO - Running main action method: install 22:08:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:08:32 INFO - Reading from file tmpfile_stdout 22:08:32 INFO - Detecting whether we're running mozinstall >=1.0... 22:08:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:08:32 INFO - Reading from file tmpfile_stdout 22:08:32 INFO - Output received: 22:08:32 INFO - Usage: mozinstall [options] installer 22:08:32 INFO - Options: 22:08:32 INFO - -h, --help show this help message and exit 22:08:32 INFO - -d DEST, --destination=DEST 22:08:32 INFO - Directory to install application into. [default: 22:08:32 INFO - "/builds/slave/test"] 22:08:32 INFO - --app=APP Application being installed. [default: firefox] 22:08:32 INFO - mkdir: /builds/slave/test/build/application 22:08:32 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'] 22:08:32 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 22:08:48 INFO - Reading from file tmpfile_stdout 22:08:48 INFO - Output received: 22:08:48 INFO - /builds/slave/test/build/application/firefox/firefox 22:08:48 INFO - Running post-action listener: _resource_record_post_action 22:08:48 INFO - [mozharness: 2016-10-27 05:08:48.739877Z] Finished install step (success) 22:08:48 INFO - [mozharness: 2016-10-27 05:08:48.740122Z] Running run-tests step. 22:08:48 INFO - Running pre-action listener: _resource_record_pre_action 22:08:48 INFO - Running main action method: run_tests 22:08:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:08:48 INFO - Python 2.7.3 22:08:48 INFO - Return code: 0 22:08:48 INFO - grabbing minidump binary from tooltool 22:08:48 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')]} 22:08:48 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 0x1f4cf60>, '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 0x1f4dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4e1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:08:48 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 22:08:48 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 22:08:48 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 22:08:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:08:48 INFO - Return code: 0 22:08:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:08:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:08:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:08:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:08:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:08:48 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/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 22:08:48 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/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 22:08:48 INFO - Using env: {'DISPLAY': ':0', 22:08:48 INFO - 'HOME': '/home/cltbld', 22:08:48 INFO - 'LANG': 'en_US.UTF-8', 22:08:48 INFO - 'LANGUAGE': 'en_US:en', 22:08:48 INFO - 'LOGNAME': 'cltbld', 22:08:48 INFO - 'MAIL': '/var/mail/cltbld', 22:08:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:08:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:48 INFO - 'MOZ_NO_REMOTE': '1', 22:08:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:48 INFO - 'NO_EM_RESTART': '1', 22:08:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:48 INFO - 'PWD': '/builds/slave/test', 22:08:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:08:48 INFO - 'SHELL': '/bin/bash', 22:08:48 INFO - 'SHLVL': '1', 22:08:48 INFO - 'TERM': 'linux', 22:08:48 INFO - 'TMOUT': '86400', 22:08:48 INFO - 'USER': 'cltbld', 22:08:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348', 22:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:48 INFO - '_': '/tools/buildbot/bin/python'} 22:08:48 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/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 22:08:49 INFO - mozversion application_buildid: 20161026185134 22:08:49 INFO - mozversion application_changeset: 88cbb2726969ee5a4204ae9df3025c39c864de15 22:08:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:08:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:08:49 INFO - mozversion application_name: Firefox 22:08:49 INFO - mozversion application_remotingname: firefox-dev 22:08:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:08:49 INFO - mozversion application_vendor: Mozilla 22:08:49 INFO - mozversion application_version: 51.0a2 22:08:49 INFO - mozversion platform_buildid: 20161026185134 22:08:49 INFO - mozversion platform_changeset: 88cbb2726969ee5a4204ae9df3025c39c864de15 22:08:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:08:49 INFO - mozversion platform_version: 51.0a2 22:08:49 INFO - using testdate: 1477544929 22:08:49 INFO - actual date: 1477544929 22:08:49 INFO - starting webserver on 'localhost:37955' 22:08:49 INFO - SUITE-START | Running 2 tests 22:08:49 INFO - TEST-START | dromaeo_css 22:08:49 INFO - operating with platform_type : linux_ 22:08:49 INFO - Initialising browser for dromaeo_css test... 22:08:49 INFO - TEST-INFO | started process 17133 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg5F6Dj/profile http://localhost:37955/getInfo.html) 22:08:53 INFO - PROCESS | 17133 | __metrics Screen width/height:1600/1200 22:08:53 INFO - PROCESS | 17133 | colorDepth:24 22:08:53 INFO - PROCESS | 17133 | Browser inner width/height: 1024/697 22:08:53 INFO - PROCESS | 17133 | __metrics 22:08:53 INFO - PROCESS | 17133 | 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" 22:08:53 INFO - PROCESS | 17133 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:08:54 INFO - PROCESS | 17133 | console.error: PushService: 22:08:54 INFO - PROCESS | 17133 | stateChangeProcessEnqueue: Error transitioning state 22:08:54 INFO - PROCESS | 17133 | UnknownError 22:08:54 INFO - TEST-INFO | 17133: exit 0 22:08:54 INFO - Browser initialized. 22:08:54 INFO - Running cycle 1/1 for dromaeo_css test... 22:08:54 INFO - TEST-INFO | started process 17225 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg5F6Dj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:09:06 INFO - PROCESS | 17225 | RSS: Main: 145063936 22:09:06 INFO - PROCESS | 17225 | 22:09:57 INFO - PROCESS | 17225 | 1477544997618 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] 22:12:06 INFO - PROCESS | 17225 | Cycle 1(1): loaded http://localhost:37955/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37955/tests/dromaeo/cssquery-ext.html) 22:12:07 INFO - PROCESS | 17225 | RSS: Main: 239534080 22:12:07 INFO - PROCESS | 17225 | 22:15:10 INFO - PROCESS | 17225 | Cycle 1(1): loaded http://localhost:37955/tests/dromaeo/cssquery-ext.html (next: http://localhost:37955/tests/dromaeo/cssquery-jquery.html) 22:15:10 INFO - PROCESS | 17225 | RSS: Main: 227008512 22:15:10 INFO - PROCESS | 17225 | 22:18:12 INFO - PROCESS | 17225 | Cycle 1(1): loaded http://localhost:37955/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37955/tests/dromaeo/cssquery-mootools.html) 22:18:13 INFO - PROCESS | 17225 | RSS: Main: 175181824 22:18:13 INFO - PROCESS | 17225 | 22:21:15 INFO - PROCESS | 17225 | Cycle 1(1): loaded http://localhost:37955/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37955/tests/dromaeo/cssquery-prototype.html) 22:21:15 INFO - PROCESS | 17225 | RSS: Main: 201080832 22:21:15 INFO - PROCESS | 17225 | 22:24:18 INFO - PROCESS | 17225 | Cycle 1(1): loaded http://localhost:37955/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37955/tests/dromaeo/cssquery-yui.html) 22:24:18 INFO - PROCESS | 17225 | RSS: Main: 176934912 22:24:18 INFO - PROCESS | 17225 | 22:27:21 INFO - PROCESS | 17225 | Cycle 1(1): loaded http://localhost:37955/tests/dromaeo/cssquery-yui.html (next: http://localhost:37955/tests/dromaeo/cssquery-dojo.html) 22:27:21 INFO - PROCESS | 17225 | RSS: Main: 266964992 22:27:21 INFO - PROCESS | 17225 | 22:27:21 INFO - PROCESS | 17225 | __start_tp_report 22:27:21 INFO - PROCESS | 17225 | _x_x_mozilla_page_load 22:27:21 INFO - PROCESS | 17225 | _x_x_mozilla_page_load_details 22:27:21 INFO - PROCESS | 17225 | |i|pagename|runs| 22:27:21 INFO - PROCESS | 17225 | |0;dojo.html;3082.4143412751578;3118.4854499007656;3119.5788568543253;3151.3382189740155;3162.414953233652;5427.185922111686;5434.565234781218;5473.042217611922;5482.753276102575;5487.396386397493;5468.6171967962255;5483.890322193553;5490.56075513959;5500.642458240211;5502.119660854264;4946.579540739032;4976.900461990758;5031.748412579374;5037.5718063964605;5052.772625231855;4809.663323567352;4832.975835120819;5014.874628134307;5057.342545469102;5076.593872490211;4860.05228980349;4881.780319885596;4907.631927605426;4930.778114984834;4966;4970.080535100999;4985.152524070917;5023.472535383805;5038.3198268234055;5072.619553533463;4751.619870410359;4950.8267210647455;4976.02967421353;4990.575801056914;5013.298138260647;5160.458151894035;5314.149736042254;5329.786808527654;5336.759845806948;5389.757460914268;5702.144678298247;5720.799772007959;5790.073588225863;5800.651960882361;5806.709664516799;5608.887822243532;5841.941580584183;5856.648601083949;5864.501517371029;5869.266341707286;5286.841394758125;5369.087255166631;5419.34967803866;5420.512153906167;5449.536789372948;5919.763209471659;5951.5238780897425;5953.583249172516;5954.940450595525;5962.821115366459;5418.051840927822;5714;5714;5723.112917497794;5724.3130824300515;5526.778928842882;5591.161325801162;5617.269754931915;5623.550115990711;5701;5648.604597678205;5649.096144616841;5650.29371328584;5654.095344744904;5688.146777983336;5600.803971861047;5650.604457688005;5653.462921022497;5670.262865827416;5681.261436013375;4227.915441691149;4266.765327906995;4309.741415515016;4314.611684948307;4353.782310884443;3882.417637354363;3919.5884432134785;3940.3695408734466;3947.2105578883766;3953.4069889516804;5503.834884953458;5510.448955104457;5521.613487055867;5527.2538207342295;5538.47384498472;5540.473655002768;5589.832305030855;5724.084146536534;5859.238299021185;5898.852528686817;5407.270018547524;5506.972465137648;5509.393966663663;5545.140503222007;5551.777928882881;4925.950740492621;4938.703677779345;4952.727599982036;4961.305417241517;4980.601551875841;5778.191053252632;5778.3643799182055;5815.651060936358;6005.459508644243;6010.459058684739;5651.406602306699;5701.030824759718;5733;5744.885102297847;5758.683272420057;5785.8553536161935;5837.357890632112;5838.036723940502;5840.445157710181;5840.737166827588;5513.338399392099;5589.832305030855;5599.832005039692;5600.047991841316;5600.075987462186;5633.267675202197;5679;5718.342390625131;5736.885262294647;5849.970750146222;5653.858653533694;5664.801731939362;5665.235193248858;5668.603197776116;5677.432256774289;5666.291713535808;5671.064274394844;5672.12082127271;5677.886442271214;5678.6876721779045;5371.274877891435;5385.084535629031;5510.752016159363;5590.99362114807;5613.354464236665;5701.543876489954;5705.429457054262;5724;5726.141078838207;5739.8852022960145;5266.920996184984;5523.8895222096135;5594.664320140804;5603.887922241451;5606.355269143937 22:27:21 INFO - PROCESS | 17225 | |1;ext.html;575.157394417179;587.3157771196556;609.6464050850507;627.1063734178794;627.1815281058217;34283.48574771377;46460.535394646045;47414;47543.286850697266;47691.284630730566;48980;49360.75319623397;49386.50613493864;49478.257826132634;49620.25569616459;8346.707865224718;8543.102974187714;9227.630894764217;9377.343585948985;9386.202172815285;11005.394703291317;11263.493142808553;11268.042216411615;11621.767564648702;11625.302481851073;11335.546578136864;11420;11432.714182145472;11440.542378304857;11441.084713222965;8446.408751387406;8639.65441382344;11256.943715281412;11348.319100853892;11349.943250283779;10712.678619641423;11034.448277586089;11056.668299951014;11336.376499292535;11358.375289359083;46994.5300546997;47299.76350118262;47325.16049037034;47352.28971565429;47513.287300690514;3782.7541209821275;3823.0633494793674;3823.445600387956;3861.7876016819064;4038.1519880825063;35151.4727279091;35308.82345588256;35414.82292588546;35484.75175368507;35599.4660080099;4563.77181140945;4635.860924172281;4640.86077417678;4668.509806470306;4689.624830013625;409165;410911;414454;415209;415502;66646;67155.32844671588;67156;67159.66420167868;67234.32765672379;40868.1826363477;41562.1687566247;41617.37573936393;43276.78361608172;70840.64579677068;19631.509212269808;19773.209071637262;19851.404457866287;19860.205591776168;19864.90067549653;13956.09285396446;14196.432142714382;14490.420383184555;14555.12669239849;14630.561083167522;20061.197552098045;20908.37274881756;21036.684449733268;21158.153673853183;21302.893485532473;7310.587506122966;7402.408482176358;7597;7606.125295591078;7654.655540500691;988.9950550247203;1112.4382186995515;1124.3815901254277;1183.1185766603935;1231.2427856868142;35885.282294353965;35898;36010.639893601256;36137.63862361343;36578.634213657526;1185.247367921376;1226.7669142862828;1231.445849367788;1264.1972347559183;1322.2793577500286;16393;18821.717674234897;18858.622827543648;19457.902710486356;20648.690269645966;658430;658615;659446;664236;666247;63736.36263637397;64216.67891660512;64252.678736606016;64286.67856660687;64317.35682643207;1335.3990704183157;1380.5444203412849;1433.849445808196;1453.2225259486124;1469.2947385254924;32317.192070198435;36816.44775328319;36951.630483695895;37322.626773731914;37371.62628373681;54798.72600636971;55820.72089639526;55829;55843.16235256556;55886.441135588124;427692;428036;428399;428904;429125;31307.686923131387;31387.21531961628;31410.52884206693;31483.527747083353;33207.667923320456;52946.704389815306;53182.73408632932;53223.20165197448;53287.46712532825;53762.731186343815;44308.33537496876;44632.77683611561;45060.32409513926;45200;46169;44491.555084448744;45422.54577454183;45775;46454.76772616115;46586;44155.55844441643;44296;45025;45398.09203815839;45708.314375283735;36506.26987460289;38710.419343709305;39442.40836387514;39556.80221598873;39698.40452393159;29152.27119322034;29422.411551769274;29802.552961705158;30026;30033 22:27:21 INFO - PROCESS | 17225 | |2;jquery.html;384.91916697493525;407.5231978585055;421.68163036907134;424.77274658057945;430.642566669664;14326.498572549963;16369.590760231;19894.90052549739;20947.895260523677;21370.89314553429;19605.705914411294;20015.499612509695;20618.896905515452;20765.480862978395;20979;5647.915281270774;5682.715864206793;5851.590388672795;5919.437653422918;5974.163617093611;4135.338345864662;5995.970020149894;6036.3661815509395;6039.1243269725865;6044.214252147215;4331.891702707426;6039.818805435822;6066.454019138277;6081.057436097412;6088.908666369985;5862.472377486026;5978.491828194608;6020.909686354708;6067.848303792396;6074.392560743934;5844.649321040752;5846;5850.853728656775;5851.23933888596;5854.034084376073;14785.926070369687;14844.109353438827;15151.848481515264;15192.696146077127;15298.770518442121;2527.1155095716535;2625.225558460259;2664.467106578692;2945.617069780936;2988.223062003873;10448;10810.35137891729;10833.241673082888;10903.454827258605;10982.725431864188;1993.3820515640198;2025.3721346382667;2036.5825005873908;2059.794020597943;2112.640851055324;84338.36097034384;86726.56636716776;88366.11633883644;88417.11582884153;91170.5441472795;11441.542338306375;11447.54209831614;11554.364509951865;11593.594224202112;11799;16509.422170223985;16705.83294167067;16726.414575489798;16830.663386732198;16839.91580042092;8493.065762766091;8575.571221438902;8589.14108589136;8749.606267717969;8970.641174353083;7841.607919603997;8001.719939802079;8014.839703205903;8162.877556836652;8238.176182381834;4890.657653964259;4940.827071052496;4958.925616115761;5002.1496345621335;5033;2056.8765874047604;2060.7733149353558;2090.4773806548365;2107.8419118566167;2116.850972094712;958.6405098088217;985.4924713772413;1028.6296933104227;1038.7922415516928;1059.7465780055156;20226.595468090556;21046.15815367368;21096;21332.122433907647;21578.46053848635;988.7182153086336;1082.107261509258;1091.7871015151968;1103.1340397787858;1123.6292023632182;10279.434631095211;11658.6502404928;11674.824877626843;11689.473973671034;11764;527658;533185;543225;559834;561735;92232;97692;106224;106844;109371;1085.2023762534532;1088.7713580148259;1145.3070892110154;1147.5180424221846;1152.809786385237;18772.718409224144;19044.523886902938;19215.90392048031;19216.807831921502;19298.13158407847;44937;45212.32181517214;45725;46065.76967115143;46745.766271168424;64555.354446454934;65365.346346535924;65486.67256663686;66089;66983.66508167428;9101.681441149529;9123.49820759838;9240.907590924005;9429.764255893657;9449.338546301968;11012.889871101186;13583.79624305656;13970;14086.577402677936;14427.78358324647;12375.876241237473;12421.19262247951;12628.558000469939;12709.745805084032;12715.427805748948;12119.394030298627;12258.816117758419;12318.81521777192;12330;12517.687057823263;12332.50669973191;12513.374331283581;12535.185212961129;12757.936210318889;12949.741005180033;8067.757967260992;8347.95826020866;8890.4665720057;9224.40041397307;9336.39313444624;9278.62885484573;11831.467583958914;12192.939035304767;12496.43766030549;12991.610251692464 22:27:21 INFO - PROCESS | 17225 | |3;mootools.html;1405.2060585769034;1525.9923700381496;1563.0855949269676;1690.8309169083093;1794.4706311638065;7539.170691223961;8425.536595487245;8769.60536775845;8776.78058048549;8780.736577902666;8456.281216096639;8633.179847914455;8636.13638636136;8847.292216622654;8873.68942087027;2415.04605680756;2461.027893981876;2461.7415171407015;2462.4705688277018;2464.963025554618;2410.6745589345387;2446.5473887330813;2450.019992003204;2450.3506570758764;2451.901923923041;2370.6444033394955;2380.976190238093;2401.855888646678;2403.663487111806;2418.8064954803663;2334.6147885598857;2345.6247000479925;2350.1656911796235;2362.9409264768346;2398.940026499334;2334.136369543263;2338.4621537046405;2342.2621874109773;2347.248880358286;2351.494428697838;8589.95705021477;8686.305095592337;8734.082921293237;8738.825223495558;8745.169208925143;5295.920561191547;5477.534409575191;5490.533304669108;5510.201020851994;5522.6134170608075;3418.7265018798435;3433.364827506919;3464.1685995361186;3471.1669199391963;3508.315878403687;1182.8225766135063;1198.5385626533848;1203.705092252395;1220.3471142938486;1239.603326935372;11538.826917596243;11679.708007299885;11708.473118709593;11724.472398741991;11751.588694395656;4406.9338959914985;4555.840545580889;4649.860504184812;4701.858944231679;4710.929336059962;10045.547950342252;10064.899351006543;10064.949675251577;10080.949595252123;10084.848727269096;5286.259923610698;5290.894182116336;5295.523402893758;5295.735213239323;5297.3643162819935;4274.123804620045;4371.344298355272;4812.687175333662;4934.753262336869;5011.599072074226;3985.5416627088257;4014.979925100356;4072.1041370898356;4142.378643203483;4179.059711564873;1302.5050480817417;1303.1985329022773;1330.8070329802124;1334.4462048249868;1365.1945352242026;889.3641046651546;976.2531663277598;1052.1740433759462;1062.1555863088863;1075.6450371377427;6206.004166645803;6322.4942004639515;6437.645929473924;6545.214574251025;6635.601863888086;982.7051884434641;1021.9540120694587;1050.3015494696094;1064.308199670205;1077.2836064017497;4245.51392672954;4453.0648563801315;4529.184746745552;4553.248713962159;4610.147122782296;10315.381077135246;10715.571377144983;10724.678259652223;10773.89226107729;10777.245592808586;10547.103496202908;10622.681319560426;10831.729206769895;10971.341719496855;11327.773444531063;8306.667733290722;8338.374621903382;8515.957420212982;8541.359398045048;8545.487270763773;6414.562466262791;6602.306757790363;6649.168853893263;6727.798166055026;6799.388055075067;9987.350822196824;10006.249531285328;10246.436445995541;10277.845832312372;10444.321119127233;10458.26792124544;10500.842487362848;10564.577416903237;10576.84134737964;10642.89357106419;8029.397795165269;8076.878846817185;8106.432549721463;8107.83784324322;8170.468919520212;8702.738917832476;8801.911980880366;8803.647854085764;8803.691870784492;8804.60379282921;7520.774376768706;7655.732049378245;7669.424793140648;7697.653605587874;7873.015873015873;7728.111267204344;7807.18024607408;7880.17258187882;7884.605769711607;7900.446968712365;7247.782566523013;7301.3428791406905;7330.376917962033;7343.596102214429;7350.191478937206;5540.36285827135;5598.664080155041;5685.431456854281;5732.34078081026;5806.274215723035;5531.363893152339;5608.663480191201;5679;5770.1056336266265;5790.507806836466 22:27:21 INFO - PROCESS | 17225 | |4;prototype.html;338.2862160840626;346.59448445318975;350.771998201169;350.90867402378603;352.91706448984485;5243.501867322599;5416.268803711498;5511.669299842012;5542.279503664528;5544.47327503887;5228.353068783331;5348.732563371826;5389.73051347432;5441.7279136043135;5446.91829622556;4288.078063216408;4301.161273551643;4307.138572285539;4313.15893400787;4338.850204695758;4202.285611446062;4300.956990430087;4302.354646802989;4320.97839510802;4331.6534677225745;4302.20409224293;4303.827846886121;4313.676474264427;4324.567543245682;4333.198358303724;4292.356146578023;4299.205516414356;4308.612224899758;4315.784210789479;4327.956720432787;3734.2531493701367;3865.903352416184;3872.8644497442738;3883.203943191639;3903.8048097595292;5216.58267338612;5233.816816411408;5274.604404669665;5286.3392075990505;5288.603354748371;4910.828121015783;4922.975385123087;4936.851894443173;4939;4939.950600493985;5438.117797567295;5669.801556945528;5685.857853553653;5699.857503562403;5752;4158.4178215049915;4178.937315940263;4185.58144185582;4193.454850869398;4193.685473589493;5851.64890106595;5883.941160588382;5885.676287804169;5904.667656275747;5947.286325640907;4220.261454245495;4334.804933777988;4357.847475338412;4363.934540981888;4372.825086996548;4325.15659446405;4344.413504176959;4349.716833534115;4361.3208914567485;4367.737935723803;4902.828401005948;4904.485029071896;4904.950950490449;4907.87730306738;4914.90170196594;5588.832335029955;5695.857603559944;5701.201831743476;5701.971490142523;5738.221151443066;1481.1557412275115;1500.647347873249;1500.7993605115864;1503.2934520775218;1506.412499125342;938;946.1484663802632;973.8052389522123;975.5073687787764;980.7302991677345;4840.878978025577;4847.636427267969;4848.539388758062;4856.611471082305;4903.754812259373;5118.283440318359;5120.897582048413;5168.9483105168465;5198.870028249324;5212.40057393397;4654.581087702123;4851.466338702739;4864.318995340655;4874.0495603357;4923.507649235119;2700.6361787297683;2724.891004359843;2732.8410555660807;2734.480448714738;2746.368335282896;5234.92147617786;5248.475152484797;5256.2115682647145;5261.289725886956;5281.234221037928;4859.854204373874;5186.144286192812;5220.207189153784;5234.141879632752;5259;5125.692458452505;5238.685678859204;5238.895222095537;5244.5279924806955;5301.814436494704;5032.73526912898;5062.772175252049;5097.541221290102;5110.437851836221;5117.948820511896;4731.4558825735485;5012.746813296676;5043.924341134959;5048.823291184791;5087;5188.662736922088;5209.926143575309;5215.452377500308;5227.869303267449;5272.920906186334;3913.76517408945;4124.257633625976;4182.728122672017;4196.1607678463815;4273.679474039522;5733.627314224562;5807;5809.302883653989;5815;5828.650280982985;5539.778408863762;5710.857228569319;5715.628484148517;5719.256496655408;5726.455986681259;5629.915551266652;5647.5199608032135;5651.519620832111;5658.8965151795;5664.49019588239;5567.66594004361;5600.747966341443;5646.971765141148;5648.943510565006;5651;5415.6479828811;5586.6871285247125;5620.353659329229;5645.266115404971;5682.744276507649;5502.339719233718;5524.364698059774;5555.000299838042;5576.992282778116;5599.580031497735 22:27:21 INFO - PROCESS | 17225 | |5;yui.html;802.546561192926;807.2613558593885;821.8520666280067;823.1521532821189;866.2766589897435;16296.266667999938;17152;18429.723554146698;18589.349372771954;18603.441896743087;16191.514254572352;16486.83513164868;16520.66958660826;16525.83474165258;16548.089855057966;846.1496196322703;865.9480431174118;873.7640836974014;876.119499902597;877.302544477139;721.3327671903495;739.7299985505279;830.0993422136988;857.6355049104137;872.5593575244493;887.4010043220833;888.2183678363064;892.8883889513811;893.0310612984919;895.3777124898198;887.1128871128807;887.1350433327518;887.5384799904047;888.0630934364243;888.5113187746714;544.7902557515386;561.5760101123625;634.0932466572846;650.5186162239909;658.6114192626374;14523.20122393268;14524.927375363055;14550.636234094127;14562.70874582513;14577.562673119717;14849.183294918776;15308.617284567863;15551.300191491297;15567.766483502757;15699.293531791096;13409.249129786176;13846;14154.646133846632;14493.202873841934;14709.705805883823;4639.39687840582;4664.5102264262105;4731.905361892777;4737.407824021997;4791.305260737174;89006.5549672254;89417;89623.55188224082;90087.09912900852;90269;12076.456559454846;12216.328101954477;12434.316112613713;12461.439235234437;12734;22443;22478.775212247994;22529.0988360467;22610.434739131324;22680.432989175402;8702.521361325187;8726.345524085718;8843.867341989875;8888.288936885034;9083.364164508554;4841.3222148899185;5048.29323894655;5109.923351149736;5133;5220.138677118309;3533.8056406897354;3690.760100593453;3694.9076273093387;3698.3712768829364;3699.5930447650735;619.7830759234322;620.6369273974685;627.4917316973266;634.0995785983822;637.0635166305524;576.9163471296722;645.2450632759683;650.4113777031794;652.2466551133354;708.231568747904;8528.701495447754;10097.192224622013;10890.019898209199;10979.396133212591;11017.944910275397;706.6113637499354;720.3444865172668;724.2974314914525;732.5568031340962;734.7171339034403;4906.828261010847;5121.334226550524;5189.351331083614;5189.896202075937;5227.60792940531;78732.6063369691;108375.45812271046;109586.45206774074;111412;125141;60436.09345859816;82327.58836205781;105052.47473762582;107337;107379;90887.54556227177;92813.53593232126;94275;97049;97321.51339243258;10228.181745460493;10614.023715257545;10683.732906677395;10724.302920310154;10800.945995269973;54277.45722542724;55365;55615;56654;57075.714621426625;84853;86085.56957215424;86094.56952715197;87182.56408717915;88859.55570222107;13932.721345572829;13999.580012599637;14146.78779818283;14383.137011779327;14644.341004655029;14880;16243.269052892885;16264.105474198559;16506.33974641001;17452.301907923542;14562.126272423688;14798.778018329518;15369.615759606098;15521.301541430888;16106.27521761547;14662.706745864809;14954.476593319181;15248.237588120328;16039.438619648256;16052.839471605135;14118.50585229512;14791.704165916837;15013.474528391454;16302.918485407497;16320.020798752113;11267.887321126684;11787;12193.756124877631;12237.326947018;12880.677983050498;12552.246865188117;12671.683207919876;12894.67763305925;13191.868081319064;13292.468301267842 22:27:21 INFO - PROCESS | 17225 | __end_tp_report 22:27:21 INFO - PROCESS | 17225 | __start_cc_report 22:27:21 INFO - PROCESS | 17225 | _x_x_mozilla_cycle_collect,891 22:27:21 INFO - PROCESS | 17225 | __end_cc_report 22:27:21 INFO - PROCESS | 17225 | __startTimestamp1477546041955__endTimestamp 22:27:21 INFO - PROCESS | 17225 | 22:27:21 INFO - PROCESS | 17225 | ------- Summary: start ------- 22:27:21 INFO - PROCESS | 17225 | Number of tests: 6 22:27:21 INFO - PROCESS | 17225 | 22:27:21 INFO - PROCESS | 17225 | [#0] dojo.html Cycles:165 Average:5346.25 Median:5545.14 stddev:596.28 (10.8%) stddev-sans-first:571.05 22:27:21 INFO - PROCESS | 17225 | Values: 3082.4 3118.5 3119.6 3151.3 3162.4 5427.2 5434.6 5473.0 5482.8 5487.4 5468.6 5483.9 5490.6 5500.6 5502.1 4946.6 4976.9 5031.7 5037.6 5052.8 4809.7 4833.0 5014.9 5057.3 5076.6 4860.1 4881.8 4907.6 4930.8 4966.0 4970.1 4985.2 5023.5 5038.3 5072.6 4751.6 4950.8 4976.0 4990.6 5013.3 5160.5 5314.1 5329.8 5336.8 5389.8 5702.1 5720.8 5790.1 5800.7 5806.7 5608.9 5841.9 5856.6 5864.5 5869.3 5286.8 5369.1 5419.3 5420.5 5449.5 5919.8 5951.5 5953.6 5954.9 5962.8 5418.1 5714.0 5714.0 5723.1 5724.3 5526.8 5591.2 5617.3 5623.6 5701.0 5648.6 5649.1 5650.3 5654.1 5688.1 5600.8 5650.6 5653.5 5670.3 5681.3 4227.9 4266.8 4309.7 4314.6 4353.8 3882.4 3919.6 3940.4 3947.2 3953.4 5503.8 5510.4 5521.6 5527.3 5538.5 5540.5 5589.8 5724.1 5859.2 5898.9 5407.3 5507.0 5509.4 5545.1 5551.8 4926.0 4938.7 4952.7 4961.3 4980.6 5778.2 5778.4 5815.7 6005.5 6010.5 5651.4 5701.0 5733.0 5744.9 5758.7 5785.9 5837.4 5838.0 5840.4 5840.7 5513.3 5589.8 5599.8 5600.0 5600.1 5633.3 5679.0 5718.3 5736.9 5850.0 5653.9 5664.8 5665.2 5668.6 5677.4 5666.3 5671.1 5672.1 5677.9 5678.7 5371.3 5385.1 5510.8 5591.0 5613.4 5701.5 5705.4 5724.0 5726.1 5739.9 5266.9 5523.9 5594.7 5603.9 5606.4 22:27:21 INFO - PROCESS | 17225 | 22:27:21 INFO - PROCESS | 17225 | [#1] ext.html Cycles:180 Average:67304.78 Median:412682.50 stddev:136564.15 (33.1%) stddev-sans-first:136855.34 22:27:21 INFO - PROCESS | 17225 | Values: 575.2 587.3 609.6 627.1 627.2 34283.5 46460.5 47414.0 47543.3 47691.3 48980.0 49360.8 49386.5 49478.3 49620.3 8346.7 8543.1 9227.6 9377.3 9386.2 11005.4 11263.5 11268.0 11621.8 11625.3 11335.5 11420.0 11432.7 11440.5 11441.1 8446.4 8639.7 11256.9 11348.3 11349.9 10712.7 11034.4 11056.7 11336.4 11358.4 46994.5 47299.8 47325.2 47352.3 47513.3 3782.8 3823.1 3823.4 3861.8 4038.2 35151.5 35308.8 35414.8 35484.8 35599.5 4563.8 4635.9 4640.9 4668.5 4689.6 409165.0 410911.0 414454.0 415209.0 415502.0 66646.0 67155.3 67156.0 67159.7 67234.3 40868.2 41562.2 41617.4 43276.8 70840.6 19631.5 19773.2 19851.4 19860.2 19864.9 13956.1 14196.4 14490.4 14555.1 14630.6 20061.2 20908.4 21036.7 21158.2 21302.9 7310.6 7402.4 7597.0 7606.1 7654.7 989.0 1112.4 1124.4 1183.1 1231.2 35885.3 35898.0 36010.6 36137.6 36578.6 1185.2 1226.8 1231.4 1264.2 1322.3 16393.0 18821.7 18858.6 19457.9 20648.7 658430.0 658615.0 659446.0 664236.0 666247.0 63736.4 64216.7 64252.7 64286.7 64317.4 1335.4 1380.5 1433.8 1453.2 1469.3 32317.2 36816.4 36951.6 37322.6 37371.6 54798.7 55820.7 55829.0 55843.2 55886.4 427692.0 428036.0 428399.0 428904.0 429125.0 31307.7 31387.2 31410.5 31483.5 33207.7 52946.7 53182.7 53223.2 53287.5 53762.7 44308.3 44632.8 45060.3 45200.0 46169.0 44491.6 45422.5 45775.0 46454.8 46586.0 44155.6 44296.0 45025.0 45398.1 45708.3 36506.3 38710.4 39442.4 39556.8 39698.4 29152.3 29422.4 29802.6 30026.0 30033.0 22:27:21 INFO - PROCESS | 17225 | 22:27:21 INFO - PROCESS | 17225 | [#2] jquery.html Cycles:180 Average:31485.84 Median:11724.49 stddev:90019.01 (767.8%) stddev-sans-first:90241.25 22:27:21 INFO - PROCESS | 17225 | Values: 384.9 407.5 421.7 424.8 430.6 14326.5 16369.6 19894.9 20947.9 21370.9 19605.7 20015.5 20618.9 20765.5 20979.0 5647.9 5682.7 5851.6 5919.4 5974.2 4135.3 5996.0 6036.4 6039.1 6044.2 4331.9 6039.8 6066.5 6081.1 6088.9 5862.5 5978.5 6020.9 6067.8 6074.4 5844.6 5846.0 5850.9 5851.2 5854.0 14785.9 14844.1 15151.8 15192.7 15298.8 2527.1 2625.2 2664.5 2945.6 2988.2 10448.0 10810.4 10833.2 10903.5 10982.7 1993.4 2025.4 2036.6 2059.8 2112.6 84338.4 86726.6 88366.1 88417.1 91170.5 11441.5 11447.5 11554.4 11593.6 11799.0 16509.4 16705.8 16726.4 16830.7 16839.9 8493.1 8575.6 8589.1 8749.6 8970.6 7841.6 8001.7 8014.8 8162.9 8238.2 4890.7 4940.8 4958.9 5002.1 5033.0 2056.9 2060.8 2090.5 2107.8 2116.9 958.6 985.5 1028.6 1038.8 1059.7 20226.6 21046.2 21096.0 21332.1 21578.5 988.7 1082.1 1091.8 1103.1 1123.6 10279.4 11658.7 11674.8 11689.5 11764.0 527658.0 533185.0 543225.0 559834.0 561735.0 92232.0 97692.0 106224.0 106844.0 109371.0 1085.2 1088.8 1145.3 1147.5 1152.8 18772.7 19044.5 19215.9 19216.8 19298.1 44937.0 45212.3 45725.0 46065.8 46745.8 64555.4 65365.3 65486.7 66089.0 66983.7 9101.7 9123.5 9240.9 9429.8 9449.3 11012.9 13583.8 13970.0 14086.6 14427.8 12375.9 12421.2 12628.6 12709.7 12715.4 12119.4 12258.8 12318.8 12330.0 12517.7 12332.5 12513.4 12535.2 12757.9 12949.7 8067.8 8348.0 8890.5 9224.4 9336.4 9278.6 11831.5 12192.9 12496.4 12991.6 22:27:21 INFO - PROCESS | 17225 | 22:27:21 INFO - PROCESS | 17225 | [#3] mootools.html Cycles:180 Average:5907.57 Median:4491.12 stddev:3239.68 (72.1%) stddev-sans-first:3231.10 22:27:21 INFO - PROCESS | 17225 | Values: 1405.2 1526.0 1563.1 1690.8 1794.5 7539.2 8425.5 8769.6 8776.8 8780.7 8456.3 8633.2 8636.1 8847.3 8873.7 2415.0 2461.0 2461.7 2462.5 2465.0 2410.7 2446.5 2450.0 2450.4 2451.9 2370.6 2381.0 2401.9 2403.7 2418.8 2334.6 2345.6 2350.2 2362.9 2398.9 2334.1 2338.5 2342.3 2347.2 2351.5 8590.0 8686.3 8734.1 8738.8 8745.2 5295.9 5477.5 5490.5 5510.2 5522.6 3418.7 3433.4 3464.2 3471.2 3508.3 1182.8 1198.5 1203.7 1220.3 1239.6 11538.8 11679.7 11708.5 11724.5 11751.6 4406.9 4555.8 4649.9 4701.9 4710.9 10045.5 10064.9 10064.9 10080.9 10084.8 5286.3 5290.9 5295.5 5295.7 5297.4 4274.1 4371.3 4812.7 4934.8 5011.6 3985.5 4015.0 4072.1 4142.4 4179.1 1302.5 1303.2 1330.8 1334.4 1365.2 889.4 976.3 1052.2 1062.2 1075.6 6206.0 6322.5 6437.6 6545.2 6635.6 982.7 1022.0 1050.3 1064.3 1077.3 4245.5 4453.1 4529.2 4553.2 4610.1 10315.4 10715.6 10724.7 10773.9 10777.2 10547.1 10622.7 10831.7 10971.3 11327.8 8306.7 8338.4 8516.0 8541.4 8545.5 6414.6 6602.3 6649.2 6727.8 6799.4 9987.4 10006.2 10246.4 10277.8 10444.3 10458.3 10500.8 10564.6 10576.8 10642.9 8029.4 8076.9 8106.4 8107.8 8170.5 8702.7 8801.9 8803.6 8803.7 8804.6 7520.8 7655.7 7669.4 7697.7 7873.0 7728.1 7807.2 7880.2 7884.6 7900.4 7247.8 7301.3 7330.4 7343.6 7350.2 5540.4 5598.7 5685.4 5732.3 5806.3 5531.4 5608.7 5679.0 5770.1 5790.5 22:27:21 INFO - PROCESS | 17225 | 22:27:21 INFO - PROCESS | 17225 | [#4] prototype.html Cycles:180 Average:4623.52 Median:5118.12 stddev:1304.01 (25.5%) stddev-sans-first:1267.38 22:27:21 INFO - PROCESS | 17225 | Values: 338.3 346.6 350.8 350.9 352.9 5243.5 5416.3 5511.7 5542.3 5544.5 5228.4 5348.7 5389.7 5441.7 5446.9 4288.1 4301.2 4307.1 4313.2 4338.9 4202.3 4301.0 4302.4 4321.0 4331.7 4302.2 4303.8 4313.7 4324.6 4333.2 4292.4 4299.2 4308.6 4315.8 4328.0 3734.3 3865.9 3872.9 3883.2 3903.8 5216.6 5233.8 5274.6 5286.3 5288.6 4910.8 4923.0 4936.9 4939.0 4940.0 5438.1 5669.8 5685.9 5699.9 5752.0 4158.4 4178.9 4185.6 4193.5 4193.7 5851.6 5883.9 5885.7 5904.7 5947.3 4220.3 4334.8 4357.8 4363.9 4372.8 4325.2 4344.4 4349.7 4361.3 4367.7 4902.8 4904.5 4905.0 4907.9 4914.9 5588.8 5695.9 5701.2 5702.0 5738.2 1481.2 1500.6 1500.8 1503.3 1506.4 938.0 946.1 973.8 975.5 980.7 4840.9 4847.6 4848.5 4856.6 4903.8 5118.3 5120.9 5168.9 5198.9 5212.4 4654.6 4851.5 4864.3 4874.0 4923.5 2700.6 2724.9 2732.8 2734.5 2746.4 5234.9 5248.5 5256.2 5261.3 5281.2 4859.9 5186.1 5220.2 5234.1 5259.0 5125.7 5238.7 5238.9 5244.5 5301.8 5032.7 5062.8 5097.5 5110.4 5117.9 4731.5 5012.7 5043.9 5048.8 5087.0 5188.7 5209.9 5215.5 5227.9 5272.9 3913.8 4124.3 4182.7 4196.2 4273.7 5733.6 5807.0 5809.3 5815.0 5828.7 5539.8 5710.9 5715.6 5719.3 5726.5 5629.9 5647.5 5651.5 5658.9 5664.5 5567.7 5600.7 5647.0 5648.9 5651.0 5415.6 5586.7 5620.4 5645.3 5682.7 5502.3 5524.4 5555.0 5577.0 5599.6 22:27:21 INFO - PROCESS | 17225 | 22:27:21 INFO - PROCESS | 17225 | [#5] yui.html Cycles:180 Average:22101.71 Median:4169.49 stddev:30949.54 (742.3%) stddev-sans-first:30995.04 22:27:21 INFO - PROCESS | 17225 | Values: 802.5 807.3 821.9 823.2 866.3 16296.3 17152.0 18429.7 18589.3 18603.4 16191.5 16486.8 16520.7 16525.8 16548.1 846.1 865.9 873.8 876.1 877.3 721.3 739.7 830.1 857.6 872.6 887.4 888.2 892.9 893.0 895.4 887.1 887.1 887.5 888.1 888.5 544.8 561.6 634.1 650.5 658.6 14523.2 14524.9 14550.6 14562.7 14577.6 14849.2 15308.6 15551.3 15567.8 15699.3 13409.2 13846.0 14154.6 14493.2 14709.7 4639.4 4664.5 4731.9 4737.4 4791.3 89006.6 89417.0 89623.6 90087.1 90269.0 12076.5 12216.3 12434.3 12461.4 12734.0 22443.0 22478.8 22529.1 22610.4 22680.4 8702.5 8726.3 8843.9 8888.3 9083.4 4841.3 5048.3 5109.9 5133.0 5220.1 3533.8 3690.8 3694.9 3698.4 3699.6 619.8 620.6 627.5 634.1 637.1 576.9 645.2 650.4 652.2 708.2 8528.7 10097.2 10890.0 10979.4 11017.9 706.6 720.3 724.3 732.6 734.7 4906.8 5121.3 5189.4 5189.9 5227.6 78732.6 108375.5 109586.5 111412.0 125141.0 60436.1 82327.6 105052.5 107337.0 107379.0 90887.5 92813.5 94275.0 97049.0 97321.5 10228.2 10614.0 10683.7 10724.3 10800.9 54277.5 55365.0 55615.0 56654.0 57075.7 84853.0 86085.6 86094.6 87182.6 88859.6 13932.7 13999.6 14146.8 14383.1 14644.3 14880.0 16243.3 16264.1 16506.3 17452.3 14562.1 14798.8 15369.6 15521.3 16106.3 14662.7 14954.5 15248.2 16039.4 16052.8 14118.5 14791.7 15013.5 16302.9 16320.0 11267.9 11787.0 12193.8 12237.3 12880.7 12552.2 12671.7 12894.7 13191.9 13292.5 22:27:21 INFO - PROCESS | 17225 | -------- Summary: end -------- 22:27:21 INFO - PROCESS | 17225 | 22:27:22 INFO - TEST-INFO | 17225: exit 0 22:27:22 INFO - TEST-OK | dromaeo_css | took 1113756ms 22:27:22 INFO - TEST-START | kraken 22:27:22 INFO - operating with platform_type : linux_ 22:27:22 INFO - Initialising browser for kraken test... 22:27:22 INFO - TEST-INFO | started process 17548 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpth9LVy/profile http://localhost:37955/getInfo.html) 22:27:26 INFO - PROCESS | 17548 | __metrics Screen width/height:1600/1200 22:27:26 INFO - PROCESS | 17548 | colorDepth:24 22:27:26 INFO - PROCESS | 17548 | Browser inner width/height: 1024/697 22:27:26 INFO - PROCESS | 17548 | __metrics 22:27:27 INFO - PROCESS | 17548 | 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" 22:27:27 INFO - PROCESS | 17548 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:27:27 INFO - PROCESS | 17548 | console.error: PushService: 22:27:27 INFO - PROCESS | 17548 | stateChangeProcessEnqueue: Error transitioning state 22:27:27 INFO - PROCESS | 17548 | UnknownError 22:27:28 INFO - TEST-INFO | 17548: exit 0 22:27:28 INFO - Browser initialized. 22:27:28 INFO - Running cycle 1/1 for kraken test... 22:27:28 INFO - TEST-INFO | started process 17643 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpth9LVy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:27:40 INFO - PROCESS | 17643 | RSS: Main: 144809984 22:27:40 INFO - PROCESS | 17643 | 22:27:42 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37955/tests/kraken/driver.html?testName=audio-beat-detection) 22:27:43 INFO - PROCESS | 17643 | RSS: Main: 186576896 22:27:43 INFO - PROCESS | 17643 | 22:27:45 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37955/tests/kraken/driver.html?testName=audio-dft) 22:27:46 INFO - PROCESS | 17643 | RSS: Main: 176447488 22:27:46 INFO - PROCESS | 17643 | 22:27:49 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37955/tests/kraken/driver.html?testName=audio-fft) 22:27:49 INFO - PROCESS | 17643 | RSS: Main: 267456512 22:27:49 INFO - PROCESS | 17643 | 22:27:51 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37955/tests/kraken/driver.html?testName=audio-oscillator) 22:27:52 INFO - PROCESS | 17643 | RSS: Main: 172732416 22:27:52 INFO - PROCESS | 17643 | 22:27:54 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37955/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:27:55 INFO - PROCESS | 17643 | RSS: Main: 175689728 22:27:55 INFO - PROCESS | 17643 | 22:27:58 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37955/tests/kraken/driver.html?testName=imaging-darkroom) 22:27:58 INFO - PROCESS | 17643 | RSS: Main: 211484672 22:27:58 INFO - PROCESS | 17643 | 22:28:02 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37955/tests/kraken/driver.html?testName=imaging-desaturate) 22:28:02 INFO - PROCESS | 17643 | RSS: Main: 210165760 22:28:02 INFO - PROCESS | 17643 | 22:28:05 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37955/tests/kraken/driver.html?testName=json-parse-financial) 22:28:06 INFO - PROCESS | 17643 | RSS: Main: 209530880 22:28:06 INFO - PROCESS | 17643 | 22:28:07 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37955/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:28:08 INFO - PROCESS | 17643 | RSS: Main: 171364352 22:28:08 INFO - PROCESS | 17643 | 22:28:09 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37955/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:28:10 INFO - PROCESS | 17643 | RSS: Main: 179322880 22:28:10 INFO - PROCESS | 17643 | 22:28:12 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37955/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:28:13 INFO - PROCESS | 17643 | RSS: Main: 188514304 22:28:13 INFO - PROCESS | 17643 | 22:28:15 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37955/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:28:16 INFO - PROCESS | 17643 | RSS: Main: 197787648 22:28:16 INFO - PROCESS | 17643 | 22:28:18 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37955/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:28:19 INFO - PROCESS | 17643 | RSS: Main: 193417216 22:28:19 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | Cycle 1(1): loaded http://localhost:37955/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37955/tests/kraken/driver.html?testName=ai-astar) 22:28:21 INFO - PROCESS | 17643 | RSS: Main: 244862976 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | __start_tp_report 22:28:21 INFO - PROCESS | 17643 | _x_x_mozilla_page_load 22:28:21 INFO - PROCESS | 17643 | _x_x_mozilla_page_load_details 22:28:21 INFO - PROCESS | 17643 | |i|pagename|runs| 22:28:21 INFO - PROCESS | 17643 | |0;ai-astar;110;114;114;112;112;112;110;112;112;109 22:28:21 INFO - PROCESS | 17643 | |1;audio-beat-detection;146;118;119;154;117;121;119;118;149;118 22:28:21 INFO - PROCESS | 17643 | |2;audio-dft;161;163;160;161;160;162;161;162;161;159 22:28:21 INFO - PROCESS | 17643 | |3;audio-fft;76;75;75;110;75;75;75;77;99;75 22:28:21 INFO - PROCESS | 17643 | |4;audio-oscillator;82;90;117;82;81;83;104;81;81;102 22:28:21 INFO - PROCESS | 17643 | |5;imaging-gaussian-blur;116;117;117;116;117;117;117;117;117;117 22:28:21 INFO - PROCESS | 17643 | |6;imaging-darkroom;146;145;145;146;146;146;146;146;146;145 22:28:21 INFO - PROCESS | 17643 | |7;imaging-desaturate;95;95;95;96;95;95;94;95;95;95 22:28:21 INFO - PROCESS | 17643 | |8;json-parse-financial;49;48;48;48;50;50;49;47;47;50 22:28:21 INFO - PROCESS | 17643 | |9;json-stringify-tinderbox;59;60;60;59;59;59;61;59;59;60 22:28:21 INFO - PROCESS | 17643 | |10;stanford-crypto-aes;77;78;73;73;72;77;73;74;72;75 22:28:21 INFO - PROCESS | 17643 | |11;stanford-crypto-ccm;122;125;124;122;125;120;121;120;154;135 22:28:21 INFO - PROCESS | 17643 | |12;stanford-crypto-pbkdf2;158;158;153;153;152;153;153;154;153;158 22:28:21 INFO - PROCESS | 17643 | |13;stanford-crypto-sha256-iterative;62;64;63;63;63;63;62;66;60;62 22:28:21 INFO - PROCESS | 17643 | __end_tp_report 22:28:21 INFO - PROCESS | 17643 | __start_cc_report 22:28:21 INFO - PROCESS | 17643 | _x_x_mozilla_cycle_collect,1612 22:28:21 INFO - PROCESS | 17643 | __end_cc_report 22:28:21 INFO - PROCESS | 17643 | __startTimestamp1477546101467__endTimestamp 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | ------- Summary: start ------- 22:28:21 INFO - PROCESS | 17643 | Number of tests: 14 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.64 (1.5%) stddev-sans-first:1.62 22:28:21 INFO - PROCESS | 17643 | Values: 110.0 114.0 114.0 112.0 112.0 112.0 110.0 112.0 112.0 109.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:120.00 stddev:15.18 (12.6%) stddev-sans-first:14.62 22:28:21 INFO - PROCESS | 17643 | Values: 146.0 118.0 119.0 154.0 117.0 121.0 119.0 118.0 149.0 118.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:1.22 22:28:21 INFO - PROCESS | 17643 | Values: 161.0 163.0 160.0 161.0 160.0 162.0 161.0 162.0 161.0 159.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#3] audio-fft Cycles:10 Average:81.20 Median:75.00 stddev:12.57 (16.8%) stddev-sans-first:13.19 22:28:21 INFO - PROCESS | 17643 | Values: 76.0 75.0 75.0 110.0 75.0 75.0 75.0 77.0 99.0 75.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:81.50 stddev:12.86 (15.8%) stddev-sans-first:13.28 22:28:21 INFO - PROCESS | 17643 | Values: 82.0 90.0 117.0 82.0 81.0 83.0 104.0 81.0 81.0 102.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 22:28:21 INFO - PROCESS | 17643 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 22:28:21 INFO - PROCESS | 17643 | Values: 146.0 145.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 22:28:21 INFO - PROCESS | 17643 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 94.0 95.0 95.0 95.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#8] json-parse-financial Cycles:10 Average:48.60 Median:49.00 stddev:1.17 (2.4%) stddev-sans-first:1.24 22:28:21 INFO - PROCESS | 17643 | Values: 49.0 48.0 48.0 48.0 50.0 50.0 49.0 47.0 47.0 50.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 22:28:21 INFO - PROCESS | 17643 | Values: 59.0 60.0 60.0 59.0 59.0 59.0 61.0 59.0 59.0 60.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:74.50 stddev:2.22 (3.0%) stddev-sans-first:2.15 22:28:21 INFO - PROCESS | 17643 | Values: 77.0 78.0 73.0 73.0 72.0 77.0 73.0 74.0 72.0 75.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:124.50 stddev:10.51 (8.4%) stddev-sans-first:11.00 22:28:21 INFO - PROCESS | 17643 | Values: 122.0 125.0 124.0 122.0 125.0 120.0 121.0 120.0 154.0 135.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.50 Median:153.50 stddev:2.46 (1.6%) stddev-sans-first:2.26 22:28:21 INFO - PROCESS | 17643 | Values: 158.0 158.0 153.0 153.0 152.0 153.0 153.0 154.0 153.0 158.0 22:28:21 INFO - PROCESS | 17643 | 22:28:21 INFO - PROCESS | 17643 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:1.55 (2.5%) stddev-sans-first:1.62 22:28:21 INFO - PROCESS | 17643 | Values: 62.0 64.0 63.0 63.0 63.0 63.0 62.0 66.0 60.0 62.0 22:28:21 INFO - PROCESS | 17643 | -------- Summary: end -------- 22:28:21 INFO - PROCESS | 17643 | 22:28:22 INFO - TEST-INFO | 17643: exit 0 22:28:22 INFO - TEST-OK | kraken | took 59983ms 22:28:22 INFO - SUITE-END | took 1173s 22:28:23 INFO - Completed test suite (00:19:34) 22:28:23 INFO - javascript benchmark 22:28:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3082.4143412751578, 3118.4854499007656, 3119.5788568543253, 3151.3382189740155, 3162.414953233652, 5427.185922111686, 5434.565234781218, 5473.042217611922, 5482.753276102575, 5487.396386397493, 5468.6171967962255, 5483.890322193553, 5490.56075513959, 5500.642458240211, 5502.119660854264, 4946.579540739032, 4976.900461990758, 5031.748412579374, 5037.5718063964605, 5052.772625231855, 4809.663323567352, 4832.975835120819, 5014.874628134307, 5057.342545469102, 5076.593872490211, 4860.05228980349, 4881.780319885596, 4907.631927605426, 4930.778114984834, 4966.0, 4970.080535100999, 4985.152524070917, 5023.472535383805, 5038.3198268234055, 5072.619553533463, 4751.619870410359, 4950.8267210647455, 4976.02967421353, 4990.575801056914, 5013.298138260647, 5160.458151894035, 5314.149736042254, 5329.786808527654, 5336.759845806948, 5389.757460914268, 5702.144678298247, 5720.799772007959, 5790.073588225863, 5800.651960882361, 5806.709664516799, 5608.887822243532, 5841.941580584183, 5856.648601083949, 5864.501517371029, 5869.266341707286, 5286.841394758125, 5369.087255166631, 5419.34967803866, 5420.512153906167, 5449.536789372948, 5919.763209471659, 5951.5238780897425, 5953.583249172516, 5954.940450595525, 5962.821115366459, 5418.051840927822, 5714.0, 5714.0, 5723.112917497794, 5724.3130824300515, 5526.778928842882, 5591.161325801162, 5617.269754931915, 5623.550115990711, 5701.0, 5648.604597678205, 5649.096144616841, 5650.29371328584, 5654.095344744904, 5688.146777983336, 5600.803971861047, 5650.604457688005, 5653.462921022497, 5670.262865827416, 5681.261436013375, 4227.915441691149, 4266.765327906995, 4309.741415515016, 4314.611684948307, 4353.782310884443, 3882.417637354363, 3919.5884432134785, 3940.3695408734466, 3947.2105578883766, 3953.4069889516804, 5503.834884953458, 5510.448955104457, 5521.613487055867, 5527.2538207342295, 5538.47384498472, 5540.473655002768, 5589.832305030855, 5724.084146536534, 5859.238299021185, 5898.852528686817, 5407.270018547524, 5506.972465137648, 5509.393966663663, 5545.140503222007, 5551.777928882881, 4925.950740492621, 4938.703677779345, 4952.727599982036, 4961.305417241517, 4980.601551875841, 5778.191053252632, 5778.3643799182055, 5815.651060936358, 6005.459508644243, 6010.459058684739, 5651.406602306699, 5701.030824759718, 5733.0, 5744.885102297847, 5758.683272420057, 5785.8553536161935, 5837.357890632112, 5838.036723940502, 5840.445157710181, 5840.737166827588, 5513.338399392099, 5589.832305030855, 5599.832005039692, 5600.047991841316, 5600.075987462186, 5633.267675202197, 5679.0, 5718.342390625131, 5736.885262294647, 5849.970750146222, 5653.858653533694, 5664.801731939362, 5665.235193248858, 5668.603197776116, 5677.432256774289, 5666.291713535808, 5671.064274394844, 5672.12082127271, 5677.886442271214, 5678.6876721779045, 5371.274877891435, 5385.084535629031, 5510.752016159363, 5590.99362114807, 5613.354464236665, 5701.543876489954, 5705.429457054262, 5724.0, 5726.141078838207, 5739.8852022960145, 5266.920996184984, 5523.8895222096135, 5594.664320140804, 5603.887922241451, 5606.355269143937], "value": 5306.553111122842, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.157394417179, 587.3157771196556, 609.6464050850507, 627.1063734178794, 627.1815281058217, 34283.48574771377, 46460.535394646045, 47414.0, 47543.286850697266, 47691.284630730566, 48980.0, 49360.75319623397, 49386.50613493864, 49478.257826132634, 49620.25569616459, 8346.707865224718, 8543.102974187714, 9227.630894764217, 9377.343585948985, 9386.202172815285, 11005.394703291317, 11263.493142808553, 11268.042216411615, 11621.767564648702, 11625.302481851073, 11335.546578136864, 11420.0, 11432.714182145472, 11440.542378304857, 11441.084713222965, 8446.408751387406, 8639.65441382344, 11256.943715281412, 11348.319100853892, 11349.943250283779, 10712.678619641423, 11034.448277586089, 11056.668299951014, 11336.376499292535, 11358.375289359083, 46994.5300546997, 47299.76350118262, 47325.16049037034, 47352.28971565429, 47513.287300690514, 3782.7541209821275, 3823.0633494793674, 3823.445600387956, 3861.7876016819064, 4038.1519880825063, 35151.4727279091, 35308.82345588256, 35414.82292588546, 35484.75175368507, 35599.4660080099, 4563.77181140945, 4635.860924172281, 4640.86077417678, 4668.509806470306, 4689.624830013625, 409165.0, 410911.0, 414454.0, 415209.0, 415502.0, 66646.0, 67155.32844671588, 67156.0, 67159.66420167868, 67234.32765672379, 40868.1826363477, 41562.1687566247, 41617.37573936393, 43276.78361608172, 70840.64579677068, 19631.509212269808, 19773.209071637262, 19851.404457866287, 19860.205591776168, 19864.90067549653, 13956.09285396446, 14196.432142714382, 14490.420383184555, 14555.12669239849, 14630.561083167522, 20061.197552098045, 20908.37274881756, 21036.684449733268, 21158.153673853183, 21302.893485532473, 7310.587506122966, 7402.408482176358, 7597.0, 7606.125295591078, 7654.655540500691, 988.9950550247203, 1112.4382186995515, 1124.3815901254277, 1183.1185766603935, 1231.2427856868142, 35885.282294353965, 35898.0, 36010.639893601256, 36137.63862361343, 36578.634213657526, 1185.247367921376, 1226.7669142862828, 1231.445849367788, 1264.1972347559183, 1322.2793577500286, 16393.0, 18821.717674234897, 18858.622827543648, 19457.902710486356, 20648.690269645966, 658430.0, 658615.0, 659446.0, 664236.0, 666247.0, 63736.36263637397, 64216.67891660512, 64252.678736606016, 64286.67856660687, 64317.35682643207, 1335.3990704183157, 1380.5444203412849, 1433.849445808196, 1453.2225259486124, 1469.2947385254924, 32317.192070198435, 36816.44775328319, 36951.630483695895, 37322.626773731914, 37371.62628373681, 54798.72600636971, 55820.72089639526, 55829.0, 55843.16235256556, 55886.441135588124, 427692.0, 428036.0, 428399.0, 428904.0, 429125.0, 31307.686923131387, 31387.21531961628, 31410.52884206693, 31483.527747083353, 33207.667923320456, 52946.704389815306, 53182.73408632932, 53223.20165197448, 53287.46712532825, 53762.731186343815, 44308.33537496876, 44632.77683611561, 45060.32409513926, 45200.0, 46169.0, 44491.555084448744, 45422.54577454183, 45775.0, 46454.76772616115, 46586.0, 44155.55844441643, 44296.0, 45025.0, 45398.09203815839, 45708.314375283735, 36506.26987460289, 38710.419343709305, 39442.40836387514, 39556.80221598873, 39698.40452393159, 29152.27119322034, 29422.411551769274, 29802.552961705158, 30026.0, 30033.0], "value": 22167.022973470008, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.91916697493525, 407.5231978585055, 421.68163036907134, 424.77274658057945, 430.642566669664, 14326.498572549963, 16369.590760231, 19894.90052549739, 20947.895260523677, 21370.89314553429, 19605.705914411294, 20015.499612509695, 20618.896905515452, 20765.480862978395, 20979.0, 5647.915281270774, 5682.715864206793, 5851.590388672795, 5919.437653422918, 5974.163617093611, 4135.338345864662, 5995.970020149894, 6036.3661815509395, 6039.1243269725865, 6044.214252147215, 4331.891702707426, 6039.818805435822, 6066.454019138277, 6081.057436097412, 6088.908666369985, 5862.472377486026, 5978.491828194608, 6020.909686354708, 6067.848303792396, 6074.392560743934, 5844.649321040752, 5846.0, 5850.853728656775, 5851.23933888596, 5854.034084376073, 14785.926070369687, 14844.109353438827, 15151.848481515264, 15192.696146077127, 15298.770518442121, 2527.1155095716535, 2625.225558460259, 2664.467106578692, 2945.617069780936, 2988.223062003873, 10448.0, 10810.35137891729, 10833.241673082888, 10903.454827258605, 10982.725431864188, 1993.3820515640198, 2025.3721346382667, 2036.5825005873908, 2059.794020597943, 2112.640851055324, 84338.36097034384, 86726.56636716776, 88366.11633883644, 88417.11582884153, 91170.5441472795, 11441.542338306375, 11447.54209831614, 11554.364509951865, 11593.594224202112, 11799.0, 16509.422170223985, 16705.83294167067, 16726.414575489798, 16830.663386732198, 16839.91580042092, 8493.065762766091, 8575.571221438902, 8589.14108589136, 8749.606267717969, 8970.641174353083, 7841.607919603997, 8001.719939802079, 8014.839703205903, 8162.877556836652, 8238.176182381834, 4890.657653964259, 4940.827071052496, 4958.925616115761, 5002.1496345621335, 5033.0, 2056.8765874047604, 2060.7733149353558, 2090.4773806548365, 2107.8419118566167, 2116.850972094712, 958.6405098088217, 985.4924713772413, 1028.6296933104227, 1038.7922415516928, 1059.7465780055156, 20226.595468090556, 21046.15815367368, 21096.0, 21332.122433907647, 21578.46053848635, 988.7182153086336, 1082.107261509258, 1091.7871015151968, 1103.1340397787858, 1123.6292023632182, 10279.434631095211, 11658.6502404928, 11674.824877626843, 11689.473973671034, 11764.0, 527658.0, 533185.0, 543225.0, 559834.0, 561735.0, 92232.0, 97692.0, 106224.0, 106844.0, 109371.0, 1085.2023762534532, 1088.7713580148259, 1145.3070892110154, 1147.5180424221846, 1152.809786385237, 18772.718409224144, 19044.523886902938, 19215.90392048031, 19216.807831921502, 19298.13158407847, 44937.0, 45212.32181517214, 45725.0, 46065.76967115143, 46745.766271168424, 64555.354446454934, 65365.346346535924, 65486.67256663686, 66089.0, 66983.66508167428, 9101.681441149529, 9123.49820759838, 9240.907590924005, 9429.764255893657, 9449.338546301968, 11012.889871101186, 13583.79624305656, 13970.0, 14086.577402677936, 14427.78358324647, 12375.876241237473, 12421.19262247951, 12628.558000469939, 12709.745805084032, 12715.427805748948, 12119.394030298627, 12258.816117758419, 12318.81521777192, 12330.0, 12517.687057823263, 12332.50669973191, 12513.374331283581, 12535.185212961129, 12757.936210318889, 12949.741005180033, 8067.757967260992, 8347.95826020866, 8890.4665720057, 9224.40041397307, 9336.39313444624, 9278.62885484573, 11831.467583958914, 12192.939035304767, 12496.43766030549, 12991.610251692464], "value": 9650.112476844275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.2060585769034, 1525.9923700381496, 1563.0855949269676, 1690.8309169083093, 1794.4706311638065, 7539.170691223961, 8425.536595487245, 8769.60536775845, 8776.78058048549, 8780.736577902666, 8456.281216096639, 8633.179847914455, 8636.13638636136, 8847.292216622654, 8873.68942087027, 2415.04605680756, 2461.027893981876, 2461.7415171407015, 2462.4705688277018, 2464.963025554618, 2410.6745589345387, 2446.5473887330813, 2450.019992003204, 2450.3506570758764, 2451.901923923041, 2370.6444033394955, 2380.976190238093, 2401.855888646678, 2403.663487111806, 2418.8064954803663, 2334.6147885598857, 2345.6247000479925, 2350.1656911796235, 2362.9409264768346, 2398.940026499334, 2334.136369543263, 2338.4621537046405, 2342.2621874109773, 2347.248880358286, 2351.494428697838, 8589.95705021477, 8686.305095592337, 8734.082921293237, 8738.825223495558, 8745.169208925143, 5295.920561191547, 5477.534409575191, 5490.533304669108, 5510.201020851994, 5522.6134170608075, 3418.7265018798435, 3433.364827506919, 3464.1685995361186, 3471.1669199391963, 3508.315878403687, 1182.8225766135063, 1198.5385626533848, 1203.705092252395, 1220.3471142938486, 1239.603326935372, 11538.826917596243, 11679.708007299885, 11708.473118709593, 11724.472398741991, 11751.588694395656, 4406.9338959914985, 4555.840545580889, 4649.860504184812, 4701.858944231679, 4710.929336059962, 10045.547950342252, 10064.899351006543, 10064.949675251577, 10080.949595252123, 10084.848727269096, 5286.259923610698, 5290.894182116336, 5295.523402893758, 5295.735213239323, 5297.3643162819935, 4274.123804620045, 4371.344298355272, 4812.687175333662, 4934.753262336869, 5011.599072074226, 3985.5416627088257, 4014.979925100356, 4072.1041370898356, 4142.378643203483, 4179.059711564873, 1302.5050480817417, 1303.1985329022773, 1330.8070329802124, 1334.4462048249868, 1365.1945352242026, 889.3641046651546, 976.2531663277598, 1052.1740433759462, 1062.1555863088863, 1075.6450371377427, 6206.004166645803, 6322.4942004639515, 6437.645929473924, 6545.214574251025, 6635.601863888086, 982.7051884434641, 1021.9540120694587, 1050.3015494696094, 1064.308199670205, 1077.2836064017497, 4245.51392672954, 4453.0648563801315, 4529.184746745552, 4553.248713962159, 4610.147122782296, 10315.381077135246, 10715.571377144983, 10724.678259652223, 10773.89226107729, 10777.245592808586, 10547.103496202908, 10622.681319560426, 10831.729206769895, 10971.341719496855, 11327.773444531063, 8306.667733290722, 8338.374621903382, 8515.957420212982, 8541.359398045048, 8545.487270763773, 6414.562466262791, 6602.306757790363, 6649.168853893263, 6727.798166055026, 6799.388055075067, 9987.350822196824, 10006.249531285328, 10246.436445995541, 10277.845832312372, 10444.321119127233, 10458.26792124544, 10500.842487362848, 10564.577416903237, 10576.84134737964, 10642.89357106419, 8029.397795165269, 8076.878846817185, 8106.432549721463, 8107.83784324322, 8170.468919520212, 8702.738917832476, 8801.911980880366, 8803.647854085764, 8803.691870784492, 8804.60379282921, 7520.774376768706, 7655.732049378245, 7669.424793140648, 7697.653605587874, 7873.015873015873, 7728.111267204344, 7807.18024607408, 7880.17258187882, 7884.605769711607, 7900.446968712365, 7247.782566523013, 7301.3428791406905, 7330.376917962033, 7343.596102214429, 7350.191478937206, 5540.36285827135, 5598.664080155041, 5685.431456854281, 5732.34078081026, 5806.274215723035, 5531.363893152339, 5608.663480191201, 5679.0, 5770.1056336266265, 5790.507806836466], "value": 4779.419695289579, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.2862160840626, 346.59448445318975, 350.771998201169, 350.90867402378603, 352.91706448984485, 5243.501867322599, 5416.268803711498, 5511.669299842012, 5542.279503664528, 5544.47327503887, 5228.353068783331, 5348.732563371826, 5389.73051347432, 5441.7279136043135, 5446.91829622556, 4288.078063216408, 4301.161273551643, 4307.138572285539, 4313.15893400787, 4338.850204695758, 4202.285611446062, 4300.956990430087, 4302.354646802989, 4320.97839510802, 4331.6534677225745, 4302.20409224293, 4303.827846886121, 4313.676474264427, 4324.567543245682, 4333.198358303724, 4292.356146578023, 4299.205516414356, 4308.612224899758, 4315.784210789479, 4327.956720432787, 3734.2531493701367, 3865.903352416184, 3872.8644497442738, 3883.203943191639, 3903.8048097595292, 5216.58267338612, 5233.816816411408, 5274.604404669665, 5286.3392075990505, 5288.603354748371, 4910.828121015783, 4922.975385123087, 4936.851894443173, 4939.0, 4939.950600493985, 5438.117797567295, 5669.801556945528, 5685.857853553653, 5699.857503562403, 5752.0, 4158.4178215049915, 4178.937315940263, 4185.58144185582, 4193.454850869398, 4193.685473589493, 5851.64890106595, 5883.941160588382, 5885.676287804169, 5904.667656275747, 5947.286325640907, 4220.261454245495, 4334.804933777988, 4357.847475338412, 4363.934540981888, 4372.825086996548, 4325.15659446405, 4344.413504176959, 4349.716833534115, 4361.3208914567485, 4367.737935723803, 4902.828401005948, 4904.485029071896, 4904.950950490449, 4907.87730306738, 4914.90170196594, 5588.832335029955, 5695.857603559944, 5701.201831743476, 5701.971490142523, 5738.221151443066, 1481.1557412275115, 1500.647347873249, 1500.7993605115864, 1503.2934520775218, 1506.412499125342, 938.0, 946.1484663802632, 973.8052389522123, 975.5073687787764, 980.7302991677345, 4840.878978025577, 4847.636427267969, 4848.539388758062, 4856.611471082305, 4903.754812259373, 5118.283440318359, 5120.897582048413, 5168.9483105168465, 5198.870028249324, 5212.40057393397, 4654.581087702123, 4851.466338702739, 4864.318995340655, 4874.0495603357, 4923.507649235119, 2700.6361787297683, 2724.891004359843, 2732.8410555660807, 2734.480448714738, 2746.368335282896, 5234.92147617786, 5248.475152484797, 5256.2115682647145, 5261.289725886956, 5281.234221037928, 4859.854204373874, 5186.144286192812, 5220.207189153784, 5234.141879632752, 5259.0, 5125.692458452505, 5238.685678859204, 5238.895222095537, 5244.5279924806955, 5301.814436494704, 5032.73526912898, 5062.772175252049, 5097.541221290102, 5110.437851836221, 5117.948820511896, 4731.4558825735485, 5012.746813296676, 5043.924341134959, 5048.823291184791, 5087.0, 5188.662736922088, 5209.926143575309, 5215.452377500308, 5227.869303267449, 5272.920906186334, 3913.76517408945, 4124.257633625976, 4182.728122672017, 4196.1607678463815, 4273.679474039522, 5733.627314224562, 5807.0, 5809.302883653989, 5815.0, 5828.650280982985, 5539.778408863762, 5710.857228569319, 5715.628484148517, 5719.256496655408, 5726.455986681259, 5629.915551266652, 5647.5199608032135, 5651.519620832111, 5658.8965151795, 5664.49019588239, 5567.66594004361, 5600.747966341443, 5646.971765141148, 5648.943510565006, 5651.0, 5415.6479828811, 5586.6871285247125, 5620.353659329229, 5645.266115404971, 5682.744276507649, 5502.339719233718, 5524.364698059774, 5555.000299838042, 5576.992282778116, 5599.580031497735], "value": 4214.251673807517, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.546561192926, 807.2613558593885, 821.8520666280067, 823.1521532821189, 866.2766589897435, 16296.266667999938, 17152.0, 18429.723554146698, 18589.349372771954, 18603.441896743087, 16191.514254572352, 16486.83513164868, 16520.66958660826, 16525.83474165258, 16548.089855057966, 846.1496196322703, 865.9480431174118, 873.7640836974014, 876.119499902597, 877.302544477139, 721.3327671903495, 739.7299985505279, 830.0993422136988, 857.6355049104137, 872.5593575244493, 887.4010043220833, 888.2183678363064, 892.8883889513811, 893.0310612984919, 895.3777124898198, 887.1128871128807, 887.1350433327518, 887.5384799904047, 888.0630934364243, 888.5113187746714, 544.7902557515386, 561.5760101123625, 634.0932466572846, 650.5186162239909, 658.6114192626374, 14523.20122393268, 14524.927375363055, 14550.636234094127, 14562.70874582513, 14577.562673119717, 14849.183294918776, 15308.617284567863, 15551.300191491297, 15567.766483502757, 15699.293531791096, 13409.249129786176, 13846.0, 14154.646133846632, 14493.202873841934, 14709.705805883823, 4639.39687840582, 4664.5102264262105, 4731.905361892777, 4737.407824021997, 4791.305260737174, 89006.5549672254, 89417.0, 89623.55188224082, 90087.09912900852, 90269.0, 12076.456559454846, 12216.328101954477, 12434.316112613713, 12461.439235234437, 12734.0, 22443.0, 22478.775212247994, 22529.0988360467, 22610.434739131324, 22680.432989175402, 8702.521361325187, 8726.345524085718, 8843.867341989875, 8888.288936885034, 9083.364164508554, 4841.3222148899185, 5048.29323894655, 5109.923351149736, 5133.0, 5220.138677118309, 3533.8056406897354, 3690.760100593453, 3694.9076273093387, 3698.3712768829364, 3699.5930447650735, 619.7830759234322, 620.6369273974685, 627.4917316973266, 634.0995785983822, 637.0635166305524, 576.9163471296722, 645.2450632759683, 650.4113777031794, 652.2466551133354, 708.231568747904, 8528.701495447754, 10097.192224622013, 10890.019898209199, 10979.396133212591, 11017.944910275397, 706.6113637499354, 720.3444865172668, 724.2974314914525, 732.5568031340962, 734.7171339034403, 4906.828261010847, 5121.334226550524, 5189.351331083614, 5189.896202075937, 5227.60792940531, 78732.6063369691, 108375.45812271046, 109586.45206774074, 111412.0, 125141.0, 60436.09345859816, 82327.58836205781, 105052.47473762582, 107337.0, 107379.0, 90887.54556227177, 92813.53593232126, 94275.0, 97049.0, 97321.51339243258, 10228.181745460493, 10614.023715257545, 10683.732906677395, 10724.302920310154, 10800.945995269973, 54277.45722542724, 55365.0, 55615.0, 56654.0, 57075.714621426625, 84853.0, 86085.56957215424, 86094.56952715197, 87182.56408717915, 88859.55570222107, 13932.721345572829, 13999.580012599637, 14146.78779818283, 14383.137011779327, 14644.341004655029, 14880.0, 16243.269052892885, 16264.105474198559, 16506.33974641001, 17452.301907923542, 14562.126272423688, 14798.778018329518, 15369.615759606098, 15521.301541430888, 16106.27521761547, 14662.706745864809, 14954.476593319181, 15248.237588120328, 16039.438619648256, 16052.839471605135, 14118.50585229512, 14791.704165916837, 15013.474528391454, 16302.918485407497, 16320.020798752113, 11267.887321126684, 11787.0, 12193.756124877631, 12237.326947018, 12880.677983050498, 12552.246865188117, 12671.683207919876, 12894.67763305925, 13191.868081319064, 13292.468301267842], "value": 8090.142935738443, "unit": "score"}], "name": "dromaeo_css", "value": 7548.468019843746, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 114.0, 112.0, 112.0, 112.0, 110.0, 112.0, 112.0, 109.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 119.0, 154.0, 117.0, 121.0, 119.0, 118.0, 149.0, 118.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 160.0, 161.0, 160.0, 162.0, 161.0, 162.0, 161.0, 159.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 110.0, 75.0, 75.0, 75.0, 77.0, 99.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 117.0, 82.0, 81.0, 83.0, 104.0, 81.0, 81.0, 102.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 48.0, 48.0, 50.0, 50.0, 49.0, 47.0, 47.0, 50.0], "value": 48.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 61.0, 59.0, 59.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 73.0, 73.0, 72.0, 77.0, 73.0, 74.0, 72.0, 75.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 125.0, 124.0, 122.0, 125.0, 120.0, 121.0, 120.0, 154.0, 135.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 153.0, 153.0, 152.0, 153.0, 153.0, 154.0, 153.0, 158.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 63.0, 63.0, 63.0, 62.0, 66.0, 60.0, 62.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1456.1999999999998, "alertThreshold": 2.0}]} 22:28:23 INFO - Return code: 0 22:28:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:28:23 INFO - # TBPL SUCCESS # 22:28:23 INFO - Running post-action listener: _resource_record_post_action 22:28:23 INFO - [mozharness: 2016-10-27 05:28:23.289425Z] Finished run-tests step (success) 22:28:23 INFO - Running post-run listener: _resource_record_post_run 22:28:23 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 365117440; Read time: 24; Write time: 416648 22:28:23 INFO - TinderboxPrint: CPU usage
13.0% 22:28:23 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 22:28:23 INFO - TinderboxPrint: I/O write bytes / time
365,117,440 / 416,648 22:28:23 INFO - TinderboxPrint: CPU idle
8,260.1 (87.0%) 22:28:23 INFO - TinderboxPrint: CPU user
1,190.7 (12.5%) 22:28:23 INFO - TinderboxPrint: Swap in / out
0 / 0 22:28:23 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114434048; Read time: 0; Write time: 126608 22:28:23 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249421824; Read time: 20; Write time: 290008 22:28:23 INFO - Running post-run listener: _upload_blobber_files 22:28:23 INFO - Blob upload gear active. 22:28:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:28:23 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 22:28:23 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'] 22:28:23 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 22:28:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:28:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:28:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:28:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:25 INFO - (blobuploader) - INFO - Done attempting. 22:28:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:28:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:28:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:29 INFO - (blobuploader) - INFO - Done attempting. 22:28:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:28:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:28:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:30 INFO - (blobuploader) - INFO - Done attempting. 22:28:30 INFO - (blobuploader) - INFO - Iteration through files over. 22:28:30 INFO - Return code: 0 22:28:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:28:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:28:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f0e196ddd4d6706490b5115c8772512e65f8595c655e2553cf6b0a7a857e16aec6f56648fd4d151ebd60f390b22aefb7d878fa7a82a7d390afa08cb7cf5dbdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/163f770f610fb38a55e9438b2ddcd450535ec3aa19801f26a11a2199f943ec9a3d371f1fff0058c2fb960bed8c9065e21b44264299340930b85afee66b78c242", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c1f66ce881aad01d3973d1829a92f674a226dcfc704c9fd0d86e70237a67c5391d3cf12184cf72b23c5b53cc834f755fef379e282f13734e01e4d4d25464e3"} 22:28:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:28:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:28:30 INFO - Contents: 22:28:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f0e196ddd4d6706490b5115c8772512e65f8595c655e2553cf6b0a7a857e16aec6f56648fd4d151ebd60f390b22aefb7d878fa7a82a7d390afa08cb7cf5dbdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/163f770f610fb38a55e9438b2ddcd450535ec3aa19801f26a11a2199f943ec9a3d371f1fff0058c2fb960bed8c9065e21b44264299340930b85afee66b78c242", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c1f66ce881aad01d3973d1829a92f674a226dcfc704c9fd0d86e70237a67c5391d3cf12184cf72b23c5b53cc834f755fef379e282f13734e01e4d4d25464e3"} 22:28:30 INFO - Running post-run listener: copy_logs_to_upload_dir 22:28:30 INFO - Copying logs to upload dir... 22:28:30 INFO - mkdir: /builds/slave/test/build/upload/logs 22:28:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.562743 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-26 22:28:29.610690) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 22:28:29.613953) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348 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/9f0e196ddd4d6706490b5115c8772512e65f8595c655e2553cf6b0a7a857e16aec6f56648fd4d151ebd60f390b22aefb7d878fa7a82a7d390afa08cb7cf5dbdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/163f770f610fb38a55e9438b2ddcd450535ec3aa19801f26a11a2199f943ec9a3d371f1fff0058c2fb960bed8c9065e21b44264299340930b85afee66b78c242", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c1f66ce881aad01d3973d1829a92f674a226dcfc704c9fd0d86e70237a67c5391d3cf12184cf72b23c5b53cc834f755fef379e282f13734e01e4d4d25464e3"} build_url:https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011445 build_url: 'https://queue.taskcluster.net/v1/task/S9232CO0T6mJVX592F0Nhw/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/9f0e196ddd4d6706490b5115c8772512e65f8595c655e2553cf6b0a7a857e16aec6f56648fd4d151ebd60f390b22aefb7d878fa7a82a7d390afa08cb7cf5dbdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/163f770f610fb38a55e9438b2ddcd450535ec3aa19801f26a11a2199f943ec9a3d371f1fff0058c2fb960bed8c9065e21b44264299340930b85afee66b78c242", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c1f66ce881aad01d3973d1829a92f674a226dcfc704c9fd0d86e70237a67c5391d3cf12184cf72b23c5b53cc834f755fef379e282f13734e01e4d4d25464e3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 22:28:29.642904) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:28:29.643537) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1477544874.63883-1152852348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:28:29.725303) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 22:28:29.725703) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 22:28:29.726060) ========= ========= Total master_lag: 0.16 =========