builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-011 starttime: 1468856474.84 results: success (0) buildid: 20160718072552 builduid: e319786e38e346359de6b5fc6f2d1f52 revision: 102f746a6474f266c6ac3762c03d2f0d4ff32479 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:14.845140) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:14.845594) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:14.845870) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:14.870238) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:14.870575) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 08:41:15-- 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% 18.6M=0.001s 2016-07-18 08:41:15 (18.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101494 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:14.991637) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:14.991945) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017150 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:15.041349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:15.041660) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 102f746a6474f266c6ac3762c03d2f0d4ff32479 --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 102f746a6474f266c6ac3762c03d2f0d4ff32479 --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=5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 08:41:15,558 truncating revision to first 12 chars 2016-07-18 08:41:15,558 Setting DEBUG logging. 2016-07-18 08:41:15,558 attempt 1/10 2016-07-18 08:41:15,558 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/102f746a6474?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 08:41:15,874 unpacking tar archive at: autoland-102f746a6474/testing/mozharness/ program finished with exit code 0 elapsedTime=0.644499 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:15.699582) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:15.699879) ========= script_repo_revision: 102f746a6474f266c6ac3762c03d2f0d4ff32479 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:15.700289) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:15.700548) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 08:41:15.711467) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-18 08:41:15.712156) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:41:16 INFO - MultiFileLogger online at 20160718 08:41:16 in /builds/slave/test 08:41:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 08:41:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:41:16 INFO - {'append_to_log': False, 08:41:16 INFO - 'base_work_dir': '/builds/slave/test', 08:41:16 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 08:41:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:41:16 INFO - 'branch': 'Autoland-Non-PGO', 08:41:16 INFO - 'buildbot_json_path': 'buildprops.json', 08:41:16 INFO - 'config_files': ('talos/linux_config.py',), 08:41:16 INFO - 'default_actions': ('clobber', 08:41:16 INFO - 'read-buildbot-config', 08:41:16 INFO - 'download-and-extract', 08:41:16 INFO - 'populate-webroot', 08:41:16 INFO - 'create-virtualenv', 08:41:16 INFO - 'install', 08:41:16 INFO - 'run-tests'), 08:41:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:41:16 INFO - 'download_minidump_stackwalk': True, 08:41:16 INFO - 'download_symbols': 'ondemand', 08:41:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:41:16 INFO - 'tooltool.py': '/tools/tooltool.py', 08:41:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:41:16 INFO - '/tools/misc-python/virtualenv.py')}, 08:41:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:41:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:41:16 INFO - 'installer_path': 'installer.exe', 08:41:16 INFO - 'log_level': 'info', 08:41:16 INFO - 'log_name': 'talos', 08:41:16 INFO - 'log_to_console': True, 08:41:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:41:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:41:16 INFO - 'opt_config_files': (), 08:41:16 INFO - 'pip_index': False, 08:41:16 INFO - 'sps_profile': False, 08:41:16 INFO - 'sps_profile_interval': 0, 08:41:16 INFO - 'suite': 'dromaeojs', 08:41:16 INFO - 'system_bits': '32', 08:41:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:41:16 INFO - 'title': 'talos-linux64-ix-011', 08:41:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:41:16 INFO - 'use_talos_json': True, 08:41:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:41:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:41:16 INFO - 'work_dir': 'build'} 08:41:16 INFO - ##### 08:41:16 INFO - ##### Running clobber step. 08:41:16 INFO - ##### 08:41:16 INFO - Running pre-action listener: _resource_record_pre_action 08:41:16 INFO - Running main action method: clobber 08:41:16 INFO - rmtree: /builds/slave/test/build 08:41:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:41:16 INFO - Running post-action listener: _resource_record_post_action 08:41:16 INFO - ##### 08:41:16 INFO - ##### Finished clobber step (success) 08:41:16 INFO - ##### 08:41:16 INFO - ##### 08:41:16 INFO - ##### Running read-buildbot-config step. 08:41:16 INFO - ##### 08:41:16 INFO - Running pre-action listener: _resource_record_pre_action 08:41:16 INFO - Running main action method: read_buildbot_config 08:41:16 INFO - Using buildbot properties: 08:41:16 INFO - { 08:41:16 INFO - "project": "", 08:41:16 INFO - "product": "firefox", 08:41:16 INFO - "script_repo_revision": "production", 08:41:16 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 08:41:16 INFO - "repository": "", 08:41:16 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 08:41:16 INFO - "buildid": "20160718072552", 08:41:16 INFO - "pgo_build": "False", 08:41:16 INFO - "basedir": "/builds/slave/test", 08:41:16 INFO - "buildnumber": 151, 08:41:16 INFO - "slavename": "talos-linux64-ix-011", 08:41:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:41:16 INFO - "platform": "ubuntu64_hw", 08:41:16 INFO - "scheduler": "tests-autoland-linux64-talos", 08:41:16 INFO - "branch": "autoland", 08:41:16 INFO - "repo_path": "integration/autoland", 08:41:16 INFO - "revision": "102f746a6474f266c6ac3762c03d2f0d4ff32479", 08:41:16 INFO - "stage_platform": "linux64", 08:41:16 INFO - "builduid": "e319786e38e346359de6b5fc6f2d1f52", 08:41:16 INFO - "slavebuilddir": "test" 08:41:16 INFO - } 08:41:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 08:41:16 INFO - Running post-action listener: _resource_record_post_action 08:41:16 INFO - ##### 08:41:16 INFO - ##### Finished read-buildbot-config step (success) 08:41:16 INFO - ##### 08:41:16 INFO - ##### 08:41:16 INFO - ##### Running download-and-extract step. 08:41:16 INFO - ##### 08:41:16 INFO - Running pre-action listener: _resource_record_pre_action 08:41:16 INFO - Running main action method: download_and_extract 08:41:16 INFO - mkdir: /builds/slave/test/build/tests 08:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:16 INFO - https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:41:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:41:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:41:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:41:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:41:21 INFO - Downloaded 1361 bytes. 08:41:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:41:21 INFO - Using the following test package requirements: 08:41:21 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:41:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:41:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:41:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:41:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:41:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:41:21 INFO - u'jsshell-linux-x86_64.zip'], 08:41:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:41:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:41:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:41:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:41:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:41:21 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:41:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:41:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:41:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:41:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:41:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:41:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:41:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:21 INFO - https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:41:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:41:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:41:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:41:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:41:23 INFO - Downloaded 22765373 bytes. 08:41:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:41:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:41:24 INFO - Return code: 0 08:41:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:24 INFO - https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:41:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:41:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:41:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:41:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:41:26 INFO - Downloaded 22765373 bytes. 08:41:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:41:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:41:28 INFO - Return code: 0 08:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:28 INFO - https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:41:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:41:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:41:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:41:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:41:29 INFO - Downloaded 14403317 bytes. 08:41:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:41:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:41:29 INFO - Return code: 0 08:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:29 INFO - https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:41:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:41:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:41:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:41:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:41:30 INFO - Downloaded 57075726 bytes. 08:41:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:41:30 INFO - mkdir: /builds/slave/test/properties 08:41:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:41:30 INFO - Writing to file /builds/slave/test/properties/build_url 08:41:30 INFO - Contents: 08:41:30 INFO - build_url:https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:41:31 INFO - Running post-action listener: _resource_record_post_action 08:41:31 INFO - Running post-action listener: set_extra_try_arguments 08:41:31 INFO - ##### 08:41:31 INFO - ##### Finished download-and-extract step (success) 08:41:31 INFO - ##### 08:41:31 INFO - ##### 08:41:31 INFO - ##### Running populate-webroot step. 08:41:31 INFO - ##### 08:41:31 INFO - Running pre-action listener: _resource_record_pre_action 08:41:31 INFO - Running main action method: populate_webroot 08:41:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:41:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:41:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:41:31 INFO - '--tppagecycles', 08:41:31 INFO - '7'], 08:41:31 INFO - 'tests': ['tsvgm']}}, 08:41:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:41:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:41:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:41:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:41:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:31 INFO - 'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:31 INFO - 'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['damp', 'tps']}, 08:41:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:31 INFO - 'tests': ['damp', 'tps']}, 08:41:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['dromaeo_dom']}, 08:41:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:41:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['basic_compositor_video']}, 08:41:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:41:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['a11yr', 08:41:31 INFO - 'ts_paint', 08:41:31 INFO - 'tpaint', 08:41:31 INFO - 'sessionrestore', 08:41:31 INFO - 'sessionrestore_no_auto_restore', 08:41:31 INFO - 'tabpaint']}, 08:41:31 INFO - 'other-e10s': {'tests': ['a11yr', 08:41:31 INFO - 'ts_paint', 08:41:31 INFO - 'tpaint', 08:41:31 INFO - 'sessionrestore', 08:41:31 INFO - 'sessionrestore_no_auto_restore', 08:41:31 INFO - 'tabpaint']}, 08:41:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:41:31 INFO - 'ts_paint', 08:41:31 INFO - 'tpaint', 08:41:31 INFO - 'sessionrestore', 08:41:31 INFO - 'sessionrestore_no_auto_restore', 08:41:31 INFO - 'tabpaint']}, 08:41:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:41:31 INFO - 'ts_paint', 08:41:31 INFO - 'tpaint', 08:41:31 INFO - 'sessionrestore', 08:41:31 INFO - 'sessionrestore_no_auto_restore', 08:41:31 INFO - 'tabpaint']}, 08:41:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['a11yr', 08:41:31 INFO - 'ts_paint', 08:41:31 INFO - 'tpaint', 08:41:31 INFO - 'sessionrestore', 08:41:31 INFO - 'sessionrestore_no_auto_restore', 08:41:31 INFO - 'tabpaint']}, 08:41:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['a11yr', 08:41:31 INFO - 'ts_paint', 08:41:31 INFO - 'tpaint', 08:41:31 INFO - 'sessionrestore', 08:41:31 INFO - 'sessionrestore_no_auto_restore', 08:41:31 INFO - 'tabpaint']}, 08:41:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['tsvgx', 08:41:31 INFO - 'tsvgr_opacity', 08:41:31 INFO - 'tart', 08:41:31 INFO - 'tscrollx', 08:41:31 INFO - 'cart']}, 08:41:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:41:31 INFO - 'tsvgr_opacity', 08:41:31 INFO - 'tart', 08:41:31 INFO - 'tscrollx', 08:41:31 INFO - 'cart']}, 08:41:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:31 INFO - 'talos_options': ['--disable-e10s'], 08:41:31 INFO - 'tests': ['tp5o']}, 08:41:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:31 INFO - 'tests': ['tp5o']}, 08:41:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:31 INFO - 'talos_options': ['--disable-e10s', 08:41:31 INFO - '--xperf_path', 08:41:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:31 INFO - 'tests': ['tp5n']}, 08:41:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:31 INFO - 'talos_options': ['--xperf_path', 08:41:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:31 INFO - 'tests': ['tp5n']}}, 08:41:31 INFO - 'talos.zip': {'path': '', 08:41:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:41:31 INFO - Running post-action listener: _resource_record_post_action 08:41:31 INFO - ##### 08:41:31 INFO - ##### Finished populate-webroot step (success) 08:41:31 INFO - ##### 08:41:31 INFO - ##### 08:41:31 INFO - ##### Running create-virtualenv step. 08:41:31 INFO - ##### 08:41:31 INFO - Running pre-action listener: _resource_record_pre_action 08:41:31 INFO - Running main action method: create_virtualenv 08:41:31 INFO - Creating virtualenv /builds/slave/test/build/venv 08:41:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:41:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:41:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:41:31 INFO - Using real prefix '/usr' 08:41:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:41:32 INFO - Installing distribute.............................................................................................................................................................................................done. 08:41:34 INFO - Installing pip.................done. 08:41:34 INFO - Return code: 0 08:41:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201c3b0>, '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 0x201d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:41: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', 'pip>=1.5'] in /builds/slave/test/build 08:41: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 pip>=1.5 08:41:34 INFO - Using env: {'DISPLAY': ':0', 08:41:34 INFO - 'HOME': '/home/cltbld', 08:41:34 INFO - 'LANG': 'en_US.UTF-8', 08:41:34 INFO - 'LANGUAGE': 'en_US:en', 08:41:34 INFO - 'LOGNAME': 'cltbld', 08:41:34 INFO - 'MAIL': '/var/mail/cltbld', 08:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:34 INFO - 'MOZ_NO_REMOTE': '1', 08:41:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:34 INFO - 'NO_EM_RESTART': '1', 08:41:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:34 INFO - 'PWD': '/builds/slave/test', 08:41:34 INFO - 'SHELL': '/bin/bash', 08:41:34 INFO - 'SHLVL': '1', 08:41:34 INFO - 'TERM': 'linux', 08:41:34 INFO - 'TMOUT': '86400', 08:41:34 INFO - 'USER': 'cltbld', 08:41:34 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 08:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:34 INFO - '_': '/tools/buildbot/bin/python'} 08:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:41:34 INFO - Cleaning up... 08:41:34 INFO - Return code: 0 08:41:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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', '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 0x1d5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201c3b0>, '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 0x201d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:41: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:41: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 psutil>=3.1.1 08:41:34 INFO - Using env: {'DISPLAY': ':0', 08:41:34 INFO - 'HOME': '/home/cltbld', 08:41:34 INFO - 'LANG': 'en_US.UTF-8', 08:41:34 INFO - 'LANGUAGE': 'en_US:en', 08:41:34 INFO - 'LOGNAME': 'cltbld', 08:41:34 INFO - 'MAIL': '/var/mail/cltbld', 08:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:34 INFO - 'MOZ_NO_REMOTE': '1', 08:41:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:34 INFO - 'NO_EM_RESTART': '1', 08:41:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:34 INFO - 'PWD': '/builds/slave/test', 08:41:34 INFO - 'SHELL': '/bin/bash', 08:41:34 INFO - 'SHLVL': '1', 08:41:34 INFO - 'TERM': 'linux', 08:41:34 INFO - 'TMOUT': '86400', 08:41:34 INFO - 'USER': 'cltbld', 08:41:34 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 08:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:34 INFO - '_': '/tools/buildbot/bin/python'} 08:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:34 INFO - Downloading/unpacking psutil>=3.1.1 08:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:41:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:36 INFO - Installing collected packages: psutil 08:41:36 INFO - Running setup.py install for psutil 08:41:36 INFO - building 'psutil._psutil_linux' extension 08:41:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:41:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:41:36 INFO - building 'psutil._psutil_posix' extension 08:41:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:41:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:41:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:37 INFO - Successfully installed psutil 08:41:37 INFO - Cleaning up... 08:41:37 INFO - Return code: 0 08:41:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201c3b0>, '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 0x201d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:41: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:41: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 mozsystemmonitor==0.3 08:41:37 INFO - Using env: {'DISPLAY': ':0', 08:41:37 INFO - 'HOME': '/home/cltbld', 08:41:37 INFO - 'LANG': 'en_US.UTF-8', 08:41:37 INFO - 'LANGUAGE': 'en_US:en', 08:41:37 INFO - 'LOGNAME': 'cltbld', 08:41:37 INFO - 'MAIL': '/var/mail/cltbld', 08:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:37 INFO - 'MOZ_NO_REMOTE': '1', 08:41:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:37 INFO - 'NO_EM_RESTART': '1', 08:41:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:37 INFO - 'PWD': '/builds/slave/test', 08:41:37 INFO - 'SHELL': '/bin/bash', 08:41:37 INFO - 'SHLVL': '1', 08:41:37 INFO - 'TERM': 'linux', 08:41:37 INFO - 'TMOUT': '86400', 08:41:37 INFO - 'USER': 'cltbld', 08:41:37 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 08:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:37 INFO - '_': '/tools/buildbot/bin/python'} 08:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:41:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:41:39 INFO - Installing collected packages: mozsystemmonitor 08:41:39 INFO - Running setup.py install for mozsystemmonitor 08:41:39 INFO - Successfully installed mozsystemmonitor 08:41:39 INFO - Cleaning up... 08:41:39 INFO - Return code: 0 08:41:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:41:39 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:39 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:39 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 0x1d5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201c3b0>, '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 0x201d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:41:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:41:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:41:39 INFO - Using env: {'DISPLAY': ':0', 08:41:39 INFO - 'HOME': '/home/cltbld', 08:41:39 INFO - 'LANG': 'en_US.UTF-8', 08:41:39 INFO - 'LANGUAGE': 'en_US:en', 08:41:39 INFO - 'LOGNAME': 'cltbld', 08:41:39 INFO - 'MAIL': '/var/mail/cltbld', 08:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:39 INFO - 'MOZ_NO_REMOTE': '1', 08:41:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:39 INFO - 'NO_EM_RESTART': '1', 08:41:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:39 INFO - 'PWD': '/builds/slave/test', 08:41:39 INFO - 'SHELL': '/bin/bash', 08:41:39 INFO - 'SHLVL': '1', 08:41:39 INFO - 'TERM': 'linux', 08:41:39 INFO - 'TMOUT': '86400', 08:41:39 INFO - 'USER': 'cltbld', 08:41:39 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 08:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:39 INFO - '_': '/tools/buildbot/bin/python'} 08:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:39 INFO - Downloading/unpacking jsonschema==2.5.1 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:41:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:41 INFO - Downloading functools32-3.2.3-2.tar.gz 08:41:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:41:41 INFO - warning: no files found matching '*.txt' 08:41:41 INFO - no previously-included directories found matching 'build' 08:41:41 INFO - no previously-included directories found matching 'dist' 08:41:41 INFO - no previously-included directories found matching '.git*' 08:41:41 INFO - Installing collected packages: jsonschema, functools32 08:41:41 INFO - Running setup.py install for functools32 08:41:41 INFO - warning: no files found matching '*.txt' 08:41:41 INFO - no previously-included directories found matching 'build' 08:41:41 INFO - no previously-included directories found matching 'dist' 08:41:41 INFO - no previously-included directories found matching '.git*' 08:41:41 INFO - Successfully installed jsonschema functools32 08:41:41 INFO - Cleaning up... 08:41:42 INFO - Return code: 0 08:41:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:41:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:42 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 0x1d5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201c3b0>, '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 0x201d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:41:42 INFO - Using env: {'DISPLAY': ':0', 08:41:42 INFO - 'HOME': '/home/cltbld', 08:41:42 INFO - 'LANG': 'en_US.UTF-8', 08:41:42 INFO - 'LANGUAGE': 'en_US:en', 08:41:42 INFO - 'LOGNAME': 'cltbld', 08:41:42 INFO - 'MAIL': '/var/mail/cltbld', 08:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:42 INFO - 'MOZ_NO_REMOTE': '1', 08:41:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:42 INFO - 'NO_EM_RESTART': '1', 08:41:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:42 INFO - 'PWD': '/builds/slave/test', 08:41:42 INFO - 'SHELL': '/bin/bash', 08:41:42 INFO - 'SHLVL': '1', 08:41:42 INFO - 'TERM': 'linux', 08:41:42 INFO - 'TMOUT': '86400', 08:41:42 INFO - 'USER': 'cltbld', 08:41:42 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 08:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:42 INFO - '_': '/tools/buildbot/bin/python'} 08:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:42 INFO - Downloading/unpacking blobuploader==1.2.4 08:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:44 INFO - Downloading blobuploader-1.2.4.tar.gz 08:41:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:41:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:41:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:44 INFO - Downloading docopt-0.6.1.tar.gz 08:41:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:41:44 INFO - Installing collected packages: blobuploader, requests, docopt 08:41:44 INFO - Running setup.py install for blobuploader 08:41:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:41:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:41:45 INFO - Running setup.py install for requests 08:41:45 INFO - Running setup.py install for docopt 08:41:45 INFO - Successfully installed blobuploader requests docopt 08:41:45 INFO - Cleaning up... 08:41:45 INFO - Return code: 0 08:41:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:45 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 0x1d5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201c3b0>, '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 0x201d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:41:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:41:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:41:45 INFO - Using env: {'DISPLAY': ':0', 08:41:45 INFO - 'HOME': '/home/cltbld', 08:41:45 INFO - 'LANG': 'en_US.UTF-8', 08:41:45 INFO - 'LANGUAGE': 'en_US:en', 08:41:45 INFO - 'LOGNAME': 'cltbld', 08:41:45 INFO - 'MAIL': '/var/mail/cltbld', 08:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:45 INFO - 'MOZ_NO_REMOTE': '1', 08:41:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:45 INFO - 'NO_EM_RESTART': '1', 08:41:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:45 INFO - 'PWD': '/builds/slave/test', 08:41:45 INFO - 'SHELL': '/bin/bash', 08:41:45 INFO - 'SHLVL': '1', 08:41:45 INFO - 'TERM': 'linux', 08:41:45 INFO - 'TMOUT': '86400', 08:41:45 INFO - 'USER': 'cltbld', 08:41:45 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 08:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:45 INFO - '_': '/tools/buildbot/bin/python'} 08:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:45 INFO - Running setup.py (path:/tmp/pip-42odX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:45 INFO - Running setup.py (path:/tmp/pip-mtVdTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:45 INFO - Running setup.py (path:/tmp/pip-1m1eAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:45 INFO - Running setup.py (path:/tmp/pip-y6G8FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:46 INFO - Running setup.py (path:/tmp/pip-hE4Fc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:46 INFO - Running setup.py (path:/tmp/pip-XOz8W9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:46 INFO - Running setup.py (path:/tmp/pip-iLeqzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:46 INFO - Running setup.py (path:/tmp/pip-Aoie9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:46 INFO - Running setup.py (path:/tmp/pip-skauHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:46 INFO - Running setup.py (path:/tmp/pip-M1zQ9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:46 INFO - Running setup.py (path:/tmp/pip-6XoP9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:46 INFO - Running setup.py (path:/tmp/pip-6tk25O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:46 INFO - Running setup.py (path:/tmp/pip-C9rLgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:46 INFO - Running setup.py (path:/tmp/pip-JXfict-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:46 INFO - Running setup.py (path:/tmp/pip-IR9xa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:46 INFO - Running setup.py (path:/tmp/pip-vxho4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:46 INFO - Running setup.py (path:/tmp/pip-KcE6a2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:41:46 INFO - Running setup.py install for manifestparser 08:41:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Running setup.py install for mozcrash 08:41:47 INFO - Running setup.py install for mozdebug 08:41:47 INFO - Running setup.py install for mozdevice 08:41:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Running setup.py install for mozfile 08:41:47 INFO - Running setup.py install for mozhttpd 08:41:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Running setup.py install for mozinfo 08:41:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Running setup.py install for mozInstall 08:41:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Running setup.py install for mozleak 08:41:47 INFO - Running setup.py install for mozlog 08:41:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Running setup.py install for moznetwork 08:41:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:41:48 INFO - Running setup.py install for mozprocess 08:41:48 INFO - Running setup.py install for mozprofile 08:41:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:41:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:41:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:41:48 INFO - Running setup.py install for mozrunner 08:41:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:41:48 INFO - Running setup.py install for mozscreenshot 08:41:48 INFO - Running setup.py install for moztest 08:41:48 INFO - Running setup.py install for mozversion 08:41:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:41:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:41:48 INFO - Cleaning up... 08:41:48 INFO - Return code: 0 08:41:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:48 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 0x1d5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201c3b0>, '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 0x201d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:41:48 INFO - Using env: {'DISPLAY': ':0', 08:41:48 INFO - 'HOME': '/home/cltbld', 08:41:48 INFO - 'LANG': 'en_US.UTF-8', 08:41:48 INFO - 'LANGUAGE': 'en_US:en', 08:41:48 INFO - 'LOGNAME': 'cltbld', 08:41:48 INFO - 'MAIL': '/var/mail/cltbld', 08:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:48 INFO - 'MOZ_NO_REMOTE': '1', 08:41:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:48 INFO - 'NO_EM_RESTART': '1', 08:41:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:48 INFO - 'PWD': '/builds/slave/test', 08:41:48 INFO - 'SHELL': '/bin/bash', 08:41:48 INFO - 'SHLVL': '1', 08:41:48 INFO - 'TERM': 'linux', 08:41:48 INFO - 'TMOUT': '86400', 08:41:48 INFO - 'USER': 'cltbld', 08:41:48 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 08:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:48 INFO - '_': '/tools/buildbot/bin/python'} 08:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:48 INFO - Running setup.py (path:/tmp/pip-XXcPOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:49 INFO - Running setup.py (path:/tmp/pip-jnarah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:49 INFO - Running setup.py (path:/tmp/pip-PRVZjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:49 INFO - Running setup.py (path:/tmp/pip-94Blhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:49 INFO - Running setup.py (path:/tmp/pip-ojGNzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:49 INFO - Running setup.py (path:/tmp/pip-3IibE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:49 INFO - Running setup.py (path:/tmp/pip-K1QUCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:49 INFO - Running setup.py (path:/tmp/pip-imx0H2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:49 INFO - Running setup.py (path:/tmp/pip-WK1QkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:49 INFO - Running setup.py (path:/tmp/pip-wyHmmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:49 INFO - Running setup.py (path:/tmp/pip-bQuQxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:49 INFO - Running setup.py (path:/tmp/pip-iRFw1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:49 INFO - Running setup.py (path:/tmp/pip-2KC6CU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:49 INFO - Running setup.py (path:/tmp/pip-D7Ll7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:50 INFO - Running setup.py (path:/tmp/pip-OT6BCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:50 INFO - Running setup.py (path:/tmp/pip-jIaooS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:50 INFO - Running setup.py (path:/tmp/pip-lwAcyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:52 INFO - Downloading blessings-1.6.tar.gz 08:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:41:52 INFO - Installing collected packages: blessings 08:41:52 INFO - Running setup.py install for blessings 08:41:52 INFO - Successfully installed blessings 08:41:52 INFO - Cleaning up... 08:41:52 INFO - Return code: 0 08:41:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:41:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:41:52 INFO - Reading from file tmpfile_stdout 08:41:52 INFO - Current package versions: 08:41:52 INFO - argparse == 1.2.1 08:41:52 INFO - blessings == 1.6 08:41:52 INFO - blobuploader == 1.2.4 08:41:52 INFO - docopt == 0.6.1 08:41:52 INFO - functools32 == 3.2.3-2 08:41:52 INFO - jsonschema == 2.5.1 08:41:52 INFO - manifestparser == 1.1 08:41:52 INFO - mozInstall == 1.12 08:41:52 INFO - mozcrash == 0.17 08:41:52 INFO - mozdebug == 0.1 08:41:52 INFO - mozdevice == 0.48 08:41:52 INFO - mozfile == 1.2 08:41:52 INFO - mozhttpd == 0.7 08:41:52 INFO - mozinfo == 0.9 08:41:52 INFO - mozleak == 0.1 08:41:52 INFO - mozlog == 3.2 08:41:52 INFO - moznetwork == 0.27 08:41:52 INFO - mozprocess == 0.23 08:41:52 INFO - mozprofile == 0.28 08:41:52 INFO - mozrunner == 6.12 08:41:52 INFO - mozscreenshot == 0.1 08:41:52 INFO - mozsystemmonitor == 0.3 08:41:52 INFO - moztest == 0.7 08:41:52 INFO - mozversion == 1.4 08:41:52 INFO - psutil == 3.1.1 08:41:52 INFO - requests == 1.2.3 08:41:52 INFO - wsgiref == 0.1.2 08:41:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:52 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 0x1d5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201c3b0>, '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 0x201d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:41:52 INFO - Using env: {'DISPLAY': ':0', 08:41:52 INFO - 'HOME': '/home/cltbld', 08:41:52 INFO - 'LANG': 'en_US.UTF-8', 08:41:52 INFO - 'LANGUAGE': 'en_US:en', 08:41:52 INFO - 'LOGNAME': 'cltbld', 08:41:52 INFO - 'MAIL': '/var/mail/cltbld', 08:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:52 INFO - 'MOZ_NO_REMOTE': '1', 08:41:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:52 INFO - 'NO_EM_RESTART': '1', 08:41:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:52 INFO - 'PWD': '/builds/slave/test', 08:41:52 INFO - 'SHELL': '/bin/bash', 08:41:52 INFO - 'SHLVL': '1', 08:41:52 INFO - 'TERM': 'linux', 08:41:52 INFO - 'TMOUT': '86400', 08:41:52 INFO - 'USER': 'cltbld', 08:41:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 08:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:52 INFO - '_': '/tools/buildbot/bin/python'} 08:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:41:52 INFO - Cleaning up... 08:41:52 INFO - Return code: 0 08:41:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:41:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:52 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 0x1d5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201c3b0>, '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 0x201d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:41:52 INFO - Using env: {'DISPLAY': ':0', 08:41:52 INFO - 'HOME': '/home/cltbld', 08:41:52 INFO - 'LANG': 'en_US.UTF-8', 08:41:52 INFO - 'LANGUAGE': 'en_US:en', 08:41:52 INFO - 'LOGNAME': 'cltbld', 08:41:52 INFO - 'MAIL': '/var/mail/cltbld', 08:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:52 INFO - 'MOZ_NO_REMOTE': '1', 08:41:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:52 INFO - 'NO_EM_RESTART': '1', 08:41:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:52 INFO - 'PWD': '/builds/slave/test', 08:41:52 INFO - 'SHELL': '/bin/bash', 08:41:52 INFO - 'SHLVL': '1', 08:41:52 INFO - 'TERM': 'linux', 08:41:52 INFO - 'TMOUT': '86400', 08:41:52 INFO - 'USER': 'cltbld', 08:41:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 08:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:52 INFO - '_': '/tools/buildbot/bin/python'} 08:41:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:41:53 INFO - Cleaning up... 08:41:53 INFO - Return code: 0 08:41:53 INFO - Running post-action listener: _resource_record_post_action 08:41:53 INFO - Running post-action listener: _start_resource_monitoring 08:41:53 INFO - Starting resource monitoring. 08:41:53 INFO - ##### 08:41:53 INFO - ##### Finished create-virtualenv step (success) 08:41:53 INFO - ##### 08:41:53 INFO - ##### 08:41:53 INFO - ##### Running install step. 08:41:53 INFO - ##### 08:41:53 INFO - Running pre-action listener: _resource_record_pre_action 08:41:53 INFO - Running main action method: install 08:41:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:41:53 INFO - Reading from file tmpfile_stdout 08:41:53 INFO - Detecting whether we're running mozinstall >=1.0... 08:41:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:41:53 INFO - Reading from file tmpfile_stdout 08:41:53 INFO - Output received: 08:41:53 INFO - Usage: mozinstall [options] installer 08:41:53 INFO - Options: 08:41:53 INFO - -h, --help show this help message and exit 08:41:53 INFO - -d DEST, --destination=DEST 08:41:53 INFO - Directory to install application into. [default: 08:41:53 INFO - "/builds/slave/test"] 08:41:53 INFO - --app=APP Application being installed. [default: firefox] 08:41:53 INFO - mkdir: /builds/slave/test/build/application 08:41:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:42:09 INFO - Reading from file tmpfile_stdout 08:42:09 INFO - Output received: 08:42:09 INFO - /builds/slave/test/build/application/firefox/firefox 08:42:09 INFO - Running post-action listener: _resource_record_post_action 08:42:09 INFO - ##### 08:42:09 INFO - ##### Finished install step (success) 08:42:09 INFO - ##### 08:42:09 INFO - ##### 08:42:09 INFO - ##### Running run-tests step. 08:42:09 INFO - ##### 08:42:09 INFO - Running pre-action listener: _resource_record_pre_action 08:42:09 INFO - Running main action method: run_tests 08:42:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:42:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:42:09 INFO - Python 2.7.3 08:42:09 INFO - Return code: 0 08:42:09 INFO - grabbing minidump binary from tooltool 08:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:42:09 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 0x201c3b0>, '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 0x201d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:42:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:42:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:42:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:42:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:42:09 INFO - Return code: 0 08:42:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:42:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:42:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:42:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:42:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:42:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:42:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:42:09 INFO - Using env: {'DISPLAY': ':0', 08:42:09 INFO - 'HOME': '/home/cltbld', 08:42:09 INFO - 'LANG': 'en_US.UTF-8', 08:42:09 INFO - 'LANGUAGE': 'en_US:en', 08:42:09 INFO - 'LOGNAME': 'cltbld', 08:42:09 INFO - 'MAIL': '/var/mail/cltbld', 08:42:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:42:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:09 INFO - 'MOZ_NO_REMOTE': '1', 08:42:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:42:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:09 INFO - 'NO_EM_RESTART': '1', 08:42:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:09 INFO - 'PWD': '/builds/slave/test', 08:42:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:42:09 INFO - 'SHELL': '/bin/bash', 08:42:09 INFO - 'SHLVL': '1', 08:42:09 INFO - 'TERM': 'linux', 08:42:09 INFO - 'TMOUT': '86400', 08:42:09 INFO - 'USER': 'cltbld', 08:42:09 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091', 08:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:09 INFO - '_': '/tools/buildbot/bin/python'} 08:42:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:42:10 INFO - mozversion application_buildid: 20160718072552 08:42:10 INFO - mozversion application_changeset: 102f746a6474f266c6ac3762c03d2f0d4ff32479 08:42:10 INFO - mozversion application_display_name: Nightly 08:42:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:42:10 INFO - mozversion application_name: Firefox 08:42:10 INFO - mozversion application_remotingname: firefox 08:42:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:42:10 INFO - mozversion application_vendor: Mozilla 08:42:10 INFO - mozversion application_version: 50.0a1 08:42:10 INFO - mozversion platform_buildid: 20160718072552 08:42:10 INFO - mozversion platform_changeset: 102f746a6474f266c6ac3762c03d2f0d4ff32479 08:42:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:42:10 INFO - mozversion platform_version: 50.0a1 08:42:10 INFO - using testdate: 1468856530 08:42:10 INFO - actual date: 1468856530 08:42:10 INFO - starting webserver on 'localhost:32795' 08:42:10 INFO - SUITE-START | Running 2 tests 08:42:10 INFO - TEST-START | dromaeo_css 08:42:10 INFO - operating with platform_type : linux_ 08:42:10 INFO - Initialising browser for dromaeo_css test... 08:42:10 INFO - TEST-INFO | started process 10165 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1MBfNM/profile http://localhost:32795/getInfo.html) 08:42:14 INFO - PROCESS | 10165 | JavaScript error: , line 0: uncaught exception: undefined 08:42:15 INFO - PROCESS | 10165 | __metrics Screen width/height:1600/1200 08:42:15 INFO - PROCESS | 10165 | colorDepth:24 08:42:15 INFO - PROCESS | 10165 | Browser inner width/height: 1024/697 08:42:15 INFO - PROCESS | 10165 | __metrics 08:42:16 INFO - PROCESS | 10165 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:42:16 INFO - PROCESS | 10165 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:42:17 INFO - TEST-INFO | 10165: exit 0 08:42:17 INFO - Browser initialized. 08:42:17 INFO - Running cycle 1/1 for dromaeo_css test... 08:42:17 INFO - TEST-INFO | started process 10247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1MBfNM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:42:29 INFO - PROCESS | 10247 | RSS: Main: 159526912 08:42:29 INFO - PROCESS | 10247 | 08:43:21 INFO - PROCESS | 10247 | 1468856601080 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:45:35 INFO - PROCESS | 10247 | Cycle 1(1): loaded http://localhost:32795/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32795/tests/dromaeo/cssquery-ext.html) 08:45:36 INFO - PROCESS | 10247 | RSS: Main: 258662400 08:45:36 INFO - PROCESS | 10247 | 08:48:38 INFO - PROCESS | 10247 | Cycle 1(1): loaded http://localhost:32795/tests/dromaeo/cssquery-ext.html (next: http://localhost:32795/tests/dromaeo/cssquery-jquery.html) 08:48:39 INFO - PROCESS | 10247 | RSS: Main: 242343936 08:48:39 INFO - PROCESS | 10247 | 08:51:41 INFO - PROCESS | 10247 | Cycle 1(1): loaded http://localhost:32795/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32795/tests/dromaeo/cssquery-mootools.html) 08:51:42 INFO - PROCESS | 10247 | RSS: Main: 244187136 08:51:42 INFO - PROCESS | 10247 | 08:54:44 INFO - PROCESS | 10247 | Cycle 1(1): loaded http://localhost:32795/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32795/tests/dromaeo/cssquery-prototype.html) 08:54:45 INFO - PROCESS | 10247 | RSS: Main: 241238016 08:54:45 INFO - PROCESS | 10247 | 08:57:48 INFO - PROCESS | 10247 | Cycle 1(1): loaded http://localhost:32795/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32795/tests/dromaeo/cssquery-yui.html) 08:57:49 INFO - PROCESS | 10247 | RSS: Main: 225570816 08:57:49 INFO - PROCESS | 10247 | 09:00:51 INFO - PROCESS | 10247 | Cycle 1(1): loaded http://localhost:32795/tests/dromaeo/cssquery-yui.html (next: http://localhost:32795/tests/dromaeo/cssquery-dojo.html) 09:00:52 INFO - PROCESS | 10247 | RSS: Main: 278548480 09:00:52 INFO - PROCESS | 10247 | 09:00:52 INFO - PROCESS | 10247 | __start_tp_report 09:00:52 INFO - PROCESS | 10247 | _x_x_mozilla_page_load 09:00:52 INFO - PROCESS | 10247 | _x_x_mozilla_page_load_details 09:00:52 INFO - PROCESS | 10247 | |i|pagename|runs| 09:00:52 INFO - PROCESS | 10247 | |0;dojo.html;2646.325187077295;2663.13448129358;2692.946141077179;2697.6762788465376;2701.243651777502;4627.8380256691025;4697.929531057037;4747.62019038477;4869.75651217439;4883.926741098886;4708.128996135717;4730.19586670266;4737.549932756391;4762.690425122365;4781.091592597413;4336.826526938935;4361.062371590107;4427.867163985085;4449.377087207794;4457.1977044132045;4457.821687132511;4505.977470112645;4531.3203019547;4547.840825571106;4548.56788605082;4223.97888010561;4233.174530966463;4248.299030659969;4305.547917468652;4368.17004769096;4299.462567179103;4333.090051089275;4341.06667066579;4363.869083927487;4403.053343531156;4285.5928686774705;4343.261645520269;4373.846915357979;4405.449318835145;4431.313146462304;4647.697899636547;4804.327394164819;4818.445878723922;4838.564529192355;4838.733869637169;5117.334746482933;5127.6154288428515;5160.045391602528;5193.091209038441;5204.34945631796;5173.224016397533;5195.766190521398;5202.011617792645;5215.348081489814;5218.165093585046;4610.262358022767;4651.511591282934;4855.854324370274;4860.854174374775;4862.708237505761;5232.529072383504;5295.285136506599;5303.28405665238;5334.7065911374475;5361.08861493547;5101.540861322499;5105.15764898795;5153.896922061613;5154.768035438415;5161.303224064778;4987.276844857475;5073.467285934924;5247.239150323182;5254.316938798008;5257.7896884124975;5075.0357432087785;5119.923201151985;5125.897482050413;5130.025295193976;5160.767765450564;5076.6446348755235;5092.998247570534;5096.566791822663;5102.183650615883;5150.510701483353;4242.936355954663;4267.829286828554;4272.4445822042935;4382.671299652475;4438.334249862482;3784.2242340320167;3793.848246070126;3814.5613254475534;3817.2365526894177;3864.439656249884;4458.08609235106;4465.218586747306;4499.55004499554;4500.347449619787;4501.662375321864;5023.02051100032;5193.350831146106;5195.68825870449;5210.6613070150315;5219.1388420910125;4354.4992325882295;4373.671974601854;4386.912261754747;4417.4036505072045;4440.378347031418;4580.450345958507;4590.48582393717;4598.3792188054185;4628.629709623222;4630.143423466669;4790.760461976957;4819.6867203632355;4838.564529192389;4840.709557426566;4886.193778026586;4954.653174277766;4993;5101.489851014869;5152.30443890085;5157.303763991813;4839.516048395132;4846.248831431134;4849.2726091086615;4884.90230195387;4889.559939605452;5099.6685215460875;5105.361829771278;5106.7957281709805;5107.259447380184;5109.1059064662795;4972.129877271391;5062.848114556569;5103.387593488805;5111.156659151198;5225.451327610546;4988.700677959334;5009.073321435546;5023.120953833138;5028.094942910311;5070.062038522738;4992.201247800334;5008.5492305691205;5012;5013.573846223112;5014.867876946519;4813.975930120327;4879.097366987119;4915.827946021872;4927.039227350632;4938.506149385176;4975.601951843916;5008.1235783736975;5016.2224855147515;5033.748312584429;5078.263651770547;4730.952690473051;4881.072596206709;4882.853514394574;4884.511548845087;4929.13740095489 09:00:52 INFO - PROCESS | 10247 | |1;ext.html;575.7610591604483;587.8706684529402;593.4154857465396;595.6039233909452;595.8063629320471;27244.591331130025;29298.85350573247;38900.80549597255;40142.59857401425;40258.59741402593;39711.40432893509;40013.99895007871;40124.19751604966;40129.7993510032;40193;7669.923300766991;7797.610119494031;7799.844003119934;7808.414368922354;7812.960935195316;7342.412606991428;9865.704028879145;10118.291719579633;10119.544620492059;10486.842697359545;10096.495175241245;10176.949115254414;10194.235432342566;10215.02957219063;10222.386656800616;7393.8521229575645;10067.194624430065;10078.496075196212;10165.237607179453;10166.89833101667;9993.80012399748;10060.748481287954;10075.697729068139;10076.193904487624;10078.344907580984;37802.810985944896;38304;38393.42409863854;38405.231895362216;38428;2750.8074434789373;3038.0126458900727;3526.823658817049;3582.408902531079;3738.214974855284;29173;29537.113886583436;29708.257293567618;29860.10419687413;30226.093217203517;3438.398280300937;3456.9308613827834;3481.9554133760025;3484.285721427101;3600.945985810215;262878;263582;263863;263931.9999999981;264572;28073.859630701714;29121.854390728335;51612;51827.22259166116;52103.73948130235;30688.693113069032;30829.537556936662;30902;30932.22669433282;31010.069697909097;12280.263184208798;12466.314352710688;12478.688032799253;12481.500739970481;12489.50041998328;8819.073997230327;9911.752206194758;10033.849492257623;10063.19494440443;10252.077313041862;7805.726799561988;7830.765077047698;7848.411369147337;7891.684332626745;7902.0517537894675;3083.7070478304527;3109.4869346558016;3148.7008734170217;3149.685031496878;3150.57467241924;879.7800549862534;910.3172620534599;916.3036092569562;937.3251259093441;1039.9428031458342;27332.180034598994;28756;28901.27746806347;29293.56059659107;29350;939.9410427688111;946.6970569417721;1004.2869562610629;1005.894381089975;1044.587387981743;16333.673326533402;17077.658446830992;17333.913330433268;17837;18023.2790688371;309784.90215098014;314558;433488;434826;435099;52101.218481722804;52969;53076;53176.73411632917;53188.73405632947;1106.7011906785283;1107.197281970581;1206.1677442564953;1213.3023511480933;1220.3593113615423;26778.330541736614;29580.260493487836;29678.554821678124;30007.849960750056;30468.542971855884;42547.787261063495;43625.56374436215;43639.12721745611;43654.78172609117;43655.563444366424;336431;345215;345855;346206;346246;38526;38737.6126238734;40415.595844041185;42954.35568466413;43628;42293.15413691771;42385.57614423816;43215.13569728651;43359.566404336816;43588;36121.45817812682;37469.81265093657;37570;37682.246355073294;37946.62053379431;36991.260174796895;37158.6284137166;37522.62477375301;37711.62288377081;37722.24555508929;36464.81767591145;36506;36840.63159368479;37616.24767504689;37681;31768.682313176574;33273.66726332706;33482.330353393285;33913.152171195914;34025.48961765526;25120.246392608253;25673.486530268918;25841.612375814755;25893.223203303933;26328.473430531667 09:00:52 INFO - PROCESS | 10247 | |2;jquery.html;350.0443788457512;363.1085684644197;379.31722898782203;380.1124374585343;393.5552825307402;12972.091953563255;16152.757708634364;16846.24191911364;18228.362007329746;18270.269189232415;17721.91139044303;17859.73210401845;17935.103244837737;18143.546411339725;18284.542886427847;4978.477259887705;5301.3373328333955;5317.41508434072;5322.148456247001;5323.467653234684;3740.45763364312;5177.637565370425;5932;6238.189035425388;6244.812655620339;6128.938710612903;6288.339724328948;6311.495080393557;6313.747450101991;6319.65241911697;6152.630842149463;6267.905981410259;6277.089821975788;6304.022876454157;6308.085327627514;5275.788968441258;5446.727663616843;5486.506214440679;5504.119340905436;5523.28197334348;20869.895650521652;21133.788662113337;21283.46791330214;21426.357209283746;21519.354419367442;1937.186381719681;2019.9091040903195;2055.989720051405;2295.850769699964;2309.84985975911;13822.861771382259;13849.792253116211;13916.721665566633;13928.51250206248;14190.787138192833;1736.5745392378822;1815.2648177488138;1837.6784062788959;1840.74229607855;1844.0503140882456;61299;65399;65961;76991;78523;8711.21599056088;9094.181523662903;9878.654247101316;9880.901190987997;10061.949690251502;11280.605178818701;11815.232009919328;11873.99071078968;11888.989435898045;11898.702532436586;6351.460125889259;6414.198225221848;6704.99425086241;7071.328223818729;7128.536645118154;6111.786087486917;6373.490120790326;6600.669966501656;6788.830279243059;6813.3527314906;3992.8003599819895;4098;4218.535961044283;4472.08322293929;4580.954190458119;1636.3699975509514;1695.5252529291818;1704.4119778676336;1742.9738553921702;1767.770189875308;796.3509739562238;861.8190180062131;891.4963045879058;954.1460392948272;972.0619602084023;17354.305827767;17639;17649.76451648398;17876.642467150585;18126.184321705292;848.5078654380444;855.3798496090409;856.4176360075085;858.7080392666523;909.3816204980544;9558.90441095594;9751;10050.246231532665;10053.195744340435;10100.98990100984;386383;391989;398280;398748;400729;77276.42824674104;77681.61159194281;78027;78968;79808.600956996;871.9389642725075;883.0374891368439;885.7475619448297;892.4199270474114;894;15435.22823858825;15783.842161578697;15831.129287889276;15907.204639768197;16259;29730.70269297366;35450.64549354473;37002.814985924895;38020.23959520849;39388.803055984536;50414;50636;50771.23843142282;50880.23679644882;51206.85517737895;6121.663308517896;6337.588056776295;6651.733930642914;6787.083743694538;6806.2513123557355;8106.229908158715;10121.797564048531;10666.73333166677;11149.609763658233;11225.66323010311;9721.659741909;9867.605295788375;9923.255755818198;10078.445685487368;10123;9905.603775849173;9958.452285124098;9969.451680157661;10075.445850478294;10156.847647285444;9427.434353938786;9656.22750179998;9773.41359518431;9849.310548261554;10082.606882583124;6750.561213521106;6825.146856642919;6884.0362349272145;7009.9649501752165;7058.3647471725735;8398.538080405637;9735.561899714388;9749.415035097916;9808.950955245178;9811.80376392454 09:00:52 INFO - PROCESS | 10247 | |3;mootools.html;1370.5134677189594;1426.0160489262407;1496.4313560846883;1665.159094657198;1704.2927185218127;7506.887396689054;7998.680052797896;8071.798205044877;8089.352851771851;8100.594970251486;6724.630145342005;6922.5154239203275;6927.7228910843505;7107.431405487561;7908.683652653901;1945.4455480188185;2130.339594725632;2192.243675931801;2197.5385169114425;2211.7235345581803;2137.7969092936146;2185.25701261571;2204.5921504521634;2204.834637402193;2234.0505285253785;2189.737231532218;2193.462601662587;2193.6709493576013;2200.4168895242774;2200.592890315289;2097.92657256996;2134.807867291951;2150.9462263443384;2151.8386121040826;2155.5150091229507;2111.4510227341;2139.8181154601875;2145.978540214609;2154.8707077575395;2161.1247444784885;8000.35997120229;8014.5591992440395;8026.638801253957;8069;8094.7166849160585;4560.8859778505475;4698.741569213692;4699.483056863743;4704.952950470486;4709.434867815849;2810.9297267568268;2888.4511942730815;2903.303207230271;2956.379160376302;2966.9109926702235;899.7390756680555;925.3013974449248;932.151741914861;946.1484663802564;951.457669128597;10990.835137472945;11009.83485247722;11012.669619911416;11040.227184097117;11050.005499505083;4194.8112334945;4222.725522841006;4383.583559561836;4397.6921615487245;4405.713628614129;9326.90673093274;9357.578908949114;9365.15713585767;9365.906340936503;9369.344145909721;4604.516525764813;4609;4611.5619016193405;4616.884577885579;4623.583877450978;3598.9280214395567;4187.97906010468;4202.243596152722;4205.894852628709;4221.430106935586;3558.484019817112;3716.7955762433326;3720.6093360197333;3746.2507498499863;3792.886213413602;1200.8378868852767;1213.8422005139275;1217.5190799634095;1225.522046401904;1229.993850030762;851.8849955256085;854.5556310718516;874.903760586335;882.0650110882485;884.5488800711555;5241.030409374278;5259.921101183485;5289.404270143862;5365.382980957162;5464.207689884945;857.6012154348258;890.1365675294953;892.433304851424;904.9728508144766;905.5200743605899;3398.745094117951;3435.6736110069505;3594.406922857699;3630.8003059831435;3648.562172539312;9607.807843843084;9920.652777152765;9932.602695892228;10027.899721002843;10028.197744180448;9919.107280344804;9943.751406214902;9953;9972.650957216607;9982.500874956224;7182.3535881770895;7251.4923955323675;7289.307515785992;7324.963375183196;7349;4953.3065370848835;4959.5536401722575;4986.626003049713;5034.395872495324;5062.519060689376;8971.237444817263;9177.678781242625;9213.12475314844;9273.072692730673;9298.488583127993;8670.739877803677;9086.863697044682;9382.859257111286;9385.296102792454;9394.95302523483;6016.278046634432;6451.387118223761;6643.634600097041;6808.965955170391;6825.078614386995;7293.161286452126;7490.887636685345;7552.207018263003;7559.20628334039;7559.659815308434;6597.538172327891;6823.829404264933;6844.794656160323;6863.931360686329;7001.859962800817;6647.800565983028;6656.369189548592;6763.695633696594;6765.188177418544;6878.38094571491;6096.451319381277;6132.049532322497;6142.2629284484365;6155.353687862889;6160.907586386118;4499.5275496073755;4511.977440112779;4651.999820038708;4679.578837904605;4683.672142949961;4511.548845115462;4555.544445555418;4591.586757191869;4627.30590411441;4665.300204969281 09:00:52 INFO - PROCESS | 10247 | |4;prototype.html;299.50133028507537;301.96678365379813;307.51105741870424;308.2248145913029;311.04819253085543;4893.8287159949405;4907.190313598261;4909;4924.9411376554035;4928.950710492894;4577.2676371780435;4715.2455607102875;4730.503297153801;4848.6363522735755;4862.5866801321845;3813.9618603814024;3823.273578020181;3846.0961674006726;3850.8062500624756;3863.150106976461;3833.9808300958457;3850.191459793433;3851.480050193216;3853.65317121459;3860.1893602343544;3661.176235347052;3689.5019172411785;3810.2760475509704;3823.254465379252;3828.25349056934;3800.7719536827603;3808.1812410331913;3817.790021548814;3822.0827001519715;3829.2341531693633;3387.864485420605;3391.6438773928876;3392.9830350848333;3394.7114495267933;3396.1679388549965;4516.006478574708;4776.09254241693;4782.3543821584335;4790.490995336493;4804.447488538828;4288.335308027261;4297.247981603238;4312.37470566766;4313.741175529447;4313.762743049131;5126.743662816882;5128.2564028216075;5141.485851414866;5148.2020286855595;5155.664881782672;3547.077759782475;3635.1457407509215;3640.4357324614725;3644.2850076692766;3651.2697460508007;5272.499112584298;5329.387120481194;5334.1732031535175;5355.544778693777;5355.866103347408;3714.331420344364;3719.9070023249637;3720.572134204547;3729.5710993235584;3731.395499935;3767.1147280389087;3780.1494663700446;3780.8676696315497;3780.9621903781162;3787.280416720814;4033.092554175287;4287.678424118204;4291.914161716748;4321.567843215653;4323.870283891489;5086.109930762102;5102.810743285447;5112.769925353368;5122.8975420492125;5122.974385128051;1193.7194759231575;1266.7656483550388;1283.8202651628778;1292.8060790881443;1298.69480672042;874.3224001398839;877.8419884420865;889.9644014239487;891.157855826251;892.9553522323857;4462.263726485158;4480.686351955332;4488.5960263576435;4494.955050449519;4495.336725411553;5189.896202075937;5207.947920520822;5211.5830733541225;5229.607779416483;5236.962561714196;4479.552044795494;4488.3940668010055;4490.114152056144;4494.505604383515;4496.797644105957;2678.6249925010516;2816.5775133729694;2830.249983754307;2833.461642288;2834.9149525514267;4970.353853998957;4995.900081998412;5017.6953991962355;5025.773840177201;5036.622253331015;5304.9734751326;5365.38298095724;5446.537044351275;5447.673139611636;5455.645383050089;4962.975185124052;4971.204607262821;4977.40271167455;4982.327385802942;4998.575121114746;5233.450487698889;5262.868428289324;5270.314859068297;5280.7359632019015;5289.6032797539565;4933.827316043921;5154.433012368561;5157.871053223699;5171.896562068813;5195.090859099718;4958.586802761196;4958.727270000185;4960.900781984268;4995.225740010304;5000.64995450315;3711.387621042498;3715.1269451679373;3720.2187540616774;3724.385476396364;3731.12318605133;5063.772130254074;5101.857219967548;5235.973820130875;5249.527542521191;5250.842474725764;5022.1211288023715;5143.636936211981;5168.560672342743;5170.327857378667;5177.2492988515705;5110.97444512775;5112.71880046601;5164.896702065863;5166.45752196029;5172.9741351293005;4889.951100488949;5000.399952005637;5067.594592432522;5096.184610462308;5096.898062038813;5160.406553246425;5168.560672342743;5178.766955487087;5188.870278243074;5190.532852043183;5067.366579177603;5068.6451948363265;5068.695878247317;5070.442251352274;5072.315237443046 09:00:52 INFO - PROCESS | 10247 | |5;yui.html;630.9369063093691;662.5031226580062;685.280455521702;730.8428687832119;735.4484136897327;12904.548340808062;14273.571792846218;14295.642608934782;14297.857021429783;14339.784903226433;12486.687832804162;12502.49990000399;12667.556635517758;12992.935035324834;13610.387532561033;757.5757575757566;780.8204113054001;781.0978320040363;785.4933567848734;785.7092875636023;626.7148447456397;750.2572453271276;765.8315170662445;776.1850057439694;780.0911937592698;819.7458787775779;823.2138307915926;824.8102936324649;825.2943733108197;826.1903334731966;816.5019338203691;816.8121332093622;816.8447994880953;816.8529664660389;816.94281400302;481.47038257916364;489.77959918037027;491.96064314855084;493.5853882738481;574.3022227992967;12793.744125117539;13547.729045419037;13711.794323085162;13744.03791734579;13954.441822327095;12350.135490515668;12635.810462843065;13282.070255082148;13336.799948000787;13729.794053089212;12091.153619246656;12133.5146594137;12246.265224086583;12270.81593776094;12678.366081695878;4075.347944328893;4081.530623978251;4122.2786012447705;4160.91678166438;4191.140816132685;63855.36144638587;64268.35731642717;65624;66870.66564667194;67053;9980.650677226262;10021.899781002097;10109.140723038478;10111.595536178618;10175.898241017643;18114.909425453054;18173.818261817476;18186.090695465173;18200.45398638043;18229.17968691412;6513.348665133449;6540.2805691373915;6542;6659.101021362151;6831.248562658104;3689.335919534456;3696.8336424860945;3748.7188460865;3760.435934609776;3771.9245615088093;2869.6412948381453;2954.7931644784658;2956.024511911064;2960.7927445079067;2967.703229677015;502.85919942416183;508.8778693113626;509.20563920284474;511.5805039867273;512.689822657287;498.50896866586385;498.7680728461283;521.6504941689077;548.3419896124666;553.236533583652;7411.2588741125455;7487.1389790173735;7603.0876294844975;7699.769006929801;7892.565908874953;513.9614528910347;546.9835904922859;551.5670198893929;579.0908274009764;583.9620424672382;3594.101474631342;3620.05878471594;3682.281955018799;3685.244524872395;3774.1885494618787;89781.55109224412;90753;92440;96416;96439.51780241054;80411;83489.16510834935;84498;84633.15366846375;85560;72412.63793680997;73517.63241183759;74426.6278668614;75289;78059;6888.070110535014;6988.9301106990315;7096.396806271318;7113.964430177816;7128.821779455555;44744.55255447404;46049.07901842011;46254.68726563726;46397;46661.533384665716;58524.707376462844;64129.67935160294;64729;66011;66069.66965165143;9182.265418766348;9233.122853328923;9324.627014919328;9327.533623318943;9366.484843333683;10384.37693738378;10491.108255798343;10522.158227341948;10895.182861285277;11133.165012624248;8999.280057595246;11549.538018479167;11837.763244734884;12512;12744.6813829655;8831.514266715136;10421.739456513647;10424.530896109542;10973.561057557608;11056;11475.483603237708;11790.646280611596;11920.344381058778;12657.050721196132;12662.683432914251;9992.700218993441;10013.749656258651;10125.392476451436;10388.792224155324;10408.323458975143;9310.534473276444;9548;9593.42439453607;9870.605175793175;9894.505274736379 09:00:52 INFO - PROCESS | 10247 | __end_tp_report 09:00:52 INFO - PROCESS | 10247 | __start_cc_report 09:00:52 INFO - PROCESS | 10247 | _x_x_mozilla_cycle_collect,960 09:00:52 INFO - PROCESS | 10247 | __end_cc_report 09:00:52 INFO - PROCESS | 10247 | __startTimestamp1468857652151__endTimestamp 09:00:52 INFO - PROCESS | 10247 | 09:00:52 INFO - PROCESS | 10247 | ------- Summary: start ------- 09:00:52 INFO - PROCESS | 10247 | Number of tests: 6 09:00:52 INFO - PROCESS | 10247 | 09:00:52 INFO - PROCESS | 10247 | [#0] dojo.html Cycles:165 Average:4740.18 Median:4869.76 stddev:506.49 (10.4%) stddev-sans-first:480.67 09:00:52 INFO - PROCESS | 10247 | Values: 2646.3 2663.1 2692.9 2697.7 2701.2 4627.8 4697.9 4747.6 4869.8 4883.9 4708.1 4730.2 4737.5 4762.7 4781.1 4336.8 4361.1 4427.9 4449.4 4457.2 4457.8 4506.0 4531.3 4547.8 4548.6 4224.0 4233.2 4248.3 4305.5 4368.2 4299.5 4333.1 4341.1 4363.9 4403.1 4285.6 4343.3 4373.8 4405.4 4431.3 4647.7 4804.3 4818.4 4838.6 4838.7 5117.3 5127.6 5160.0 5193.1 5204.3 5173.2 5195.8 5202.0 5215.3 5218.2 4610.3 4651.5 4855.9 4860.9 4862.7 5232.5 5295.3 5303.3 5334.7 5361.1 5101.5 5105.2 5153.9 5154.8 5161.3 4987.3 5073.5 5247.2 5254.3 5257.8 5075.0 5119.9 5125.9 5130.0 5160.8 5076.6 5093.0 5096.6 5102.2 5150.5 4242.9 4267.8 4272.4 4382.7 4438.3 3784.2 3793.8 3814.6 3817.2 3864.4 4458.1 4465.2 4499.6 4500.3 4501.7 5023.0 5193.4 5195.7 5210.7 5219.1 4354.5 4373.7 4386.9 4417.4 4440.4 4580.5 4590.5 4598.4 4628.6 4630.1 4790.8 4819.7 4838.6 4840.7 4886.2 4954.7 4993.0 5101.5 5152.3 5157.3 4839.5 4846.2 4849.3 4884.9 4889.6 5099.7 5105.4 5106.8 5107.3 5109.1 4972.1 5062.8 5103.4 5111.2 5225.5 4988.7 5009.1 5023.1 5028.1 5070.1 4992.2 5008.5 5012.0 5013.6 5014.9 4814.0 4879.1 4915.8 4927.0 4938.5 4975.6 5008.1 5016.2 5033.7 5078.3 4731.0 4881.1 4882.9 4884.5 4929.1 09:00:52 INFO - PROCESS | 10247 | 09:00:52 INFO - PROCESS | 10247 | [#1] ext.html Cycles:180 Average:47977.30 Median:346030.50 stddev:88806.15 (25.7%) stddev-sans-first:88983.96 09:00:52 INFO - PROCESS | 10247 | Values: 575.8 587.9 593.4 595.6 595.8 27244.6 29298.9 38900.8 40142.6 40258.6 39711.4 40014.0 40124.2 40129.8 40193.0 7669.9 7797.6 7799.8 7808.4 7813.0 7342.4 9865.7 10118.3 10119.5 10486.8 10096.5 10176.9 10194.2 10215.0 10222.4 7393.9 10067.2 10078.5 10165.2 10166.9 9993.8 10060.7 10075.7 10076.2 10078.3 37802.8 38304.0 38393.4 38405.2 38428.0 2750.8 3038.0 3526.8 3582.4 3738.2 29173.0 29537.1 29708.3 29860.1 30226.1 3438.4 3456.9 3482.0 3484.3 3600.9 262878.0 263582.0 263863.0 263932.0 264572.0 28073.9 29121.9 51612.0 51827.2 52103.7 30688.7 30829.5 30902.0 30932.2 31010.1 12280.3 12466.3 12478.7 12481.5 12489.5 8819.1 9911.8 10033.8 10063.2 10252.1 7805.7 7830.8 7848.4 7891.7 7902.1 3083.7 3109.5 3148.7 3149.7 3150.6 879.8 910.3 916.3 937.3 1039.9 27332.2 28756.0 28901.3 29293.6 29350.0 939.9 946.7 1004.3 1005.9 1044.6 16333.7 17077.7 17333.9 17837.0 18023.3 309784.9 314558.0 433488.0 434826.0 435099.0 52101.2 52969.0 53076.0 53176.7 53188.7 1106.7 1107.2 1206.2 1213.3 1220.4 26778.3 29580.3 29678.6 30007.8 30468.5 42547.8 43625.6 43639.1 43654.8 43655.6 336431.0 345215.0 345855.0 346206.0 346246.0 38526.0 38737.6 40415.6 42954.4 43628.0 42293.2 42385.6 43215.1 43359.6 43588.0 36121.5 37469.8 37570.0 37682.2 37946.6 36991.3 37158.6 37522.6 37711.6 37722.2 36464.8 36506.0 36840.6 37616.2 37681.0 31768.7 33273.7 33482.3 33913.2 34025.5 25120.2 25673.5 25841.6 25893.2 26328.5 09:00:52 INFO - PROCESS | 10247 | 09:00:52 INFO - PROCESS | 10247 | [#2] jquery.html Cycles:180 Average:24504.45 Median:5226.71 stddev:65274.90 (1248.9%) stddev-sans-first:65432.82 09:00:52 INFO - PROCESS | 10247 | Values: 350.0 363.1 379.3 380.1 393.6 12972.1 16152.8 16846.2 18228.4 18270.3 17721.9 17859.7 17935.1 18143.5 18284.5 4978.5 5301.3 5317.4 5322.1 5323.5 3740.5 5177.6 5932.0 6238.2 6244.8 6128.9 6288.3 6311.5 6313.7 6319.7 6152.6 6267.9 6277.1 6304.0 6308.1 5275.8 5446.7 5486.5 5504.1 5523.3 20869.9 21133.8 21283.5 21426.4 21519.4 1937.2 2019.9 2056.0 2295.9 2309.8 13822.9 13849.8 13916.7 13928.5 14190.8 1736.6 1815.3 1837.7 1840.7 1844.1 61299.0 65399.0 65961.0 76991.0 78523.0 8711.2 9094.2 9878.7 9880.9 10061.9 11280.6 11815.2 11874.0 11889.0 11898.7 6351.5 6414.2 6705.0 7071.3 7128.5 6111.8 6373.5 6600.7 6788.8 6813.4 3992.8 4098.0 4218.5 4472.1 4581.0 1636.4 1695.5 1704.4 1743.0 1767.8 796.4 861.8 891.5 954.1 972.1 17354.3 17639.0 17649.8 17876.6 18126.2 848.5 855.4 856.4 858.7 909.4 9558.9 9751.0 10050.2 10053.2 10101.0 386383.0 391989.0 398280.0 398748.0 400729.0 77276.4 77681.6 78027.0 78968.0 79808.6 871.9 883.0 885.7 892.4 894.0 15435.2 15783.8 15831.1 15907.2 16259.0 29730.7 35450.6 37002.8 38020.2 39388.8 50414.0 50636.0 50771.2 50880.2 51206.9 6121.7 6337.6 6651.7 6787.1 6806.3 8106.2 10121.8 10666.7 11149.6 11225.7 9721.7 9867.6 9923.3 10078.4 10123.0 9905.6 9958.5 9969.5 10075.4 10156.8 9427.4 9656.2 9773.4 9849.3 10082.6 6750.6 6825.1 6884.0 7010.0 7058.4 8398.5 9735.6 9749.4 9809.0 9811.8 09:00:52 INFO - PROCESS | 10247 | 09:00:52 INFO - PROCESS | 10247 | [#3] mootools.html Cycles:180 Average:5176.53 Median:5048.46 stddev:2940.65 (58.2%) stddev-sans-first:2934.99 09:00:52 INFO - PROCESS | 10247 | Values: 1370.5 1426.0 1496.4 1665.2 1704.3 7506.9 7998.7 8071.8 8089.4 8100.6 6724.6 6922.5 6927.7 7107.4 7908.7 1945.4 2130.3 2192.2 2197.5 2211.7 2137.8 2185.3 2204.6 2204.8 2234.1 2189.7 2193.5 2193.7 2200.4 2200.6 2097.9 2134.8 2150.9 2151.8 2155.5 2111.5 2139.8 2146.0 2154.9 2161.1 8000.4 8014.6 8026.6 8069.0 8094.7 4560.9 4698.7 4699.5 4705.0 4709.4 2810.9 2888.5 2903.3 2956.4 2966.9 899.7 925.3 932.2 946.1 951.5 10990.8 11009.8 11012.7 11040.2 11050.0 4194.8 4222.7 4383.6 4397.7 4405.7 9326.9 9357.6 9365.2 9365.9 9369.3 4604.5 4609.0 4611.6 4616.9 4623.6 3598.9 4188.0 4202.2 4205.9 4221.4 3558.5 3716.8 3720.6 3746.3 3792.9 1200.8 1213.8 1217.5 1225.5 1230.0 851.9 854.6 874.9 882.1 884.5 5241.0 5259.9 5289.4 5365.4 5464.2 857.6 890.1 892.4 905.0 905.5 3398.7 3435.7 3594.4 3630.8 3648.6 9607.8 9920.7 9932.6 10027.9 10028.2 9919.1 9943.8 9953.0 9972.7 9982.5 7182.4 7251.5 7289.3 7325.0 7349.0 4953.3 4959.6 4986.6 5034.4 5062.5 8971.2 9177.7 9213.1 9273.1 9298.5 8670.7 9086.9 9382.9 9385.3 9395.0 6016.3 6451.4 6643.6 6809.0 6825.1 7293.2 7490.9 7552.2 7559.2 7559.7 6597.5 6823.8 6844.8 6863.9 7001.9 6647.8 6656.4 6763.7 6765.2 6878.4 6096.5 6132.0 6142.3 6155.4 6160.9 4499.5 4512.0 4652.0 4679.6 4683.7 4511.5 4555.5 4591.6 4627.3 4665.3 09:00:52 INFO - PROCESS | 10247 | 09:00:52 INFO - PROCESS | 10247 | [#4] prototype.html Cycles:180 Average:4249.40 Median:4891.89 stddev:1235.46 (25.3%) stddev-sans-first:1202.83 09:00:52 INFO - PROCESS | 10247 | Values: 299.5 302.0 307.5 308.2 311.0 4893.8 4907.2 4909.0 4924.9 4929.0 4577.3 4715.2 4730.5 4848.6 4862.6 3814.0 3823.3 3846.1 3850.8 3863.2 3834.0 3850.2 3851.5 3853.7 3860.2 3661.2 3689.5 3810.3 3823.3 3828.3 3800.8 3808.2 3817.8 3822.1 3829.2 3387.9 3391.6 3393.0 3394.7 3396.2 4516.0 4776.1 4782.4 4790.5 4804.4 4288.3 4297.2 4312.4 4313.7 4313.8 5126.7 5128.3 5141.5 5148.2 5155.7 3547.1 3635.1 3640.4 3644.3 3651.3 5272.5 5329.4 5334.2 5355.5 5355.9 3714.3 3719.9 3720.6 3729.6 3731.4 3767.1 3780.1 3780.9 3781.0 3787.3 4033.1 4287.7 4291.9 4321.6 4323.9 5086.1 5102.8 5112.8 5122.9 5123.0 1193.7 1266.8 1283.8 1292.8 1298.7 874.3 877.8 890.0 891.2 893.0 4462.3 4480.7 4488.6 4495.0 4495.3 5189.9 5207.9 5211.6 5229.6 5237.0 4479.6 4488.4 4490.1 4494.5 4496.8 2678.6 2816.6 2830.2 2833.5 2834.9 4970.4 4995.9 5017.7 5025.8 5036.6 5305.0 5365.4 5446.5 5447.7 5455.6 4963.0 4971.2 4977.4 4982.3 4998.6 5233.5 5262.9 5270.3 5280.7 5289.6 4933.8 5154.4 5157.9 5171.9 5195.1 4958.6 4958.7 4960.9 4995.2 5000.6 3711.4 3715.1 3720.2 3724.4 3731.1 5063.8 5101.9 5236.0 5249.5 5250.8 5022.1 5143.6 5168.6 5170.3 5177.2 5111.0 5112.7 5164.9 5166.5 5173.0 4890.0 5000.4 5067.6 5096.2 5096.9 5160.4 5168.6 5178.8 5188.9 5190.5 5067.4 5068.6 5068.7 5070.4 5072.3 09:00:52 INFO - PROCESS | 10247 | 09:00:52 INFO - PROCESS | 10247 | [#5] yui.html Cycles:180 Average:17682.68 Median:512.14 stddev:25254.65 (4931.2%) stddev-sans-first:25293.04 09:00:52 INFO - PROCESS | 10247 | Values: 630.9 662.5 685.3 730.8 735.4 12904.5 14273.6 14295.6 14297.9 14339.8 12486.7 12502.5 12667.6 12992.9 13610.4 757.6 780.8 781.1 785.5 785.7 626.7 750.3 765.8 776.2 780.1 819.7 823.2 824.8 825.3 826.2 816.5 816.8 816.8 816.9 816.9 481.5 489.8 492.0 493.6 574.3 12793.7 13547.7 13711.8 13744.0 13954.4 12350.1 12635.8 13282.1 13336.8 13729.8 12091.2 12133.5 12246.3 12270.8 12678.4 4075.3 4081.5 4122.3 4160.9 4191.1 63855.4 64268.4 65624.0 66870.7 67053.0 9980.7 10021.9 10109.1 10111.6 10175.9 18114.9 18173.8 18186.1 18200.5 18229.2 6513.3 6540.3 6542.0 6659.1 6831.2 3689.3 3696.8 3748.7 3760.4 3771.9 2869.6 2954.8 2956.0 2960.8 2967.7 502.9 508.9 509.2 511.6 512.7 498.5 498.8 521.7 548.3 553.2 7411.3 7487.1 7603.1 7699.8 7892.6 514.0 547.0 551.6 579.1 584.0 3594.1 3620.1 3682.3 3685.2 3774.2 89781.6 90753.0 92440.0 96416.0 96439.5 80411.0 83489.2 84498.0 84633.2 85560.0 72412.6 73517.6 74426.6 75289.0 78059.0 6888.1 6988.9 7096.4 7114.0 7128.8 44744.6 46049.1 46254.7 46397.0 46661.5 58524.7 64129.7 64729.0 66011.0 66069.7 9182.3 9233.1 9324.6 9327.5 9366.5 10384.4 10491.1 10522.2 10895.2 11133.2 8999.3 11549.5 11837.8 12512.0 12744.7 8831.5 10421.7 10424.5 10973.6 11056.0 11475.5 11790.6 11920.3 12657.1 12662.7 9992.7 10013.7 10125.4 10388.8 10408.3 9310.5 9548.0 9593.4 9870.6 9894.5 09:00:52 INFO - PROCESS | 10247 | -------- Summary: end -------- 09:00:52 INFO - PROCESS | 10247 | 09:00:52 INFO - TEST-INFO | 10247: exit 0 09:00:52 INFO - TEST-OK | dromaeo_css | took 1122946ms 09:00:52 INFO - TEST-START | kraken 09:00:52 INFO - operating with platform_type : linux_ 09:00:52 INFO - Initialising browser for kraken test... 09:00:52 INFO - TEST-INFO | started process 10429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAhh4Bq/profile http://localhost:32795/getInfo.html) 09:00:56 INFO - PROCESS | 10429 | JavaScript error: , line 0: uncaught exception: undefined 09:00:57 INFO - PROCESS | 10429 | __metrics Screen width/height:1600/1200 09:00:57 INFO - PROCESS | 10429 | colorDepth:24 09:00:57 INFO - PROCESS | 10429 | Browser inner width/height: 1024/697 09:00:57 INFO - PROCESS | 10429 | __metrics 09:00:58 INFO - PROCESS | 10429 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:00:58 INFO - PROCESS | 10429 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:01:00 INFO - TEST-INFO | 10429: exit 0 09:01:00 INFO - Browser initialized. 09:01:00 INFO - Running cycle 1/1 for kraken test... 09:01:00 INFO - TEST-INFO | started process 10513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAhh4Bq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:12 INFO - PROCESS | 10513 | RSS: Main: 159690752 09:01:12 INFO - PROCESS | 10513 | 09:01:14 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32795/tests/kraken/driver.html?testName=audio-beat-detection) 09:01:15 INFO - PROCESS | 10513 | RSS: Main: 218054656 09:01:15 INFO - PROCESS | 10513 | 09:01:18 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32795/tests/kraken/driver.html?testName=audio-dft) 09:01:19 INFO - PROCESS | 10513 | RSS: Main: 206888960 09:01:19 INFO - PROCESS | 10513 | 09:01:22 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32795/tests/kraken/driver.html?testName=audio-fft) 09:01:22 INFO - PROCESS | 10513 | RSS: Main: 273920000 09:01:22 INFO - PROCESS | 10513 | 09:01:25 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32795/tests/kraken/driver.html?testName=audio-oscillator) 09:01:25 INFO - PROCESS | 10513 | RSS: Main: 181800960 09:01:25 INFO - PROCESS | 10513 | 09:01:27 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32795/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:01:28 INFO - PROCESS | 10513 | RSS: Main: 186195968 09:01:28 INFO - PROCESS | 10513 | 09:01:31 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32795/tests/kraken/driver.html?testName=imaging-darkroom) 09:01:32 INFO - PROCESS | 10513 | RSS: Main: 221732864 09:01:32 INFO - PROCESS | 10513 | 09:01:35 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32795/tests/kraken/driver.html?testName=imaging-desaturate) 09:01:36 INFO - PROCESS | 10513 | RSS: Main: 217227264 09:01:36 INFO - PROCESS | 10513 | 09:01:39 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32795/tests/kraken/driver.html?testName=json-parse-financial) 09:01:39 INFO - PROCESS | 10513 | RSS: Main: 220762112 09:01:39 INFO - PROCESS | 10513 | 09:01:41 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32795/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:01:42 INFO - PROCESS | 10513 | RSS: Main: 211083264 09:01:42 INFO - PROCESS | 10513 | 09:01:44 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32795/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:01:44 INFO - PROCESS | 10513 | RSS: Main: 215093248 09:01:44 INFO - PROCESS | 10513 | 09:01:46 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32795/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:01:47 INFO - PROCESS | 10513 | RSS: Main: 212819968 09:01:47 INFO - PROCESS | 10513 | 09:01:50 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32795/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:01:50 INFO - PROCESS | 10513 | RSS: Main: 226828288 09:01:50 INFO - PROCESS | 10513 | 09:01:53 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32795/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:01:54 INFO - PROCESS | 10513 | RSS: Main: 225935360 09:01:54 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | Cycle 1(1): loaded http://localhost:32795/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32795/tests/kraken/driver.html?testName=ai-astar) 09:01:56 INFO - PROCESS | 10513 | RSS: Main: 267612160 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | __start_tp_report 09:01:56 INFO - PROCESS | 10513 | _x_x_mozilla_page_load 09:01:56 INFO - PROCESS | 10513 | _x_x_mozilla_page_load_details 09:01:56 INFO - PROCESS | 10513 | |i|pagename|runs| 09:01:56 INFO - PROCESS | 10513 | |0;ai-astar;104;114;114;114;114;116;113;114;115;113 09:01:56 INFO - PROCESS | 10513 | |1;audio-beat-detection;145;125;124;169;127;128;151;121;160;128 09:01:56 INFO - PROCESS | 10513 | |2;audio-dft;161;162;161;160;163;163;160;163;161;160 09:01:56 INFO - PROCESS | 10513 | |3;audio-fft;76;76;76;131;76;78;75;76;102;76 09:01:56 INFO - PROCESS | 10513 | |4;audio-oscillator;84;86;110;85;85;85;120;84;85;123 09:01:56 INFO - PROCESS | 10513 | |5;imaging-gaussian-blur;116;116;118;117;117;117;117;116;116;116 09:01:56 INFO - PROCESS | 10513 | |6;imaging-darkroom;147;148;147;147;148;145;146;146;147;146 09:01:56 INFO - PROCESS | 10513 | |7;imaging-desaturate;95;95;94;95;95;95;94;95;94;94 09:01:56 INFO - PROCESS | 10513 | |8;json-parse-financial;63;61;62;63;61;62;63;62;61;62 09:01:56 INFO - PROCESS | 10513 | |9;json-stringify-tinderbox;66;66;66;66;66;65;65;66;67;67 09:01:56 INFO - PROCESS | 10513 | |10;stanford-crypto-aes;79;79;78;82;85;79;79;87;84;82 09:01:56 INFO - PROCESS | 10513 | |11;stanford-crypto-ccm;129;128;131;135;136;128;127;126;165;133 09:01:56 INFO - PROCESS | 10513 | |12;stanford-crypto-pbkdf2;163;166;167;163;168;169;163;162;164;161 09:01:56 INFO - PROCESS | 10513 | |13;stanford-crypto-sha256-iterative;66;65;67;65;67;66;65;66;65;64 09:01:56 INFO - PROCESS | 10513 | __end_tp_report 09:01:56 INFO - PROCESS | 10513 | __start_cc_report 09:01:56 INFO - PROCESS | 10513 | _x_x_mozilla_cycle_collect,1941 09:01:56 INFO - PROCESS | 10513 | __end_cc_report 09:01:56 INFO - PROCESS | 10513 | __startTimestamp1468857716417__endTimestamp 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | ------- Summary: start ------- 09:01:56 INFO - PROCESS | 10513 | Number of tests: 14 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:3.31 (2.9%) stddev-sans-first:0.93 09:01:56 INFO - PROCESS | 10513 | Values: 104.0 114.0 114.0 114.0 114.0 116.0 113.0 114.0 115.0 113.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:136.50 stddev:17.12 (12.5%) stddev-sans-first:17.96 09:01:56 INFO - PROCESS | 10513 | Values: 145.0 125.0 124.0 169.0 127.0 128.0 151.0 121.0 160.0 128.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.26 (0.8%) stddev-sans-first:1.33 09:01:56 INFO - PROCESS | 10513 | Values: 161.0 162.0 161.0 160.0 163.0 163.0 160.0 163.0 161.0 160.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#3] audio-fft Cycles:10 Average:84.20 Median:76.00 stddev:18.36 (24.2%) stddev-sans-first:19.23 09:01:56 INFO - PROCESS | 10513 | Values: 76.0 76.0 76.0 131.0 76.0 78.0 75.0 76.0 102.0 76.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:85.00 stddev:16.18 (19.0%) stddev-sans-first:16.69 09:01:56 INFO - PROCESS | 10513 | Values: 84.0 86.0 110.0 85.0 85.0 85.0 120.0 84.0 85.0 123.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 09:01:56 INFO - PROCESS | 10513 | Values: 116.0 116.0 118.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 09:01:56 INFO - PROCESS | 10513 | Values: 147.0 148.0 147.0 147.0 148.0 145.0 146.0 146.0 147.0 146.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:01:56 INFO - PROCESS | 10513 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 94.0 94.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:62.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 09:01:56 INFO - PROCESS | 10513 | Values: 63.0 61.0 62.0 63.0 61.0 62.0 63.0 62.0 61.0 62.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 09:01:56 INFO - PROCESS | 10513 | Values: 66.0 66.0 66.0 66.0 66.0 65.0 65.0 66.0 67.0 67.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:82.00 stddev:3.10 (3.8%) stddev-sans-first:3.16 09:01:56 INFO - PROCESS | 10513 | Values: 79.0 79.0 78.0 82.0 85.0 79.0 79.0 87.0 84.0 82.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#11] stanford-crypto-ccm Cycles:10 Average:133.80 Median:132.00 stddev:11.48 (8.7%) stddev-sans-first:12.04 09:01:56 INFO - PROCESS | 10513 | Values: 129.0 128.0 131.0 135.0 136.0 128.0 127.0 126.0 165.0 133.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.60 Median:165.00 stddev:2.72 (1.6%) stddev-sans-first:2.82 09:01:56 INFO - PROCESS | 10513 | Values: 163.0 166.0 167.0 163.0 168.0 169.0 163.0 162.0 164.0 161.0 09:01:56 INFO - PROCESS | 10513 | 09:01:56 INFO - PROCESS | 10513 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 09:01:56 INFO - PROCESS | 10513 | Values: 66.0 65.0 67.0 65.0 67.0 66.0 65.0 66.0 65.0 64.0 09:01:56 INFO - PROCESS | 10513 | -------- Summary: end -------- 09:01:56 INFO - PROCESS | 10513 | 09:01:57 INFO - TEST-INFO | 10513: exit 0 09:01:57 INFO - TEST-OK | kraken | took 64596ms 09:01:57 INFO - SUITE-END | took 1187s 09:01:58 INFO - Completed test suite (00:19:48) 09:01:58 INFO - javascript benchmark 09:01:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2646.325187077295, 2663.13448129358, 2692.946141077179, 2697.6762788465376, 2701.243651777502, 4627.8380256691025, 4697.929531057037, 4747.62019038477, 4869.75651217439, 4883.926741098886, 4708.128996135717, 4730.19586670266, 4737.549932756391, 4762.690425122365, 4781.091592597413, 4336.826526938935, 4361.062371590107, 4427.867163985085, 4449.377087207794, 4457.1977044132045, 4457.821687132511, 4505.977470112645, 4531.3203019547, 4547.840825571106, 4548.56788605082, 4223.97888010561, 4233.174530966463, 4248.299030659969, 4305.547917468652, 4368.17004769096, 4299.462567179103, 4333.090051089275, 4341.06667066579, 4363.869083927487, 4403.053343531156, 4285.5928686774705, 4343.261645520269, 4373.846915357979, 4405.449318835145, 4431.313146462304, 4647.697899636547, 4804.327394164819, 4818.445878723922, 4838.564529192355, 4838.733869637169, 5117.334746482933, 5127.6154288428515, 5160.045391602528, 5193.091209038441, 5204.34945631796, 5173.224016397533, 5195.766190521398, 5202.011617792645, 5215.348081489814, 5218.165093585046, 4610.262358022767, 4651.511591282934, 4855.854324370274, 4860.854174374775, 4862.708237505761, 5232.529072383504, 5295.285136506599, 5303.28405665238, 5334.7065911374475, 5361.08861493547, 5101.540861322499, 5105.15764898795, 5153.896922061613, 5154.768035438415, 5161.303224064778, 4987.276844857475, 5073.467285934924, 5247.239150323182, 5254.316938798008, 5257.7896884124975, 5075.0357432087785, 5119.923201151985, 5125.897482050413, 5130.025295193976, 5160.767765450564, 5076.6446348755235, 5092.998247570534, 5096.566791822663, 5102.183650615883, 5150.510701483353, 4242.936355954663, 4267.829286828554, 4272.4445822042935, 4382.671299652475, 4438.334249862482, 3784.2242340320167, 3793.848246070126, 3814.5613254475534, 3817.2365526894177, 3864.439656249884, 4458.08609235106, 4465.218586747306, 4499.55004499554, 4500.347449619787, 4501.662375321864, 5023.02051100032, 5193.350831146106, 5195.68825870449, 5210.6613070150315, 5219.1388420910125, 4354.4992325882295, 4373.671974601854, 4386.912261754747, 4417.4036505072045, 4440.378347031418, 4580.450345958507, 4590.48582393717, 4598.3792188054185, 4628.629709623222, 4630.143423466669, 4790.760461976957, 4819.6867203632355, 4838.564529192389, 4840.709557426566, 4886.193778026586, 4954.653174277766, 4993.0, 5101.489851014869, 5152.30443890085, 5157.303763991813, 4839.516048395132, 4846.248831431134, 4849.2726091086615, 4884.90230195387, 4889.559939605452, 5099.6685215460875, 5105.361829771278, 5106.7957281709805, 5107.259447380184, 5109.1059064662795, 4972.129877271391, 5062.848114556569, 5103.387593488805, 5111.156659151198, 5225.451327610546, 4988.700677959334, 5009.073321435546, 5023.120953833138, 5028.094942910311, 5070.062038522738, 4992.201247800334, 5008.5492305691205, 5012.0, 5013.573846223112, 5014.867876946519, 4813.975930120327, 4879.097366987119, 4915.827946021872, 4927.039227350632, 4938.506149385176, 4975.601951843916, 5008.1235783736975, 5016.2224855147515, 5033.748312584429, 5078.263651770547, 4730.952690473051, 4881.072596206709, 4882.853514394574, 4884.511548845087, 4929.13740095489], "value": 4707.556892325734, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.7610591604483, 587.8706684529402, 593.4154857465396, 595.6039233909452, 595.8063629320471, 27244.591331130025, 29298.85350573247, 38900.80549597255, 40142.59857401425, 40258.59741402593, 39711.40432893509, 40013.99895007871, 40124.19751604966, 40129.7993510032, 40193.0, 7669.923300766991, 7797.610119494031, 7799.844003119934, 7808.414368922354, 7812.960935195316, 7342.412606991428, 9865.704028879145, 10118.291719579633, 10119.544620492059, 10486.842697359545, 10096.495175241245, 10176.949115254414, 10194.235432342566, 10215.02957219063, 10222.386656800616, 7393.8521229575645, 10067.194624430065, 10078.496075196212, 10165.237607179453, 10166.89833101667, 9993.80012399748, 10060.748481287954, 10075.697729068139, 10076.193904487624, 10078.344907580984, 37802.810985944896, 38304.0, 38393.42409863854, 38405.231895362216, 38428.0, 2750.8074434789373, 3038.0126458900727, 3526.823658817049, 3582.408902531079, 3738.214974855284, 29173.0, 29537.113886583436, 29708.257293567618, 29860.10419687413, 30226.093217203517, 3438.398280300937, 3456.9308613827834, 3481.9554133760025, 3484.285721427101, 3600.945985810215, 262878.0, 263582.0, 263863.0, 263931.9999999981, 264572.0, 28073.859630701714, 29121.854390728335, 51612.0, 51827.22259166116, 52103.73948130235, 30688.693113069032, 30829.537556936662, 30902.0, 30932.22669433282, 31010.069697909097, 12280.263184208798, 12466.314352710688, 12478.688032799253, 12481.500739970481, 12489.50041998328, 8819.073997230327, 9911.752206194758, 10033.849492257623, 10063.19494440443, 10252.077313041862, 7805.726799561988, 7830.765077047698, 7848.411369147337, 7891.684332626745, 7902.0517537894675, 3083.7070478304527, 3109.4869346558016, 3148.7008734170217, 3149.685031496878, 3150.57467241924, 879.7800549862534, 910.3172620534599, 916.3036092569562, 937.3251259093441, 1039.9428031458342, 27332.180034598994, 28756.0, 28901.27746806347, 29293.56059659107, 29350.0, 939.9410427688111, 946.6970569417721, 1004.2869562610629, 1005.894381089975, 1044.587387981743, 16333.673326533402, 17077.658446830992, 17333.913330433268, 17837.0, 18023.2790688371, 309784.90215098014, 314558.0, 433488.0, 434826.0, 435099.0, 52101.218481722804, 52969.0, 53076.0, 53176.73411632917, 53188.73405632947, 1106.7011906785283, 1107.197281970581, 1206.1677442564953, 1213.3023511480933, 1220.3593113615423, 26778.330541736614, 29580.260493487836, 29678.554821678124, 30007.849960750056, 30468.542971855884, 42547.787261063495, 43625.56374436215, 43639.12721745611, 43654.78172609117, 43655.563444366424, 336431.0, 345215.0, 345855.0, 346206.0, 346246.0, 38526.0, 38737.6126238734, 40415.595844041185, 42954.35568466413, 43628.0, 42293.15413691771, 42385.57614423816, 43215.13569728651, 43359.566404336816, 43588.0, 36121.45817812682, 37469.81265093657, 37570.0, 37682.246355073294, 37946.62053379431, 36991.260174796895, 37158.6284137166, 37522.62477375301, 37711.62288377081, 37722.24555508929, 36464.81767591145, 36506.0, 36840.63159368479, 37616.24767504689, 37681.0, 31768.682313176574, 33273.66726332706, 33482.330353393285, 33913.152171195914, 34025.48961765526, 25120.246392608253, 25673.486530268918, 25841.612375814755, 25893.223203303933, 26328.473430531667], "value": 17220.11656812908, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.0443788457512, 363.1085684644197, 379.31722898782203, 380.1124374585343, 393.5552825307402, 12972.091953563255, 16152.757708634364, 16846.24191911364, 18228.362007329746, 18270.269189232415, 17721.91139044303, 17859.73210401845, 17935.103244837737, 18143.546411339725, 18284.542886427847, 4978.477259887705, 5301.3373328333955, 5317.41508434072, 5322.148456247001, 5323.467653234684, 3740.45763364312, 5177.637565370425, 5932.0, 6238.189035425388, 6244.812655620339, 6128.938710612903, 6288.339724328948, 6311.495080393557, 6313.747450101991, 6319.65241911697, 6152.630842149463, 6267.905981410259, 6277.089821975788, 6304.022876454157, 6308.085327627514, 5275.788968441258, 5446.727663616843, 5486.506214440679, 5504.119340905436, 5523.28197334348, 20869.895650521652, 21133.788662113337, 21283.46791330214, 21426.357209283746, 21519.354419367442, 1937.186381719681, 2019.9091040903195, 2055.989720051405, 2295.850769699964, 2309.84985975911, 13822.861771382259, 13849.792253116211, 13916.721665566633, 13928.51250206248, 14190.787138192833, 1736.5745392378822, 1815.2648177488138, 1837.6784062788959, 1840.74229607855, 1844.0503140882456, 61299.0, 65399.0, 65961.0, 76991.0, 78523.0, 8711.21599056088, 9094.181523662903, 9878.654247101316, 9880.901190987997, 10061.949690251502, 11280.605178818701, 11815.232009919328, 11873.99071078968, 11888.989435898045, 11898.702532436586, 6351.460125889259, 6414.198225221848, 6704.99425086241, 7071.328223818729, 7128.536645118154, 6111.786087486917, 6373.490120790326, 6600.669966501656, 6788.830279243059, 6813.3527314906, 3992.8003599819895, 4098.0, 4218.535961044283, 4472.08322293929, 4580.954190458119, 1636.3699975509514, 1695.5252529291818, 1704.4119778676336, 1742.9738553921702, 1767.770189875308, 796.3509739562238, 861.8190180062131, 891.4963045879058, 954.1460392948272, 972.0619602084023, 17354.305827767, 17639.0, 17649.76451648398, 17876.642467150585, 18126.184321705292, 848.5078654380444, 855.3798496090409, 856.4176360075085, 858.7080392666523, 909.3816204980544, 9558.90441095594, 9751.0, 10050.246231532665, 10053.195744340435, 10100.98990100984, 386383.0, 391989.0, 398280.0, 398748.0, 400729.0, 77276.42824674104, 77681.61159194281, 78027.0, 78968.0, 79808.600956996, 871.9389642725075, 883.0374891368439, 885.7475619448297, 892.4199270474114, 894.0, 15435.22823858825, 15783.842161578697, 15831.129287889276, 15907.204639768197, 16259.0, 29730.70269297366, 35450.64549354473, 37002.814985924895, 38020.23959520849, 39388.803055984536, 50414.0, 50636.0, 50771.23843142282, 50880.23679644882, 51206.85517737895, 6121.663308517896, 6337.588056776295, 6651.733930642914, 6787.083743694538, 6806.2513123557355, 8106.229908158715, 10121.797564048531, 10666.73333166677, 11149.609763658233, 11225.66323010311, 9721.659741909, 9867.605295788375, 9923.255755818198, 10078.445685487368, 10123.0, 9905.603775849173, 9958.452285124098, 9969.451680157661, 10075.445850478294, 10156.847647285444, 9427.434353938786, 9656.22750179998, 9773.41359518431, 9849.310548261554, 10082.606882583124, 6750.561213521106, 6825.146856642919, 6884.0362349272145, 7009.9649501752165, 7058.3647471725735, 8398.538080405637, 9735.561899714388, 9749.415035097916, 9808.950955245178, 9811.80376392454], "value": 8230.44815337111, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.5134677189594, 1426.0160489262407, 1496.4313560846883, 1665.159094657198, 1704.2927185218127, 7506.887396689054, 7998.680052797896, 8071.798205044877, 8089.352851771851, 8100.594970251486, 6724.630145342005, 6922.5154239203275, 6927.7228910843505, 7107.431405487561, 7908.683652653901, 1945.4455480188185, 2130.339594725632, 2192.243675931801, 2197.5385169114425, 2211.7235345581803, 2137.7969092936146, 2185.25701261571, 2204.5921504521634, 2204.834637402193, 2234.0505285253785, 2189.737231532218, 2193.462601662587, 2193.6709493576013, 2200.4168895242774, 2200.592890315289, 2097.92657256996, 2134.807867291951, 2150.9462263443384, 2151.8386121040826, 2155.5150091229507, 2111.4510227341, 2139.8181154601875, 2145.978540214609, 2154.8707077575395, 2161.1247444784885, 8000.35997120229, 8014.5591992440395, 8026.638801253957, 8069.0, 8094.7166849160585, 4560.8859778505475, 4698.741569213692, 4699.483056863743, 4704.952950470486, 4709.434867815849, 2810.9297267568268, 2888.4511942730815, 2903.303207230271, 2956.379160376302, 2966.9109926702235, 899.7390756680555, 925.3013974449248, 932.151741914861, 946.1484663802564, 951.457669128597, 10990.835137472945, 11009.83485247722, 11012.669619911416, 11040.227184097117, 11050.005499505083, 4194.8112334945, 4222.725522841006, 4383.583559561836, 4397.6921615487245, 4405.713628614129, 9326.90673093274, 9357.578908949114, 9365.15713585767, 9365.906340936503, 9369.344145909721, 4604.516525764813, 4609.0, 4611.5619016193405, 4616.884577885579, 4623.583877450978, 3598.9280214395567, 4187.97906010468, 4202.243596152722, 4205.894852628709, 4221.430106935586, 3558.484019817112, 3716.7955762433326, 3720.6093360197333, 3746.2507498499863, 3792.886213413602, 1200.8378868852767, 1213.8422005139275, 1217.5190799634095, 1225.522046401904, 1229.993850030762, 851.8849955256085, 854.5556310718516, 874.903760586335, 882.0650110882485, 884.5488800711555, 5241.030409374278, 5259.921101183485, 5289.404270143862, 5365.382980957162, 5464.207689884945, 857.6012154348258, 890.1365675294953, 892.433304851424, 904.9728508144766, 905.5200743605899, 3398.745094117951, 3435.6736110069505, 3594.406922857699, 3630.8003059831435, 3648.562172539312, 9607.807843843084, 9920.652777152765, 9932.602695892228, 10027.899721002843, 10028.197744180448, 9919.107280344804, 9943.751406214902, 9953.0, 9972.650957216607, 9982.500874956224, 7182.3535881770895, 7251.4923955323675, 7289.307515785992, 7324.963375183196, 7349.0, 4953.3065370848835, 4959.5536401722575, 4986.626003049713, 5034.395872495324, 5062.519060689376, 8971.237444817263, 9177.678781242625, 9213.12475314844, 9273.072692730673, 9298.488583127993, 8670.739877803677, 9086.863697044682, 9382.859257111286, 9385.296102792454, 9394.95302523483, 6016.278046634432, 6451.387118223761, 6643.634600097041, 6808.965955170391, 6825.078614386995, 7293.161286452126, 7490.887636685345, 7552.207018263003, 7559.20628334039, 7559.659815308434, 6597.538172327891, 6823.829404264933, 6844.794656160323, 6863.931360686329, 7001.859962800817, 6647.800565983028, 6656.369189548592, 6763.695633696594, 6765.188177418544, 6878.38094571491, 6096.451319381277, 6132.049532322497, 6142.2629284484365, 6155.353687862889, 6160.907586386118, 4499.5275496073755, 4511.977440112779, 4651.999820038708, 4679.578837904605, 4683.672142949961, 4511.548845115462, 4555.544445555418, 4591.586757191869, 4627.30590411441, 4665.300204969281], "value": 4166.130446285129, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.50133028507537, 301.96678365379813, 307.51105741870424, 308.2248145913029, 311.04819253085543, 4893.8287159949405, 4907.190313598261, 4909.0, 4924.9411376554035, 4928.950710492894, 4577.2676371780435, 4715.2455607102875, 4730.503297153801, 4848.6363522735755, 4862.5866801321845, 3813.9618603814024, 3823.273578020181, 3846.0961674006726, 3850.8062500624756, 3863.150106976461, 3833.9808300958457, 3850.191459793433, 3851.480050193216, 3853.65317121459, 3860.1893602343544, 3661.176235347052, 3689.5019172411785, 3810.2760475509704, 3823.254465379252, 3828.25349056934, 3800.7719536827603, 3808.1812410331913, 3817.790021548814, 3822.0827001519715, 3829.2341531693633, 3387.864485420605, 3391.6438773928876, 3392.9830350848333, 3394.7114495267933, 3396.1679388549965, 4516.006478574708, 4776.09254241693, 4782.3543821584335, 4790.490995336493, 4804.447488538828, 4288.335308027261, 4297.247981603238, 4312.37470566766, 4313.741175529447, 4313.762743049131, 5126.743662816882, 5128.2564028216075, 5141.485851414866, 5148.2020286855595, 5155.664881782672, 3547.077759782475, 3635.1457407509215, 3640.4357324614725, 3644.2850076692766, 3651.2697460508007, 5272.499112584298, 5329.387120481194, 5334.1732031535175, 5355.544778693777, 5355.866103347408, 3714.331420344364, 3719.9070023249637, 3720.572134204547, 3729.5710993235584, 3731.395499935, 3767.1147280389087, 3780.1494663700446, 3780.8676696315497, 3780.9621903781162, 3787.280416720814, 4033.092554175287, 4287.678424118204, 4291.914161716748, 4321.567843215653, 4323.870283891489, 5086.109930762102, 5102.810743285447, 5112.769925353368, 5122.8975420492125, 5122.974385128051, 1193.7194759231575, 1266.7656483550388, 1283.8202651628778, 1292.8060790881443, 1298.69480672042, 874.3224001398839, 877.8419884420865, 889.9644014239487, 891.157855826251, 892.9553522323857, 4462.263726485158, 4480.686351955332, 4488.5960263576435, 4494.955050449519, 4495.336725411553, 5189.896202075937, 5207.947920520822, 5211.5830733541225, 5229.607779416483, 5236.962561714196, 4479.552044795494, 4488.3940668010055, 4490.114152056144, 4494.505604383515, 4496.797644105957, 2678.6249925010516, 2816.5775133729694, 2830.249983754307, 2833.461642288, 2834.9149525514267, 4970.353853998957, 4995.900081998412, 5017.6953991962355, 5025.773840177201, 5036.622253331015, 5304.9734751326, 5365.38298095724, 5446.537044351275, 5447.673139611636, 5455.645383050089, 4962.975185124052, 4971.204607262821, 4977.40271167455, 4982.327385802942, 4998.575121114746, 5233.450487698889, 5262.868428289324, 5270.314859068297, 5280.7359632019015, 5289.6032797539565, 4933.827316043921, 5154.433012368561, 5157.871053223699, 5171.896562068813, 5195.090859099718, 4958.586802761196, 4958.727270000185, 4960.900781984268, 4995.225740010304, 5000.64995450315, 3711.387621042498, 3715.1269451679373, 3720.2187540616774, 3724.385476396364, 3731.12318605133, 5063.772130254074, 5101.857219967548, 5235.973820130875, 5249.527542521191, 5250.842474725764, 5022.1211288023715, 5143.636936211981, 5168.560672342743, 5170.327857378667, 5177.2492988515705, 5110.97444512775, 5112.71880046601, 5164.896702065863, 5166.45752196029, 5172.9741351293005, 4889.951100488949, 5000.399952005637, 5067.594592432522, 5096.184610462308, 5096.898062038813, 5160.406553246425, 5168.560672342743, 5178.766955487087, 5188.870278243074, 5190.532852043183, 5067.366579177603, 5068.6451948363265, 5068.695878247317, 5070.442251352274, 5072.315237443046], "value": 3857.294437016072, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.9369063093691, 662.5031226580062, 685.280455521702, 730.8428687832119, 735.4484136897327, 12904.548340808062, 14273.571792846218, 14295.642608934782, 14297.857021429783, 14339.784903226433, 12486.687832804162, 12502.49990000399, 12667.556635517758, 12992.935035324834, 13610.387532561033, 757.5757575757566, 780.8204113054001, 781.0978320040363, 785.4933567848734, 785.7092875636023, 626.7148447456397, 750.2572453271276, 765.8315170662445, 776.1850057439694, 780.0911937592698, 819.7458787775779, 823.2138307915926, 824.8102936324649, 825.2943733108197, 826.1903334731966, 816.5019338203691, 816.8121332093622, 816.8447994880953, 816.8529664660389, 816.94281400302, 481.47038257916364, 489.77959918037027, 491.96064314855084, 493.5853882738481, 574.3022227992967, 12793.744125117539, 13547.729045419037, 13711.794323085162, 13744.03791734579, 13954.441822327095, 12350.135490515668, 12635.810462843065, 13282.070255082148, 13336.799948000787, 13729.794053089212, 12091.153619246656, 12133.5146594137, 12246.265224086583, 12270.81593776094, 12678.366081695878, 4075.347944328893, 4081.530623978251, 4122.2786012447705, 4160.91678166438, 4191.140816132685, 63855.36144638587, 64268.35731642717, 65624.0, 66870.66564667194, 67053.0, 9980.650677226262, 10021.899781002097, 10109.140723038478, 10111.595536178618, 10175.898241017643, 18114.909425453054, 18173.818261817476, 18186.090695465173, 18200.45398638043, 18229.17968691412, 6513.348665133449, 6540.2805691373915, 6542.0, 6659.101021362151, 6831.248562658104, 3689.335919534456, 3696.8336424860945, 3748.7188460865, 3760.435934609776, 3771.9245615088093, 2869.6412948381453, 2954.7931644784658, 2956.024511911064, 2960.7927445079067, 2967.703229677015, 502.85919942416183, 508.8778693113626, 509.20563920284474, 511.5805039867273, 512.689822657287, 498.50896866586385, 498.7680728461283, 521.6504941689077, 548.3419896124666, 553.236533583652, 7411.2588741125455, 7487.1389790173735, 7603.0876294844975, 7699.769006929801, 7892.565908874953, 513.9614528910347, 546.9835904922859, 551.5670198893929, 579.0908274009764, 583.9620424672382, 3594.101474631342, 3620.05878471594, 3682.281955018799, 3685.244524872395, 3774.1885494618787, 89781.55109224412, 90753.0, 92440.0, 96416.0, 96439.51780241054, 80411.0, 83489.16510834935, 84498.0, 84633.15366846375, 85560.0, 72412.63793680997, 73517.63241183759, 74426.6278668614, 75289.0, 78059.0, 6888.070110535014, 6988.9301106990315, 7096.396806271318, 7113.964430177816, 7128.821779455555, 44744.55255447404, 46049.07901842011, 46254.68726563726, 46397.0, 46661.533384665716, 58524.707376462844, 64129.67935160294, 64729.0, 66011.0, 66069.66965165143, 9182.265418766348, 9233.122853328923, 9324.627014919328, 9327.533623318943, 9366.484843333683, 10384.37693738378, 10491.108255798343, 10522.158227341948, 10895.182861285277, 11133.165012624248, 8999.280057595246, 11549.538018479167, 11837.763244734884, 12512.0, 12744.6813829655, 8831.514266715136, 10421.739456513647, 10424.530896109542, 10973.561057557608, 11056.0, 11475.483603237708, 11790.646280611596, 11920.344381058778, 12657.050721196132, 12662.683432914251, 9992.700218993441, 10013.749656258651, 10125.392476451436, 10388.792224155324, 10408.323458975143, 9310.534473276444, 9548.0, 9593.42439453607, 9870.605175793175, 9894.505274736379], "value": 6445.20712157082, "unit": "score"}], "name": "dromaeo_css", "value": 6406.08298399095, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 114.0, 114.0, 116.0, 113.0, 114.0, 115.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 125.0, 124.0, 169.0, 127.0, 128.0, 151.0, 121.0, 160.0, 128.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 160.0, 163.0, 163.0, 160.0, 163.0, 161.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 131.0, 76.0, 78.0, 75.0, 76.0, 102.0, 76.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 110.0, 85.0, 85.0, 85.0, 120.0, 84.0, 85.0, 123.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 148.0, 145.0, 146.0, 146.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 63.0, 61.0, 62.0, 63.0, 62.0, 61.0, 62.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0, 67.0, 67.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 82.0, 85.0, 79.0, 79.0, 87.0, 84.0, 82.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 128.0, 131.0, 135.0, 136.0, 128.0, 127.0, 126.0, 165.0, 133.0], "value": 133.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 167.0, 163.0, 168.0, 169.0, 163.0, 162.0, 164.0, 161.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 65.0, 67.0, 66.0, 65.0, 66.0, 65.0, 64.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1522.4999999999998, "alertThreshold": 2.0}]} 09:01:58 INFO - Return code: 0 09:01:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:01:58 INFO - # TBPL SUCCESS # 09:01:58 INFO - Running post-action listener: _resource_record_post_action 09:01:58 INFO - ##### 09:01:58 INFO - ##### Finished run-tests step (success) 09:01:58 INFO - ##### 09:01:58 INFO - Running post-run listener: _resource_record_post_run 09:01:58 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 555298816; Read time: 20; Write time: 902568 09:01:58 INFO - TinderboxPrint: CPU usage
13.0% 09:01:58 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 09:01:58 INFO - TinderboxPrint: I/O write bytes / time
555,298,816 / 902,568 09:01:58 INFO - TinderboxPrint: CPU idle
8,367.0 (87.0%) 09:01:58 INFO - TinderboxPrint: CPU user
1,202.3 (12.5%) 09:01:58 INFO - TinderboxPrint: Swap in / out
0 / 0 09:01:58 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 258916352; Read time: 0; Write time: 495720 09:01:58 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296382464; Read time: 20; Write time: 406848 09:01:58 INFO - Running post-run listener: _upload_blobber_files 09:01:58 INFO - Blob upload gear active. 09:01:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:01:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:01:58 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:01:58 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:01:59 INFO - (blobuploader) - INFO - Open directory for files ... 09:01:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:01:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:02:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:00 INFO - (blobuploader) - INFO - Done attempting. 09:02:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:02:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:02:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:00 INFO - (blobuploader) - INFO - Done attempting. 09:02:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:02:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:02:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:11 INFO - (blobuploader) - INFO - Done attempting. 09:02:11 INFO - (blobuploader) - INFO - Iteration through files over. 09:02:11 INFO - Return code: 0 09:02:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:02:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:02:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b271c9ecafa0e3cd8f7a1c2601e55d02267b0180ed8d2014d2c470d24565064a7f375789a1cccb156cd15b67dde5e4378f2a23582bfd89981cf4ccd32fad170b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/be76e42fc157aa06b7387259babd3eb8bdd3d242247ce3fdfc87a5abd337ee5d2b5562292aed31ca4153f1198b6f137bf7e08d904901c5b743987bee980d88c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ca97cced76a9b10a41a721492f65521bae0e55aac3be26ebbcca922c53633bd3b66cc921e0ec936d72f121533b64eaf7635f0d12c31031ea00b91f7e5d2c1d75"} 09:02:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:02:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:02:11 INFO - Contents: 09:02:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b271c9ecafa0e3cd8f7a1c2601e55d02267b0180ed8d2014d2c470d24565064a7f375789a1cccb156cd15b67dde5e4378f2a23582bfd89981cf4ccd32fad170b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/be76e42fc157aa06b7387259babd3eb8bdd3d242247ce3fdfc87a5abd337ee5d2b5562292aed31ca4153f1198b6f137bf7e08d904901c5b743987bee980d88c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ca97cced76a9b10a41a721492f65521bae0e55aac3be26ebbcca922c53633bd3b66cc921e0ec936d72f121533b64eaf7635f0d12c31031ea00b91f7e5d2c1d75"} 09:02:11 INFO - Running post-run listener: copy_logs_to_upload_dir 09:02:11 INFO - Copying logs to upload dir... 09:02:11 INFO - mkdir: /builds/slave/test/build/upload/logs 09:02:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.404672 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-18 09:02:11.138827) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 09:02:11.142303) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091 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-Non-PGO/sha512/b271c9ecafa0e3cd8f7a1c2601e55d02267b0180ed8d2014d2c470d24565064a7f375789a1cccb156cd15b67dde5e4378f2a23582bfd89981cf4ccd32fad170b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/be76e42fc157aa06b7387259babd3eb8bdd3d242247ce3fdfc87a5abd337ee5d2b5562292aed31ca4153f1198b6f137bf7e08d904901c5b743987bee980d88c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ca97cced76a9b10a41a721492f65521bae0e55aac3be26ebbcca922c53633bd3b66cc921e0ec936d72f121533b64eaf7635f0d12c31031ea00b91f7e5d2c1d75"} build_url:https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011316 build_url: 'https://queue.taskcluster.net/v1/task/QbqGuDgsQAunnCVQHgzRgA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b271c9ecafa0e3cd8f7a1c2601e55d02267b0180ed8d2014d2c470d24565064a7f375789a1cccb156cd15b67dde5e4378f2a23582bfd89981cf4ccd32fad170b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/be76e42fc157aa06b7387259babd3eb8bdd3d242247ce3fdfc87a5abd337ee5d2b5562292aed31ca4153f1198b6f137bf7e08d904901c5b743987bee980d88c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ca97cced76a9b10a41a721492f65521bae0e55aac3be26ebbcca922c53633bd3b66cc921e0ec936d72f121533b64eaf7635f0d12c31031ea00b91f7e5d2c1d75"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 09:02:11.167297) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 09:02:11.167614) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468856354.247221-1381538091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004509 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 09:02:11.249607) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 09:02:11.249928) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 09:02:11.250200) ========= ========= Total master_lag: 0.18 =========