builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1480914534.18 results: success (0) buildid: 20161204193113 builduid: 58bc2760502e4cf2b0404a112a78f415 revision: bd9e81439725f3d4135652cc3d65f2bfba527b7b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:54.180313) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:54.180797) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:54.181142) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:54.197368) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:54.197819) ========= 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=62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-04 21:08: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 213M=0s 2016-12-04 21:08:54 (213 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.167616 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:54.380829) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:54.381186) ========= 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=62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018568 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:54.431482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:54.431884) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bd9e81439725f3d4135652cc3d65f2bfba527b7b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev bd9e81439725f3d4135652cc3d65f2bfba527b7b --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=62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-04 21:08:54,623 truncating revision to first 12 chars 2016-12-04 21:08:54,623 Setting DEBUG logging. 2016-12-04 21:08:54,623 attempt 1/10 2016-12-04 21:08:54,624 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bd9e81439725?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-04 21:08:54,943 unpacking tar archive at: mozilla-central-bd9e81439725/testing/mozharness/ program finished with exit code 0 elapsedTime=0.633564 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:55.079405) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:55.079771) ========= script_repo_revision: bd9e81439725f3d4135652cc3d65f2bfba527b7b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:55.080249) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:55.080583) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 21:08:55.091581) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-04 21:08:55.091943) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:08:55 INFO - MultiFileLogger online at 20161204 21:08:55 in /builds/slave/test 21:08:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:08:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:08:55 INFO - {'append_to_log': False, 21:08:55 INFO - 'base_work_dir': '/builds/slave/test', 21:08:55 INFO - 'blob_upload_branch': 'Firefox', 21:08:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:08:55 INFO - 'branch': 'Firefox', 21:08:55 INFO - 'buildbot_json_path': 'buildprops.json', 21:08:55 INFO - 'config_files': ('talos/linux_config.py',), 21:08:55 INFO - 'default_actions': ('clobber', 21:08:55 INFO - 'read-buildbot-config', 21:08:55 INFO - 'download-and-extract', 21:08:55 INFO - 'populate-webroot', 21:08:55 INFO - 'create-virtualenv', 21:08:55 INFO - 'install', 21:08:55 INFO - 'run-tests'), 21:08:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:08:55 INFO - 'download_minidump_stackwalk': True, 21:08:55 INFO - 'download_symbols': 'ondemand', 21:08:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:08:55 INFO - 'tooltool.py': '/tools/tooltool.py', 21:08:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:08:55 INFO - '/tools/misc-python/virtualenv.py')}, 21:08:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:08:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:08:55 INFO - 'installer_path': 'installer.exe', 21:08:55 INFO - 'log_level': 'info', 21:08:55 INFO - 'log_name': 'talos', 21:08:55 INFO - 'log_to_console': True, 21:08:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:08:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:08:55 INFO - 'opt_config_files': (), 21:08:55 INFO - 'pip_index': False, 21:08:55 INFO - 'sps_profile': False, 21:08:55 INFO - 'sps_profile_interval': 0, 21:08:55 INFO - 'suite': 'dromaeojs', 21:08:55 INFO - 'system_bits': '32', 21:08:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:08:55 INFO - 'title': 'talos-linux64-ix-018', 21:08:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:08:55 INFO - 'use_talos_json': True, 21:08:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:08:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:08:55 INFO - 'work_dir': 'build'} 21:08:55 INFO - [mozharness: 2016-12-05 05:08:55.392694Z] Running clobber step. 21:08:55 INFO - Running pre-action listener: _resource_record_pre_action 21:08:55 INFO - Running main action method: clobber 21:08:55 INFO - rmtree: /builds/slave/test/build 21:08:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:08:55 INFO - Running post-action listener: _resource_record_post_action 21:08:55 INFO - [mozharness: 2016-12-05 05:08:55.850642Z] Finished clobber step (success) 21:08:55 INFO - [mozharness: 2016-12-05 05:08:55.850742Z] Running read-buildbot-config step. 21:08:55 INFO - Running pre-action listener: _resource_record_pre_action 21:08:55 INFO - Running main action method: read_buildbot_config 21:08:55 INFO - Using buildbot properties: 21:08:55 INFO - { 21:08:55 INFO - "project": "", 21:08:55 INFO - "product": "firefox", 21:08:55 INFO - "script_repo_revision": "production", 21:08:55 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:08:55 INFO - "repository": "", 21:08:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:08:55 INFO - "buildid": "20161204193113", 21:08:55 INFO - "pgo_build": "True", 21:08:55 INFO - "basedir": "/builds/slave/test", 21:08:55 INFO - "buildnumber": 96, 21:08:55 INFO - "slavename": "talos-linux64-ix-018", 21:08:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:08:55 INFO - "platform": "ubuntu64_hw", 21:08:55 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:08:55 INFO - "branch": "mozilla-central", 21:08:55 INFO - "repo_path": "mozilla-central", 21:08:55 INFO - "revision": "bd9e81439725f3d4135652cc3d65f2bfba527b7b", 21:08:55 INFO - "stage_platform": "linux64-pgo", 21:08:55 INFO - "builduid": "58bc2760502e4cf2b0404a112a78f415", 21:08:55 INFO - "slavebuilddir": "test" 21:08:55 INFO - } 21:08:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:08:55 INFO - Running post-action listener: _resource_record_post_action 21:08:55 INFO - [mozharness: 2016-12-05 05:08:55.853179Z] Finished read-buildbot-config step (success) 21:08:55 INFO - [mozharness: 2016-12-05 05:08:55.853266Z] Running download-and-extract step. 21:08:55 INFO - Running pre-action listener: _resource_record_pre_action 21:08:55 INFO - Running main action method: download_and_extract 21:08:55 INFO - mkdir: /builds/slave/test/build/tests 21:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:55 INFO - trying https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:08:55 INFO - Downloading https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:08:56 INFO - Downloaded 1361 bytes. 21:08:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:08:56 INFO - Using the following test package requirements: 21:08:56 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:08:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:08:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:08:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:56 INFO - u'jsshell-linux-x86_64.zip'], 21:08:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:08:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:08:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:08:56 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:08:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:08:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:08:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:08:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:08:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:08:56 INFO - Fetch https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:08:57 INFO - Expected file size: 21780627 21:08:57 INFO - Obtained file size: 21780627 21:09:02 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:09:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:09:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:09:02 INFO - Fetch https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:09:03 INFO - Expected file size: 21780627 21:09:03 INFO - Obtained file size: 21780627 21:09:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:09:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:09:07 INFO - Fetch https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:09:08 INFO - Expected file size: 14409734 21:09:08 INFO - Obtained file size: 14409734 21:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:09 INFO - trying https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:09:09 INFO - Downloading https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:09:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:09:11 INFO - Downloaded 59836184 bytes. 21:09:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:09:11 INFO - mkdir: /builds/slave/test/properties 21:09:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:09:11 INFO - Writing to file /builds/slave/test/properties/build_url 21:09:11 INFO - Contents: 21:09:11 INFO - build_url:https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:09:12 INFO - Running post-action listener: _resource_record_post_action 21:09:12 INFO - Running post-action listener: set_extra_try_arguments 21:09:12 INFO - [mozharness: 2016-12-05 05:09:12.649125Z] Finished download-and-extract step (success) 21:09:12 INFO - [mozharness: 2016-12-05 05:09:12.649353Z] Running populate-webroot step. 21:09:12 INFO - Running pre-action listener: _resource_record_pre_action 21:09:12 INFO - Running main action method: populate_webroot 21:09:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:09:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:09:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:09:12 INFO - '--tppagecycles', 21:09:12 INFO - '7'], 21:09:12 INFO - 'tests': ['tsvgm']}}, 21:09:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:09:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:09:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:09:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:09:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:09:12 INFO - 'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:09:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:09:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:09:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:09:12 INFO - 'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['damp', 'tps']}, 21:09:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:09:12 INFO - 'tests': ['damp', 'tps']}, 21:09:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['dromaeo_dom']}, 21:09:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:09:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['basic_compositor_video']}, 21:09:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:09:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['a11yr', 21:09:12 INFO - 'ts_paint', 21:09:12 INFO - 'tpaint', 21:09:12 INFO - 'sessionrestore', 21:09:12 INFO - 'sessionrestore_no_auto_restore', 21:09:12 INFO - 'tabpaint']}, 21:09:12 INFO - 'other-e10s': {'tests': ['a11yr', 21:09:12 INFO - 'ts_paint', 21:09:12 INFO - 'tpaint', 21:09:12 INFO - 'sessionrestore', 21:09:12 INFO - 'sessionrestore_no_auto_restore', 21:09:12 INFO - 'tabpaint']}, 21:09:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:09:12 INFO - 'ts_paint', 21:09:12 INFO - 'tpaint', 21:09:12 INFO - 'sessionrestore', 21:09:12 INFO - 'sessionrestore_no_auto_restore', 21:09:12 INFO - 'tabpaint']}, 21:09:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:09:12 INFO - 'ts_paint', 21:09:12 INFO - 'tpaint', 21:09:12 INFO - 'sessionrestore', 21:09:12 INFO - 'sessionrestore_no_auto_restore', 21:09:12 INFO - 'tabpaint']}, 21:09:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['a11yr', 21:09:12 INFO - 'ts_paint', 21:09:12 INFO - 'tpaint', 21:09:12 INFO - 'sessionrestore', 21:09:12 INFO - 'sessionrestore_no_auto_restore', 21:09:12 INFO - 'tabpaint']}, 21:09:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['a11yr', 21:09:12 INFO - 'ts_paint', 21:09:12 INFO - 'tpaint', 21:09:12 INFO - 'sessionrestore', 21:09:12 INFO - 'sessionrestore_no_auto_restore', 21:09:12 INFO - 'tabpaint']}, 21:09:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['tsvgx', 21:09:12 INFO - 'tsvgr_opacity', 21:09:12 INFO - 'tart', 21:09:12 INFO - 'tscrollx', 21:09:12 INFO - 'cart', 21:09:12 INFO - 'tsvg_static']}, 21:09:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:09:12 INFO - 'tsvgr_opacity', 21:09:12 INFO - 'tart', 21:09:12 INFO - 'tscrollx', 21:09:12 INFO - 'cart', 21:09:12 INFO - 'tsvg_static']}, 21:09:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:09:12 INFO - 'talos_options': ['--disable-e10s'], 21:09:12 INFO - 'tests': ['tp5o']}, 21:09:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:09:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:09:12 INFO - 'talos_options': ['--disable-e10s', 21:09:12 INFO - '--xperf_path', 21:09:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:09:12 INFO - 'tests': ['tp5n']}, 21:09:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:09:12 INFO - 'talos_options': ['--xperf_path', 21:09:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:09:12 INFO - 'tests': ['tp5n']}}, 21:09:12 INFO - 'talos.zip': {'path': '', 21:09:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:09:12 INFO - Running post-action listener: _resource_record_post_action 21:09:12 INFO - [mozharness: 2016-12-05 05:09:12.668847Z] Finished populate-webroot step (success) 21:09:12 INFO - [mozharness: 2016-12-05 05:09:12.668936Z] Running create-virtualenv step. 21:09:12 INFO - Running pre-action listener: _resource_record_pre_action 21:09:12 INFO - Running main action method: create_virtualenv 21:09:12 INFO - Creating virtualenv /builds/slave/test/build/venv 21:09:12 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 21:09:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:09:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:09:12 INFO - Using real prefix '/usr' 21:09:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:09:13 INFO - Installing distribute.............................................................................................................................................................................................done. 21:09:15 INFO - Installing pip.................done. 21:09:15 INFO - Return code: 0 21:09:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:09:15 INFO - Reading from file tmpfile_stdout 21:09:15 INFO - Output received: 21:09:15 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:09:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:09:15 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')]} 21:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:15 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')]} 21:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:15 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 0x7fdf1ca53e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b93120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, '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': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', '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 21:09:15 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 21:09:15 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 21:09:15 INFO - Using env: {'DISPLAY': ':0', 21:09:15 INFO - 'HOME': '/home/cltbld', 21:09:15 INFO - 'LANG': 'en_US.UTF-8', 21:09:15 INFO - 'LANGUAGE': 'en_US:en', 21:09:15 INFO - 'LOGNAME': 'cltbld', 21:09:15 INFO - 'MAIL': '/var/mail/cltbld', 21:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:15 INFO - 'MOZ_NO_REMOTE': '1', 21:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:15 INFO - 'NO_EM_RESTART': '1', 21:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:15 INFO - 'PWD': '/builds/slave/test', 21:09:15 INFO - 'SHELL': '/bin/bash', 21:09:15 INFO - 'SHLVL': '1', 21:09:15 INFO - 'TERM': 'linux', 21:09:15 INFO - 'TMOUT': '86400', 21:09:15 INFO - 'USER': 'cltbld', 21:09:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:15 INFO - '_': '/tools/buildbot/bin/python'} 21:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:15 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 21:09:15 INFO - Cleaning up... 21:09:15 INFO - Return code: 0 21:09:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:09:15 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')]} 21:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:15 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')]} 21:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:15 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 0x7fdf1ca53e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b93120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, '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': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', '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 21:09:15 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 21:09:15 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 21:09:15 INFO - Using env: {'DISPLAY': ':0', 21:09:15 INFO - 'HOME': '/home/cltbld', 21:09:15 INFO - 'LANG': 'en_US.UTF-8', 21:09:15 INFO - 'LANGUAGE': 'en_US:en', 21:09:15 INFO - 'LOGNAME': 'cltbld', 21:09:15 INFO - 'MAIL': '/var/mail/cltbld', 21:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:15 INFO - 'MOZ_NO_REMOTE': '1', 21:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:15 INFO - 'NO_EM_RESTART': '1', 21:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:15 INFO - 'PWD': '/builds/slave/test', 21:09:15 INFO - 'SHELL': '/bin/bash', 21:09:15 INFO - 'SHLVL': '1', 21:09:15 INFO - 'TERM': 'linux', 21:09:15 INFO - 'TMOUT': '86400', 21:09:15 INFO - 'USER': 'cltbld', 21:09:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:15 INFO - '_': '/tools/buildbot/bin/python'} 21:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:15 INFO - Downloading/unpacking psutil>=3.1.1 21:09:15 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 21:09:15 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 21:09:15 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 21:09:15 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 21:09:15 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 21:09:15 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 21:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:09:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:09:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:09:17 INFO - Installing collected packages: psutil 21:09:17 INFO - Running setup.py install for psutil 21:09:18 INFO - building 'psutil._psutil_linux' extension 21:09:18 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 21:09:18 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 21:09:18 INFO - building 'psutil._psutil_posix' extension 21:09:18 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 21:09:18 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 21:09:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:09:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:09:18 INFO - Successfully installed psutil 21:09:18 INFO - Cleaning up... 21:09:18 INFO - Return code: 0 21:09:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:09: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')]} 21:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09: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')]} 21:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf1ca53e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b93120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, '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': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', '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 21:09: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:09: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 mozsystemmonitor==0.3 21:09:18 INFO - Using env: {'DISPLAY': ':0', 21:09:18 INFO - 'HOME': '/home/cltbld', 21:09:18 INFO - 'LANG': 'en_US.UTF-8', 21:09:18 INFO - 'LANGUAGE': 'en_US:en', 21:09:18 INFO - 'LOGNAME': 'cltbld', 21:09:18 INFO - 'MAIL': '/var/mail/cltbld', 21:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:18 INFO - 'MOZ_NO_REMOTE': '1', 21:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:18 INFO - 'NO_EM_RESTART': '1', 21:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:18 INFO - 'PWD': '/builds/slave/test', 21:09:18 INFO - 'SHELL': '/bin/bash', 21:09:18 INFO - 'SHLVL': '1', 21:09:18 INFO - 'TERM': 'linux', 21:09:18 INFO - 'TMOUT': '86400', 21:09:18 INFO - 'USER': 'cltbld', 21:09:18 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:18 INFO - '_': '/tools/buildbot/bin/python'} 21:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:09:20 INFO - Installing collected packages: mozsystemmonitor 21:09:20 INFO - Running setup.py install for mozsystemmonitor 21:09:20 INFO - Successfully installed mozsystemmonitor 21:09:20 INFO - Cleaning up... 21:09:20 INFO - Return code: 0 21:09:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:09:20 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')]} 21:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:20 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')]} 21:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:20 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 0x7fdf1ca53e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b93120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, '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': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', '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 21:09:20 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 21:09:20 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 21:09:20 INFO - Using env: {'DISPLAY': ':0', 21:09:20 INFO - 'HOME': '/home/cltbld', 21:09:20 INFO - 'LANG': 'en_US.UTF-8', 21:09:20 INFO - 'LANGUAGE': 'en_US:en', 21:09:20 INFO - 'LOGNAME': 'cltbld', 21:09:20 INFO - 'MAIL': '/var/mail/cltbld', 21:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:20 INFO - 'MOZ_NO_REMOTE': '1', 21:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:20 INFO - 'NO_EM_RESTART': '1', 21:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:20 INFO - 'PWD': '/builds/slave/test', 21:09:20 INFO - 'SHELL': '/bin/bash', 21:09:20 INFO - 'SHLVL': '1', 21:09:20 INFO - 'TERM': 'linux', 21:09:20 INFO - 'TMOUT': '86400', 21:09:20 INFO - 'USER': 'cltbld', 21:09:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:20 INFO - '_': '/tools/buildbot/bin/python'} 21:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:21 INFO - Downloading/unpacking jsonschema==2.5.1 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:09:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09:23 INFO - Downloading functools32-3.2.3-2.tar.gz 21:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:09:23 INFO - warning: no files found matching '*.txt' 21:09:23 INFO - no previously-included directories found matching 'build' 21:09:23 INFO - no previously-included directories found matching 'dist' 21:09:23 INFO - no previously-included directories found matching '.git*' 21:09:23 INFO - Installing collected packages: jsonschema, functools32 21:09:23 INFO - Running setup.py install for functools32 21:09:23 INFO - warning: no files found matching '*.txt' 21:09:23 INFO - no previously-included directories found matching 'build' 21:09:23 INFO - no previously-included directories found matching 'dist' 21:09:23 INFO - no previously-included directories found matching '.git*' 21:09:23 INFO - Successfully installed jsonschema functools32 21:09:23 INFO - Cleaning up... 21:09:23 INFO - Return code: 0 21:09:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:09:23 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')]} 21:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:23 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')]} 21:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:23 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 0x7fdf1ca53e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b93120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, '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': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', '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 21:09:23 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 21:09:23 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 21:09:23 INFO - Using env: {'DISPLAY': ':0', 21:09:23 INFO - 'HOME': '/home/cltbld', 21:09:23 INFO - 'LANG': 'en_US.UTF-8', 21:09:23 INFO - 'LANGUAGE': 'en_US:en', 21:09:23 INFO - 'LOGNAME': 'cltbld', 21:09:23 INFO - 'MAIL': '/var/mail/cltbld', 21:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:23 INFO - 'MOZ_NO_REMOTE': '1', 21:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:23 INFO - 'NO_EM_RESTART': '1', 21:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:23 INFO - 'PWD': '/builds/slave/test', 21:09:23 INFO - 'SHELL': '/bin/bash', 21:09:23 INFO - 'SHLVL': '1', 21:09:23 INFO - 'TERM': 'linux', 21:09:23 INFO - 'TMOUT': '86400', 21:09:23 INFO - 'USER': 'cltbld', 21:09:23 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:23 INFO - '_': '/tools/buildbot/bin/python'} 21:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:09:23 INFO - Cleaning up... 21:09:23 INFO - Return code: 0 21:09:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:09:23 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')]} 21:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:23 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')]} 21:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:23 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 0x7fdf1ca53e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b93120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, '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': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', '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 21:09:23 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 21:09:23 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 21:09:23 INFO - Using env: {'DISPLAY': ':0', 21:09:23 INFO - 'HOME': '/home/cltbld', 21:09:23 INFO - 'LANG': 'en_US.UTF-8', 21:09:23 INFO - 'LANGUAGE': 'en_US:en', 21:09:23 INFO - 'LOGNAME': 'cltbld', 21:09:23 INFO - 'MAIL': '/var/mail/cltbld', 21:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:23 INFO - 'MOZ_NO_REMOTE': '1', 21:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:23 INFO - 'NO_EM_RESTART': '1', 21:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:23 INFO - 'PWD': '/builds/slave/test', 21:09:23 INFO - 'SHELL': '/bin/bash', 21:09:23 INFO - 'SHLVL': '1', 21:09:23 INFO - 'TERM': 'linux', 21:09:23 INFO - 'TMOUT': '86400', 21:09:23 INFO - 'USER': 'cltbld', 21:09:23 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:23 INFO - '_': '/tools/buildbot/bin/python'} 21:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:24 INFO - Downloading/unpacking blobuploader==1.2.4 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09:25 INFO - Downloading blobuploader-1.2.4.tar.gz 21:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:09:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:09:26 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 21:09:26 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 21:09:26 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 21:09:26 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 21:09:26 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 21:09:26 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 21:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:09:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:09:26 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 21:09:26 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 21:09:26 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 21:09:26 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 21:09:26 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 21:09:26 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 21:09:26 INFO - Downloading docopt-0.6.1.tar.gz 21:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:09:27 INFO - Installing collected packages: blobuploader, requests, docopt 21:09:27 INFO - Running setup.py install for blobuploader 21:09:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:09:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:09:27 INFO - Running setup.py install for requests 21:09:27 INFO - Running setup.py install for docopt 21:09:27 INFO - Successfully installed blobuploader requests docopt 21:09:27 INFO - Cleaning up... 21:09:27 INFO - Return code: 0 21:09:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:27 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 0x7fdf1ca53e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b93120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, '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': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', '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 21:09:27 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 21:09:27 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 21:09:27 INFO - Using env: {'DISPLAY': ':0', 21:09:27 INFO - 'HOME': '/home/cltbld', 21:09:27 INFO - 'LANG': 'en_US.UTF-8', 21:09:27 INFO - 'LANGUAGE': 'en_US:en', 21:09:27 INFO - 'LOGNAME': 'cltbld', 21:09:27 INFO - 'MAIL': '/var/mail/cltbld', 21:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:27 INFO - 'MOZ_NO_REMOTE': '1', 21:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:27 INFO - 'NO_EM_RESTART': '1', 21:09:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:27 INFO - 'PWD': '/builds/slave/test', 21:09:27 INFO - 'SHELL': '/bin/bash', 21:09:27 INFO - 'SHLVL': '1', 21:09:27 INFO - 'TERM': 'linux', 21:09:27 INFO - 'TMOUT': '86400', 21:09:27 INFO - 'USER': 'cltbld', 21:09:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:27 INFO - '_': '/tools/buildbot/bin/python'} 21:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:09:27 INFO - Running setup.py (path:/tmp/pip-GymatA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:09:27 INFO - Running setup.py (path:/tmp/pip-uarCeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:09:28 INFO - Running setup.py (path:/tmp/pip-S7mKl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:09:28 INFO - Running setup.py (path:/tmp/pip-pmtsWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:09:28 INFO - Running setup.py (path:/tmp/pip-eqT7m6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:09:28 INFO - Running setup.py (path:/tmp/pip-ff3koH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:09:28 INFO - Running setup.py (path:/tmp/pip-oCdKFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:09:28 INFO - Running setup.py (path:/tmp/pip-MubrQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:09:28 INFO - Running setup.py (path:/tmp/pip-APOAmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:09:28 INFO - Running setup.py (path:/tmp/pip-8zUALl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:09:28 INFO - Running setup.py (path:/tmp/pip-Vz7fJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:09:28 INFO - Running setup.py (path:/tmp/pip-5QP9n1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:09:28 INFO - Running setup.py (path:/tmp/pip-XWIMVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:09:28 INFO - Running setup.py (path:/tmp/pip-lGQDOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:28 INFO - Running setup.py (path:/tmp/pip-fwtvqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:09:28 INFO - Running setup.py (path:/tmp/pip-ztsoXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:09:29 INFO - Running setup.py (path:/tmp/pip-eF76yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:09:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:09:29 INFO - Running setup.py install for manifestparser 21:09:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:09:29 INFO - Running setup.py install for mozcrash 21:09:29 INFO - Running setup.py install for mozdebug 21:09:29 INFO - Running setup.py install for mozdevice 21:09:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:09:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:09:29 INFO - Running setup.py install for mozfile 21:09:29 INFO - Running setup.py install for mozhttpd 21:09:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:09:29 INFO - Running setup.py install for mozinfo 21:09:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:09:29 INFO - Running setup.py install for mozInstall 21:09:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:09:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:09:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:09:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:09:29 INFO - Running setup.py install for mozleak 21:09:29 INFO - Running setup.py install for mozlog 21:09:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:09:30 INFO - Running setup.py install for moznetwork 21:09:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:09:30 INFO - Running setup.py install for mozprocess 21:09:30 INFO - Running setup.py install for mozprofile 21:09:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:09:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:09:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:09:30 INFO - Running setup.py install for mozrunner 21:09:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:09:30 INFO - Running setup.py install for mozscreenshot 21:09:30 INFO - Running setup.py install for moztest 21:09:30 INFO - Running setup.py install for mozversion 21:09:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:09:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:09:30 INFO - Cleaning up... 21:09:30 INFO - Return code: 0 21:09:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:30 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 0x7fdf1ca53e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b93120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, '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': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', '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 21:09:30 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 21:09:30 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 21:09:30 INFO - Using env: {'DISPLAY': ':0', 21:09:30 INFO - 'HOME': '/home/cltbld', 21:09:30 INFO - 'LANG': 'en_US.UTF-8', 21:09:30 INFO - 'LANGUAGE': 'en_US:en', 21:09:30 INFO - 'LOGNAME': 'cltbld', 21:09:30 INFO - 'MAIL': '/var/mail/cltbld', 21:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:30 INFO - 'MOZ_NO_REMOTE': '1', 21:09:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:30 INFO - 'NO_EM_RESTART': '1', 21:09:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:30 INFO - 'PWD': '/builds/slave/test', 21:09:30 INFO - 'SHELL': '/bin/bash', 21:09:30 INFO - 'SHLVL': '1', 21:09:30 INFO - 'TERM': 'linux', 21:09:30 INFO - 'TMOUT': '86400', 21:09:30 INFO - 'USER': 'cltbld', 21:09:30 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:30 INFO - '_': '/tools/buildbot/bin/python'} 21:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:09:31 INFO - Running setup.py (path:/tmp/pip-c9Vngj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:09:31 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:09:31 INFO - Running setup.py (path:/tmp/pip-Ig0_TJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:09:31 INFO - Running setup.py (path:/tmp/pip-Yfu3m4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:09:31 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:09:31 INFO - Running setup.py (path:/tmp/pip-th7mZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:09:31 INFO - Running setup.py (path:/tmp/pip-qGmnbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:09:31 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:09:31 INFO - Running setup.py (path:/tmp/pip-vD3E3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:09:31 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:09:31 INFO - Running setup.py (path:/tmp/pip-v5221Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:09:31 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:09:31 INFO - Running setup.py (path:/tmp/pip-wqnwgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:09:31 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:09:31 INFO - Running setup.py (path:/tmp/pip-dD6tUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:09:31 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:09:31 INFO - Running setup.py (path:/tmp/pip-nUggRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:09:31 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:09:31 INFO - Running setup.py (path:/tmp/pip-jeOp7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:09:31 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:09:31 INFO - Running setup.py (path:/tmp/pip-RJnuD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:09:31 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)) 21:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:09:31 INFO - Running setup.py (path:/tmp/pip-v42GFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:09:32 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)) 21:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:09:32 INFO - Running setup.py (path:/tmp/pip-UU1VF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 21:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:32 INFO - Running setup.py (path:/tmp/pip-iPGjEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:32 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)) 21:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:09:32 INFO - Running setup.py (path:/tmp/pip-a4Z9Z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:09:32 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)) 21:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:09:32 INFO - Running setup.py (path:/tmp/pip-tk7CzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:09:32 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)) 21:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:09:32 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)) 21:09:32 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:09:32 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:09:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:09:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:09:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:09:34 INFO - Downloading blessings-1.6.tar.gz 21:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:09:34 INFO - Installing collected packages: blessings 21:09:34 INFO - Running setup.py install for blessings 21:09:34 INFO - Successfully installed blessings 21:09:34 INFO - Cleaning up... 21:09:34 INFO - Return code: 0 21:09:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:09:34 INFO - Reading from file tmpfile_stdout 21:09:34 INFO - Current package versions: 21:09:34 INFO - argparse == 1.2.1 21:09:34 INFO - blessings == 1.6 21:09:34 INFO - blobuploader == 1.2.4 21:09:34 INFO - docopt == 0.6.1 21:09:34 INFO - functools32 == 3.2.3-2 21:09:34 INFO - jsonschema == 2.5.1 21:09:34 INFO - manifestparser == 1.1 21:09:34 INFO - mozInstall == 1.12 21:09:34 INFO - mozcrash == 1.0 21:09:34 INFO - mozdebug == 0.1 21:09:34 INFO - mozdevice == 0.49 21:09:34 INFO - mozfile == 1.2 21:09:34 INFO - mozhttpd == 0.7 21:09:34 INFO - mozinfo == 0.9 21:09:34 INFO - mozleak == 0.1 21:09:34 INFO - mozlog == 3.4 21:09:34 INFO - moznetwork == 0.27 21:09:34 INFO - mozprocess == 0.23 21:09:34 INFO - mozprofile == 0.28 21:09:34 INFO - mozrunner == 6.13 21:09:34 INFO - mozscreenshot == 0.1 21:09:34 INFO - mozsystemmonitor == 0.3 21:09:34 INFO - moztest == 0.7 21:09:34 INFO - mozversion == 1.4 21:09:34 INFO - psutil == 3.1.1 21:09:34 INFO - requests == 1.2.3 21:09:34 INFO - wsgiref == 0.1.2 21:09:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:09:34 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')]} 21:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:34 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')]} 21:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:34 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 0x7fdf1ca53e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b93120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, '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': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', '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 21:09:34 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 21:09:34 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 21:09:34 INFO - Using env: {'DISPLAY': ':0', 21:09:34 INFO - 'HOME': '/home/cltbld', 21:09:34 INFO - 'LANG': 'en_US.UTF-8', 21:09:34 INFO - 'LANGUAGE': 'en_US:en', 21:09:34 INFO - 'LOGNAME': 'cltbld', 21:09:34 INFO - 'MAIL': '/var/mail/cltbld', 21:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:34 INFO - 'MOZ_NO_REMOTE': '1', 21:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:34 INFO - 'NO_EM_RESTART': '1', 21:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:34 INFO - 'PWD': '/builds/slave/test', 21:09:34 INFO - 'SHELL': '/bin/bash', 21:09:34 INFO - 'SHLVL': '1', 21:09:34 INFO - 'TERM': 'linux', 21:09:34 INFO - 'TMOUT': '86400', 21:09:34 INFO - 'USER': 'cltbld', 21:09:34 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:34 INFO - '_': '/tools/buildbot/bin/python'} 21:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:35 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)) 21:09:35 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)) 21:09:35 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)) 21:09:35 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)) 21:09:35 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)) 21:09:35 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)) 21:09:35 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)) 21:09:35 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)) 21:09:35 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)) 21:09:35 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)) 21:09:35 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)) 21:09:35 INFO - Cleaning up... 21:09:35 INFO - Return code: 0 21:09:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf1ca53e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b93120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, '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': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', '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 21:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:09:35 INFO - Using env: {'DISPLAY': ':0', 21:09:35 INFO - 'HOME': '/home/cltbld', 21:09:35 INFO - 'LANG': 'en_US.UTF-8', 21:09:35 INFO - 'LANGUAGE': 'en_US:en', 21:09:35 INFO - 'LOGNAME': 'cltbld', 21:09:35 INFO - 'MAIL': '/var/mail/cltbld', 21:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:35 INFO - 'MOZ_NO_REMOTE': '1', 21:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:35 INFO - 'NO_EM_RESTART': '1', 21:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:35 INFO - 'PWD': '/builds/slave/test', 21:09:35 INFO - 'SHELL': '/bin/bash', 21:09:35 INFO - 'SHLVL': '1', 21:09:35 INFO - 'TERM': 'linux', 21:09:35 INFO - 'TMOUT': '86400', 21:09:35 INFO - 'USER': 'cltbld', 21:09:35 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:35 INFO - '_': '/tools/buildbot/bin/python'} 21:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:09:35 INFO - Cleaning up... 21:09:35 INFO - Return code: 0 21:09:35 INFO - Running post-action listener: _resource_record_post_action 21:09:35 INFO - Running post-action listener: _start_resource_monitoring 21:09:35 INFO - Starting resource monitoring. 21:09:35 INFO - [mozharness: 2016-12-05 05:09:35.363861Z] Finished create-virtualenv step (success) 21:09:35 INFO - [mozharness: 2016-12-05 05:09:35.364328Z] Running install step. 21:09:35 INFO - Running pre-action listener: _resource_record_pre_action 21:09:35 INFO - Running main action method: install 21:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:09:35 INFO - Reading from file tmpfile_stdout 21:09:35 INFO - Detecting whether we're running mozinstall >=1.0... 21:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:09:35 INFO - Reading from file tmpfile_stdout 21:09:35 INFO - Output received: 21:09:35 INFO - Usage: mozinstall [options] installer 21:09:35 INFO - Options: 21:09:35 INFO - -h, --help show this help message and exit 21:09:35 INFO - -d DEST, --destination=DEST 21:09:35 INFO - Directory to install application into. [default: 21:09:35 INFO - "/builds/slave/test"] 21:09:35 INFO - --app=APP Application being installed. [default: firefox] 21:09:35 INFO - mkdir: /builds/slave/test/build/application 21:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:09:53 INFO - Reading from file tmpfile_stdout 21:09:53 INFO - Output received: 21:09:53 INFO - /builds/slave/test/build/application/firefox/firefox 21:09:53 INFO - Running post-action listener: _resource_record_post_action 21:09:53 INFO - [mozharness: 2016-12-05 05:09:53.456847Z] Finished install step (success) 21:09:53 INFO - [mozharness: 2016-12-05 05:09:53.457089Z] Running run-tests step. 21:09:53 INFO - Running pre-action listener: _resource_record_pre_action 21:09:53 INFO - Running main action method: run_tests 21:09:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:09:53 INFO - Python 2.7.3 21:09:53 INFO - Return code: 0 21:09:53 INFO - grabbing minidump binary from tooltool 21:09:53 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')]} 21:09:53 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 0x1c23f20>, '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 0x1b90c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b90f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:09:53 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 21:09:53 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 21:09:53 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 21:09:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:09:53 INFO - Return code: 0 21:09:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:09:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:09:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:09:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:09:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:09:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 21:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 21:09:53 INFO - Using env: {'DISPLAY': ':0', 21:09:53 INFO - 'HOME': '/home/cltbld', 21:09:53 INFO - 'LANG': 'en_US.UTF-8', 21:09:53 INFO - 'LANGUAGE': 'en_US:en', 21:09:53 INFO - 'LOGNAME': 'cltbld', 21:09:53 INFO - 'MAIL': '/var/mail/cltbld', 21:09:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:09:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:53 INFO - 'MOZ_NO_REMOTE': '1', 21:09:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:09:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:53 INFO - 'NO_EM_RESTART': '1', 21:09:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:53 INFO - 'PWD': '/builds/slave/test', 21:09:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:09:53 INFO - 'SHELL': '/bin/bash', 21:09:53 INFO - 'SHLVL': '1', 21:09:53 INFO - 'TERM': 'linux', 21:09:53 INFO - 'TMOUT': '86400', 21:09:53 INFO - 'USER': 'cltbld', 21:09:53 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663', 21:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:53 INFO - '_': '/tools/buildbot/bin/python'} 21:09:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 21:09:53 INFO - mozversion application_buildid: 20161204193113 21:09:53 INFO - mozversion application_changeset: bd9e81439725f3d4135652cc3d65f2bfba527b7b 21:09:53 INFO - mozversion application_display_name: Nightly 21:09:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:09:53 INFO - mozversion application_name: Firefox 21:09:53 INFO - mozversion application_remotingname: firefox 21:09:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:09:53 INFO - mozversion application_vendor: Mozilla 21:09:53 INFO - mozversion application_version: 53.0a1 21:09:53 INFO - mozversion platform_buildid: 20161204193113 21:09:53 INFO - mozversion platform_changeset: bd9e81439725f3d4135652cc3d65f2bfba527b7b 21:09:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:09:53 INFO - mozversion platform_version: 53.0a1 21:09:53 INFO - using testdate: 1480914593 21:09:53 INFO - actual date: 1480914593 21:09:53 INFO - starting webserver on 'localhost:55101' 21:09:53 INFO - SUITE-START | Running 2 tests 21:09:53 INFO - TEST-START | dromaeo_css 21:09:53 INFO - operating with platform_type : linux_ 21:09:53 INFO - Initialising browser for dromaeo_css test... 21:09:53 INFO - TEST-INFO | started process 16069 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3WjhL_/profile http://localhost:55101/getInfo.html) 21:09:57 INFO - PROCESS | 16069 | __metrics Screen width/height:1600/1200 21:09:57 INFO - PROCESS | 16069 | colorDepth:24 21:09:57 INFO - PROCESS | 16069 | Browser inner width/height: 1024/697 21:09:57 INFO - PROCESS | 16069 | __metrics 21:09:58 INFO - PROCESS | 16069 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:09:58 INFO - PROCESS | 16069 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:09:58 INFO - PROCESS | 16069 | console.error: PushService: 21:09:58 INFO - PROCESS | 16069 | stateChangeProcessEnqueue: Error transitioning state 21:09:58 INFO - PROCESS | 16069 | UnknownError 21:09:59 INFO - TEST-INFO | 16069: exit 0 21:09:59 INFO - Browser initialized. 21:09:59 INFO - Running cycle 1/1 for dromaeo_css test... 21:09:59 INFO - TEST-INFO | started process 16152 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3WjhL_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:10:11 INFO - PROCESS | 16152 | RSS: Main: 172060672 21:10:11 INFO - PROCESS | 16152 | 21:11:02 INFO - PROCESS | 16152 | 1480914662894 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] 21:13:12 INFO - PROCESS | 16152 | Cycle 1(1): loaded http://localhost:55101/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55101/tests/dromaeo/cssquery-ext.html) 21:13:14 INFO - PROCESS | 16152 | RSS: Main: 390553600 21:13:14 INFO - PROCESS | 16152 | 21:16:17 INFO - PROCESS | 16152 | Cycle 1(1): loaded http://localhost:55101/tests/dromaeo/cssquery-ext.html (next: http://localhost:55101/tests/dromaeo/cssquery-jquery.html) 21:16:17 INFO - PROCESS | 16152 | RSS: Main: 289615872 21:16:17 INFO - PROCESS | 16152 | 21:19:19 INFO - PROCESS | 16152 | Cycle 1(1): loaded http://localhost:55101/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55101/tests/dromaeo/cssquery-mootools.html) 21:19:20 INFO - PROCESS | 16152 | RSS: Main: 227794944 21:19:20 INFO - PROCESS | 16152 | 21:22:22 INFO - PROCESS | 16152 | Cycle 1(1): loaded http://localhost:55101/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55101/tests/dromaeo/cssquery-prototype.html) 21:22:23 INFO - PROCESS | 16152 | RSS: Main: 284598272 21:22:23 INFO - PROCESS | 16152 | 21:25:25 INFO - PROCESS | 16152 | Cycle 1(1): loaded http://localhost:55101/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55101/tests/dromaeo/cssquery-yui.html) 21:25:26 INFO - PROCESS | 16152 | RSS: Main: 227356672 21:25:26 INFO - PROCESS | 16152 | 21:28:29 INFO - PROCESS | 16152 | Cycle 1(1): loaded http://localhost:55101/tests/dromaeo/cssquery-yui.html (next: http://localhost:55101/tests/dromaeo/cssquery-dojo.html) 21:28:29 INFO - PROCESS | 16152 | RSS: Main: 314257408 21:28:29 INFO - PROCESS | 16152 | 21:28:29 INFO - PROCESS | 16152 | __start_tp_report 21:28:29 INFO - PROCESS | 16152 | _x_x_mozilla_page_load 21:28:29 INFO - PROCESS | 16152 | _x_x_mozilla_page_load_details 21:28:29 INFO - PROCESS | 16152 | |i|pagename|runs| 21:28:29 INFO - PROCESS | 16152 | |0;dojo.html;3172.9206769830753;3184.6019247594036;3203.4073696366167;3236.1585987643207;3255.3814775192714;5137.511936366049;5163.922541161881;5205.011047900905;5252.579793616506;5272.23552584875;5211.947880521194;5218.791248350061;5290.867728306795;5304.443033481487;5327.627066105364;4742.478327383985;4743.976280118594;4775.3075804008395;4788.688735232199;4816.44610869751;4856;4863.610911127119;4910.7299098549565;4913.975430122844;4953.801847926079;4689.460712018094;4838.346823178897;4864.124457597631;4895.11887860185;4965.602751779849;4704.223803072523;4731.692439991424;4735.5974742146955;4743.691660042121;4761.119192949281;4713.646476514241;4727.2436410174205;4730.597899178574;4771.904561908777;4790.281457781326;5154.974225128888;5179.404368497596;5181.145111056671;5189.662671926313;5197.818076367347;5404.216388623667;5440.211169380417;5500.477454641803;5536.529395001429;5558.499735023827;5592.412796656374;5608.298962629671;5611.158326251095;5650.660960342392;5666.546676265889;5244.711540865251;5253.26454296395;5257.027449921777;5257.421683614799;5311.256424100629;5534.557235421156;5592.161175823659;5680.517156041783;5685;5686.2323586315315;5513.393526712059;5532.363778165482;5538.806141785099;5576.442355764391;5586.91619625706;5566.526845218121;5617.185508101385;5621.550275977912;5622.690752008679;5625.493705566519;5362.463753624685;5407.594430417735;5510.090835012258;5594.468167605442;5595.21666966625;5482.396936337;5505.917411238835;5508.421615730291;5542.057850165482;5580.525655319262;4330.870073897788;4338.349247612883;4339.218940590724;4343.109662519176;4377.233984052778;3902.824372903226;3935.5080614923136;3943.467631869661;3961.603839616015;3995.300822356076;5216.191490319006;5258.2638430619745;5285.841424757264;5303.681779093266;5337.839864804062;5606.103023516218;5608.49523542875;5621.522170615462;5627.155926611041;5628.212050312959;5269.525742683177;5269.657472264291;5307.6019298552765;5310.097283461821;5360.222767698662;4757.881052973634;4805.207140821795;4845.733484658307;4846.854594362175;4868.537488938545;5797.971010144923;5803.303603567599;5816.447437493347;5822.621529600647;5858.091995740667;5575.275214222125;5606.495415412633;5618.606697531297;5624.240727501735;5635.718214089361;5649.406812284651;5677.858053548694;5691.14632805066;5770.653760774367;5821.679807610452;5478.150886612581;5478.534324582455;5480.972595136999;5482.205080263419;5484.149956756709;5521.365043020104;5574.804881829116;5576.470235327637;5577.274954256083;5580.804671836466;5419.214213939035;5434.891302173855;5438.211459338453;5439.48324909133;5442;5449.945500545103;5460.890782184413;5462.180672899097;5466.91799622998;5468.945310546844;5397.514223719884;5398.109311963639;5402.540784033401;5403.351597808288;5407.053765591085;5401.081816091352;5401.594880384066;5409.134538473825;5426.538744206787;5497.45025497447;5283.418823929288;5339.092354299856;5339.492748188916;5350.598705097211;5390.407055223844 21:28:29 INFO - PROCESS | 16152 | |1;ext.html;594.7264258441116;597.6682940967762;630.4893036640321;635.8187916443811;639.9104125422439;39060.80469597651;39981.80009099954;40268.99999999996;43314.783426082904;43632.12735745283;41700;42326.57673423265;42480;42492.15015699684;42494;7756.340711039568;8397.832043359145;8640.913590864106;8649.69726059588;8654.567271636393;7735;10033.347832390908;10293.433861137635;10300.536475858567;10623.521941512612;10279.280450368477;10349.482525873715;10360.37837729735;10385.948070259637;10394.428306443142;10169;10195.541200645952;10199.54102065409;10246.692599222035;10266.794664106676;10103.797924041479;10166.440845753481;10195.694129176136;10196.490175491195;10196.49017549127;38900.22199555993;39500;39578.80210598958;39582.406263906065;39584.40623390651;3428.7599868009247;3495.353359628477;3499.5450591423205;3700.35243832331;3872.244912242086;29510.852445737848;29566;29573.55639665407;29782.40435191284;29839.10482685523;4092.242935057024;4154.667626589866;4190.224808410423;4374.58441448062;4382.255016647177;309373;310401.2399690026;311636;312053;312988;53266.467335326925;53500.73249633727;53573.9819513538;53609.1958620621;53636.80900628868;57550.71224643934;57556.71221643865;57597;57649;57655.71172144113;14841.628959276104;14986.850131498764;14994.625134371729;14995.925020374829;15000.174990375634;11016.449177541092;11251.606193783342;11416.200865939305;11439.428028598537;12553.184043037174;17825.910870445565;18113.818861811214;18983.62032759337;19040.61918761617;19068.80931190698;6352.7141278642575;7086.149662040588;7153.7138514459875;7352.889706654404;7366.668499917517;889.2397000564424;933.4119504711988;1006.3509036671306;1038.2680210451613;1061.3578784835063;30267.243318917204;30267.394652106836;30477.23806904845;30758;31000.68999310023;1032.5611615063717;1059.4014381874217;1071.6838532632892;1119.5242022140558;1127.261643623428;15255.61860953463;15722.371105155895;15833.841661583467;15979.760303595456;15984.360625574847;502700;504618;505179;505966;507071;36988.81505592455;36988.81505592455;37221.627783721815;37354.81322593424;37581;1170.5551890281808;1230.5262473947416;1272.7963525835821;1294.139397300822;1334.3728447629599;28590.857045714638;31028;31294.37411251808;31526.527102093947;31976.52035219427;47308.52691473179;47878.28182577195;47909;47912;47930.76034619805;288153;288790;288964;289175;290019;26885.86557067202;27576.724232757417;27771.44457110887;29342.065793420486;38644.6135538641;43925.341119882585;44143.33784993163;44331;44483.33275000813;45882;37222;37454.81272593619;37886;38116;38645.2270954585;36655.45016824803;37780.811095944344;38157;38474;38891.2221755569;37408;37984.43023354707;38398.42402363911;38873.8056309728;38948.61051389563;32762;34535.481967771004;35184.29631407409;35567.64432355747;35568.644313556535;26574.335641609116;26720.732792671824;26847;27107.45785084248;27170.592441113793 21:28:29 INFO - PROCESS | 16152 | |2;jquery.html;365.97255205859534;375.9548854137502;394.91509325495014;398.98259438431995;415.48064918851435;13711.65720856979;14558.490452834152;16231.675366492664;18874.90562547189;19145.808541914575;16910.661786764256;17305.653886922253;17425.825741742578;17931.193096310664;18108.728369074473;5068.847934561969;5254.474552544753;5255.395629502618;5259.421463638997;5261.815836445725;4631.235846085392;4765.142274390609;5177.249298851664;5181.1969144782615;5201.583873290147;5223.529882310591;5227.5295223429885;5228.503292187236;5231.136862417697;5231.529162375386;5066.518680725344;5096.668716533413;5107;5148.304978827885;5166.5608423283875;4979.253112033188;5022.246663000579;5022.4224214215465;5026.748662566857;5032.220005899091;13691.657708557266;20659.17363305466;20938.476538086517;21699.783002169934;21982.230621928156;2195;2594.26063571883;2655.952464033961;2797.832130072182;2854.372038151603;9146.862797057984;9576.090271424204;9901.05939935705;9906.752331191707;10271.28101032928;1842.106578309532;1863.18951256204;1876.5496280892487;1892;1893.6212757448566;76244.61877690631;78380.12358188827;79415.6029219856;79584.60207698982;81255.5937220316;10128;10328.783964098082;10519.579216831395;10577.312474689274;10733.78532429347;14043.50847720325;14077.71844563103;14081.732644062084;14116;14246.430142794172;7539.359154471822;7735.497192682457;7750.379969602418;7823.9608801956765;7889.329407000355;6887.104676392137;7008.229094799568;7035.859282814417;7268.018817459575;7639.350655194259;4520.440448045419;4574.398960363846;4592.862214133581;4713.670043097019;4742.95257047432;1859.432872973756;1894.0908363985363;1918.7026010968323;1949.7757757858028;2007.8996050197431;807.2895851650627;894.5169608411385;906.7914379692708;946.6450081219542;1008.1531513528671;17529.56176095608;18371.72442413365;18399.632007360047;18907.527311817314;18936.52658683544;904.3895370624933;915.4324318922178;916.7295647783919;968.4021364748222;1034.4517405774952;9320.347575669639;10245.282830201962;10250.692479225636;10257.948710256402;10380.221483388776;449322;454121;455228;455300;458424;84253.82608434919;85574.57212714021;85926;86493.5675321632;90744.5462772682;996.0487734213808;1015.603914473356;1018.2464975917768;1021.581151727788;1024.4979959819784;15705.607359816097;15776.368945242062;16038.278277477775;16116.597085072966;16282.59293517624;38626.2274754509;38691.2261754769;39215.41176882406;39349.60650393459;39502;49882.50117498779;50149.74925125351;55999.720001399735;56554.43445565492;57326.42673573211;7308.96345518269;7517.548947063194;7654.578998154932;7696.286643655002;8071.434999549975;8074.313683336982;9409.764755881159;10376.896231037594;10709.464526773785;11095.667129986114;9148.817023659623;9397.718068457958;9409.811803763749;9773.706788796348;9932.25508086882;9299.163075323253;9381.108794664497;9475.147236748726;9506.429614223169;9707;9411.952940235255;9579.281553883626;9660.565274562521;9896.406215627085;9986.201103911815;7579.317861392501;7659.54042757414;7698.2686644768655;7742.806429839074;7785.5686508527615;8622.78443038929;9659.32384733062;9671.274654401088;9688.079632434907;9937.503124843584 21:28:29 INFO - PROCESS | 16152 | |3;mootools.html;1342.2148043394614;1410.0904916328964;1450.8621680940312;1542.5449492399741;1683.7642730017799;7085.4331653467725;7633.465657403984;7825.882611760828;7834.33408160307;8021.719239826607;7876.015498062742;7939.642716077776;7948.244916732916;7954.125046244909;7954.244346787046;1999.8100180482904;2063.659496183128;2218.157100301883;2256.8194544436406;2259.9774002260015;2213.800757931786;2271.1483193802324;2276.237460450751;2282.3837563857746;2285.6342985122383;2212.977870221301;2227.2984010036785;2227.654713519407;2227.8774667393286;2231.5871563760757;2089.4462967313675;2112.176251262009;2113.820325272354;2114.5832292364;2116.312198535482;2014.5165160361407;2068.069368784053;2191.9671204931938;2192.736871575405;2194.264921251383;7897.447178697494;7930.048394192676;7975.96012019942;8036.357091432671;8074.232947869943;4849.757512124415;4997.525235102659;4999.900001999976;5000;5000.599952003831;3042.1938186380626;3168.524721291825;3202.711755941953;3224.6617653673698;3253.9349213015844;923.6259314977444;979.2802290316612;1026.7587117027492;1031.0359813574332;1048.1195795531714;10853.294535855222;10867.239293249475;10904.6183383582;10906.182036347305;10907.18196135293;4291.892702682396;4445.5776701213335;4487.842925497592;4500.3249512573375;4605.930911036337;9312.44125352481;9327.067293270755;9327.253819694408;9337.57980890848;9342.532873356306;5085.74571271435;5096.668716533413;5099.36257967754;5101.566366858859;5102.311187989648;4480.081583275421;4544.818207271672;4582.725036497821;4616.030633566921;4620.0990806793025;3759.1353988582778;3798.278327117894;3849.711271654581;3882.495275614152;3891.2606604745006;1304.6347022833622;1315.6579289384638;1315.6645055510976;1327.2633688302892;1333.4599487207697;912.9406588571721;924.6347692661495;939.1172298039958;964.7250533597891;997.9800403991879;5476.91784623231;5633.352164501053;5639;5723.627964182315;5734.541236701054;421.93461509688285;430.1053808079188;435.4252386849328;445.4342984409781;445.9063596644741;3886.650201481824;3946.782926939046;3963.6432721054666;3986.86045988389;4093.669557393859;9414;9419.293552983554;9615.711528654012;9710;9720.95139524312;9434.811303773886;9596.520173991272;9611.951940240393;9612.231021518262;9614.086661767122;7250.818729531804;7366.815829604196;7403.333699967029;7474.065741782278;7483.812904677317;5402.621816472809;5422.186671999074;5426.375966763715;5433.347998240237;5708.429157084258;8754.956225218833;8900.910990890266;8920.687775927812;8949.194572488508;8955.208958208254;8542.871856922027;8857.955710221408;8869.955650221966;8988.820223595621;9144.679936202201;6423.935760642334;6604.14146160996;6610.074589557562;6776;6787.5588086774205;7433.293837085468;7552.773416797505;7598.468107232441;7762.844743105219;7796.805079873048;6637.369449902251;6720.865582688417;6752.155980502437;6867.690953907186;6905.240423553305;6928.826779330557;6999.51003429755;7010.789676309719;7212.134543854771;7242.52923559988;6464.191976003;6464.870702586016;6487.643179625166;6506.804795855943;6510.381513756186;4871.561559459666;4921.311016457627;4944.577157193022;4959.380077490314;5029.396472423186;4759.286107083826;4887.144749668722;4910.779014944407;4948.8762780930765;5119.513646203604 21:28:29 INFO - PROCESS | 16152 | |4;prototype.html;312.38927895963394;334.68539572801564;336.26861576072037;339.36199944105107;341.77613663050704;5018.799248030079;5118.053160165372;5131.845334799668;5200.791968321268;5213.2701421800975;5123;5125.692458452496;5127.717975511346;5130.563902068328;5154.0980328442465;4092.4270602115726;4111.897202569945;4114.280000999821;4118.217538667658;4120.711550191473;3918.5689574146963;4123.299039163335;4131.5042194936495;4135.4623898893105;4136.958630413687;4084.36692312692;4094.283500387435;4105.3020986432375;4112.403701463301;4114.218298523271;4082.6121518455698;4102.610252026053;4108.445359876423;4110.794460276974;4123.45382970645;3664.6701796838147;3665.505156803824;3674.4855720199193;3680.4479328100733;3691.151035261878;4795.856124316241;5009.549140577329;5023.572996295319;5024.944761600068;5092.115917110656;4560.429946256718;4571.519990401027;4572.7713614319155;4582.679212455129;4617.575772719316;5309.6372114409105;5334.839954801362;5344.866378340534;5347.358317001946;5368.758405871745;3755.4930084438524;3889.183271512986;3891.5135608048995;3911.471951286568;3916.8836881488924;5256.579473642099;5427.1316589345515;5503.834884953458;5544.944550554484;5560.4161563035705;3875.8837234883;3956.188981258835;3978.104926391539;4008.819603117867;4014.558398576154;3778.545260074836;3874.2251549690177;3908.726389152732;4050.9392359114636;4063.2482990646827;4535.160995215896;4553.157665831832;4563.566461186182;4575.885602859888;4576.519465456079;5368.2752828367675;5373.731313434313;5380.955237009989;5422.132458806572;5436.130219164915;1317.736452709462;1342.7515909556764;1346.562367230654;1346.670065833877;1349.4804500267467;930.831806083361;941.2140862379853;942.9858552121724;950.1211379473959;957.8946315905245;4839.879003024882;4905.607551395879;4921.803127874845;4934.654574179773;5004;5166.664166829144;5221.321228240305;5231.581473482112;5244.6328756987405;5258.7370631468275;4907.429622520759;4935.457099719028;4937.901241975141;4959.975200124049;4979.3526841510375;2798.9224148702897;2818.422223444189;2838.9432211355656;2842.545192769147;2843.644544431946;5505.587080968943;5516.558675305966;5531.3362396512675;5538.75075621598;5540.889182216333;5347.51872331492;5466.3713672928125;5484.2596249506605;5637.23897273871;5651.632643878217;5729.828105156852;5734.082546792577;5736.483716465371;5756.539476841926;5758.596898217084;5423.864403389789;5551.722413879209;5561.888762224652;5575.470330318613;5626.43735626434;5443.945560544344;5457.889003268099;5481.725913704219;5488.753006114654;5504.8622285297;5677.3471050829685;5714.257146570919;5715.171300161536;5720.942790572208;5770.259126914682;3856.8264428101365;3978.553189788317;4009.3785463253244;4012.9799351003057;4014.8996275093355;5497.890042199214;5513.2005859151;5519.337679478488;5520.834374968757;5522.806701765419;5350.571954243574;5359.490848369554;5359.866003349947;5419.620626556103;5430.945690543044;5219.564619729605;5328.8401347959625;5337.706426146599;5373.865653358541;5388.326459192601;5247.921281180709;5265.341832270966;5279.683219006872;5294.867628309323;5301.3373328333955;5376.784928602812;5379.946200537945;5381.354237491526;5429.864253393696;5448.56411487088;5082.593392528515;5219.347581552306;5249.527542521191;5257.868553286045;5258.474152584711 21:28:29 INFO - PROCESS | 16152 | |5;yui.html;710.3997122431546;714.9070620819293;738.2949283434317;747.0549754560482;761.9238076192385;14963.401463941444;15992.681975866539;16078.839211607881;16169.595760106004;16200.837991620096;15133;15311.387544498235;15326.770098448533;15333.310001049951;15335.233238338094;646.8803271394784;665.490899461912;759.2824780582351;765.2309429023824;777.0481160578303;776.4332037612518;786.3709032773787;786.4612740272903;786.9567173805439;787.4055088408272;829.6225217526041;830.5971603772166;831.0069466986923;831.2767891934026;831.5675848558745;687.4740823270203;710.3997122431537;792.9603519823986;827.8882350882614;828.457360428917;598.3059650805045;604.0727483313137;604.3533419241413;605.0017471172556;605.2192671453819;15761.369545218178;15794.526164215093;15854.207289635588;15858.762118568115;15859.841401586067;14555.199464029474;14579.854201457956;14654.926725366411;14731;14798.630034249123;12954.48182072716;12997.350132493431;13000.739985200244;13336;13370.197788132744;4277.283204832702;4307.483102027777;4333.501647310568;4371.868843934687;4460.063386688799;84708.57645711732;86722;86917;89705.55147224288;93148.42496263054;11296.305554166776;11338.546458141582;11366.602168924213;11368.715782105513;11757.529698811952;22091.66862497064;22211.555768884533;22446.887765561067;22658.43353916165;22727;7766.805829854299;7892.684292628231;7912.208779122157;7987.3610111190965;7993.560354180576;4328.11273688893;4429.53489883564;4464.955350446519;4528.569785870403;4666.253399456071;3105.2857842695853;3188.58548388708;3219.967800321967;3220.2593403516853;3227.402930457873;567.2229046206724;575.689127870945;577.3072313224147;587.5329113354893;595.6068994463633;539.8812261302508;591.9526437884958;592.3099588978878;615.2125279641975;618.523736722721;9042.186203241608;9315.627374905063;9376.812463750686;9393.671221507215;9729.367591106555;570.292836882263;604.7490291528966;619.1888625900085;676.3540818518303;678.9083473730983;4245.448091748053;4457.2199865023495;4482.1707984022405;4541.046380260116;4541.364209010741;83111;85390.14609853821;85951;85964;89314;74495;75212;76313;76688.61655691685;76724;57501;67368.6631566839;68045.65977170083;68384;70699.64650176716;8782.516961567175;8843.248323892283;8979.668955504565;9038.548072596475;9329.906700933176;45038.324425134306;45336.10630617999;45656.54343456523;46570.301445477664;46667;58457.70771146117;60370;62452.375476244655;62888;62991.37008629855;11204.43977801123;11322.773544529227;11576.768464630828;11783.351915644722;11820.76358472843;12300.200486968319;13096.21422714602;14011.881188118812;15298.77051844201;15389.38442462289;14124.2937853109;14139.929300353433;14158.292085395895;14163.787543186654;14439;14275.214863182624;14316.57050288493;14352.856471435152;14559.272036397926;14832.18422986746;13339.19964802115;13705.177689338672;13764.105333153313;13871.583852484442;13941.233232172328;10414.47927603632;11211.383373914514;11489.425528723697;11827.645170644895;12357.629271121881;11078.647308251402;11459.109246974156;11498.25261358009;11504.309741415207;11731.296122232694 21:28:29 INFO - PROCESS | 16152 | __end_tp_report 21:28:29 INFO - PROCESS | 16152 | __start_cc_report 21:28:29 INFO - PROCESS | 16152 | _x_x_mozilla_cycle_collect,2199 21:28:29 INFO - PROCESS | 16152 | __end_cc_report 21:28:29 INFO - PROCESS | 16152 | __startTimestamp1480915709585__endTimestamp 21:28:29 INFO - PROCESS | 16152 | 21:28:29 INFO - PROCESS | 16152 | ------- Summary: start ------- 21:28:29 INFO - PROCESS | 16152 | Number of tests: 6 21:28:29 INFO - PROCESS | 16152 | 21:28:29 INFO - PROCESS | 16152 | [#0] dojo.html Cycles:165 Average:5201.61 Median:5398.11 stddev:543.16 (10.1%) stddev-sans-first:520.99 21:28:29 INFO - PROCESS | 16152 | Values: 3172.9 3184.6 3203.4 3236.2 3255.4 5137.5 5163.9 5205.0 5252.6 5272.2 5211.9 5218.8 5290.9 5304.4 5327.6 4742.5 4744.0 4775.3 4788.7 4816.4 4856.0 4863.6 4910.7 4914.0 4953.8 4689.5 4838.3 4864.1 4895.1 4965.6 4704.2 4731.7 4735.6 4743.7 4761.1 4713.6 4727.2 4730.6 4771.9 4790.3 5155.0 5179.4 5181.1 5189.7 5197.8 5404.2 5440.2 5500.5 5536.5 5558.5 5592.4 5608.3 5611.2 5650.7 5666.5 5244.7 5253.3 5257.0 5257.4 5311.3 5534.6 5592.2 5680.5 5685.0 5686.2 5513.4 5532.4 5538.8 5576.4 5586.9 5566.5 5617.2 5621.6 5622.7 5625.5 5362.5 5407.6 5510.1 5594.5 5595.2 5482.4 5505.9 5508.4 5542.1 5580.5 4330.9 4338.3 4339.2 4343.1 4377.2 3902.8 3935.5 3943.5 3961.6 3995.3 5216.2 5258.3 5285.8 5303.7 5337.8 5606.1 5608.5 5621.5 5627.2 5628.2 5269.5 5269.7 5307.6 5310.1 5360.2 4757.9 4805.2 4845.7 4846.9 4868.5 5798.0 5803.3 5816.4 5822.6 5858.1 5575.3 5606.5 5618.6 5624.2 5635.7 5649.4 5677.9 5691.1 5770.7 5821.7 5478.2 5478.5 5481.0 5482.2 5484.1 5521.4 5574.8 5576.5 5577.3 5580.8 5419.2 5434.9 5438.2 5439.5 5442.0 5449.9 5460.9 5462.2 5466.9 5468.9 5397.5 5398.1 5402.5 5403.4 5407.1 5401.1 5401.6 5409.1 5426.5 5497.5 5283.4 5339.1 5339.5 5350.6 5390.4 21:28:29 INFO - PROCESS | 16152 | 21:28:29 INFO - PROCESS | 16152 | [#1] ext.html Cycles:180 Average:52629.83 Median:19341.92 stddev:100866.09 (521.5%) stddev-sans-first:101073.38 21:28:29 INFO - PROCESS | 16152 | Values: 594.7 597.7 630.5 635.8 639.9 39060.8 39981.8 40269.0 43314.8 43632.1 41700.0 42326.6 42480.0 42492.2 42494.0 7756.3 8397.8 8640.9 8649.7 8654.6 7735.0 10033.3 10293.4 10300.5 10623.5 10279.3 10349.5 10360.4 10385.9 10394.4 10169.0 10195.5 10199.5 10246.7 10266.8 10103.8 10166.4 10195.7 10196.5 10196.5 38900.2 39500.0 39578.8 39582.4 39584.4 3428.8 3495.4 3499.5 3700.4 3872.2 29510.9 29566.0 29573.6 29782.4 29839.1 4092.2 4154.7 4190.2 4374.6 4382.3 309373.0 310401.2 311636.0 312053.0 312988.0 53266.5 53500.7 53574.0 53609.2 53636.8 57550.7 57556.7 57597.0 57649.0 57655.7 14841.6 14986.9 14994.6 14995.9 15000.2 11016.4 11251.6 11416.2 11439.4 12553.2 17825.9 18113.8 18983.6 19040.6 19068.8 6352.7 7086.1 7153.7 7352.9 7366.7 889.2 933.4 1006.4 1038.3 1061.4 30267.2 30267.4 30477.2 30758.0 31000.7 1032.6 1059.4 1071.7 1119.5 1127.3 15255.6 15722.4 15833.8 15979.8 15984.4 502700.0 504618.0 505179.0 505966.0 507071.0 36988.8 36988.8 37221.6 37354.8 37581.0 1170.6 1230.5 1272.8 1294.1 1334.4 28590.9 31028.0 31294.4 31526.5 31976.5 47308.5 47878.3 47909.0 47912.0 47930.8 288153.0 288790.0 288964.0 289175.0 290019.0 26885.9 27576.7 27771.4 29342.1 38644.6 43925.3 44143.3 44331.0 44483.3 45882.0 37222.0 37454.8 37886.0 38116.0 38645.2 36655.5 37780.8 38157.0 38474.0 38891.2 37408.0 37984.4 38398.4 38873.8 38948.6 32762.0 34535.5 35184.3 35567.6 35568.6 26574.3 26720.7 26847.0 27107.5 27170.6 21:28:29 INFO - PROCESS | 16152 | 21:28:29 INFO - PROCESS | 16152 | [#2] jquery.html Cycles:180 Average:26780.29 Median:5024.59 stddev:75095.69 (1494.6%) stddev-sans-first:75280.17 21:28:29 INFO - PROCESS | 16152 | Values: 366.0 376.0 394.9 399.0 415.5 13711.7 14558.5 16231.7 18874.9 19145.8 16910.7 17305.7 17425.8 17931.2 18108.7 5068.8 5254.5 5255.4 5259.4 5261.8 4631.2 4765.1 5177.2 5181.2 5201.6 5223.5 5227.5 5228.5 5231.1 5231.5 5066.5 5096.7 5107.0 5148.3 5166.6 4979.3 5022.2 5022.4 5026.7 5032.2 13691.7 20659.2 20938.5 21699.8 21982.2 2195.0 2594.3 2656.0 2797.8 2854.4 9146.9 9576.1 9901.1 9906.8 10271.3 1842.1 1863.2 1876.5 1892.0 1893.6 76244.6 78380.1 79415.6 79584.6 81255.6 10128.0 10328.8 10519.6 10577.3 10733.8 14043.5 14077.7 14081.7 14116.0 14246.4 7539.4 7735.5 7750.4 7824.0 7889.3 6887.1 7008.2 7035.9 7268.0 7639.4 4520.4 4574.4 4592.9 4713.7 4743.0 1859.4 1894.1 1918.7 1949.8 2007.9 807.3 894.5 906.8 946.6 1008.2 17529.6 18371.7 18399.6 18907.5 18936.5 904.4 915.4 916.7 968.4 1034.5 9320.3 10245.3 10250.7 10257.9 10380.2 449322.0 454121.0 455228.0 455300.0 458424.0 84253.8 85574.6 85926.0 86493.6 90744.5 996.0 1015.6 1018.2 1021.6 1024.5 15705.6 15776.4 16038.3 16116.6 16282.6 38626.2 38691.2 39215.4 39349.6 39502.0 49882.5 50149.7 55999.7 56554.4 57326.4 7309.0 7517.5 7654.6 7696.3 8071.4 8074.3 9409.8 10376.9 10709.5 11095.7 9148.8 9397.7 9409.8 9773.7 9932.3 9299.2 9381.1 9475.1 9506.4 9707.0 9412.0 9579.3 9660.6 9896.4 9986.2 7579.3 7659.5 7698.3 7742.8 7785.6 8622.8 9659.3 9671.3 9688.1 9937.5 21:28:29 INFO - PROCESS | 16152 | 21:28:29 INFO - PROCESS | 16152 | [#3] mootools.html Cycles:180 Average:5275.39 Median:5101.94 stddev:2888.32 (56.6%) stddev-sans-first:2881.29 21:28:29 INFO - PROCESS | 16152 | Values: 1342.2 1410.1 1450.9 1542.5 1683.8 7085.4 7633.5 7825.9 7834.3 8021.7 7876.0 7939.6 7948.2 7954.1 7954.2 1999.8 2063.7 2218.2 2256.8 2260.0 2213.8 2271.1 2276.2 2282.4 2285.6 2213.0 2227.3 2227.7 2227.9 2231.6 2089.4 2112.2 2113.8 2114.6 2116.3 2014.5 2068.1 2192.0 2192.7 2194.3 7897.4 7930.0 7976.0 8036.4 8074.2 4849.8 4997.5 4999.9 5000.0 5000.6 3042.2 3168.5 3202.7 3224.7 3253.9 923.6 979.3 1026.8 1031.0 1048.1 10853.3 10867.2 10904.6 10906.2 10907.2 4291.9 4445.6 4487.8 4500.3 4605.9 9312.4 9327.1 9327.3 9337.6 9342.5 5085.7 5096.7 5099.4 5101.6 5102.3 4480.1 4544.8 4582.7 4616.0 4620.1 3759.1 3798.3 3849.7 3882.5 3891.3 1304.6 1315.7 1315.7 1327.3 1333.5 912.9 924.6 939.1 964.7 998.0 5476.9 5633.4 5639.0 5723.6 5734.5 421.9 430.1 435.4 445.4 445.9 3886.7 3946.8 3963.6 3986.9 4093.7 9414.0 9419.3 9615.7 9710.0 9721.0 9434.8 9596.5 9612.0 9612.2 9614.1 7250.8 7366.8 7403.3 7474.1 7483.8 5402.6 5422.2 5426.4 5433.3 5708.4 8755.0 8900.9 8920.7 8949.2 8955.2 8542.9 8858.0 8870.0 8988.8 9144.7 6423.9 6604.1 6610.1 6776.0 6787.6 7433.3 7552.8 7598.5 7762.8 7796.8 6637.4 6720.9 6752.2 6867.7 6905.2 6928.8 6999.5 7010.8 7212.1 7242.5 6464.2 6464.9 6487.6 6506.8 6510.4 4871.6 4921.3 4944.6 4959.4 5029.4 4759.3 4887.1 4910.8 4948.9 5119.5 21:28:29 INFO - PROCESS | 16152 | 21:28:29 INFO - PROCESS | 16152 | [#4] prototype.html Cycles:180 Average:4517.18 Median:5129.14 stddev:1309.99 (25.5%) stddev-sans-first:1275.08 21:28:29 INFO - PROCESS | 16152 | Values: 312.4 334.7 336.3 339.4 341.8 5018.8 5118.1 5131.8 5200.8 5213.3 5123.0 5125.7 5127.7 5130.6 5154.1 4092.4 4111.9 4114.3 4118.2 4120.7 3918.6 4123.3 4131.5 4135.5 4137.0 4084.4 4094.3 4105.3 4112.4 4114.2 4082.6 4102.6 4108.4 4110.8 4123.5 3664.7 3665.5 3674.5 3680.4 3691.2 4795.9 5009.5 5023.6 5024.9 5092.1 4560.4 4571.5 4572.8 4582.7 4617.6 5309.6 5334.8 5344.9 5347.4 5368.8 3755.5 3889.2 3891.5 3911.5 3916.9 5256.6 5427.1 5503.8 5544.9 5560.4 3875.9 3956.2 3978.1 4008.8 4014.6 3778.5 3874.2 3908.7 4050.9 4063.2 4535.2 4553.2 4563.6 4575.9 4576.5 5368.3 5373.7 5381.0 5422.1 5436.1 1317.7 1342.8 1346.6 1346.7 1349.5 930.8 941.2 943.0 950.1 957.9 4839.9 4905.6 4921.8 4934.7 5004.0 5166.7 5221.3 5231.6 5244.6 5258.7 4907.4 4935.5 4937.9 4960.0 4979.4 2798.9 2818.4 2838.9 2842.5 2843.6 5505.6 5516.6 5531.3 5538.8 5540.9 5347.5 5466.4 5484.3 5637.2 5651.6 5729.8 5734.1 5736.5 5756.5 5758.6 5423.9 5551.7 5561.9 5575.5 5626.4 5443.9 5457.9 5481.7 5488.8 5504.9 5677.3 5714.3 5715.2 5720.9 5770.3 3856.8 3978.6 4009.4 4013.0 4014.9 5497.9 5513.2 5519.3 5520.8 5522.8 5350.6 5359.5 5359.9 5419.6 5430.9 5219.6 5328.8 5337.7 5373.9 5388.3 5247.9 5265.3 5279.7 5294.9 5301.3 5376.8 5379.9 5381.4 5429.9 5448.6 5082.6 5219.3 5249.5 5257.9 5258.5 21:28:29 INFO - PROCESS | 16152 | 21:28:29 INFO - PROCESS | 16152 | [#5] yui.html Cycles:180 Average:18674.36 Median:25098.95 stddev:24765.47 (98.7%) stddev-sans-first:24798.21 21:28:29 INFO - PROCESS | 16152 | Values: 710.4 714.9 738.3 747.1 761.9 14963.4 15992.7 16078.8 16169.6 16200.8 15133.0 15311.4 15326.8 15333.3 15335.2 646.9 665.5 759.3 765.2 777.0 776.4 786.4 786.5 787.0 787.4 829.6 830.6 831.0 831.3 831.6 687.5 710.4 793.0 827.9 828.5 598.3 604.1 604.4 605.0 605.2 15761.4 15794.5 15854.2 15858.8 15859.8 14555.2 14579.9 14654.9 14731.0 14798.6 12954.5 12997.4 13000.7 13336.0 13370.2 4277.3 4307.5 4333.5 4371.9 4460.1 84708.6 86722.0 86917.0 89705.6 93148.4 11296.3 11338.5 11366.6 11368.7 11757.5 22091.7 22211.6 22446.9 22658.4 22727.0 7766.8 7892.7 7912.2 7987.4 7993.6 4328.1 4429.5 4465.0 4528.6 4666.3 3105.3 3188.6 3220.0 3220.3 3227.4 567.2 575.7 577.3 587.5 595.6 539.9 592.0 592.3 615.2 618.5 9042.2 9315.6 9376.8 9393.7 9729.4 570.3 604.7 619.2 676.4 678.9 4245.4 4457.2 4482.2 4541.0 4541.4 83111.0 85390.1 85951.0 85964.0 89314.0 74495.0 75212.0 76313.0 76688.6 76724.0 57501.0 67368.7 68045.7 68384.0 70699.6 8782.5 8843.2 8979.7 9038.5 9329.9 45038.3 45336.1 45656.5 46570.3 46667.0 58457.7 60370.0 62452.4 62888.0 62991.4 11204.4 11322.8 11576.8 11783.4 11820.8 12300.2 13096.2 14011.9 15298.8 15389.4 14124.3 14139.9 14158.3 14163.8 14439.0 14275.2 14316.6 14352.9 14559.3 14832.2 13339.2 13705.2 13764.1 13871.6 13941.2 10414.5 11211.4 11489.4 11827.6 12357.6 11078.6 11459.1 11498.3 11504.3 11731.3 21:28:29 INFO - PROCESS | 16152 | -------- Summary: end -------- 21:28:29 INFO - PROCESS | 16152 | 21:28:30 INFO - TEST-INFO | 16152: exit 0 21:28:30 INFO - TEST-OK | dromaeo_css | took 1116713ms 21:28:30 INFO - TEST-START | kraken 21:28:30 INFO - operating with platform_type : linux_ 21:28:30 INFO - Initialising browser for kraken test... 21:28:30 INFO - TEST-INFO | started process 16456 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDHmMAP/profile http://localhost:55101/getInfo.html) 21:28:34 INFO - PROCESS | 16456 | __metrics Screen width/height:1600/1200 21:28:34 INFO - PROCESS | 16456 | colorDepth:24 21:28:34 INFO - PROCESS | 16456 | Browser inner width/height: 1024/697 21:28:34 INFO - PROCESS | 16456 | __metrics 21:28:35 INFO - PROCESS | 16456 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:28:35 INFO - PROCESS | 16456 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:28:36 INFO - TEST-INFO | 16456: exit 0 21:28:36 INFO - Browser initialized. 21:28:36 INFO - Running cycle 1/1 for kraken test... 21:28:36 INFO - TEST-INFO | started process 16539 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDHmMAP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:28:48 INFO - PROCESS | 16539 | RSS: Main: 171982848 21:28:48 INFO - PROCESS | 16539 | 21:28:50 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55101/tests/kraken/driver.html?testName=audio-beat-detection) 21:28:51 INFO - PROCESS | 16539 | RSS: Main: 234266624 21:28:51 INFO - PROCESS | 16539 | 21:28:54 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55101/tests/kraken/driver.html?testName=audio-dft) 21:28:54 INFO - PROCESS | 16539 | RSS: Main: 216432640 21:28:54 INFO - PROCESS | 16539 | 21:28:57 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55101/tests/kraken/driver.html?testName=audio-fft) 21:28:58 INFO - PROCESS | 16539 | RSS: Main: 297918464 21:28:58 INFO - PROCESS | 16539 | 21:29:00 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55101/tests/kraken/driver.html?testName=audio-oscillator) 21:29:01 INFO - PROCESS | 16539 | RSS: Main: 209244160 21:29:01 INFO - PROCESS | 16539 | 21:29:03 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55101/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:29:03 INFO - PROCESS | 16539 | RSS: Main: 213864448 21:29:03 INFO - PROCESS | 16539 | 21:29:06 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55101/tests/kraken/driver.html?testName=imaging-darkroom) 21:29:07 INFO - PROCESS | 16539 | RSS: Main: 253206528 21:29:07 INFO - PROCESS | 16539 | 21:29:10 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55101/tests/kraken/driver.html?testName=imaging-desaturate) 21:29:11 INFO - PROCESS | 16539 | RSS: Main: 253194240 21:29:11 INFO - PROCESS | 16539 | 21:29:14 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55101/tests/kraken/driver.html?testName=json-parse-financial) 21:29:14 INFO - PROCESS | 16539 | RSS: Main: 251408384 21:29:14 INFO - PROCESS | 16539 | 21:29:16 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55101/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:29:16 INFO - PROCESS | 16539 | RSS: Main: 213708800 21:29:16 INFO - PROCESS | 16539 | 21:29:18 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55101/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:29:19 INFO - PROCESS | 16539 | RSS: Main: 220459008 21:29:19 INFO - PROCESS | 16539 | 21:29:21 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55101/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:29:21 INFO - PROCESS | 16539 | RSS: Main: 229416960 21:29:21 INFO - PROCESS | 16539 | 21:29:24 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55101/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:29:25 INFO - PROCESS | 16539 | RSS: Main: 238432256 21:29:25 INFO - PROCESS | 16539 | 21:29:28 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55101/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:29:28 INFO - PROCESS | 16539 | RSS: Main: 235589632 21:29:28 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | Cycle 1(1): loaded http://localhost:55101/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55101/tests/kraken/driver.html?testName=ai-astar) 21:29:30 INFO - PROCESS | 16539 | RSS: Main: 286171136 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | __start_tp_report 21:29:30 INFO - PROCESS | 16539 | _x_x_mozilla_page_load 21:29:30 INFO - PROCESS | 16539 | _x_x_mozilla_page_load_details 21:29:30 INFO - PROCESS | 16539 | |i|pagename|runs| 21:29:30 INFO - PROCESS | 16539 | |0;ai-astar;109;111;112;113;112;112;110;112;111;113 21:29:30 INFO - PROCESS | 16539 | |1;audio-beat-detection;157;124;125;166;126;123;124;123;149;124 21:29:30 INFO - PROCESS | 16539 | |2;audio-dft;161;163;161;161;159;162;169;162;161;162 21:29:30 INFO - PROCESS | 16539 | |3;audio-fft;76;75;75;122;77;77;87;78;92;77 21:29:30 INFO - PROCESS | 16539 | |4;audio-oscillator;83;82;104;82;84;83;94;82;82;110 21:29:30 INFO - PROCESS | 16539 | |5;imaging-gaussian-blur;117;117;117;116;117;116;117;116;116;117 21:29:30 INFO - PROCESS | 16539 | |6;imaging-darkroom;145;145;145;145;145;145;146;145;145;145 21:29:30 INFO - PROCESS | 16539 | |7;imaging-desaturate;96;96;95;95;95;95;96;95;95;95 21:29:30 INFO - PROCESS | 16539 | |8;json-parse-financial;55;53;52;53;53;54;53;53;53;54 21:29:30 INFO - PROCESS | 16539 | |9;json-stringify-tinderbox;62;62;62;62;62;62;63;63;63;63 21:29:30 INFO - PROCESS | 16539 | |10;stanford-crypto-aes;81;81;81;81;81;87;87;81;79;80 21:29:30 INFO - PROCESS | 16539 | |11;stanford-crypto-ccm;143;133;134;138;141;138;131;141;174;142 21:29:30 INFO - PROCESS | 16539 | |12;stanford-crypto-pbkdf2;173;167;170;168;176;174;174;167;168;165 21:29:30 INFO - PROCESS | 16539 | |13;stanford-crypto-sha256-iterative;66;68;66;64;65;67;66;66;66;65 21:29:30 INFO - PROCESS | 16539 | __end_tp_report 21:29:30 INFO - PROCESS | 16539 | __start_cc_report 21:29:30 INFO - PROCESS | 16539 | _x_x_mozilla_cycle_collect,1655 21:29:30 INFO - PROCESS | 16539 | __end_cc_report 21:29:30 INFO - PROCESS | 16539 | __startTimestamp1480915770748__endTimestamp 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | ------- Summary: start ------- 21:29:30 INFO - PROCESS | 16539 | Number of tests: 14 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.27 (1.1%) stddev-sans-first:0.97 21:29:30 INFO - PROCESS | 16539 | Values: 109.0 111.0 112.0 113.0 112.0 112.0 110.0 112.0 111.0 113.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:125.50 stddev:16.55 (13.2%) stddev-sans-first:15.34 21:29:30 INFO - PROCESS | 16539 | Values: 157.0 124.0 125.0 166.0 126.0 123.0 124.0 123.0 149.0 124.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:2.64 (1.6%) stddev-sans-first:2.77 21:29:30 INFO - PROCESS | 16539 | Values: 161.0 163.0 161.0 161.0 159.0 162.0 169.0 162.0 161.0 162.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#3] audio-fft Cycles:10 Average:83.60 Median:77.00 stddev:14.62 (19.0%) stddev-sans-first:15.25 21:29:30 INFO - PROCESS | 16539 | Values: 76.0 75.0 75.0 122.0 77.0 77.0 87.0 78.0 92.0 77.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:82.50 stddev:10.45 (12.7%) stddev-sans-first:10.88 21:29:30 INFO - PROCESS | 16539 | Values: 83.0 82.0 104.0 82.0 84.0 83.0 94.0 82.0 82.0 110.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 21:29:30 INFO - PROCESS | 16539 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 117.0 116.0 116.0 117.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 21:29:30 INFO - PROCESS | 16539 | Values: 145.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 21:29:30 INFO - PROCESS | 16539 | Values: 96.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:0.82 (1.6%) stddev-sans-first:0.60 21:29:30 INFO - PROCESS | 16539 | Values: 55.0 53.0 52.0 53.0 53.0 54.0 53.0 53.0 53.0 54.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#9] json-stringify-tinderbox Cycles:10 Average:62.40 Median:62.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 21:29:30 INFO - PROCESS | 16539 | Values: 62.0 62.0 62.0 62.0 62.0 62.0 63.0 63.0 63.0 63.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:81.00 stddev:2.77 (3.4%) stddev-sans-first:2.92 21:29:30 INFO - PROCESS | 16539 | Values: 81.0 81.0 81.0 81.0 81.0 87.0 87.0 81.0 79.0 80.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#11] stanford-crypto-ccm Cycles:10 Average:141.50 Median:141.00 stddev:12.12 (8.6%) stddev-sans-first:12.85 21:29:30 INFO - PROCESS | 16539 | Values: 143.0 133.0 134.0 138.0 141.0 138.0 131.0 141.0 174.0 142.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:171.50 stddev:3.77 (2.2%) stddev-sans-first:3.86 21:29:30 INFO - PROCESS | 16539 | Values: 173.0 167.0 170.0 168.0 176.0 174.0 174.0 167.0 168.0 165.0 21:29:30 INFO - PROCESS | 16539 | 21:29:30 INFO - PROCESS | 16539 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:1.10 (1.7%) stddev-sans-first:1.17 21:29:30 INFO - PROCESS | 16539 | Values: 66.0 68.0 66.0 64.0 65.0 67.0 66.0 66.0 66.0 65.0 21:29:30 INFO - PROCESS | 16539 | -------- Summary: end -------- 21:29:30 INFO - PROCESS | 16539 | 21:29:31 INFO - TEST-INFO | 16539: exit 0 21:29:32 INFO - TEST-OK | kraken | took 61558ms 21:29:32 INFO - SUITE-END | took 1178s 21:29:32 INFO - Completed test suite (00:19:38) 21:29:32 INFO - javascript benchmark 21:29:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3172.9206769830753, 3184.6019247594036, 3203.4073696366167, 3236.1585987643207, 3255.3814775192714, 5137.511936366049, 5163.922541161881, 5205.011047900905, 5252.579793616506, 5272.23552584875, 5211.947880521194, 5218.791248350061, 5290.867728306795, 5304.443033481487, 5327.627066105364, 4742.478327383985, 4743.976280118594, 4775.3075804008395, 4788.688735232199, 4816.44610869751, 4856.0, 4863.610911127119, 4910.7299098549565, 4913.975430122844, 4953.801847926079, 4689.460712018094, 4838.346823178897, 4864.124457597631, 4895.11887860185, 4965.602751779849, 4704.223803072523, 4731.692439991424, 4735.5974742146955, 4743.691660042121, 4761.119192949281, 4713.646476514241, 4727.2436410174205, 4730.597899178574, 4771.904561908777, 4790.281457781326, 5154.974225128888, 5179.404368497596, 5181.145111056671, 5189.662671926313, 5197.818076367347, 5404.216388623667, 5440.211169380417, 5500.477454641803, 5536.529395001429, 5558.499735023827, 5592.412796656374, 5608.298962629671, 5611.158326251095, 5650.660960342392, 5666.546676265889, 5244.711540865251, 5253.26454296395, 5257.027449921777, 5257.421683614799, 5311.256424100629, 5534.557235421156, 5592.161175823659, 5680.517156041783, 5685.0, 5686.2323586315315, 5513.393526712059, 5532.363778165482, 5538.806141785099, 5576.442355764391, 5586.91619625706, 5566.526845218121, 5617.185508101385, 5621.550275977912, 5622.690752008679, 5625.493705566519, 5362.463753624685, 5407.594430417735, 5510.090835012258, 5594.468167605442, 5595.21666966625, 5482.396936337, 5505.917411238835, 5508.421615730291, 5542.057850165482, 5580.525655319262, 4330.870073897788, 4338.349247612883, 4339.218940590724, 4343.109662519176, 4377.233984052778, 3902.824372903226, 3935.5080614923136, 3943.467631869661, 3961.603839616015, 3995.300822356076, 5216.191490319006, 5258.2638430619745, 5285.841424757264, 5303.681779093266, 5337.839864804062, 5606.103023516218, 5608.49523542875, 5621.522170615462, 5627.155926611041, 5628.212050312959, 5269.525742683177, 5269.657472264291, 5307.6019298552765, 5310.097283461821, 5360.222767698662, 4757.881052973634, 4805.207140821795, 4845.733484658307, 4846.854594362175, 4868.537488938545, 5797.971010144923, 5803.303603567599, 5816.447437493347, 5822.621529600647, 5858.091995740667, 5575.275214222125, 5606.495415412633, 5618.606697531297, 5624.240727501735, 5635.718214089361, 5649.406812284651, 5677.858053548694, 5691.14632805066, 5770.653760774367, 5821.679807610452, 5478.150886612581, 5478.534324582455, 5480.972595136999, 5482.205080263419, 5484.149956756709, 5521.365043020104, 5574.804881829116, 5576.470235327637, 5577.274954256083, 5580.804671836466, 5419.214213939035, 5434.891302173855, 5438.211459338453, 5439.48324909133, 5442.0, 5449.945500545103, 5460.890782184413, 5462.180672899097, 5466.91799622998, 5468.945310546844, 5397.514223719884, 5398.109311963639, 5402.540784033401, 5403.351597808288, 5407.053765591085, 5401.081816091352, 5401.594880384066, 5409.134538473825, 5426.538744206787, 5497.45025497447, 5283.418823929288, 5339.092354299856, 5339.492748188916, 5350.598705097211, 5390.407055223844], "value": 5168.5349130864715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.7264258441116, 597.6682940967762, 630.4893036640321, 635.8187916443811, 639.9104125422439, 39060.80469597651, 39981.80009099954, 40268.99999999996, 43314.783426082904, 43632.12735745283, 41700.0, 42326.57673423265, 42480.0, 42492.15015699684, 42494.0, 7756.340711039568, 8397.832043359145, 8640.913590864106, 8649.69726059588, 8654.567271636393, 7735.0, 10033.347832390908, 10293.433861137635, 10300.536475858567, 10623.521941512612, 10279.280450368477, 10349.482525873715, 10360.37837729735, 10385.948070259637, 10394.428306443142, 10169.0, 10195.541200645952, 10199.54102065409, 10246.692599222035, 10266.794664106676, 10103.797924041479, 10166.440845753481, 10195.694129176136, 10196.490175491195, 10196.49017549127, 38900.22199555993, 39500.0, 39578.80210598958, 39582.406263906065, 39584.40623390651, 3428.7599868009247, 3495.353359628477, 3499.5450591423205, 3700.35243832331, 3872.244912242086, 29510.852445737848, 29566.0, 29573.55639665407, 29782.40435191284, 29839.10482685523, 4092.242935057024, 4154.667626589866, 4190.224808410423, 4374.58441448062, 4382.255016647177, 309373.0, 310401.2399690026, 311636.0, 312053.0, 312988.0, 53266.467335326925, 53500.73249633727, 53573.9819513538, 53609.1958620621, 53636.80900628868, 57550.71224643934, 57556.71221643865, 57597.0, 57649.0, 57655.71172144113, 14841.628959276104, 14986.850131498764, 14994.625134371729, 14995.925020374829, 15000.174990375634, 11016.449177541092, 11251.606193783342, 11416.200865939305, 11439.428028598537, 12553.184043037174, 17825.910870445565, 18113.818861811214, 18983.62032759337, 19040.61918761617, 19068.80931190698, 6352.7141278642575, 7086.149662040588, 7153.7138514459875, 7352.889706654404, 7366.668499917517, 889.2397000564424, 933.4119504711988, 1006.3509036671306, 1038.2680210451613, 1061.3578784835063, 30267.243318917204, 30267.394652106836, 30477.23806904845, 30758.0, 31000.68999310023, 1032.5611615063717, 1059.4014381874217, 1071.6838532632892, 1119.5242022140558, 1127.261643623428, 15255.61860953463, 15722.371105155895, 15833.841661583467, 15979.760303595456, 15984.360625574847, 502700.0, 504618.0, 505179.0, 505966.0, 507071.0, 36988.81505592455, 36988.81505592455, 37221.627783721815, 37354.81322593424, 37581.0, 1170.5551890281808, 1230.5262473947416, 1272.7963525835821, 1294.139397300822, 1334.3728447629599, 28590.857045714638, 31028.0, 31294.37411251808, 31526.527102093947, 31976.52035219427, 47308.52691473179, 47878.28182577195, 47909.0, 47912.0, 47930.76034619805, 288153.0, 288790.0, 288964.0, 289175.0, 290019.0, 26885.86557067202, 27576.724232757417, 27771.44457110887, 29342.065793420486, 38644.6135538641, 43925.341119882585, 44143.33784993163, 44331.0, 44483.33275000813, 45882.0, 37222.0, 37454.81272593619, 37886.0, 38116.0, 38645.2270954585, 36655.45016824803, 37780.811095944344, 38157.0, 38474.0, 38891.2221755569, 37408.0, 37984.43023354707, 38398.42402363911, 38873.8056309728, 38948.61051389563, 32762.0, 34535.481967771004, 35184.29631407409, 35567.64432355747, 35568.644313556535, 26574.335641609116, 26720.732792671824, 26847.0, 27107.45785084248, 27170.592441113793], "value": 19111.451011376008, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.97255205859534, 375.9548854137502, 394.91509325495014, 398.98259438431995, 415.48064918851435, 13711.65720856979, 14558.490452834152, 16231.675366492664, 18874.90562547189, 19145.808541914575, 16910.661786764256, 17305.653886922253, 17425.825741742578, 17931.193096310664, 18108.728369074473, 5068.847934561969, 5254.474552544753, 5255.395629502618, 5259.421463638997, 5261.815836445725, 4631.235846085392, 4765.142274390609, 5177.249298851664, 5181.1969144782615, 5201.583873290147, 5223.529882310591, 5227.5295223429885, 5228.503292187236, 5231.136862417697, 5231.529162375386, 5066.518680725344, 5096.668716533413, 5107.0, 5148.304978827885, 5166.5608423283875, 4979.253112033188, 5022.246663000579, 5022.4224214215465, 5026.748662566857, 5032.220005899091, 13691.657708557266, 20659.17363305466, 20938.476538086517, 21699.783002169934, 21982.230621928156, 2195.0, 2594.26063571883, 2655.952464033961, 2797.832130072182, 2854.372038151603, 9146.862797057984, 9576.090271424204, 9901.05939935705, 9906.752331191707, 10271.28101032928, 1842.106578309532, 1863.18951256204, 1876.5496280892487, 1892.0, 1893.6212757448566, 76244.61877690631, 78380.12358188827, 79415.6029219856, 79584.60207698982, 81255.5937220316, 10128.0, 10328.783964098082, 10519.579216831395, 10577.312474689274, 10733.78532429347, 14043.50847720325, 14077.71844563103, 14081.732644062084, 14116.0, 14246.430142794172, 7539.359154471822, 7735.497192682457, 7750.379969602418, 7823.9608801956765, 7889.329407000355, 6887.104676392137, 7008.229094799568, 7035.859282814417, 7268.018817459575, 7639.350655194259, 4520.440448045419, 4574.398960363846, 4592.862214133581, 4713.670043097019, 4742.95257047432, 1859.432872973756, 1894.0908363985363, 1918.7026010968323, 1949.7757757858028, 2007.8996050197431, 807.2895851650627, 894.5169608411385, 906.7914379692708, 946.6450081219542, 1008.1531513528671, 17529.56176095608, 18371.72442413365, 18399.632007360047, 18907.527311817314, 18936.52658683544, 904.3895370624933, 915.4324318922178, 916.7295647783919, 968.4021364748222, 1034.4517405774952, 9320.347575669639, 10245.282830201962, 10250.692479225636, 10257.948710256402, 10380.221483388776, 449322.0, 454121.0, 455228.0, 455300.0, 458424.0, 84253.82608434919, 85574.57212714021, 85926.0, 86493.5675321632, 90744.5462772682, 996.0487734213808, 1015.603914473356, 1018.2464975917768, 1021.581151727788, 1024.4979959819784, 15705.607359816097, 15776.368945242062, 16038.278277477775, 16116.597085072966, 16282.59293517624, 38626.2274754509, 38691.2261754769, 39215.41176882406, 39349.60650393459, 39502.0, 49882.50117498779, 50149.74925125351, 55999.720001399735, 56554.43445565492, 57326.42673573211, 7308.96345518269, 7517.548947063194, 7654.578998154932, 7696.286643655002, 8071.434999549975, 8074.313683336982, 9409.764755881159, 10376.896231037594, 10709.464526773785, 11095.667129986114, 9148.817023659623, 9397.718068457958, 9409.811803763749, 9773.706788796348, 9932.25508086882, 9299.163075323253, 9381.108794664497, 9475.147236748726, 9506.429614223169, 9707.0, 9411.952940235255, 9579.281553883626, 9660.565274562521, 9896.406215627085, 9986.201103911815, 7579.317861392501, 7659.54042757414, 7698.2686644768655, 7742.806429839074, 7785.5686508527615, 8622.78443038929, 9659.32384733062, 9671.274654401088, 9688.079632434907, 9937.503124843584], "value": 8446.970672316025, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.2148043394614, 1410.0904916328964, 1450.8621680940312, 1542.5449492399741, 1683.7642730017799, 7085.4331653467725, 7633.465657403984, 7825.882611760828, 7834.33408160307, 8021.719239826607, 7876.015498062742, 7939.642716077776, 7948.244916732916, 7954.125046244909, 7954.244346787046, 1999.8100180482904, 2063.659496183128, 2218.157100301883, 2256.8194544436406, 2259.9774002260015, 2213.800757931786, 2271.1483193802324, 2276.237460450751, 2282.3837563857746, 2285.6342985122383, 2212.977870221301, 2227.2984010036785, 2227.654713519407, 2227.8774667393286, 2231.5871563760757, 2089.4462967313675, 2112.176251262009, 2113.820325272354, 2114.5832292364, 2116.312198535482, 2014.5165160361407, 2068.069368784053, 2191.9671204931938, 2192.736871575405, 2194.264921251383, 7897.447178697494, 7930.048394192676, 7975.96012019942, 8036.357091432671, 8074.232947869943, 4849.757512124415, 4997.525235102659, 4999.900001999976, 5000.0, 5000.599952003831, 3042.1938186380626, 3168.524721291825, 3202.711755941953, 3224.6617653673698, 3253.9349213015844, 923.6259314977444, 979.2802290316612, 1026.7587117027492, 1031.0359813574332, 1048.1195795531714, 10853.294535855222, 10867.239293249475, 10904.6183383582, 10906.182036347305, 10907.18196135293, 4291.892702682396, 4445.5776701213335, 4487.842925497592, 4500.3249512573375, 4605.930911036337, 9312.44125352481, 9327.067293270755, 9327.253819694408, 9337.57980890848, 9342.532873356306, 5085.74571271435, 5096.668716533413, 5099.36257967754, 5101.566366858859, 5102.311187989648, 4480.081583275421, 4544.818207271672, 4582.725036497821, 4616.030633566921, 4620.0990806793025, 3759.1353988582778, 3798.278327117894, 3849.711271654581, 3882.495275614152, 3891.2606604745006, 1304.6347022833622, 1315.6579289384638, 1315.6645055510976, 1327.2633688302892, 1333.4599487207697, 912.9406588571721, 924.6347692661495, 939.1172298039958, 964.7250533597891, 997.9800403991879, 5476.91784623231, 5633.352164501053, 5639.0, 5723.627964182315, 5734.541236701054, 421.93461509688285, 430.1053808079188, 435.4252386849328, 445.4342984409781, 445.9063596644741, 3886.650201481824, 3946.782926939046, 3963.6432721054666, 3986.86045988389, 4093.669557393859, 9414.0, 9419.293552983554, 9615.711528654012, 9710.0, 9720.95139524312, 9434.811303773886, 9596.520173991272, 9611.951940240393, 9612.231021518262, 9614.086661767122, 7250.818729531804, 7366.815829604196, 7403.333699967029, 7474.065741782278, 7483.812904677317, 5402.621816472809, 5422.186671999074, 5426.375966763715, 5433.347998240237, 5708.429157084258, 8754.956225218833, 8900.910990890266, 8920.687775927812, 8949.194572488508, 8955.208958208254, 8542.871856922027, 8857.955710221408, 8869.955650221966, 8988.820223595621, 9144.679936202201, 6423.935760642334, 6604.14146160996, 6610.074589557562, 6776.0, 6787.5588086774205, 7433.293837085468, 7552.773416797505, 7598.468107232441, 7762.844743105219, 7796.805079873048, 6637.369449902251, 6720.865582688417, 6752.155980502437, 6867.690953907186, 6905.240423553305, 6928.826779330557, 6999.51003429755, 7010.789676309719, 7212.134543854771, 7242.52923559988, 6464.191976003, 6464.870702586016, 6487.643179625166, 6506.804795855943, 6510.381513756186, 4871.561559459666, 4921.311016457627, 4944.577157193022, 4959.380077490314, 5029.396472423186, 4759.286107083826, 4887.144749668722, 4910.779014944407, 4948.8762780930765, 5119.513646203604], "value": 4216.091828135299, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.38927895963394, 334.68539572801564, 336.26861576072037, 339.36199944105107, 341.77613663050704, 5018.799248030079, 5118.053160165372, 5131.845334799668, 5200.791968321268, 5213.2701421800975, 5123.0, 5125.692458452496, 5127.717975511346, 5130.563902068328, 5154.0980328442465, 4092.4270602115726, 4111.897202569945, 4114.280000999821, 4118.217538667658, 4120.711550191473, 3918.5689574146963, 4123.299039163335, 4131.5042194936495, 4135.4623898893105, 4136.958630413687, 4084.36692312692, 4094.283500387435, 4105.3020986432375, 4112.403701463301, 4114.218298523271, 4082.6121518455698, 4102.610252026053, 4108.445359876423, 4110.794460276974, 4123.45382970645, 3664.6701796838147, 3665.505156803824, 3674.4855720199193, 3680.4479328100733, 3691.151035261878, 4795.856124316241, 5009.549140577329, 5023.572996295319, 5024.944761600068, 5092.115917110656, 4560.429946256718, 4571.519990401027, 4572.7713614319155, 4582.679212455129, 4617.575772719316, 5309.6372114409105, 5334.839954801362, 5344.866378340534, 5347.358317001946, 5368.758405871745, 3755.4930084438524, 3889.183271512986, 3891.5135608048995, 3911.471951286568, 3916.8836881488924, 5256.579473642099, 5427.1316589345515, 5503.834884953458, 5544.944550554484, 5560.4161563035705, 3875.8837234883, 3956.188981258835, 3978.104926391539, 4008.819603117867, 4014.558398576154, 3778.545260074836, 3874.2251549690177, 3908.726389152732, 4050.9392359114636, 4063.2482990646827, 4535.160995215896, 4553.157665831832, 4563.566461186182, 4575.885602859888, 4576.519465456079, 5368.2752828367675, 5373.731313434313, 5380.955237009989, 5422.132458806572, 5436.130219164915, 1317.736452709462, 1342.7515909556764, 1346.562367230654, 1346.670065833877, 1349.4804500267467, 930.831806083361, 941.2140862379853, 942.9858552121724, 950.1211379473959, 957.8946315905245, 4839.879003024882, 4905.607551395879, 4921.803127874845, 4934.654574179773, 5004.0, 5166.664166829144, 5221.321228240305, 5231.581473482112, 5244.6328756987405, 5258.7370631468275, 4907.429622520759, 4935.457099719028, 4937.901241975141, 4959.975200124049, 4979.3526841510375, 2798.9224148702897, 2818.422223444189, 2838.9432211355656, 2842.545192769147, 2843.644544431946, 5505.587080968943, 5516.558675305966, 5531.3362396512675, 5538.75075621598, 5540.889182216333, 5347.51872331492, 5466.3713672928125, 5484.2596249506605, 5637.23897273871, 5651.632643878217, 5729.828105156852, 5734.082546792577, 5736.483716465371, 5756.539476841926, 5758.596898217084, 5423.864403389789, 5551.722413879209, 5561.888762224652, 5575.470330318613, 5626.43735626434, 5443.945560544344, 5457.889003268099, 5481.725913704219, 5488.753006114654, 5504.8622285297, 5677.3471050829685, 5714.257146570919, 5715.171300161536, 5720.942790572208, 5770.259126914682, 3856.8264428101365, 3978.553189788317, 4009.3785463253244, 4012.9799351003057, 4014.8996275093355, 5497.890042199214, 5513.2005859151, 5519.337679478488, 5520.834374968757, 5522.806701765419, 5350.571954243574, 5359.490848369554, 5359.866003349947, 5419.620626556103, 5430.945690543044, 5219.564619729605, 5328.8401347959625, 5337.706426146599, 5373.865653358541, 5388.326459192601, 5247.921281180709, 5265.341832270966, 5279.683219006872, 5294.867628309323, 5301.3373328333955, 5376.784928602812, 5379.946200537945, 5381.354237491526, 5429.864253393696, 5448.56411487088, 5082.593392528515, 5219.347581552306, 5249.527542521191, 5257.868553286045, 5258.474152584711], "value": 4102.729144098557, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.3997122431546, 714.9070620819293, 738.2949283434317, 747.0549754560482, 761.9238076192385, 14963.401463941444, 15992.681975866539, 16078.839211607881, 16169.595760106004, 16200.837991620096, 15133.0, 15311.387544498235, 15326.770098448533, 15333.310001049951, 15335.233238338094, 646.8803271394784, 665.490899461912, 759.2824780582351, 765.2309429023824, 777.0481160578303, 776.4332037612518, 786.3709032773787, 786.4612740272903, 786.9567173805439, 787.4055088408272, 829.6225217526041, 830.5971603772166, 831.0069466986923, 831.2767891934026, 831.5675848558745, 687.4740823270203, 710.3997122431537, 792.9603519823986, 827.8882350882614, 828.457360428917, 598.3059650805045, 604.0727483313137, 604.3533419241413, 605.0017471172556, 605.2192671453819, 15761.369545218178, 15794.526164215093, 15854.207289635588, 15858.762118568115, 15859.841401586067, 14555.199464029474, 14579.854201457956, 14654.926725366411, 14731.0, 14798.630034249123, 12954.48182072716, 12997.350132493431, 13000.739985200244, 13336.0, 13370.197788132744, 4277.283204832702, 4307.483102027777, 4333.501647310568, 4371.868843934687, 4460.063386688799, 84708.57645711732, 86722.0, 86917.0, 89705.55147224288, 93148.42496263054, 11296.305554166776, 11338.546458141582, 11366.602168924213, 11368.715782105513, 11757.529698811952, 22091.66862497064, 22211.555768884533, 22446.887765561067, 22658.43353916165, 22727.0, 7766.805829854299, 7892.684292628231, 7912.208779122157, 7987.3610111190965, 7993.560354180576, 4328.11273688893, 4429.53489883564, 4464.955350446519, 4528.569785870403, 4666.253399456071, 3105.2857842695853, 3188.58548388708, 3219.967800321967, 3220.2593403516853, 3227.402930457873, 567.2229046206724, 575.689127870945, 577.3072313224147, 587.5329113354893, 595.6068994463633, 539.8812261302508, 591.9526437884958, 592.3099588978878, 615.2125279641975, 618.523736722721, 9042.186203241608, 9315.627374905063, 9376.812463750686, 9393.671221507215, 9729.367591106555, 570.292836882263, 604.7490291528966, 619.1888625900085, 676.3540818518303, 678.9083473730983, 4245.448091748053, 4457.2199865023495, 4482.1707984022405, 4541.046380260116, 4541.364209010741, 83111.0, 85390.14609853821, 85951.0, 85964.0, 89314.0, 74495.0, 75212.0, 76313.0, 76688.61655691685, 76724.0, 57501.0, 67368.6631566839, 68045.65977170083, 68384.0, 70699.64650176716, 8782.516961567175, 8843.248323892283, 8979.668955504565, 9038.548072596475, 9329.906700933176, 45038.324425134306, 45336.10630617999, 45656.54343456523, 46570.301445477664, 46667.0, 58457.70771146117, 60370.0, 62452.375476244655, 62888.0, 62991.37008629855, 11204.43977801123, 11322.773544529227, 11576.768464630828, 11783.351915644722, 11820.76358472843, 12300.200486968319, 13096.21422714602, 14011.881188118812, 15298.77051844201, 15389.38442462289, 14124.2937853109, 14139.929300353433, 14158.292085395895, 14163.787543186654, 14439.0, 14275.214863182624, 14316.57050288493, 14352.856471435152, 14559.272036397926, 14832.18422986746, 13339.19964802115, 13705.177689338672, 13764.105333153313, 13871.583852484442, 13941.233232172328, 10414.47927603632, 11211.383373914514, 11489.425528723697, 11827.645170644895, 12357.629271121881, 11078.647308251402, 11459.109246974156, 11498.25261358009, 11504.309741415207, 11731.296122232694], "value": 7216.620634647672, "unit": "score"}], "name": "dromaeo_css", "value": 6859.424810927522, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 112.0, 113.0, 112.0, 112.0, 110.0, 112.0, 111.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 124.0, 125.0, 166.0, 126.0, 123.0, 124.0, 123.0, 149.0, 124.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 161.0, 159.0, 162.0, 169.0, 162.0, 161.0, 162.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 122.0, 77.0, 77.0, 87.0, 78.0, 92.0, 77.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 104.0, 82.0, 84.0, 83.0, 94.0, 82.0, 82.0, 110.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 53.0, 53.0, 54.0, 53.0, 53.0, 53.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 63.0, 63.0, 63.0], "value": 62.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 81.0, 81.0, 87.0, 87.0, 81.0, 79.0, 80.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 133.0, 134.0, 138.0, 141.0, 138.0, 131.0, 141.0, 174.0, 142.0], "value": 141.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 167.0, 170.0, 168.0, 176.0, 174.0, 174.0, 167.0, 168.0, 165.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 64.0, 65.0, 67.0, 66.0, 66.0, 66.0, 65.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1512.1000000000001, "alertThreshold": 2.0}]} 21:29:32 INFO - Return code: 0 21:29:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:29:32 INFO - # TBPL SUCCESS # 21:29:32 INFO - Running post-action listener: _resource_record_post_action 21:29:32 INFO - [mozharness: 2016-12-05 05:29:32.546525Z] Finished run-tests step (success) 21:29:32 INFO - Running post-run listener: _resource_record_post_run 21:29:32 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 0; Write bytes: 393007104; Read time: 0; Write time: 367856 21:29:32 INFO - TinderboxPrint: CPU usage
13.1% 21:29:32 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 21:29:32 INFO - TinderboxPrint: I/O write bytes / time
393,007,104 / 367,856 21:29:32 INFO - TinderboxPrint: CPU idle
8,300.4 (86.9%) 21:29:32 INFO - TinderboxPrint: CPU user
1,200.5 (12.6%) 21:29:32 INFO - TinderboxPrint: Swap in / out
0 / 0 21:29:32 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 103936000; Read time: 0; Write time: 97528 21:29:32 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 288522240; Read time: 0; Write time: 270212 21:29:33 INFO - Running post-run listener: _upload_blobber_files 21:29:33 INFO - Blob upload gear active. 21:29:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:29:33 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 21:29:33 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:29:33 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:29:33 INFO - (blobuploader) - INFO - Open directory for files ... 21:29:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:29:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:29:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:35 INFO - (blobuploader) - INFO - Done attempting. 21:29:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:29:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:29:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:35 INFO - (blobuploader) - INFO - Done attempting. 21:29:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:29:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:29:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:39 INFO - (blobuploader) - INFO - Done attempting. 21:29:39 INFO - (blobuploader) - INFO - Iteration through files over. 21:29:40 INFO - Return code: 0 21:29:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:29:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:29:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1faa7d87f831ce1a79c91d475057f46d4db71dd0d6be7be384f127a8480a4bfc76bfd6680610cf1140878944b47a998389cbc77972471d3af0a0c517b261ecb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f061605384fb13984a34c1aa7140ff8d2664c059a994ec5d44de4cacf509eb4aedaf85142e4d13bd71042807306824e275d8c5b6e16df3edc9919e59de43cbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a5f0f858ce1bed1eb1693a12fee7e6459d66527531349bc7284306296b0cfb41f9837c22d97657ce0987a25aadf53afd0a20351af5508130d12c78a5e11d08a"} 21:29:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:29:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:29:40 INFO - Contents: 21:29:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1faa7d87f831ce1a79c91d475057f46d4db71dd0d6be7be384f127a8480a4bfc76bfd6680610cf1140878944b47a998389cbc77972471d3af0a0c517b261ecb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f061605384fb13984a34c1aa7140ff8d2664c059a994ec5d44de4cacf509eb4aedaf85142e4d13bd71042807306824e275d8c5b6e16df3edc9919e59de43cbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a5f0f858ce1bed1eb1693a12fee7e6459d66527531349bc7284306296b0cfb41f9837c22d97657ce0987a25aadf53afd0a20351af5508130d12c78a5e11d08a"} 21:29:40 INFO - Running post-run listener: copy_logs_to_upload_dir 21:29:40 INFO - Copying logs to upload dir... 21:29:40 INFO - mkdir: /builds/slave/test/build/upload/logs 21:29:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.789945 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-04 21:29:39.901316) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 21:29:39.905045) ========= 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=62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1faa7d87f831ce1a79c91d475057f46d4db71dd0d6be7be384f127a8480a4bfc76bfd6680610cf1140878944b47a998389cbc77972471d3af0a0c517b261ecb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f061605384fb13984a34c1aa7140ff8d2664c059a994ec5d44de4cacf509eb4aedaf85142e4d13bd71042807306824e275d8c5b6e16df3edc9919e59de43cbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a5f0f858ce1bed1eb1693a12fee7e6459d66527531349bc7284306296b0cfb41f9837c22d97657ce0987a25aadf53afd0a20351af5508130d12c78a5e11d08a"} build_url:https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/N-vHMLVpRZygQJY7YXaZKQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1faa7d87f831ce1a79c91d475057f46d4db71dd0d6be7be384f127a8480a4bfc76bfd6680610cf1140878944b47a998389cbc77972471d3af0a0c517b261ecb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f061605384fb13984a34c1aa7140ff8d2664c059a994ec5d44de4cacf509eb4aedaf85142e4d13bd71042807306824e275d8c5b6e16df3edc9919e59de43cbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a5f0f858ce1bed1eb1693a12fee7e6459d66527531349bc7284306296b0cfb41f9837c22d97657ce0987a25aadf53afd0a20351af5508130d12c78a5e11d08a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 21:29:39.930707) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 21:29:39.931026) ========= 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=62c568580acbfdaac3eec7be000001dc-1480912241.588750-125557663 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004391 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 21:29:40.013452) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 21:29:40.013797) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 21:29:40.014082) ========= ========= Total master_lag: 0.17 =========