builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1470496316.82 results: success (0) buildid: 20160806053018 builduid: 3d69fa3769384188bf1a97aeb3fb2d09 revision: a159faf4c29b835d89fa6bb002c4d54c12b4ab04 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:56.817732) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:56.818274) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:56.821725) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:56.996967) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:56.997270) ========= 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=745a4373a2a16df560f216e000000264-1470491670.677045-184049152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-06 08:11:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.1M=0s 2016-08-06 08:11:57 (67.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.152706 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:57.184358) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:57.184799) ========= 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=745a4373a2a16df560f216e000000264-1470491670.677045-184049152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018216 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:57.307769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-06 08:11:57.308347) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a159faf4c29b835d89fa6bb002c4d54c12b4ab04 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev a159faf4c29b835d89fa6bb002c4d54c12b4ab04 --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=745a4373a2a16df560f216e000000264-1470491670.677045-184049152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-06 08:11:57,423 truncating revision to first 12 chars 2016-08-06 08:11:57,423 Setting DEBUG logging. 2016-08-06 08:11:57,423 attempt 1/10 2016-08-06 08:11:57,423 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a159faf4c29b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-06 08:11:58,844 unpacking tar archive at: fx-team-a159faf4c29b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.758754 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-06 08:11:59.098153) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:59.101536) ========= script_repo_revision: a159faf4c29b835d89fa6bb002c4d54c12b4ab04 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:59.101957) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:59.102227) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-06 08:11:59.112773) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-08-06 08:11:59.113046) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=745a4373a2a16df560f216e000000264-1470491670.677045-184049152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:11:59 INFO - MultiFileLogger online at 20160806 08:11:59 in /builds/slave/test 08:11:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:11:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:11:59 INFO - {'append_to_log': False, 08:11:59 INFO - 'base_work_dir': '/builds/slave/test', 08:11:59 INFO - 'blob_upload_branch': 'Fx-Team', 08:11:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:11:59 INFO - 'branch': 'Fx-Team', 08:11:59 INFO - 'buildbot_json_path': 'buildprops.json', 08:11:59 INFO - 'config_files': ('talos/linux_config.py',), 08:11:59 INFO - 'default_actions': ('clobber', 08:11:59 INFO - 'read-buildbot-config', 08:11:59 INFO - 'download-and-extract', 08:11:59 INFO - 'populate-webroot', 08:11:59 INFO - 'create-virtualenv', 08:11:59 INFO - 'install', 08:11:59 INFO - 'run-tests'), 08:11:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:11:59 INFO - 'download_minidump_stackwalk': True, 08:11:59 INFO - 'download_symbols': 'ondemand', 08:11:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:11:59 INFO - 'tooltool.py': '/tools/tooltool.py', 08:11:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:11:59 INFO - '/tools/misc-python/virtualenv.py')}, 08:11:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:11:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:11:59 INFO - 'installer_path': 'installer.exe', 08:11:59 INFO - 'log_level': 'info', 08:11:59 INFO - 'log_name': 'talos', 08:11:59 INFO - 'log_to_console': True, 08:11:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:11:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:11:59 INFO - 'opt_config_files': (), 08:11:59 INFO - 'pip_index': False, 08:11:59 INFO - 'sps_profile': False, 08:11:59 INFO - 'sps_profile_interval': 0, 08:11:59 INFO - 'suite': 'dromaeojs', 08:11:59 INFO - 'system_bits': '32', 08:11:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:11:59 INFO - 'title': 'talos-linux64-ix-032', 08:11:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:11:59 INFO - 'use_talos_json': True, 08:11:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:11:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:11:59 INFO - 'work_dir': 'build'} 08:11:59 INFO - [mozharness: 2016-08-06 15:11:59.298544Z] Running clobber step. 08:11:59 INFO - Running pre-action listener: _resource_record_pre_action 08:11:59 INFO - Running main action method: clobber 08:11:59 INFO - rmtree: /builds/slave/test/build 08:11:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:12:00 INFO - Running post-action listener: _resource_record_post_action 08:12:00 INFO - [mozharness: 2016-08-06 15:12:00.269312Z] Finished clobber step (success) 08:12:00 INFO - [mozharness: 2016-08-06 15:12:00.269415Z] Running read-buildbot-config step. 08:12:00 INFO - Running pre-action listener: _resource_record_pre_action 08:12:00 INFO - Running main action method: read_buildbot_config 08:12:00 INFO - Using buildbot properties: 08:12:00 INFO - { 08:12:00 INFO - "project": "", 08:12:00 INFO - "product": "firefox", 08:12:00 INFO - "script_repo_revision": "production", 08:12:00 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:12:00 INFO - "repository": "", 08:12:00 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 08:12:00 INFO - "buildid": "20160806053018", 08:12:00 INFO - "pgo_build": "True", 08:12:00 INFO - "basedir": "/builds/slave/test", 08:12:00 INFO - "buildnumber": 1435, 08:12:00 INFO - "slavename": "talos-linux64-ix-032", 08:12:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:12:00 INFO - "platform": "ubuntu64_hw", 08:12:00 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 08:12:00 INFO - "branch": "fx-team", 08:12:00 INFO - "repo_path": "integration/fx-team", 08:12:00 INFO - "revision": "a159faf4c29b835d89fa6bb002c4d54c12b4ab04", 08:12:00 INFO - "stage_platform": "linux64-pgo", 08:12:00 INFO - "builduid": "3d69fa3769384188bf1a97aeb3fb2d09", 08:12:00 INFO - "slavebuilddir": "test" 08:12:00 INFO - } 08:12:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:12:00 INFO - Running post-action listener: _resource_record_post_action 08:12:00 INFO - [mozharness: 2016-08-06 15:12:00.271847Z] Finished read-buildbot-config step (success) 08:12:00 INFO - [mozharness: 2016-08-06 15:12:00.271936Z] Running download-and-extract step. 08:12:00 INFO - Running pre-action listener: _resource_record_pre_action 08:12:00 INFO - Running main action method: download_and_extract 08:12:00 INFO - mkdir: /builds/slave/test/build/tests 08:12:00 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')]} 08:12:00 INFO - trying https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:12:00 INFO - Downloading https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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 08:12:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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 08:12:00 INFO - Downloaded 1361 bytes. 08:12:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:12:00 INFO - Using the following test package requirements: 08:12:00 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:12:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:12:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:12:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:00 INFO - u'jsshell-linux-x86_64.zip'], 08:12:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:12:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:12:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:12:00 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:12:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:12:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:12:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:12:00 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')]} 08:12:00 INFO - trying https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:12:00 INFO - Downloading https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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 08:12:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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 08:12:01 INFO - Downloaded 21825937 bytes. 08:12:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:12:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:12:03 INFO - Return code: 0 08:12:03 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 08:12:03 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')]} 08:12:03 INFO - trying https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:12:03 INFO - Downloading https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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 08:12:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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 08:12:04 INFO - Downloaded 21825937 bytes. 08:12:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:12:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:12:05 INFO - Return code: 0 08:12:05 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')]} 08:12:05 INFO - trying https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:12:05 INFO - Downloading https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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 08:12:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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 08:12:06 INFO - Downloaded 14413723 bytes. 08:12:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:12:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:12:06 INFO - Return code: 0 08:12:06 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')]} 08:12:06 INFO - trying https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:12:06 INFO - Downloading https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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 08:12:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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 08:12:07 INFO - Downloaded 59987847 bytes. 08:12:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:12:07 INFO - mkdir: /builds/slave/test/properties 08:12:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:12:07 INFO - Writing to file /builds/slave/test/properties/build_url 08:12:07 INFO - Contents: 08:12:07 INFO - build_url:https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:12:08 INFO - Running post-action listener: _resource_record_post_action 08:12:08 INFO - Running post-action listener: set_extra_try_arguments 08:12:08 INFO - [mozharness: 2016-08-06 15:12:08.692628Z] Finished download-and-extract step (success) 08:12:08 INFO - [mozharness: 2016-08-06 15:12:08.692859Z] Running populate-webroot step. 08:12:08 INFO - Running pre-action listener: _resource_record_pre_action 08:12:08 INFO - Running main action method: populate_webroot 08:12:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:12:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:12:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:12:08 INFO - '--tppagecycles', 08:12:08 INFO - '7'], 08:12:08 INFO - 'tests': ['tsvgm']}}, 08:12:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:12:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:12:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:12:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:12:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:08 INFO - 'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:12:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:12:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:08 INFO - 'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['damp', 'tps']}, 08:12:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:08 INFO - 'tests': ['damp', 'tps']}, 08:12:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['dromaeo_dom']}, 08:12:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:12:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['basic_compositor_video']}, 08:12:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:12:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['a11yr', 08:12:08 INFO - 'ts_paint', 08:12:08 INFO - 'tpaint', 08:12:08 INFO - 'sessionrestore', 08:12:08 INFO - 'sessionrestore_no_auto_restore', 08:12:08 INFO - 'tabpaint']}, 08:12:08 INFO - 'other-e10s': {'tests': ['a11yr', 08:12:08 INFO - 'ts_paint', 08:12:08 INFO - 'tpaint', 08:12:08 INFO - 'sessionrestore', 08:12:08 INFO - 'sessionrestore_no_auto_restore', 08:12:08 INFO - 'tabpaint']}, 08:12:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:12:08 INFO - 'ts_paint', 08:12:08 INFO - 'tpaint', 08:12:08 INFO - 'sessionrestore', 08:12:08 INFO - 'sessionrestore_no_auto_restore', 08:12:08 INFO - 'tabpaint']}, 08:12:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:12:08 INFO - 'ts_paint', 08:12:08 INFO - 'tpaint', 08:12:08 INFO - 'sessionrestore', 08:12:08 INFO - 'sessionrestore_no_auto_restore', 08:12:08 INFO - 'tabpaint']}, 08:12:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['a11yr', 08:12:08 INFO - 'ts_paint', 08:12:08 INFO - 'tpaint', 08:12:08 INFO - 'sessionrestore', 08:12:08 INFO - 'sessionrestore_no_auto_restore', 08:12:08 INFO - 'tabpaint']}, 08:12:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['a11yr', 08:12:08 INFO - 'ts_paint', 08:12:08 INFO - 'tpaint', 08:12:08 INFO - 'sessionrestore', 08:12:08 INFO - 'sessionrestore_no_auto_restore', 08:12:08 INFO - 'tabpaint']}, 08:12:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['tsvgx', 08:12:08 INFO - 'tsvgr_opacity', 08:12:08 INFO - 'tart', 08:12:08 INFO - 'tscrollx', 08:12:08 INFO - 'cart']}, 08:12:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:12:08 INFO - 'tsvgr_opacity', 08:12:08 INFO - 'tart', 08:12:08 INFO - 'tscrollx', 08:12:08 INFO - 'cart']}, 08:12:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:08 INFO - 'talos_options': ['--disable-e10s'], 08:12:08 INFO - 'tests': ['tp5o']}, 08:12:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:08 INFO - 'tests': ['tp5o']}, 08:12:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:08 INFO - 'talos_options': ['--disable-e10s', 08:12:08 INFO - '--xperf_path', 08:12:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:12:08 INFO - 'tests': ['tp5n']}, 08:12:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:08 INFO - 'talos_options': ['--xperf_path', 08:12:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:12:08 INFO - 'tests': ['tp5n']}}, 08:12:08 INFO - 'talos.zip': {'path': '', 08:12:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:12:08 INFO - Running post-action listener: _resource_record_post_action 08:12:08 INFO - [mozharness: 2016-08-06 15:12:08.714056Z] Finished populate-webroot step (success) 08:12:08 INFO - [mozharness: 2016-08-06 15:12:08.714144Z] Running create-virtualenv step. 08:12:08 INFO - Running pre-action listener: _resource_record_pre_action 08:12:08 INFO - Running main action method: create_virtualenv 08:12:08 INFO - Creating virtualenv /builds/slave/test/build/venv 08:12:08 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 08:12:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:12:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:12:08 INFO - Using real prefix '/usr' 08:12:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:12:09 INFO - Installing distribute.............................................................................................................................................................................................done. 08:12:11 INFO - Installing pip.................done. 08:12:11 INFO - Return code: 0 08:12:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:12:11 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')]} 08:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:11 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')]} 08:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:11 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 0x2453030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2714180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, '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': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', '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 08:12:11 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 08:12:11 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 08:12:11 INFO - Using env: {'DISPLAY': ':0', 08:12:11 INFO - 'HOME': '/home/cltbld', 08:12:11 INFO - 'LANG': 'en_US.UTF-8', 08:12:11 INFO - 'LANGUAGE': 'en_US:en', 08:12:11 INFO - 'LOGNAME': 'cltbld', 08:12:11 INFO - 'MAIL': '/var/mail/cltbld', 08:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:11 INFO - 'MOZ_NO_REMOTE': '1', 08:12:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:11 INFO - 'NO_EM_RESTART': '1', 08:12:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:11 INFO - 'PWD': '/builds/slave/test', 08:12:11 INFO - 'SHELL': '/bin/bash', 08:12:11 INFO - 'SHLVL': '1', 08:12:11 INFO - 'TERM': 'linux', 08:12:11 INFO - 'TMOUT': '86400', 08:12:11 INFO - 'USER': 'cltbld', 08:12:11 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:11 INFO - '_': '/tools/buildbot/bin/python'} 08:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:11 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 08:12:11 INFO - Cleaning up... 08:12:11 INFO - Return code: 0 08:12:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:12:11 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')]} 08:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:11 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')]} 08:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:11 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 0x2453030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2714180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, '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': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', '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 08:12:11 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 08:12:11 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 08:12:11 INFO - Using env: {'DISPLAY': ':0', 08:12:11 INFO - 'HOME': '/home/cltbld', 08:12:11 INFO - 'LANG': 'en_US.UTF-8', 08:12:11 INFO - 'LANGUAGE': 'en_US:en', 08:12:11 INFO - 'LOGNAME': 'cltbld', 08:12:11 INFO - 'MAIL': '/var/mail/cltbld', 08:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:11 INFO - 'MOZ_NO_REMOTE': '1', 08:12:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:11 INFO - 'NO_EM_RESTART': '1', 08:12:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:11 INFO - 'PWD': '/builds/slave/test', 08:12:11 INFO - 'SHELL': '/bin/bash', 08:12:11 INFO - 'SHLVL': '1', 08:12:11 INFO - 'TERM': 'linux', 08:12:11 INFO - 'TMOUT': '86400', 08:12:11 INFO - 'USER': 'cltbld', 08:12:11 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:11 INFO - '_': '/tools/buildbot/bin/python'} 08:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:11 INFO - Downloading/unpacking psutil>=3.1.1 08:12:11 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 08:12:11 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 08:12:11 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 08:12:11 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 08:12:11 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 08:12:11 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 08:12:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:12:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:12:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:12:13 INFO - Installing collected packages: psutil 08:12:13 INFO - Running setup.py install for psutil 08:12:13 INFO - building 'psutil._psutil_linux' extension 08:12:13 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 08:12:13 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 08:12:13 INFO - building 'psutil._psutil_posix' extension 08:12:13 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 08:12:14 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 08:12:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:12:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:12:14 INFO - Successfully installed psutil 08:12:14 INFO - Cleaning up... 08:12:14 INFO - Return code: 0 08:12:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:12:14 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')]} 08:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:14 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')]} 08:12:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:14 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 0x2453030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2714180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, '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': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', '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 08:12:14 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 08:12:14 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 08:12:14 INFO - Using env: {'DISPLAY': ':0', 08:12:14 INFO - 'HOME': '/home/cltbld', 08:12:14 INFO - 'LANG': 'en_US.UTF-8', 08:12:14 INFO - 'LANGUAGE': 'en_US:en', 08:12:14 INFO - 'LOGNAME': 'cltbld', 08:12:14 INFO - 'MAIL': '/var/mail/cltbld', 08:12:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:14 INFO - 'MOZ_NO_REMOTE': '1', 08:12:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:14 INFO - 'NO_EM_RESTART': '1', 08:12:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:14 INFO - 'PWD': '/builds/slave/test', 08:12:14 INFO - 'SHELL': '/bin/bash', 08:12:14 INFO - 'SHLVL': '1', 08:12:14 INFO - 'TERM': 'linux', 08:12:14 INFO - 'TMOUT': '86400', 08:12:14 INFO - 'USER': 'cltbld', 08:12:14 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:14 INFO - '_': '/tools/buildbot/bin/python'} 08:12:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:12:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:12:16 INFO - Installing collected packages: mozsystemmonitor 08:12:16 INFO - Running setup.py install for mozsystemmonitor 08:12:16 INFO - Successfully installed mozsystemmonitor 08:12:16 INFO - Cleaning up... 08:12:16 INFO - Return code: 0 08:12:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:12: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')]} 08:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12: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')]} 08:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12: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', '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 0x2453030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2714180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, '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': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', '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 08:12: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:12: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 jsonschema==2.5.1 08:12:16 INFO - Using env: {'DISPLAY': ':0', 08:12:16 INFO - 'HOME': '/home/cltbld', 08:12:16 INFO - 'LANG': 'en_US.UTF-8', 08:12:16 INFO - 'LANGUAGE': 'en_US:en', 08:12:16 INFO - 'LOGNAME': 'cltbld', 08:12:16 INFO - 'MAIL': '/var/mail/cltbld', 08:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:16 INFO - 'MOZ_NO_REMOTE': '1', 08:12:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:16 INFO - 'NO_EM_RESTART': '1', 08:12:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:16 INFO - 'PWD': '/builds/slave/test', 08:12:16 INFO - 'SHELL': '/bin/bash', 08:12:16 INFO - 'SHLVL': '1', 08:12:16 INFO - 'TERM': 'linux', 08:12:16 INFO - 'TMOUT': '86400', 08:12:16 INFO - 'USER': 'cltbld', 08:12:16 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:16 INFO - '_': '/tools/buildbot/bin/python'} 08:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:16 INFO - Downloading/unpacking jsonschema==2.5.1 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:12:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:18 INFO - Downloading functools32-3.2.3-2.tar.gz 08:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:12:18 INFO - warning: no files found matching '*.txt' 08:12:18 INFO - no previously-included directories found matching 'build' 08:12:18 INFO - no previously-included directories found matching 'dist' 08:12:18 INFO - no previously-included directories found matching '.git*' 08:12:18 INFO - Installing collected packages: jsonschema, functools32 08:12:18 INFO - Running setup.py install for functools32 08:12:19 INFO - warning: no files found matching '*.txt' 08:12:19 INFO - no previously-included directories found matching 'build' 08:12:19 INFO - no previously-included directories found matching 'dist' 08:12:19 INFO - no previously-included directories found matching '.git*' 08:12:19 INFO - Successfully installed jsonschema functools32 08:12:19 INFO - Cleaning up... 08:12:19 INFO - Return code: 0 08:12:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:12:19 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')]} 08:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:19 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')]} 08:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:19 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 0x2453030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2714180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, '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': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', '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 08:12:19 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 08:12:19 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 08:12:19 INFO - Using env: {'DISPLAY': ':0', 08:12:19 INFO - 'HOME': '/home/cltbld', 08:12:19 INFO - 'LANG': 'en_US.UTF-8', 08:12:19 INFO - 'LANGUAGE': 'en_US:en', 08:12:19 INFO - 'LOGNAME': 'cltbld', 08:12:19 INFO - 'MAIL': '/var/mail/cltbld', 08:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:19 INFO - 'MOZ_NO_REMOTE': '1', 08:12:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:19 INFO - 'NO_EM_RESTART': '1', 08:12:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:19 INFO - 'PWD': '/builds/slave/test', 08:12:19 INFO - 'SHELL': '/bin/bash', 08:12:19 INFO - 'SHLVL': '1', 08:12:19 INFO - 'TERM': 'linux', 08:12:19 INFO - 'TMOUT': '86400', 08:12:19 INFO - 'USER': 'cltbld', 08:12:19 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:19 INFO - '_': '/tools/buildbot/bin/python'} 08:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:12:19 INFO - Cleaning up... 08:12:19 INFO - Return code: 0 08:12:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:12:19 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')]} 08:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:19 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')]} 08:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:19 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 0x2453030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2714180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, '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': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', '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 08:12:19 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 08:12:19 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 08:12:19 INFO - Using env: {'DISPLAY': ':0', 08:12:19 INFO - 'HOME': '/home/cltbld', 08:12:19 INFO - 'LANG': 'en_US.UTF-8', 08:12:19 INFO - 'LANGUAGE': 'en_US:en', 08:12:19 INFO - 'LOGNAME': 'cltbld', 08:12:19 INFO - 'MAIL': '/var/mail/cltbld', 08:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:19 INFO - 'MOZ_NO_REMOTE': '1', 08:12:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:19 INFO - 'NO_EM_RESTART': '1', 08:12:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:19 INFO - 'PWD': '/builds/slave/test', 08:12:19 INFO - 'SHELL': '/bin/bash', 08:12:19 INFO - 'SHLVL': '1', 08:12:19 INFO - 'TERM': 'linux', 08:12:19 INFO - 'TMOUT': '86400', 08:12:19 INFO - 'USER': 'cltbld', 08:12:19 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:19 INFO - '_': '/tools/buildbot/bin/python'} 08:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:19 INFO - Downloading/unpacking blobuploader==1.2.4 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12:21 INFO - Downloading blobuploader-1.2.4.tar.gz 08:12:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:12:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:12:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12: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 08:12:22 INFO - Downloading docopt-0.6.1.tar.gz 08:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:12:22 INFO - Installing collected packages: blobuploader, requests, docopt 08:12:22 INFO - Running setup.py install for blobuploader 08:12:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:12:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:12:22 INFO - Running setup.py install for requests 08:12:22 INFO - Running setup.py install for docopt 08:12:22 INFO - Successfully installed blobuploader requests docopt 08:12:22 INFO - Cleaning up... 08:12:22 INFO - Return code: 0 08:12:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:22 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')]} 08:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:22 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')]} 08:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:22 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 0x2453030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2714180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, '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': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', '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 08:12:22 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 08:12:22 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 08:12:22 INFO - Using env: {'DISPLAY': ':0', 08:12:22 INFO - 'HOME': '/home/cltbld', 08:12:22 INFO - 'LANG': 'en_US.UTF-8', 08:12:22 INFO - 'LANGUAGE': 'en_US:en', 08:12:22 INFO - 'LOGNAME': 'cltbld', 08:12:22 INFO - 'MAIL': '/var/mail/cltbld', 08:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:22 INFO - 'MOZ_NO_REMOTE': '1', 08:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:22 INFO - 'NO_EM_RESTART': '1', 08:12:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:22 INFO - 'PWD': '/builds/slave/test', 08:12:22 INFO - 'SHELL': '/bin/bash', 08:12:22 INFO - 'SHLVL': '1', 08:12:22 INFO - 'TERM': 'linux', 08:12:22 INFO - 'TMOUT': '86400', 08:12:22 INFO - 'USER': 'cltbld', 08:12:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:22 INFO - '_': '/tools/buildbot/bin/python'} 08:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:12:22 INFO - Running setup.py (path:/tmp/pip-9VSA2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:12:23 INFO - Running setup.py (path:/tmp/pip-S8HPrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:12:23 INFO - Running setup.py (path:/tmp/pip-DxeWaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:12:23 INFO - Running setup.py (path:/tmp/pip-XMFnpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:12:23 INFO - Running setup.py (path:/tmp/pip-6eWMn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:12:23 INFO - Running setup.py (path:/tmp/pip-FRQrSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:12:23 INFO - Running setup.py (path:/tmp/pip-bsE0Yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:12:23 INFO - Running setup.py (path:/tmp/pip-xOYROI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:12:23 INFO - Running setup.py (path:/tmp/pip-ENLd1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:12:23 INFO - Running setup.py (path:/tmp/pip-mQ2qbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:12:23 INFO - Running setup.py (path:/tmp/pip-wCpV0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:12:23 INFO - Running setup.py (path:/tmp/pip-PHPzjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:12:23 INFO - Running setup.py (path:/tmp/pip-CTK6p_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:12:23 INFO - Running setup.py (path:/tmp/pip-fYDdXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:23 INFO - Running setup.py (path:/tmp/pip-ycvQGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:12:24 INFO - Running setup.py (path:/tmp/pip-vLTAA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:12:24 INFO - Running setup.py (path:/tmp/pip-B5_jwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:12:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:12:24 INFO - Running setup.py install for manifestparser 08:12:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:12:24 INFO - Running setup.py install for mozcrash 08:12:24 INFO - Running setup.py install for mozdebug 08:12:24 INFO - Running setup.py install for mozdevice 08:12:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:12:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:12:24 INFO - Running setup.py install for mozfile 08:12:24 INFO - Running setup.py install for mozhttpd 08:12:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:12:24 INFO - Running setup.py install for mozinfo 08:12:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:12:24 INFO - Running setup.py install for mozInstall 08:12:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:12:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:12:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:12:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:12:24 INFO - Running setup.py install for mozleak 08:12:25 INFO - Running setup.py install for mozlog 08:12:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:12:25 INFO - Running setup.py install for moznetwork 08:12:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:12:25 INFO - Running setup.py install for mozprocess 08:12:25 INFO - Running setup.py install for mozprofile 08:12:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:12:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:12:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:12:25 INFO - Running setup.py install for mozrunner 08:12:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:12:25 INFO - Running setup.py install for mozscreenshot 08:12:25 INFO - Running setup.py install for moztest 08:12:25 INFO - Running setup.py install for mozversion 08:12:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:12:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:12:25 INFO - Cleaning up... 08:12:25 INFO - Return code: 0 08:12:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12: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')]} 08:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12: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')]} 08:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:25 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 0x2453030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2714180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, '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': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', '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 08:12:25 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 08:12:25 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 08:12:25 INFO - Using env: {'DISPLAY': ':0', 08:12:25 INFO - 'HOME': '/home/cltbld', 08:12:25 INFO - 'LANG': 'en_US.UTF-8', 08:12:25 INFO - 'LANGUAGE': 'en_US:en', 08:12:25 INFO - 'LOGNAME': 'cltbld', 08:12:25 INFO - 'MAIL': '/var/mail/cltbld', 08:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:25 INFO - 'MOZ_NO_REMOTE': '1', 08:12:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:25 INFO - 'NO_EM_RESTART': '1', 08:12:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:25 INFO - 'PWD': '/builds/slave/test', 08:12:25 INFO - 'SHELL': '/bin/bash', 08:12:25 INFO - 'SHLVL': '1', 08:12:25 INFO - 'TERM': 'linux', 08:12:25 INFO - 'TMOUT': '86400', 08:12:25 INFO - 'USER': 'cltbld', 08:12:25 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:25 INFO - '_': '/tools/buildbot/bin/python'} 08:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:12:26 INFO - Running setup.py (path:/tmp/pip-Dbbveb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:12:26 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)) 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:12:26 INFO - Running setup.py (path:/tmp/pip-T9qv4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:12:26 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)) 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:12:26 INFO - Running setup.py (path:/tmp/pip-KuWxeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:12:26 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)) 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:12:26 INFO - Running setup.py (path:/tmp/pip-k0m3ng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:12:26 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)) 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:12:26 INFO - Running setup.py (path:/tmp/pip-L0cvjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:12:26 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)) 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:12:26 INFO - Running setup.py (path:/tmp/pip-EH6GV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:12:26 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)) 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:12:26 INFO - Running setup.py (path:/tmp/pip-KEAqA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:12:26 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)) 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:12:26 INFO - Running setup.py (path:/tmp/pip-psUqwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:12:26 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)) 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:12:26 INFO - Running setup.py (path:/tmp/pip-iHSuHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:12:26 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)) 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:12:26 INFO - Running setup.py (path:/tmp/pip-MOcjEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:12:26 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)) 08:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:12:26 INFO - Running setup.py (path:/tmp/pip-inRAuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:12:27 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)) 08:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:12:27 INFO - Running setup.py (path:/tmp/pip-2j9J1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:12:27 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)) 08:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:12:27 INFO - Running setup.py (path:/tmp/pip-S4PZjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:12:27 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)) 08:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:12:27 INFO - Running setup.py (path:/tmp/pip-_0YYGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:12:27 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)) 08:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:27 INFO - Running setup.py (path:/tmp/pip-IxPQf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:27 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)) 08:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:12:27 INFO - Running setup.py (path:/tmp/pip-L0uvlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:12:27 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)) 08:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:12:27 INFO - Running setup.py (path:/tmp/pip-8SCQqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:12:27 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)) 08:12:27 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)) 08:12:27 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)) 08:12:27 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)) 08:12:27 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)) 08:12:27 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)) 08:12:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:29 INFO - Downloading blessings-1.6.tar.gz 08:12:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:12:29 INFO - Installing collected packages: blessings 08:12:29 INFO - Running setup.py install for blessings 08:12:29 INFO - Successfully installed blessings 08:12:29 INFO - Cleaning up... 08:12:29 INFO - Return code: 0 08:12:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:12:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:12:29 INFO - Reading from file tmpfile_stdout 08:12:29 INFO - Current package versions: 08:12:29 INFO - argparse == 1.2.1 08:12:29 INFO - blessings == 1.6 08:12:29 INFO - blobuploader == 1.2.4 08:12:29 INFO - docopt == 0.6.1 08:12:29 INFO - functools32 == 3.2.3-2 08:12:29 INFO - jsonschema == 2.5.1 08:12:29 INFO - manifestparser == 1.1 08:12:29 INFO - mozInstall == 1.12 08:12:29 INFO - mozcrash == 0.17 08:12:29 INFO - mozdebug == 0.1 08:12:29 INFO - mozdevice == 0.48 08:12:29 INFO - mozfile == 1.2 08:12:29 INFO - mozhttpd == 0.7 08:12:29 INFO - mozinfo == 0.9 08:12:29 INFO - mozleak == 0.1 08:12:29 INFO - mozlog == 3.3 08:12:29 INFO - moznetwork == 0.27 08:12:29 INFO - mozprocess == 0.23 08:12:29 INFO - mozprofile == 0.28 08:12:29 INFO - mozrunner == 6.12 08:12:29 INFO - mozscreenshot == 0.1 08:12:29 INFO - mozsystemmonitor == 0.3 08:12:29 INFO - moztest == 0.7 08:12:29 INFO - mozversion == 1.4 08:12:29 INFO - psutil == 3.1.1 08:12:29 INFO - requests == 1.2.3 08:12:29 INFO - wsgiref == 0.1.2 08:12:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:29 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')]} 08:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:29 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')]} 08:12:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:29 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 0x2453030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2714180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, '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': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', '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 08:12:29 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 08:12:29 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 08:12:29 INFO - Using env: {'DISPLAY': ':0', 08:12:29 INFO - 'HOME': '/home/cltbld', 08:12:29 INFO - 'LANG': 'en_US.UTF-8', 08:12:29 INFO - 'LANGUAGE': 'en_US:en', 08:12:29 INFO - 'LOGNAME': 'cltbld', 08:12:29 INFO - 'MAIL': '/var/mail/cltbld', 08:12:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:29 INFO - 'MOZ_NO_REMOTE': '1', 08:12:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:29 INFO - 'NO_EM_RESTART': '1', 08:12:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:29 INFO - 'PWD': '/builds/slave/test', 08:12:29 INFO - 'SHELL': '/bin/bash', 08:12:29 INFO - 'SHLVL': '1', 08:12:29 INFO - 'TERM': 'linux', 08:12:29 INFO - 'TMOUT': '86400', 08:12:29 INFO - 'USER': 'cltbld', 08:12:29 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:29 INFO - '_': '/tools/buildbot/bin/python'} 08:12:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:29 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)) 08:12:29 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)) 08:12:29 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)) 08:12:29 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)) 08:12:29 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)) 08:12:29 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)) 08:12:29 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)) 08:12:29 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)) 08:12:29 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)) 08:12:29 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)) 08:12:29 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)) 08:12:29 INFO - Cleaning up... 08:12:29 INFO - Return code: 0 08:12:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:12:29 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')]} 08:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:29 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')]} 08:12:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:29 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 0x2453030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2714180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, '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': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', '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 08:12:29 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 08:12:29 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 08:12:30 INFO - Using env: {'DISPLAY': ':0', 08:12:30 INFO - 'HOME': '/home/cltbld', 08:12:30 INFO - 'LANG': 'en_US.UTF-8', 08:12:30 INFO - 'LANGUAGE': 'en_US:en', 08:12:30 INFO - 'LOGNAME': 'cltbld', 08:12:30 INFO - 'MAIL': '/var/mail/cltbld', 08:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:30 INFO - 'MOZ_NO_REMOTE': '1', 08:12:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:30 INFO - 'NO_EM_RESTART': '1', 08:12:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:30 INFO - 'PWD': '/builds/slave/test', 08:12:30 INFO - 'SHELL': '/bin/bash', 08:12:30 INFO - 'SHLVL': '1', 08:12:30 INFO - 'TERM': 'linux', 08:12:30 INFO - 'TMOUT': '86400', 08:12:30 INFO - 'USER': 'cltbld', 08:12:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:30 INFO - '_': '/tools/buildbot/bin/python'} 08:12:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:12:30 INFO - Cleaning up... 08:12:30 INFO - Return code: 0 08:12:30 INFO - Running post-action listener: _resource_record_post_action 08:12:30 INFO - Running post-action listener: _start_resource_monitoring 08:12:30 INFO - Starting resource monitoring. 08:12:30 INFO - [mozharness: 2016-08-06 15:12:30.252664Z] Finished create-virtualenv step (success) 08:12:30 INFO - [mozharness: 2016-08-06 15:12:30.253107Z] Running install step. 08:12:30 INFO - Running pre-action listener: _resource_record_pre_action 08:12:30 INFO - Running main action method: install 08:12:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:12:30 INFO - Reading from file tmpfile_stdout 08:12:30 INFO - Detecting whether we're running mozinstall >=1.0... 08:12:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:12:30 INFO - Reading from file tmpfile_stdout 08:12:30 INFO - Output received: 08:12:30 INFO - Usage: mozinstall [options] installer 08:12:30 INFO - Options: 08:12:30 INFO - -h, --help show this help message and exit 08:12:30 INFO - -d DEST, --destination=DEST 08:12:30 INFO - Directory to install application into. [default: 08:12:30 INFO - "/builds/slave/test"] 08:12:30 INFO - --app=APP Application being installed. [default: firefox] 08:12:30 INFO - mkdir: /builds/slave/test/build/application 08:12:30 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'] 08:12:30 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 08:12:48 INFO - Reading from file tmpfile_stdout 08:12:48 INFO - Output received: 08:12:48 INFO - /builds/slave/test/build/application/firefox/firefox 08:12:48 INFO - Running post-action listener: _resource_record_post_action 08:12:48 INFO - [mozharness: 2016-08-06 15:12:48.232895Z] Finished install step (success) 08:12:48 INFO - [mozharness: 2016-08-06 15:12:48.233143Z] Running run-tests step. 08:12:48 INFO - Running pre-action listener: _resource_record_pre_action 08:12:48 INFO - Running main action method: run_tests 08:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:12:48 INFO - Python 2.7.3 08:12:48 INFO - Return code: 0 08:12:48 INFO - grabbing minidump binary from tooltool 08:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:48 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27123b0>, '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 0x27132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2713740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:12:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:12:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:12:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:12:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:12:48 INFO - Return code: 0 08:12:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:12:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:12:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:12:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:12:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 08:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 08:12:48 INFO - Using env: {'DISPLAY': ':0', 08:12:48 INFO - 'HOME': '/home/cltbld', 08:12:48 INFO - 'LANG': 'en_US.UTF-8', 08:12:48 INFO - 'LANGUAGE': 'en_US:en', 08:12:48 INFO - 'LOGNAME': 'cltbld', 08:12:48 INFO - 'MAIL': '/var/mail/cltbld', 08:12:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:12:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:48 INFO - 'MOZ_NO_REMOTE': '1', 08:12:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:48 INFO - 'NO_EM_RESTART': '1', 08:12:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:48 INFO - 'PWD': '/builds/slave/test', 08:12:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:12:48 INFO - 'SHELL': '/bin/bash', 08:12:48 INFO - 'SHLVL': '1', 08:12:48 INFO - 'TERM': 'linux', 08:12:48 INFO - 'TMOUT': '86400', 08:12:48 INFO - 'USER': 'cltbld', 08:12:48 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470491670.677045-184049152', 08:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:48 INFO - '_': '/tools/buildbot/bin/python'} 08:12:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 08:12:48 INFO - mozversion application_buildid: 20160806053018 08:12:48 INFO - mozversion application_changeset: a159faf4c29b835d89fa6bb002c4d54c12b4ab04 08:12:48 INFO - mozversion application_display_name: Nightly 08:12:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:12:48 INFO - mozversion application_name: Firefox 08:12:48 INFO - mozversion application_remotingname: firefox 08:12:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:12:48 INFO - mozversion application_vendor: Mozilla 08:12:48 INFO - mozversion application_version: 51.0a1 08:12:48 INFO - mozversion platform_buildid: 20160806053018 08:12:48 INFO - mozversion platform_changeset: a159faf4c29b835d89fa6bb002c4d54c12b4ab04 08:12:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:12:48 INFO - mozversion platform_version: 51.0a1 08:12:48 INFO - using testdate: 1470496368 08:12:48 INFO - actual date: 1470496368 08:12:48 INFO - starting webserver on 'localhost:35831' 08:12:48 INFO - SUITE-START | Running 2 tests 08:12:48 INFO - TEST-START | dromaeo_css 08:12:48 INFO - operating with platform_type : linux_ 08:12:48 INFO - Initialising browser for dromaeo_css test... 08:12:48 INFO - TEST-INFO | started process 27228 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJYCyLa/profile http://localhost:35831/getInfo.html) 08:12:53 INFO - PROCESS | 27228 | __metrics Screen width/height:1600/1200 08:12:53 INFO - PROCESS | 27228 | colorDepth:24 08:12:53 INFO - PROCESS | 27228 | Browser inner width/height: 1024/697 08:12:53 INFO - PROCESS | 27228 | __metrics 08:12:54 INFO - PROCESS | 27228 | 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" 08:12:54 INFO - PROCESS | 27228 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:12:54 INFO - PROCESS | 27228 | console.error: PushService: 08:12:54 INFO - PROCESS | 27228 | stateChangeProcessEnqueue: Error transitioning state 08:12:54 INFO - PROCESS | 27228 | UnknownError 08:12:55 INFO - TEST-INFO | 27228: exit 0 08:12:55 INFO - Browser initialized. 08:12:55 INFO - Running cycle 1/1 for dromaeo_css test... 08:12:55 INFO - TEST-INFO | started process 27314 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJYCyLa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:13:07 INFO - PROCESS | 27314 | RSS: Main: 156192768 08:13:07 INFO - PROCESS | 27314 | 08:13:58 INFO - PROCESS | 27314 | 1470496438914 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] 08:16:08 INFO - PROCESS | 27314 | Cycle 1(1): loaded http://localhost:35831/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35831/tests/dromaeo/cssquery-ext.html) 08:16:09 INFO - PROCESS | 27314 | RSS: Main: 255053824 08:16:09 INFO - PROCESS | 27314 | 08:19:12 INFO - PROCESS | 27314 | Cycle 1(1): loaded http://localhost:35831/tests/dromaeo/cssquery-ext.html (next: http://localhost:35831/tests/dromaeo/cssquery-jquery.html) 08:19:12 INFO - PROCESS | 27314 | RSS: Main: 248885248 08:19:12 INFO - PROCESS | 27314 | 08:22:14 INFO - PROCESS | 27314 | Cycle 1(1): loaded http://localhost:35831/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35831/tests/dromaeo/cssquery-mootools.html) 08:22:15 INFO - PROCESS | 27314 | RSS: Main: 185733120 08:22:15 INFO - PROCESS | 27314 | 08:25:17 INFO - PROCESS | 27314 | Cycle 1(1): loaded http://localhost:35831/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35831/tests/dromaeo/cssquery-prototype.html) 08:25:17 INFO - PROCESS | 27314 | RSS: Main: 226361344 08:25:17 INFO - PROCESS | 27314 | 08:28:20 INFO - PROCESS | 27314 | Cycle 1(1): loaded http://localhost:35831/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35831/tests/dromaeo/cssquery-yui.html) 08:28:21 INFO - PROCESS | 27314 | RSS: Main: 184512512 08:28:21 INFO - PROCESS | 27314 | 08:31:23 INFO - PROCESS | 27314 | Cycle 1(1): loaded http://localhost:35831/tests/dromaeo/cssquery-yui.html (next: http://localhost:35831/tests/dromaeo/cssquery-dojo.html) 08:31:24 INFO - PROCESS | 27314 | RSS: Main: 277020672 08:31:24 INFO - PROCESS | 27314 | 08:31:24 INFO - PROCESS | 27314 | __start_tp_report 08:31:24 INFO - PROCESS | 27314 | _x_x_mozilla_page_load 08:31:24 INFO - PROCESS | 27314 | _x_x_mozilla_page_load_details 08:31:24 INFO - PROCESS | 27314 | |i|pagename|runs| 08:31:24 INFO - PROCESS | 27314 | |0;dojo.html;3067.14120046387;3089.2276930767307;3113.548535462358;3115.953260701089;3128.6089238845143;5199.272101905727;5241.371035475739;5309.654872433294;5369.731513424328;5372.570194384445;5195.662281951671;5237.47625237476;5240.8689782755355;5242.18746094355;5308.70802105884;4724.692894961825;4807.61539076873;4808.759562021903;4818.614510839125;4972.70163790172;4921.7785199666105;4924.655274130812;4926.1379258629595;4945.455999840015;4958.181899986516;4729.95270047302;4759.714417134983;4877.780499877496;4906.803727850882;4925.236588328815;4763.261694437368;4799.6400269979895;4799.664023518355;4824.517548245148;4827.637927155477;4803.639727020487;4829.203181475053;4834.879128021792;4913.656044076916;4922.876928076791;5163.7159956202395;5348.786048558092;5378.112611419111;5406.675599464044;5419;5567;5614.803481878155;5633.211350410946;5670.149477578355;5720.799772008001;5691.430856914317;5698.40166782486;5732.455416735445;5735.369109397963;5740.9712951435395;5032.823851165192;5245.239440281214;5378.0857254266475;5378.1663842104535;5384.434634363413;5771.307443106854;5840.88318233633;5890.204822349014;5895.35151133375;5900.173975643327;5601.6358936668985;5602.467765562265;5605.80379686709;5651.858703532363;5660.518855897212;5505.146702261076;5536.695481748542;5683.2895888014;5735.1684005818925;5740.13897915316;5522.502974732293;5605.159226116034;5623.128415095667;5628.015097358028;5632.549396048306;5514.724263786795;5566.109422492463;5572.247746554244;5574.331080270313;5576.498115169654;4300.247456695066;4303.569643035734;4307.806148723315;4308.073764140662;4342.283523218697;3809.695224382043;3833.386658134685;3850.306944749916;3881.7865017423746;3903.531576210816;5312.814051508256;5315.787368505294;5323.547498462596;5365.3293338332705;5368.409474957752;5692.544596432275;5693.060644993612;5696.088625819849;5700.97149514258;5704.3154821421695;5184.740762961912;5285.392179899284;5341.332333458317;5346.064438723208;5349.839504814861;4905.730184839868;4910.631702622246;4912.459629440758;4914.8034078637165;4948.455669876311;5811.767529298781;5859.707014649165;5860.970695146582;5870.7945221917025;5908.4091590841435;5473.808416705396;5583.1904373864745;5622;5640.153976903497;5640.351359593699;5883;5886.470217680429;5886.646801191942;5897.174395584536;5934.6142500737315;5553.944460555343;5595;5598.804041858515;5600.635958662674;5602.299712535933;5428.592855535771;5624.465675760796;5660.801871934463;5664.37691853904;5679.971600141973;5561;5563.749631266522;5565.332160140647;5567.192757050124;5608.6073974821375;5509.146082357241;5530.281063461724;5535.640183388067;5537.169424586345;5549.056660367828;5282.022825777243;5361.35663720356;5419.10584753527;5431.56547476193;5540.529055030368;5493.560515158858;5541.972290138524;5568.220449137043;5576.414476480073;5584.692841893735;5401.243825864461;5414.079606466831;5424.972875135599;5427.077396842626;5427.565794736491 08:31:24 INFO - PROCESS | 27314 | |1;ext.html;566.0944904409914;585.0434539527872;597.0715537339436;608.8112685067628;610.825914614335;32915.177120572;35329.82335088327;35704;35837.820810895944;44282.55717442824;44759;45198.09603807922;45233.321500177524;45306.09387812242;45328.546714532844;9055.773605659846;9207.631694732203;9218.400803947756;9218.677346292881;9222.40054396462;8333.458325208841;8545.444546104483;9099.22656574192;9171.816563668723;9214.539273036355;10815.18886083547;11204.775904481905;11207.103431725483;11582.768344633103;11588.536458541648;10994.835077473845;11049.17131215162;11061.612843550438;11073.723156921142;11073.889261107406;10856.077233435168;10916.454177291103;10938.960798724189;10940.726481837939;10941.398223097727;43498.347524787154;43895.78052109751;43896.12207755827;43898;43924;3590.2460483298537;3615.566132064143;3738.345789486829;4085.428040074392;4390.27560452525;31900.20249493758;33636.56269283578;34402.311953760785;34654.653453465646;34716.13209669753;4356.629686476654;4372.068500713745;4396.296592545177;4417.823287068513;4426.44669416323;375006;375723;375870;376399;377279.99999999726;59349;60323.69838150781;60624.696876515336;60785.392146078855;60853;66329.33670663231;66434.33565664281;66447.33552664508;66510.33489665041;66524.66737666377;15897.602559935864;16142.35430582787;16142.919285403497;16155.75766363482;16170;10818.67543973682;10851.782964340828;10858.945705271422;10872.238943273895;11092.223544351978;17386.65226695459;19099.331523396893;19378.22348153323;19516.90241548783;20467.4883127923;7063.470239732041;7355.77932662021;7389.371903388164;7433.479656423999;7513.4740568159705;955.3551352836807;1033.0908800255727;1055.4325043480821;1062.1980404794485;1130.852185032193;32740;32782.672173278435;32981.835090824396;33584.160395989806;33611;1087.6138970665459;1127.3179726265491;1143.274020996673;1164.7379339648512;1180.2446434282067;16575.171241437878;17063.232154553076;17266.309347625953;17341.913290433466;17348.826511734973;157820;598156;608355;610234;972830;52300.47699522956;53232.20151697728;53325.2001219982;58497.415025850045;61075.86234481772;1342.9529966451128;1380.178793617796;1383.2737812648238;1387.66695992963;1400.1878910232042;31266.53100203453;34582.48126278057;34726.13184670403;34815.303693926486;35058.12446411873;54984.725076374365;55879;56190.719046404505;56224.43775562192;56227.43772562222;517812;526020;534651;535076;539340;43116.568834311256;43197.56802431936;43526;43636.56363436452;44105.11789764123;47440.52559474361;47591.76204119096;47728;47880;49218.75390623024;40439.1912161761;40742.18515629612;40833;40976.590234097275;42883.7855810719;40073.79963100166;40953.18093638051;41110.588894110675;41512.73527132679;42102.578974209864;39157.41263881101;40381.596184037786;41047.58952410557;41108.79445602753;41202.587974121074;32454.83772581122;35177.29645407027;35294.64705353016;36298.8185059073;36493;27117.186484405498;27364.72635273622;27672.308192295353;27801.887924482795;27895.860520697268 08:31:24 INFO - PROCESS | 27314 | |2;jquery.html;358.44262172322027;365.7531166462637;382.0734718308103;382.2250461648433;403.68310875962374;14878.628034299149;18734.437966860984;18807.811921880777;19185.32851350203;20505.17979280827;18146.5463363416;19698;19723.1124599393;19808.207671693115;19832.206711731513;5718.456746609076;5924.763009479616;5933.080372542263;5936.050231962887;5940.287165540141;5799.681017544033;5828.766849326022;5840;5857.619254748449;5879.823605291826;5662.858428539278;5808.273965754281;5809.6804675742815;5813.912791308113;5832.445917637818;5680.715964201815;5752.942470575283;5763.5101016413655;5801.59388842779;5848.853778655525;5050;5222.712750798704;5228.816991405321;5228.947710522884;5228.947710522884;23929.162479313316;24295.514089718108;24385.63421548678;24470.021199152012;24765.399633038633;2489.8630575318352;2550.1074623881677;2697.8381297122237;2700.405910699643;2875.511163102257;14836.851631483654;15184.392624295015;15230;15269.694606107816;15540.61148471286;1912.4262721183588;1922.5674223299786;1924.3553409607798;1966.3511041356462;1997.7902320256455;79074.60462697649;79101.20898790997;81172.59413702953;81218.18781812166;81998.59000705018;10265.281430299956;10496.370217786956;10501.264911456125;10858.891411085946;11190.880911908744;14569.198694071716;14604.634884127769;14627.634309142144;14629.561113166623;14639.926800366144;7806.998348882973;7939.126696063428;8025.117237103914;8207.712730054538;8301.750947471586;8029.959850200712;8060.274575288135;8216.630251638691;8273.669053237803;8291.129431409736;6514.1531600891585;6580.177477815273;6621.172353455818;6633.933660663428;6680.198376194888;1870.9345172918881;1919.865609407356;1937.350987419202;1938.821838937093;1949.2495389275232;920;987.086944576264;1012.0537297626743;1030.618671091701;1093.1091160704;19830.70253946192;20908.79091209099;21018.369448916557;21034.975089110256;21113.366599002056;1035.0747609446423;1036.259074761549;1063.4310643805682;1065.3980501017056;1108.8780234174235;11171.608993685182;11781.710885048895;11858.703532411586;11880.168388212742;12024.45889934955;435299;462652;481562;493768;496347;82114.58942705368;85241;85571;86255.568722156;86563.56718216494;1006.808706345792;1008.5864795433837;1030.340582027503;1034.4000479721744;1037.3464717228098;17342.479725608253;17986.863021504865;18419.53951151232;18541.536461588566;18861.622767544846;27226.183214503595;33685.83157084199;33777.493337599466;39848;42631.573684264;55089.72455137859;57149.428505716074;57151.42848571461;57575;57964.420355795904;7914.841703166019;8487.236148746642;8506.872396914176;8717.782055448411;8877.28981681451;13219.603411897659;13490.32548372597;13723.588292351245;13824.723505529631;14099.929500352433;12057.276563406223;12171.573994910135;12309.93845030769;12427.502899884264;12514.624561263177;11837.171398002058;11885.524579017088;12080.75838483243;12462.563810266598;12630.179038362478;12191.512339506675;12382.133250672367;12482.687932801753;12484.87515124837;12614.55849045279;7937.563434010954;8010.279074883289;8203.753887383387;8226.095129535626;8252.83494330098;10394.116500097576;11414.48634811419;11941.343226122646;12366.381680916098;12601.11792174539 08:31:24 INFO - PROCESS | 27314 | |3;mootools.html;1356.6133651909208;1483.5030264861268;1530.6249968757652;1661.8670506359492;1801.4415531185325;8126.10612832588;8411.789705257364;8454.746357609274;8476.745697629081;8779.736607901774;8030.116687164422;8129.674813007473;8348;8355.707550235744;8381.413301068913;2384.809215262779;2425.3815277104313;2426.259990702835;2429.975700242993;2431.963520547193;2413.9758602413926;2436.914707985221;2440.1459489178824;2446.2905757330354;2446.376174075617;2329.1615018593293;2333.0667732906804;2365.976340236593;2399.8680072596;2399.8800059997016;2358.0449917783153;2375.762423757628;2376.6791483149727;2382.0233704181287;2406.855588664686;2249.5051088760447;2250.909963601454;2305.170138750049;2317.5480781247666;2327.767223277676;8490.23587877094;8668.39321247513;8783.736487905373;8797.7800554986;8804.339674524435;5082.517160869711;5242.606804489678;5269.420363759983;5291.259223708684;5305;3447.1382154461385;3495.038864312309;3527.488514165457;3530.929381412383;3548.254866478043;1172.150191111446;1180.4746887635006;1187.4122309456789;1222.0895432902455;1230.95076196952;12299.754004919942;12450.875491245062;12491.875081249253;12501.812472812824;12512.311822849739;4419.204543182258;4691.507391723888;4708.552687494683;4866.562009419098;4878.0975519529;10515.158787296998;10612.840807387895;10629.4153821539;10631.734206644742;10652.946735266429;5223.52988231061;5224.608154388436;5254.448282930313;5272.130098533699;5288.524032837063;4314.158739045919;4654.138984287918;4799.904001919942;4802.7838747255755;4837.564619184291;3983.103801644607;4083.2854250506043;4111.527174374985;4148;4188.246115699142;1228.3244215681339;1234.5802427174624;1248.918820276679;1254.5483625894672;1273.3187744556803;869.6477926439802;980.3970558106713;1005.3816902604846;1013.2552573858209;1014.4674046125843;5871.089981052944;5994.520438365008;6037.758489660365;6149.108379284979;6166.414190651881;483.3692031898406;485.36902027364283;492.39927288708435;493.0557981465525;493.7629937629958;4022.8390864365097;4296.334068219431;4357.43353364067;4424.911501769947;4450.287953927356;10526.684199474028;10705.250632455809;11096.05683516894;11163.776724465466;11170.385628790333;10876.347419154717;11101.777964440666;11145.052670523097;11159.832602510969;11161.218714689894;8108.878366824502;8304.211099945496;8315.126911674308;8353.039400469024;8357.58212089393;6034.310393089766;6219.253689557282;6346.492280617632;6352.714127864165;6383.170187875547;10033.598656053677;10271.94864025675;10298.691039268833;10384.169266458815;10445.9477702611;10386.480675966322;10562.735931601772;10653.360798352123;10664.680059598224;10666.200034997251;6898.275681053417;6902.758403455855;7027.578345299298;7739.148693643808;7874.21257874208;7742.883856742041;8060.11338752725;8728.476291422536;8774.561271936505;8781.604827782892;7718.15100338951;7744.8451030980195;7748.612569371621;7769.689212431439;7959.880601790862;7184.066071410683;7369.668364923484;7555.962220188864;7586;7652.158262591;6963.895541566982;7144.535605185646;7203.747868824566;7204.747833825791;7224.963875180591;5134.537891589625;5182.067227898863;5278.656887302313;5326.547243484347;5343.225232341367;5075.974620127024;5131.536862306039;5143.254228136974;5278.81524146653;5380.166074258496 08:31:24 INFO - PROCESS | 27314 | |4;prototype.html;321.3845485894512;331.4613752651941;338.83058470764615;339.7621664834616;339.76895710916574;5214.826664000603;5244.160934250521;5251.763670634826;5270.947290527093;5348.358197016354;4928.2361234008695;4986.058225646868;5101.668391554546;5115.437301896798;5278.445763194867;4100.821863056126;4107;4116.938245926313;4145.378193271018;4152.211079894825;4107.486564179478;4121.5672354402805;4133.049398638315;4143.70994030418;4158.916821663565;3908.2965066288057;3938.448617193595;4065.9593404065877;4097.119119389331;4105.835766569334;4090.263752524574;4092.3221479193685;4092.570280120589;4095.672346212296;4096.365063415175;3633.5465813674477;3640.908977275563;3650.5254316938626;3660.4509323601405;3660.5424321959754;4988.900221995576;5021.271915572258;5043.420006699203;5103.515166059218;5104.923426148611;4635.049814787994;4644.7213167210075;4663.417072865892;4667.766611669437;4685.1566717990745;5338.439463856317;5368.4631536846;5401.918971215435;5408.107662235727;5429.642589352662;3703.4445532876257;3906.2968665640174;4008.579099194572;4013.6387725104587;4014.3175660137845;5593.916091258634;5605.32736071674;5612;5627.493525582676;5630.239917611111;4012.4583181270154;4017.8995525112105;4023.3160362738404;4025.5370632377067;4027.375756757707;3902.3951287550476;4075.2664520386024;4084.7957602119777;4086.775227362464;4088.12105397341;4580.129775342674;4595.793189306489;4597.7011494252765;4608.354830323758;4623.8844028898875;5317.361916570037;5392.91910621341;5396.595255355785;5401.5948803839865;5478.753456094485;1372.8695773901463;1384.7507448659337;1393.4008376398247;1394.8326200855963;1417.9220142892034;930.5393830054167;969.1907257439975;974.5858010345574;981.749653838281;985.8274801909637;5228.686278823283;5236.329471969247;5265.052290587731;5275.261463395128;5281.494773989394;5522.144067669517;5527.613067085265;5556.833295001156;5566.582506312043;5588.189712491666;5137.17805151174;5163.483651634881;5215.217717342429;5220.347456567929;5269;2901.4912245632154;2902.4775540402507;2923.678395376507;2923.956140657892;2928.31184671602;5613.972991972336;5686.488216060575;5711.771529138788;5720.170575266647;5757.625754325955;5581.888362232733;5593.496585307342;5616.466435688603;5617.157426386075;5632.690202038845;5710.7430165642645;5726.942730572724;5741.483266506035;5790.855228619318;5795.507381872504;5422.864428389322;5431.891362172655;5452.255278310902;5501.669899806025;5572.442755724395;5377.865553361197;5391.218273350264;5410.621256512007;5417.864553386197;5420.428075857957;5578.693171875586;5583.330000399816;5743.942560574341;5747.885042299047;5752.884942301047;3947.795922243743;3954.339177545495;3959.1685745993664;3959.7228194027293;3973.92052158961;5247.737613119252;5420;5435.592330575302;5462.617616766788;5558.666480011212;5425.969065877471;5427.3215848019;5452.7546260417585;5477.343488408039;5481.205225242238;5325.227841962971;5367.758450869642;5389.83830485086;5440.945590544044;5453.972730136324;5117.462666419972;5305.098133317269;5306.787728490817;5315.202719591937;5377.354717433933;5401.567874570103;5406.540444062299;5445.891082178414;5447.373552041566;5487.29893732944;5063.13926632466;5277.8416647500635;5345.465453454779;5409.7024663642305;5432.185172224198 08:31:24 INFO - PROCESS | 27314 | |5;yui.html;697.2574208467983;742;755.3207877200743;756.8599809035326;883.4699180491704;15673.52979410618;16883.24025418856;16969.91515042423;16990.065546394944;16995.23521441535;14910.40358385666;15022.399104035825;15042.097474151558;15072.547823565283;15808.604784880385;810.6959890041235;824.1593574553951;832.4630613254448;832.8750687396879;832.9583520823965;674.578388507183;799.6401619271322;838.5681374092347;841.5834162089774;842.1999100854182;860.2988564320091;877.0746862060523;877.5217506458995;878.3017501086651;878.4553576782404;874.6501399440211;874.6545114679698;874.6719980007497;874.9300055995537;875.2691502595341;514.976826042829;615.4983688294026;639.6929473852576;640.0814830717935;640.452413186728;14012.579622611236;14334;14382.640433989234;14386.640333991629;14393.928030359886;14444.422223111063;14913.2410504056;15036.17301048442;15037.774433383507;15271.312790924436;13837.377318020612;13912.791308130387;14023.368948397347;14083.647908802259;14542.709145817025;4478.820847166109;4483;4525.456945166601;4616.69991450558;4639.420072490939;87412.5629371849;87535;87978.5601071997;88108;90297.54851225768;11178.888211117948;11208.943955280172;11252.26860254081;11257.718557035976;11525.366104864313;21822.45443863916;21838.45403864916;21897.12411503522;21918.671219931715;21935.451613709785;8133.349332053422;8200.5489698066;8276.627551760184;8292.33661307094;8348.68564410376;4443.755593442392;4600.95399046012;4617.653675974316;4765.237561990065;4885.047415753965;3575.982120089383;3725.4970578972034;3726.869559565402;3729.4778730977687;3730;602.3255350126655;612.2438788096697;619.294004834489;641.9614823110628;648.8215740671259;717.2397258905577;724.663031690256;728.4572993120054;729.1869565434608;754.6490881740017;8582.934684673493;9121.45271283725;9124.224440922464;9177.908220917705;9340.4862732549;663.6316844151446;669.2704951602776;685.095673710697;685.1914740606132;692.4010730717893;4262.211490874198;4281.850135245251;4516.751578663205;4537.659675524349;4567.223571992769;73756.63121684357;110922.44538777255;132328;133132.33433832962;136865;84964;86394;98658.50670746744;116257.4187129059;117710.41144794221;53508;54390.728046359516;87050;89511.55244223737;90123.54938225266;8831.735047948572;8867.866981995405;9213.585388657393;9296.535173241446;9388.530573471437;50616.493835062654;52062;52925;53151;53197.46802531925;72612;78343.6082819605;79952;80048;80476;11945.761084778429;12008.63974080779;12298.631041068782;13332;13420.530281440102;13408.671517888348;13710.725785484434;14134.929325353307;14153;14439.133651980916;13383.19700817954;13523.188608683511;13569.321533923066;13827.377768000262;14302.785458218343;8305.086440491661;12012.879871201176;12568.183068100545;13662.453501860213;13723.656908577366;13128.474861005452;13357.732845343233;13385.732285354434;13432;14283.42866285337;10807.894841034182;11163.665090047312;11386.202965792647;11514.827277590675;11987.580434684744;10667.306625069346;10686.412247326161;10712.339587363971;10796.946015269874;10851.837222441827 08:31:24 INFO - PROCESS | 27314 | __end_tp_report 08:31:24 INFO - PROCESS | 27314 | __start_cc_report 08:31:24 INFO - PROCESS | 27314 | _x_x_mozilla_cycle_collect,853 08:31:24 INFO - PROCESS | 27314 | __end_cc_report 08:31:24 INFO - PROCESS | 27314 | __startTimestamp1470497484120__endTimestamp 08:31:24 INFO - PROCESS | 27314 | 08:31:24 INFO - PROCESS | 27314 | ------- Summary: start ------- 08:31:24 INFO - PROCESS | 27314 | Number of tests: 6 08:31:24 INFO - PROCESS | 27314 | 08:31:24 INFO - PROCESS | 27314 | [#0] dojo.html Cycles:165 Average:5262.04 Median:5427.57 stddev:589.58 (10.9%) stddev-sans-first:565.68 08:31:24 INFO - PROCESS | 27314 | Values: 3067.1 3089.2 3113.5 3116.0 3128.6 5199.3 5241.4 5309.7 5369.7 5372.6 5195.7 5237.5 5240.9 5242.2 5308.7 4724.7 4807.6 4808.8 4818.6 4972.7 4921.8 4924.7 4926.1 4945.5 4958.2 4730.0 4759.7 4877.8 4906.8 4925.2 4763.3 4799.6 4799.7 4824.5 4827.6 4803.6 4829.2 4834.9 4913.7 4922.9 5163.7 5348.8 5378.1 5406.7 5419.0 5567.0 5614.8 5633.2 5670.1 5720.8 5691.4 5698.4 5732.5 5735.4 5741.0 5032.8 5245.2 5378.1 5378.2 5384.4 5771.3 5840.9 5890.2 5895.4 5900.2 5601.6 5602.5 5605.8 5651.9 5660.5 5505.1 5536.7 5683.3 5735.2 5740.1 5522.5 5605.2 5623.1 5628.0 5632.5 5514.7 5566.1 5572.2 5574.3 5576.5 4300.2 4303.6 4307.8 4308.1 4342.3 3809.7 3833.4 3850.3 3881.8 3903.5 5312.8 5315.8 5323.5 5365.3 5368.4 5692.5 5693.1 5696.1 5701.0 5704.3 5184.7 5285.4 5341.3 5346.1 5349.8 4905.7 4910.6 4912.5 4914.8 4948.5 5811.8 5859.7 5861.0 5870.8 5908.4 5473.8 5583.2 5622.0 5640.2 5640.4 5883.0 5886.5 5886.6 5897.2 5934.6 5553.9 5595.0 5598.8 5600.6 5602.3 5428.6 5624.5 5660.8 5664.4 5680.0 5561.0 5563.7 5565.3 5567.2 5608.6 5509.1 5530.3 5535.6 5537.2 5549.1 5282.0 5361.4 5419.1 5431.6 5540.5 5493.6 5542.0 5568.2 5576.4 5584.7 5401.2 5414.1 5425.0 5427.1 5427.6 08:31:24 INFO - PROCESS | 27314 | 08:31:24 INFO - PROCESS | 27314 | [#1] ext.html Cycles:180 Average:65914.55 Median:40590.69 stddev:141503.74 (348.6%) stddev-sans-first:141815.63 08:31:24 INFO - PROCESS | 27314 | Values: 566.1 585.0 597.1 608.8 610.8 32915.2 35329.8 35704.0 35837.8 44282.6 44759.0 45198.1 45233.3 45306.1 45328.5 9055.8 9207.6 9218.4 9218.7 9222.4 8333.5 8545.4 9099.2 9171.8 9214.5 10815.2 11204.8 11207.1 11582.8 11588.5 10994.8 11049.2 11061.6 11073.7 11073.9 10856.1 10916.5 10939.0 10940.7 10941.4 43498.3 43895.8 43896.1 43898.0 43924.0 3590.2 3615.6 3738.3 4085.4 4390.3 31900.2 33636.6 34402.3 34654.7 34716.1 4356.6 4372.1 4396.3 4417.8 4426.4 375006.0 375723.0 375870.0 376399.0 377280.0 59349.0 60323.7 60624.7 60785.4 60853.0 66329.3 66434.3 66447.3 66510.3 66524.7 15897.6 16142.4 16142.9 16155.8 16170.0 10818.7 10851.8 10858.9 10872.2 11092.2 17386.7 19099.3 19378.2 19516.9 20467.5 7063.5 7355.8 7389.4 7433.5 7513.5 955.4 1033.1 1055.4 1062.2 1130.9 32740.0 32782.7 32981.8 33584.2 33611.0 1087.6 1127.3 1143.3 1164.7 1180.2 16575.2 17063.2 17266.3 17341.9 17348.8 157820.0 598156.0 608355.0 610234.0 972830.0 52300.5 53232.2 53325.2 58497.4 61075.9 1343.0 1380.2 1383.3 1387.7 1400.2 31266.5 34582.5 34726.1 34815.3 35058.1 54984.7 55879.0 56190.7 56224.4 56227.4 517812.0 526020.0 534651.0 535076.0 539340.0 43116.6 43197.6 43526.0 43636.6 44105.1 47440.5 47591.8 47728.0 47880.0 49218.8 40439.2 40742.2 40833.0 40976.6 42883.8 40073.8 40953.2 41110.6 41512.7 42102.6 39157.4 40381.6 41047.6 41108.8 41202.6 32454.8 35177.3 35294.6 36298.8 36493.0 27117.2 27364.7 27672.3 27801.9 27895.9 08:31:24 INFO - PROCESS | 27314 | 08:31:24 INFO - PROCESS | 27314 | [#2] jquery.html Cycles:180 Average:28493.09 Median:24427.83 stddev:78072.77 (319.6%) stddev-sans-first:78263.21 08:31:24 INFO - PROCESS | 27314 | Values: 358.4 365.8 382.1 382.2 403.7 14878.6 18734.4 18807.8 19185.3 20505.2 18146.5 19698.0 19723.1 19808.2 19832.2 5718.5 5924.8 5933.1 5936.1 5940.3 5799.7 5828.8 5840.0 5857.6 5879.8 5662.9 5808.3 5809.7 5813.9 5832.4 5680.7 5752.9 5763.5 5801.6 5848.9 5050.0 5222.7 5228.8 5228.9 5228.9 23929.2 24295.5 24385.6 24470.0 24765.4 2489.9 2550.1 2697.8 2700.4 2875.5 14836.9 15184.4 15230.0 15269.7 15540.6 1912.4 1922.6 1924.4 1966.4 1997.8 79074.6 79101.2 81172.6 81218.2 81998.6 10265.3 10496.4 10501.3 10858.9 11190.9 14569.2 14604.6 14627.6 14629.6 14639.9 7807.0 7939.1 8025.1 8207.7 8301.8 8030.0 8060.3 8216.6 8273.7 8291.1 6514.2 6580.2 6621.2 6633.9 6680.2 1870.9 1919.9 1937.4 1938.8 1949.2 920.0 987.1 1012.1 1030.6 1093.1 19830.7 20908.8 21018.4 21035.0 21113.4 1035.1 1036.3 1063.4 1065.4 1108.9 11171.6 11781.7 11858.7 11880.2 12024.5 435299.0 462652.0 481562.0 493768.0 496347.0 82114.6 85241.0 85571.0 86255.6 86563.6 1006.8 1008.6 1030.3 1034.4 1037.3 17342.5 17986.9 18419.5 18541.5 18861.6 27226.2 33685.8 33777.5 39848.0 42631.6 55089.7 57149.4 57151.4 57575.0 57964.4 7914.8 8487.2 8506.9 8717.8 8877.3 13219.6 13490.3 13723.6 13824.7 14099.9 12057.3 12171.6 12309.9 12427.5 12514.6 11837.2 11885.5 12080.8 12462.6 12630.2 12191.5 12382.1 12482.7 12484.9 12614.6 7937.6 8010.3 8203.8 8226.1 8252.8 10394.1 11414.5 11941.3 12366.4 12601.1 08:31:24 INFO - PROCESS | 27314 | 08:31:24 INFO - PROCESS | 27314 | [#3] mootools.html Cycles:180 Average:5825.22 Median:4672.82 stddev:3321.47 (71.1%) stddev-sans-first:3313.81 08:31:24 INFO - PROCESS | 27314 | Values: 1356.6 1483.5 1530.6 1661.9 1801.4 8126.1 8411.8 8454.7 8476.7 8779.7 8030.1 8129.7 8348.0 8355.7 8381.4 2384.8 2425.4 2426.3 2430.0 2432.0 2414.0 2436.9 2440.1 2446.3 2446.4 2329.2 2333.1 2366.0 2399.9 2399.9 2358.0 2375.8 2376.7 2382.0 2406.9 2249.5 2250.9 2305.2 2317.5 2327.8 8490.2 8668.4 8783.7 8797.8 8804.3 5082.5 5242.6 5269.4 5291.3 5305.0 3447.1 3495.0 3527.5 3530.9 3548.3 1172.2 1180.5 1187.4 1222.1 1231.0 12299.8 12450.9 12491.9 12501.8 12512.3 4419.2 4691.5 4708.6 4866.6 4878.1 10515.2 10612.8 10629.4 10631.7 10652.9 5223.5 5224.6 5254.4 5272.1 5288.5 4314.2 4654.1 4799.9 4802.8 4837.6 3983.1 4083.3 4111.5 4148.0 4188.2 1228.3 1234.6 1248.9 1254.5 1273.3 869.6 980.4 1005.4 1013.3 1014.5 5871.1 5994.5 6037.8 6149.1 6166.4 483.4 485.4 492.4 493.1 493.8 4022.8 4296.3 4357.4 4424.9 4450.3 10526.7 10705.3 11096.1 11163.8 11170.4 10876.3 11101.8 11145.1 11159.8 11161.2 8108.9 8304.2 8315.1 8353.0 8357.6 6034.3 6219.3 6346.5 6352.7 6383.2 10033.6 10271.9 10298.7 10384.2 10445.9 10386.5 10562.7 10653.4 10664.7 10666.2 6898.3 6902.8 7027.6 7739.1 7874.2 7742.9 8060.1 8728.5 8774.6 8781.6 7718.2 7744.8 7748.6 7769.7 7959.9 7184.1 7369.7 7556.0 7586.0 7652.2 6963.9 7144.5 7203.7 7204.7 7225.0 5134.5 5182.1 5278.7 5326.5 5343.2 5076.0 5131.5 5143.3 5278.8 5380.2 08:31:24 INFO - PROCESS | 27314 | 08:31:24 INFO - PROCESS | 27314 | [#4] prototype.html Cycles:180 Average:4571.82 Median:5258.41 stddev:1323.83 (25.2%) stddev-sans-first:1288.53 08:31:24 INFO - PROCESS | 27314 | Values: 321.4 331.5 338.8 339.8 339.8 5214.8 5244.2 5251.8 5270.9 5348.4 4928.2 4986.1 5101.7 5115.4 5278.4 4100.8 4107.0 4116.9 4145.4 4152.2 4107.5 4121.6 4133.0 4143.7 4158.9 3908.3 3938.4 4066.0 4097.1 4105.8 4090.3 4092.3 4092.6 4095.7 4096.4 3633.5 3640.9 3650.5 3660.5 3660.5 4988.9 5021.3 5043.4 5103.5 5104.9 4635.0 4644.7 4663.4 4667.8 4685.2 5338.4 5368.5 5401.9 5408.1 5429.6 3703.4 3906.3 4008.6 4013.6 4014.3 5593.9 5605.3 5612.0 5627.5 5630.2 4012.5 4017.9 4023.3 4025.5 4027.4 3902.4 4075.3 4084.8 4086.8 4088.1 4580.1 4595.8 4597.7 4608.4 4623.9 5317.4 5392.9 5396.6 5401.6 5478.8 1372.9 1384.8 1393.4 1394.8 1417.9 930.5 969.2 974.6 981.7 985.8 5228.7 5236.3 5265.1 5275.3 5281.5 5522.1 5527.6 5556.8 5566.6 5588.2 5137.2 5163.5 5215.2 5220.3 5269.0 2901.5 2902.5 2923.7 2924.0 2928.3 5614.0 5686.5 5711.8 5720.2 5757.6 5581.9 5593.5 5616.5 5617.2 5632.7 5710.7 5726.9 5741.5 5790.9 5795.5 5422.9 5431.9 5452.3 5501.7 5572.4 5377.9 5391.2 5410.6 5417.9 5420.4 5578.7 5583.3 5743.9 5747.9 5752.9 3947.8 3954.3 3959.2 3959.7 3973.9 5247.7 5420.0 5435.6 5462.6 5558.7 5426.0 5427.3 5452.8 5477.3 5481.2 5325.2 5367.8 5389.8 5440.9 5454.0 5117.5 5305.1 5306.8 5315.2 5377.4 5401.6 5406.5 5445.9 5447.4 5487.3 5063.1 5277.8 5345.5 5409.7 5432.2 08:31:24 INFO - PROCESS | 27314 | 08:31:24 INFO - PROCESS | 27314 | [#5] yui.html Cycles:180 Average:21082.44 Median:4617.18 stddev:31023.97 (671.9%) stddev-sans-first:31073.25 08:31:24 INFO - PROCESS | 27314 | Values: 697.3 742.0 755.3 756.9 883.5 15673.5 16883.2 16969.9 16990.1 16995.2 14910.4 15022.4 15042.1 15072.5 15808.6 810.7 824.2 832.5 832.9 833.0 674.6 799.6 838.6 841.6 842.2 860.3 877.1 877.5 878.3 878.5 874.7 874.7 874.7 874.9 875.3 515.0 615.5 639.7 640.1 640.5 14012.6 14334.0 14382.6 14386.6 14393.9 14444.4 14913.2 15036.2 15037.8 15271.3 13837.4 13912.8 14023.4 14083.6 14542.7 4478.8 4483.0 4525.5 4616.7 4639.4 87412.6 87535.0 87978.6 88108.0 90297.5 11178.9 11208.9 11252.3 11257.7 11525.4 21822.5 21838.5 21897.1 21918.7 21935.5 8133.3 8200.5 8276.6 8292.3 8348.7 4443.8 4601.0 4617.7 4765.2 4885.0 3576.0 3725.5 3726.9 3729.5 3730.0 602.3 612.2 619.3 642.0 648.8 717.2 724.7 728.5 729.2 754.6 8582.9 9121.5 9124.2 9177.9 9340.5 663.6 669.3 685.1 685.2 692.4 4262.2 4281.9 4516.8 4537.7 4567.2 73756.6 110922.4 132328.0 133132.3 136865.0 84964.0 86394.0 98658.5 116257.4 117710.4 53508.0 54390.7 87050.0 89511.6 90123.5 8831.7 8867.9 9213.6 9296.5 9388.5 50616.5 52062.0 52925.0 53151.0 53197.5 72612.0 78343.6 79952.0 80048.0 80476.0 11945.8 12008.6 12298.6 13332.0 13420.5 13408.7 13710.7 14134.9 14153.0 14439.1 13383.2 13523.2 13569.3 13827.4 14302.8 8305.1 12012.9 12568.2 13662.5 13723.7 13128.5 13357.7 13385.7 13432.0 14283.4 10807.9 11163.7 11386.2 11514.8 11987.6 10667.3 10686.4 10712.3 10796.9 10851.8 08:31:24 INFO - PROCESS | 27314 | -------- Summary: end -------- 08:31:24 INFO - PROCESS | 27314 | 08:31:24 INFO - TEST-INFO | 27314: exit 0 08:31:24 INFO - TEST-OK | dromaeo_css | took 1116421ms 08:31:24 INFO - TEST-START | kraken 08:31:24 INFO - operating with platform_type : linux_ 08:31:24 INFO - Initialising browser for kraken test... 08:31:24 INFO - TEST-INFO | started process 27515 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYIytOo/profile http://localhost:35831/getInfo.html) 08:31:29 INFO - PROCESS | 27515 | __metrics Screen width/height:1600/1200 08:31:29 INFO - PROCESS | 27515 | colorDepth:24 08:31:29 INFO - PROCESS | 27515 | Browser inner width/height: 1024/697 08:31:29 INFO - PROCESS | 27515 | __metrics 08:31:30 INFO - PROCESS | 27515 | 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" 08:31:30 INFO - PROCESS | 27515 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:31:30 INFO - PROCESS | 27515 | console.error: PushService: 08:31:30 INFO - PROCESS | 27515 | stateChangeProcessEnqueue: Error transitioning state 08:31:30 INFO - PROCESS | 27515 | UnknownError 08:31:31 INFO - TEST-INFO | 27515: exit 0 08:31:31 INFO - Browser initialized. 08:31:31 INFO - Running cycle 1/1 for kraken test... 08:31:31 INFO - TEST-INFO | started process 27594 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYIytOo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:43 INFO - PROCESS | 27594 | RSS: Main: 156389376 08:31:43 INFO - PROCESS | 27594 | 08:31:45 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35831/tests/kraken/driver.html?testName=audio-beat-detection) 08:31:46 INFO - PROCESS | 27594 | RSS: Main: 212504576 08:31:46 INFO - PROCESS | 27594 | 08:31:49 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35831/tests/kraken/driver.html?testName=audio-dft) 08:31:49 INFO - PROCESS | 27594 | RSS: Main: 192249856 08:31:49 INFO - PROCESS | 27594 | 08:31:52 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35831/tests/kraken/driver.html?testName=audio-fft) 08:31:53 INFO - PROCESS | 27594 | RSS: Main: 270180352 08:31:53 INFO - PROCESS | 27594 | 08:31:55 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35831/tests/kraken/driver.html?testName=audio-oscillator) 08:31:56 INFO - PROCESS | 27594 | RSS: Main: 179261440 08:31:56 INFO - PROCESS | 27594 | 08:31:58 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35831/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:31:58 INFO - PROCESS | 27594 | RSS: Main: 184229888 08:31:58 INFO - PROCESS | 27594 | 08:32:02 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35831/tests/kraken/driver.html?testName=imaging-darkroom) 08:32:02 INFO - PROCESS | 27594 | RSS: Main: 221143040 08:32:02 INFO - PROCESS | 27594 | 08:32:06 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35831/tests/kraken/driver.html?testName=imaging-desaturate) 08:32:06 INFO - PROCESS | 27594 | RSS: Main: 220418048 08:32:06 INFO - PROCESS | 27594 | 08:32:09 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35831/tests/kraken/driver.html?testName=json-parse-financial) 08:32:10 INFO - PROCESS | 27594 | RSS: Main: 219828224 08:32:10 INFO - PROCESS | 27594 | 08:32:11 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35831/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:32:12 INFO - PROCESS | 27594 | RSS: Main: 184041472 08:32:12 INFO - PROCESS | 27594 | 08:32:13 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35831/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:32:14 INFO - PROCESS | 27594 | RSS: Main: 190537728 08:32:14 INFO - PROCESS | 27594 | 08:32:16 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35831/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:32:17 INFO - PROCESS | 27594 | RSS: Main: 194514944 08:32:17 INFO - PROCESS | 27594 | 08:32:19 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35831/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:32:20 INFO - PROCESS | 27594 | RSS: Main: 187531264 08:32:20 INFO - PROCESS | 27594 | 08:32:22 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35831/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:32:23 INFO - PROCESS | 27594 | RSS: Main: 192331776 08:32:23 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | Cycle 1(1): loaded http://localhost:35831/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35831/tests/kraken/driver.html?testName=ai-astar) 08:32:25 INFO - PROCESS | 27594 | RSS: Main: 244006912 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | __start_tp_report 08:32:25 INFO - PROCESS | 27594 | _x_x_mozilla_page_load 08:32:25 INFO - PROCESS | 27594 | _x_x_mozilla_page_load_details 08:32:25 INFO - PROCESS | 27594 | |i|pagename|runs| 08:32:25 INFO - PROCESS | 27594 | |0;ai-astar;104;114;112;109;110;111;111;110;110;110 08:32:25 INFO - PROCESS | 27594 | |1;audio-beat-detection;148;119;120;178;118;121;154;117;155;118 08:32:25 INFO - PROCESS | 27594 | |2;audio-dft;160;161;160;160;159;160;160;161;162;162 08:32:25 INFO - PROCESS | 27594 | |3;audio-fft;74;75;73;113;76;75;74;75;98;76 08:32:25 INFO - PROCESS | 27594 | |4;audio-oscillator;83;85;128;83;83;84;130;84;85;142 08:32:25 INFO - PROCESS | 27594 | |5;imaging-gaussian-blur;116;117;116;116;117;116;117;117;117;116 08:32:25 INFO - PROCESS | 27594 | |6;imaging-darkroom;146;148;147;147;147;147;146;145;145;145 08:32:25 INFO - PROCESS | 27594 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 08:32:25 INFO - PROCESS | 27594 | |8;json-parse-financial;51;49;50;50;50;50;50;50;50;51 08:32:25 INFO - PROCESS | 27594 | |9;json-stringify-tinderbox;60;60;60;60;61;60;60;61;60;61 08:32:25 INFO - PROCESS | 27594 | |10;stanford-crypto-aes;82;77;76;79;76;75;75;79;75;75 08:32:25 INFO - PROCESS | 27594 | |11;stanford-crypto-ccm;132;120;130;126;118;127;127;118;164;125 08:32:25 INFO - PROCESS | 27594 | |12;stanford-crypto-pbkdf2;159;157;157;156;156;159;158;160;156;161 08:32:25 INFO - PROCESS | 27594 | |13;stanford-crypto-sha256-iterative;64;63;64;62;62;62;63;66;61;60 08:32:25 INFO - PROCESS | 27594 | __end_tp_report 08:32:25 INFO - PROCESS | 27594 | __start_cc_report 08:32:25 INFO - PROCESS | 27594 | _x_x_mozilla_cycle_collect,1702 08:32:25 INFO - PROCESS | 27594 | __end_cc_report 08:32:25 INFO - PROCESS | 27594 | __startTimestamp1470497545508__endTimestamp 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | ------- Summary: start ------- 08:32:25 INFO - PROCESS | 27594 | Number of tests: 14 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.50 stddev:2.56 (2.3%) stddev-sans-first:1.48 08:32:25 INFO - PROCESS | 27594 | Values: 104.0 114.0 112.0 109.0 110.0 111.0 111.0 110.0 110.0 110.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:134.50 stddev:22.00 (16.4%) stddev-sans-first:22.81 08:32:25 INFO - PROCESS | 27594 | Values: 148.0 119.0 120.0 178.0 118.0 121.0 154.0 117.0 155.0 118.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:0.97 (0.6%) stddev-sans-first:1.01 08:32:25 INFO - PROCESS | 27594 | Values: 160.0 161.0 160.0 160.0 159.0 160.0 160.0 161.0 162.0 162.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.00 stddev:13.47 (18.0%) stddev-sans-first:14.05 08:32:25 INFO - PROCESS | 27594 | Values: 74.0 75.0 73.0 113.0 76.0 75.0 74.0 75.0 98.0 76.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#4] audio-oscillator Cycles:10 Average:98.70 Median:83.50 stddev:24.18 (29.0%) stddev-sans-first:24.97 08:32:25 INFO - PROCESS | 27594 | Values: 83.0 85.0 128.0 83.0 83.0 84.0 130.0 84.0 85.0 142.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:32:25 INFO - PROCESS | 27594 | Values: 116.0 117.0 116.0 116.0 117.0 116.0 117.0 117.0 117.0 116.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:147.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 08:32:25 INFO - PROCESS | 27594 | Values: 146.0 148.0 147.0 147.0 147.0 147.0 146.0 145.0 145.0 145.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 08:32:25 INFO - PROCESS | 27594 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.57 (1.1%) stddev-sans-first:0.50 08:32:25 INFO - PROCESS | 27594 | Values: 51.0 49.0 50.0 50.0 50.0 50.0 50.0 50.0 50.0 51.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 08:32:25 INFO - PROCESS | 27594 | Values: 60.0 60.0 60.0 60.0 61.0 60.0 60.0 61.0 60.0 61.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:76.50 stddev:2.38 (3.1%) stddev-sans-first:1.66 08:32:25 INFO - PROCESS | 27594 | Values: 82.0 77.0 76.0 79.0 76.0 75.0 75.0 79.0 75.0 75.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:127.00 stddev:13.29 (10.5%) stddev-sans-first:14.04 08:32:25 INFO - PROCESS | 27594 | Values: 132.0 120.0 130.0 126.0 118.0 127.0 127.0 118.0 164.0 125.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.90 Median:158.50 stddev:1.79 (1.1%) stddev-sans-first:1.86 08:32:25 INFO - PROCESS | 27594 | Values: 159.0 157.0 157.0 156.0 156.0 159.0 158.0 160.0 156.0 161.0 08:32:25 INFO - PROCESS | 27594 | 08:32:25 INFO - PROCESS | 27594 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.70 (2.7%) stddev-sans-first:1.74 08:32:25 INFO - PROCESS | 27594 | Values: 64.0 63.0 64.0 62.0 62.0 62.0 63.0 66.0 61.0 60.0 08:32:25 INFO - PROCESS | 27594 | -------- Summary: end -------- 08:32:25 INFO - PROCESS | 27594 | 08:32:26 INFO - TEST-INFO | 27594: exit 0 08:32:26 INFO - TEST-OK | kraken | took 61559ms 08:32:26 INFO - SUITE-END | took 1177s 08:32:26 INFO - Completed test suite (00:19:38) 08:32:26 INFO - javascript benchmark 08:32:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3067.14120046387, 3089.2276930767307, 3113.548535462358, 3115.953260701089, 3128.6089238845143, 5199.272101905727, 5241.371035475739, 5309.654872433294, 5369.731513424328, 5372.570194384445, 5195.662281951671, 5237.47625237476, 5240.8689782755355, 5242.18746094355, 5308.70802105884, 4724.692894961825, 4807.61539076873, 4808.759562021903, 4818.614510839125, 4972.70163790172, 4921.7785199666105, 4924.655274130812, 4926.1379258629595, 4945.455999840015, 4958.181899986516, 4729.95270047302, 4759.714417134983, 4877.780499877496, 4906.803727850882, 4925.236588328815, 4763.261694437368, 4799.6400269979895, 4799.664023518355, 4824.517548245148, 4827.637927155477, 4803.639727020487, 4829.203181475053, 4834.879128021792, 4913.656044076916, 4922.876928076791, 5163.7159956202395, 5348.786048558092, 5378.112611419111, 5406.675599464044, 5419.0, 5567.0, 5614.803481878155, 5633.211350410946, 5670.149477578355, 5720.799772008001, 5691.430856914317, 5698.40166782486, 5732.455416735445, 5735.369109397963, 5740.9712951435395, 5032.823851165192, 5245.239440281214, 5378.0857254266475, 5378.1663842104535, 5384.434634363413, 5771.307443106854, 5840.88318233633, 5890.204822349014, 5895.35151133375, 5900.173975643327, 5601.6358936668985, 5602.467765562265, 5605.80379686709, 5651.858703532363, 5660.518855897212, 5505.146702261076, 5536.695481748542, 5683.2895888014, 5735.1684005818925, 5740.13897915316, 5522.502974732293, 5605.159226116034, 5623.128415095667, 5628.015097358028, 5632.549396048306, 5514.724263786795, 5566.109422492463, 5572.247746554244, 5574.331080270313, 5576.498115169654, 4300.247456695066, 4303.569643035734, 4307.806148723315, 4308.073764140662, 4342.283523218697, 3809.695224382043, 3833.386658134685, 3850.306944749916, 3881.7865017423746, 3903.531576210816, 5312.814051508256, 5315.787368505294, 5323.547498462596, 5365.3293338332705, 5368.409474957752, 5692.544596432275, 5693.060644993612, 5696.088625819849, 5700.97149514258, 5704.3154821421695, 5184.740762961912, 5285.392179899284, 5341.332333458317, 5346.064438723208, 5349.839504814861, 4905.730184839868, 4910.631702622246, 4912.459629440758, 4914.8034078637165, 4948.455669876311, 5811.767529298781, 5859.707014649165, 5860.970695146582, 5870.7945221917025, 5908.4091590841435, 5473.808416705396, 5583.1904373864745, 5622.0, 5640.153976903497, 5640.351359593699, 5883.0, 5886.470217680429, 5886.646801191942, 5897.174395584536, 5934.6142500737315, 5553.944460555343, 5595.0, 5598.804041858515, 5600.635958662674, 5602.299712535933, 5428.592855535771, 5624.465675760796, 5660.801871934463, 5664.37691853904, 5679.971600141973, 5561.0, 5563.749631266522, 5565.332160140647, 5567.192757050124, 5608.6073974821375, 5509.146082357241, 5530.281063461724, 5535.640183388067, 5537.169424586345, 5549.056660367828, 5282.022825777243, 5361.35663720356, 5419.10584753527, 5431.56547476193, 5540.529055030368, 5493.560515158858, 5541.972290138524, 5568.220449137043, 5576.414476480073, 5584.692841893735, 5401.243825864461, 5414.079606466831, 5424.972875135599, 5427.077396842626, 5427.565794736491], "value": 5222.928453199227, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.0944904409914, 585.0434539527872, 597.0715537339436, 608.8112685067628, 610.825914614335, 32915.177120572, 35329.82335088327, 35704.0, 35837.820810895944, 44282.55717442824, 44759.0, 45198.09603807922, 45233.321500177524, 45306.09387812242, 45328.546714532844, 9055.773605659846, 9207.631694732203, 9218.400803947756, 9218.677346292881, 9222.40054396462, 8333.458325208841, 8545.444546104483, 9099.22656574192, 9171.816563668723, 9214.539273036355, 10815.18886083547, 11204.775904481905, 11207.103431725483, 11582.768344633103, 11588.536458541648, 10994.835077473845, 11049.17131215162, 11061.612843550438, 11073.723156921142, 11073.889261107406, 10856.077233435168, 10916.454177291103, 10938.960798724189, 10940.726481837939, 10941.398223097727, 43498.347524787154, 43895.78052109751, 43896.12207755827, 43898.0, 43924.0, 3590.2460483298537, 3615.566132064143, 3738.345789486829, 4085.428040074392, 4390.27560452525, 31900.20249493758, 33636.56269283578, 34402.311953760785, 34654.653453465646, 34716.13209669753, 4356.629686476654, 4372.068500713745, 4396.296592545177, 4417.823287068513, 4426.44669416323, 375006.0, 375723.0, 375870.0, 376399.0, 377279.99999999726, 59349.0, 60323.69838150781, 60624.696876515336, 60785.392146078855, 60853.0, 66329.33670663231, 66434.33565664281, 66447.33552664508, 66510.33489665041, 66524.66737666377, 15897.602559935864, 16142.35430582787, 16142.919285403497, 16155.75766363482, 16170.0, 10818.67543973682, 10851.782964340828, 10858.945705271422, 10872.238943273895, 11092.223544351978, 17386.65226695459, 19099.331523396893, 19378.22348153323, 19516.90241548783, 20467.4883127923, 7063.470239732041, 7355.77932662021, 7389.371903388164, 7433.479656423999, 7513.4740568159705, 955.3551352836807, 1033.0908800255727, 1055.4325043480821, 1062.1980404794485, 1130.852185032193, 32740.0, 32782.672173278435, 32981.835090824396, 33584.160395989806, 33611.0, 1087.6138970665459, 1127.3179726265491, 1143.274020996673, 1164.7379339648512, 1180.2446434282067, 16575.171241437878, 17063.232154553076, 17266.309347625953, 17341.913290433466, 17348.826511734973, 157820.0, 598156.0, 608355.0, 610234.0, 972830.0, 52300.47699522956, 53232.20151697728, 53325.2001219982, 58497.415025850045, 61075.86234481772, 1342.9529966451128, 1380.178793617796, 1383.2737812648238, 1387.66695992963, 1400.1878910232042, 31266.53100203453, 34582.48126278057, 34726.13184670403, 34815.303693926486, 35058.12446411873, 54984.725076374365, 55879.0, 56190.719046404505, 56224.43775562192, 56227.43772562222, 517812.0, 526020.0, 534651.0, 535076.0, 539340.0, 43116.568834311256, 43197.56802431936, 43526.0, 43636.56363436452, 44105.11789764123, 47440.52559474361, 47591.76204119096, 47728.0, 47880.0, 49218.75390623024, 40439.1912161761, 40742.18515629612, 40833.0, 40976.590234097275, 42883.7855810719, 40073.79963100166, 40953.18093638051, 41110.588894110675, 41512.73527132679, 42102.578974209864, 39157.41263881101, 40381.596184037786, 41047.58952410557, 41108.79445602753, 41202.587974121074, 32454.83772581122, 35177.29645407027, 35294.64705353016, 36298.8185059073, 36493.0, 27117.186484405498, 27364.72635273622, 27672.308192295353, 27801.887924482795, 27895.860520697268], "value": 21069.351958741107, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.44262172322027, 365.7531166462637, 382.0734718308103, 382.2250461648433, 403.68310875962374, 14878.628034299149, 18734.437966860984, 18807.811921880777, 19185.32851350203, 20505.17979280827, 18146.5463363416, 19698.0, 19723.1124599393, 19808.207671693115, 19832.206711731513, 5718.456746609076, 5924.763009479616, 5933.080372542263, 5936.050231962887, 5940.287165540141, 5799.681017544033, 5828.766849326022, 5840.0, 5857.619254748449, 5879.823605291826, 5662.858428539278, 5808.273965754281, 5809.6804675742815, 5813.912791308113, 5832.445917637818, 5680.715964201815, 5752.942470575283, 5763.5101016413655, 5801.59388842779, 5848.853778655525, 5050.0, 5222.712750798704, 5228.816991405321, 5228.947710522884, 5228.947710522884, 23929.162479313316, 24295.514089718108, 24385.63421548678, 24470.021199152012, 24765.399633038633, 2489.8630575318352, 2550.1074623881677, 2697.8381297122237, 2700.405910699643, 2875.511163102257, 14836.851631483654, 15184.392624295015, 15230.0, 15269.694606107816, 15540.61148471286, 1912.4262721183588, 1922.5674223299786, 1924.3553409607798, 1966.3511041356462, 1997.7902320256455, 79074.60462697649, 79101.20898790997, 81172.59413702953, 81218.18781812166, 81998.59000705018, 10265.281430299956, 10496.370217786956, 10501.264911456125, 10858.891411085946, 11190.880911908744, 14569.198694071716, 14604.634884127769, 14627.634309142144, 14629.561113166623, 14639.926800366144, 7806.998348882973, 7939.126696063428, 8025.117237103914, 8207.712730054538, 8301.750947471586, 8029.959850200712, 8060.274575288135, 8216.630251638691, 8273.669053237803, 8291.129431409736, 6514.1531600891585, 6580.177477815273, 6621.172353455818, 6633.933660663428, 6680.198376194888, 1870.9345172918881, 1919.865609407356, 1937.350987419202, 1938.821838937093, 1949.2495389275232, 920.0, 987.086944576264, 1012.0537297626743, 1030.618671091701, 1093.1091160704, 19830.70253946192, 20908.79091209099, 21018.369448916557, 21034.975089110256, 21113.366599002056, 1035.0747609446423, 1036.259074761549, 1063.4310643805682, 1065.3980501017056, 1108.8780234174235, 11171.608993685182, 11781.710885048895, 11858.703532411586, 11880.168388212742, 12024.45889934955, 435299.0, 462652.0, 481562.0, 493768.0, 496347.0, 82114.58942705368, 85241.0, 85571.0, 86255.568722156, 86563.56718216494, 1006.808706345792, 1008.5864795433837, 1030.340582027503, 1034.4000479721744, 1037.3464717228098, 17342.479725608253, 17986.863021504865, 18419.53951151232, 18541.536461588566, 18861.622767544846, 27226.183214503595, 33685.83157084199, 33777.493337599466, 39848.0, 42631.573684264, 55089.72455137859, 57149.428505716074, 57151.42848571461, 57575.0, 57964.420355795904, 7914.841703166019, 8487.236148746642, 8506.872396914176, 8717.782055448411, 8877.28981681451, 13219.603411897659, 13490.32548372597, 13723.588292351245, 13824.723505529631, 14099.929500352433, 12057.276563406223, 12171.573994910135, 12309.93845030769, 12427.502899884264, 12514.624561263177, 11837.171398002058, 11885.524579017088, 12080.75838483243, 12462.563810266598, 12630.179038362478, 12191.512339506675, 12382.133250672367, 12482.687932801753, 12484.87515124837, 12614.55849045279, 7937.563434010954, 8010.279074883289, 8203.753887383387, 8226.095129535626, 8252.83494330098, 10394.116500097576, 11414.48634811419, 11941.343226122646, 12366.381680916098, 12601.11792174539], "value": 9457.715892816297, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.6133651909208, 1483.5030264861268, 1530.6249968757652, 1661.8670506359492, 1801.4415531185325, 8126.10612832588, 8411.789705257364, 8454.746357609274, 8476.745697629081, 8779.736607901774, 8030.116687164422, 8129.674813007473, 8348.0, 8355.707550235744, 8381.413301068913, 2384.809215262779, 2425.3815277104313, 2426.259990702835, 2429.975700242993, 2431.963520547193, 2413.9758602413926, 2436.914707985221, 2440.1459489178824, 2446.2905757330354, 2446.376174075617, 2329.1615018593293, 2333.0667732906804, 2365.976340236593, 2399.8680072596, 2399.8800059997016, 2358.0449917783153, 2375.762423757628, 2376.6791483149727, 2382.0233704181287, 2406.855588664686, 2249.5051088760447, 2250.909963601454, 2305.170138750049, 2317.5480781247666, 2327.767223277676, 8490.23587877094, 8668.39321247513, 8783.736487905373, 8797.7800554986, 8804.339674524435, 5082.517160869711, 5242.606804489678, 5269.420363759983, 5291.259223708684, 5305.0, 3447.1382154461385, 3495.038864312309, 3527.488514165457, 3530.929381412383, 3548.254866478043, 1172.150191111446, 1180.4746887635006, 1187.4122309456789, 1222.0895432902455, 1230.95076196952, 12299.754004919942, 12450.875491245062, 12491.875081249253, 12501.812472812824, 12512.311822849739, 4419.204543182258, 4691.507391723888, 4708.552687494683, 4866.562009419098, 4878.0975519529, 10515.158787296998, 10612.840807387895, 10629.4153821539, 10631.734206644742, 10652.946735266429, 5223.52988231061, 5224.608154388436, 5254.448282930313, 5272.130098533699, 5288.524032837063, 4314.158739045919, 4654.138984287918, 4799.904001919942, 4802.7838747255755, 4837.564619184291, 3983.103801644607, 4083.2854250506043, 4111.527174374985, 4148.0, 4188.246115699142, 1228.3244215681339, 1234.5802427174624, 1248.918820276679, 1254.5483625894672, 1273.3187744556803, 869.6477926439802, 980.3970558106713, 1005.3816902604846, 1013.2552573858209, 1014.4674046125843, 5871.089981052944, 5994.520438365008, 6037.758489660365, 6149.108379284979, 6166.414190651881, 483.3692031898406, 485.36902027364283, 492.39927288708435, 493.0557981465525, 493.7629937629958, 4022.8390864365097, 4296.334068219431, 4357.43353364067, 4424.911501769947, 4450.287953927356, 10526.684199474028, 10705.250632455809, 11096.05683516894, 11163.776724465466, 11170.385628790333, 10876.347419154717, 11101.777964440666, 11145.052670523097, 11159.832602510969, 11161.218714689894, 8108.878366824502, 8304.211099945496, 8315.126911674308, 8353.039400469024, 8357.58212089393, 6034.310393089766, 6219.253689557282, 6346.492280617632, 6352.714127864165, 6383.170187875547, 10033.598656053677, 10271.94864025675, 10298.691039268833, 10384.169266458815, 10445.9477702611, 10386.480675966322, 10562.735931601772, 10653.360798352123, 10664.680059598224, 10666.200034997251, 6898.275681053417, 6902.758403455855, 7027.578345299298, 7739.148693643808, 7874.21257874208, 7742.883856742041, 8060.11338752725, 8728.476291422536, 8774.561271936505, 8781.604827782892, 7718.15100338951, 7744.8451030980195, 7748.612569371621, 7769.689212431439, 7959.880601790862, 7184.066071410683, 7369.668364923484, 7555.962220188864, 7586.0, 7652.158262591, 6963.895541566982, 7144.535605185646, 7203.747868824566, 7204.747833825791, 7224.963875180591, 5134.537891589625, 5182.067227898863, 5278.656887302313, 5326.547243484347, 5343.225232341367, 5075.974620127024, 5131.536862306039, 5143.254228136974, 5278.81524146653, 5380.166074258496], "value": 4604.016558558048, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.3845485894512, 331.4613752651941, 338.83058470764615, 339.7621664834616, 339.76895710916574, 5214.826664000603, 5244.160934250521, 5251.763670634826, 5270.947290527093, 5348.358197016354, 4928.2361234008695, 4986.058225646868, 5101.668391554546, 5115.437301896798, 5278.445763194867, 4100.821863056126, 4107.0, 4116.938245926313, 4145.378193271018, 4152.211079894825, 4107.486564179478, 4121.5672354402805, 4133.049398638315, 4143.70994030418, 4158.916821663565, 3908.2965066288057, 3938.448617193595, 4065.9593404065877, 4097.119119389331, 4105.835766569334, 4090.263752524574, 4092.3221479193685, 4092.570280120589, 4095.672346212296, 4096.365063415175, 3633.5465813674477, 3640.908977275563, 3650.5254316938626, 3660.4509323601405, 3660.5424321959754, 4988.900221995576, 5021.271915572258, 5043.420006699203, 5103.515166059218, 5104.923426148611, 4635.049814787994, 4644.7213167210075, 4663.417072865892, 4667.766611669437, 4685.1566717990745, 5338.439463856317, 5368.4631536846, 5401.918971215435, 5408.107662235727, 5429.642589352662, 3703.4445532876257, 3906.2968665640174, 4008.579099194572, 4013.6387725104587, 4014.3175660137845, 5593.916091258634, 5605.32736071674, 5612.0, 5627.493525582676, 5630.239917611111, 4012.4583181270154, 4017.8995525112105, 4023.3160362738404, 4025.5370632377067, 4027.375756757707, 3902.3951287550476, 4075.2664520386024, 4084.7957602119777, 4086.775227362464, 4088.12105397341, 4580.129775342674, 4595.793189306489, 4597.7011494252765, 4608.354830323758, 4623.8844028898875, 5317.361916570037, 5392.91910621341, 5396.595255355785, 5401.5948803839865, 5478.753456094485, 1372.8695773901463, 1384.7507448659337, 1393.4008376398247, 1394.8326200855963, 1417.9220142892034, 930.5393830054167, 969.1907257439975, 974.5858010345574, 981.749653838281, 985.8274801909637, 5228.686278823283, 5236.329471969247, 5265.052290587731, 5275.261463395128, 5281.494773989394, 5522.144067669517, 5527.613067085265, 5556.833295001156, 5566.582506312043, 5588.189712491666, 5137.17805151174, 5163.483651634881, 5215.217717342429, 5220.347456567929, 5269.0, 2901.4912245632154, 2902.4775540402507, 2923.678395376507, 2923.956140657892, 2928.31184671602, 5613.972991972336, 5686.488216060575, 5711.771529138788, 5720.170575266647, 5757.625754325955, 5581.888362232733, 5593.496585307342, 5616.466435688603, 5617.157426386075, 5632.690202038845, 5710.7430165642645, 5726.942730572724, 5741.483266506035, 5790.855228619318, 5795.507381872504, 5422.864428389322, 5431.891362172655, 5452.255278310902, 5501.669899806025, 5572.442755724395, 5377.865553361197, 5391.218273350264, 5410.621256512007, 5417.864553386197, 5420.428075857957, 5578.693171875586, 5583.330000399816, 5743.942560574341, 5747.885042299047, 5752.884942301047, 3947.795922243743, 3954.339177545495, 3959.1685745993664, 3959.7228194027293, 3973.92052158961, 5247.737613119252, 5420.0, 5435.592330575302, 5462.617616766788, 5558.666480011212, 5425.969065877471, 5427.3215848019, 5452.7546260417585, 5477.343488408039, 5481.205225242238, 5325.227841962971, 5367.758450869642, 5389.83830485086, 5440.945590544044, 5453.972730136324, 5117.462666419972, 5305.098133317269, 5306.787728490817, 5315.202719591937, 5377.354717433933, 5401.567874570103, 5406.540444062299, 5445.891082178414, 5447.373552041566, 5487.29893732944, 5063.13926632466, 5277.8416647500635, 5345.465453454779, 5409.7024663642305, 5432.185172224198], "value": 4154.525844947092, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.2574208467983, 742.0, 755.3207877200743, 756.8599809035326, 883.4699180491704, 15673.52979410618, 16883.24025418856, 16969.91515042423, 16990.065546394944, 16995.23521441535, 14910.40358385666, 15022.399104035825, 15042.097474151558, 15072.547823565283, 15808.604784880385, 810.6959890041235, 824.1593574553951, 832.4630613254448, 832.8750687396879, 832.9583520823965, 674.578388507183, 799.6401619271322, 838.5681374092347, 841.5834162089774, 842.1999100854182, 860.2988564320091, 877.0746862060523, 877.5217506458995, 878.3017501086651, 878.4553576782404, 874.6501399440211, 874.6545114679698, 874.6719980007497, 874.9300055995537, 875.2691502595341, 514.976826042829, 615.4983688294026, 639.6929473852576, 640.0814830717935, 640.452413186728, 14012.579622611236, 14334.0, 14382.640433989234, 14386.640333991629, 14393.928030359886, 14444.422223111063, 14913.2410504056, 15036.17301048442, 15037.774433383507, 15271.312790924436, 13837.377318020612, 13912.791308130387, 14023.368948397347, 14083.647908802259, 14542.709145817025, 4478.820847166109, 4483.0, 4525.456945166601, 4616.69991450558, 4639.420072490939, 87412.5629371849, 87535.0, 87978.5601071997, 88108.0, 90297.54851225768, 11178.888211117948, 11208.943955280172, 11252.26860254081, 11257.718557035976, 11525.366104864313, 21822.45443863916, 21838.45403864916, 21897.12411503522, 21918.671219931715, 21935.451613709785, 8133.349332053422, 8200.5489698066, 8276.627551760184, 8292.33661307094, 8348.68564410376, 4443.755593442392, 4600.95399046012, 4617.653675974316, 4765.237561990065, 4885.047415753965, 3575.982120089383, 3725.4970578972034, 3726.869559565402, 3729.4778730977687, 3730.0, 602.3255350126655, 612.2438788096697, 619.294004834489, 641.9614823110628, 648.8215740671259, 717.2397258905577, 724.663031690256, 728.4572993120054, 729.1869565434608, 754.6490881740017, 8582.934684673493, 9121.45271283725, 9124.224440922464, 9177.908220917705, 9340.4862732549, 663.6316844151446, 669.2704951602776, 685.095673710697, 685.1914740606132, 692.4010730717893, 4262.211490874198, 4281.850135245251, 4516.751578663205, 4537.659675524349, 4567.223571992769, 73756.63121684357, 110922.44538777255, 132328.0, 133132.33433832962, 136865.0, 84964.0, 86394.0, 98658.50670746744, 116257.4187129059, 117710.41144794221, 53508.0, 54390.728046359516, 87050.0, 89511.55244223737, 90123.54938225266, 8831.735047948572, 8867.866981995405, 9213.585388657393, 9296.535173241446, 9388.530573471437, 50616.493835062654, 52062.0, 52925.0, 53151.0, 53197.46802531925, 72612.0, 78343.6082819605, 79952.0, 80048.0, 80476.0, 11945.761084778429, 12008.63974080779, 12298.631041068782, 13332.0, 13420.530281440102, 13408.671517888348, 13710.725785484434, 14134.929325353307, 14153.0, 14439.133651980916, 13383.19700817954, 13523.188608683511, 13569.321533923066, 13827.377768000262, 14302.785458218343, 8305.086440491661, 12012.879871201176, 12568.183068100545, 13662.453501860213, 13723.656908577366, 13128.474861005452, 13357.732845343233, 13385.732285354434, 13432.0, 14283.42866285337, 10807.894841034182, 11163.665090047312, 11386.202965792647, 11514.827277590675, 11987.580434684744, 10667.306625069346, 10686.412247326161, 10712.339587363971, 10796.946015269874, 10851.837222441827], "value": 7599.374084163247, "unit": "score"}], "name": "dromaeo_css", "value": 7299.717726071244, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 112.0, 109.0, 110.0, 111.0, 111.0, 110.0, 110.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 119.0, 120.0, 178.0, 118.0, 121.0, 154.0, 117.0, 155.0, 118.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 159.0, 160.0, 160.0, 161.0, 162.0, 162.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 113.0, 76.0, 75.0, 74.0, 75.0, 98.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 128.0, 83.0, 83.0, 84.0, 130.0, 84.0, 85.0, 142.0], "value": 98.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 147.0, 147.0, 147.0, 147.0, 146.0, 145.0, 145.0, 145.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 50.0, 50.0, 50.0, 50.0, 50.0, 50.0, 51.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0, 61.0, 60.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 76.0, 79.0, 76.0, 75.0, 75.0, 79.0, 75.0, 75.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 120.0, 130.0, 126.0, 118.0, 127.0, 127.0, 118.0, 164.0, 125.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 157.0, 157.0, 156.0, 156.0, 159.0, 158.0, 160.0, 156.0, 161.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 62.0, 62.0, 62.0, 63.0, 66.0, 61.0, 60.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1479.4000000000003, "alertThreshold": 2.0}]} 08:32:26 INFO - Return code: 0 08:32:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:32:26 INFO - # TBPL SUCCESS # 08:32:26 INFO - Running post-action listener: _resource_record_post_action 08:32:26 INFO - [mozharness: 2016-08-06 15:32:26.819584Z] Finished run-tests step (success) 08:32:26 INFO - Running post-run listener: _resource_record_post_run 08:32:26 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 557477888; Read time: 16; Write time: 1252532 08:32:26 INFO - TinderboxPrint: CPU usage
13.0% 08:32:26 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:32:26 INFO - TinderboxPrint: I/O write bytes / time
557,477,888 / 1,252,532 08:32:26 INFO - TinderboxPrint: CPU idle
8,300.1 (86.9%) 08:32:26 INFO - TinderboxPrint: CPU user
1,189.6 (12.5%) 08:32:26 INFO - TinderboxPrint: Swap in / out
0 / 0 08:32:26 INFO - install - Wall time: 18s; CPU: 18.0%; Read bytes: 0; Write bytes: 263819264; Read time: 0; Write time: 825116 08:32:26 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292880384; Read time: 16; Write time: 427312 08:32:27 INFO - Running post-run listener: _upload_blobber_files 08:32:27 INFO - Blob upload gear active. 08:32:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:32:27 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 08:32:27 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:32:27 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:32:27 INFO - (blobuploader) - INFO - Open directory for files ... 08:32:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:32:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:32:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:31 INFO - (blobuploader) - INFO - Done attempting. 08:32:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:32:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:32:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:31 INFO - (blobuploader) - INFO - Done attempting. 08:32:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:32:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:32:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:32 INFO - (blobuploader) - INFO - Done attempting. 08:32:32 INFO - (blobuploader) - INFO - Iteration through files over. 08:32:32 INFO - Return code: 0 08:32:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:32:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:32:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88e8d0d57263773374f2f4af655319e52087e40ca120f95eb8abe85436829f76c5034a40e2025dc6753629439ab14fc6940c70806315697fb2189fcede1e2478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbbf29106674123617826715a22feddeaf04f70bee78fa4a79b325e8cee5699d9bad0f5efef6044225cd8e073115c815fce0834864dd4b80a284377663afb04c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4015400f723f774c3221bffd706dd88c446fae9e8f49e0ad93e86050aeef95713914977b9734e9de1af68dc4673292039bd96eddd1b7055bd270a02714f69ca"} 08:32:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:32:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:32:32 INFO - Contents: 08:32:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88e8d0d57263773374f2f4af655319e52087e40ca120f95eb8abe85436829f76c5034a40e2025dc6753629439ab14fc6940c70806315697fb2189fcede1e2478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbbf29106674123617826715a22feddeaf04f70bee78fa4a79b325e8cee5699d9bad0f5efef6044225cd8e073115c815fce0834864dd4b80a284377663afb04c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4015400f723f774c3221bffd706dd88c446fae9e8f49e0ad93e86050aeef95713914977b9734e9de1af68dc4673292039bd96eddd1b7055bd270a02714f69ca"} 08:32:32 INFO - Running post-run listener: copy_logs_to_upload_dir 08:32:32 INFO - Copying logs to upload dir... 08:32:32 INFO - mkdir: /builds/slave/test/build/upload/logs 08:32:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.392213 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-08-06 08:32:32.523945) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-06 08:32:32.528507) ========= 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=745a4373a2a16df560f216e000000264-1470491670.677045-184049152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88e8d0d57263773374f2f4af655319e52087e40ca120f95eb8abe85436829f76c5034a40e2025dc6753629439ab14fc6940c70806315697fb2189fcede1e2478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbbf29106674123617826715a22feddeaf04f70bee78fa4a79b325e8cee5699d9bad0f5efef6044225cd8e073115c815fce0834864dd4b80a284377663afb04c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4015400f723f774c3221bffd706dd88c446fae9e8f49e0ad93e86050aeef95713914977b9734e9de1af68dc4673292039bd96eddd1b7055bd270a02714f69ca"} build_url:https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011300 build_url: 'https://queue.taskcluster.net/v1/task/TGypPaVgRdmhQrCocbky8Q/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/Fx-Team/sha512/88e8d0d57263773374f2f4af655319e52087e40ca120f95eb8abe85436829f76c5034a40e2025dc6753629439ab14fc6940c70806315697fb2189fcede1e2478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbbf29106674123617826715a22feddeaf04f70bee78fa4a79b325e8cee5699d9bad0f5efef6044225cd8e073115c815fce0834864dd4b80a284377663afb04c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4015400f723f774c3221bffd706dd88c446fae9e8f49e0ad93e86050aeef95713914977b9734e9de1af68dc4673292039bd96eddd1b7055bd270a02714f69ca"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-06 08:32:32.554213) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 08:32:32.554576) ========= 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=745a4373a2a16df560f216e000000264-1470491670.677045-184049152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004400 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 08:32:32.639833) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-06 08:32:32.640248) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-06 08:32:32.640598) ========= ========= Total master_lag: 0.28 =========