builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1471148166.35 results: success (0) buildid: 20160813183001 builduid: 6f4ebe3fb74145a58e41e55f7b4bcb37 revision: b20c524ca0c476169855e301ed32474a28a3c630 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:06.348822) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:06.349321) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:06.349633) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:06.743399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:06.743727) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-13 21:16:09-- 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% 50.8M=0s 2016-08-13 21:16:09 (50.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146832 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:06.923784) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:06.924121) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019896 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:06.973963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-13 21:16:06.977371) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev b20c524ca0c476169855e301ed32474a28a3c630 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev b20c524ca0c476169855e301ed32474a28a3c630 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-13 21:16:09,624 truncating revision to first 12 chars 2016-08-13 21:16:09,624 Setting DEBUG logging. 2016-08-13 21:16:09,624 attempt 1/10 2016-08-13 21:16:09,624 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/b20c524ca0c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-13 21:16:11,054 unpacking tar archive at: autoland-b20c524ca0c4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.765663 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-13 21:16:08.757578) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:08.757979) ========= script_repo_revision: b20c524ca0c476169855e301ed32474a28a3c630 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:08.758441) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:08.758752) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-13 21:16:08.769144) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-13 21:16:08.769475) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:16:11 INFO - MultiFileLogger online at 20160813 21:16:11 in /builds/slave/test 21:16:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 21:16:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:16:11 INFO - {'append_to_log': False, 21:16:11 INFO - 'base_work_dir': '/builds/slave/test', 21:16:11 INFO - 'blob_upload_branch': 'Autoland', 21:16:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:16:11 INFO - 'branch': 'Autoland', 21:16:11 INFO - 'buildbot_json_path': 'buildprops.json', 21:16:11 INFO - 'config_files': ('talos/linux_config.py',), 21:16:11 INFO - 'default_actions': ('clobber', 21:16:11 INFO - 'read-buildbot-config', 21:16:11 INFO - 'download-and-extract', 21:16:11 INFO - 'populate-webroot', 21:16:11 INFO - 'create-virtualenv', 21:16:11 INFO - 'install', 21:16:11 INFO - 'run-tests'), 21:16:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:16:11 INFO - 'download_minidump_stackwalk': True, 21:16:11 INFO - 'download_symbols': 'ondemand', 21:16:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:16:11 INFO - 'tooltool.py': '/tools/tooltool.py', 21:16:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:16:11 INFO - '/tools/misc-python/virtualenv.py')}, 21:16:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:16:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:16:11 INFO - 'installer_path': 'installer.exe', 21:16:11 INFO - 'log_level': 'info', 21:16:11 INFO - 'log_name': 'talos', 21:16:11 INFO - 'log_to_console': True, 21:16:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:16:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:16:11 INFO - 'opt_config_files': (), 21:16:11 INFO - 'pip_index': False, 21:16:11 INFO - 'sps_profile': False, 21:16:11 INFO - 'sps_profile_interval': 0, 21:16:11 INFO - 'suite': 'dromaeojs', 21:16:11 INFO - 'system_bits': '32', 21:16:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:16:11 INFO - 'title': 'talos-linux64-ix-069', 21:16:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:16:11 INFO - 'use_talos_json': True, 21:16:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:16:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:16:11 INFO - 'work_dir': 'build'} 21:16:11 INFO - [mozharness: 2016-08-14 04:16:11.519588Z] Running clobber step. 21:16:11 INFO - Running pre-action listener: _resource_record_pre_action 21:16:11 INFO - Running main action method: clobber 21:16:11 INFO - rmtree: /builds/slave/test/build 21:16:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:16:11 INFO - Running post-action listener: _resource_record_post_action 21:16:11 INFO - [mozharness: 2016-08-14 04:16:11.988182Z] Finished clobber step (success) 21:16:11 INFO - [mozharness: 2016-08-14 04:16:11.988282Z] Running read-buildbot-config step. 21:16:11 INFO - Running pre-action listener: _resource_record_pre_action 21:16:11 INFO - Running main action method: read_buildbot_config 21:16:11 INFO - Using buildbot properties: 21:16:11 INFO - { 21:16:11 INFO - "project": "", 21:16:11 INFO - "product": "firefox", 21:16:11 INFO - "script_repo_revision": "production", 21:16:11 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 21:16:11 INFO - "repository": "", 21:16:11 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 21:16:11 INFO - "buildid": "20160813183001", 21:16:11 INFO - "pgo_build": "True", 21:16:11 INFO - "basedir": "/builds/slave/test", 21:16:11 INFO - "buildnumber": 122, 21:16:11 INFO - "slavename": "talos-linux64-ix-069", 21:16:11 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:16:11 INFO - "platform": "ubuntu64_hw", 21:16:11 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 21:16:11 INFO - "branch": "autoland", 21:16:11 INFO - "repo_path": "integration/autoland", 21:16:11 INFO - "revision": "b20c524ca0c476169855e301ed32474a28a3c630", 21:16:11 INFO - "stage_platform": "linux64-pgo", 21:16:11 INFO - "builduid": "6f4ebe3fb74145a58e41e55f7b4bcb37", 21:16:11 INFO - "slavebuilddir": "test" 21:16:11 INFO - } 21:16:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 21:16:11 INFO - Running post-action listener: _resource_record_post_action 21:16:11 INFO - [mozharness: 2016-08-14 04:16:11.990694Z] Finished read-buildbot-config step (success) 21:16:11 INFO - [mozharness: 2016-08-14 04:16:11.990782Z] Running download-and-extract step. 21:16:11 INFO - Running pre-action listener: _resource_record_pre_action 21:16:11 INFO - Running main action method: download_and_extract 21:16:11 INFO - mkdir: /builds/slave/test/build/tests 21:16: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')]} 21:16:11 INFO - trying https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:16:11 INFO - Downloading https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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 21:16:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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 21:16:12 INFO - Downloaded 1361 bytes. 21:16:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:16:12 INFO - Using the following test package requirements: 21:16:12 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 21:16:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:16:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:16:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:12 INFO - u'jsshell-linux-x86_64.zip'], 21:16:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:16:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 21:16:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:16:12 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:16:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:16:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:16:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:12 INFO - trying https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:16:12 INFO - Downloading https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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 21:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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 21:16:13 INFO - Downloaded 21837055 bytes. 21:16:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 21:16:17 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 21:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:17 INFO - trying https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:16:17 INFO - Downloading https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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 21:16:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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 21:16:18 INFO - Downloaded 21837055 bytes. 21:16:18 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 21:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:23 INFO - trying https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 21:16:23 INFO - Downloading https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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 21:16:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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 21:16:24 INFO - Downloaded 14413719 bytes. 21:16:24 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 21:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:24 INFO - trying https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:16:24 INFO - Downloading https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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 21:16:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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 21:16:26 INFO - Downloaded 59405638 bytes. 21:16:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:16:26 INFO - mkdir: /builds/slave/test/properties 21:16:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:16:26 INFO - Writing to file /builds/slave/test/properties/build_url 21:16:26 INFO - Contents: 21:16:26 INFO - build_url:https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:16:27 INFO - Running post-action listener: _resource_record_post_action 21:16:27 INFO - Running post-action listener: set_extra_try_arguments 21:16:27 INFO - [mozharness: 2016-08-14 04:16:27.006846Z] Finished download-and-extract step (success) 21:16:27 INFO - [mozharness: 2016-08-14 04:16:27.007160Z] Running populate-webroot step. 21:16:27 INFO - Running pre-action listener: _resource_record_pre_action 21:16:27 INFO - Running main action method: populate_webroot 21:16:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:16:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:16:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:16:27 INFO - '--tppagecycles', 21:16:27 INFO - '7'], 21:16:27 INFO - 'tests': ['tsvgm']}}, 21:16:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:16:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:16:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:16:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:16:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:16:27 INFO - 'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:16:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:16:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:16:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:16:27 INFO - 'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['damp', 'tps']}, 21:16:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:16:27 INFO - 'tests': ['damp', 'tps']}, 21:16:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['dromaeo_dom']}, 21:16:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:16:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['basic_compositor_video']}, 21:16:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:16:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['a11yr', 21:16:27 INFO - 'ts_paint', 21:16:27 INFO - 'tpaint', 21:16:27 INFO - 'sessionrestore', 21:16:27 INFO - 'sessionrestore_no_auto_restore', 21:16:27 INFO - 'tabpaint']}, 21:16:27 INFO - 'other-e10s': {'tests': ['a11yr', 21:16:27 INFO - 'ts_paint', 21:16:27 INFO - 'tpaint', 21:16:27 INFO - 'sessionrestore', 21:16:27 INFO - 'sessionrestore_no_auto_restore', 21:16:27 INFO - 'tabpaint']}, 21:16:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:16:27 INFO - 'ts_paint', 21:16:27 INFO - 'tpaint', 21:16:27 INFO - 'sessionrestore', 21:16:27 INFO - 'sessionrestore_no_auto_restore', 21:16:27 INFO - 'tabpaint']}, 21:16:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:16:27 INFO - 'ts_paint', 21:16:27 INFO - 'tpaint', 21:16:27 INFO - 'sessionrestore', 21:16:27 INFO - 'sessionrestore_no_auto_restore', 21:16:27 INFO - 'tabpaint']}, 21:16:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['a11yr', 21:16:27 INFO - 'ts_paint', 21:16:27 INFO - 'tpaint', 21:16:27 INFO - 'sessionrestore', 21:16:27 INFO - 'sessionrestore_no_auto_restore', 21:16:27 INFO - 'tabpaint']}, 21:16:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['a11yr', 21:16:27 INFO - 'ts_paint', 21:16:27 INFO - 'tpaint', 21:16:27 INFO - 'sessionrestore', 21:16:27 INFO - 'sessionrestore_no_auto_restore', 21:16:27 INFO - 'tabpaint']}, 21:16:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['tsvgx', 21:16:27 INFO - 'tsvgr_opacity', 21:16:27 INFO - 'tart', 21:16:27 INFO - 'tscrollx', 21:16:27 INFO - 'cart']}, 21:16:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:16:27 INFO - 'tsvgr_opacity', 21:16:27 INFO - 'tart', 21:16:27 INFO - 'tscrollx', 21:16:27 INFO - 'cart']}, 21:16:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:16:27 INFO - 'talos_options': ['--disable-e10s'], 21:16:27 INFO - 'tests': ['tp5o']}, 21:16:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:16:27 INFO - 'tests': ['tp5o']}, 21:16:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:16:27 INFO - 'talos_options': ['--disable-e10s', 21:16:27 INFO - '--xperf_path', 21:16:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:16:27 INFO - 'tests': ['tp5n']}, 21:16:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:16:27 INFO - 'talos_options': ['--xperf_path', 21:16:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:16:27 INFO - 'tests': ['tp5n']}}, 21:16:27 INFO - 'talos.zip': {'path': '', 21:16:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:16:27 INFO - Running post-action listener: _resource_record_post_action 21:16:27 INFO - [mozharness: 2016-08-14 04:16:27.023000Z] Finished populate-webroot step (success) 21:16:27 INFO - [mozharness: 2016-08-14 04:16:27.023108Z] Running create-virtualenv step. 21:16:27 INFO - Running pre-action listener: _resource_record_pre_action 21:16:27 INFO - Running main action method: create_virtualenv 21:16:27 INFO - Creating virtualenv /builds/slave/test/build/venv 21:16:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:16:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:16:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:16:27 INFO - Using real prefix '/usr' 21:16:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:16:27 INFO - Installing distribute.............................................................................................................................................................................................done. 21:16:29 INFO - Installing pip.................done. 21:16:29 INFO - Return code: 0 21:16:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:16: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')]} 21:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16: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')]} 21:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0d5ef9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:16: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', 'pip>=1.5'] in /builds/slave/test/build 21:16: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 pip>=1.5 21:16:29 INFO - Using env: {'DISPLAY': ':0', 21:16:29 INFO - 'HOME': '/home/cltbld', 21:16:29 INFO - 'LANG': 'en_US.UTF-8', 21:16:29 INFO - 'LANGUAGE': 'en_US:en', 21:16:29 INFO - 'LOGNAME': 'cltbld', 21:16:29 INFO - 'MAIL': '/var/mail/cltbld', 21:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:29 INFO - 'MOZ_NO_REMOTE': '1', 21:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:29 INFO - 'NO_EM_RESTART': '1', 21:16:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:29 INFO - 'PWD': '/builds/slave/test', 21:16:29 INFO - 'SHELL': '/bin/bash', 21:16:29 INFO - 'SHLVL': '1', 21:16:29 INFO - 'TERM': 'linux', 21:16:29 INFO - 'TMOUT': '86400', 21:16:29 INFO - 'USER': 'cltbld', 21:16:29 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:29 INFO - '_': '/tools/buildbot/bin/python'} 21:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:16:29 INFO - Cleaning up... 21:16:29 INFO - Return code: 0 21:16:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:16: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')]} 21:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16: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')]} 21:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16: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', '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 0x7f0d5ef9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:16: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', 'psutil>=3.1.1'] in /builds/slave/test/build 21:16: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 psutil>=3.1.1 21:16:29 INFO - Using env: {'DISPLAY': ':0', 21:16:29 INFO - 'HOME': '/home/cltbld', 21:16:29 INFO - 'LANG': 'en_US.UTF-8', 21:16:29 INFO - 'LANGUAGE': 'en_US:en', 21:16:29 INFO - 'LOGNAME': 'cltbld', 21:16:29 INFO - 'MAIL': '/var/mail/cltbld', 21:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:29 INFO - 'MOZ_NO_REMOTE': '1', 21:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:29 INFO - 'NO_EM_RESTART': '1', 21:16:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:29 INFO - 'PWD': '/builds/slave/test', 21:16:29 INFO - 'SHELL': '/bin/bash', 21:16:29 INFO - 'SHLVL': '1', 21:16:29 INFO - 'TERM': 'linux', 21:16:29 INFO - 'TMOUT': '86400', 21:16:29 INFO - 'USER': 'cltbld', 21:16:29 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:29 INFO - '_': '/tools/buildbot/bin/python'} 21:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:30 INFO - Downloading/unpacking psutil>=3.1.1 21:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:16:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:16:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:16:32 INFO - Installing collected packages: psutil 21:16:32 INFO - Running setup.py install for psutil 21:16:32 INFO - building 'psutil._psutil_linux' extension 21:16:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:16:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:16:32 INFO - building 'psutil._psutil_posix' extension 21:16:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:16:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:16:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:16:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:16:32 INFO - Successfully installed psutil 21:16:32 INFO - Cleaning up... 21:16:32 INFO - Return code: 0 21:16:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0d5ef9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:16:32 INFO - Using env: {'DISPLAY': ':0', 21:16:32 INFO - 'HOME': '/home/cltbld', 21:16:32 INFO - 'LANG': 'en_US.UTF-8', 21:16:32 INFO - 'LANGUAGE': 'en_US:en', 21:16:32 INFO - 'LOGNAME': 'cltbld', 21:16:32 INFO - 'MAIL': '/var/mail/cltbld', 21:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:32 INFO - 'MOZ_NO_REMOTE': '1', 21:16:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:32 INFO - 'NO_EM_RESTART': '1', 21:16:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:32 INFO - 'PWD': '/builds/slave/test', 21:16:32 INFO - 'SHELL': '/bin/bash', 21:16:32 INFO - 'SHLVL': '1', 21:16:32 INFO - 'TERM': 'linux', 21:16:32 INFO - 'TMOUT': '86400', 21:16:32 INFO - 'USER': 'cltbld', 21:16:32 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:32 INFO - '_': '/tools/buildbot/bin/python'} 21:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:16:34 INFO - Installing collected packages: mozsystemmonitor 21:16:34 INFO - Running setup.py install for mozsystemmonitor 21:16:34 INFO - Successfully installed mozsystemmonitor 21:16:34 INFO - Cleaning up... 21:16:34 INFO - Return code: 0 21:16:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:34 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 0x7f0d5ef9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:16:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:16:34 INFO - Using env: {'DISPLAY': ':0', 21:16:34 INFO - 'HOME': '/home/cltbld', 21:16:34 INFO - 'LANG': 'en_US.UTF-8', 21:16:34 INFO - 'LANGUAGE': 'en_US:en', 21:16:34 INFO - 'LOGNAME': 'cltbld', 21:16:34 INFO - 'MAIL': '/var/mail/cltbld', 21:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:34 INFO - 'MOZ_NO_REMOTE': '1', 21:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:34 INFO - 'NO_EM_RESTART': '1', 21:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:34 INFO - 'PWD': '/builds/slave/test', 21:16:34 INFO - 'SHELL': '/bin/bash', 21:16:34 INFO - 'SHLVL': '1', 21:16:34 INFO - 'TERM': 'linux', 21:16:34 INFO - 'TMOUT': '86400', 21:16:34 INFO - 'USER': 'cltbld', 21:16:34 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:34 INFO - '_': '/tools/buildbot/bin/python'} 21:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:35 INFO - Downloading/unpacking jsonschema==2.5.1 21:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:16:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:37 INFO - Downloading functools32-3.2.3-2.tar.gz 21:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:16:37 INFO - warning: no files found matching '*.txt' 21:16:37 INFO - no previously-included directories found matching 'build' 21:16:37 INFO - no previously-included directories found matching 'dist' 21:16:37 INFO - no previously-included directories found matching '.git*' 21:16:37 INFO - Installing collected packages: jsonschema, functools32 21:16:37 INFO - Running setup.py install for functools32 21:16:37 INFO - warning: no files found matching '*.txt' 21:16:37 INFO - no previously-included directories found matching 'build' 21:16:37 INFO - no previously-included directories found matching 'dist' 21:16:37 INFO - no previously-included directories found matching '.git*' 21:16:37 INFO - Successfully installed jsonschema functools32 21:16:37 INFO - Cleaning up... 21:16:37 INFO - Return code: 0 21:16:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:37 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 0x7f0d5ef9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:16:37 INFO - Using env: {'DISPLAY': ':0', 21:16:37 INFO - 'HOME': '/home/cltbld', 21:16:37 INFO - 'LANG': 'en_US.UTF-8', 21:16:37 INFO - 'LANGUAGE': 'en_US:en', 21:16:37 INFO - 'LOGNAME': 'cltbld', 21:16:37 INFO - 'MAIL': '/var/mail/cltbld', 21:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:37 INFO - 'MOZ_NO_REMOTE': '1', 21:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:37 INFO - 'NO_EM_RESTART': '1', 21:16:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:37 INFO - 'PWD': '/builds/slave/test', 21:16:37 INFO - 'SHELL': '/bin/bash', 21:16:37 INFO - 'SHLVL': '1', 21:16:37 INFO - 'TERM': 'linux', 21:16:37 INFO - 'TMOUT': '86400', 21:16:37 INFO - 'USER': 'cltbld', 21:16:37 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:37 INFO - '_': '/tools/buildbot/bin/python'} 21:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:16:37 INFO - Cleaning up... 21:16:37 INFO - Return code: 0 21:16:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:37 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 0x7f0d5ef9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:16:37 INFO - Using env: {'DISPLAY': ':0', 21:16:37 INFO - 'HOME': '/home/cltbld', 21:16:37 INFO - 'LANG': 'en_US.UTF-8', 21:16:37 INFO - 'LANGUAGE': 'en_US:en', 21:16:37 INFO - 'LOGNAME': 'cltbld', 21:16:37 INFO - 'MAIL': '/var/mail/cltbld', 21:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:37 INFO - 'MOZ_NO_REMOTE': '1', 21:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:37 INFO - 'NO_EM_RESTART': '1', 21:16:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:37 INFO - 'PWD': '/builds/slave/test', 21:16:37 INFO - 'SHELL': '/bin/bash', 21:16:37 INFO - 'SHLVL': '1', 21:16:37 INFO - 'TERM': 'linux', 21:16:37 INFO - 'TMOUT': '86400', 21:16:37 INFO - 'USER': 'cltbld', 21:16:37 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:37 INFO - '_': '/tools/buildbot/bin/python'} 21:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:37 INFO - Downloading/unpacking blobuploader==1.2.4 21:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:39 INFO - Downloading blobuploader-1.2.4.tar.gz 21:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:16:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:16:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:40 INFO - Downloading docopt-0.6.1.tar.gz 21:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:16:40 INFO - Installing collected packages: blobuploader, requests, docopt 21:16:40 INFO - Running setup.py install for blobuploader 21:16:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:16:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:16:40 INFO - Running setup.py install for requests 21:16:40 INFO - Running setup.py install for docopt 21:16:41 INFO - Successfully installed blobuploader requests docopt 21:16:41 INFO - Cleaning up... 21:16:41 INFO - Return code: 0 21:16:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:41 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 0x7f0d5ef9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:16:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:16:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:16:41 INFO - Using env: {'DISPLAY': ':0', 21:16:41 INFO - 'HOME': '/home/cltbld', 21:16:41 INFO - 'LANG': 'en_US.UTF-8', 21:16:41 INFO - 'LANGUAGE': 'en_US:en', 21:16:41 INFO - 'LOGNAME': 'cltbld', 21:16:41 INFO - 'MAIL': '/var/mail/cltbld', 21:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:41 INFO - 'MOZ_NO_REMOTE': '1', 21:16:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:41 INFO - 'NO_EM_RESTART': '1', 21:16:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:41 INFO - 'PWD': '/builds/slave/test', 21:16:41 INFO - 'SHELL': '/bin/bash', 21:16:41 INFO - 'SHLVL': '1', 21:16:41 INFO - 'TERM': 'linux', 21:16:41 INFO - 'TMOUT': '86400', 21:16:41 INFO - 'USER': 'cltbld', 21:16:41 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:41 INFO - '_': '/tools/buildbot/bin/python'} 21:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:16:41 INFO - Running setup.py (path:/tmp/pip-6GRZVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:16:41 INFO - Running setup.py (path:/tmp/pip-zlqyYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:16:41 INFO - Running setup.py (path:/tmp/pip-H43BBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:16:41 INFO - Running setup.py (path:/tmp/pip-naDENe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:16:41 INFO - Running setup.py (path:/tmp/pip-ywrS4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:16:41 INFO - Running setup.py (path:/tmp/pip-1mCZMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:16:41 INFO - Running setup.py (path:/tmp/pip-_45A76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:16:41 INFO - Running setup.py (path:/tmp/pip-b160AY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:16:41 INFO - Running setup.py (path:/tmp/pip-D8NHwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:16:42 INFO - Running setup.py (path:/tmp/pip-tts1ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:16:42 INFO - Running setup.py (path:/tmp/pip-5ysloW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:16:42 INFO - Running setup.py (path:/tmp/pip-gZrD9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:16:42 INFO - Running setup.py (path:/tmp/pip-oJRbnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:16:42 INFO - Running setup.py (path:/tmp/pip-_nHkQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:42 INFO - Running setup.py (path:/tmp/pip-Q1IOIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:16:42 INFO - Running setup.py (path:/tmp/pip-ML2yKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:16:42 INFO - Running setup.py (path:/tmp/pip-Llj353-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:16:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:16:42 INFO - Running setup.py install for manifestparser 21:16:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:16:42 INFO - Running setup.py install for mozcrash 21:16:42 INFO - Running setup.py install for mozdebug 21:16:42 INFO - Running setup.py install for mozdevice 21:16:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Running setup.py install for mozfile 21:16:43 INFO - Running setup.py install for mozhttpd 21:16:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Running setup.py install for mozinfo 21:16:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Running setup.py install for mozInstall 21:16:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Running setup.py install for mozleak 21:16:43 INFO - Running setup.py install for mozlog 21:16:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Running setup.py install for moznetwork 21:16:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Running setup.py install for mozprocess 21:16:43 INFO - Running setup.py install for mozprofile 21:16:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:16:43 INFO - Running setup.py install for mozrunner 21:16:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:16:44 INFO - Running setup.py install for mozscreenshot 21:16:44 INFO - Running setup.py install for moztest 21:16:44 INFO - Running setup.py install for mozversion 21:16:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:16:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:16:44 INFO - Cleaning up... 21:16:44 INFO - Return code: 0 21:16:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:44 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 0x7f0d5ef9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:16:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:16:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:16:44 INFO - Using env: {'DISPLAY': ':0', 21:16:44 INFO - 'HOME': '/home/cltbld', 21:16:44 INFO - 'LANG': 'en_US.UTF-8', 21:16:44 INFO - 'LANGUAGE': 'en_US:en', 21:16:44 INFO - 'LOGNAME': 'cltbld', 21:16:44 INFO - 'MAIL': '/var/mail/cltbld', 21:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:44 INFO - 'MOZ_NO_REMOTE': '1', 21:16:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:44 INFO - 'NO_EM_RESTART': '1', 21:16:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:44 INFO - 'PWD': '/builds/slave/test', 21:16:44 INFO - 'SHELL': '/bin/bash', 21:16:44 INFO - 'SHLVL': '1', 21:16:44 INFO - 'TERM': 'linux', 21:16:44 INFO - 'TMOUT': '86400', 21:16:44 INFO - 'USER': 'cltbld', 21:16:44 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:44 INFO - '_': '/tools/buildbot/bin/python'} 21:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:16:44 INFO - Running setup.py (path:/tmp/pip-5ZqS4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:16:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:16:44 INFO - Running setup.py (path:/tmp/pip-YTfPOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:16:44 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)) 21:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:16:44 INFO - Running setup.py (path:/tmp/pip-63WOqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:16:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:16:44 INFO - Running setup.py (path:/tmp/pip-u7ZmfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:16:44 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)) 21:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:16:44 INFO - Running setup.py (path:/tmp/pip-GSML6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:16:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:16:44 INFO - Running setup.py (path:/tmp/pip-Qr0HDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:16:45 INFO - Running setup.py (path:/tmp/pip-rBzTsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:16:45 INFO - Running setup.py (path:/tmp/pip-V1a6JV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:16:45 INFO - Running setup.py (path:/tmp/pip-7TYqLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:16:45 INFO - Running setup.py (path:/tmp/pip-ghTSoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:16:45 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)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:16:45 INFO - Running setup.py (path:/tmp/pip-1dCbdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:16:45 INFO - Running setup.py (path:/tmp/pip-AxhEuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:16:45 INFO - Running setup.py (path:/tmp/pip-mysy4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:16:45 INFO - Running setup.py (path:/tmp/pip-FcNH7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:16:45 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)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:45 INFO - Running setup.py (path:/tmp/pip-BOdOD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:16:45 INFO - Running setup.py (path:/tmp/pip-kJCe5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:16:45 INFO - Running setup.py (path:/tmp/pip-o7Ci98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:16:45 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)) 21:16:45 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)) 21:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:16:45 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)) 21:16:45 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)) 21:16:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:47 INFO - Downloading blessings-1.6.tar.gz 21:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:16:47 INFO - Installing collected packages: blessings 21:16:47 INFO - Running setup.py install for blessings 21:16:47 INFO - Successfully installed blessings 21:16:47 INFO - Cleaning up... 21:16:47 INFO - Return code: 0 21:16:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:16:48 INFO - Reading from file tmpfile_stdout 21:16:48 INFO - Current package versions: 21:16:48 INFO - argparse == 1.2.1 21:16:48 INFO - blessings == 1.6 21:16:48 INFO - blobuploader == 1.2.4 21:16:48 INFO - docopt == 0.6.1 21:16:48 INFO - functools32 == 3.2.3-2 21:16:48 INFO - jsonschema == 2.5.1 21:16:48 INFO - manifestparser == 1.1 21:16:48 INFO - mozInstall == 1.12 21:16:48 INFO - mozcrash == 0.17 21:16:48 INFO - mozdebug == 0.1 21:16:48 INFO - mozdevice == 0.48 21:16:48 INFO - mozfile == 1.2 21:16:48 INFO - mozhttpd == 0.7 21:16:48 INFO - mozinfo == 0.9 21:16:48 INFO - mozleak == 0.1 21:16:48 INFO - mozlog == 3.3 21:16:48 INFO - moznetwork == 0.27 21:16:48 INFO - mozprocess == 0.23 21:16:48 INFO - mozprofile == 0.28 21:16:48 INFO - mozrunner == 6.12 21:16:48 INFO - mozscreenshot == 0.1 21:16:48 INFO - mozsystemmonitor == 0.3 21:16:48 INFO - moztest == 0.7 21:16:48 INFO - mozversion == 1.4 21:16:48 INFO - psutil == 3.1.1 21:16:48 INFO - requests == 1.2.3 21:16:48 INFO - wsgiref == 0.1.2 21:16:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16: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')]} 21:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16: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')]} 21:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:48 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 0x7f0d5ef9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:16:48 INFO - Using env: {'DISPLAY': ':0', 21:16:48 INFO - 'HOME': '/home/cltbld', 21:16:48 INFO - 'LANG': 'en_US.UTF-8', 21:16:48 INFO - 'LANGUAGE': 'en_US:en', 21:16:48 INFO - 'LOGNAME': 'cltbld', 21:16:48 INFO - 'MAIL': '/var/mail/cltbld', 21:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:48 INFO - 'MOZ_NO_REMOTE': '1', 21:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:48 INFO - 'NO_EM_RESTART': '1', 21:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:48 INFO - 'PWD': '/builds/slave/test', 21:16:48 INFO - 'SHELL': '/bin/bash', 21:16:48 INFO - 'SHLVL': '1', 21:16:48 INFO - 'TERM': 'linux', 21:16:48 INFO - 'TMOUT': '86400', 21:16:48 INFO - 'USER': 'cltbld', 21:16:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:48 INFO - '_': '/tools/buildbot/bin/python'} 21:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:16:48 INFO - Cleaning up... 21:16:48 INFO - Return code: 0 21:16:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:16: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')]} 21:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16: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')]} 21:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:48 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 0x7f0d5ef9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:16:48 INFO - Using env: {'DISPLAY': ':0', 21:16:48 INFO - 'HOME': '/home/cltbld', 21:16:48 INFO - 'LANG': 'en_US.UTF-8', 21:16:48 INFO - 'LANGUAGE': 'en_US:en', 21:16:48 INFO - 'LOGNAME': 'cltbld', 21:16:48 INFO - 'MAIL': '/var/mail/cltbld', 21:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:48 INFO - 'MOZ_NO_REMOTE': '1', 21:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:48 INFO - 'NO_EM_RESTART': '1', 21:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:48 INFO - 'PWD': '/builds/slave/test', 21:16:48 INFO - 'SHELL': '/bin/bash', 21:16:48 INFO - 'SHLVL': '1', 21:16:48 INFO - 'TERM': 'linux', 21:16:48 INFO - 'TMOUT': '86400', 21:16:48 INFO - 'USER': 'cltbld', 21:16:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:48 INFO - '_': '/tools/buildbot/bin/python'} 21:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:16:48 INFO - Cleaning up... 21:16:48 INFO - Return code: 0 21:16:48 INFO - Running post-action listener: _resource_record_post_action 21:16:48 INFO - Running post-action listener: _start_resource_monitoring 21:16:48 INFO - Starting resource monitoring. 21:16:48 INFO - [mozharness: 2016-08-14 04:16:48.625032Z] Finished create-virtualenv step (success) 21:16:48 INFO - [mozharness: 2016-08-14 04:16:48.625484Z] Running install step. 21:16:48 INFO - Running pre-action listener: _resource_record_pre_action 21:16:48 INFO - Running main action method: install 21:16:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:16:48 INFO - Reading from file tmpfile_stdout 21:16:48 INFO - Detecting whether we're running mozinstall >=1.0... 21:16:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:16:48 INFO - Reading from file tmpfile_stdout 21:16:48 INFO - Output received: 21:16:48 INFO - Usage: mozinstall [options] installer 21:16:48 INFO - Options: 21:16:48 INFO - -h, --help show this help message and exit 21:16:48 INFO - -d DEST, --destination=DEST 21:16:48 INFO - Directory to install application into. [default: 21:16:48 INFO - "/builds/slave/test"] 21:16:48 INFO - --app=APP Application being installed. [default: firefox] 21:16:48 INFO - mkdir: /builds/slave/test/build/application 21:16:48 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'] 21:16:48 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 21:17:05 INFO - Reading from file tmpfile_stdout 21:17:05 INFO - Output received: 21:17:05 INFO - /builds/slave/test/build/application/firefox/firefox 21:17:05 INFO - Running post-action listener: _resource_record_post_action 21:17:05 INFO - [mozharness: 2016-08-14 04:17:05.837142Z] Finished install step (success) 21:17:05 INFO - [mozharness: 2016-08-14 04:17:05.837387Z] Running run-tests step. 21:17:05 INFO - Running pre-action listener: _resource_record_pre_action 21:17:05 INFO - Running main action method: run_tests 21:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:17:05 INFO - Python 2.7.3 21:17:05 INFO - Return code: 0 21:17:05 INFO - grabbing minidump binary from tooltool 21:17: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')]} 21:17:05 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 0x20c8370>, '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 0x20cb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:17:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:17:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:17:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:17:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:17:05 INFO - Return code: 0 21:17:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:17:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:17:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:17:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:17:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:17:05 INFO - Using env: {'DISPLAY': ':0', 21:17:05 INFO - 'HOME': '/home/cltbld', 21:17:05 INFO - 'LANG': 'en_US.UTF-8', 21:17:05 INFO - 'LANGUAGE': 'en_US:en', 21:17:05 INFO - 'LOGNAME': 'cltbld', 21:17:05 INFO - 'MAIL': '/var/mail/cltbld', 21:17:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:17:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:17:05 INFO - 'MOZ_NO_REMOTE': '1', 21:17:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:17:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:17:05 INFO - 'NO_EM_RESTART': '1', 21:17:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:17:05 INFO - 'PWD': '/builds/slave/test', 21:17:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:17:05 INFO - 'SHELL': '/bin/bash', 21:17:05 INFO - 'SHLVL': '1', 21:17:05 INFO - 'TERM': 'linux', 21:17:05 INFO - 'TMOUT': '86400', 21:17:05 INFO - 'USER': 'cltbld', 21:17:05 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321', 21:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:17:05 INFO - '_': '/tools/buildbot/bin/python'} 21:17:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:17:06 INFO - mozversion application_buildid: 20160813183001 21:17:06 INFO - mozversion application_changeset: b20c524ca0c476169855e301ed32474a28a3c630 21:17:06 INFO - mozversion application_display_name: Nightly 21:17:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:17:06 INFO - mozversion application_name: Firefox 21:17:06 INFO - mozversion application_remotingname: firefox 21:17:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:17:06 INFO - mozversion application_vendor: Mozilla 21:17:06 INFO - mozversion application_version: 51.0a1 21:17:06 INFO - mozversion platform_buildid: 20160813183001 21:17:06 INFO - mozversion platform_changeset: b20c524ca0c476169855e301ed32474a28a3c630 21:17:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:17:06 INFO - mozversion platform_version: 51.0a1 21:17:06 INFO - using testdate: 1471148226 21:17:06 INFO - actual date: 1471148226 21:17:06 INFO - starting webserver on 'localhost:57809' 21:17:06 INFO - SUITE-START | Running 2 tests 21:17:06 INFO - TEST-START | dromaeo_css 21:17:06 INFO - operating with platform_type : linux_ 21:17:06 INFO - Initialising browser for dromaeo_css test... 21:17:06 INFO - TEST-INFO | started process 28995 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPUm1UZ/profile http://localhost:57809/getInfo.html) 21:17:10 INFO - PROCESS | 28995 | __metrics Screen width/height:1600/1200 21:17:10 INFO - PROCESS | 28995 | colorDepth:24 21:17:10 INFO - PROCESS | 28995 | Browser inner width/height: 1024/697 21:17:10 INFO - PROCESS | 28995 | __metrics 21:17:11 INFO - PROCESS | 28995 | 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" 21:17:11 INFO - PROCESS | 28995 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:17:12 INFO - TEST-INFO | 28995: exit 0 21:17:12 INFO - Browser initialized. 21:17:12 INFO - Running cycle 1/1 for dromaeo_css test... 21:17:12 INFO - TEST-INFO | started process 29077 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPUm1UZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:17:23 INFO - PROCESS | 29077 | RSS: Main: 155758592 21:17:23 INFO - PROCESS | 29077 | 21:18:15 INFO - PROCESS | 29077 | 1471148295356 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:20:24 INFO - PROCESS | 29077 | Cycle 1(1): loaded http://localhost:57809/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57809/tests/dromaeo/cssquery-ext.html) 21:20:25 INFO - PROCESS | 29077 | RSS: Main: 245559296 21:20:25 INFO - PROCESS | 29077 | 21:23:27 INFO - PROCESS | 29077 | Cycle 1(1): loaded http://localhost:57809/tests/dromaeo/cssquery-ext.html (next: http://localhost:57809/tests/dromaeo/cssquery-jquery.html) 21:23:28 INFO - PROCESS | 29077 | RSS: Main: 248332288 21:23:28 INFO - PROCESS | 29077 | 21:26:30 INFO - PROCESS | 29077 | Cycle 1(1): loaded http://localhost:57809/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57809/tests/dromaeo/cssquery-mootools.html) 21:26:30 INFO - PROCESS | 29077 | RSS: Main: 219967488 21:26:30 INFO - PROCESS | 29077 | 21:29:32 INFO - PROCESS | 29077 | Cycle 1(1): loaded http://localhost:57809/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57809/tests/dromaeo/cssquery-prototype.html) 21:29:33 INFO - PROCESS | 29077 | RSS: Main: 196411392 21:29:33 INFO - PROCESS | 29077 | 21:32:36 INFO - PROCESS | 29077 | Cycle 1(1): loaded http://localhost:57809/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57809/tests/dromaeo/cssquery-yui.html) 21:32:36 INFO - PROCESS | 29077 | RSS: Main: 186462208 21:32:36 INFO - PROCESS | 29077 | 21:35:39 INFO - PROCESS | 29077 | Cycle 1(1): loaded http://localhost:57809/tests/dromaeo/cssquery-yui.html (next: http://localhost:57809/tests/dromaeo/cssquery-dojo.html) 21:35:40 INFO - PROCESS | 29077 | RSS: Main: 276156416 21:35:40 INFO - PROCESS | 29077 | 21:35:40 INFO - PROCESS | 29077 | __start_tp_report 21:35:40 INFO - PROCESS | 29077 | _x_x_mozilla_page_load 21:35:40 INFO - PROCESS | 29077 | _x_x_mozilla_page_load_details 21:35:40 INFO - PROCESS | 29077 | |i|pagename|runs| 21:35:40 INFO - PROCESS | 29077 | |0;dojo.html;3027.3491199392124;3027.4096551172524;3049.6340439147307;3060.1584564244818;3064.708852658998;5142.0487209866205;5202.557782588485;5249.92125118123;5256.605754568403;5299.2581038654525;5224.529792318691;5248.186531087678;5289.471052894718;5305.363356397238;5310.256564081022;4716.268978308368;4798.7840547175465;4801.951980480186;4808.639352048593;4990.700557966516;4898.828541001066;4910.803567857281;4946.777395017215;4951.183054795973;4970.6520543561965;4656.301554766778;4679.953200467986;4739.218029025206;4795.520447955211;4865.732384718839;4741.122892264942;4741.5495527924795;4752.667313288071;4765.809367625291;4857.392825896763;4779.641526885463;4787.186178349654;4791.4250289965075;4793.065352256312;4820.156472617278;5122.33409656746;5140.794368225266;5212.817551385683;5222.97388513055;5324.893502129936;5564.916526252109;5609.046462101453;5655.5475561954945;5657.8019769308285;5657.8585535361535;5691.51622112121;5709.685967271798;5710.457506536873;5715.199872017921;5716.828495145152;5148.922766158473;5200.3759548854005;5251.081060814342;5255.0278198533015;5339.572834173335;5820.912686309709;5824.009918313896;5824.563157763185;5834.066549352084;5840.036393995028;5493.313335833021;5499.752511136929;5541.168824676251;5600.075987462104;5602.215689803431;5483.314585676791;5590.664560126405;5623.072193088176;5630.07103827872;5668.659880407189;5622.718864056781;5627.127795191752;5627.746751396197;5683.772649094072;5722.113072473773;5581.5813813963305;5626.324841019104;5639.718014099279;5640.717964101778;5641;4272.124214536013;4282.9785851071165;4291.03451723366;4356.825726970886;4428.025834316445;3839.7504162229925;3875.399313106473;3913.021744563859;3940.9802950985063;3941.2708648900043;5377.139657654835;5383.16560933056;5383.273258110184;5384.165454354581;5428.077226871363;5675.886482270332;5677.460641239076;5683.715814209273;5685.175649530877;5709.685967271756;5196.76614552346;5237.502437268454;5245.134552798745;5366.812161574326;5375.892482150335;4821.54195351441;4854.587360074363;4866.197077482176;4889.828855990023;4907.852764417074;5799.2750906136735;5843.561732870052;5854.443827836349;5859.941400586024;5913.083472061838;5474.233607294902;5569.053260945568;5573.832785016456;5648.350439699323;5659.037963546125;5821.505172060422;5835.387284335084;5835.7373918174635;5869.706514674334;5882.352941176553;5526.640768350045;5531.391546929754;5542.861428464321;5548.694821784679;5559.861003474945;5490.533304668988;5596.916046259391;5654.06707893193;5691.004074287066;5700.002499562477;5569.832905012856;5573.860653483695;5574.554035677055;5603.10350343943;5609.382967873449;5516.9448305516435;5541.806036788855;5561.777528898799;5564.527015203753;5575.470330318613;5280.471952804689;5386.030514507426;5438.129899216106;5491.917621235605;5559;5619.185218143264;5648.068068768607;5657.519110875457;5659.292588426447;5664.178694089416;5421.186821976735;5468.371137319259;5469.972650136724;5474.397816240131;5475.863103422446 21:35:40 INFO - PROCESS | 29077 | |1;ext.html;572.3176903103572;582.4263100845667;588.9116632505127;601.0353382820575;604.3110853626865;41389;42808.35787463187;42840.928976775554;42861.78569107158;46069;45423;45856.77071614646;45915.540844591545;45974.77012614932;45977;8818.470891746483;8945.865812012826;8957.776055598597;8963.417377870417;8966.327525435585;8920.10798920106;8994.190522852941;9003.639854405816;9018.413803102778;9021.323400744937;9055.773605659846;9087.500562407195;9098.363114581982;9099.272058235325;9103.135202155818;9024.819503609924;9050.77373065672;9063.41087829289;9066.184043436057;9069.59186836594;8670.52312122833;8720.651173953034;8728.956355218183;8743.825123497494;8745.650173993032;42186.57813421857;42411.363829542584;42592.361114583306;42607.147857043;42618.360724589154;3279.360524697685;3471.1842716961496;3547.385939397568;3688.944665830015;3884.844606215748;31657.683423165705;32493.83753081243;32525.83737081323;32548.18629534257;32972.83513582441;3982.741121827101;4115.567865374122;4125.319322311815;4152.854650087263;4251.638610718092;345669;346249;346307.3692630742;346761;347317;60134;60516.39483605108;60518.697406513566;60542.091868622;60569.39430605726;61124;61230.693846530485;61255.693721531105;61290;61324.38675613276;15169.848301516844;15450.768238476432;15450.845491545166;15466.845331546765;15467.690646187239;11260.831087533694;11298.491567879466;11328.150388720878;11328.830067548994;11330.09359251258;13606.455741770218;17389.47831565055;17420.128993550527;17445.389411370543;17650.735238971425;5099.923501147486;5715.828525144252;5740.741666625012;5750.568707346965;5931.555133365015;936.2463217110292;1058.4178701714027;1108.2242430298822;1128.841962125303;1168.7779321928977;32115.839420802746;32471.51292730611;32967;33523.4971475428;33874.49188262129;1053.3837704942694;1065.4406436620682;1113.2429947635685;1125.3754166437707;1152.4703276308423;15589.39335865344;16661.166941652868;17002.659946800995;17042;17156.571085722706;442594;448364;448429;448584;449165.5417229126;57908.42091579115;57986.55033624109;58086.709566452744;58117.4188258112;58196;1245.2839617219954;1270.6442196185083;1296.7600993816175;1386.7157232767258;1407.7184563087424;29414.11757647274;32055.67944320527;33293.16767080843;33315.33369332648;33922;49789.518943295276;50367;50387;50413.4875953722;50435.495645044546;380322;380716;381552;382290;382332;27424;28603.713962860103;28901;30079.548806768355;30132.69867301299;47406.76296618495;47589.337492750354;48411.5158848407;48793.2681009778;49254;38968;40016.79991600024;41398;41727.791361043;43158;39688;40580.59419405768;40810.59189408187;41317.58682413137;42113.57886421097;41137;41316.38025429681;41462.37806432966;41980.79009604932;42284;33879.830600846835;36942.630573694994;37116.81441592775;37418.006559474634;37642;28109.57835632508;28418.57372139461;28419.431611367243;28423.715762842934;28787.85606071956 21:35:40 INFO - PROCESS | 29077 | |2;jquery.html;367.6195138032134;382.2507884546289;397.2392867658434;398.58945286355055;411.8640848519989;14345.64759215811;17258.65482690347;19248.326308579202;19961.900190499025;19963.800361996393;18277.817221827776;18325.633487330244;18387.63224735508;18591.442256732287;18609.7208541872;4180.205760905418;5570.136628822539;5740.052891272935;5791.131330300467;5794.5074668653215;4804.663673542854;6334.873302533946;6461.256955450136;6541.443977261938;6559.34406559345;6258.405451482102;6427.357264273559;6464.579802312858;6489.02664600309;6517.608943463408;6446.774362897299;6449.903251451232;6471.870562588745;6477.708503117371;6482.416582507597;5114.872128196825;5354.732263386815;5410.1073322901675;5478.808241711561;5486.286782718261;14144.646383840383;14186.148831070168;14263.928680356532;14279.500217492337;14377.56867293983;2212.126210146983;2220.455988282865;2282.589133955887;2287.0508738873345;2287.2452090809993;10085.647002354955;10424.68725938223;10831.945840270828;13127.884129848975;13533.729325413535;1889.1498825528568;1946.347973428903;1961.3135402609116;2000.1899230811543;2001.1595130045416;79550.20449795543;82717.17282827155;86391.56804216001;87039;88083;10848.566057357775;10858.945705271422;11055.889441105646;11174.329540227613;11385.259958102697;15351.155686437352;15358.385664573292;15371.461998829987;15374.077555346716;15390.923045384701;7410.888836667455;7637.770866874002;7704.961475192588;7804.2195780422635;7806.960965195138;7000.334968178015;7052.682629281695;7052.929470705228;7235.493515453868;7236.710531578678;4842.564169224786;4845.709257444564;4856.3201151838775;4910;5014.5737612303365;1852.3239017758422;1909.8663093583593;1920.9903950480439;1939.166158551808;1971.4085774267662;908.2733812949614;964.1226483899618;1004.7839714461426;1020.9438480883474;1067.8398240264023;17671.73492397615;19694;19700.408987730392;19760.308389206308;20012.49968750793;979.740368802268;987.0672214756986;1010.4190090697878;1032.230987914009;1050.3120456101267;10211;10828.891711082946;10851.40317282557;10953.635851885827;10979.780404391868;451165;455602;473943;476427;479235;88140.5592972031;88287.55856220677;89095.55452222697;90393;90684;970.2044323654677;985.6303886042734;993.6671215143008;1006.8187726209206;1009.152312057875;16538.58653533671;16791.74812377791;17021.48935531936;17661.735073973643;17968.910155449663;39994.400083999346;42361.82902584493;42719.786401067795;42999;43127.56872431361;54899.58855554449;56211.156832646724;56381;56783.43216567781;57093.42906570881;8346.874796878174;8500.787480313042;8663.166598591268;8721.389502734748;8969.416987895765;12456.875431245933;13108.147970381895;13271.469141234242;13277.336133193108;13539.59381218565;11253.549858005916;11559.19085664029;11664.825027624423;11875.049996000127;12005.379273798275;11365.886341136484;11503.079753619857;11716.121290903387;11744;11816.40917954116;10644.20168487382;11666.650000499998;11868.881311187122;11908.880911190778;12038.699032524257;8577.056523782254;8583.742487725378;8707.521086340312;8844.469331839853;9054.411463254868;9314.95342523283;11136.443177841238;11495.942520287344;12060.51757929673;12108.757824843278 21:35:40 INFO - PROCESS | 29077 | |3;mootools.html;1302.6287508060204;1464.1361596657969;1485.7177136344094;1594.8165960914491;1718.5531761741943;7396.926030739691;7697.345725613322;7764.107127680319;8000.239977202171;8018.198180181979;7870.881936770938;7973.681052757883;7977.641006154722;7981.321587665041;7981.800454988628;1992.8804271743743;2214.745304290003;2288.313505948213;2305.5043165719367;2311.2603966730653;2290.1068583252545;2312.3756585721844;2315.340128063502;2317.455397981473;2318.9652155217686;2248.8425810193294;2252.988735056331;2260.3445000949705;2263.479399738053;2300.3673989652893;2190.1896298369547;2260.5930932432157;2267.331137314488;2269.614165591853;2272.7727227277305;2278.305116939333;2299.241250387368;2302.7236731592147;2302.781235782598;2304.7003889494426;8028;8073.919260807375;8074;8079.232472915063;8084.272415482576;4814.205656066745;4938.753062346868;4941.901161976776;4943.752812359367;4945.67853089548;3145.2608636970294;3191.5850939377733;3372.106391806173;3373.224158443572;3376.1728376547903;1158.29922896648;1175.647305808254;2037.7554693436734;2075.0869617368307;2110.778368271325;10416.42709650969;10451.111655509214;10527.894571070081;10538;10549.367037977747;4131.566185550534;4210.7473551587;4339.305711086211;4347.9565204347555;4351.0645211279725;8547.444416112932;8553.529555874362;8571.314294856522;8571.742847714579;8591.44155629882;4802.687825291345;4804.2073057945745;4807.254875494304;4817.759112044384;4820.493848145964;4045.2314060328445;4396.252637051709;4445.844395446208;4529.818807247739;4532.320151977164;3524.6299138590593;3575.3564358415733;3603.4594810778594;3629.7822130672243;3648.927021459556;1087.5867170475326;1104.3815463340395;1114.1254115519278;1118.222835129577;1123.5056575106926;834.3950635789005;954.8376775948105;985.8373368394134;988.1896844587369;990.1732053735068;5364.651297665718;5553.416891226363;5602.635828671123;5680.488756011896;5763.423657634203;842.751388340441;866.4627930683017;868.1188593577524;903.0698380667791;912.5163663258484;3696.7966761828357;3735.1969326594367;3831.310364134427;3833.559140698855;3864.265789499986;9344.859827102599;9702;9716.75708107303;9728.075832795874;9734.707958761248;9587.520623968774;9623.807523849484;9640.325177237666;9640.469774162348;9654.420734755937;7408.85182296351;7486.326230639269;7525.247475252431;7525.811854703786;7527.209642987517;5333.7866485340155;5370.491543235561;5443.564514838883;5491.862703432446;5602.803901863415;8638.395312328077;8683.869741954002;8692.78268043304;8850.424722393023;8868.15752503511;8786.16531429513;8908.777280568038;9016.729498115066;9022.774430639287;9027.954860225656;6150.323464419;6668.866622667617;6836.658167091725;6849.876538273152;6976.651167441506;7791.104023037423;7881.921180788118;7885.763427097197;7912.169222231582;7918.604069796603;7140.250273721184;7164.820879478055;7200.927990720026;7216.458765592497;7415.184329723618;6981.825454363681;7026.6135362553505;7053.647317633995;7122.715091396286;7152.499325047197;6052.485538729257;6188.876222475616;6247.6563788990225;6248.125262463168;6271.905921411091;4956.10790057793;5010.899782004267;5054.620903432184;5126.4617215191865;5139.254808052737;4834.709917404967;4887.413510378778;4986.626003049713;5015.17249653803;5076.949230507647 21:35:40 INFO - PROCESS | 29077 | |4;prototype.html;318.1092939768646;334.95980482342117;341.6600482519893;342.39054483020215;342.73609320822965;5325.707086110262;5367.75845086971;5371.1674690423;5389.73051347433;5407.297051383314;4963.3547638806995;5034.597232221423;5185.799502403056;5226.503482169188;5292.338457692788;4160.2303573838935;4165.33354663254;4173.6661067114555;4178.206140833247;4200.886765007275;4214.5459816363455;4216.5361810201;4221.831126754926;4231.809568569406;4233.618974292313;3928.5285765708154;3992.4610177626087;4026.3960405939197;4137.937930931039;4161.729487583312;4162.750234985895;4174.144300418407;4184.351425529047;4185.414042034117;4193.03560181157;3698.2048859495067;3698.33429982603;3705.925881482382;3712.628737126293;3716.4425336199515;4979.950200498021;5121.385433747974;5150.71232191952;5202.193659982709;5211.947880521184;4738.265568836836;4738.336632871401;4781.450133234688;4798.4001999750035;4798.496157903405;5468.808591699271;5504.862378440571;5505.256790333294;5509.669419834822;5514.531264842493;3972.940405893914;4045.817938192759;4056.432099506042;4065.9796701016603;4070.674346052309;5687.857803554903;5689.9146512802345;5695.259616249861;5703.62926409782;5715.971420142914;4150.584941505826;4157.189348077096;4161.375793630979;4162.438070860456;4187.476565429321;4015.217032678658;4163.708540402143;4221.493420789525;4239.97880010598;4240.151969606091;4722;4729.9054018920115;4747.098051370229;4762.666613337103;4767.976160119178;5533.529649979797;5542.168674698747;5548.306461692288;5552.805651802168;5557.805476808373;1344.610063081715;1402.747505449029;1442.9855701443062;1464.5386703188462;1466.9633259168393;905.9547022648841;944.2304521814837;976.9071938165863;980.8283550378655;984.0995489127512;5252.553532949666;5311.7690569960705;5357.098230128327;5364.390682795146;5385.838424847261;5619.297587801525;5621.156826476062;5631.18347839561;5639.492445679908;5645.830625081254;5231.424543300234;5269.604779641542;5282.392524859613;5284.735763211824;5340.305760251143;2945.9705402946124;2963.3776906849803;2967.243352945028;2973.9702602974126;2995.445842519141;5565.193047008162;5769.451902069297;5833.620814647034;5859.326177489558;5863.560232982543;5652.943470565324;5682.77268909239;5705.144228365695;5723.6851973141875;5724.313082430135;5804.970975145097;5819.621724587888;5821.27234095738;5840;5881.823545293648;5370.62405631602;5567.109262517978;5567.8608034797835;5591.748371323381;5602.571344307175;5530.253415788817;5545.223668686468;5564.38791732901;5592.608517403905;5596.972015139898;5564.554835613222;5844.912326315194;5862.472377486047;5866.50134738552;5869.442402971711;4050.959490405058;4087.6729861610406;4090.795460227036;4106.589341065869;4122.835086596502;5764.19301297827;5802.390748971297;5820.476157145878;5828.5520081565;5865.120231965239;5373.570114390761;5508.08071721988;5672.546196304368;5716.456936591017;5721.369409718211;5558.777648893999;5571.164325351229;5575.665460072409;5587.245721827501;5593.356763972195;5274.868128296824;5360.946390536044;5511.669299842022;5532.6680399176175;5591.077472217039;5575.804846830504;5578.693171875586;5583.972080139573;5586.664800112007;5601.60788744784;5474.781008759605;5488.506034456758;5496.5053145217125;5525.182157099827;5557.41647127062 21:35:40 INFO - PROCESS | 29077 | |5;yui.html;707.7805880177145;720.4848533298697;730.6638946084801;739.2348918868971;802.6949759091544;15016.549503514885;16367.999999999985;16370.181490925466;16377.590560236;16473;14098.436062557512;14162.71674566508;14175.220362880038;14176.716465670681;14198.361073751681;661.5402295404696;778.4550814429907;796.9601519924009;797.6729540888236;800.0799081056773;656.6913550631195;662.5395350231591;662.7084083003463;769.7652216074096;800.3277247112425;854.5214679779313;867.7483529776357;870.1689886158734;871.5163084488106;872.1583671756754;865.2213008292524;865.4417900454172;866.1944391715701;866.27233124176;866.4671227195292;508.72783061062154;602.9457348838625;620.9192804935375;643.8261669349296;644.2751904107879;14713.70572588553;14815.18516481593;14841.25793710321;14852.331645075998;14853.183074930874;14110.294485275695;14531.418743250257;14753.92623036878;14961.476348327757;15012.249387530579;13160.539381121614;13359.198448093146;13394.263315517643;13398.732025359439;14069.226192559405;4488.147252022105;4585.12882552314;4585.3122031695175;4608.400907881987;4617.607503362218;83100.99999999939;83969.58015209946;84787;84960;85126.14873851243;11364.943175284236;11544.134189935789;11602.245854019462;11685.415729213506;11814.173007889538;22585.548289034126;22896;22901.885490572775;22978.885105574365;22997.770022299897;8716.171963663443;8735.956320218358;8836.513991730517;8941.910580894108;8977.371583989057;4739.478657347689;4950.430700469492;5009.020797398844;5023.422306434807;5068.113080210948;3218.1471909943884;3294.341131773655;3296.620888597794;3312.3540909522894;3320.070380293522;542.6174546944395;547.0535972767135;549.4121290219507;551.0851985703713;585.8798946216016;577.4889223037642;597.4294548705975;653.1672118049524;685.9965700171567;734.4051318432087;9025.684101056562;9318.95340523293;9758.7713834394;9774.902250977399;10238.129758970423;626.7085805100559;669.1067424987696;669.9732010719614;680.9080774095434;723.7068987060144;4696.483386827446;4741.099191153669;4774.379330686988;4781.163296423112;4808.7595620219545;121270;123751;126092;127677;129512;105800.01399762058;106382.38669959422;107017.4649126765;108016.4599176999;111763.4411827952;90983;93217.53391233001;93566;94282.52858735662;95564.9999999986;8818.91181088181;8856.911430885608;8939.821203575762;8951.552422378985;9190.678326258549;51401.48598513967;53170.73414632902;53337.73331133319;53461.465385347205;54651.667653444165;72183.27816721976;72594.63702681453;75319.62340188264;77673.44653106794;81278.18721812706;11393.658190254306;11426.48580813882;11821.172517923662;12009.81985270239;12234.327112008925;13256.93371533136;13508.459661613426;13713;14165.575032749033;14302.713945721236;14236.715265694835;14448;14504.927475362556;15149.394024238907;15166.49169027192;10297.433641149808;13168;13924.860751392762;14694.706105878036;15039.398424062914;13689.45242190341;14345.641358965691;15114.244287785346;15556.144412056992;15572.221388930735;11142.498587563741;11222.494987725735;11240.55037798479;11343.262687925258;11842.940785296018;11145.219834611824;12089.214201076902;12407.594294833178;12444.688882778002;12643 21:35:40 INFO - PROCESS | 29077 | __end_tp_report 21:35:40 INFO - PROCESS | 29077 | __start_cc_report 21:35:40 INFO - PROCESS | 29077 | _x_x_mozilla_cycle_collect,920 21:35:40 INFO - PROCESS | 29077 | __end_cc_report 21:35:40 INFO - PROCESS | 29077 | __startTimestamp1471149340157__endTimestamp 21:35:40 INFO - PROCESS | 29077 | 21:35:40 INFO - PROCESS | 29077 | ------- Summary: start ------- 21:35:40 INFO - PROCESS | 29077 | Number of tests: 6 21:35:40 INFO - PROCESS | 29077 | 21:35:40 INFO - PROCESS | 29077 | [#0] dojo.html Cycles:165 Average:5250.26 Median:5474.40 stddev:595.82 (10.9%) stddev-sans-first:571.56 21:35:40 INFO - PROCESS | 29077 | Values: 3027.3 3027.4 3049.6 3060.2 3064.7 5142.0 5202.6 5249.9 5256.6 5299.3 5224.5 5248.2 5289.5 5305.4 5310.3 4716.3 4798.8 4802.0 4808.6 4990.7 4898.8 4910.8 4946.8 4951.2 4970.7 4656.3 4680.0 4739.2 4795.5 4865.7 4741.1 4741.5 4752.7 4765.8 4857.4 4779.6 4787.2 4791.4 4793.1 4820.2 5122.3 5140.8 5212.8 5223.0 5324.9 5564.9 5609.0 5655.5 5657.8 5657.9 5691.5 5709.7 5710.5 5715.2 5716.8 5148.9 5200.4 5251.1 5255.0 5339.6 5820.9 5824.0 5824.6 5834.1 5840.0 5493.3 5499.8 5541.2 5600.1 5602.2 5483.3 5590.7 5623.1 5630.1 5668.7 5622.7 5627.1 5627.7 5683.8 5722.1 5581.6 5626.3 5639.7 5640.7 5641.0 4272.1 4283.0 4291.0 4356.8 4428.0 3839.8 3875.4 3913.0 3941.0 3941.3 5377.1 5383.2 5383.3 5384.2 5428.1 5675.9 5677.5 5683.7 5685.2 5709.7 5196.8 5237.5 5245.1 5366.8 5375.9 4821.5 4854.6 4866.2 4889.8 4907.9 5799.3 5843.6 5854.4 5859.9 5913.1 5474.2 5569.1 5573.8 5648.4 5659.0 5821.5 5835.4 5835.7 5869.7 5882.4 5526.6 5531.4 5542.9 5548.7 5559.9 5490.5 5596.9 5654.1 5691.0 5700.0 5569.8 5573.9 5574.6 5603.1 5609.4 5516.9 5541.8 5561.8 5564.5 5575.5 5280.5 5386.0 5438.1 5491.9 5559.0 5619.2 5648.1 5657.5 5659.3 5664.2 5421.2 5468.4 5470.0 5474.4 5475.9 21:35:40 INFO - PROCESS | 29077 | 21:35:40 INFO - PROCESS | 29077 | [#1] ext.html Cycles:180 Average:56193.89 Median:41393.50 stddev:103855.54 (250.9%) stddev-sans-first:104062.92 21:35:40 INFO - PROCESS | 29077 | Values: 572.3 582.4 588.9 601.0 604.3 41389.0 42808.4 42840.9 42861.8 46069.0 45423.0 45856.8 45915.5 45974.8 45977.0 8818.5 8945.9 8957.8 8963.4 8966.3 8920.1 8994.2 9003.6 9018.4 9021.3 9055.8 9087.5 9098.4 9099.3 9103.1 9024.8 9050.8 9063.4 9066.2 9069.6 8670.5 8720.7 8729.0 8743.8 8745.7 42186.6 42411.4 42592.4 42607.1 42618.4 3279.4 3471.2 3547.4 3688.9 3884.8 31657.7 32493.8 32525.8 32548.2 32972.8 3982.7 4115.6 4125.3 4152.9 4251.6 345669.0 346249.0 346307.4 346761.0 347317.0 60134.0 60516.4 60518.7 60542.1 60569.4 61124.0 61230.7 61255.7 61290.0 61324.4 15169.8 15450.8 15450.8 15466.8 15467.7 11260.8 11298.5 11328.2 11328.8 11330.1 13606.5 17389.5 17420.1 17445.4 17650.7 5099.9 5715.8 5740.7 5750.6 5931.6 936.2 1058.4 1108.2 1128.8 1168.8 32115.8 32471.5 32967.0 33523.5 33874.5 1053.4 1065.4 1113.2 1125.4 1152.5 15589.4 16661.2 17002.7 17042.0 17156.6 442594.0 448364.0 448429.0 448584.0 449165.5 57908.4 57986.6 58086.7 58117.4 58196.0 1245.3 1270.6 1296.8 1386.7 1407.7 29414.1 32055.7 33293.2 33315.3 33922.0 49789.5 50367.0 50387.0 50413.5 50435.5 380322.0 380716.0 381552.0 382290.0 382332.0 27424.0 28603.7 28901.0 30079.5 30132.7 47406.8 47589.3 48411.5 48793.3 49254.0 38968.0 40016.8 41398.0 41727.8 43158.0 39688.0 40580.6 40810.6 41317.6 42113.6 41137.0 41316.4 41462.4 41980.8 42284.0 33879.8 36942.6 37116.8 37418.0 37642.0 28109.6 28418.6 28419.4 28423.7 28787.9 21:35:40 INFO - PROCESS | 29077 | 21:35:40 INFO - PROCESS | 29077 | [#2] jquery.html Cycles:180 Average:28104.92 Median:2287.15 stddev:77227.82 (3376.6%) stddev-sans-first:77416.38 21:35:40 INFO - PROCESS | 29077 | Values: 367.6 382.3 397.2 398.6 411.9 14345.6 17258.7 19248.3 19961.9 19963.8 18277.8 18325.6 18387.6 18591.4 18609.7 4180.2 5570.1 5740.1 5791.1 5794.5 4804.7 6334.9 6461.3 6541.4 6559.3 6258.4 6427.4 6464.6 6489.0 6517.6 6446.8 6449.9 6471.9 6477.7 6482.4 5114.9 5354.7 5410.1 5478.8 5486.3 14144.6 14186.1 14263.9 14279.5 14377.6 2212.1 2220.5 2282.6 2287.1 2287.2 10085.6 10424.7 10831.9 13127.9 13533.7 1889.1 1946.3 1961.3 2000.2 2001.2 79550.2 82717.2 86391.6 87039.0 88083.0 10848.6 10858.9 11055.9 11174.3 11385.3 15351.2 15358.4 15371.5 15374.1 15390.9 7410.9 7637.8 7705.0 7804.2 7807.0 7000.3 7052.7 7052.9 7235.5 7236.7 4842.6 4845.7 4856.3 4910.0 5014.6 1852.3 1909.9 1921.0 1939.2 1971.4 908.3 964.1 1004.8 1020.9 1067.8 17671.7 19694.0 19700.4 19760.3 20012.5 979.7 987.1 1010.4 1032.2 1050.3 10211.0 10828.9 10851.4 10953.6 10979.8 451165.0 455602.0 473943.0 476427.0 479235.0 88140.6 88287.6 89095.6 90393.0 90684.0 970.2 985.6 993.7 1006.8 1009.2 16538.6 16791.7 17021.5 17661.7 17968.9 39994.4 42361.8 42719.8 42999.0 43127.6 54899.6 56211.2 56381.0 56783.4 57093.4 8346.9 8500.8 8663.2 8721.4 8969.4 12456.9 13108.1 13271.5 13277.3 13539.6 11253.5 11559.2 11664.8 11875.0 12005.4 11365.9 11503.1 11716.1 11744.0 11816.4 10644.2 11666.7 11868.9 11908.9 12038.7 8577.1 8583.7 8707.5 8844.5 9054.4 9315.0 11136.4 11495.9 12060.5 12108.8 21:35:40 INFO - PROCESS | 29077 | 21:35:40 INFO - PROCESS | 29077 | [#3] mootools.html Cycles:180 Average:5279.12 Median:5349.22 stddev:2815.96 (52.6%) stddev-sans-first:2808.00 21:35:40 INFO - PROCESS | 29077 | Values: 1302.6 1464.1 1485.7 1594.8 1718.6 7396.9 7697.3 7764.1 8000.2 8018.2 7870.9 7973.7 7977.6 7981.3 7981.8 1992.9 2214.7 2288.3 2305.5 2311.3 2290.1 2312.4 2315.3 2317.5 2319.0 2248.8 2253.0 2260.3 2263.5 2300.4 2190.2 2260.6 2267.3 2269.6 2272.8 2278.3 2299.2 2302.7 2302.8 2304.7 8028.0 8073.9 8074.0 8079.2 8084.3 4814.2 4938.8 4941.9 4943.8 4945.7 3145.3 3191.6 3372.1 3373.2 3376.2 1158.3 1175.6 2037.8 2075.1 2110.8 10416.4 10451.1 10527.9 10538.0 10549.4 4131.6 4210.7 4339.3 4348.0 4351.1 8547.4 8553.5 8571.3 8571.7 8591.4 4802.7 4804.2 4807.3 4817.8 4820.5 4045.2 4396.3 4445.8 4529.8 4532.3 3524.6 3575.4 3603.5 3629.8 3648.9 1087.6 1104.4 1114.1 1118.2 1123.5 834.4 954.8 985.8 988.2 990.2 5364.7 5553.4 5602.6 5680.5 5763.4 842.8 866.5 868.1 903.1 912.5 3696.8 3735.2 3831.3 3833.6 3864.3 9344.9 9702.0 9716.8 9728.1 9734.7 9587.5 9623.8 9640.3 9640.5 9654.4 7408.9 7486.3 7525.2 7525.8 7527.2 5333.8 5370.5 5443.6 5491.9 5602.8 8638.4 8683.9 8692.8 8850.4 8868.2 8786.2 8908.8 9016.7 9022.8 9028.0 6150.3 6668.9 6836.7 6849.9 6976.7 7791.1 7881.9 7885.8 7912.2 7918.6 7140.3 7164.8 7200.9 7216.5 7415.2 6981.8 7026.6 7053.6 7122.7 7152.5 6052.5 6188.9 6247.7 6248.1 6271.9 4956.1 5010.9 5054.6 5126.5 5139.3 4834.7 4887.4 4986.6 5015.2 5076.9 21:35:40 INFO - PROCESS | 29077 | 21:35:40 INFO - PROCESS | 29077 | [#4] prototype.html Cycles:180 Average:4678.07 Median:5362.67 stddev:1359.47 (25.4%) stddev-sans-first:1323.31 21:35:40 INFO - PROCESS | 29077 | Values: 318.1 335.0 341.7 342.4 342.7 5325.7 5367.8 5371.2 5389.7 5407.3 4963.4 5034.6 5185.8 5226.5 5292.3 4160.2 4165.3 4173.7 4178.2 4200.9 4214.5 4216.5 4221.8 4231.8 4233.6 3928.5 3992.5 4026.4 4137.9 4161.7 4162.8 4174.1 4184.4 4185.4 4193.0 3698.2 3698.3 3705.9 3712.6 3716.4 4980.0 5121.4 5150.7 5202.2 5211.9 4738.3 4738.3 4781.5 4798.4 4798.5 5468.8 5504.9 5505.3 5509.7 5514.5 3972.9 4045.8 4056.4 4066.0 4070.7 5687.9 5689.9 5695.3 5703.6 5716.0 4150.6 4157.2 4161.4 4162.4 4187.5 4015.2 4163.7 4221.5 4240.0 4240.2 4722.0 4729.9 4747.1 4762.7 4768.0 5533.5 5542.2 5548.3 5552.8 5557.8 1344.6 1402.7 1443.0 1464.5 1467.0 906.0 944.2 976.9 980.8 984.1 5252.6 5311.8 5357.1 5364.4 5385.8 5619.3 5621.2 5631.2 5639.5 5645.8 5231.4 5269.6 5282.4 5284.7 5340.3 2946.0 2963.4 2967.2 2974.0 2995.4 5565.2 5769.5 5833.6 5859.3 5863.6 5652.9 5682.8 5705.1 5723.7 5724.3 5805.0 5819.6 5821.3 5840.0 5881.8 5370.6 5567.1 5567.9 5591.7 5602.6 5530.3 5545.2 5564.4 5592.6 5597.0 5564.6 5844.9 5862.5 5866.5 5869.4 4051.0 4087.7 4090.8 4106.6 4122.8 5764.2 5802.4 5820.5 5828.6 5865.1 5373.6 5508.1 5672.5 5716.5 5721.4 5558.8 5571.2 5575.7 5587.2 5593.4 5274.9 5360.9 5511.7 5532.7 5591.1 5575.8 5578.7 5584.0 5586.7 5601.6 5474.8 5488.5 5496.5 5525.2 5557.4 21:35:40 INFO - PROCESS | 29077 | 21:35:40 INFO - PROCESS | 29077 | [#5] yui.html Cycles:180 Average:21988.65 Median:4657.05 stddev:32483.40 (697.5%) stddev-sans-first:32535.22 21:35:40 INFO - PROCESS | 29077 | Values: 707.8 720.5 730.7 739.2 802.7 15016.5 16368.0 16370.2 16377.6 16473.0 14098.4 14162.7 14175.2 14176.7 14198.4 661.5 778.5 797.0 797.7 800.1 656.7 662.5 662.7 769.8 800.3 854.5 867.7 870.2 871.5 872.2 865.2 865.4 866.2 866.3 866.5 508.7 602.9 620.9 643.8 644.3 14713.7 14815.2 14841.3 14852.3 14853.2 14110.3 14531.4 14753.9 14961.5 15012.2 13160.5 13359.2 13394.3 13398.7 14069.2 4488.1 4585.1 4585.3 4608.4 4617.6 83101.0 83969.6 84787.0 84960.0 85126.1 11364.9 11544.1 11602.2 11685.4 11814.2 22585.5 22896.0 22901.9 22978.9 22997.8 8716.2 8736.0 8836.5 8941.9 8977.4 4739.5 4950.4 5009.0 5023.4 5068.1 3218.1 3294.3 3296.6 3312.4 3320.1 542.6 547.1 549.4 551.1 585.9 577.5 597.4 653.2 686.0 734.4 9025.7 9319.0 9758.8 9774.9 10238.1 626.7 669.1 670.0 680.9 723.7 4696.5 4741.1 4774.4 4781.2 4808.8 121270.0 123751.0 126092.0 127677.0 129512.0 105800.0 106382.4 107017.5 108016.5 111763.4 90983.0 93217.5 93566.0 94282.5 95565.0 8818.9 8856.9 8939.8 8951.6 9190.7 51401.5 53170.7 53337.7 53461.5 54651.7 72183.3 72594.6 75319.6 77673.4 81278.2 11393.7 11426.5 11821.2 12009.8 12234.3 13256.9 13508.5 13713.0 14165.6 14302.7 14236.7 14448.0 14504.9 15149.4 15166.5 10297.4 13168.0 13924.9 14694.7 15039.4 13689.5 14345.6 15114.2 15556.1 15572.2 11142.5 11222.5 11240.6 11343.3 11842.9 11145.2 12089.2 12407.6 12444.7 12643.0 21:35:40 INFO - PROCESS | 29077 | -------- Summary: end -------- 21:35:40 INFO - PROCESS | 29077 | 21:35:40 INFO - TEST-INFO | 29077: exit 0 21:35:40 INFO - TEST-OK | dromaeo_css | took 1114858ms 21:35:40 INFO - TEST-START | kraken 21:35:40 INFO - operating with platform_type : linux_ 21:35:40 INFO - Initialising browser for kraken test... 21:35:40 INFO - TEST-INFO | started process 29379 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDPNwGY/profile http://localhost:57809/getInfo.html) 21:35:45 INFO - PROCESS | 29379 | __metrics Screen width/height:1600/1200 21:35:45 INFO - PROCESS | 29379 | colorDepth:24 21:35:45 INFO - PROCESS | 29379 | Browser inner width/height: 1024/697 21:35:45 INFO - PROCESS | 29379 | __metrics 21:35:45 INFO - PROCESS | 29379 | 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" 21:35:45 INFO - PROCESS | 29379 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:35:46 INFO - TEST-INFO | 29379: exit 0 21:35:46 INFO - Browser initialized. 21:35:46 INFO - Running cycle 1/1 for kraken test... 21:35:46 INFO - TEST-INFO | started process 29459 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDPNwGY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:35:58 INFO - PROCESS | 29459 | RSS: Main: 155992064 21:35:58 INFO - PROCESS | 29459 | 21:36:01 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57809/tests/kraken/driver.html?testName=audio-beat-detection) 21:36:02 INFO - PROCESS | 29459 | RSS: Main: 204177408 21:36:02 INFO - PROCESS | 29459 | 21:36:04 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57809/tests/kraken/driver.html?testName=audio-dft) 21:36:05 INFO - PROCESS | 29459 | RSS: Main: 191160320 21:36:05 INFO - PROCESS | 29459 | 21:36:08 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57809/tests/kraken/driver.html?testName=audio-fft) 21:36:09 INFO - PROCESS | 29459 | RSS: Main: 275939328 21:36:09 INFO - PROCESS | 29459 | 21:36:11 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57809/tests/kraken/driver.html?testName=audio-oscillator) 21:36:11 INFO - PROCESS | 29459 | RSS: Main: 186126336 21:36:11 INFO - PROCESS | 29459 | 21:36:13 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57809/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:36:14 INFO - PROCESS | 29459 | RSS: Main: 191168512 21:36:14 INFO - PROCESS | 29459 | 21:36:17 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57809/tests/kraken/driver.html?testName=imaging-darkroom) 21:36:18 INFO - PROCESS | 29459 | RSS: Main: 225316864 21:36:18 INFO - PROCESS | 29459 | 21:36:21 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57809/tests/kraken/driver.html?testName=imaging-desaturate) 21:36:22 INFO - PROCESS | 29459 | RSS: Main: 226013184 21:36:22 INFO - PROCESS | 29459 | 21:36:24 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57809/tests/kraken/driver.html?testName=json-parse-financial) 21:36:25 INFO - PROCESS | 29459 | RSS: Main: 226340864 21:36:25 INFO - PROCESS | 29459 | 21:36:26 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57809/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:36:27 INFO - PROCESS | 29459 | RSS: Main: 188170240 21:36:27 INFO - PROCESS | 29459 | 21:36:29 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57809/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:36:29 INFO - PROCESS | 29459 | RSS: Main: 196100096 21:36:29 INFO - PROCESS | 29459 | 21:36:32 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57809/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:36:32 INFO - PROCESS | 29459 | RSS: Main: 193687552 21:36:32 INFO - PROCESS | 29459 | 21:36:35 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:36:35 INFO - PROCESS | 29459 | RSS: Main: 186634240 21:36:35 INFO - PROCESS | 29459 | 21:36:38 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:36:39 INFO - PROCESS | 29459 | RSS: Main: 191557632 21:36:39 INFO - PROCESS | 29459 | 21:36:40 INFO - PROCESS | 29459 | Cycle 1(1): loaded http://localhost:57809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57809/tests/kraken/driver.html?testName=ai-astar) 21:36:41 INFO - PROCESS | 29459 | RSS: Main: 241684480 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | __start_tp_report 21:36:41 INFO - PROCESS | 29459 | _x_x_mozilla_page_load 21:36:41 INFO - PROCESS | 29459 | _x_x_mozilla_page_load_details 21:36:41 INFO - PROCESS | 29459 | |i|pagename|runs| 21:36:41 INFO - PROCESS | 29459 | |0;ai-astar;107;112;113;113;108;112;112;112;111;111 21:36:41 INFO - PROCESS | 29459 | |1;audio-beat-detection;147;118;122;177;120;121;146;129;155;120 21:36:41 INFO - PROCESS | 29459 | |2;audio-dft;161;161;161;160;161;160;161;162;160;160 21:36:41 INFO - PROCESS | 29459 | |3;audio-fft;74;74;76;108;76;76;75;75;104;76 21:36:41 INFO - PROCESS | 29459 | |4;audio-oscillator;83;85;124;84;85;85;127;84;85;123 21:36:41 INFO - PROCESS | 29459 | |5;imaging-gaussian-blur;116;117;117;117;118;117;117;117;117;117 21:36:41 INFO - PROCESS | 29459 | |6;imaging-darkroom;145;145;145;145;145;145;146;145;145;146 21:36:41 INFO - PROCESS | 29459 | |7;imaging-desaturate;95;95;95;94;95;95;95;95;95;96 21:36:41 INFO - PROCESS | 29459 | |8;json-parse-financial;52;51;53;53;53;55;54;52;54;53 21:36:41 INFO - PROCESS | 29459 | |9;json-stringify-tinderbox;61;61;61;61;61;62;61;62;61;62 21:36:41 INFO - PROCESS | 29459 | |10;stanford-crypto-aes;87;78;77;78;78;78;79;79;77;79 21:36:41 INFO - PROCESS | 29459 | |11;stanford-crypto-ccm;125;123;136;123;133;122;121;121;148;130 21:36:41 INFO - PROCESS | 29459 | |12;stanford-crypto-pbkdf2;164;158;159;162;163;162;162;166;160;165 21:36:41 INFO - PROCESS | 29459 | |13;stanford-crypto-sha256-iterative;64;63;65;66;63;63;62;63;62;62 21:36:41 INFO - PROCESS | 29459 | __end_tp_report 21:36:41 INFO - PROCESS | 29459 | __start_cc_report 21:36:41 INFO - PROCESS | 29459 | _x_x_mozilla_cycle_collect,1669 21:36:41 INFO - PROCESS | 29459 | __end_cc_report 21:36:41 INFO - PROCESS | 29459 | __startTimestamp1471149401147__endTimestamp 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | ------- Summary: start ------- 21:36:41 INFO - PROCESS | 29459 | Number of tests: 14 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:2.02 (1.8%) stddev-sans-first:1.51 21:36:41 INFO - PROCESS | 29459 | Values: 107.0 112.0 113.0 113.0 108.0 112.0 112.0 112.0 111.0 111.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:137.50 stddev:19.91 (14.5%) stddev-sans-first:20.67 21:36:41 INFO - PROCESS | 29459 | Values: 147.0 118.0 122.0 177.0 120.0 121.0 146.0 129.0 155.0 120.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 21:36:41 INFO - PROCESS | 29459 | Values: 161.0 161.0 161.0 160.0 161.0 160.0 161.0 162.0 160.0 160.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.50 stddev:13.02 (17.2%) stddev-sans-first:13.53 21:36:41 INFO - PROCESS | 29459 | Values: 74.0 74.0 76.0 108.0 76.0 76.0 75.0 75.0 104.0 76.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:84.50 stddev:19.47 (23.0%) stddev-sans-first:20.03 21:36:41 INFO - PROCESS | 29459 | Values: 83.0 85.0 124.0 84.0 85.0 85.0 127.0 84.0 85.0 123.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 21:36:41 INFO - PROCESS | 29459 | Values: 116.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 21:36:41 INFO - PROCESS | 29459 | Values: 145.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 146.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 21:36:41 INFO - PROCESS | 29459 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 96.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:1.15 (2.2%) stddev-sans-first:1.17 21:36:41 INFO - PROCESS | 29459 | Values: 52.0 51.0 53.0 53.0 53.0 55.0 54.0 52.0 54.0 53.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 21:36:41 INFO - PROCESS | 29459 | Values: 61.0 61.0 61.0 61.0 61.0 62.0 61.0 62.0 61.0 62.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:78.50 stddev:2.91 (3.7%) stddev-sans-first:0.78 21:36:41 INFO - PROCESS | 29459 | Values: 87.0 78.0 77.0 78.0 78.0 78.0 79.0 79.0 77.0 79.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#11] stanford-crypto-ccm Cycles:10 Average:128.20 Median:127.50 stddev:8.73 (6.8%) stddev-sans-first:9.18 21:36:41 INFO - PROCESS | 29459 | Values: 125.0 123.0 136.0 123.0 133.0 122.0 121.0 121.0 148.0 130.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.10 Median:162.50 stddev:2.56 (1.6%) stddev-sans-first:2.62 21:36:41 INFO - PROCESS | 29459 | Values: 164.0 158.0 159.0 162.0 163.0 162.0 162.0 166.0 160.0 165.0 21:36:41 INFO - PROCESS | 29459 | 21:36:41 INFO - PROCESS | 29459 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.00 stddev:1.34 (2.1%) stddev-sans-first:1.39 21:36:41 INFO - PROCESS | 29459 | Values: 64.0 63.0 65.0 66.0 63.0 63.0 62.0 63.0 62.0 62.0 21:36:41 INFO - PROCESS | 29459 | -------- Summary: end -------- 21:36:41 INFO - PROCESS | 29459 | 21:36:42 INFO - TEST-INFO | 29459: exit 0 21:36:42 INFO - TEST-OK | kraken | took 61235ms 21:36:42 INFO - SUITE-END | took 1176s 21:36:42 INFO - Completed test suite (00:19:36) 21:36:42 INFO - javascript benchmark 21:36:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3027.3491199392124, 3027.4096551172524, 3049.6340439147307, 3060.1584564244818, 3064.708852658998, 5142.0487209866205, 5202.557782588485, 5249.92125118123, 5256.605754568403, 5299.2581038654525, 5224.529792318691, 5248.186531087678, 5289.471052894718, 5305.363356397238, 5310.256564081022, 4716.268978308368, 4798.7840547175465, 4801.951980480186, 4808.639352048593, 4990.700557966516, 4898.828541001066, 4910.803567857281, 4946.777395017215, 4951.183054795973, 4970.6520543561965, 4656.301554766778, 4679.953200467986, 4739.218029025206, 4795.520447955211, 4865.732384718839, 4741.122892264942, 4741.5495527924795, 4752.667313288071, 4765.809367625291, 4857.392825896763, 4779.641526885463, 4787.186178349654, 4791.4250289965075, 4793.065352256312, 4820.156472617278, 5122.33409656746, 5140.794368225266, 5212.817551385683, 5222.97388513055, 5324.893502129936, 5564.916526252109, 5609.046462101453, 5655.5475561954945, 5657.8019769308285, 5657.8585535361535, 5691.51622112121, 5709.685967271798, 5710.457506536873, 5715.199872017921, 5716.828495145152, 5148.922766158473, 5200.3759548854005, 5251.081060814342, 5255.0278198533015, 5339.572834173335, 5820.912686309709, 5824.009918313896, 5824.563157763185, 5834.066549352084, 5840.036393995028, 5493.313335833021, 5499.752511136929, 5541.168824676251, 5600.075987462104, 5602.215689803431, 5483.314585676791, 5590.664560126405, 5623.072193088176, 5630.07103827872, 5668.659880407189, 5622.718864056781, 5627.127795191752, 5627.746751396197, 5683.772649094072, 5722.113072473773, 5581.5813813963305, 5626.324841019104, 5639.718014099279, 5640.717964101778, 5641.0, 4272.124214536013, 4282.9785851071165, 4291.03451723366, 4356.825726970886, 4428.025834316445, 3839.7504162229925, 3875.399313106473, 3913.021744563859, 3940.9802950985063, 3941.2708648900043, 5377.139657654835, 5383.16560933056, 5383.273258110184, 5384.165454354581, 5428.077226871363, 5675.886482270332, 5677.460641239076, 5683.715814209273, 5685.175649530877, 5709.685967271756, 5196.76614552346, 5237.502437268454, 5245.134552798745, 5366.812161574326, 5375.892482150335, 4821.54195351441, 4854.587360074363, 4866.197077482176, 4889.828855990023, 4907.852764417074, 5799.2750906136735, 5843.561732870052, 5854.443827836349, 5859.941400586024, 5913.083472061838, 5474.233607294902, 5569.053260945568, 5573.832785016456, 5648.350439699323, 5659.037963546125, 5821.505172060422, 5835.387284335084, 5835.7373918174635, 5869.706514674334, 5882.352941176553, 5526.640768350045, 5531.391546929754, 5542.861428464321, 5548.694821784679, 5559.861003474945, 5490.533304668988, 5596.916046259391, 5654.06707893193, 5691.004074287066, 5700.002499562477, 5569.832905012856, 5573.860653483695, 5574.554035677055, 5603.10350343943, 5609.382967873449, 5516.9448305516435, 5541.806036788855, 5561.777528898799, 5564.527015203753, 5575.470330318613, 5280.471952804689, 5386.030514507426, 5438.129899216106, 5491.917621235605, 5559.0, 5619.185218143264, 5648.068068768607, 5657.519110875457, 5659.292588426447, 5664.178694089416, 5421.186821976735, 5468.371137319259, 5469.972650136724, 5474.397816240131, 5475.863103422446], "value": 5210.036407040007, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.3176903103572, 582.4263100845667, 588.9116632505127, 601.0353382820575, 604.3110853626865, 41389.0, 42808.35787463187, 42840.928976775554, 42861.78569107158, 46069.0, 45423.0, 45856.77071614646, 45915.540844591545, 45974.77012614932, 45977.0, 8818.470891746483, 8945.865812012826, 8957.776055598597, 8963.417377870417, 8966.327525435585, 8920.10798920106, 8994.190522852941, 9003.639854405816, 9018.413803102778, 9021.323400744937, 9055.773605659846, 9087.500562407195, 9098.363114581982, 9099.272058235325, 9103.135202155818, 9024.819503609924, 9050.77373065672, 9063.41087829289, 9066.184043436057, 9069.59186836594, 8670.52312122833, 8720.651173953034, 8728.956355218183, 8743.825123497494, 8745.650173993032, 42186.57813421857, 42411.363829542584, 42592.361114583306, 42607.147857043, 42618.360724589154, 3279.360524697685, 3471.1842716961496, 3547.385939397568, 3688.944665830015, 3884.844606215748, 31657.683423165705, 32493.83753081243, 32525.83737081323, 32548.18629534257, 32972.83513582441, 3982.741121827101, 4115.567865374122, 4125.319322311815, 4152.854650087263, 4251.638610718092, 345669.0, 346249.0, 346307.3692630742, 346761.0, 347317.0, 60134.0, 60516.39483605108, 60518.697406513566, 60542.091868622, 60569.39430605726, 61124.0, 61230.693846530485, 61255.693721531105, 61290.0, 61324.38675613276, 15169.848301516844, 15450.768238476432, 15450.845491545166, 15466.845331546765, 15467.690646187239, 11260.831087533694, 11298.491567879466, 11328.150388720878, 11328.830067548994, 11330.09359251258, 13606.455741770218, 17389.47831565055, 17420.128993550527, 17445.389411370543, 17650.735238971425, 5099.923501147486, 5715.828525144252, 5740.741666625012, 5750.568707346965, 5931.555133365015, 936.2463217110292, 1058.4178701714027, 1108.2242430298822, 1128.841962125303, 1168.7779321928977, 32115.839420802746, 32471.51292730611, 32967.0, 33523.4971475428, 33874.49188262129, 1053.3837704942694, 1065.4406436620682, 1113.2429947635685, 1125.3754166437707, 1152.4703276308423, 15589.39335865344, 16661.166941652868, 17002.659946800995, 17042.0, 17156.571085722706, 442594.0, 448364.0, 448429.0, 448584.0, 449165.5417229126, 57908.42091579115, 57986.55033624109, 58086.709566452744, 58117.4188258112, 58196.0, 1245.2839617219954, 1270.6442196185083, 1296.7600993816175, 1386.7157232767258, 1407.7184563087424, 29414.11757647274, 32055.67944320527, 33293.16767080843, 33315.33369332648, 33922.0, 49789.518943295276, 50367.0, 50387.0, 50413.4875953722, 50435.495645044546, 380322.0, 380716.0, 381552.0, 382290.0, 382332.0, 27424.0, 28603.713962860103, 28901.0, 30079.548806768355, 30132.69867301299, 47406.76296618495, 47589.337492750354, 48411.5158848407, 48793.2681009778, 49254.0, 38968.0, 40016.79991600024, 41398.0, 41727.791361043, 43158.0, 39688.0, 40580.59419405768, 40810.59189408187, 41317.58682413137, 42113.57886421097, 41137.0, 41316.38025429681, 41462.37806432966, 41980.79009604932, 42284.0, 33879.830600846835, 36942.630573694994, 37116.81441592775, 37418.006559474634, 37642.0, 28109.57835632508, 28418.57372139461, 28419.431611367243, 28423.715762842934, 28787.85606071956], "value": 19718.813222466957, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.6195138032134, 382.2507884546289, 397.2392867658434, 398.58945286355055, 411.8640848519989, 14345.64759215811, 17258.65482690347, 19248.326308579202, 19961.900190499025, 19963.800361996393, 18277.817221827776, 18325.633487330244, 18387.63224735508, 18591.442256732287, 18609.7208541872, 4180.205760905418, 5570.136628822539, 5740.052891272935, 5791.131330300467, 5794.5074668653215, 4804.663673542854, 6334.873302533946, 6461.256955450136, 6541.443977261938, 6559.34406559345, 6258.405451482102, 6427.357264273559, 6464.579802312858, 6489.02664600309, 6517.608943463408, 6446.774362897299, 6449.903251451232, 6471.870562588745, 6477.708503117371, 6482.416582507597, 5114.872128196825, 5354.732263386815, 5410.1073322901675, 5478.808241711561, 5486.286782718261, 14144.646383840383, 14186.148831070168, 14263.928680356532, 14279.500217492337, 14377.56867293983, 2212.126210146983, 2220.455988282865, 2282.589133955887, 2287.0508738873345, 2287.2452090809993, 10085.647002354955, 10424.68725938223, 10831.945840270828, 13127.884129848975, 13533.729325413535, 1889.1498825528568, 1946.347973428903, 1961.3135402609116, 2000.1899230811543, 2001.1595130045416, 79550.20449795543, 82717.17282827155, 86391.56804216001, 87039.0, 88083.0, 10848.566057357775, 10858.945705271422, 11055.889441105646, 11174.329540227613, 11385.259958102697, 15351.155686437352, 15358.385664573292, 15371.461998829987, 15374.077555346716, 15390.923045384701, 7410.888836667455, 7637.770866874002, 7704.961475192588, 7804.2195780422635, 7806.960965195138, 7000.334968178015, 7052.682629281695, 7052.929470705228, 7235.493515453868, 7236.710531578678, 4842.564169224786, 4845.709257444564, 4856.3201151838775, 4910.0, 5014.5737612303365, 1852.3239017758422, 1909.8663093583593, 1920.9903950480439, 1939.166158551808, 1971.4085774267662, 908.2733812949614, 964.1226483899618, 1004.7839714461426, 1020.9438480883474, 1067.8398240264023, 17671.73492397615, 19694.0, 19700.408987730392, 19760.308389206308, 20012.49968750793, 979.740368802268, 987.0672214756986, 1010.4190090697878, 1032.230987914009, 1050.3120456101267, 10211.0, 10828.891711082946, 10851.40317282557, 10953.635851885827, 10979.780404391868, 451165.0, 455602.0, 473943.0, 476427.0, 479235.0, 88140.5592972031, 88287.55856220677, 89095.55452222697, 90393.0, 90684.0, 970.2044323654677, 985.6303886042734, 993.6671215143008, 1006.8187726209206, 1009.152312057875, 16538.58653533671, 16791.74812377791, 17021.48935531936, 17661.735073973643, 17968.910155449663, 39994.400083999346, 42361.82902584493, 42719.786401067795, 42999.0, 43127.56872431361, 54899.58855554449, 56211.156832646724, 56381.0, 56783.43216567781, 57093.42906570881, 8346.874796878174, 8500.787480313042, 8663.166598591268, 8721.389502734748, 8969.416987895765, 12456.875431245933, 13108.147970381895, 13271.469141234242, 13277.336133193108, 13539.59381218565, 11253.549858005916, 11559.19085664029, 11664.825027624423, 11875.049996000127, 12005.379273798275, 11365.886341136484, 11503.079753619857, 11716.121290903387, 11744.0, 11816.40917954116, 10644.20168487382, 11666.650000499998, 11868.881311187122, 11908.880911190778, 12038.699032524257, 8577.056523782254, 8583.742487725378, 8707.521086340312, 8844.469331839853, 9054.411463254868, 9314.95342523283, 11136.443177841238, 11495.942520287344, 12060.51757929673, 12108.757824843278], "value": 9096.142279721318, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.6287508060204, 1464.1361596657969, 1485.7177136344094, 1594.8165960914491, 1718.5531761741943, 7396.926030739691, 7697.345725613322, 7764.107127680319, 8000.239977202171, 8018.198180181979, 7870.881936770938, 7973.681052757883, 7977.641006154722, 7981.321587665041, 7981.800454988628, 1992.8804271743743, 2214.745304290003, 2288.313505948213, 2305.5043165719367, 2311.2603966730653, 2290.1068583252545, 2312.3756585721844, 2315.340128063502, 2317.455397981473, 2318.9652155217686, 2248.8425810193294, 2252.988735056331, 2260.3445000949705, 2263.479399738053, 2300.3673989652893, 2190.1896298369547, 2260.5930932432157, 2267.331137314488, 2269.614165591853, 2272.7727227277305, 2278.305116939333, 2299.241250387368, 2302.7236731592147, 2302.781235782598, 2304.7003889494426, 8028.0, 8073.919260807375, 8074.0, 8079.232472915063, 8084.272415482576, 4814.205656066745, 4938.753062346868, 4941.901161976776, 4943.752812359367, 4945.67853089548, 3145.2608636970294, 3191.5850939377733, 3372.106391806173, 3373.224158443572, 3376.1728376547903, 1158.29922896648, 1175.647305808254, 2037.7554693436734, 2075.0869617368307, 2110.778368271325, 10416.42709650969, 10451.111655509214, 10527.894571070081, 10538.0, 10549.367037977747, 4131.566185550534, 4210.7473551587, 4339.305711086211, 4347.9565204347555, 4351.0645211279725, 8547.444416112932, 8553.529555874362, 8571.314294856522, 8571.742847714579, 8591.44155629882, 4802.687825291345, 4804.2073057945745, 4807.254875494304, 4817.759112044384, 4820.493848145964, 4045.2314060328445, 4396.252637051709, 4445.844395446208, 4529.818807247739, 4532.320151977164, 3524.6299138590593, 3575.3564358415733, 3603.4594810778594, 3629.7822130672243, 3648.927021459556, 1087.5867170475326, 1104.3815463340395, 1114.1254115519278, 1118.222835129577, 1123.5056575106926, 834.3950635789005, 954.8376775948105, 985.8373368394134, 988.1896844587369, 990.1732053735068, 5364.651297665718, 5553.416891226363, 5602.635828671123, 5680.488756011896, 5763.423657634203, 842.751388340441, 866.4627930683017, 868.1188593577524, 903.0698380667791, 912.5163663258484, 3696.7966761828357, 3735.1969326594367, 3831.310364134427, 3833.559140698855, 3864.265789499986, 9344.859827102599, 9702.0, 9716.75708107303, 9728.075832795874, 9734.707958761248, 9587.520623968774, 9623.807523849484, 9640.325177237666, 9640.469774162348, 9654.420734755937, 7408.85182296351, 7486.326230639269, 7525.247475252431, 7525.811854703786, 7527.209642987517, 5333.7866485340155, 5370.491543235561, 5443.564514838883, 5491.862703432446, 5602.803901863415, 8638.395312328077, 8683.869741954002, 8692.78268043304, 8850.424722393023, 8868.15752503511, 8786.16531429513, 8908.777280568038, 9016.729498115066, 9022.774430639287, 9027.954860225656, 6150.323464419, 6668.866622667617, 6836.658167091725, 6849.876538273152, 6976.651167441506, 7791.104023037423, 7881.921180788118, 7885.763427097197, 7912.169222231582, 7918.604069796603, 7140.250273721184, 7164.820879478055, 7200.927990720026, 7216.458765592497, 7415.184329723618, 6981.825454363681, 7026.6135362553505, 7053.647317633995, 7122.715091396286, 7152.499325047197, 6052.485538729257, 6188.876222475616, 6247.6563788990225, 6248.125262463168, 6271.905921411091, 4956.10790057793, 5010.899782004267, 5054.620903432184, 5126.4617215191865, 5139.254808052737, 4834.709917404967, 4887.413510378778, 4986.626003049713, 5015.17249653803, 5076.949230507647], "value": 4341.617674687547, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.1092939768646, 334.95980482342117, 341.6600482519893, 342.39054483020215, 342.73609320822965, 5325.707086110262, 5367.75845086971, 5371.1674690423, 5389.73051347433, 5407.297051383314, 4963.3547638806995, 5034.597232221423, 5185.799502403056, 5226.503482169188, 5292.338457692788, 4160.2303573838935, 4165.33354663254, 4173.6661067114555, 4178.206140833247, 4200.886765007275, 4214.5459816363455, 4216.5361810201, 4221.831126754926, 4231.809568569406, 4233.618974292313, 3928.5285765708154, 3992.4610177626087, 4026.3960405939197, 4137.937930931039, 4161.729487583312, 4162.750234985895, 4174.144300418407, 4184.351425529047, 4185.414042034117, 4193.03560181157, 3698.2048859495067, 3698.33429982603, 3705.925881482382, 3712.628737126293, 3716.4425336199515, 4979.950200498021, 5121.385433747974, 5150.71232191952, 5202.193659982709, 5211.947880521184, 4738.265568836836, 4738.336632871401, 4781.450133234688, 4798.4001999750035, 4798.496157903405, 5468.808591699271, 5504.862378440571, 5505.256790333294, 5509.669419834822, 5514.531264842493, 3972.940405893914, 4045.817938192759, 4056.432099506042, 4065.9796701016603, 4070.674346052309, 5687.857803554903, 5689.9146512802345, 5695.259616249861, 5703.62926409782, 5715.971420142914, 4150.584941505826, 4157.189348077096, 4161.375793630979, 4162.438070860456, 4187.476565429321, 4015.217032678658, 4163.708540402143, 4221.493420789525, 4239.97880010598, 4240.151969606091, 4722.0, 4729.9054018920115, 4747.098051370229, 4762.666613337103, 4767.976160119178, 5533.529649979797, 5542.168674698747, 5548.306461692288, 5552.805651802168, 5557.805476808373, 1344.610063081715, 1402.747505449029, 1442.9855701443062, 1464.5386703188462, 1466.9633259168393, 905.9547022648841, 944.2304521814837, 976.9071938165863, 980.8283550378655, 984.0995489127512, 5252.553532949666, 5311.7690569960705, 5357.098230128327, 5364.390682795146, 5385.838424847261, 5619.297587801525, 5621.156826476062, 5631.18347839561, 5639.492445679908, 5645.830625081254, 5231.424543300234, 5269.604779641542, 5282.392524859613, 5284.735763211824, 5340.305760251143, 2945.9705402946124, 2963.3776906849803, 2967.243352945028, 2973.9702602974126, 2995.445842519141, 5565.193047008162, 5769.451902069297, 5833.620814647034, 5859.326177489558, 5863.560232982543, 5652.943470565324, 5682.77268909239, 5705.144228365695, 5723.6851973141875, 5724.313082430135, 5804.970975145097, 5819.621724587888, 5821.27234095738, 5840.0, 5881.823545293648, 5370.62405631602, 5567.109262517978, 5567.8608034797835, 5591.748371323381, 5602.571344307175, 5530.253415788817, 5545.223668686468, 5564.38791732901, 5592.608517403905, 5596.972015139898, 5564.554835613222, 5844.912326315194, 5862.472377486047, 5866.50134738552, 5869.442402971711, 4050.959490405058, 4087.6729861610406, 4090.795460227036, 4106.589341065869, 4122.835086596502, 5764.19301297827, 5802.390748971297, 5820.476157145878, 5828.5520081565, 5865.120231965239, 5373.570114390761, 5508.08071721988, 5672.546196304368, 5716.456936591017, 5721.369409718211, 5558.777648893999, 5571.164325351229, 5575.665460072409, 5587.245721827501, 5593.356763972195, 5274.868128296824, 5360.946390536044, 5511.669299842022, 5532.6680399176175, 5591.077472217039, 5575.804846830504, 5578.693171875586, 5583.972080139573, 5586.664800112007, 5601.60788744784, 5474.781008759605, 5488.506034456758, 5496.5053145217125, 5525.182157099827, 5557.41647127062], "value": 4245.458143238261, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.7805880177145, 720.4848533298697, 730.6638946084801, 739.2348918868971, 802.6949759091544, 15016.549503514885, 16367.999999999985, 16370.181490925466, 16377.590560236, 16473.0, 14098.436062557512, 14162.71674566508, 14175.220362880038, 14176.716465670681, 14198.361073751681, 661.5402295404696, 778.4550814429907, 796.9601519924009, 797.6729540888236, 800.0799081056773, 656.6913550631195, 662.5395350231591, 662.7084083003463, 769.7652216074096, 800.3277247112425, 854.5214679779313, 867.7483529776357, 870.1689886158734, 871.5163084488106, 872.1583671756754, 865.2213008292524, 865.4417900454172, 866.1944391715701, 866.27233124176, 866.4671227195292, 508.72783061062154, 602.9457348838625, 620.9192804935375, 643.8261669349296, 644.2751904107879, 14713.70572588553, 14815.18516481593, 14841.25793710321, 14852.331645075998, 14853.183074930874, 14110.294485275695, 14531.418743250257, 14753.92623036878, 14961.476348327757, 15012.249387530579, 13160.539381121614, 13359.198448093146, 13394.263315517643, 13398.732025359439, 14069.226192559405, 4488.147252022105, 4585.12882552314, 4585.3122031695175, 4608.400907881987, 4617.607503362218, 83100.99999999939, 83969.58015209946, 84787.0, 84960.0, 85126.14873851243, 11364.943175284236, 11544.134189935789, 11602.245854019462, 11685.415729213506, 11814.173007889538, 22585.548289034126, 22896.0, 22901.885490572775, 22978.885105574365, 22997.770022299897, 8716.171963663443, 8735.956320218358, 8836.513991730517, 8941.910580894108, 8977.371583989057, 4739.478657347689, 4950.430700469492, 5009.020797398844, 5023.422306434807, 5068.113080210948, 3218.1471909943884, 3294.341131773655, 3296.620888597794, 3312.3540909522894, 3320.070380293522, 542.6174546944395, 547.0535972767135, 549.4121290219507, 551.0851985703713, 585.8798946216016, 577.4889223037642, 597.4294548705975, 653.1672118049524, 685.9965700171567, 734.4051318432087, 9025.684101056562, 9318.95340523293, 9758.7713834394, 9774.902250977399, 10238.129758970423, 626.7085805100559, 669.1067424987696, 669.9732010719614, 680.9080774095434, 723.7068987060144, 4696.483386827446, 4741.099191153669, 4774.379330686988, 4781.163296423112, 4808.7595620219545, 121270.0, 123751.0, 126092.0, 127677.0, 129512.0, 105800.01399762058, 106382.38669959422, 107017.4649126765, 108016.4599176999, 111763.4411827952, 90983.0, 93217.53391233001, 93566.0, 94282.52858735662, 95564.9999999986, 8818.91181088181, 8856.911430885608, 8939.821203575762, 8951.552422378985, 9190.678326258549, 51401.48598513967, 53170.73414632902, 53337.73331133319, 53461.465385347205, 54651.667653444165, 72183.27816721976, 72594.63702681453, 75319.62340188264, 77673.44653106794, 81278.18721812706, 11393.658190254306, 11426.48580813882, 11821.172517923662, 12009.81985270239, 12234.327112008925, 13256.93371533136, 13508.459661613426, 13713.0, 14165.575032749033, 14302.713945721236, 14236.715265694835, 14448.0, 14504.927475362556, 15149.394024238907, 15166.49169027192, 10297.433641149808, 13168.0, 13924.860751392762, 14694.706105878036, 15039.398424062914, 13689.45242190341, 14345.641358965691, 15114.244287785346, 15556.144412056992, 15572.221388930735, 11142.498587563741, 11222.494987725735, 11240.55037798479, 11343.262687925258, 11842.940785296018, 11145.219834611824, 12089.214201076902, 12407.594294833178, 12444.688882778002, 12643.0], "value": 7637.566007018185, "unit": "score"}], "name": "dromaeo_css", "value": 7131.695681884001, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 112.0, 113.0, 113.0, 108.0, 112.0, 112.0, 112.0, 111.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 122.0, 177.0, 120.0, 121.0, 146.0, 129.0, 155.0, 120.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 161.0, 160.0, 161.0, 162.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 108.0, 76.0, 76.0, 75.0, 75.0, 104.0, 76.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 124.0, 84.0, 85.0, 85.0, 127.0, 84.0, 85.0, 123.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 53.0, 53.0, 55.0, 54.0, 52.0, 54.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 62.0, 61.0, 62.0, 61.0, 62.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 78.0, 77.0, 78.0, 78.0, 78.0, 79.0, 79.0, 77.0, 79.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 123.0, 136.0, 123.0, 133.0, 122.0, 121.0, 121.0, 148.0, 130.0], "value": 128.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 158.0, 159.0, 162.0, 163.0, 162.0, 162.0, 166.0, 160.0, 165.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 66.0, 63.0, 63.0, 62.0, 63.0, 62.0, 62.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1489.2999999999997, "alertThreshold": 2.0}]} 21:36:42 INFO - Return code: 0 21:36:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:36:42 INFO - # TBPL SUCCESS # 21:36:42 INFO - Running post-action listener: _resource_record_post_action 21:36:42 INFO - [mozharness: 2016-08-14 04:36:42.482300Z] Finished run-tests step (success) 21:36:42 INFO - Running post-run listener: _resource_record_post_run 21:36:42 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 418394112; Read time: 0; Write time: 471680 21:36:42 INFO - TinderboxPrint: CPU usage
13.0% 21:36:42 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 21:36:42 INFO - TinderboxPrint: I/O write bytes / time
418,394,112 / 471,680 21:36:42 INFO - TinderboxPrint: CPU idle
8,284.6 (87.0%) 21:36:42 INFO - TinderboxPrint: CPU user
1,191.7 (12.5%) 21:36:42 INFO - TinderboxPrint: Swap in / out
0 / 0 21:36:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118091776; Read time: 0; Write time: 191228 21:36:42 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 288645120; Read time: 0; Write time: 276680 21:36:43 INFO - Running post-run listener: _upload_blobber_files 21:36:43 INFO - Blob upload gear active. 21:36:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:36:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:36:43 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:36:43 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:36:43 INFO - (blobuploader) - INFO - Open directory for files ... 21:36:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:36:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:36:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:44 INFO - (blobuploader) - INFO - Done attempting. 21:36:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:36:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:36:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:48 INFO - (blobuploader) - INFO - Done attempting. 21:36:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:36:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:36:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:49 INFO - (blobuploader) - INFO - Done attempting. 21:36:49 INFO - (blobuploader) - INFO - Iteration through files over. 21:36:49 INFO - Return code: 0 21:36:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:36:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:36:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b71e6cfb374bab7d08a559f90e0f6bc8faf082a8f8fe91865fa7d502b23de1371a45becec50d5c902404f37e9c0df4fa49af24d94de992725a0126a9a3801b45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/14323705c7b26f0b0da692fa982ebd1495d475f437784b41c635291aba65f9d9652f9cf452132019399db0c593c9b4d587d7affb8b442626d2894e8cb1d3afdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cd578b5f66723693d16a4a4808d5dbb29944a37fe0fb74fdc23d71be27c9d64cba75e1e79a4228f49b597948bf519acce32dcceb770d98b9c8e2f16cc914d028"} 21:36:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:36:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:36:49 INFO - Contents: 21:36:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b71e6cfb374bab7d08a559f90e0f6bc8faf082a8f8fe91865fa7d502b23de1371a45becec50d5c902404f37e9c0df4fa49af24d94de992725a0126a9a3801b45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/14323705c7b26f0b0da692fa982ebd1495d475f437784b41c635291aba65f9d9652f9cf452132019399db0c593c9b4d587d7affb8b442626d2894e8cb1d3afdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cd578b5f66723693d16a4a4808d5dbb29944a37fe0fb74fdc23d71be27c9d64cba75e1e79a4228f49b597948bf519acce32dcceb770d98b9c8e2f16cc914d028"} 21:36:49 INFO - Running post-run listener: copy_logs_to_upload_dir 21:36:49 INFO - Copying logs to upload dir... 21:36:49 INFO - mkdir: /builds/slave/test/build/upload/logs 21:36:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.664643 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-13 21:36:46.432581) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-13 21:36:46.435646) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b71e6cfb374bab7d08a559f90e0f6bc8faf082a8f8fe91865fa7d502b23de1371a45becec50d5c902404f37e9c0df4fa49af24d94de992725a0126a9a3801b45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/14323705c7b26f0b0da692fa982ebd1495d475f437784b41c635291aba65f9d9652f9cf452132019399db0c593c9b4d587d7affb8b442626d2894e8cb1d3afdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cd578b5f66723693d16a4a4808d5dbb29944a37fe0fb74fdc23d71be27c9d64cba75e1e79a4228f49b597948bf519acce32dcceb770d98b9c8e2f16cc914d028"} build_url:https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/M2XBgVxIQJqbaDfgCpXy_Q/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/Autoland/sha512/b71e6cfb374bab7d08a559f90e0f6bc8faf082a8f8fe91865fa7d502b23de1371a45becec50d5c902404f37e9c0df4fa49af24d94de992725a0126a9a3801b45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/14323705c7b26f0b0da692fa982ebd1495d475f437784b41c635291aba65f9d9652f9cf452132019399db0c593c9b4d587d7affb8b442626d2894e8cb1d3afdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cd578b5f66723693d16a4a4808d5dbb29944a37fe0fb74fdc23d71be27c9d64cba75e1e79a4228f49b597948bf519acce32dcceb770d98b9c8e2f16cc914d028"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-13 21:36:46.461254) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 21:36:46.461592) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1471147904.270279-137606321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004445 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 21:36:46.546190) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-13 21:36:46.546544) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-13 21:36:46.546860) ========= ========= Total master_lag: 0.17 =========