builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-038 starttime: 1472141154.1 results: success (0) buildid: 20160825075526 builduid: 247f8c23c41f42e7a3a63f410cb2829a revision: f5de686d697d9b845e5c58e3ec99e0f134ad7014 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:54.104123) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:54.104569) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:54.104843) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:54.128208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:54.128472) ========= 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=8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-25 09:05: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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-08-25 09:05:54 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093243 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:54.237738) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:54.238088) ========= 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=8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020472 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:54.287994) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 09:05:54.288316) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev f5de686d697d9b845e5c58e3ec99e0f134ad7014 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev f5de686d697d9b845e5c58e3ec99e0f134ad7014 --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=8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-25 09:05:54,682 truncating revision to first 12 chars 2016-08-25 09:05:54,682 Setting DEBUG logging. 2016-08-25 09:05:54,682 attempt 1/10 2016-08-25 09:05:54,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/f5de686d697d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 09:05:55,996 unpacking tar archive at: larch-f5de686d697d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.604578 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 09:05:55.906630) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:55.907105) ========= script_repo_revision: f5de686d697d9b845e5c58e3ec99e0f134ad7014 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:55.907520) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:55.908070) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 09:05:55.917645) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-08-25 09:05:55.918029) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 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', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-Non-PGO'] 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=8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:05:56 INFO - MultiFileLogger online at 20160825 09:05:56 in /builds/slave/test 09:05:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 09:05:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:05:56 INFO - {'append_to_log': False, 09:05:56 INFO - 'base_work_dir': '/builds/slave/test', 09:05:56 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 09:05:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:05:56 INFO - 'branch': 'Larch-Non-PGO', 09:05:56 INFO - 'buildbot_json_path': 'buildprops.json', 09:05:56 INFO - 'config_files': ('talos/linux_config.py',), 09:05:56 INFO - 'default_actions': ('clobber', 09:05:56 INFO - 'read-buildbot-config', 09:05:56 INFO - 'download-and-extract', 09:05:56 INFO - 'populate-webroot', 09:05:56 INFO - 'create-virtualenv', 09:05:56 INFO - 'install', 09:05:56 INFO - 'run-tests'), 09:05:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:05:56 INFO - 'download_minidump_stackwalk': True, 09:05:56 INFO - 'download_symbols': 'ondemand', 09:05:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:05:56 INFO - 'tooltool.py': '/tools/tooltool.py', 09:05:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:05:56 INFO - '/tools/misc-python/virtualenv.py')}, 09:05:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:05:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:05:56 INFO - 'installer_path': 'installer.exe', 09:05:56 INFO - 'log_level': 'info', 09:05:56 INFO - 'log_name': 'talos', 09:05:56 INFO - 'log_to_console': True, 09:05:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:05:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:05:56 INFO - 'opt_config_files': (), 09:05:56 INFO - 'pip_index': False, 09:05:56 INFO - 'sps_profile': False, 09:05:56 INFO - 'sps_profile_interval': 0, 09:05:56 INFO - 'suite': 'dromaeojs', 09:05:56 INFO - 'system_bits': '32', 09:05:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:05:56 INFO - 'title': 'talos-linux64-ix-038', 09:05:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:05:56 INFO - 'use_talos_json': True, 09:05:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:05:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:05:56 INFO - 'work_dir': 'build'} 09:05:56 INFO - [mozharness: 2016-08-25 16:05:56.412628Z] Running clobber step. 09:05:56 INFO - Running pre-action listener: _resource_record_pre_action 09:05:56 INFO - Running main action method: clobber 09:05:56 INFO - rmtree: /builds/slave/test/build 09:05:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:05:56 INFO - Running post-action listener: _resource_record_post_action 09:05:56 INFO - [mozharness: 2016-08-25 16:05:56.873518Z] Finished clobber step (success) 09:05:56 INFO - [mozharness: 2016-08-25 16:05:56.873619Z] Running read-buildbot-config step. 09:05:56 INFO - Running pre-action listener: _resource_record_pre_action 09:05:56 INFO - Running main action method: read_buildbot_config 09:05:56 INFO - Using buildbot properties: 09:05:56 INFO - { 09:05:56 INFO - "project": "", 09:05:56 INFO - "product": "firefox", 09:05:56 INFO - "script_repo_revision": "production", 09:05:56 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 09:05:56 INFO - "repository": "", 09:05:56 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 09:05:56 INFO - "buildid": "20160825075526", 09:05:56 INFO - "pgo_build": "False", 09:05:56 INFO - "basedir": "/builds/slave/test", 09:05:56 INFO - "buildnumber": 3, 09:05:56 INFO - "slavename": "talos-linux64-ix-038", 09:05:56 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:05:56 INFO - "platform": "ubuntu64_hw", 09:05:56 INFO - "scheduler": "tests-larch-linux64-talos", 09:05:56 INFO - "branch": "larch", 09:05:56 INFO - "repo_path": "projects/larch", 09:05:56 INFO - "revision": "f5de686d697d9b845e5c58e3ec99e0f134ad7014", 09:05:56 INFO - "stage_platform": "linux64", 09:05:56 INFO - "builduid": "247f8c23c41f42e7a3a63f410cb2829a", 09:05:56 INFO - "slavebuilddir": "test" 09:05:56 INFO - } 09:05:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:05:56 INFO - Running post-action listener: _resource_record_post_action 09:05:56 INFO - [mozharness: 2016-08-25 16:05:56.876041Z] Finished read-buildbot-config step (success) 09:05:56 INFO - [mozharness: 2016-08-25 16:05:56.876129Z] Running download-and-extract step. 09:05:56 INFO - Running pre-action listener: _resource_record_pre_action 09:05:56 INFO - Running main action method: download_and_extract 09:05:56 INFO - mkdir: /builds/slave/test/build/tests 09:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:56 INFO - trying https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:05:56 INFO - Downloading https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:05:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:05:57 INFO - Downloaded 1361 bytes. 09:05:57 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:05:57 INFO - Using the following test package requirements: 09:05:57 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:05:57 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:05:57 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:05:57 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:57 INFO - u'jsshell-linux-x86_64.zip'], 09:05:57 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:05:57 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:05:57 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:05:57 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:05:57 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:05:57 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:05:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:57 INFO - trying https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:05:57 INFO - Downloading https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:05:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:05:58 INFO - Downloaded 20330692 bytes. 09:05:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 09:06:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:02 INFO - trying https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:06:02 INFO - Downloading https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:06:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:06:03 INFO - Downloaded 20330692 bytes. 09:06:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 09:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:08 INFO - trying https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:06:08 INFO - Downloading https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:06:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:06:08 INFO - Downloaded 14413981 bytes. 09:06:08 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 09:06: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')]} 09:06:09 INFO - trying https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:06:09 INFO - Downloading https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:06:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:06:10 INFO - Downloaded 55748077 bytes. 09:06:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:06:10 INFO - mkdir: /builds/slave/test/properties 09:06:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:06:10 INFO - Writing to file /builds/slave/test/properties/build_url 09:06:10 INFO - Contents: 09:06:10 INFO - build_url:https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:06:11 INFO - Running post-action listener: _resource_record_post_action 09:06:11 INFO - Running post-action listener: set_extra_try_arguments 09:06:11 INFO - [mozharness: 2016-08-25 16:06:11.119721Z] Finished download-and-extract step (success) 09:06:11 INFO - [mozharness: 2016-08-25 16:06:11.119956Z] Running populate-webroot step. 09:06:11 INFO - Running pre-action listener: _resource_record_pre_action 09:06:11 INFO - Running main action method: populate_webroot 09:06:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:06:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:06:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:06:11 INFO - '--tppagecycles', 09:06:11 INFO - '7'], 09:06:11 INFO - 'tests': ['tsvgm']}}, 09:06:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:06:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:06:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:06:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:06:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:11 INFO - 'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:11 INFO - 'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['damp', 'tps']}, 09:06:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:11 INFO - 'tests': ['damp', 'tps']}, 09:06:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['dromaeo_dom']}, 09:06:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:06:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['basic_compositor_video']}, 09:06:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:06:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['a11yr', 09:06:11 INFO - 'ts_paint', 09:06:11 INFO - 'tpaint', 09:06:11 INFO - 'sessionrestore', 09:06:11 INFO - 'sessionrestore_no_auto_restore', 09:06:11 INFO - 'tabpaint']}, 09:06:11 INFO - 'other-e10s': {'tests': ['a11yr', 09:06:11 INFO - 'ts_paint', 09:06:11 INFO - 'tpaint', 09:06:11 INFO - 'sessionrestore', 09:06:11 INFO - 'sessionrestore_no_auto_restore', 09:06:11 INFO - 'tabpaint']}, 09:06:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:06:11 INFO - 'ts_paint', 09:06:11 INFO - 'tpaint', 09:06:11 INFO - 'sessionrestore', 09:06:11 INFO - 'sessionrestore_no_auto_restore', 09:06:11 INFO - 'tabpaint']}, 09:06:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:06:11 INFO - 'ts_paint', 09:06:11 INFO - 'tpaint', 09:06:11 INFO - 'sessionrestore', 09:06:11 INFO - 'sessionrestore_no_auto_restore', 09:06:11 INFO - 'tabpaint']}, 09:06:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['a11yr', 09:06:11 INFO - 'ts_paint', 09:06:11 INFO - 'tpaint', 09:06:11 INFO - 'sessionrestore', 09:06:11 INFO - 'sessionrestore_no_auto_restore', 09:06:11 INFO - 'tabpaint']}, 09:06:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['a11yr', 09:06:11 INFO - 'ts_paint', 09:06:11 INFO - 'tpaint', 09:06:11 INFO - 'sessionrestore', 09:06:11 INFO - 'sessionrestore_no_auto_restore', 09:06:11 INFO - 'tabpaint']}, 09:06:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['tsvgx', 09:06:11 INFO - 'tsvgr_opacity', 09:06:11 INFO - 'tart', 09:06:11 INFO - 'tscrollx', 09:06:11 INFO - 'cart']}, 09:06:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:06:11 INFO - 'tsvgr_opacity', 09:06:11 INFO - 'tart', 09:06:11 INFO - 'tscrollx', 09:06:11 INFO - 'cart']}, 09:06:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:11 INFO - 'talos_options': ['--disable-e10s'], 09:06:11 INFO - 'tests': ['tp5o']}, 09:06:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:11 INFO - 'tests': ['tp5o']}, 09:06:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:11 INFO - 'talos_options': ['--disable-e10s', 09:06:11 INFO - '--xperf_path', 09:06:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:11 INFO - 'tests': ['tp5n']}, 09:06:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:11 INFO - 'talos_options': ['--xperf_path', 09:06:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:11 INFO - 'tests': ['tp5n']}}, 09:06:11 INFO - 'talos.zip': {'path': '', 09:06:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:06:11 INFO - Running post-action listener: _resource_record_post_action 09:06:11 INFO - [mozharness: 2016-08-25 16:06:11.135676Z] Finished populate-webroot step (success) 09:06:11 INFO - [mozharness: 2016-08-25 16:06:11.135783Z] Running create-virtualenv step. 09:06:11 INFO - Running pre-action listener: _resource_record_pre_action 09:06:11 INFO - Running main action method: create_virtualenv 09:06:11 INFO - Creating virtualenv /builds/slave/test/build/venv 09:06:11 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 09:06:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:06:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:06:11 INFO - Using real prefix '/usr' 09:06:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:06:11 INFO - Installing distribute.............................................................................................................................................................................................done. 09:06:13 INFO - Installing pip.................done. 09:06:13 INFO - Return code: 0 09:06:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdecc9d9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, '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': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', '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 09:06:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:06:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:06:13 INFO - Using env: {'DISPLAY': ':0', 09:06:13 INFO - 'HOME': '/home/cltbld', 09:06:13 INFO - 'LANG': 'en_US.UTF-8', 09:06:13 INFO - 'LANGUAGE': 'en_US:en', 09:06:13 INFO - 'LOGNAME': 'cltbld', 09:06:13 INFO - 'MAIL': '/var/mail/cltbld', 09:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:13 INFO - 'MOZ_NO_REMOTE': '1', 09:06:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:13 INFO - 'NO_EM_RESTART': '1', 09:06:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:13 INFO - 'PWD': '/builds/slave/test', 09:06:13 INFO - 'SHELL': '/bin/bash', 09:06:13 INFO - 'SHLVL': '1', 09:06:13 INFO - 'TERM': 'linux', 09:06:13 INFO - 'TMOUT': '86400', 09:06:13 INFO - 'USER': 'cltbld', 09:06:13 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:13 INFO - '_': '/tools/buildbot/bin/python'} 09:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:06:13 INFO - Cleaning up... 09:06:13 INFO - Return code: 0 09:06:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdecc9d9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, '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': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', '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 09:06:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:06:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:06:13 INFO - Using env: {'DISPLAY': ':0', 09:06:13 INFO - 'HOME': '/home/cltbld', 09:06:13 INFO - 'LANG': 'en_US.UTF-8', 09:06:13 INFO - 'LANGUAGE': 'en_US:en', 09:06:13 INFO - 'LOGNAME': 'cltbld', 09:06:13 INFO - 'MAIL': '/var/mail/cltbld', 09:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:13 INFO - 'MOZ_NO_REMOTE': '1', 09:06:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:13 INFO - 'NO_EM_RESTART': '1', 09:06:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:13 INFO - 'PWD': '/builds/slave/test', 09:06:13 INFO - 'SHELL': '/bin/bash', 09:06:13 INFO - 'SHLVL': '1', 09:06:13 INFO - 'TERM': 'linux', 09:06:13 INFO - 'TMOUT': '86400', 09:06:13 INFO - 'USER': 'cltbld', 09:06:13 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:13 INFO - '_': '/tools/buildbot/bin/python'} 09:06:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:14 INFO - Downloading/unpacking psutil>=3.1.1 09:06:14 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 09:06:14 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 09:06:14 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 09:06:14 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 09:06:14 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 09:06:14 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 09:06:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:06:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:16 INFO - Installing collected packages: psutil 09:06:16 INFO - Running setup.py install for psutil 09:06:16 INFO - building 'psutil._psutil_linux' extension 09:06:16 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 09:06:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:06:16 INFO - building 'psutil._psutil_posix' extension 09:06:16 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 09:06:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:06:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:16 INFO - Successfully installed psutil 09:06:16 INFO - Cleaning up... 09:06:16 INFO - Return code: 0 09:06:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:06:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdecc9d9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, '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': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', '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 09:06:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:06:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:06:16 INFO - Using env: {'DISPLAY': ':0', 09:06:16 INFO - 'HOME': '/home/cltbld', 09:06:16 INFO - 'LANG': 'en_US.UTF-8', 09:06:16 INFO - 'LANGUAGE': 'en_US:en', 09:06:16 INFO - 'LOGNAME': 'cltbld', 09:06:16 INFO - 'MAIL': '/var/mail/cltbld', 09:06:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:16 INFO - 'MOZ_NO_REMOTE': '1', 09:06:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:16 INFO - 'NO_EM_RESTART': '1', 09:06:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:16 INFO - 'PWD': '/builds/slave/test', 09:06:16 INFO - 'SHELL': '/bin/bash', 09:06:16 INFO - 'SHLVL': '1', 09:06:16 INFO - 'TERM': 'linux', 09:06:16 INFO - 'TMOUT': '86400', 09:06:16 INFO - 'USER': 'cltbld', 09:06:16 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:16 INFO - '_': '/tools/buildbot/bin/python'} 09:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:06:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:06:18 INFO - Installing collected packages: mozsystemmonitor 09:06:18 INFO - Running setup.py install for mozsystemmonitor 09:06:18 INFO - Successfully installed mozsystemmonitor 09:06:18 INFO - Cleaning up... 09:06:18 INFO - Return code: 0 09:06:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:06: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')]} 09:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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')]} 09:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdecc9d9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, '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': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', '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 09:06:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:06:18 INFO - Using env: {'DISPLAY': ':0', 09:06:18 INFO - 'HOME': '/home/cltbld', 09:06:18 INFO - 'LANG': 'en_US.UTF-8', 09:06:18 INFO - 'LANGUAGE': 'en_US:en', 09:06:18 INFO - 'LOGNAME': 'cltbld', 09:06:18 INFO - 'MAIL': '/var/mail/cltbld', 09:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:18 INFO - 'MOZ_NO_REMOTE': '1', 09:06:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:18 INFO - 'NO_EM_RESTART': '1', 09:06:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:18 INFO - 'PWD': '/builds/slave/test', 09:06:18 INFO - 'SHELL': '/bin/bash', 09:06:18 INFO - 'SHLVL': '1', 09:06:18 INFO - 'TERM': 'linux', 09:06:18 INFO - 'TMOUT': '86400', 09:06:18 INFO - 'USER': 'cltbld', 09:06:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:18 INFO - '_': '/tools/buildbot/bin/python'} 09:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:19 INFO - Downloading/unpacking jsonschema==2.5.1 09:06:19 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 09:06:19 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 09:06:19 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 09:06:19 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 09:06:19 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 09:06:19 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 09:06:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:06:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:21 INFO - Downloading functools32-3.2.3-2.tar.gz 09:06:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:06:21 INFO - warning: no files found matching '*.txt' 09:06:21 INFO - no previously-included directories found matching 'build' 09:06:21 INFO - no previously-included directories found matching 'dist' 09:06:21 INFO - no previously-included directories found matching '.git*' 09:06:21 INFO - Installing collected packages: jsonschema, functools32 09:06:21 INFO - Running setup.py install for functools32 09:06:21 INFO - warning: no files found matching '*.txt' 09:06:21 INFO - no previously-included directories found matching 'build' 09:06:21 INFO - no previously-included directories found matching 'dist' 09:06:21 INFO - no previously-included directories found matching '.git*' 09:06:21 INFO - Successfully installed jsonschema functools32 09:06:21 INFO - Cleaning up... 09:06:21 INFO - Return code: 0 09:06:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdecc9d9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, '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': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', '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 09:06:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:06:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:06:21 INFO - Using env: {'DISPLAY': ':0', 09:06:21 INFO - 'HOME': '/home/cltbld', 09:06:21 INFO - 'LANG': 'en_US.UTF-8', 09:06:21 INFO - 'LANGUAGE': 'en_US:en', 09:06:21 INFO - 'LOGNAME': 'cltbld', 09:06:21 INFO - 'MAIL': '/var/mail/cltbld', 09:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:21 INFO - 'MOZ_NO_REMOTE': '1', 09:06:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:21 INFO - 'NO_EM_RESTART': '1', 09:06:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:21 INFO - 'PWD': '/builds/slave/test', 09:06:21 INFO - 'SHELL': '/bin/bash', 09:06:21 INFO - 'SHLVL': '1', 09:06:21 INFO - 'TERM': 'linux', 09:06:21 INFO - 'TMOUT': '86400', 09:06:21 INFO - 'USER': 'cltbld', 09:06:21 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:21 INFO - '_': '/tools/buildbot/bin/python'} 09:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:06:21 INFO - Cleaning up... 09:06:21 INFO - Return code: 0 09:06:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdecc9d9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, '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': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', '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 09:06:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:06:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:06:21 INFO - Using env: {'DISPLAY': ':0', 09:06:21 INFO - 'HOME': '/home/cltbld', 09:06:21 INFO - 'LANG': 'en_US.UTF-8', 09:06:21 INFO - 'LANGUAGE': 'en_US:en', 09:06:21 INFO - 'LOGNAME': 'cltbld', 09:06:21 INFO - 'MAIL': '/var/mail/cltbld', 09:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:21 INFO - 'MOZ_NO_REMOTE': '1', 09:06:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:21 INFO - 'NO_EM_RESTART': '1', 09:06:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:21 INFO - 'PWD': '/builds/slave/test', 09:06:21 INFO - 'SHELL': '/bin/bash', 09:06:21 INFO - 'SHLVL': '1', 09:06:21 INFO - 'TERM': 'linux', 09:06:21 INFO - 'TMOUT': '86400', 09:06:21 INFO - 'USER': 'cltbld', 09:06:21 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:21 INFO - '_': '/tools/buildbot/bin/python'} 09:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:21 INFO - Downloading/unpacking blobuploader==1.2.4 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:23 INFO - Downloading blobuploader-1.2.4.tar.gz 09:06:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:06:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:06:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:24 INFO - Downloading docopt-0.6.1.tar.gz 09:06:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:06:24 INFO - Installing collected packages: blobuploader, requests, docopt 09:06:24 INFO - Running setup.py install for blobuploader 09:06:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:06:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:06:24 INFO - Running setup.py install for requests 09:06:25 INFO - Running setup.py install for docopt 09:06:25 INFO - Successfully installed blobuploader requests docopt 09:06:25 INFO - Cleaning up... 09:06:25 INFO - Return code: 0 09:06:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:25 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 0x7fdecc9d9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, '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': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', '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 09:06:25 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 09:06:25 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 09:06:25 INFO - Using env: {'DISPLAY': ':0', 09:06:25 INFO - 'HOME': '/home/cltbld', 09:06:25 INFO - 'LANG': 'en_US.UTF-8', 09:06:25 INFO - 'LANGUAGE': 'en_US:en', 09:06:25 INFO - 'LOGNAME': 'cltbld', 09:06:25 INFO - 'MAIL': '/var/mail/cltbld', 09:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:25 INFO - 'MOZ_NO_REMOTE': '1', 09:06:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:25 INFO - 'NO_EM_RESTART': '1', 09:06:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:25 INFO - 'PWD': '/builds/slave/test', 09:06:25 INFO - 'SHELL': '/bin/bash', 09:06:25 INFO - 'SHLVL': '1', 09:06:25 INFO - 'TERM': 'linux', 09:06:25 INFO - 'TMOUT': '86400', 09:06:25 INFO - 'USER': 'cltbld', 09:06:25 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:25 INFO - '_': '/tools/buildbot/bin/python'} 09:06:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:25 INFO - Running setup.py (path:/tmp/pip-4YARD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:25 INFO - Running setup.py (path:/tmp/pip-q1eCF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:25 INFO - Running setup.py (path:/tmp/pip-mKkjjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:25 INFO - Running setup.py (path:/tmp/pip-VZaP7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:25 INFO - Running setup.py (path:/tmp/pip-Vrjml3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:25 INFO - Running setup.py (path:/tmp/pip-KM0oBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:25 INFO - Running setup.py (path:/tmp/pip-VE9uOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:25 INFO - Running setup.py (path:/tmp/pip-gRcsRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:25 INFO - Running setup.py (path:/tmp/pip-Nv89Ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:26 INFO - Running setup.py (path:/tmp/pip-oyjfWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:26 INFO - Running setup.py (path:/tmp/pip-eLBrjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:26 INFO - Running setup.py (path:/tmp/pip-1xhr2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:26 INFO - Running setup.py (path:/tmp/pip-ZyRIZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:26 INFO - Running setup.py (path:/tmp/pip-fCvUOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:26 INFO - Running setup.py (path:/tmp/pip-C2vedh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:26 INFO - Running setup.py (path:/tmp/pip-QYOSJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:26 INFO - Running setup.py (path:/tmp/pip-rmJb_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:06:26 INFO - Running setup.py install for manifestparser 09:06:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:06:26 INFO - Running setup.py install for mozcrash 09:06:26 INFO - Running setup.py install for mozdebug 09:06:26 INFO - Running setup.py install for mozdevice 09:06:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for mozfile 09:06:27 INFO - Running setup.py install for mozhttpd 09:06:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for mozinfo 09:06:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for mozInstall 09:06:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for mozleak 09:06:27 INFO - Running setup.py install for mozlog 09:06:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for moznetwork 09:06:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for mozprocess 09:06:27 INFO - Running setup.py install for mozprofile 09:06:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for mozrunner 09:06:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:06:28 INFO - Running setup.py install for mozscreenshot 09:06:28 INFO - Running setup.py install for moztest 09:06:28 INFO - Running setup.py install for mozversion 09:06:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:06:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:06:28 INFO - Cleaning up... 09:06:28 INFO - Return code: 0 09:06:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdecc9d9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, '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': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', '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 09:06:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:06:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:06:28 INFO - Using env: {'DISPLAY': ':0', 09:06:28 INFO - 'HOME': '/home/cltbld', 09:06:28 INFO - 'LANG': 'en_US.UTF-8', 09:06:28 INFO - 'LANGUAGE': 'en_US:en', 09:06:28 INFO - 'LOGNAME': 'cltbld', 09:06:28 INFO - 'MAIL': '/var/mail/cltbld', 09:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:28 INFO - 'MOZ_NO_REMOTE': '1', 09:06:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:28 INFO - 'NO_EM_RESTART': '1', 09:06:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:28 INFO - 'PWD': '/builds/slave/test', 09:06:28 INFO - 'SHELL': '/bin/bash', 09:06:28 INFO - 'SHLVL': '1', 09:06:28 INFO - 'TERM': 'linux', 09:06:28 INFO - 'TMOUT': '86400', 09:06:28 INFO - 'USER': 'cltbld', 09:06:28 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:28 INFO - '_': '/tools/buildbot/bin/python'} 09:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:28 INFO - Running setup.py (path:/tmp/pip-X6lKez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:28 INFO - Running setup.py (path:/tmp/pip-CaLpKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:28 INFO - Running setup.py (path:/tmp/pip-54HMR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:28 INFO - Running setup.py (path:/tmp/pip-Kv0hKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:28 INFO - Running setup.py (path:/tmp/pip-X1WlK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:29 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)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:29 INFO - Running setup.py (path:/tmp/pip-IBDwqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:29 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)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:29 INFO - Running setup.py (path:/tmp/pip-_JlJNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:29 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)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:29 INFO - Running setup.py (path:/tmp/pip-tsRB7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:29 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)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:29 INFO - Running setup.py (path:/tmp/pip-jdFWLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:29 INFO - Running setup.py (path:/tmp/pip-DVM2JF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:29 INFO - Running setup.py (path:/tmp/pip-G6aSjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:29 INFO - Running setup.py (path:/tmp/pip-1fzc3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:29 INFO - Running setup.py (path:/tmp/pip-myk3SZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:29 INFO - Running setup.py (path:/tmp/pip-8eXLYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:29 INFO - Running setup.py (path:/tmp/pip-nONirc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:29 INFO - Running setup.py (path:/tmp/pip-5bwoHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:29 INFO - Running setup.py (path:/tmp/pip-vivdbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:06:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:31 INFO - Downloading blessings-1.6.tar.gz 09:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:06:31 INFO - Installing collected packages: blessings 09:06:31 INFO - Running setup.py install for blessings 09:06:31 INFO - Successfully installed blessings 09:06:31 INFO - Cleaning up... 09:06:32 INFO - Return code: 0 09:06:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:06:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:32 INFO - Reading from file tmpfile_stdout 09:06:32 INFO - Current package versions: 09:06:32 INFO - argparse == 1.2.1 09:06:32 INFO - blessings == 1.6 09:06:32 INFO - blobuploader == 1.2.4 09:06:32 INFO - docopt == 0.6.1 09:06:32 INFO - functools32 == 3.2.3-2 09:06:32 INFO - jsonschema == 2.5.1 09:06:32 INFO - manifestparser == 1.1 09:06:32 INFO - mozInstall == 1.12 09:06:32 INFO - mozcrash == 0.17 09:06:32 INFO - mozdebug == 0.1 09:06:32 INFO - mozdevice == 0.48 09:06:32 INFO - mozfile == 1.2 09:06:32 INFO - mozhttpd == 0.7 09:06:32 INFO - mozinfo == 0.9 09:06:32 INFO - mozleak == 0.1 09:06:32 INFO - mozlog == 3.3 09:06:32 INFO - moznetwork == 0.27 09:06:32 INFO - mozprocess == 0.23 09:06:32 INFO - mozprofile == 0.28 09:06:32 INFO - mozrunner == 6.12 09:06:32 INFO - mozscreenshot == 0.1 09:06:32 INFO - mozsystemmonitor == 0.3 09:06:32 INFO - moztest == 0.7 09:06:32 INFO - mozversion == 1.4 09:06:32 INFO - psutil == 3.1.1 09:06:32 INFO - requests == 1.2.3 09:06:32 INFO - wsgiref == 0.1.2 09:06:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdecc9d9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, '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': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', '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 09:06:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:06:32 INFO - Using env: {'DISPLAY': ':0', 09:06:32 INFO - 'HOME': '/home/cltbld', 09:06:32 INFO - 'LANG': 'en_US.UTF-8', 09:06:32 INFO - 'LANGUAGE': 'en_US:en', 09:06:32 INFO - 'LOGNAME': 'cltbld', 09:06:32 INFO - 'MAIL': '/var/mail/cltbld', 09:06:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:32 INFO - 'MOZ_NO_REMOTE': '1', 09:06:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:32 INFO - 'NO_EM_RESTART': '1', 09:06:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:32 INFO - 'PWD': '/builds/slave/test', 09:06:32 INFO - 'SHELL': '/bin/bash', 09:06:32 INFO - 'SHLVL': '1', 09:06:32 INFO - 'TERM': 'linux', 09:06:32 INFO - 'TMOUT': '86400', 09:06:32 INFO - 'USER': 'cltbld', 09:06:32 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:32 INFO - '_': '/tools/buildbot/bin/python'} 09:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:06:32 INFO - Cleaning up... 09:06:32 INFO - Return code: 0 09:06:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdecc9d9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, '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': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', '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 09:06:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:06:32 INFO - Using env: {'DISPLAY': ':0', 09:06:32 INFO - 'HOME': '/home/cltbld', 09:06:32 INFO - 'LANG': 'en_US.UTF-8', 09:06:32 INFO - 'LANGUAGE': 'en_US:en', 09:06:32 INFO - 'LOGNAME': 'cltbld', 09:06:32 INFO - 'MAIL': '/var/mail/cltbld', 09:06:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:32 INFO - 'MOZ_NO_REMOTE': '1', 09:06:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:32 INFO - 'NO_EM_RESTART': '1', 09:06:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:32 INFO - 'PWD': '/builds/slave/test', 09:06:32 INFO - 'SHELL': '/bin/bash', 09:06:32 INFO - 'SHLVL': '1', 09:06:32 INFO - 'TERM': 'linux', 09:06:32 INFO - 'TMOUT': '86400', 09:06:32 INFO - 'USER': 'cltbld', 09:06:32 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:32 INFO - '_': '/tools/buildbot/bin/python'} 09:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:06:32 INFO - Cleaning up... 09:06:32 INFO - Return code: 0 09:06:32 INFO - Running post-action listener: _resource_record_post_action 09:06:32 INFO - Running post-action listener: _start_resource_monitoring 09:06:32 INFO - Starting resource monitoring. 09:06:32 INFO - [mozharness: 2016-08-25 16:06:32.732960Z] Finished create-virtualenv step (success) 09:06:32 INFO - [mozharness: 2016-08-25 16:06:32.733473Z] Running install step. 09:06:32 INFO - Running pre-action listener: _resource_record_pre_action 09:06:32 INFO - Running main action method: install 09:06:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:32 INFO - Reading from file tmpfile_stdout 09:06:32 INFO - Detecting whether we're running mozinstall >=1.0... 09:06:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:06:33 INFO - Reading from file tmpfile_stdout 09:06:33 INFO - Output received: 09:06:33 INFO - Usage: mozinstall [options] installer 09:06:33 INFO - Options: 09:06:33 INFO - -h, --help show this help message and exit 09:06:33 INFO - -d DEST, --destination=DEST 09:06:33 INFO - Directory to install application into. [default: 09:06:33 INFO - "/builds/slave/test"] 09:06:33 INFO - --app=APP Application being installed. [default: firefox] 09:06:33 INFO - mkdir: /builds/slave/test/build/application 09:06:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:06:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:06:50 INFO - Reading from file tmpfile_stdout 09:06:50 INFO - Output received: 09:06:50 INFO - /builds/slave/test/build/application/firefox/firefox 09:06:50 INFO - Running post-action listener: _resource_record_post_action 09:06:50 INFO - [mozharness: 2016-08-25 16:06:50.191247Z] Finished install step (success) 09:06:50 INFO - [mozharness: 2016-08-25 16:06:50.191494Z] Running run-tests step. 09:06:50 INFO - Running pre-action listener: _resource_record_pre_action 09:06:50 INFO - Running main action method: run_tests 09:06:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:06:50 INFO - Python 2.7.3 09:06:50 INFO - Return code: 0 09:06:50 INFO - grabbing minidump binary from tooltool 09:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:50 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 0x10e5d20>, '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 0x10e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e70b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:06:50 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 09:06:50 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 09:06:50 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 09:06:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:06:50 INFO - Return code: 0 09:06:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:06:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:06:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:06:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:06:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:06:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 09:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 09:06:50 INFO - Using env: {'DISPLAY': ':0', 09:06:50 INFO - 'HOME': '/home/cltbld', 09:06:50 INFO - 'LANG': 'en_US.UTF-8', 09:06:50 INFO - 'LANGUAGE': 'en_US:en', 09:06:50 INFO - 'LOGNAME': 'cltbld', 09:06:50 INFO - 'MAIL': '/var/mail/cltbld', 09:06:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:06:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:50 INFO - 'MOZ_NO_REMOTE': '1', 09:06:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:50 INFO - 'NO_EM_RESTART': '1', 09:06:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:50 INFO - 'PWD': '/builds/slave/test', 09:06:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:06:50 INFO - 'SHELL': '/bin/bash', 09:06:50 INFO - 'SHLVL': '1', 09:06:50 INFO - 'TERM': 'linux', 09:06:50 INFO - 'TMOUT': '86400', 09:06:50 INFO - 'USER': 'cltbld', 09:06:50 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442', 09:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:50 INFO - '_': '/tools/buildbot/bin/python'} 09:06:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 09:06:50 INFO - mozversion application_buildid: 20160825075526 09:06:50 INFO - mozversion application_changeset: f5de686d697d9b845e5c58e3ec99e0f134ad7014 09:06:50 INFO - mozversion application_display_name: Nightly 09:06:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:06:50 INFO - mozversion application_name: Firefox 09:06:50 INFO - mozversion application_remotingname: firefox 09:06:50 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 09:06:50 INFO - mozversion application_vendor: Mozilla 09:06:50 INFO - mozversion application_version: 51.0a1 09:06:50 INFO - mozversion platform_buildid: 20160825075526 09:06:50 INFO - mozversion platform_changeset: f5de686d697d9b845e5c58e3ec99e0f134ad7014 09:06:50 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 09:06:50 INFO - mozversion platform_version: 51.0a1 09:06:50 INFO - using testdate: 1472141210 09:06:50 INFO - actual date: 1472141210 09:06:50 INFO - starting webserver on 'localhost:54138' 09:06:50 INFO - SUITE-START | Running 2 tests 09:06:50 INFO - TEST-START | dromaeo_css 09:06:50 INFO - operating with platform_type : linux_ 09:06:50 INFO - Initialising browser for dromaeo_css test... 09:06:50 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpard_cd/profile http://localhost:54138/getInfo.html) 09:06:55 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 09:06:55 INFO - PROCESS | 1914 | colorDepth:24 09:06:55 INFO - PROCESS | 1914 | Browser inner width/height: 1024/697 09:06:55 INFO - PROCESS | 1914 | __metrics 09:06:56 INFO - PROCESS | 1914 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:06:56 INFO - PROCESS | 1914 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:57 INFO - TEST-INFO | 1914: exit 0 09:06:57 INFO - Browser initialized. 09:06:57 INFO - Running cycle 1/1 for dromaeo_css test... 09:06:57 INFO - TEST-INFO | started process 1993 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpard_cd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:07:09 INFO - PROCESS | 1993 | RSS: Main: 177967104 09:07:09 INFO - PROCESS | 1993 | 09:08:00 INFO - PROCESS | 1993 | 1472141280837 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] 09:10:14 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:54138/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54138/tests/dromaeo/cssquery-ext.html) 09:10:15 INFO - PROCESS | 1993 | RSS: Main: 279543808 09:10:15 INFO - PROCESS | 1993 | 09:13:17 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:54138/tests/dromaeo/cssquery-ext.html (next: http://localhost:54138/tests/dromaeo/cssquery-jquery.html) 09:13:18 INFO - PROCESS | 1993 | RSS: Main: 278437888 09:13:18 INFO - PROCESS | 1993 | 09:16:20 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:54138/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54138/tests/dromaeo/cssquery-mootools.html) 09:16:21 INFO - PROCESS | 1993 | RSS: Main: 258551808 09:16:21 INFO - PROCESS | 1993 | 09:19:23 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:54138/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54138/tests/dromaeo/cssquery-prototype.html) 09:19:24 INFO - PROCESS | 1993 | RSS: Main: 221478912 09:19:24 INFO - PROCESS | 1993 | 09:22:27 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:54138/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54138/tests/dromaeo/cssquery-yui.html) 09:22:28 INFO - PROCESS | 1993 | RSS: Main: 221212672 09:22:28 INFO - PROCESS | 1993 | 09:25:31 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:54138/tests/dromaeo/cssquery-yui.html (next: http://localhost:54138/tests/dromaeo/cssquery-dojo.html) 09:25:31 INFO - PROCESS | 1993 | RSS: Main: 310243328 09:25:31 INFO - PROCESS | 1993 | 09:25:31 INFO - PROCESS | 1993 | __start_tp_report 09:25:31 INFO - PROCESS | 1993 | _x_x_mozilla_page_load 09:25:31 INFO - PROCESS | 1993 | _x_x_mozilla_page_load_details 09:25:31 INFO - PROCESS | 1993 | |i|pagename|runs| 09:25:31 INFO - PROCESS | 1993 | |0;dojo.html;2532.4555220627562;2591.766740993311;2597.3896134408424;2608.2044976282236;2622.9213123606287;4557.430321209848;4673.742944138071;4688.437387513494;4701.647376446772;4746.667733258673;4499.932501012488;4652.209124448844;4658.371119898813;4661.277501987197;4785.904281914369;4336.97831510842;4366.08312254427;4370.519242883281;4397.406350142738;4407;4367.978160109211;4400.867973960786;4419.779011049451;4426.026274219666;4435.800388982488;4200.621944025022;4207.242696314662;4212.894677633053;4236.809343579546;4364;4256.425382573375;4305.203537345601;4309.159713855799;4318.676099292534;4371.693981421302;4325.026868954492;4347.934780978288;4358.411614432042;4363.192809330284;4370.715903466296;4509.887252818673;4546.29532422475;4579.061292435043;4622.306654001893;4723.126221649006;4982;4992.226204938241;5000.1249781288125;5004.274380214885;5008.474110218411;4989.625778066623;4991.276264941599;5022.748862556894;5025.447200807908;5026.472220416877;4502.369668246448;4570.451545814457;4607.285870690049;4616.561426664462;4682.81268749253;5114.846554603368;5127.07712611726;5141.742912854343;5144.38267407906;5147.922781158286;4927.285543596158;4933.654644174873;4934.087193869145;4935.284383764406;4945.134601444733;4903.607711383081;4972.179590367621;5015.3730783652045;5016.347874776256;5077.695338279715;5043.3191519145175;5043.5112749652835;5044.54599086084;5052.747362631853;5067.417247016567;5017.096922553902;5035.043341765127;5035.496450354935;5036.169032109661;5038.193888977746;4061.6547593454297;4131.607497287753;4152.418661387408;4167.4790651168605;4177.707560470738;3689.24370504046;3692.390755525362;3694.3350196964266;3703.759255648374;3708.2212735325343;4395.055063161436;4408.25059739845;4410.625096866738;4413.007073408522;4417.712848664826;4806.038792241566;4938.358013458227;4952.405711314594;4967.3294105296045;5053.5957123430035;4382.276924307517;4391.187630288407;4403.119376124724;4405.757683327448;4408.382826404306;4491.505934347219;4493;4499.280115181556;4499.392582001454;4502.954970450253;4548.431446069241;4633.72197668141;4669.252919532859;4683.484816670096;4744.525547445228;4990.301357809837;4995.800167993385;5006;5013.172826483736;5015.3730783652045;4721.669483136147;4738.241881299114;4748.0978614063215;4748.240281554935;4748.3827102477835;4808.134535783593;4849.224124140262;4871.244957031666;4894.779734911866;4966.130927087817;4984.975075124601;4996.475370086088;4999.525045120708;5001.199808030698;5008.924866127084;4877.414710234794;4887.36464259644;4889.535494128052;4890.584300334369;4904.485029071896;4731.858044258678;4816.34979277807;4831.806727730851;4844.32179494864;4894.363732714866;4846.878828029327;4853.611711063036;4857.344258525195;4857.587105096107;4859.635527335535;4944.406671199479;4951.554360107608;4954.1330267202375;4957.6281778866005;4959.504049595012;4635.443746750411;4670.766461676971;4721.480637129845;4727.054589082129;4757.833475828329 09:25:31 INFO - PROCESS | 1993 | |1;ext.html;553.1757681055228;557.2031994248223;557.3256359804639;564.9435056494349;567.835327754951;28338.433231335363;38714.806425967865;39762;39970.80014599923;43643;34312.48531272033;34758.47862282068;34778.82610586943;34780.478292825566;34795.30409391811;7565.243475652418;7687.461877668566;7703.922960770376;7719.4210434217375;7722.7297044603165;7595.392368610498;7672.578008209546;7676.961615191916;7686.730964416247;7689.115751688571;7678.232176782333;7721.884171737428;7724.227577242259;7725.922740772605;7727.459077864552;7441.888371674429;7486.737964171282;7493.775186744352;7495.587742674151;7501.024866767312;7603.125640551352;7669.156392796788;7672.693092276302;7673.46285759997;7676.424268179853;33129;33451;33457.832710836534;33460.83269583636;33475.33049339024;2547.847129172256;2627.356297707055;2646.4707058588165;2721.809473336867;3014.5779590857296;28279.575806362922;28472;28639.57040644392;28666.140015799352;29468.705312947026;3245.302260014085;3269.1173383186406;3275.983620081908;3350.430426827445;3357.5467311912826;260185;261099;261829;262055.99999999808;262289;49955.500444995814;50015.74992125089;50447.999999999265;50448.243276350884;50669.746651266505;32093.35813283768;32885.342293154004;32928.17679557982;43273.56726432758;43430;13080.215187088805;13324.067315287837;13324.200547967153;13330.866691332962;13334.533291334757;10021.298509104292;10167.135793457492;10168.135708464715;10200.795984080276;10207.030332118586;14357.353919073666;14376.424943002372;14380.26113210552;14469.49356772508;14621.853781462049;6417.965516897533;6557.458997135669;6654.23476300222;6754.3921047106;6826.556273842284;792.6076592086954;880.190224993008;933.5938866593053;952.6808519146025;972.601233494264;27507.724922750916;27744.16767496938;28228;28645.14064578066;28799.56800647992;887.1970866365949;893.6380765789736;922.4096578189964;948.83869742144;968.5641461342424;14653.34059967304;14793.150009217623;14875.553733388017;14991.550253492413;15112.319945602474;384626;385739;386127;387431;389993;49673;50090.74954625204;50096;50142.49857501451;50211.24683129756;1038.4703801061512;1133.3030186435449;1153.5501154549731;1196.0909708621336;1206.7465832175342;26511.60232596474;27964.580531292453;29234.269143271587;29428.264293392836;29848.403031939673;40631.593684062784;41318;41326;41388.586114138474;41419.58580414157;294085;295616;296025;296125;296758;21483.570328593654;22249.666255005865;25250.116245931305;25898;26684.19947401581;41261.79369103135;41279.7936010318;41282.1743565133;41299.58700412957;41359.793201033805;34500.48249276212;34583.481247781805;34739.4789078159;34752.304953900275;34878.82560587181;33949.981500555026;34232.82883585566;34263.31473370569;34561.827190863885;34632;34852.825735871156;35175.47236791399;35193.29613407769;35374;35944.820275898455;25532.95711850761;27886.44227115487;28077;28082;28619.427611447238;24329.14847980383;24434.51130977406;24578.754212457647;24842.50314993726;25034.248972530124 09:25:31 INFO - PROCESS | 1993 | |2;jquery.html;338.3571214692086;353.07670441794716;360.920597468557;366.9027707657471;381.7594915203421;12826.871731282685;16242.837571624295;16439.4246201383;17277.74083388747;17529.649407011853;13326.666833329171;15877.920610396961;16019.19904004798;16099.356025758985;16263;4421.24838777407;4843.564079232869;5039;5041.8487445376695;5057.367829021372;4915.655904086715;4983.401991760976;4987.900241995158;4988.451270360258;5002;4777.092352453023;4956.157453232941;4962.0820148272505;4970;5026.447090820006;4981.452040275567;5029.119904016819;5036.244563315532;5036.949630503703;5038.496150384969;4463.977680111579;4478.507364189936;4485.551444855521;4572.337011133401;4823.083614113307;11598.710032249177;11688.415579221006;11794.587189448413;11798.46906889192;11811.291322520676;1733.939312124082;1752.553098959757;1820.1172431370774;2064.5354795171115;2166.4150679316654;8505.659773609048;8531.74404767858;8762;8899.199072083544;9005.189532942066;1676.2791999440235;1676.6646670665793;1705.2496901363484;1730.9307627694877;1804.9458516244533;57759.422405775826;68741;69225.3077469224;69966;71272.2872771276;9334;9391.436513809193;9478.431294122374;9645.276604254708;9648.807023859483;12789.232646041268;12809.871901281054;12812.103152779402;12812.679683007813;12835.165714228546;6176.752929882844;6450.516211284079;6645.368689974444;6652.101966234496;6655.46756259498;5949.8215053548465;6018.187544681499;6154.3845615438995;6165.753369865245;6172.9074063889075;3916;3995.62041606047;4000.6199411055904;4110.630043296058;4124.154548317648;1747.4146161035942;1805.286911669883;1812.2750899639987;1824.1700026488181;1833.7066069428827;749.7338444852109;826.557791581513;869.2958703450225;946.6071580294101;964.9276304277207;15700.76448853268;15796.131212783408;16040.358385664442;16648.25082871274;17332.566685832957;819.6762278899802;819.9262066414051;828.8300898315831;842.5029232752582;900.8558630619069;9049.09509049103;9472.526373681427;9619.56711947964;9676.322657414054;9679.370840895319;389291;393079;397349.1325433717;398218;398442;75142;77640.01239888437;79218;79656.20343796603;83971.16028839756;816.0330008939426;827.8592639251342;831.8419500294924;832.5046597274612;834.2866848844272;14142.717145657234;14934.626634334229;15045.699086018438;15889.920550397637;16311.184440778152;39478.802605987934;39975.80012099921;40436.393454097626;40619.7969010153;40673.79663101665;51443.74278128735;51729.741351293;52204.47795521996;52903.47096528985;53336.46663533315;6657.800265992028;6822.11312529368;6938.965305173442;7282.745103921337;7388.926110738824;6347.428731414195;9825.950870245602;10088.899111008795;11446.942765286454;12211.26732396059;9883.85174222373;9887.159591434809;10144.74638134023;10281.691549253534;10474.790504189721;9736.75658108553;10019;10056.49717514136;10207.234457415872;10629.574817007233;10158.796824063329;10377.792444151226;10607.734806629587;10724.142068634646;10884.45577721095;7268.38218751412;7339.449541284532;7458.962705186439;7520.8871866920945;7596.08846938371;5001.999600079926;5539.8892022160135;8205.58972051407;9607.951960240154;10079.848802268118 09:25:31 INFO - PROCESS | 1993 | |3;mootools.html;1227.1164761371808;1371.6707990082384;1407.6762344660729;1466.5142583321274;1646.5801220688722;6550.148480697509;7391.854262589301;7705.383569314456;7722.768316950486;7906.327962123227;7741.458097933147;7790.220977902207;7810.9609451952665;7855.764327070197;7872.488288261259;2092.236333738182;2128.595566842295;2133.040131940625;2133.455968727972;2134.6371116910163;2056.085042156241;2093.434772611394;2140.3899888531773;2140.7752186020475;2141.511649403665;2128.1487405037956;2151.4836439254623;2156.579467003935;2163.675448682702;2166.4150679316576;2083.114676262582;2089.226986015184;2090.5296308330503;2091.7071609974614;2092.194505115534;2044.601302745965;2094.790520947908;2096.035823521182;2098.674705420662;2101.117530637136;7667.079950405987;7724.961375193088;7813.726519571787;7821.2960833524685;7822.921770782276;4333.978330108361;4464.6874718769695;4464.843730469451;4468.977655111704;4472.508024117345;2786.888524459019;2927.5169597016466;2970.5395663672166;2981.552767084933;2986.148947549938;886.3042511628341;896.6368620708623;903.9231665308457;904.8461761500561;909.7225346269386;10678.1991350649;10773.946130269376;10899.182561307935;10907.454627268606;10908.400037997906;3687.539057617798;3818.6563209311294;3953.7034722395933;3962;3982.2433737589763;8475.194856488624;8483.70307039263;8487.10885357041;8490.575471226537;8497.150284971452;4364.061726728769;4398.64810815134;4401.053773438662;4403.383526306319;4406.449193850769;3425.246445781924;3863.61363863617;4047.2714911315657;4067.003584121909;4083.571225021389;3300.966990330114;3310.155910242921;3429.53701250328;3434.4848272759286;3449.413599688059;1009.6516701738036;1027.0602398805158;1032.1226957086567;1041.7239431550645;1052.1950707708609;619.8884200843801;697.1216267502983;735.2610626320513;762.1768490030754;778.5756762564303;5133.871653208625;5243.947560524422;5268.841934741964;5389.272448219519;5436.483534064258;855.1320409784074;884.9380543362032;885.840548701227;886.3883920095113;942.7878727286305;3322.966770332314;3446.551948246712;3514.262004978932;3559.519464872261;3640;9493.38293010966;9949.253805964581;9953.303268771117;9954.850677239847;9959.651412200537;9869.06243906842;9870.457124858201;9871.309008369346;9884.901150988399;9885.901140988643;6949.235584085746;7264.963675181591;7279.2720727927845;7445;7449.0316258886;5280.471952804843;5311.362636483646;5345.705986170798;5539.723013849372;5645.040343141758;8803.383763136775;8994.010658827392;9014.729558113268;9035.644653301795;9052;8973.37186396946;9083.863742044006;9195.482745346942;9286.442813431217;9305.627774888928;5927.762889484372;6411.3909178627955;6582.967085164543;6766.4248538874745;6772.830679233059;7297.379722723628;7532.811679707832;7555.168931417649;7559.319661230295;7561.584112873625;6355.841103972438;6516;6567.31043240472;6577.375149360803;6611.305812889577;6339.936600634119;6367.87264254703;6376.202974628171;6386;6410.45511131559;5917.792877249276;6012.3386427493815;6035.36628653985;6035.879282414239;6039.275286965592;4536.070105628404;4577.29051996941;4597.609203217764;4629.1436084324505;4630.513796051366;4545;4552.430946131733;4649.5815376616265;4709.293605959134;4873.731944742932 09:25:31 INFO - PROCESS | 1993 | |4;prototype.html;290.0544225823815;293.6300261670295;294.4405629304322;295.47110671897303;302.0967307749828;4685.765711714414;4715.811367545302;4717.853376713596;4739.170645137105;4769.761511924407;4655.626590155904;4658.650601204906;4670.182718024349;4690.1557719610455;4747.952520474794;3512.8243587820634;3623.9128261521437;3628.7299445194235;3707.796071216082;3732.387328869802;3711.8122200895723;3720.367537518615;3730.832112554928;3756.2111956489034;3770;3681.7975011374365;3685.3919103347916;3697.796621185834;3706.7775933443945;3710.5547334319917;3497.772644778094;3594.8382322795537;3667.5965643779164;3704.703353826166;3722.0322716093874;3271.2312606537685;3282.671732826698;3292.1440425489304;3319.96680033199;3320.236345640516;4581.541845815425;4591.747453890035;4612.446506419251;4620.861374158781;4627.606653434462;4114.938275925833;4140.937885931713;4198.845317537671;4209.327077305902;4220.577942205755;4847.733374664392;4848.200046992242;4857.587105096071;4863.468007577637;4873;3557.0929412999776;3566.144125409909;3569.1434055826676;3574.946375804339;3576.8032758198287;5095.592352611782;5107.846764597068;5115.283860259567;5171.198464238049;5177.275181474596;3589.2642008388216;3656.3911878773256;3659.158393569494;3672.485851980725;3675.963240367562;3689.631036896289;3703.759255648374;3708.592054873962;3709.425039118887;3724.646158614927;4201.390798334224;4202.936955945664;4203.201391735622;4205.642520385802;4206.978965105216;4674.906501870011;4800.687955282895;4846.39420072491;4875.001873847629;4877.487863774323;1265.5823578219165;1288.375138058041;1288.3944546063335;1288.916220445668;1290.6321698315935;808.4179390838586;823.5247256577235;827.7872916177828;829.2371018662844;830.5639539241946;4248.893777655583;4336.111097225062;4382.846600368972;4391.912161756747;4396.3625274335045;4967.577755890789;4978.303037574742;4990.151674215392;5006.998600279959;5023.296738456619;4378.5183629800695;4386.934195987063;4388.692791504564;4390.156134423486;4392.165488557164;2483.341914392687;2627.3037645024083;2641.524525585413;2645.6957449893125;2653.8009649276382;4681.301771600398;4746.738929358849;4747.240441529339;4748.928766068511;4764.499727528629;5089.847304580869;5106.744662766847;5118;5154.278401023859;5163.586913046947;4579.7252164870215;4835.8065677372515;4840.789802549362;4840.83057093;4842;5071.264666623393;5072.239164125263;5092.046612964546;5094.490550944876;5106.821261255838;4813.663043586916;4964.180910149785;4991.575716064175;5002.249662550501;5022.146235139962;4746.92879606813;4781.976090119527;4783.42147091452;4795.424549054136;4822.107910036654;3563.5901871284095;3578.8905439313894;3579;3582.659647333499;3584.283143371388;5041.722705251246;5044.167712327425;5052.317937078447;5058.418281897481;5074.162763144188;4730.6583241295775;4916.237983112624;4930.211166213388;4937.160682683881;4940.567235501664;4932.901341973069;4935.284383764406;4941.78783790539;4941.827036053721;4944.975275123601;4876.440198654931;4883.145449546386;4887.413510378778;4891.90216195667;4903.362562866804;4670.859874203779;4761.3810204674555;4790.520947905182;4906.828261010847;4918.213085906238;4770.233264706531;4773.73744444045;4830.2513110467935;4839.806407743651;4841.298011788201 09:25:31 INFO - PROCESS | 1993 | |5;yui.html;569.7265312649928;579.9246098007255;603.5835273661173;613.2089604410311;642.954993150479;11895.881041189596;12832.230066196034;12841.42213600388;12854.164479308838;12864.09951303409;11358.545658173683;12042.157049006573;12148.69628259294;12169.14815962883;12172.573959911404;738.505201514985;769.8075481129717;772.4245437149332;773.0877564473918;773.4237992695449;565.9349174844883;566.4363957861933;567.027547755599;568.4116938968169;570.3896830391483;798.9760307190764;801.683335082642;805.9879101813477;807.3379828540599;807.5598798654718;800.7757827808223;804.1516200408566;804.1717031457608;804.4087595617217;805.138501803068;581.8429024163495;586.2905883880467;589.5519405252039;589.599072630611;589.6432658241745;11802.94098529502;11806.173567850254;11828.645140645709;11840.526378944833;11855.110866685032;12916.677083072998;12992.41534130957;13313.134646247963;13467.12463689857;13587.52443664467;8977.820443591158;9139.268858491305;9144.954275228582;9250.491222982733;9339.112784285482;3209.149575362531;3211.373782112489;3237.546743455918;3258.152880251128;3278.0329802708256;53498.73250633761;54373.45626543763;54634.180487292724;55791.44208557903;66011;8766.561671916379;9424.667905225395;9429.669961551313;9444.811103778024;9473.194778443909;20178.19287228524;20339.44059789531;20708.171673132907;20719;20818.167273308896;6776.2546119926765;6794.72821087151;6826;6837.247902730695;6983.092198014226;3614.6023937366867;3631.0377749896297;3705.925881482355;3723.94414083789;3819.560750513671;2743.931401714933;2837.5034368985757;2847.2597124747713;2849.430113977213;2854.1152242804797;499.9875003124951;509.22089203518675;510.30853193922445;519.6570263626064;531.43136843577;434.3289617540907;464.1436549566045;508.5524738230334;545.6535100211315;549.9587530935196;7283.417326613858;7530.811729706801;7832.138464768871;7885.408594355446;7992.3606111510935;452.2741000693905;489.4029284273181;522.8536009917229;530.7770736290768;553.6677993204125;3408.3353746019784;3608.6752192302292;3738.813059347076;3855.768653880776;3886.4947556817433;77326;102099.4895025535;104181;109264;109500.452497737;86370.56814716011;86581.56709216414;86875.56562217275;87011.56494217488;88377;79150.60424697955;79431;82127;82245;82390.58804706058;7201.279872012757;7378.594177320084;7424.740134095281;7559.395248380007;7631.7328893490685;45451.545484546055;46677;46995.53004469912;47621.52378476171;47706.52293477021;38056.42915356327;38869;38935.22129557336;44656.33015504705;51718.74140629273;10200.28597998133;10273.332233404804;10348.120409765253;10484.21368397358;10527.631532896312;11082.390468524309;11164.720881978015;11463.197576169589;11597.304161750322;11945.641630751092;11031.944840275748;11184.328940263611;11622.593209237637;11860.051195904134;11860.406979651156;10603.310784798963;10702;10889.891101088888;11077.390743509184;11375.54497820078;10466.581336746749;10617.628383006559;10978.945105274423;11146.442677865913;11237.213395062266;9144.085591440802;9455.905440945502;9522.381045232038;9650.469224192457;9968.950155249177;9805.849961809165;10065.43129713783;10065.870625895925;10100.545475453475;10149.949250253701 09:25:31 INFO - PROCESS | 1993 | __end_tp_report 09:25:31 INFO - PROCESS | 1993 | __start_cc_report 09:25:31 INFO - PROCESS | 1993 | _x_x_mozilla_cycle_collect,1055 09:25:31 INFO - PROCESS | 1993 | __end_cc_report 09:25:31 INFO - PROCESS | 1993 | __startTimestamp1472142331648__endTimestamp 09:25:31 INFO - PROCESS | 1993 | 09:25:31 INFO - PROCESS | 1993 | ------- Summary: start ------- 09:25:31 INFO - PROCESS | 1993 | Number of tests: 6 09:25:31 INFO - PROCESS | 1993 | 09:25:31 INFO - PROCESS | 1993 | [#0] dojo.html Cycles:165 Average:4622.63 Median:4731.86 stddev:484.58 (10.2%) stddev-sans-first:457.48 09:25:31 INFO - PROCESS | 1993 | Values: 2532.5 2591.8 2597.4 2608.2 2622.9 4557.4 4673.7 4688.4 4701.6 4746.7 4499.9 4652.2 4658.4 4661.3 4785.9 4337.0 4366.1 4370.5 4397.4 4407.0 4368.0 4400.9 4419.8 4426.0 4435.8 4200.6 4207.2 4212.9 4236.8 4364.0 4256.4 4305.2 4309.2 4318.7 4371.7 4325.0 4347.9 4358.4 4363.2 4370.7 4509.9 4546.3 4579.1 4622.3 4723.1 4982.0 4992.2 5000.1 5004.3 5008.5 4989.6 4991.3 5022.7 5025.4 5026.5 4502.4 4570.5 4607.3 4616.6 4682.8 5114.8 5127.1 5141.7 5144.4 5147.9 4927.3 4933.7 4934.1 4935.3 4945.1 4903.6 4972.2 5015.4 5016.3 5077.7 5043.3 5043.5 5044.5 5052.7 5067.4 5017.1 5035.0 5035.5 5036.2 5038.2 4061.7 4131.6 4152.4 4167.5 4177.7 3689.2 3692.4 3694.3 3703.8 3708.2 4395.1 4408.3 4410.6 4413.0 4417.7 4806.0 4938.4 4952.4 4967.3 5053.6 4382.3 4391.2 4403.1 4405.8 4408.4 4491.5 4493.0 4499.3 4499.4 4503.0 4548.4 4633.7 4669.3 4683.5 4744.5 4990.3 4995.8 5006.0 5013.2 5015.4 4721.7 4738.2 4748.1 4748.2 4748.4 4808.1 4849.2 4871.2 4894.8 4966.1 4985.0 4996.5 4999.5 5001.2 5008.9 4877.4 4887.4 4889.5 4890.6 4904.5 4731.9 4816.3 4831.8 4844.3 4894.4 4846.9 4853.6 4857.3 4857.6 4859.6 4944.4 4951.6 4954.1 4957.6 4959.5 4635.4 4670.8 4721.5 4727.1 4757.8 09:25:31 INFO - PROCESS | 1993 | 09:25:31 INFO - PROCESS | 1993 | [#1] ext.html Cycles:180 Average:45653.02 Median:34607.74 stddev:84087.59 (243.0%) stddev-sans-first:84255.29 09:25:31 INFO - PROCESS | 1993 | Values: 553.2 557.2 557.3 564.9 567.8 28338.4 38714.8 39762.0 39970.8 43643.0 34312.5 34758.5 34778.8 34780.5 34795.3 7565.2 7687.5 7703.9 7719.4 7722.7 7595.4 7672.6 7677.0 7686.7 7689.1 7678.2 7721.9 7724.2 7725.9 7727.5 7441.9 7486.7 7493.8 7495.6 7501.0 7603.1 7669.2 7672.7 7673.5 7676.4 33129.0 33451.0 33457.8 33460.8 33475.3 2547.8 2627.4 2646.5 2721.8 3014.6 28279.6 28472.0 28639.6 28666.1 29468.7 3245.3 3269.1 3276.0 3350.4 3357.5 260185.0 261099.0 261829.0 262056.0 262289.0 49955.5 50015.7 50448.0 50448.2 50669.7 32093.4 32885.3 32928.2 43273.6 43430.0 13080.2 13324.1 13324.2 13330.9 13334.5 10021.3 10167.1 10168.1 10200.8 10207.0 14357.4 14376.4 14380.3 14469.5 14621.9 6418.0 6557.5 6654.2 6754.4 6826.6 792.6 880.2 933.6 952.7 972.6 27507.7 27744.2 28228.0 28645.1 28799.6 887.2 893.6 922.4 948.8 968.6 14653.3 14793.2 14875.6 14991.6 15112.3 384626.0 385739.0 386127.0 387431.0 389993.0 49673.0 50090.7 50096.0 50142.5 50211.2 1038.5 1133.3 1153.6 1196.1 1206.7 26511.6 27964.6 29234.3 29428.3 29848.4 40631.6 41318.0 41326.0 41388.6 41419.6 294085.0 295616.0 296025.0 296125.0 296758.0 21483.6 22249.7 25250.1 25898.0 26684.2 41261.8 41279.8 41282.2 41299.6 41359.8 34500.5 34583.5 34739.5 34752.3 34878.8 33950.0 34232.8 34263.3 34561.8 34632.0 34852.8 35175.5 35193.3 35374.0 35944.8 25533.0 27886.4 28077.0 28082.0 28619.4 24329.1 24434.5 24578.8 24842.5 25034.2 09:25:31 INFO - PROCESS | 1993 | 09:25:31 INFO - PROCESS | 1993 | [#2] jquery.html Cycles:180 Average:23946.91 Median:4959.12 stddev:65412.56 (1319.0%) stddev-sans-first:65572.04 09:25:31 INFO - PROCESS | 1993 | Values: 338.4 353.1 360.9 366.9 381.8 12826.9 16242.8 16439.4 17277.7 17529.6 13326.7 15877.9 16019.2 16099.4 16263.0 4421.2 4843.6 5039.0 5041.8 5057.4 4915.7 4983.4 4987.9 4988.5 5002.0 4777.1 4956.2 4962.1 4970.0 5026.4 4981.5 5029.1 5036.2 5036.9 5038.5 4464.0 4478.5 4485.6 4572.3 4823.1 11598.7 11688.4 11794.6 11798.5 11811.3 1733.9 1752.6 1820.1 2064.5 2166.4 8505.7 8531.7 8762.0 8899.2 9005.2 1676.3 1676.7 1705.2 1730.9 1804.9 57759.4 68741.0 69225.3 69966.0 71272.3 9334.0 9391.4 9478.4 9645.3 9648.8 12789.2 12809.9 12812.1 12812.7 12835.2 6176.8 6450.5 6645.4 6652.1 6655.5 5949.8 6018.2 6154.4 6165.8 6172.9 3916.0 3995.6 4000.6 4110.6 4124.2 1747.4 1805.3 1812.3 1824.2 1833.7 749.7 826.6 869.3 946.6 964.9 15700.8 15796.1 16040.4 16648.3 17332.6 819.7 819.9 828.8 842.5 900.9 9049.1 9472.5 9619.6 9676.3 9679.4 389291.0 393079.0 397349.1 398218.0 398442.0 75142.0 77640.0 79218.0 79656.2 83971.2 816.0 827.9 831.8 832.5 834.3 14142.7 14934.6 15045.7 15889.9 16311.2 39478.8 39975.8 40436.4 40619.8 40673.8 51443.7 51729.7 52204.5 52903.5 53336.5 6657.8 6822.1 6939.0 7282.7 7388.9 6347.4 9826.0 10088.9 11446.9 12211.3 9883.9 9887.2 10144.7 10281.7 10474.8 9736.8 10019.0 10056.5 10207.2 10629.6 10158.8 10377.8 10607.7 10724.1 10884.5 7268.4 7339.4 7459.0 7520.9 7596.1 5002.0 5539.9 8205.6 9608.0 10079.8 09:25:31 INFO - PROCESS | 1993 | 09:25:31 INFO - PROCESS | 1993 | [#3] mootools.html Cycles:180 Average:5065.77 Median:5367.49 stddev:2908.64 (54.2%) stddev-sans-first:2902.50 09:25:31 INFO - PROCESS | 1993 | Values: 1227.1 1371.7 1407.7 1466.5 1646.6 6550.1 7391.9 7705.4 7722.8 7906.3 7741.5 7790.2 7811.0 7855.8 7872.5 2092.2 2128.6 2133.0 2133.5 2134.6 2056.1 2093.4 2140.4 2140.8 2141.5 2128.1 2151.5 2156.6 2163.7 2166.4 2083.1 2089.2 2090.5 2091.7 2092.2 2044.6 2094.8 2096.0 2098.7 2101.1 7667.1 7725.0 7813.7 7821.3 7822.9 4334.0 4464.7 4464.8 4469.0 4472.5 2786.9 2927.5 2970.5 2981.6 2986.1 886.3 896.6 903.9 904.8 909.7 10678.2 10773.9 10899.2 10907.5 10908.4 3687.5 3818.7 3953.7 3962.0 3982.2 8475.2 8483.7 8487.1 8490.6 8497.2 4364.1 4398.6 4401.1 4403.4 4406.4 3425.2 3863.6 4047.3 4067.0 4083.6 3301.0 3310.2 3429.5 3434.5 3449.4 1009.7 1027.1 1032.1 1041.7 1052.2 619.9 697.1 735.3 762.2 778.6 5133.9 5243.9 5268.8 5389.3 5436.5 855.1 884.9 885.8 886.4 942.8 3323.0 3446.6 3514.3 3559.5 3640.0 9493.4 9949.3 9953.3 9954.9 9959.7 9869.1 9870.5 9871.3 9884.9 9885.9 6949.2 7265.0 7279.3 7445.0 7449.0 5280.5 5311.4 5345.7 5539.7 5645.0 8803.4 8994.0 9014.7 9035.6 9052.0 8973.4 9083.9 9195.5 9286.4 9305.6 5927.8 6411.4 6583.0 6766.4 6772.8 7297.4 7532.8 7555.2 7559.3 7561.6 6355.8 6516.0 6567.3 6577.4 6611.3 6339.9 6367.9 6376.2 6386.0 6410.5 5917.8 6012.3 6035.4 6035.9 6039.3 4536.1 4577.3 4597.6 4629.1 4630.5 4545.0 4552.4 4649.6 4709.3 4873.7 09:25:31 INFO - PROCESS | 1993 | 09:25:31 INFO - PROCESS | 1993 | [#4] prototype.html Cycles:180 Average:4092.83 Median:4678.10 stddev:1179.31 (25.2%) stddev-sans-first:1147.56 09:25:31 INFO - PROCESS | 1993 | Values: 290.1 293.6 294.4 295.5 302.1 4685.8 4715.8 4717.9 4739.2 4769.8 4655.6 4658.7 4670.2 4690.2 4748.0 3512.8 3623.9 3628.7 3707.8 3732.4 3711.8 3720.4 3730.8 3756.2 3770.0 3681.8 3685.4 3697.8 3706.8 3710.6 3497.8 3594.8 3667.6 3704.7 3722.0 3271.2 3282.7 3292.1 3320.0 3320.2 4581.5 4591.7 4612.4 4620.9 4627.6 4114.9 4140.9 4198.8 4209.3 4220.6 4847.7 4848.2 4857.6 4863.5 4873.0 3557.1 3566.1 3569.1 3574.9 3576.8 5095.6 5107.8 5115.3 5171.2 5177.3 3589.3 3656.4 3659.2 3672.5 3676.0 3689.6 3703.8 3708.6 3709.4 3724.6 4201.4 4202.9 4203.2 4205.6 4207.0 4674.9 4800.7 4846.4 4875.0 4877.5 1265.6 1288.4 1288.4 1288.9 1290.6 808.4 823.5 827.8 829.2 830.6 4248.9 4336.1 4382.8 4391.9 4396.4 4967.6 4978.3 4990.2 5007.0 5023.3 4378.5 4386.9 4388.7 4390.2 4392.2 2483.3 2627.3 2641.5 2645.7 2653.8 4681.3 4746.7 4747.2 4748.9 4764.5 5089.8 5106.7 5118.0 5154.3 5163.6 4579.7 4835.8 4840.8 4840.8 4842.0 5071.3 5072.2 5092.0 5094.5 5106.8 4813.7 4964.2 4991.6 5002.2 5022.1 4746.9 4782.0 4783.4 4795.4 4822.1 3563.6 3578.9 3579.0 3582.7 3584.3 5041.7 5044.2 5052.3 5058.4 5074.2 4730.7 4916.2 4930.2 4937.2 4940.6 4932.9 4935.3 4941.8 4941.8 4945.0 4876.4 4883.1 4887.4 4891.9 4903.4 4670.9 4761.4 4790.5 4906.8 4918.2 4770.2 4773.7 4830.3 4839.8 4841.3 09:25:31 INFO - PROCESS | 1993 | 09:25:31 INFO - PROCESS | 1993 | [#5] yui.html Cycles:180 Average:17204.85 Median:494.70 stddev:25479.12 (5150.5%) stddev-sans-first:25519.98 09:25:31 INFO - PROCESS | 1993 | Values: 569.7 579.9 603.6 613.2 643.0 11895.9 12832.2 12841.4 12854.2 12864.1 11358.5 12042.2 12148.7 12169.1 12172.6 738.5 769.8 772.4 773.1 773.4 565.9 566.4 567.0 568.4 570.4 799.0 801.7 806.0 807.3 807.6 800.8 804.2 804.2 804.4 805.1 581.8 586.3 589.6 589.6 589.6 11802.9 11806.2 11828.6 11840.5 11855.1 12916.7 12992.4 13313.1 13467.1 13587.5 8977.8 9139.3 9145.0 9250.5 9339.1 3209.1 3211.4 3237.5 3258.2 3278.0 53498.7 54373.5 54634.2 55791.4 66011.0 8766.6 9424.7 9429.7 9444.8 9473.2 20178.2 20339.4 20708.2 20719.0 20818.2 6776.3 6794.7 6826.0 6837.2 6983.1 3614.6 3631.0 3705.9 3723.9 3819.6 2743.9 2837.5 2847.3 2849.4 2854.1 500.0 509.2 510.3 519.7 531.4 434.3 464.1 508.6 545.7 550.0 7283.4 7530.8 7832.1 7885.4 7992.4 452.3 489.4 522.9 530.8 553.7 3408.3 3608.7 3738.8 3855.8 3886.5 77326.0 102099.5 104181.0 109264.0 109500.5 86370.6 86581.6 86875.6 87011.6 88377.0 79150.6 79431.0 82127.0 82245.0 82390.6 7201.3 7378.6 7424.7 7559.4 7631.7 45451.5 46677.0 46995.5 47621.5 47706.5 38056.4 38869.0 38935.2 44656.3 51718.7 10200.3 10273.3 10348.1 10484.2 10527.6 11082.4 11164.7 11463.2 11597.3 11945.6 11031.9 11184.3 11622.6 11860.1 11860.4 10603.3 10702.0 10889.9 11077.4 11375.5 10466.6 10617.6 10978.9 11146.4 11237.2 9144.1 9455.9 9522.4 9650.5 9969.0 9805.8 10065.4 10065.9 10100.5 10149.9 09:25:31 INFO - PROCESS | 1993 | -------- Summary: end -------- 09:25:31 INFO - PROCESS | 1993 | 09:25:32 INFO - TEST-INFO | 1993: exit 0 09:25:32 INFO - TEST-OK | dromaeo_css | took 1122207ms 09:25:32 INFO - TEST-START | kraken 09:25:32 INFO - operating with platform_type : linux_ 09:25:32 INFO - Initialising browser for kraken test... 09:25:32 INFO - TEST-INFO | started process 2308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPIOnmy/profile http://localhost:54138/getInfo.html) 09:25:37 INFO - PROCESS | 2308 | __metrics Screen width/height:1600/1200 09:25:37 INFO - PROCESS | 2308 | colorDepth:24 09:25:37 INFO - PROCESS | 2308 | Browser inner width/height: 1024/697 09:25:37 INFO - PROCESS | 2308 | __metrics 09:25:38 INFO - PROCESS | 2308 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:25:38 INFO - PROCESS | 2308 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:25:39 INFO - TEST-INFO | 2308: exit 0 09:25:39 INFO - Browser initialized. 09:25:39 INFO - Running cycle 1/1 for kraken test... 09:25:39 INFO - TEST-INFO | started process 2462 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPIOnmy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:52 INFO - PROCESS | 2462 | RSS: Main: 177700864 09:25:52 INFO - PROCESS | 2462 | 09:25:54 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54138/tests/kraken/driver.html?testName=audio-beat-detection) 09:25:55 INFO - PROCESS | 2462 | RSS: Main: 246714368 09:25:55 INFO - PROCESS | 2462 | 09:25:58 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54138/tests/kraken/driver.html?testName=audio-dft) 09:25:59 INFO - PROCESS | 2462 | RSS: Main: 228954112 09:25:59 INFO - PROCESS | 2462 | 09:26:02 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54138/tests/kraken/driver.html?testName=audio-fft) 09:26:03 INFO - PROCESS | 2462 | RSS: Main: 305442816 09:26:03 INFO - PROCESS | 2462 | 09:26:05 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54138/tests/kraken/driver.html?testName=audio-oscillator) 09:26:05 INFO - PROCESS | 2462 | RSS: Main: 213147648 09:26:05 INFO - PROCESS | 2462 | 09:26:08 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54138/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:26:08 INFO - PROCESS | 2462 | RSS: Main: 216682496 09:26:08 INFO - PROCESS | 2462 | 09:26:12 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54138/tests/kraken/driver.html?testName=imaging-darkroom) 09:26:12 INFO - PROCESS | 2462 | RSS: Main: 252698624 09:26:12 INFO - PROCESS | 2462 | 09:26:16 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54138/tests/kraken/driver.html?testName=imaging-desaturate) 09:26:17 INFO - PROCESS | 2462 | RSS: Main: 247939072 09:26:17 INFO - PROCESS | 2462 | 09:26:19 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54138/tests/kraken/driver.html?testName=json-parse-financial) 09:26:20 INFO - PROCESS | 2462 | RSS: Main: 251650048 09:26:20 INFO - PROCESS | 2462 | 09:26:22 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54138/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:26:22 INFO - PROCESS | 2462 | RSS: Main: 213995520 09:26:22 INFO - PROCESS | 2462 | 09:26:24 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54138/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:26:25 INFO - PROCESS | 2462 | RSS: Main: 221392896 09:26:25 INFO - PROCESS | 2462 | 09:26:27 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54138/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:26:28 INFO - PROCESS | 2462 | RSS: Main: 225697792 09:26:28 INFO - PROCESS | 2462 | 09:26:31 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54138/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:26:31 INFO - PROCESS | 2462 | RSS: Main: 221450240 09:26:31 INFO - PROCESS | 2462 | 09:26:34 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54138/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:26:35 INFO - PROCESS | 2462 | RSS: Main: 223825920 09:26:35 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | Cycle 1(1): loaded http://localhost:54138/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54138/tests/kraken/driver.html?testName=ai-astar) 09:26:37 INFO - PROCESS | 2462 | RSS: Main: 275759104 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | __start_tp_report 09:26:37 INFO - PROCESS | 2462 | _x_x_mozilla_page_load 09:26:37 INFO - PROCESS | 2462 | _x_x_mozilla_page_load_details 09:26:37 INFO - PROCESS | 2462 | |i|pagename|runs| 09:26:37 INFO - PROCESS | 2462 | |0;ai-astar;101;114;111;115;113;113;121;114;114;115 09:26:37 INFO - PROCESS | 2462 | |1;audio-beat-detection;158;122;152;191;126;149;150;125;188;125 09:26:37 INFO - PROCESS | 2462 | |2;audio-dft;162;161;161;160;162;162;163;161;163;161 09:26:37 INFO - PROCESS | 2462 | |3;audio-fft;75;75;77;178;77;78;76;75;162;77 09:26:37 INFO - PROCESS | 2462 | |4;audio-oscillator;87;86;148;86;87;86;140;86;87;140 09:26:37 INFO - PROCESS | 2462 | |5;imaging-gaussian-blur;117;117;117;117;117;117;123;117;118;117 09:26:37 INFO - PROCESS | 2462 | |6;imaging-darkroom;147;146;149;147;146;146;147;147;147;147 09:26:37 INFO - PROCESS | 2462 | |7;imaging-desaturate;95;94;94;95;94;95;96;96;95;95 09:26:37 INFO - PROCESS | 2462 | |8;json-parse-financial;66;65;65;66;65;66;67;66;65;66 09:26:37 INFO - PROCESS | 2462 | |9;json-stringify-tinderbox;66;66;67;66;67;66;66;66;67;66 09:26:37 INFO - PROCESS | 2462 | |10;stanford-crypto-aes;84;81;82;82;85;83;81;82;83;82 09:26:37 INFO - PROCESS | 2462 | |11;stanford-crypto-ccm;131;132;130;132;133;132;130;126;164;134 09:26:37 INFO - PROCESS | 2462 | |12;stanford-crypto-pbkdf2;173;173;171;174;170;171;174;177;170;172 09:26:37 INFO - PROCESS | 2462 | |13;stanford-crypto-sha256-iterative;69;73;70;72;68;70;69;67;67;69 09:26:37 INFO - PROCESS | 2462 | __end_tp_report 09:26:37 INFO - PROCESS | 2462 | __start_cc_report 09:26:37 INFO - PROCESS | 2462 | _x_x_mozilla_cycle_collect,2029 09:26:37 INFO - PROCESS | 2462 | __end_cc_report 09:26:37 INFO - PROCESS | 2462 | __startTimestamp1472142397443__endTimestamp 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | ------- Summary: start ------- 09:26:37 INFO - PROCESS | 2462 | Number of tests: 14 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:4.98 (4.4%) stddev-sans-first:2.74 09:26:37 INFO - PROCESS | 2462 | Values: 101.0 114.0 111.0 115.0 113.0 113.0 121.0 114.0 114.0 115.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#1] audio-beat-detection Cycles:10 Average:148.60 Median:151.00 stddev:25.35 (16.8%) stddev-sans-first:26.66 09:26:37 INFO - PROCESS | 2462 | Values: 158.0 122.0 152.0 191.0 126.0 149.0 150.0 125.0 188.0 125.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 09:26:37 INFO - PROCESS | 2462 | Values: 162.0 161.0 161.0 160.0 162.0 162.0 163.0 161.0 163.0 161.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#3] audio-fft Cycles:10 Average:95.00 Median:76.50 stddev:39.72 (51.9%) stddev-sans-first:41.47 09:26:37 INFO - PROCESS | 2462 | Values: 75.0 75.0 77.0 178.0 77.0 78.0 76.0 75.0 162.0 77.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#4] audio-oscillator Cycles:10 Average:103.30 Median:86.00 stddev:27.26 (31.7%) stddev-sans-first:28.26 09:26:37 INFO - PROCESS | 2462 | Values: 87.0 86.0 148.0 86.0 87.0 86.0 140.0 86.0 87.0 140.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:117.00 stddev:1.89 (1.6%) stddev-sans-first:1.99 09:26:37 INFO - PROCESS | 2462 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 123.0 117.0 118.0 117.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 09:26:37 INFO - PROCESS | 2462 | Values: 147.0 146.0 149.0 147.0 146.0 146.0 147.0 147.0 147.0 147.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 09:26:37 INFO - PROCESS | 2462 | Values: 95.0 94.0 94.0 95.0 94.0 95.0 96.0 96.0 95.0 95.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 09:26:37 INFO - PROCESS | 2462 | Values: 66.0 65.0 65.0 66.0 65.0 66.0 67.0 66.0 65.0 66.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 09:26:37 INFO - PROCESS | 2462 | Values: 66.0 66.0 67.0 66.0 67.0 66.0 66.0 66.0 67.0 66.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:82.50 stddev:1.27 (1.5%) stddev-sans-first:1.22 09:26:37 INFO - PROCESS | 2462 | Values: 84.0 81.0 82.0 82.0 85.0 83.0 81.0 82.0 83.0 82.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#11] stanford-crypto-ccm Cycles:10 Average:134.40 Median:132.00 stddev:10.63 (8.1%) stddev-sans-first:11.20 09:26:37 INFO - PROCESS | 2462 | Values: 131.0 132.0 130.0 132.0 133.0 132.0 130.0 126.0 164.0 134.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.50 Median:173.00 stddev:2.17 (1.3%) stddev-sans-first:2.30 09:26:37 INFO - PROCESS | 2462 | Values: 173.0 173.0 171.0 174.0 170.0 171.0 174.0 177.0 170.0 172.0 09:26:37 INFO - PROCESS | 2462 | 09:26:37 INFO - PROCESS | 2462 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.50 stddev:1.96 (2.8%) stddev-sans-first:2.07 09:26:37 INFO - PROCESS | 2462 | Values: 69.0 73.0 70.0 72.0 68.0 70.0 69.0 67.0 67.0 69.0 09:26:37 INFO - PROCESS | 2462 | -------- Summary: end -------- 09:26:37 INFO - PROCESS | 2462 | 09:26:38 INFO - TEST-INFO | 2462: exit 0 09:26:38 INFO - TEST-OK | kraken | took 66035ms 09:26:38 INFO - SUITE-END | took 1188s 09:26:39 INFO - Completed test suite (00:19:48) 09:26:39 INFO - javascript benchmark 09:26:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2532.4555220627562, 2591.766740993311, 2597.3896134408424, 2608.2044976282236, 2622.9213123606287, 4557.430321209848, 4673.742944138071, 4688.437387513494, 4701.647376446772, 4746.667733258673, 4499.932501012488, 4652.209124448844, 4658.371119898813, 4661.277501987197, 4785.904281914369, 4336.97831510842, 4366.08312254427, 4370.519242883281, 4397.406350142738, 4407.0, 4367.978160109211, 4400.867973960786, 4419.779011049451, 4426.026274219666, 4435.800388982488, 4200.621944025022, 4207.242696314662, 4212.894677633053, 4236.809343579546, 4364.0, 4256.425382573375, 4305.203537345601, 4309.159713855799, 4318.676099292534, 4371.693981421302, 4325.026868954492, 4347.934780978288, 4358.411614432042, 4363.192809330284, 4370.715903466296, 4509.887252818673, 4546.29532422475, 4579.061292435043, 4622.306654001893, 4723.126221649006, 4982.0, 4992.226204938241, 5000.1249781288125, 5004.274380214885, 5008.474110218411, 4989.625778066623, 4991.276264941599, 5022.748862556894, 5025.447200807908, 5026.472220416877, 4502.369668246448, 4570.451545814457, 4607.285870690049, 4616.561426664462, 4682.81268749253, 5114.846554603368, 5127.07712611726, 5141.742912854343, 5144.38267407906, 5147.922781158286, 4927.285543596158, 4933.654644174873, 4934.087193869145, 4935.284383764406, 4945.134601444733, 4903.607711383081, 4972.179590367621, 5015.3730783652045, 5016.347874776256, 5077.695338279715, 5043.3191519145175, 5043.5112749652835, 5044.54599086084, 5052.747362631853, 5067.417247016567, 5017.096922553902, 5035.043341765127, 5035.496450354935, 5036.169032109661, 5038.193888977746, 4061.6547593454297, 4131.607497287753, 4152.418661387408, 4167.4790651168605, 4177.707560470738, 3689.24370504046, 3692.390755525362, 3694.3350196964266, 3703.759255648374, 3708.2212735325343, 4395.055063161436, 4408.25059739845, 4410.625096866738, 4413.007073408522, 4417.712848664826, 4806.038792241566, 4938.358013458227, 4952.405711314594, 4967.3294105296045, 5053.5957123430035, 4382.276924307517, 4391.187630288407, 4403.119376124724, 4405.757683327448, 4408.382826404306, 4491.505934347219, 4493.0, 4499.280115181556, 4499.392582001454, 4502.954970450253, 4548.431446069241, 4633.72197668141, 4669.252919532859, 4683.484816670096, 4744.525547445228, 4990.301357809837, 4995.800167993385, 5006.0, 5013.172826483736, 5015.3730783652045, 4721.669483136147, 4738.241881299114, 4748.0978614063215, 4748.240281554935, 4748.3827102477835, 4808.134535783593, 4849.224124140262, 4871.244957031666, 4894.779734911866, 4966.130927087817, 4984.975075124601, 4996.475370086088, 4999.525045120708, 5001.199808030698, 5008.924866127084, 4877.414710234794, 4887.36464259644, 4889.535494128052, 4890.584300334369, 4904.485029071896, 4731.858044258678, 4816.34979277807, 4831.806727730851, 4844.32179494864, 4894.363732714866, 4846.878828029327, 4853.611711063036, 4857.344258525195, 4857.587105096107, 4859.635527335535, 4944.406671199479, 4951.554360107608, 4954.1330267202375, 4957.6281778866005, 4959.504049595012, 4635.443746750411, 4670.766461676971, 4721.480637129845, 4727.054589082129, 4757.833475828329], "value": 4591.410168722168, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.1757681055228, 557.2031994248223, 557.3256359804639, 564.9435056494349, 567.835327754951, 28338.433231335363, 38714.806425967865, 39762.0, 39970.80014599923, 43643.0, 34312.48531272033, 34758.47862282068, 34778.82610586943, 34780.478292825566, 34795.30409391811, 7565.243475652418, 7687.461877668566, 7703.922960770376, 7719.4210434217375, 7722.7297044603165, 7595.392368610498, 7672.578008209546, 7676.961615191916, 7686.730964416247, 7689.115751688571, 7678.232176782333, 7721.884171737428, 7724.227577242259, 7725.922740772605, 7727.459077864552, 7441.888371674429, 7486.737964171282, 7493.775186744352, 7495.587742674151, 7501.024866767312, 7603.125640551352, 7669.156392796788, 7672.693092276302, 7673.46285759997, 7676.424268179853, 33129.0, 33451.0, 33457.832710836534, 33460.83269583636, 33475.33049339024, 2547.847129172256, 2627.356297707055, 2646.4707058588165, 2721.809473336867, 3014.5779590857296, 28279.575806362922, 28472.0, 28639.57040644392, 28666.140015799352, 29468.705312947026, 3245.302260014085, 3269.1173383186406, 3275.983620081908, 3350.430426827445, 3357.5467311912826, 260185.0, 261099.0, 261829.0, 262055.99999999808, 262289.0, 49955.500444995814, 50015.74992125089, 50447.999999999265, 50448.243276350884, 50669.746651266505, 32093.35813283768, 32885.342293154004, 32928.17679557982, 43273.56726432758, 43430.0, 13080.215187088805, 13324.067315287837, 13324.200547967153, 13330.866691332962, 13334.533291334757, 10021.298509104292, 10167.135793457492, 10168.135708464715, 10200.795984080276, 10207.030332118586, 14357.353919073666, 14376.424943002372, 14380.26113210552, 14469.49356772508, 14621.853781462049, 6417.965516897533, 6557.458997135669, 6654.23476300222, 6754.3921047106, 6826.556273842284, 792.6076592086954, 880.190224993008, 933.5938866593053, 952.6808519146025, 972.601233494264, 27507.724922750916, 27744.16767496938, 28228.0, 28645.14064578066, 28799.56800647992, 887.1970866365949, 893.6380765789736, 922.4096578189964, 948.83869742144, 968.5641461342424, 14653.34059967304, 14793.150009217623, 14875.553733388017, 14991.550253492413, 15112.319945602474, 384626.0, 385739.0, 386127.0, 387431.0, 389993.0, 49673.0, 50090.74954625204, 50096.0, 50142.49857501451, 50211.24683129756, 1038.4703801061512, 1133.3030186435449, 1153.5501154549731, 1196.0909708621336, 1206.7465832175342, 26511.60232596474, 27964.580531292453, 29234.269143271587, 29428.264293392836, 29848.403031939673, 40631.593684062784, 41318.0, 41326.0, 41388.586114138474, 41419.58580414157, 294085.0, 295616.0, 296025.0, 296125.0, 296758.0, 21483.570328593654, 22249.666255005865, 25250.116245931305, 25898.0, 26684.19947401581, 41261.79369103135, 41279.7936010318, 41282.1743565133, 41299.58700412957, 41359.793201033805, 34500.48249276212, 34583.481247781805, 34739.4789078159, 34752.304953900275, 34878.82560587181, 33949.981500555026, 34232.82883585566, 34263.31473370569, 34561.827190863885, 34632.0, 34852.825735871156, 35175.47236791399, 35193.29613407769, 35374.0, 35944.820275898455, 25532.95711850761, 27886.44227115487, 28077.0, 28082.0, 28619.427611447238, 24329.14847980383, 24434.51130977406, 24578.754212457647, 24842.50314993726, 25034.248972530124], "value": 16586.141397870575, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.3571214692086, 353.07670441794716, 360.920597468557, 366.9027707657471, 381.7594915203421, 12826.871731282685, 16242.837571624295, 16439.4246201383, 17277.74083388747, 17529.649407011853, 13326.666833329171, 15877.920610396961, 16019.19904004798, 16099.356025758985, 16263.0, 4421.24838777407, 4843.564079232869, 5039.0, 5041.8487445376695, 5057.367829021372, 4915.655904086715, 4983.401991760976, 4987.900241995158, 4988.451270360258, 5002.0, 4777.092352453023, 4956.157453232941, 4962.0820148272505, 4970.0, 5026.447090820006, 4981.452040275567, 5029.119904016819, 5036.244563315532, 5036.949630503703, 5038.496150384969, 4463.977680111579, 4478.507364189936, 4485.551444855521, 4572.337011133401, 4823.083614113307, 11598.710032249177, 11688.415579221006, 11794.587189448413, 11798.46906889192, 11811.291322520676, 1733.939312124082, 1752.553098959757, 1820.1172431370774, 2064.5354795171115, 2166.4150679316654, 8505.659773609048, 8531.74404767858, 8762.0, 8899.199072083544, 9005.189532942066, 1676.2791999440235, 1676.6646670665793, 1705.2496901363484, 1730.9307627694877, 1804.9458516244533, 57759.422405775826, 68741.0, 69225.3077469224, 69966.0, 71272.2872771276, 9334.0, 9391.436513809193, 9478.431294122374, 9645.276604254708, 9648.807023859483, 12789.232646041268, 12809.871901281054, 12812.103152779402, 12812.679683007813, 12835.165714228546, 6176.752929882844, 6450.516211284079, 6645.368689974444, 6652.101966234496, 6655.46756259498, 5949.8215053548465, 6018.187544681499, 6154.3845615438995, 6165.753369865245, 6172.9074063889075, 3916.0, 3995.62041606047, 4000.6199411055904, 4110.630043296058, 4124.154548317648, 1747.4146161035942, 1805.286911669883, 1812.2750899639987, 1824.1700026488181, 1833.7066069428827, 749.7338444852109, 826.557791581513, 869.2958703450225, 946.6071580294101, 964.9276304277207, 15700.76448853268, 15796.131212783408, 16040.358385664442, 16648.25082871274, 17332.566685832957, 819.6762278899802, 819.9262066414051, 828.8300898315831, 842.5029232752582, 900.8558630619069, 9049.09509049103, 9472.526373681427, 9619.56711947964, 9676.322657414054, 9679.370840895319, 389291.0, 393079.0, 397349.1325433717, 398218.0, 398442.0, 75142.0, 77640.01239888437, 79218.0, 79656.20343796603, 83971.16028839756, 816.0330008939426, 827.8592639251342, 831.8419500294924, 832.5046597274612, 834.2866848844272, 14142.717145657234, 14934.626634334229, 15045.699086018438, 15889.920550397637, 16311.184440778152, 39478.802605987934, 39975.80012099921, 40436.393454097626, 40619.7969010153, 40673.79663101665, 51443.74278128735, 51729.741351293, 52204.47795521996, 52903.47096528985, 53336.46663533315, 6657.800265992028, 6822.11312529368, 6938.965305173442, 7282.745103921337, 7388.926110738824, 6347.428731414195, 9825.950870245602, 10088.899111008795, 11446.942765286454, 12211.26732396059, 9883.85174222373, 9887.159591434809, 10144.74638134023, 10281.691549253534, 10474.790504189721, 9736.75658108553, 10019.0, 10056.49717514136, 10207.234457415872, 10629.574817007233, 10158.796824063329, 10377.792444151226, 10607.734806629587, 10724.142068634646, 10884.45577721095, 7268.38218751412, 7339.449541284532, 7458.962705186439, 7520.8871866920945, 7596.08846938371, 5001.999600079926, 5539.8892022160135, 8205.58972051407, 9607.951960240154, 10079.848802268118], "value": 7698.267062494683, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1227.1164761371808, 1371.6707990082384, 1407.6762344660729, 1466.5142583321274, 1646.5801220688722, 6550.148480697509, 7391.854262589301, 7705.383569314456, 7722.768316950486, 7906.327962123227, 7741.458097933147, 7790.220977902207, 7810.9609451952665, 7855.764327070197, 7872.488288261259, 2092.236333738182, 2128.595566842295, 2133.040131940625, 2133.455968727972, 2134.6371116910163, 2056.085042156241, 2093.434772611394, 2140.3899888531773, 2140.7752186020475, 2141.511649403665, 2128.1487405037956, 2151.4836439254623, 2156.579467003935, 2163.675448682702, 2166.4150679316576, 2083.114676262582, 2089.226986015184, 2090.5296308330503, 2091.7071609974614, 2092.194505115534, 2044.601302745965, 2094.790520947908, 2096.035823521182, 2098.674705420662, 2101.117530637136, 7667.079950405987, 7724.961375193088, 7813.726519571787, 7821.2960833524685, 7822.921770782276, 4333.978330108361, 4464.6874718769695, 4464.843730469451, 4468.977655111704, 4472.508024117345, 2786.888524459019, 2927.5169597016466, 2970.5395663672166, 2981.552767084933, 2986.148947549938, 886.3042511628341, 896.6368620708623, 903.9231665308457, 904.8461761500561, 909.7225346269386, 10678.1991350649, 10773.946130269376, 10899.182561307935, 10907.454627268606, 10908.400037997906, 3687.539057617798, 3818.6563209311294, 3953.7034722395933, 3962.0, 3982.2433737589763, 8475.194856488624, 8483.70307039263, 8487.10885357041, 8490.575471226537, 8497.150284971452, 4364.061726728769, 4398.64810815134, 4401.053773438662, 4403.383526306319, 4406.449193850769, 3425.246445781924, 3863.61363863617, 4047.2714911315657, 4067.003584121909, 4083.571225021389, 3300.966990330114, 3310.155910242921, 3429.53701250328, 3434.4848272759286, 3449.413599688059, 1009.6516701738036, 1027.0602398805158, 1032.1226957086567, 1041.7239431550645, 1052.1950707708609, 619.8884200843801, 697.1216267502983, 735.2610626320513, 762.1768490030754, 778.5756762564303, 5133.871653208625, 5243.947560524422, 5268.841934741964, 5389.272448219519, 5436.483534064258, 855.1320409784074, 884.9380543362032, 885.840548701227, 886.3883920095113, 942.7878727286305, 3322.966770332314, 3446.551948246712, 3514.262004978932, 3559.519464872261, 3640.0, 9493.38293010966, 9949.253805964581, 9953.303268771117, 9954.850677239847, 9959.651412200537, 9869.06243906842, 9870.457124858201, 9871.309008369346, 9884.901150988399, 9885.901140988643, 6949.235584085746, 7264.963675181591, 7279.2720727927845, 7445.0, 7449.0316258886, 5280.471952804843, 5311.362636483646, 5345.705986170798, 5539.723013849372, 5645.040343141758, 8803.383763136775, 8994.010658827392, 9014.729558113268, 9035.644653301795, 9052.0, 8973.37186396946, 9083.863742044006, 9195.482745346942, 9286.442813431217, 9305.627774888928, 5927.762889484372, 6411.3909178627955, 6582.967085164543, 6766.4248538874745, 6772.830679233059, 7297.379722723628, 7532.811679707832, 7555.168931417649, 7559.319661230295, 7561.584112873625, 6355.841103972438, 6516.0, 6567.31043240472, 6577.375149360803, 6611.305812889577, 6339.936600634119, 6367.87264254703, 6376.202974628171, 6386.0, 6410.45511131559, 5917.792877249276, 6012.3386427493815, 6035.36628653985, 6035.879282414239, 6039.275286965592, 4536.070105628404, 4577.29051996941, 4597.609203217764, 4629.1436084324505, 4630.513796051366, 4545.0, 4552.430946131733, 4649.5815376616265, 4709.293605959134, 4873.731944742932], "value": 4038.8100602025665, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.0544225823815, 293.6300261670295, 294.4405629304322, 295.47110671897303, 302.0967307749828, 4685.765711714414, 4715.811367545302, 4717.853376713596, 4739.170645137105, 4769.761511924407, 4655.626590155904, 4658.650601204906, 4670.182718024349, 4690.1557719610455, 4747.952520474794, 3512.8243587820634, 3623.9128261521437, 3628.7299445194235, 3707.796071216082, 3732.387328869802, 3711.8122200895723, 3720.367537518615, 3730.832112554928, 3756.2111956489034, 3770.0, 3681.7975011374365, 3685.3919103347916, 3697.796621185834, 3706.7775933443945, 3710.5547334319917, 3497.772644778094, 3594.8382322795537, 3667.5965643779164, 3704.703353826166, 3722.0322716093874, 3271.2312606537685, 3282.671732826698, 3292.1440425489304, 3319.96680033199, 3320.236345640516, 4581.541845815425, 4591.747453890035, 4612.446506419251, 4620.861374158781, 4627.606653434462, 4114.938275925833, 4140.937885931713, 4198.845317537671, 4209.327077305902, 4220.577942205755, 4847.733374664392, 4848.200046992242, 4857.587105096071, 4863.468007577637, 4873.0, 3557.0929412999776, 3566.144125409909, 3569.1434055826676, 3574.946375804339, 3576.8032758198287, 5095.592352611782, 5107.846764597068, 5115.283860259567, 5171.198464238049, 5177.275181474596, 3589.2642008388216, 3656.3911878773256, 3659.158393569494, 3672.485851980725, 3675.963240367562, 3689.631036896289, 3703.759255648374, 3708.592054873962, 3709.425039118887, 3724.646158614927, 4201.390798334224, 4202.936955945664, 4203.201391735622, 4205.642520385802, 4206.978965105216, 4674.906501870011, 4800.687955282895, 4846.39420072491, 4875.001873847629, 4877.487863774323, 1265.5823578219165, 1288.375138058041, 1288.3944546063335, 1288.916220445668, 1290.6321698315935, 808.4179390838586, 823.5247256577235, 827.7872916177828, 829.2371018662844, 830.5639539241946, 4248.893777655583, 4336.111097225062, 4382.846600368972, 4391.912161756747, 4396.3625274335045, 4967.577755890789, 4978.303037574742, 4990.151674215392, 5006.998600279959, 5023.296738456619, 4378.5183629800695, 4386.934195987063, 4388.692791504564, 4390.156134423486, 4392.165488557164, 2483.341914392687, 2627.3037645024083, 2641.524525585413, 2645.6957449893125, 2653.8009649276382, 4681.301771600398, 4746.738929358849, 4747.240441529339, 4748.928766068511, 4764.499727528629, 5089.847304580869, 5106.744662766847, 5118.0, 5154.278401023859, 5163.586913046947, 4579.7252164870215, 4835.8065677372515, 4840.789802549362, 4840.83057093, 4842.0, 5071.264666623393, 5072.239164125263, 5092.046612964546, 5094.490550944876, 5106.821261255838, 4813.663043586916, 4964.180910149785, 4991.575716064175, 5002.249662550501, 5022.146235139962, 4746.92879606813, 4781.976090119527, 4783.42147091452, 4795.424549054136, 4822.107910036654, 3563.5901871284095, 3578.8905439313894, 3579.0, 3582.659647333499, 3584.283143371388, 5041.722705251246, 5044.167712327425, 5052.317937078447, 5058.418281897481, 5074.162763144188, 4730.6583241295775, 4916.237983112624, 4930.211166213388, 4937.160682683881, 4940.567235501664, 4932.901341973069, 4935.284383764406, 4941.78783790539, 4941.827036053721, 4944.975275123601, 4876.440198654931, 4883.145449546386, 4887.413510378778, 4891.90216195667, 4903.362562866804, 4670.859874203779, 4761.3810204674555, 4790.520947905182, 4906.828261010847, 4918.213085906238, 4770.233264706531, 4773.73744444045, 4830.2513110467935, 4839.806407743651, 4841.298011788201], "value": 3717.776941834756, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.7265312649928, 579.9246098007255, 603.5835273661173, 613.2089604410311, 642.954993150479, 11895.881041189596, 12832.230066196034, 12841.42213600388, 12854.164479308838, 12864.09951303409, 11358.545658173683, 12042.157049006573, 12148.69628259294, 12169.14815962883, 12172.573959911404, 738.505201514985, 769.8075481129717, 772.4245437149332, 773.0877564473918, 773.4237992695449, 565.9349174844883, 566.4363957861933, 567.027547755599, 568.4116938968169, 570.3896830391483, 798.9760307190764, 801.683335082642, 805.9879101813477, 807.3379828540599, 807.5598798654718, 800.7757827808223, 804.1516200408566, 804.1717031457608, 804.4087595617217, 805.138501803068, 581.8429024163495, 586.2905883880467, 589.5519405252039, 589.599072630611, 589.6432658241745, 11802.94098529502, 11806.173567850254, 11828.645140645709, 11840.526378944833, 11855.110866685032, 12916.677083072998, 12992.41534130957, 13313.134646247963, 13467.12463689857, 13587.52443664467, 8977.820443591158, 9139.268858491305, 9144.954275228582, 9250.491222982733, 9339.112784285482, 3209.149575362531, 3211.373782112489, 3237.546743455918, 3258.152880251128, 3278.0329802708256, 53498.73250633761, 54373.45626543763, 54634.180487292724, 55791.44208557903, 66011.0, 8766.561671916379, 9424.667905225395, 9429.669961551313, 9444.811103778024, 9473.194778443909, 20178.19287228524, 20339.44059789531, 20708.171673132907, 20719.0, 20818.167273308896, 6776.2546119926765, 6794.72821087151, 6826.0, 6837.247902730695, 6983.092198014226, 3614.6023937366867, 3631.0377749896297, 3705.925881482355, 3723.94414083789, 3819.560750513671, 2743.931401714933, 2837.5034368985757, 2847.2597124747713, 2849.430113977213, 2854.1152242804797, 499.9875003124951, 509.22089203518675, 510.30853193922445, 519.6570263626064, 531.43136843577, 434.3289617540907, 464.1436549566045, 508.5524738230334, 545.6535100211315, 549.9587530935196, 7283.417326613858, 7530.811729706801, 7832.138464768871, 7885.408594355446, 7992.3606111510935, 452.2741000693905, 489.4029284273181, 522.8536009917229, 530.7770736290768, 553.6677993204125, 3408.3353746019784, 3608.6752192302292, 3738.813059347076, 3855.768653880776, 3886.4947556817433, 77326.0, 102099.4895025535, 104181.0, 109264.0, 109500.452497737, 86370.56814716011, 86581.56709216414, 86875.56562217275, 87011.56494217488, 88377.0, 79150.60424697955, 79431.0, 82127.0, 82245.0, 82390.58804706058, 7201.279872012757, 7378.594177320084, 7424.740134095281, 7559.395248380007, 7631.7328893490685, 45451.545484546055, 46677.0, 46995.53004469912, 47621.52378476171, 47706.52293477021, 38056.42915356327, 38869.0, 38935.22129557336, 44656.33015504705, 51718.74140629273, 10200.28597998133, 10273.332233404804, 10348.120409765253, 10484.21368397358, 10527.631532896312, 11082.390468524309, 11164.720881978015, 11463.197576169589, 11597.304161750322, 11945.641630751092, 11031.944840275748, 11184.328940263611, 11622.593209237637, 11860.051195904134, 11860.406979651156, 10603.310784798963, 10702.0, 10889.891101088888, 11077.390743509184, 11375.54497820078, 10466.581336746749, 10617.628383006559, 10978.945105274423, 11146.442677865913, 11237.213395062266, 9144.085591440802, 9455.905440945502, 9522.381045232038, 9650.469224192457, 9968.950155249177, 9805.849961809165, 10065.43129713783, 10065.870625895925, 10100.545475453475, 10149.949250253701], "value": 6201.049988361109, "unit": "score"}], "name": "dromaeo_css", "value": 6159.174148084547, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 111.0, 115.0, 113.0, 113.0, 121.0, 114.0, 114.0, 115.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 122.0, 152.0, 191.0, 126.0, 149.0, 150.0, 125.0, 188.0, 125.0], "value": 148.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 160.0, 162.0, 162.0, 163.0, 161.0, 163.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 178.0, 77.0, 78.0, 76.0, 75.0, 162.0, 77.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 148.0, 86.0, 87.0, 86.0, 140.0, 86.0, 87.0, 140.0], "value": 103.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 123.0, 117.0, 118.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 149.0, 147.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 65.0, 66.0, 67.0, 66.0, 65.0, 66.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 82.0, 82.0, 85.0, 83.0, 81.0, 82.0, 83.0, 82.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 132.0, 130.0, 132.0, 133.0, 132.0, 130.0, 126.0, 164.0, 134.0], "value": 134.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 171.0, 174.0, 170.0, 171.0, 174.0, 177.0, 170.0, 172.0], "value": 172.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 70.0, 72.0, 68.0, 70.0, 69.0, 67.0, 67.0, 69.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1571.9, "alertThreshold": 2.0}]} 09:26:39 INFO - Return code: 0 09:26:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:26:39 INFO - # TBPL SUCCESS # 09:26:39 INFO - Running post-action listener: _resource_record_post_action 09:26:39 INFO - [mozharness: 2016-08-25 16:26:39.214176Z] Finished run-tests step (success) 09:26:39 INFO - Running post-run listener: _resource_record_post_run 09:26:39 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 0; Write bytes: 414470144; Read time: 0; Write time: 543564 09:26:39 INFO - TinderboxPrint: CPU usage
13.2% 09:26:39 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:26:39 INFO - TinderboxPrint: I/O write bytes / time
414,470,144 / 543,564 09:26:39 INFO - TinderboxPrint: CPU idle
8,347.3 (86.7%) 09:26:39 INFO - TinderboxPrint: CPU user
1,204.5 (12.5%) 09:26:39 INFO - TinderboxPrint: Swap in / out
0 / 0 09:26:39 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 125112320; Read time: 0; Write time: 193908 09:26:39 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 288743424; Read time: 0; Write time: 349656 09:26:39 INFO - Running post-run listener: _upload_blobber_files 09:26:39 INFO - Blob upload gear active. 09:26:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:26:39 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 09:26:39 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:26:39 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:26:40 INFO - (blobuploader) - INFO - Open directory for files ... 09:26:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:26:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:26:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:40 INFO - (blobuploader) - INFO - Done attempting. 09:26:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:26:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:26:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:44 INFO - (blobuploader) - INFO - Done attempting. 09:26:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:26:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:26:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:45 INFO - (blobuploader) - INFO - Done attempting. 09:26:45 INFO - (blobuploader) - INFO - Iteration through files over. 09:26:45 INFO - Return code: 0 09:26:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:26:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:26:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/32b0aa4c49a1580db740fdf37f6ec9a739c10f992acf400fbe920553fc95946f89fd283becb76aa0be2186f28b030afb43ef1f73fea81c1f59ba60f4b4a49b17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/5e8a10dba714080900972cb7c3ac26be9cfbff90660828d1793532742e13ea32b4f6730a7747350d8c3a9b69d94ce4423a668ee462d798ac5c122bd8e99b9203", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/8717bbd5642648f088c600764d1b0d846593d3e087ec44bea224b1a1ced4b03be1c125973fa8b8c2f52824be19b6913e630b4d750b28c6478c7228ff54dba79f"} 09:26:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:26:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:26:45 INFO - Contents: 09:26:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/32b0aa4c49a1580db740fdf37f6ec9a739c10f992acf400fbe920553fc95946f89fd283becb76aa0be2186f28b030afb43ef1f73fea81c1f59ba60f4b4a49b17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/5e8a10dba714080900972cb7c3ac26be9cfbff90660828d1793532742e13ea32b4f6730a7747350d8c3a9b69d94ce4423a668ee462d798ac5c122bd8e99b9203", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/8717bbd5642648f088c600764d1b0d846593d3e087ec44bea224b1a1ced4b03be1c125973fa8b8c2f52824be19b6913e630b4d750b28c6478c7228ff54dba79f"} 09:26:45 INFO - Running post-run listener: copy_logs_to_upload_dir 09:26:45 INFO - Copying logs to upload dir... 09:26:45 INFO - mkdir: /builds/slave/test/build/upload/logs 09:26:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.244658 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-08-25 09:26:45.180770) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 09:26:45.184478) ========= 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=8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/32b0aa4c49a1580db740fdf37f6ec9a739c10f992acf400fbe920553fc95946f89fd283becb76aa0be2186f28b030afb43ef1f73fea81c1f59ba60f4b4a49b17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/5e8a10dba714080900972cb7c3ac26be9cfbff90660828d1793532742e13ea32b4f6730a7747350d8c3a9b69d94ce4423a668ee462d798ac5c122bd8e99b9203", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/8717bbd5642648f088c600764d1b0d846593d3e087ec44bea224b1a1ced4b03be1c125973fa8b8c2f52824be19b6913e630b4d750b28c6478c7228ff54dba79f"} build_url:https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/eKBJHHOTSG20OnDaz6j0mQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/32b0aa4c49a1580db740fdf37f6ec9a739c10f992acf400fbe920553fc95946f89fd283becb76aa0be2186f28b030afb43ef1f73fea81c1f59ba60f4b4a49b17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/5e8a10dba714080900972cb7c3ac26be9cfbff90660828d1793532742e13ea32b4f6730a7747350d8c3a9b69d94ce4423a668ee462d798ac5c122bd8e99b9203", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/8717bbd5642648f088c600764d1b0d846593d3e087ec44bea224b1a1ced4b03be1c125973fa8b8c2f52824be19b6913e630b4d750b28c6478c7228ff54dba79f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 09:26:45.209821) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:26:45.210159) ========= 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=8783db0840cc980e80bb0bd400000236-1472140698.451159-1907646442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004431 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:26:45.294958) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 09:26:45.295284) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 09:26:45.295575) ========= ========= Total master_lag: 0.17 =========