builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-032 starttime: 1471418484.89 results: success (0) buildid: 20160816222215 builduid: 7115bec0a27d40b5823a93ba3cbff05c revision: 5b442b4e42c3a3a5a8b29746e4639eec4707c913 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:24.888557) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:24.888945) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:24.889225) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:24.907932) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:24.908190) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471417034.628219-1218225099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-17 00:21:25-- 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% 32.9M=0s 2016-08-17 00:21:25 (32.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083181 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:25.019163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:25.019473) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471417034.628219-1218225099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017549 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:25.083110) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:25.083474) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 5b442b4e42c3a3a5a8b29746e4639eec4707c913 --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 5b442b4e42c3a3a5a8b29746e4639eec4707c913 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471417034.628219-1218225099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-17 00:21:25,569 truncating revision to first 12 chars 2016-08-17 00:21:25,569 Setting DEBUG logging. 2016-08-17 00:21:25,569 attempt 1/10 2016-08-17 00:21:25,569 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/5b442b4e42c3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-17 00:21:25,876 unpacking tar archive at: autoland-5b442b4e42c3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.650436 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:25.749273) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:25.749673) ========= script_repo_revision: 5b442b4e42c3a3a5a8b29746e4639eec4707c913 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:25.750150) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:25.750447) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 00:21:25.759991) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-08-17 00:21:25.760429) ========= /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=745a4373a2a16df560f216e000000264-1471417034.628219-1218225099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:21:26 INFO - MultiFileLogger online at 20160817 00:21:26 in /builds/slave/test 00:21:26 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 00:21:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:21:26 INFO - {'append_to_log': False, 00:21:26 INFO - 'base_work_dir': '/builds/slave/test', 00:21:26 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 00:21:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:21:26 INFO - 'branch': 'Autoland-Non-PGO', 00:21:26 INFO - 'buildbot_json_path': 'buildprops.json', 00:21:26 INFO - 'config_files': ('talos/linux_config.py',), 00:21:26 INFO - 'default_actions': ('clobber', 00:21:26 INFO - 'read-buildbot-config', 00:21:26 INFO - 'download-and-extract', 00:21:26 INFO - 'populate-webroot', 00:21:26 INFO - 'create-virtualenv', 00:21:26 INFO - 'install', 00:21:26 INFO - 'run-tests'), 00:21:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:21:26 INFO - 'download_minidump_stackwalk': True, 00:21:26 INFO - 'download_symbols': 'ondemand', 00:21:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:21:26 INFO - 'tooltool.py': '/tools/tooltool.py', 00:21:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:21:26 INFO - '/tools/misc-python/virtualenv.py')}, 00:21:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:21:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:21:26 INFO - 'installer_path': 'installer.exe', 00:21:26 INFO - 'log_level': 'info', 00:21:26 INFO - 'log_name': 'talos', 00:21:26 INFO - 'log_to_console': True, 00:21:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:21:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:21:26 INFO - 'opt_config_files': (), 00:21:26 INFO - 'pip_index': False, 00:21:26 INFO - 'sps_profile': False, 00:21:26 INFO - 'sps_profile_interval': 0, 00:21:26 INFO - 'suite': 'dromaeojs', 00:21:26 INFO - 'system_bits': '32', 00:21:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:21:26 INFO - 'title': 'talos-linux64-ix-032', 00:21:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:21:26 INFO - 'use_talos_json': True, 00:21:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:21:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:21:26 INFO - 'work_dir': 'build'} 00:21:26 INFO - [mozharness: 2016-08-17 07:21:26.341897Z] Running clobber step. 00:21:26 INFO - Running pre-action listener: _resource_record_pre_action 00:21:26 INFO - Running main action method: clobber 00:21:26 INFO - rmtree: /builds/slave/test/build 00:21:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:21:27 INFO - Running post-action listener: _resource_record_post_action 00:21:27 INFO - [mozharness: 2016-08-17 07:21:27.310259Z] Finished clobber step (success) 00:21:27 INFO - [mozharness: 2016-08-17 07:21:27.310360Z] Running read-buildbot-config step. 00:21:27 INFO - Running pre-action listener: _resource_record_pre_action 00:21:27 INFO - Running main action method: read_buildbot_config 00:21:27 INFO - Using buildbot properties: 00:21:27 INFO - { 00:21:27 INFO - "project": "", 00:21:27 INFO - "product": "firefox", 00:21:27 INFO - "script_repo_revision": "production", 00:21:27 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 00:21:27 INFO - "repository": "", 00:21:27 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 00:21:27 INFO - "buildid": "20160816222215", 00:21:27 INFO - "pgo_build": "False", 00:21:27 INFO - "basedir": "/builds/slave/test", 00:21:27 INFO - "buildnumber": 258, 00:21:27 INFO - "slavename": "talos-linux64-ix-032", 00:21:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 00:21:27 INFO - "platform": "ubuntu64_hw", 00:21:27 INFO - "scheduler": "tests-autoland-linux64-talos-7-3600", 00:21:27 INFO - "branch": "autoland", 00:21:27 INFO - "repo_path": "integration/autoland", 00:21:27 INFO - "revision": "5b442b4e42c3a3a5a8b29746e4639eec4707c913", 00:21:27 INFO - "stage_platform": "linux64", 00:21:27 INFO - "builduid": "7115bec0a27d40b5823a93ba3cbff05c", 00:21:27 INFO - "slavebuilddir": "test" 00:21:27 INFO - } 00:21:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 00:21:27 INFO - Running post-action listener: _resource_record_post_action 00:21:27 INFO - [mozharness: 2016-08-17 07:21:27.312803Z] Finished read-buildbot-config step (success) 00:21:27 INFO - [mozharness: 2016-08-17 07:21:27.312893Z] Running download-and-extract step. 00:21:27 INFO - Running pre-action listener: _resource_record_pre_action 00:21:27 INFO - Running main action method: download_and_extract 00:21:27 INFO - mkdir: /builds/slave/test/build/tests 00:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:27 INFO - trying https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 00:21:27 INFO - Downloading https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 00:21:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 00:21:27 INFO - Downloaded 1361 bytes. 00:21:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 00:21:27 INFO - Using the following test package requirements: 00:21:27 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 00:21:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 00:21:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 00:21:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:21:27 INFO - u'jsshell-linux-x86_64.zip'], 00:21:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 00:21:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 00:21:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 00:21:27 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 00:21:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 00:21:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 00:21:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:27 INFO - trying https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 00:21:27 INFO - Downloading https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 00:21:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:21:28 INFO - Downloaded 20315699 bytes. 00:21:28 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 00:21:33 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 00:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:33 INFO - trying https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 00:21:33 INFO - Downloading https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 00:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:21:33 INFO - Downloaded 20315699 bytes. 00:21:33 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 00:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:38 INFO - trying https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 00:21:38 INFO - Downloading https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 00:21:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 00:21:39 INFO - Downloaded 14413719 bytes. 00:21:39 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 00:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:39 INFO - trying https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 00:21:39 INFO - Downloading https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 00:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 00:21:40 INFO - Downloaded 55771615 bytes. 00:21:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 00:21:40 INFO - mkdir: /builds/slave/test/properties 00:21:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:21:40 INFO - Writing to file /builds/slave/test/properties/build_url 00:21:40 INFO - Contents: 00:21:40 INFO - build_url:https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 00:21:41 INFO - Running post-action listener: _resource_record_post_action 00:21:41 INFO - Running post-action listener: set_extra_try_arguments 00:21:41 INFO - [mozharness: 2016-08-17 07:21:41.330922Z] Finished download-and-extract step (success) 00:21:41 INFO - [mozharness: 2016-08-17 07:21:41.331147Z] Running populate-webroot step. 00:21:41 INFO - Running pre-action listener: _resource_record_pre_action 00:21:41 INFO - Running main action method: populate_webroot 00:21:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:21:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:21:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:21:41 INFO - '--tppagecycles', 00:21:41 INFO - '7'], 00:21:41 INFO - 'tests': ['tsvgm']}}, 00:21:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:21:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:21:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:21:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:21:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:21:41 INFO - 'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:21:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:21:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:21:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:21:41 INFO - 'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['damp', 'tps']}, 00:21:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:21:41 INFO - 'tests': ['damp', 'tps']}, 00:21:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['dromaeo_dom']}, 00:21:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:21:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['basic_compositor_video']}, 00:21:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:21:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['a11yr', 00:21:41 INFO - 'ts_paint', 00:21:41 INFO - 'tpaint', 00:21:41 INFO - 'sessionrestore', 00:21:41 INFO - 'sessionrestore_no_auto_restore', 00:21:41 INFO - 'tabpaint']}, 00:21:41 INFO - 'other-e10s': {'tests': ['a11yr', 00:21:41 INFO - 'ts_paint', 00:21:41 INFO - 'tpaint', 00:21:41 INFO - 'sessionrestore', 00:21:41 INFO - 'sessionrestore_no_auto_restore', 00:21:41 INFO - 'tabpaint']}, 00:21:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:21:41 INFO - 'ts_paint', 00:21:41 INFO - 'tpaint', 00:21:41 INFO - 'sessionrestore', 00:21:41 INFO - 'sessionrestore_no_auto_restore', 00:21:41 INFO - 'tabpaint']}, 00:21:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:21:41 INFO - 'ts_paint', 00:21:41 INFO - 'tpaint', 00:21:41 INFO - 'sessionrestore', 00:21:41 INFO - 'sessionrestore_no_auto_restore', 00:21:41 INFO - 'tabpaint']}, 00:21:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['a11yr', 00:21:41 INFO - 'ts_paint', 00:21:41 INFO - 'tpaint', 00:21:41 INFO - 'sessionrestore', 00:21:41 INFO - 'sessionrestore_no_auto_restore', 00:21:41 INFO - 'tabpaint']}, 00:21:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['a11yr', 00:21:41 INFO - 'ts_paint', 00:21:41 INFO - 'tpaint', 00:21:41 INFO - 'sessionrestore', 00:21:41 INFO - 'sessionrestore_no_auto_restore', 00:21:41 INFO - 'tabpaint']}, 00:21:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['tsvgx', 00:21:41 INFO - 'tsvgr_opacity', 00:21:41 INFO - 'tart', 00:21:41 INFO - 'tscrollx', 00:21:41 INFO - 'cart']}, 00:21:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:21:41 INFO - 'tsvgr_opacity', 00:21:41 INFO - 'tart', 00:21:41 INFO - 'tscrollx', 00:21:41 INFO - 'cart']}, 00:21:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:21:41 INFO - 'talos_options': ['--disable-e10s'], 00:21:41 INFO - 'tests': ['tp5o']}, 00:21:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:21:41 INFO - 'tests': ['tp5o']}, 00:21:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:21:41 INFO - 'talos_options': ['--disable-e10s', 00:21:41 INFO - '--xperf_path', 00:21:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:21:41 INFO - 'tests': ['tp5n']}, 00:21:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:21:41 INFO - 'talos_options': ['--xperf_path', 00:21:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:21:41 INFO - 'tests': ['tp5n']}}, 00:21:41 INFO - 'talos.zip': {'path': '', 00:21:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:21:41 INFO - Running post-action listener: _resource_record_post_action 00:21:41 INFO - [mozharness: 2016-08-17 07:21:41.350700Z] Finished populate-webroot step (success) 00:21:41 INFO - [mozharness: 2016-08-17 07:21:41.350788Z] Running create-virtualenv step. 00:21:41 INFO - Running pre-action listener: _resource_record_pre_action 00:21:41 INFO - Running main action method: create_virtualenv 00:21:41 INFO - Creating virtualenv /builds/slave/test/build/venv 00:21:41 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 00:21:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:21:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:21:41 INFO - Using real prefix '/usr' 00:21:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:21:41 INFO - Installing distribute.............................................................................................................................................................................................done. 00:21:43 INFO - Installing pip.................done. 00:21:43 INFO - Return code: 0 00:21:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:43 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 0x7fd169e0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', '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 00:21:43 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 00:21:43 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 00:21:43 INFO - Using env: {'DISPLAY': ':0', 00:21:43 INFO - 'HOME': '/home/cltbld', 00:21:43 INFO - 'LANG': 'en_US.UTF-8', 00:21:43 INFO - 'LANGUAGE': 'en_US:en', 00:21:43 INFO - 'LOGNAME': 'cltbld', 00:21:43 INFO - 'MAIL': '/var/mail/cltbld', 00:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:21:43 INFO - 'MOZ_NO_REMOTE': '1', 00:21:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:21:43 INFO - 'NO_EM_RESTART': '1', 00:21:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:21:43 INFO - 'PWD': '/builds/slave/test', 00:21:43 INFO - 'SHELL': '/bin/bash', 00:21:43 INFO - 'SHLVL': '1', 00:21:43 INFO - 'TERM': 'linux', 00:21:43 INFO - 'TMOUT': '86400', 00:21:43 INFO - 'USER': 'cltbld', 00:21:43 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:21:43 INFO - '_': '/tools/buildbot/bin/python'} 00:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:21:44 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 00:21:44 INFO - Cleaning up... 00:21:44 INFO - Return code: 0 00:21:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:44 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 0x7fd169e0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', '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 00:21:44 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 00:21:44 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 00:21:44 INFO - Using env: {'DISPLAY': ':0', 00:21:44 INFO - 'HOME': '/home/cltbld', 00:21:44 INFO - 'LANG': 'en_US.UTF-8', 00:21:44 INFO - 'LANGUAGE': 'en_US:en', 00:21:44 INFO - 'LOGNAME': 'cltbld', 00:21:44 INFO - 'MAIL': '/var/mail/cltbld', 00:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:21:44 INFO - 'MOZ_NO_REMOTE': '1', 00:21:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:21:44 INFO - 'NO_EM_RESTART': '1', 00:21:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:21:44 INFO - 'PWD': '/builds/slave/test', 00:21:44 INFO - 'SHELL': '/bin/bash', 00:21:44 INFO - 'SHLVL': '1', 00:21:44 INFO - 'TERM': 'linux', 00:21:44 INFO - 'TMOUT': '86400', 00:21:44 INFO - 'USER': 'cltbld', 00:21:44 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:21:44 INFO - '_': '/tools/buildbot/bin/python'} 00:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:21:44 INFO - Downloading/unpacking psutil>=3.1.1 00:21: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 00:21: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 00:21: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 00:21: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 00:21: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 00:21: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 00:21:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:21:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:21:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:21:46 INFO - Installing collected packages: psutil 00:21:46 INFO - Running setup.py install for psutil 00:21:46 INFO - building 'psutil._psutil_linux' extension 00:21:46 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 00:21:46 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 00:21:46 INFO - building 'psutil._psutil_posix' extension 00:21:46 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 00:21:46 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 00:21:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:21:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:21:46 INFO - Successfully installed psutil 00:21:46 INFO - Cleaning up... 00:21:46 INFO - Return code: 0 00:21:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:21:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:21:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:46 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 0x7fd169e0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', '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 00:21:46 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 00:21:46 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 00:21:46 INFO - Using env: {'DISPLAY': ':0', 00:21:46 INFO - 'HOME': '/home/cltbld', 00:21:46 INFO - 'LANG': 'en_US.UTF-8', 00:21:46 INFO - 'LANGUAGE': 'en_US:en', 00:21:46 INFO - 'LOGNAME': 'cltbld', 00:21:46 INFO - 'MAIL': '/var/mail/cltbld', 00:21:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:21:46 INFO - 'MOZ_NO_REMOTE': '1', 00:21:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:21:46 INFO - 'NO_EM_RESTART': '1', 00:21:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:21:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:21:46 INFO - 'PWD': '/builds/slave/test', 00:21:46 INFO - 'SHELL': '/bin/bash', 00:21:46 INFO - 'SHLVL': '1', 00:21:46 INFO - 'TERM': 'linux', 00:21:46 INFO - 'TMOUT': '86400', 00:21:46 INFO - 'USER': 'cltbld', 00:21:46 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:21:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:21:46 INFO - '_': '/tools/buildbot/bin/python'} 00:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:21:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:21:47 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 00:21:47 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 00:21:47 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 00:21:47 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 00:21:47 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 00:21:47 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 00:21:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:21:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:21:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:21:49 INFO - Installing collected packages: mozsystemmonitor 00:21:49 INFO - Running setup.py install for mozsystemmonitor 00:21:49 INFO - Successfully installed mozsystemmonitor 00:21:49 INFO - Cleaning up... 00:21:49 INFO - Return code: 0 00:21:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:49 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 0x7fd169e0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', '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 00:21:49 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 00:21:49 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 00:21:49 INFO - Using env: {'DISPLAY': ':0', 00:21:49 INFO - 'HOME': '/home/cltbld', 00:21:49 INFO - 'LANG': 'en_US.UTF-8', 00:21:49 INFO - 'LANGUAGE': 'en_US:en', 00:21:49 INFO - 'LOGNAME': 'cltbld', 00:21:49 INFO - 'MAIL': '/var/mail/cltbld', 00:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:21:49 INFO - 'MOZ_NO_REMOTE': '1', 00:21:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:21:49 INFO - 'NO_EM_RESTART': '1', 00:21:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:21:49 INFO - 'PWD': '/builds/slave/test', 00:21:49 INFO - 'SHELL': '/bin/bash', 00:21:49 INFO - 'SHLVL': '1', 00:21:49 INFO - 'TERM': 'linux', 00:21:49 INFO - 'TMOUT': '86400', 00:21:49 INFO - 'USER': 'cltbld', 00:21:49 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:21:49 INFO - '_': '/tools/buildbot/bin/python'} 00:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:21:49 INFO - Downloading/unpacking jsonschema==2.5.1 00:21:49 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 00:21:49 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 00:21:49 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 00:21:49 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 00:21:49 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 00:21:49 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 00:21:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:21:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:21:51 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 00:21:51 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 00:21:51 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 00:21:51 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 00:21:51 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 00:21:51 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 00:21:51 INFO - Downloading functools32-3.2.3-2.tar.gz 00:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:21:51 INFO - warning: no files found matching '*.txt' 00:21:51 INFO - no previously-included directories found matching 'build' 00:21:51 INFO - no previously-included directories found matching 'dist' 00:21:51 INFO - no previously-included directories found matching '.git*' 00:21:51 INFO - Installing collected packages: jsonschema, functools32 00:21:51 INFO - Running setup.py install for functools32 00:21:51 INFO - warning: no files found matching '*.txt' 00:21:51 INFO - no previously-included directories found matching 'build' 00:21:51 INFO - no previously-included directories found matching 'dist' 00:21:51 INFO - no previously-included directories found matching '.git*' 00:21:51 INFO - Successfully installed jsonschema functools32 00:21:51 INFO - Cleaning up... 00:21:51 INFO - Return code: 0 00:21:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:21:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:21:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd169e0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', '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 00:21:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 00:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 00:21:51 INFO - Using env: {'DISPLAY': ':0', 00:21:51 INFO - 'HOME': '/home/cltbld', 00:21:51 INFO - 'LANG': 'en_US.UTF-8', 00:21:51 INFO - 'LANGUAGE': 'en_US:en', 00:21:51 INFO - 'LOGNAME': 'cltbld', 00:21:51 INFO - 'MAIL': '/var/mail/cltbld', 00:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:21:51 INFO - 'MOZ_NO_REMOTE': '1', 00:21:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:21:51 INFO - 'NO_EM_RESTART': '1', 00:21:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:21:51 INFO - 'PWD': '/builds/slave/test', 00:21:51 INFO - 'SHELL': '/bin/bash', 00:21:51 INFO - 'SHLVL': '1', 00:21:51 INFO - 'TERM': 'linux', 00:21:51 INFO - 'TMOUT': '86400', 00:21:51 INFO - 'USER': 'cltbld', 00:21:51 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:21:51 INFO - '_': '/tools/buildbot/bin/python'} 00:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:21:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:21:52 INFO - Cleaning up... 00:21:52 INFO - Return code: 0 00:21:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:21:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:21:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21: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', '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 0x7fd169e0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', '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 00:21: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:21: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 blobuploader==1.2.4 00:21:52 INFO - Using env: {'DISPLAY': ':0', 00:21:52 INFO - 'HOME': '/home/cltbld', 00:21:52 INFO - 'LANG': 'en_US.UTF-8', 00:21:52 INFO - 'LANGUAGE': 'en_US:en', 00:21:52 INFO - 'LOGNAME': 'cltbld', 00:21:52 INFO - 'MAIL': '/var/mail/cltbld', 00:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:21:52 INFO - 'MOZ_NO_REMOTE': '1', 00:21:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:21:52 INFO - 'NO_EM_RESTART': '1', 00:21:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:21:52 INFO - 'PWD': '/builds/slave/test', 00:21:52 INFO - 'SHELL': '/bin/bash', 00:21:52 INFO - 'SHLVL': '1', 00:21:52 INFO - 'TERM': 'linux', 00:21:52 INFO - 'TMOUT': '86400', 00:21:52 INFO - 'USER': 'cltbld', 00:21:52 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:21:52 INFO - '_': '/tools/buildbot/bin/python'} 00:21:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:21:52 INFO - Downloading/unpacking blobuploader==1.2.4 00:21:52 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 00:21:52 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 00:21:52 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 00:21:52 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 00:21:52 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 00:21:52 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 00:21:54 INFO - Downloading blobuploader-1.2.4.tar.gz 00:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:21:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:21:54 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 00:21:54 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 00:21:54 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 00:21:54 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 00:21:54 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 00:21:54 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 00:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:21:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:21:54 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 00:21:54 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 00:21:54 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 00:21:54 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 00:21:54 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 00:21:54 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 00:21:54 INFO - Downloading docopt-0.6.1.tar.gz 00:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:21:54 INFO - Installing collected packages: blobuploader, requests, docopt 00:21:54 INFO - Running setup.py install for blobuploader 00:21:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:21:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:21:54 INFO - Running setup.py install for requests 00:21:55 INFO - Running setup.py install for docopt 00:21:55 INFO - Successfully installed blobuploader requests docopt 00:21:55 INFO - Cleaning up... 00:21:55 INFO - Return code: 0 00:21:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:55 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 0x7fd169e0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', '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 00:21:55 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 00:21:55 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 00:21:55 INFO - Using env: {'DISPLAY': ':0', 00:21:55 INFO - 'HOME': '/home/cltbld', 00:21:55 INFO - 'LANG': 'en_US.UTF-8', 00:21:55 INFO - 'LANGUAGE': 'en_US:en', 00:21:55 INFO - 'LOGNAME': 'cltbld', 00:21:55 INFO - 'MAIL': '/var/mail/cltbld', 00:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:21:55 INFO - 'MOZ_NO_REMOTE': '1', 00:21:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:21:55 INFO - 'NO_EM_RESTART': '1', 00:21:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:21:55 INFO - 'PWD': '/builds/slave/test', 00:21:55 INFO - 'SHELL': '/bin/bash', 00:21:55 INFO - 'SHLVL': '1', 00:21:55 INFO - 'TERM': 'linux', 00:21:55 INFO - 'TMOUT': '86400', 00:21:55 INFO - 'USER': 'cltbld', 00:21:55 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:21:55 INFO - '_': '/tools/buildbot/bin/python'} 00:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:21:55 INFO - Running setup.py (path:/tmp/pip-X51ypW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:21:55 INFO - Running setup.py (path:/tmp/pip-_Ravtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:21:55 INFO - Running setup.py (path:/tmp/pip-v2qFyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:21:55 INFO - Running setup.py (path:/tmp/pip-Dm09fB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:21:56 INFO - Running setup.py (path:/tmp/pip-S8QBAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:21:56 INFO - Running setup.py (path:/tmp/pip-Qlmtqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:21:56 INFO - Running setup.py (path:/tmp/pip-GqPi2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:21:56 INFO - Running setup.py (path:/tmp/pip-EJH0Td-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:21:56 INFO - Running setup.py (path:/tmp/pip-MghoU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:21:56 INFO - Running setup.py (path:/tmp/pip-4D9cRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:21:56 INFO - Running setup.py (path:/tmp/pip-wdRYAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:21:56 INFO - Running setup.py (path:/tmp/pip-5T_OyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:21:56 INFO - Running setup.py (path:/tmp/pip-zrd2Zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:21:56 INFO - Running setup.py (path:/tmp/pip-UxwmM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:21:56 INFO - Running setup.py (path:/tmp/pip-1LMMUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:21:56 INFO - Running setup.py (path:/tmp/pip-mGBjH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:21:56 INFO - Running setup.py (path:/tmp/pip-7e75LX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:21:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:21:56 INFO - Running setup.py install for manifestparser 00:21:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:21:57 INFO - Running setup.py install for mozcrash 00:21:57 INFO - Running setup.py install for mozdebug 00:21:57 INFO - Running setup.py install for mozdevice 00:21:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:21:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:21:57 INFO - Running setup.py install for mozfile 00:21:57 INFO - Running setup.py install for mozhttpd 00:21:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:21:57 INFO - Running setup.py install for mozinfo 00:21:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:21:57 INFO - Running setup.py install for mozInstall 00:21:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:21:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:21:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:21:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:21:57 INFO - Running setup.py install for mozleak 00:21:57 INFO - Running setup.py install for mozlog 00:21:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:21:58 INFO - Running setup.py install for moznetwork 00:21:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:21:58 INFO - Running setup.py install for mozprocess 00:21:58 INFO - Running setup.py install for mozprofile 00:21:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:21:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:21:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:21:58 INFO - Running setup.py install for mozrunner 00:21:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:21:58 INFO - Running setup.py install for mozscreenshot 00:21:58 INFO - Running setup.py install for moztest 00:21:58 INFO - Running setup.py install for mozversion 00:21:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:21:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:21:58 INFO - Cleaning up... 00:21:58 INFO - Return code: 0 00:21:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:21:58 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 0x7fd169e0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', '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 00:21:58 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 00:21:58 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 00:21:58 INFO - Using env: {'DISPLAY': ':0', 00:21:58 INFO - 'HOME': '/home/cltbld', 00:21:58 INFO - 'LANG': 'en_US.UTF-8', 00:21:58 INFO - 'LANGUAGE': 'en_US:en', 00:21:58 INFO - 'LOGNAME': 'cltbld', 00:21:58 INFO - 'MAIL': '/var/mail/cltbld', 00:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:21:58 INFO - 'MOZ_NO_REMOTE': '1', 00:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:21:58 INFO - 'NO_EM_RESTART': '1', 00:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:21:58 INFO - 'PWD': '/builds/slave/test', 00:21:58 INFO - 'SHELL': '/bin/bash', 00:21:58 INFO - 'SHLVL': '1', 00:21:58 INFO - 'TERM': 'linux', 00:21:58 INFO - 'TMOUT': '86400', 00:21:58 INFO - 'USER': 'cltbld', 00:21:58 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:21:58 INFO - '_': '/tools/buildbot/bin/python'} 00:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:21:58 INFO - Running setup.py (path:/tmp/pip-xfpfjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:21:59 INFO - Running setup.py (path:/tmp/pip-uWh0lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:21:59 INFO - Running setup.py (path:/tmp/pip-tKgN39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:21:59 INFO - Running setup.py (path:/tmp/pip-JsUelj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:21:59 INFO - Running setup.py (path:/tmp/pip-H7anVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:21:59 INFO - Running setup.py (path:/tmp/pip-ztn06N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:21:59 INFO - Running setup.py (path:/tmp/pip-GLmArj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:21:59 INFO - Running setup.py (path:/tmp/pip-e_98EO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:21:59 INFO - Running setup.py (path:/tmp/pip-cbKaBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:21:59 INFO - Running setup.py (path:/tmp/pip-LBJyuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:21:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:21:59 INFO - Running setup.py (path:/tmp/pip-0XfXIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:21:59 INFO - Running setup.py (path:/tmp/pip-NDYwS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:21:59 INFO - Running setup.py (path:/tmp/pip-LGvY11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:21:59 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)) 00:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:21:59 INFO - Running setup.py (path:/tmp/pip-3NeN8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:22:00 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)) 00:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:22:00 INFO - Running setup.py (path:/tmp/pip-HSZ_ZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:22:00 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)) 00:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:22:00 INFO - Running setup.py (path:/tmp/pip-dNEJEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:22:00 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)) 00:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:22:00 INFO - Running setup.py (path:/tmp/pip-7w6R_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:22:00 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)) 00:22:00 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)) 00:22:00 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)) 00:22:00 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)) 00:22:00 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)) 00:22:00 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)) 00:22:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:22:00 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 00:22:00 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 00:22:00 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 00:22:00 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 00:22:00 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 00:22:00 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 00:22:02 INFO - Downloading blessings-1.6.tar.gz 00:22:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:22:02 INFO - Installing collected packages: blessings 00:22:02 INFO - Running setup.py install for blessings 00:22:02 INFO - Successfully installed blessings 00:22:02 INFO - Cleaning up... 00:22:02 INFO - Return code: 0 00:22:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:22:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:22:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:22:02 INFO - Reading from file tmpfile_stdout 00:22:02 INFO - Current package versions: 00:22:02 INFO - argparse == 1.2.1 00:22:02 INFO - blessings == 1.6 00:22:02 INFO - blobuploader == 1.2.4 00:22:02 INFO - docopt == 0.6.1 00:22:02 INFO - functools32 == 3.2.3-2 00:22:02 INFO - jsonschema == 2.5.1 00:22:02 INFO - manifestparser == 1.1 00:22:02 INFO - mozInstall == 1.12 00:22:02 INFO - mozcrash == 0.17 00:22:02 INFO - mozdebug == 0.1 00:22:02 INFO - mozdevice == 0.48 00:22:02 INFO - mozfile == 1.2 00:22:02 INFO - mozhttpd == 0.7 00:22:02 INFO - mozinfo == 0.9 00:22:02 INFO - mozleak == 0.1 00:22:02 INFO - mozlog == 3.3 00:22:02 INFO - moznetwork == 0.27 00:22:02 INFO - mozprocess == 0.23 00:22:02 INFO - mozprofile == 0.28 00:22:02 INFO - mozrunner == 6.12 00:22:02 INFO - mozscreenshot == 0.1 00:22:02 INFO - mozsystemmonitor == 0.3 00:22:02 INFO - moztest == 0.7 00:22:02 INFO - mozversion == 1.4 00:22:02 INFO - psutil == 3.1.1 00:22:02 INFO - requests == 1.2.3 00:22:02 INFO - wsgiref == 0.1.2 00:22:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:02 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 0x7fd169e0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', '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 00:22:02 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 00:22:02 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 00:22:02 INFO - Using env: {'DISPLAY': ':0', 00:22:02 INFO - 'HOME': '/home/cltbld', 00:22:02 INFO - 'LANG': 'en_US.UTF-8', 00:22:02 INFO - 'LANGUAGE': 'en_US:en', 00:22:02 INFO - 'LOGNAME': 'cltbld', 00:22:02 INFO - 'MAIL': '/var/mail/cltbld', 00:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:02 INFO - 'MOZ_NO_REMOTE': '1', 00:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:02 INFO - 'NO_EM_RESTART': '1', 00:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:02 INFO - 'PWD': '/builds/slave/test', 00:22:02 INFO - 'SHELL': '/bin/bash', 00:22:02 INFO - 'SHLVL': '1', 00:22:02 INFO - 'TERM': 'linux', 00:22:02 INFO - 'TMOUT': '86400', 00:22:02 INFO - 'USER': 'cltbld', 00:22:02 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:02 INFO - '_': '/tools/buildbot/bin/python'} 00:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:02 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)) 00:22:02 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)) 00:22:02 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)) 00:22:02 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)) 00:22:02 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)) 00:22:02 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)) 00:22:02 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)) 00:22:02 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)) 00:22:02 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)) 00:22:02 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)) 00:22:02 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)) 00:22:02 INFO - Cleaning up... 00:22:02 INFO - Return code: 0 00:22:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:02 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 0x7fd169e0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', '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 00:22:02 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 00:22:02 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 00:22:02 INFO - Using env: {'DISPLAY': ':0', 00:22:02 INFO - 'HOME': '/home/cltbld', 00:22:02 INFO - 'LANG': 'en_US.UTF-8', 00:22:02 INFO - 'LANGUAGE': 'en_US:en', 00:22:02 INFO - 'LOGNAME': 'cltbld', 00:22:02 INFO - 'MAIL': '/var/mail/cltbld', 00:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:02 INFO - 'MOZ_NO_REMOTE': '1', 00:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:02 INFO - 'NO_EM_RESTART': '1', 00:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:02 INFO - 'PWD': '/builds/slave/test', 00:22:02 INFO - 'SHELL': '/bin/bash', 00:22:02 INFO - 'SHLVL': '1', 00:22:02 INFO - 'TERM': 'linux', 00:22:02 INFO - 'TMOUT': '86400', 00:22:02 INFO - 'USER': 'cltbld', 00:22:02 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:02 INFO - '_': '/tools/buildbot/bin/python'} 00:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:22:03 INFO - Cleaning up... 00:22:03 INFO - Return code: 0 00:22:03 INFO - Running post-action listener: _resource_record_post_action 00:22:03 INFO - Running post-action listener: _start_resource_monitoring 00:22:03 INFO - Starting resource monitoring. 00:22:03 INFO - [mozharness: 2016-08-17 07:22:03.065549Z] Finished create-virtualenv step (success) 00:22:03 INFO - [mozharness: 2016-08-17 07:22:03.066067Z] Running install step. 00:22:03 INFO - Running pre-action listener: _resource_record_pre_action 00:22:03 INFO - Running main action method: install 00:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:22:03 INFO - Reading from file tmpfile_stdout 00:22:03 INFO - Detecting whether we're running mozinstall >=1.0... 00:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:22:03 INFO - Reading from file tmpfile_stdout 00:22:03 INFO - Output received: 00:22:03 INFO - Usage: mozinstall [options] installer 00:22:03 INFO - Options: 00:22:03 INFO - -h, --help show this help message and exit 00:22:03 INFO - -d DEST, --destination=DEST 00:22:03 INFO - Directory to install application into. [default: 00:22:03 INFO - "/builds/slave/test"] 00:22:03 INFO - --app=APP Application being installed. [default: firefox] 00:22:03 INFO - mkdir: /builds/slave/test/build/application 00:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 00:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 00:22:19 INFO - Reading from file tmpfile_stdout 00:22:19 INFO - Output received: 00:22:19 INFO - /builds/slave/test/build/application/firefox/firefox 00:22:19 INFO - Running post-action listener: _resource_record_post_action 00:22:19 INFO - [mozharness: 2016-08-17 07:22:19.463290Z] Finished install step (success) 00:22:19 INFO - [mozharness: 2016-08-17 07:22:19.463538Z] Running run-tests step. 00:22:19 INFO - Running pre-action listener: _resource_record_pre_action 00:22:19 INFO - Running main action method: run_tests 00:22:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:22:19 INFO - Python 2.7.3 00:22:19 INFO - Return code: 0 00:22:19 INFO - grabbing minidump binary from tooltool 00:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:19 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 0x1d89090>, '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 0x1d8c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8ce40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:22:19 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 00:22:19 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 00:22:19 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 00:22:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:22:19 INFO - Return code: 0 00:22:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:22:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:22:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:22:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:22:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:22:19 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/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 00:22:19 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/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 00:22:19 INFO - Using env: {'DISPLAY': ':0', 00:22:19 INFO - 'HOME': '/home/cltbld', 00:22:19 INFO - 'LANG': 'en_US.UTF-8', 00:22:19 INFO - 'LANGUAGE': 'en_US:en', 00:22:19 INFO - 'LOGNAME': 'cltbld', 00:22:19 INFO - 'MAIL': '/var/mail/cltbld', 00:22:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:22:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:19 INFO - 'MOZ_NO_REMOTE': '1', 00:22:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:22:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:19 INFO - 'NO_EM_RESTART': '1', 00:22:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:19 INFO - 'PWD': '/builds/slave/test', 00:22:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:22:19 INFO - 'SHELL': '/bin/bash', 00:22:19 INFO - 'SHLVL': '1', 00:22:19 INFO - 'TERM': 'linux', 00:22:19 INFO - 'TMOUT': '86400', 00:22:19 INFO - 'USER': 'cltbld', 00:22:19 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471417034.628219-1218225099', 00:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:19 INFO - '_': '/tools/buildbot/bin/python'} 00:22:19 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/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 00:22:19 INFO - mozversion application_buildid: 20160816222215 00:22:19 INFO - mozversion application_changeset: 5b442b4e42c3a3a5a8b29746e4639eec4707c913 00:22:19 INFO - mozversion application_display_name: Nightly 00:22:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:22:19 INFO - mozversion application_name: Firefox 00:22:19 INFO - mozversion application_remotingname: firefox 00:22:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 00:22:19 INFO - mozversion application_vendor: Mozilla 00:22:19 INFO - mozversion application_version: 51.0a1 00:22:19 INFO - mozversion platform_buildid: 20160816222215 00:22:19 INFO - mozversion platform_changeset: 5b442b4e42c3a3a5a8b29746e4639eec4707c913 00:22:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 00:22:19 INFO - mozversion platform_version: 51.0a1 00:22:19 INFO - using testdate: 1471418539 00:22:19 INFO - actual date: 1471418539 00:22:19 INFO - starting webserver on 'localhost:33182' 00:22:19 INFO - SUITE-START | Running 2 tests 00:22:19 INFO - TEST-START | dromaeo_css 00:22:19 INFO - operating with platform_type : linux_ 00:22:19 INFO - Initialising browser for dromaeo_css test... 00:22:19 INFO - TEST-INFO | started process 18497 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppEOHCE/profile http://localhost:33182/getInfo.html) 00:22:24 INFO - PROCESS | 18497 | __metrics Screen width/height:1600/1200 00:22:24 INFO - PROCESS | 18497 | colorDepth:24 00:22:24 INFO - PROCESS | 18497 | Browser inner width/height: 1024/697 00:22:24 INFO - PROCESS | 18497 | __metrics 00:22:25 INFO - PROCESS | 18497 | 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" 00:22:25 INFO - PROCESS | 18497 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:22:26 INFO - PROCESS | 18497 | console.error: PushService: 00:22:26 INFO - PROCESS | 18497 | stateChangeProcessEnqueue: Error transitioning state 00:22:26 INFO - PROCESS | 18497 | UnknownError 00:22:26 INFO - TEST-INFO | 18497: exit 0 00:22:26 INFO - Browser initialized. 00:22:26 INFO - Running cycle 1/1 for dromaeo_css test... 00:22:26 INFO - TEST-INFO | started process 18577 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppEOHCE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:22:38 INFO - PROCESS | 18577 | RSS: Main: 156798976 00:22:38 INFO - PROCESS | 18577 | 00:23:29 INFO - PROCESS | 18577 | 1471418609692 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] 00:25:43 INFO - PROCESS | 18577 | Cycle 1(1): loaded http://localhost:33182/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33182/tests/dromaeo/cssquery-ext.html) 00:25:44 INFO - PROCESS | 18577 | RSS: Main: 231079936 00:25:44 INFO - PROCESS | 18577 | 00:28:47 INFO - PROCESS | 18577 | Cycle 1(1): loaded http://localhost:33182/tests/dromaeo/cssquery-ext.html (next: http://localhost:33182/tests/dromaeo/cssquery-jquery.html) 00:28:47 INFO - PROCESS | 18577 | RSS: Main: 251015168 00:28:47 INFO - PROCESS | 18577 | 00:31:49 INFO - PROCESS | 18577 | Cycle 1(1): loaded http://localhost:33182/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33182/tests/dromaeo/cssquery-mootools.html) 00:31:50 INFO - PROCESS | 18577 | RSS: Main: 248098816 00:31:50 INFO - PROCESS | 18577 | 00:34:52 INFO - PROCESS | 18577 | Cycle 1(1): loaded http://localhost:33182/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33182/tests/dromaeo/cssquery-prototype.html) 00:34:53 INFO - PROCESS | 18577 | RSS: Main: 192483328 00:34:53 INFO - PROCESS | 18577 | 00:37:56 INFO - PROCESS | 18577 | Cycle 1(1): loaded http://localhost:33182/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33182/tests/dromaeo/cssquery-yui.html) 00:37:57 INFO - PROCESS | 18577 | RSS: Main: 189374464 00:37:57 INFO - PROCESS | 18577 | 00:41:00 INFO - PROCESS | 18577 | Cycle 1(1): loaded http://localhost:33182/tests/dromaeo/cssquery-yui.html (next: http://localhost:33182/tests/dromaeo/cssquery-dojo.html) 00:41:00 INFO - PROCESS | 18577 | RSS: Main: 276692992 00:41:00 INFO - PROCESS | 18577 | 00:41:00 INFO - PROCESS | 18577 | __start_tp_report 00:41:00 INFO - PROCESS | 18577 | _x_x_mozilla_page_load 00:41:00 INFO - PROCESS | 18577 | _x_x_mozilla_page_load_details 00:41:00 INFO - PROCESS | 18577 | |i|pagename|runs| 00:41:00 INFO - PROCESS | 18577 | |0;dojo.html;2579.4712084022776;2591.1578736910487;2611.1905309354097;2621.698504671962;2625.6849178098632;4532.274836026237;4610.561996610317;4670;4716.363290955725;4717.339572459854;4662.79017444215;4667.556582124693;4683.180443422404;4729.73986430747;4731.313959475874;4216.494020717518;4248.299030659953;4275.8075886585175;4328.982689068074;4344.804483798221;4272.850450234242;4344.717593356421;4368.497622773374;4373.1690978714105;4413.227685155085;4352.216601011848;4365.126974605076;4381.255186618282;4404.031113155117;4419.823207071713;4178.540360560336;4211.410402543646;4244.87265382039;4288;4306.655467562588;4197.034682023152;4216.409702641633;4237.4279472271155;4238.470191226097;4330.935035974463;4632.698874573142;4666.090112428078;4680.297955306697;4682.578567928903;4689.062187562501;4764.237721964469;4889.266610008527;4931.0630980113665;5019.799208031674;5055.191169412877;5068.873278168038;5074.898502029939;5080.49195080489;5088.211327244283;5090.872728181788;4670.859874203711;4692.859214223578;4734.147853386424;4738.881527961828;4739.360186374795;4888.853334399973;5040.697558146449;5107.080725469451;5107.693538387634;5206.271122042917;4919.3358896549225;4938.506149385033;4952.182889823139;4962.801487940442;5015.648904576645;5048.29323894655;5048.798048078109;5054.797808087709;5055.69665820052;5090.338256026693;4809.711417314972;4906.632002599819;4924.556789888928;4954.925676114861;5054.494550544916;4957.330760347307;4979.825706100269;4986.775595098229;4991.600671946236;5028.647994640413;4221.451211342506;4229.407882896397;4234.428352172479;4240.533541310453;4274.508431530414;3620.3483372992587;3635.3092912347097;3655.6344365563764;3681.7054635629083;3735.5704094029534;4282.057947251599;4302.65578753699;4331.068820203608;4344.782760861945;4389.122175564899;5067.366579177603;5076.162433198481;5081.9745901271;5085.847424577269;5130.179171332569;4181.790910454465;4278.272693642089;4310.590493903074;4343.087951530214;4353.36876152956;4524.09518096382;4539.818407263739;4579.061292435043;4586.770661466913;4587.61005314552;4781.832635857728;4786.186348320724;4786.569208771228;4787.880302992314;4844.15227335222;4850.854474365775;4942.505749425172;4984.053029924303;5065.620078494201;5085.567726743268;4710.29345598163;4737.38414006177;4739.099571081483;4769.761511924459;4785.162596545661;4894.486078961658;4923.950760492349;4927.507249275044;4934.383202099738;4964.3298154748645;4858.028394321079;4974.104661161025;5001.874953126201;5007.449180590205;5038;4893.119238536955;4905.7792399342825;4909.459959404391;4933.77797999098;4952.331435256195;4861.586765124863;4866.026794641157;4866.343043689057;4868.805247790048;4896.1921282987905;4596.862094137181;4720.291956206596;4735.502772208868;4801;4858.441279252931;4871;4888.828890988798;4900.705957642553;4915.631327650369;4934.753262336941;4755.83354582588;4768.76156192182;4774.737389443614;4786.664933454625;4827.348307978518 00:41:00 INFO - PROCESS | 18577 | |1;ext.html;564.1932037186823;573.3635664412504;579.9449052340026;583.9708014599272;588.8439563515669;28517.572236416472;37622.24755504892;38926.80536597317;38957.22085558287;42979.57020429795;40390.39414408779;40653.39019914696;40725.79637101818;40759.38860917081;40789.79605101978;7526.736564220267;7656.0812702475505;7663.655135518887;7663.693452261903;7668.616569171547;7452.77641670748;7541.886871696929;7545.434092443062;7546.245375462465;7549.245075492462;7521.24787521249;7559.357454616336;7570.621468926532;7573.356264717506;7574.848503029936;7501.0998680158455;7539.547627142389;7542.849143017136;7547.471676982614;7552.66013029415;7453.664585093684;7514.361279291267;7524.8118797029965;7528.435367347416;7532.096148462165;33562.32875342504;33825.492617610755;33855.15362115942;33894.15264618379;34094.6590534094;3127.1712996056062;3291.2594666200152;3479.860805567774;3604.873829415958;3810.314143454177;27288;29309.56035659467;29330;29786.10641680753;30350;3527.8588856445967;3547.6274991126074;3549.964500354989;3598.1724203433096;3660.04838741929;257233;258246;258428;259118;259582.70208649026;53952.190717139274;54279.45720542823;54283.45716542863;54328.45671543313;54407.455925441034;32163.356732865213;33102;33185.502217466754;34942.301153976776;56435;14527.201003944672;14623.122612643276;14624.122552646875;14682.045667031609;14721.926390368193;10951.83572246417;11030.944845275722;11058.391788451552;11067.391293478937;11072.557097716162;17888.55278618045;17924.372646957294;17933.372331968578;18059.458216253533;18080.819191807914;6809.557378770364;6881.621510816853;6938.826529336807;6987.065432518354;7009.264027277062;783.8549868274388;876.640577363278;884.2086332732168;980.2109302011806;989.302541708094;27523.86238068797;27551.862240688668;28094.85952570265;28458.85770571134;28753.137405877857;943.056943056943;987.9753006174903;988.6037338420408;1042.1923009667416;1054.1145437832104;14587.781183282259;14669.193194374198;14694.338754756061;15066.171360575054;15154.802553629663;121282;121745;121789.39105304416;121904.39047804705;122105;49945.75027124841;50415;50514.242286365734;50547;50670;1019.8572199892021;1118.7930232906938;1152.8962393384636;1161.6340852631395;1173.7065733566778;27648.861755691094;28787;29015;29227.269318266364;29532.0935812834;49854.50145498499;52434.47565524296;52520.47479525309;52537;52590.47409526009;346539;354606;354755;354771.48756818625;355765;32624.673753263112;33067.503987439726;33498.81761232679;35530.644693552735;35851.64148358483;35777.28445431025;45179;45765;46333.76833115813;46393.76803115963;34807.651923480444;35999.820000899825;36220.81889590535;36416.635833641325;36850.63149368472;36296.81851590725;36309.818450908635;36555.45166822553;36623;37405.812970934974;35703;36412.27175456529;36510.8174459126;36798.63201368059;36927.44608830923;31138;33686.49470257997;33984.320313593096;34075.14812129716;34182.487262690585;23594.17420390278;24601.753982459948;24667.136650217155;25072;25249.621255680813 00:41:00 INFO - PROCESS | 18577 | |2;jquery.html;366.8532586965214;368.4896418460433;374.1357464257565;383.88483454963506;387.5543125405783;15905.363785448582;17136.742948855775;17242.310307587697;17464.82535174648;17472.475825725218;16040.83959160408;16080.517584472454;16950.49148525546;17286.13569321535;17336.046517441537;4864.7567621618955;5096.923546146811;5100.642954993152;5102.770375333119;5103.617228707843;3329.9334013319717;3462.1690794209335;4912.656114072016;5672.461116193955;5883.911741323884;6008.849778755544;6016.879662406749;6021.367756385582;6023.30731965823;6026.578139530213;5738.684372359518;5830.125481177836;5839.503642190419;5851.912221316684;5854.063349864001;5035.420926593452;5191.558717508979;5193.246979188034;5194.480551944813;5195.090859099642;21028;21234.89382553093;21344;21374.465638359165;21375.358739237694;1987.1852540458488;2195.10009855551;2316.131450705985;2462.4213309872166;2506.5989441689426;12918.289494077822;13409.329533523285;13410.396532156077;13436.932815335958;14047.297635118306;1689.6029433083215;1691.7124088904793;1696.6945949729045;1709.1368858726387;1711.6063305439693;67039;67116.66441667809;67648.9999999995;67972.3202767971;68701;8376.288015518749;8935.463872167691;8954.776130596656;9039.412438191497;9258.583363748647;11898.345590992405;11938.820917686242;11947.223430476992;11955.222910510789;11990.280583165038;6820.6930688119155;6973.302669733088;6988.37104660583;7179.964100179465;7205.927940720526;6899.310068993061;6979.69670008906;7097.751578694721;7122.572645641175;7188.065551478275;3672.6694597486353;3737.8131093445218;3820.522434695663;3854.3447613905705;3882.2041481496226;1693.3734518228334;1707.163489889945;1744.485376813843;1839.2275244397385;1852.2405813616353;760.84402697448;821.5235163605095;827.4869580859785;829.088003196479;902.41794042842;17040.57398565046;17085.48743537696;17680.646387072185;17739.645207095786;18054.5486362842;797.6678946159927;828.763802316337;834.9707760228363;870.0646804684989;870.8476016697052;9429.622815087458;9477.952610237042;9981.251406144569;10064.245181611408;10294.948525257476;380510;387843;388634;391048;392624;78769.60615196887;80013.59993199997;80381.59809200917;81628.18371816324;82212.58893705493;880.0627331891387;880.2561835249204;883.3198437203317;924.8797656304637;946.4699768129869;15845.445409410615;16102.114383709008;16405.343786248894;16630.168491575616;16632.50102496927;35515;35994.28011439704;36263.27473450569;36779.63220367762;41073;51962.740186298826;52467;52731.47268527265;53283.73358133184;53893;7226.687460012746;7596.734114305973;7656.4640475168435;7778.611069446391;7843.333316667919;11221.943890280496;11335.659930202108;11595.130365222474;11795.764084718428;11941.76116477683;9743.951280243837;9769.46267955241;9775.169110625677;9819.459929703935;10111.3427627204;10337.17302615804;10383.688489345332;10542.156627469938;10704.571817127227;11236.66290011301;9113.635454582007;9489.525523723923;9682.27382946296;10094.444805535766;10461.843072353768;7489.101307843093;7763.68945242184;7850.979372681515;7897.644605992629;8293;8906.421082629608;9580.712578622653;9719.84105165838;9742.707718768448;10104.312548904141 00:41:00 INFO - PROCESS | 18577 | |3;mootools.html;1341.7987301904716;1383.439706918698;1451.9709605807884;1606.365485633174;1675.9581010474737;7471.738489152881;8053;8069.636866341014;8074.636641351146;8076.313513351358;6622.8675426491445;7382.261773822615;7606.847863042736;7606.885896711554;7923.484973476734;2094.7276854008996;2128.1274677382276;2129.5634394948997;2131.9680204796864;2133.349328454821;2065.8967051647433;2101.653227217515;2107.0202355904503;2110.3352443980098;2114.196605289995;1877.896715680637;1938.4184744576642;1939.5054261163455;1949.9220031198736;2128.744550653916;2078.594674038563;2123.587814103619;2124.468882779305;2128.404046866872;2137.0490131891315;2135.359392182339;2149.9897595748025;2158.665406861939;2172.73927128744;2174.9021294041804;7866.567338796364;7964.203579642048;8083.555404452753;8090.878636820453;8092.919070809335;4492.887677808081;4616.307553866914;4630.513796051399;4632.189366860786;4632.953670463286;2931.9560206596916;3024.8638811253545;3040.6351237851377;3045.0255918106213;3072.6312842458824;936.3024546712743;950.5912457643211;962.8555716642489;971.6987733802473;975.9560819763057;10742.086922611586;10742.570297188182;10817.296875703132;10825.350478971286;10832;4157.521884983205;4261.850835220753;4389.714668546534;4393.472783266028;4401.317795741666;8955.194032537103;8959.238464730572;8974.461532308082;8990.505522196212;9096.636134554543;4624.1445332613575;4630;4633.768311584475;4635.6523260755575;4637.188492013952;4042.8989275267763;4222.071144348239;4235.385869048971;4242.109157076987;4246.8301267949555;3502.649735026477;3530.1880567469625;3609.2059746856175;3614.29521243355;3645.872394466181;1159.4260840883817;1190.6368557589844;1199.2670590995926;1207.2032458577296;1209.2804781155198;638.6998110887876;656.5732274021829;789.1595450844832;809.1665584448028;818.6397984886631;4977.850664479999;5019.573336266459;5166.3025491558155;5275.155975044051;5400.83797486076;821.7124006597643;843.1863251961886;865.0830120072743;871.664409202449;891.2753821355698;3510.701040614989;3511.2977404519197;3527.188746588299;3570.0896271450615;3614.945775813365;9235.21500672437;9630.903690963141;9656.275779316438;9666.371685840399;9667.854982175273;9600.85598716006;9609.615615375307;9611.038896110333;9613.759656008517;9617.759556011155;7245.818854528679;7446;7447.180810110992;7555.753300705322;7575.318221360104;4972.825951091694;5077.968812475039;5083.38999320084;5108.3103780989095;5151.201563757623;8764.737057888273;9095.135962083592;9173.908260917573;9178.586963586522;9195.35632505745;8663.133686631287;8902;8950.731478055668;9255.953720231626;9431.434113953184;6560.868782624416;6824.7952561423235;6845.418139458202;7024.297570242935;7036.9296307036275;7752.3798096153305;7840.411969102226;7870.606469676608;7897.921020789719;7900.051993760785;6598.868022639616;6652.268250492539;6654.667266636552;6871.553349032297;6878.7248510059035;6546.705398256994;6622;6669.0996715444735;6676.998450232504;6700.061991321317;6454.935450645434;6529.477641788741;6530.444912182327;6536.182977127859;6538.705758240795;4373.475182978063;4580.106879158665;4586.243269860569;4638.953610463987;4645.790939407802;4596.172688916027;4604.470485894165;4692.906141877212;4788.545088216614;4948.035133149001 00:41:00 INFO - PROCESS | 18577 | |4;prototype.html;300.1371058207653;302.77594580010793;309.4260147426523;315.55034076441063;317.3383495412066;4652.627789776819;4783.258594917785;4797.928031079532;4843.951560484394;4857.489963553829;4609.331646911196;4672.64955128365;4701.694389864657;4714.9764251178785;4746.145693775119;3763.9059023524355;3770.509833721626;3780.6786423154067;3792.431135329702;3802.90492737681;3612.1150318172085;3636.363636363639;3763.247350529905;3821.445890345912;3823.655870971626;3733.8879833605033;3759.590153692365;3773.3208022555928;3779.5842457329672;3780.546334439871;3758.962410375889;3783.7351385402894;3785.470034195215;3790.9620903790883;3802.8098595070137;3351.245969656832;3364.34395292918;3368.781029233092;3372.7133193678565;3383.678550537695;4448.443044934247;4543.840965566223;4584.40402747644;4606.193916064675;4643.698159619614;4250.511191213019;4255.5957184067465;4255.659547236213;4269.231538323101;4277.443932288814;4914.262860570907;4924.901501969976;4927.556519913225;4928.162212423897;4941.950580494185;3340.448825943716;3502.2645244498685;3612.5664920209742;3631.382664946965;3647.94528082079;5089.821856235014;5095.312132862054;5107.4892510749;5109.565686916654;5122.897542049138;3610;3618.1859081706407;3619.438986956973;3623.1847834237615;3632.5096112025067;3668.192997540537;3668.321360548254;3673.44898265257;3716.535433070866;3717.553893532793;3999.320115580358;4186.853460128942;4209.34755112958;4211.599898009684;4227.556106608762;4965.776540055706;4968.677035992649;4987.925181122286;4990.975045124824;5005.823631446608;1207.9094067944939;1240.7890658588062;1263.77252094622;1269.974600508003;1273.5797186928291;874;874.8294082653821;891.0910870911633;895.9283257339397;895.9462432254086;4352.630026447724;4397.802098905557;4398.714083584557;4399.758013309235;4405.273129933525;5043.117454445457;5066.442691303953;5078.619103567247;5087.694738315713;5099.311592934906;4371.737695738266;4378.671599630041;4382.320740285261;4390.47314322277;4394.252976993918;2299.7930186283315;2403.074816195742;2418.625113107471;2422.842515236504;2422.854628722282;4308.4399028126145;4321.9135617287475;4326.819787456217;4333.894856811493;4340.197063543254;4589.875480507297;4642.282355528476;4650.511696271843;4652.092841895866;4685.625149987993;4000.7599544027453;4281.507626622978;4294.957050429456;4296.37702533131;4309.026771527636;4489.236829739018;4493.0789188216995;4497.797599107983;4511.481179664381;4542.931856022228;4449.955500444954;4480.9103817922805;4485.282354823213;4486.932696009497;4540.0011997360525;4112.157007813451;4288.228118938621;4325.199838029975;4329.7835108243835;4360.86617479464;3362.6973572377624;3365.3005232358482;3369.1913940653576;3369.7809642373168;3403.2853100849097;4625.606823419912;4628.722276663411;4638.652101092364;4652.116097941381;4999.800007999639;4778.235482322951;4930.457649658606;4991.47589503097;5010.749462526932;5025.7989680412375;4922.4585295616735;4956;5009.974950125226;5033.2635240841855;5051.646384753032;4732.597729193058;4892.730899800546;4919.877003074952;4933.802647894044;4977.552020318189;4948.579370753526;5010.594914441016;5025.899482010266;5048.368953880765;5048.844472204872;4905;4917.778699958439;4935.333729946411;4949.356583644103;4953.479884612208 00:41:00 INFO - PROCESS | 18577 | |5;yui.html;591.2581099581357;653.8365408647838;669.0927918195441;674.9875187219171;721.2354903801973;11168.720781980455;12770;12846.48614055439;12890.226586404822;12891.613251602443;12184;12260.693482662915;12275.570355037575;12279.201851879621;12280.815787763191;639.6961443314435;767.9385649148055;787.4094429178116;788.9368850491976;789.3014682006408;643.279526929837;648.6011103171563;648.6691787188544;649.2922714241475;762.8550575390658;820.1716266570777;827.9089300176976;832.1262674192075;832.9125441828612;833.5790425834945;825.7852958230843;827.4704189318841;827.8675411934062;828.6022709099606;829.0258945738733;486.47460742398476;577.7775556410812;602.2923065398139;602.9125776762345;603.5563860562484;11642.18504704671;12094.637160885188;12107.152499325051;12107.515699372014;12117.818232726517;12190.939045304805;12463.376831158497;12487.31319777412;12809.359532023454;12901.548445804445;9533.170508925547;10041.899581004243;11340.489677964511;11574.247673901169;11656.883431165665;4058.756474611503;4082.2447847148374;4088.407180958774;4090.713650044498;4307.052448461333;63788;64313.35686643167;64743.352566474205;64979.67510162419;66803.99794003142;10032.949835250776;10114.039166279192;10123.240756943258;10159.542820573091;10214.08073273409;19308.227670893008;19692.21231150738;19698.90150549238;19719;19737.506562336057;6900.309969003059;6953.200381956039;7064.576125432388;7224.422046236288;7271;3665.627160456972;3677.8712745053795;3691.2617476504806;3731.2350968051483;3897.9805100974313;3027.2129246396094;3134.670859559759;3155.936881262362;3155.984220078885;3162.335909459039;515.4922401434584;520.1287842863189;525.6057956532601;533.4931814775979;536.0592160757845;484.26875418118897;503.7632312812972;556.2783186039202;577.9364269930304;597.3280059932575;7592.240775922474;7740.26467485588;7833.1775163608145;7837;7841.882371764428;540.7728753923361;565.0366125755577;580.9041508151108;583.7978686902337;585.9912101318483;3542.574891013095;3714.1643130295465;3915.1190982028816;3920.157166209279;4033.737807042533;57645.423545764;74948;105302;114553.42723286331;114742;63318.68340658267;85026.63641542858;86470.56764716136;89954;91660;82855.72900265356;83400.74898876643;84325;84540;85582.57208713917;7188.101487314086;7274.199838017708;7364.374028207662;7420.257974202536;7603.9619801900635;49468.75265623649;49805;50757.492425075274;50880.710368032785;51738.48261517487;63758.681206595524;64297;69726.65136674284;71603.64198178975;74909;9498.335116542052;9727.756806079904;9936.20510359184;9948.801023979624;10849.566017359217;10239.590416383262;10339.63811266602;10487.685369438928;10518.158547316043;10843.67468975932;9564.713058608253;9773.706788796348;9903;9979.251556133175;10129.746756331151;9703.320767546204;9889.55497002651;10032.749181270527;10711.732206694895;11077.446127693744;10982.560697572328;11213.943930280622;11263.436828158723;11561.132915031238;11987.34069626144;9560.919219500329;9785.168260697921;9997.900020999989;10096.79806403853;10288.2798204125;9135.862962055708;9190.724278271662;9241.445513269226;9304.395214311047;9318.95340523293 00:41:00 INFO - PROCESS | 18577 | __end_tp_report 00:41:00 INFO - PROCESS | 18577 | __start_cc_report 00:41:00 INFO - PROCESS | 18577 | _x_x_mozilla_cycle_collect,896 00:41:00 INFO - PROCESS | 18577 | __end_cc_report 00:41:00 INFO - PROCESS | 18577 | __startTimestamp1471419660278__endTimestamp 00:41:00 INFO - PROCESS | 18577 | 00:41:00 INFO - PROCESS | 18577 | ------- Summary: start ------- 00:41:00 INFO - PROCESS | 18577 | Number of tests: 6 00:41:00 INFO - PROCESS | 18577 | 00:41:00 INFO - PROCESS | 18577 | [#0] dojo.html Cycles:165 Average:4627.31 Median:4764.24 stddev:489.79 (10.3%) stddev-sans-first:464.20 00:41:00 INFO - PROCESS | 18577 | Values: 2579.5 2591.2 2611.2 2621.7 2625.7 4532.3 4610.6 4670.0 4716.4 4717.3 4662.8 4667.6 4683.2 4729.7 4731.3 4216.5 4248.3 4275.8 4329.0 4344.8 4272.9 4344.7 4368.5 4373.2 4413.2 4352.2 4365.1 4381.3 4404.0 4419.8 4178.5 4211.4 4244.9 4288.0 4306.7 4197.0 4216.4 4237.4 4238.5 4330.9 4632.7 4666.1 4680.3 4682.6 4689.1 4764.2 4889.3 4931.1 5019.8 5055.2 5068.9 5074.9 5080.5 5088.2 5090.9 4670.9 4692.9 4734.1 4738.9 4739.4 4888.9 5040.7 5107.1 5107.7 5206.3 4919.3 4938.5 4952.2 4962.8 5015.6 5048.3 5048.8 5054.8 5055.7 5090.3 4809.7 4906.6 4924.6 4954.9 5054.5 4957.3 4979.8 4986.8 4991.6 5028.6 4221.5 4229.4 4234.4 4240.5 4274.5 3620.3 3635.3 3655.6 3681.7 3735.6 4282.1 4302.7 4331.1 4344.8 4389.1 5067.4 5076.2 5082.0 5085.8 5130.2 4181.8 4278.3 4310.6 4343.1 4353.4 4524.1 4539.8 4579.1 4586.8 4587.6 4781.8 4786.2 4786.6 4787.9 4844.2 4850.9 4942.5 4984.1 5065.6 5085.6 4710.3 4737.4 4739.1 4769.8 4785.2 4894.5 4924.0 4927.5 4934.4 4964.3 4858.0 4974.1 5001.9 5007.4 5038.0 4893.1 4905.8 4909.5 4933.8 4952.3 4861.6 4866.0 4866.3 4868.8 4896.2 4596.9 4720.3 4735.5 4801.0 4858.4 4871.0 4888.8 4900.7 4915.6 4934.8 4755.8 4768.8 4774.7 4786.7 4827.3 00:41:00 INFO - PROCESS | 18577 | 00:41:00 INFO - PROCESS | 18577 | [#1] ext.html Cycles:180 Average:41258.00 Median:19724.91 stddev:69246.75 (351.1%) stddev-sans-first:69373.59 00:41:00 INFO - PROCESS | 18577 | Values: 564.2 573.4 579.9 584.0 588.8 28517.6 37622.2 38926.8 38957.2 42979.6 40390.4 40653.4 40725.8 40759.4 40789.8 7526.7 7656.1 7663.7 7663.7 7668.6 7452.8 7541.9 7545.4 7546.2 7549.2 7521.2 7559.4 7570.6 7573.4 7574.8 7501.1 7539.5 7542.8 7547.5 7552.7 7453.7 7514.4 7524.8 7528.4 7532.1 33562.3 33825.5 33855.2 33894.2 34094.7 3127.2 3291.3 3479.9 3604.9 3810.3 27288.0 29309.6 29330.0 29786.1 30350.0 3527.9 3547.6 3550.0 3598.2 3660.0 257233.0 258246.0 258428.0 259118.0 259582.7 53952.2 54279.5 54283.5 54328.5 54407.5 32163.4 33102.0 33185.5 34942.3 56435.0 14527.2 14623.1 14624.1 14682.0 14721.9 10951.8 11030.9 11058.4 11067.4 11072.6 17888.6 17924.4 17933.4 18059.5 18080.8 6809.6 6881.6 6938.8 6987.1 7009.3 783.9 876.6 884.2 980.2 989.3 27523.9 27551.9 28094.9 28458.9 28753.1 943.1 988.0 988.6 1042.2 1054.1 14587.8 14669.2 14694.3 15066.2 15154.8 121282.0 121745.0 121789.4 121904.4 122105.0 49945.8 50415.0 50514.2 50547.0 50670.0 1019.9 1118.8 1152.9 1161.6 1173.7 27648.9 28787.0 29015.0 29227.3 29532.1 49854.5 52434.5 52520.5 52537.0 52590.5 346539.0 354606.0 354755.0 354771.5 355765.0 32624.7 33067.5 33498.8 35530.6 35851.6 35777.3 45179.0 45765.0 46333.8 46393.8 34807.7 35999.8 36220.8 36416.6 36850.6 36296.8 36309.8 36555.5 36623.0 37405.8 35703.0 36412.3 36510.8 36798.6 36927.4 31138.0 33686.5 33984.3 34075.1 34182.5 23594.2 24601.8 24667.1 25072.0 25249.6 00:41:00 INFO - PROCESS | 18577 | 00:41:00 INFO - PROCESS | 18577 | [#2] jquery.html Cycles:180 Average:24436.01 Median:5066.17 stddev:64177.01 (1266.8%) stddev-sans-first:64331.60 00:41:00 INFO - PROCESS | 18577 | Values: 366.9 368.5 374.1 383.9 387.6 15905.4 17136.7 17242.3 17464.8 17472.5 16040.8 16080.5 16950.5 17286.1 17336.0 4864.8 5096.9 5100.6 5102.8 5103.6 3329.9 3462.2 4912.7 5672.5 5883.9 6008.8 6016.9 6021.4 6023.3 6026.6 5738.7 5830.1 5839.5 5851.9 5854.1 5035.4 5191.6 5193.2 5194.5 5195.1 21028.0 21234.9 21344.0 21374.5 21375.4 1987.2 2195.1 2316.1 2462.4 2506.6 12918.3 13409.3 13410.4 13436.9 14047.3 1689.6 1691.7 1696.7 1709.1 1711.6 67039.0 67116.7 67649.0 67972.3 68701.0 8376.3 8935.5 8954.8 9039.4 9258.6 11898.3 11938.8 11947.2 11955.2 11990.3 6820.7 6973.3 6988.4 7180.0 7205.9 6899.3 6979.7 7097.8 7122.6 7188.1 3672.7 3737.8 3820.5 3854.3 3882.2 1693.4 1707.2 1744.5 1839.2 1852.2 760.8 821.5 827.5 829.1 902.4 17040.6 17085.5 17680.6 17739.6 18054.5 797.7 828.8 835.0 870.1 870.8 9429.6 9478.0 9981.3 10064.2 10294.9 380510.0 387843.0 388634.0 391048.0 392624.0 78769.6 80013.6 80381.6 81628.2 82212.6 880.1 880.3 883.3 924.9 946.5 15845.4 16102.1 16405.3 16630.2 16632.5 35515.0 35994.3 36263.3 36779.6 41073.0 51962.7 52467.0 52731.5 53283.7 53893.0 7226.7 7596.7 7656.5 7778.6 7843.3 11221.9 11335.7 11595.1 11795.8 11941.8 9744.0 9769.5 9775.2 9819.5 10111.3 10337.2 10383.7 10542.2 10704.6 11236.7 9113.6 9489.5 9682.3 10094.4 10461.8 7489.1 7763.7 7851.0 7897.6 8293.0 8906.4 9580.7 9719.8 9742.7 10104.3 00:41:00 INFO - PROCESS | 18577 | 00:41:00 INFO - PROCESS | 18577 | [#3] mootools.html Cycles:180 Average:5158.26 Median:5129.76 stddev:2914.22 (56.8%) stddev-sans-first:2908.28 00:41:00 INFO - PROCESS | 18577 | Values: 1341.8 1383.4 1452.0 1606.4 1676.0 7471.7 8053.0 8069.6 8074.6 8076.3 6622.9 7382.3 7606.8 7606.9 7923.5 2094.7 2128.1 2129.6 2132.0 2133.3 2065.9 2101.7 2107.0 2110.3 2114.2 1877.9 1938.4 1939.5 1949.9 2128.7 2078.6 2123.6 2124.5 2128.4 2137.0 2135.4 2150.0 2158.7 2172.7 2174.9 7866.6 7964.2 8083.6 8090.9 8092.9 4492.9 4616.3 4630.5 4632.2 4633.0 2932.0 3024.9 3040.6 3045.0 3072.6 936.3 950.6 962.9 971.7 976.0 10742.1 10742.6 10817.3 10825.4 10832.0 4157.5 4261.9 4389.7 4393.5 4401.3 8955.2 8959.2 8974.5 8990.5 9096.6 4624.1 4630.0 4633.8 4635.7 4637.2 4042.9 4222.1 4235.4 4242.1 4246.8 3502.6 3530.2 3609.2 3614.3 3645.9 1159.4 1190.6 1199.3 1207.2 1209.3 638.7 656.6 789.2 809.2 818.6 4977.9 5019.6 5166.3 5275.2 5400.8 821.7 843.2 865.1 871.7 891.3 3510.7 3511.3 3527.2 3570.1 3614.9 9235.2 9630.9 9656.3 9666.4 9667.9 9600.9 9609.6 9611.0 9613.8 9617.8 7245.8 7446.0 7447.2 7555.8 7575.3 4972.8 5078.0 5083.4 5108.3 5151.2 8764.7 9095.1 9173.9 9178.6 9195.4 8663.1 8902.0 8950.7 9256.0 9431.4 6560.9 6824.8 6845.4 7024.3 7036.9 7752.4 7840.4 7870.6 7897.9 7900.1 6598.9 6652.3 6654.7 6871.6 6878.7 6546.7 6622.0 6669.1 6677.0 6700.1 6454.9 6529.5 6530.4 6536.2 6538.7 4373.5 4580.1 4586.2 4639.0 4645.8 4596.2 4604.5 4692.9 4788.5 4948.0 00:41:00 INFO - PROCESS | 18577 | 00:41:00 INFO - PROCESS | 18577 | [#4] prototype.html Cycles:180 Average:4022.61 Median:4396.03 stddev:1148.96 (26.1%) stddev-sans-first:1117.69 00:41:00 INFO - PROCESS | 18577 | Values: 300.1 302.8 309.4 315.6 317.3 4652.6 4783.3 4797.9 4844.0 4857.5 4609.3 4672.6 4701.7 4715.0 4746.1 3763.9 3770.5 3780.7 3792.4 3802.9 3612.1 3636.4 3763.2 3821.4 3823.7 3733.9 3759.6 3773.3 3779.6 3780.5 3759.0 3783.7 3785.5 3791.0 3802.8 3351.2 3364.3 3368.8 3372.7 3383.7 4448.4 4543.8 4584.4 4606.2 4643.7 4250.5 4255.6 4255.7 4269.2 4277.4 4914.3 4924.9 4927.6 4928.2 4942.0 3340.4 3502.3 3612.6 3631.4 3647.9 5089.8 5095.3 5107.5 5109.6 5122.9 3610.0 3618.2 3619.4 3623.2 3632.5 3668.2 3668.3 3673.4 3716.5 3717.6 3999.3 4186.9 4209.3 4211.6 4227.6 4965.8 4968.7 4987.9 4991.0 5005.8 1207.9 1240.8 1263.8 1270.0 1273.6 874.0 874.8 891.1 895.9 895.9 4352.6 4397.8 4398.7 4399.8 4405.3 5043.1 5066.4 5078.6 5087.7 5099.3 4371.7 4378.7 4382.3 4390.5 4394.3 2299.8 2403.1 2418.6 2422.8 2422.9 4308.4 4321.9 4326.8 4333.9 4340.2 4589.9 4642.3 4650.5 4652.1 4685.6 4000.8 4281.5 4295.0 4296.4 4309.0 4489.2 4493.1 4497.8 4511.5 4542.9 4450.0 4480.9 4485.3 4486.9 4540.0 4112.2 4288.2 4325.2 4329.8 4360.9 3362.7 3365.3 3369.2 3369.8 3403.3 4625.6 4628.7 4638.7 4652.1 4999.8 4778.2 4930.5 4991.5 5010.7 5025.8 4922.5 4956.0 5010.0 5033.3 5051.6 4732.6 4892.7 4919.9 4933.8 4977.6 4948.6 5010.6 5025.9 5048.4 5048.8 4905.0 4917.8 4935.3 4949.4 4953.5 00:41:00 INFO - PROCESS | 18577 | 00:41:00 INFO - PROCESS | 18577 | [#5] yui.html Cycles:180 Average:18037.01 Median:29111.60 stddev:26621.76 (91.4%) stddev-sans-first:26664.22 00:41:00 INFO - PROCESS | 18577 | Values: 591.3 653.8 669.1 675.0 721.2 11168.7 12770.0 12846.5 12890.2 12891.6 12184.0 12260.7 12275.6 12279.2 12280.8 639.7 767.9 787.4 788.9 789.3 643.3 648.6 648.7 649.3 762.9 820.2 827.9 832.1 832.9 833.6 825.8 827.5 827.9 828.6 829.0 486.5 577.8 602.3 602.9 603.6 11642.2 12094.6 12107.2 12107.5 12117.8 12190.9 12463.4 12487.3 12809.4 12901.5 9533.2 10041.9 11340.5 11574.2 11656.9 4058.8 4082.2 4088.4 4090.7 4307.1 63788.0 64313.4 64743.4 64979.7 66804.0 10032.9 10114.0 10123.2 10159.5 10214.1 19308.2 19692.2 19698.9 19719.0 19737.5 6900.3 6953.2 7064.6 7224.4 7271.0 3665.6 3677.9 3691.3 3731.2 3898.0 3027.2 3134.7 3155.9 3156.0 3162.3 515.5 520.1 525.6 533.5 536.1 484.3 503.8 556.3 577.9 597.3 7592.2 7740.3 7833.2 7837.0 7841.9 540.8 565.0 580.9 583.8 586.0 3542.6 3714.2 3915.1 3920.2 4033.7 57645.4 74948.0 105302.0 114553.4 114742.0 63318.7 85026.6 86470.6 89954.0 91660.0 82855.7 83400.7 84325.0 84540.0 85582.6 7188.1 7274.2 7364.4 7420.3 7604.0 49468.8 49805.0 50757.5 50880.7 51738.5 63758.7 64297.0 69726.7 71603.6 74909.0 9498.3 9727.8 9936.2 9948.8 10849.6 10239.6 10339.6 10487.7 10518.2 10843.7 9564.7 9773.7 9903.0 9979.3 10129.7 9703.3 9889.6 10032.7 10711.7 11077.4 10982.6 11213.9 11263.4 11561.1 11987.3 9560.9 9785.2 9997.9 10096.8 10288.3 9135.9 9190.7 9241.4 9304.4 9319.0 00:41:00 INFO - PROCESS | 18577 | -------- Summary: end -------- 00:41:00 INFO - PROCESS | 18577 | 00:41:01 INFO - TEST-INFO | 18577: exit 0 00:41:01 INFO - TEST-OK | dromaeo_css | took 1121497ms 00:41:01 INFO - TEST-START | kraken 00:41:01 INFO - operating with platform_type : linux_ 00:41:01 INFO - Initialising browser for kraken test... 00:41:01 INFO - TEST-INFO | started process 18874 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqUN0uK/profile http://localhost:33182/getInfo.html) 00:41:06 INFO - PROCESS | 18874 | __metrics Screen width/height:1600/1200 00:41:06 INFO - PROCESS | 18874 | colorDepth:24 00:41:06 INFO - PROCESS | 18874 | Browser inner width/height: 1024/697 00:41:06 INFO - PROCESS | 18874 | __metrics 00:41:06 INFO - PROCESS | 18874 | 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" 00:41:06 INFO - PROCESS | 18874 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:41:07 INFO - PROCESS | 18874 | console.error: PushService: 00:41:07 INFO - PROCESS | 18874 | stateChangeProcessEnqueue: Error transitioning state 00:41:07 INFO - PROCESS | 18874 | UnknownError 00:41:08 INFO - TEST-INFO | 18874: exit 0 00:41:08 INFO - Browser initialized. 00:41:08 INFO - Running cycle 1/1 for kraken test... 00:41:08 INFO - TEST-INFO | started process 18956 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqUN0uK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:41:20 INFO - PROCESS | 18956 | RSS: Main: 157315072 00:41:20 INFO - PROCESS | 18956 | 00:41:22 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33182/tests/kraken/driver.html?testName=audio-beat-detection) 00:41:23 INFO - PROCESS | 18956 | RSS: Main: 205414400 00:41:23 INFO - PROCESS | 18956 | 00:41:26 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33182/tests/kraken/driver.html?testName=audio-dft) 00:41:27 INFO - PROCESS | 18956 | RSS: Main: 196022272 00:41:27 INFO - PROCESS | 18956 | 00:41:30 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33182/tests/kraken/driver.html?testName=audio-fft) 00:41:30 INFO - PROCESS | 18956 | RSS: Main: 277229568 00:41:30 INFO - PROCESS | 18956 | 00:41:33 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33182/tests/kraken/driver.html?testName=audio-oscillator) 00:41:33 INFO - PROCESS | 18956 | RSS: Main: 188895232 00:41:33 INFO - PROCESS | 18956 | 00:41:35 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33182/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:41:36 INFO - PROCESS | 18956 | RSS: Main: 191877120 00:41:36 INFO - PROCESS | 18956 | 00:41:39 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33182/tests/kraken/driver.html?testName=imaging-darkroom) 00:41:40 INFO - PROCESS | 18956 | RSS: Main: 228749312 00:41:40 INFO - PROCESS | 18956 | 00:41:43 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33182/tests/kraken/driver.html?testName=imaging-desaturate) 00:41:44 INFO - PROCESS | 18956 | RSS: Main: 222355456 00:41:44 INFO - PROCESS | 18956 | 00:41:47 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33182/tests/kraken/driver.html?testName=json-parse-financial) 00:41:48 INFO - PROCESS | 18956 | RSS: Main: 225935360 00:41:48 INFO - PROCESS | 18956 | 00:41:49 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33182/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:41:50 INFO - PROCESS | 18956 | RSS: Main: 189640704 00:41:50 INFO - PROCESS | 18956 | 00:41:52 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33182/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:41:52 INFO - PROCESS | 18956 | RSS: Main: 196784128 00:41:52 INFO - PROCESS | 18956 | 00:41:55 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33182/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:41:55 INFO - PROCESS | 18956 | RSS: Main: 193892352 00:41:55 INFO - PROCESS | 18956 | 00:41:58 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33182/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:41:59 INFO - PROCESS | 18956 | RSS: Main: 189919232 00:41:59 INFO - PROCESS | 18956 | 00:42:02 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33182/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:42:02 INFO - PROCESS | 18956 | RSS: Main: 192647168 00:42:02 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | Cycle 1(1): loaded http://localhost:33182/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33182/tests/kraken/driver.html?testName=ai-astar) 00:42:04 INFO - PROCESS | 18956 | RSS: Main: 243372032 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | __start_tp_report 00:42:04 INFO - PROCESS | 18956 | _x_x_mozilla_page_load 00:42:04 INFO - PROCESS | 18956 | _x_x_mozilla_page_load_details 00:42:04 INFO - PROCESS | 18956 | |i|pagename|runs| 00:42:04 INFO - PROCESS | 18956 | |0;ai-astar;105;114;116;113;110;115;118;114;115;113 00:42:04 INFO - PROCESS | 18956 | |1;audio-beat-detection;167;127;125;191;125;124;161;123;186;122 00:42:04 INFO - PROCESS | 18956 | |2;audio-dft;161;160;160;163;161;163;161;163;161;162 00:42:04 INFO - PROCESS | 18956 | |3;audio-fft;74;76;77;116;77;78;76;78;133;75 00:42:04 INFO - PROCESS | 18956 | |4;audio-oscillator;86;86;141;86;85;87;111;85;85;127 00:42:04 INFO - PROCESS | 18956 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;116;116;117 00:42:04 INFO - PROCESS | 18956 | |6;imaging-darkroom;145;146;146;146;146;147;147;146;146;145 00:42:04 INFO - PROCESS | 18956 | |7;imaging-desaturate;95;94;95;95;95;94;95;95;95;94 00:42:04 INFO - PROCESS | 18956 | |8;json-parse-financial;65;65;67;65;66;68;72;66;68;65 00:42:04 INFO - PROCESS | 18956 | |9;json-stringify-tinderbox;65;65;65;66;66;65;66;66;65;66 00:42:04 INFO - PROCESS | 18956 | |10;stanford-crypto-aes;82;81;90;83;82;83;82;81;83;83 00:42:04 INFO - PROCESS | 18956 | |11;stanford-crypto-ccm;135;134;133;144;132;141;132;130;155;126 00:42:04 INFO - PROCESS | 18956 | |12;stanford-crypto-pbkdf2;174;171;170;177;171;172;169;170;169;167 00:42:04 INFO - PROCESS | 18956 | |13;stanford-crypto-sha256-iterative;69;75;68;66;67;67;74;72;66;68 00:42:04 INFO - PROCESS | 18956 | __end_tp_report 00:42:04 INFO - PROCESS | 18956 | __start_cc_report 00:42:04 INFO - PROCESS | 18956 | _x_x_mozilla_cycle_collect,1902 00:42:04 INFO - PROCESS | 18956 | __end_cc_report 00:42:04 INFO - PROCESS | 18956 | __startTimestamp1471419724782__endTimestamp 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | ------- Summary: start ------- 00:42:04 INFO - PROCESS | 18956 | Number of tests: 14 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#0] ai-astar Cycles:10 Average:113.30 Median:114.50 stddev:3.59 (3.1%) stddev-sans-first:2.22 00:42:04 INFO - PROCESS | 18956 | Values: 105.0 114.0 116.0 113.0 110.0 115.0 118.0 114.0 115.0 113.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:144.00 stddev:28.12 (19.5%) stddev-sans-first:28.68 00:42:04 INFO - PROCESS | 18956 | Values: 167.0 127.0 125.0 191.0 125.0 124.0 161.0 123.0 186.0 122.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:1.18 (0.7%) stddev-sans-first:1.24 00:42:04 INFO - PROCESS | 18956 | Values: 161.0 160.0 160.0 163.0 161.0 163.0 161.0 163.0 161.0 162.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#3] audio-fft Cycles:10 Average:86.00 Median:76.50 stddev:20.72 (27.1%) stddev-sans-first:21.52 00:42:04 INFO - PROCESS | 18956 | Values: 74.0 76.0 77.0 116.0 77.0 78.0 76.0 78.0 133.0 75.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:85.50 stddev:20.87 (24.4%) stddev-sans-first:21.68 00:42:04 INFO - PROCESS | 18956 | Values: 86.0 86.0 141.0 86.0 85.0 87.0 111.0 85.0 85.0 127.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 00:42:04 INFO - PROCESS | 18956 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 00:42:04 INFO - PROCESS | 18956 | Values: 145.0 146.0 146.0 146.0 146.0 147.0 147.0 146.0 146.0 145.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 00:42:04 INFO - PROCESS | 18956 | Values: 95.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#8] json-parse-financial Cycles:10 Average:66.70 Median:66.50 stddev:2.21 (3.3%) stddev-sans-first:2.26 00:42:04 INFO - PROCESS | 18956 | Values: 65.0 65.0 67.0 65.0 66.0 68.0 72.0 66.0 68.0 65.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 00:42:04 INFO - PROCESS | 18956 | Values: 65.0 65.0 65.0 66.0 66.0 65.0 66.0 66.0 65.0 66.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:2.58 (3.1%) stddev-sans-first:2.71 00:42:04 INFO - PROCESS | 18956 | Values: 82.0 81.0 90.0 83.0 82.0 83.0 82.0 81.0 83.0 83.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#11] stanford-crypto-ccm Cycles:10 Average:136.20 Median:134.50 stddev:8.38 (6.2%) stddev-sans-first:8.87 00:42:04 INFO - PROCESS | 18956 | Values: 135.0 134.0 133.0 144.0 132.0 141.0 132.0 130.0 155.0 126.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.00 Median:171.00 stddev:2.83 (1.7%) stddev-sans-first:2.78 00:42:04 INFO - PROCESS | 18956 | Values: 174.0 171.0 170.0 177.0 171.0 172.0 169.0 170.0 169.0 167.0 00:42:04 INFO - PROCESS | 18956 | 00:42:04 INFO - PROCESS | 18956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:68.50 stddev:3.29 (4.8%) stddev-sans-first:3.49 00:42:04 INFO - PROCESS | 18956 | Values: 69.0 75.0 68.0 66.0 67.0 67.0 74.0 72.0 66.0 68.0 00:42:04 INFO - PROCESS | 18956 | -------- Summary: end -------- 00:42:04 INFO - PROCESS | 18956 | 00:42:06 INFO - TEST-INFO | 18956: exit 0 00:42:06 INFO - TEST-OK | kraken | took 64902ms 00:42:06 INFO - SUITE-END | took 1186s 00:42:06 INFO - Completed test suite (00:19:46) 00:42:06 INFO - javascript benchmark 00:42:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2579.4712084022776, 2591.1578736910487, 2611.1905309354097, 2621.698504671962, 2625.6849178098632, 4532.274836026237, 4610.561996610317, 4670.0, 4716.363290955725, 4717.339572459854, 4662.79017444215, 4667.556582124693, 4683.180443422404, 4729.73986430747, 4731.313959475874, 4216.494020717518, 4248.299030659953, 4275.8075886585175, 4328.982689068074, 4344.804483798221, 4272.850450234242, 4344.717593356421, 4368.497622773374, 4373.1690978714105, 4413.227685155085, 4352.216601011848, 4365.126974605076, 4381.255186618282, 4404.031113155117, 4419.823207071713, 4178.540360560336, 4211.410402543646, 4244.87265382039, 4288.0, 4306.655467562588, 4197.034682023152, 4216.409702641633, 4237.4279472271155, 4238.470191226097, 4330.935035974463, 4632.698874573142, 4666.090112428078, 4680.297955306697, 4682.578567928903, 4689.062187562501, 4764.237721964469, 4889.266610008527, 4931.0630980113665, 5019.799208031674, 5055.191169412877, 5068.873278168038, 5074.898502029939, 5080.49195080489, 5088.211327244283, 5090.872728181788, 4670.859874203711, 4692.859214223578, 4734.147853386424, 4738.881527961828, 4739.360186374795, 4888.853334399973, 5040.697558146449, 5107.080725469451, 5107.693538387634, 5206.271122042917, 4919.3358896549225, 4938.506149385033, 4952.182889823139, 4962.801487940442, 5015.648904576645, 5048.29323894655, 5048.798048078109, 5054.797808087709, 5055.69665820052, 5090.338256026693, 4809.711417314972, 4906.632002599819, 4924.556789888928, 4954.925676114861, 5054.494550544916, 4957.330760347307, 4979.825706100269, 4986.775595098229, 4991.600671946236, 5028.647994640413, 4221.451211342506, 4229.407882896397, 4234.428352172479, 4240.533541310453, 4274.508431530414, 3620.3483372992587, 3635.3092912347097, 3655.6344365563764, 3681.7054635629083, 3735.5704094029534, 4282.057947251599, 4302.65578753699, 4331.068820203608, 4344.782760861945, 4389.122175564899, 5067.366579177603, 5076.162433198481, 5081.9745901271, 5085.847424577269, 5130.179171332569, 4181.790910454465, 4278.272693642089, 4310.590493903074, 4343.087951530214, 4353.36876152956, 4524.09518096382, 4539.818407263739, 4579.061292435043, 4586.770661466913, 4587.61005314552, 4781.832635857728, 4786.186348320724, 4786.569208771228, 4787.880302992314, 4844.15227335222, 4850.854474365775, 4942.505749425172, 4984.053029924303, 5065.620078494201, 5085.567726743268, 4710.29345598163, 4737.38414006177, 4739.099571081483, 4769.761511924459, 4785.162596545661, 4894.486078961658, 4923.950760492349, 4927.507249275044, 4934.383202099738, 4964.3298154748645, 4858.028394321079, 4974.104661161025, 5001.874953126201, 5007.449180590205, 5038.0, 4893.119238536955, 4905.7792399342825, 4909.459959404391, 4933.77797999098, 4952.331435256195, 4861.586765124863, 4866.026794641157, 4866.343043689057, 4868.805247790048, 4896.1921282987905, 4596.862094137181, 4720.291956206596, 4735.502772208868, 4801.0, 4858.441279252931, 4871.0, 4888.828890988798, 4900.705957642553, 4915.631327650369, 4934.753262336941, 4755.83354582588, 4768.76156192182, 4774.737389443614, 4786.664933454625, 4827.348307978518], "value": 4595.685847500686, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.1932037186823, 573.3635664412504, 579.9449052340026, 583.9708014599272, 588.8439563515669, 28517.572236416472, 37622.24755504892, 38926.80536597317, 38957.22085558287, 42979.57020429795, 40390.39414408779, 40653.39019914696, 40725.79637101818, 40759.38860917081, 40789.79605101978, 7526.736564220267, 7656.0812702475505, 7663.655135518887, 7663.693452261903, 7668.616569171547, 7452.77641670748, 7541.886871696929, 7545.434092443062, 7546.245375462465, 7549.245075492462, 7521.24787521249, 7559.357454616336, 7570.621468926532, 7573.356264717506, 7574.848503029936, 7501.0998680158455, 7539.547627142389, 7542.849143017136, 7547.471676982614, 7552.66013029415, 7453.664585093684, 7514.361279291267, 7524.8118797029965, 7528.435367347416, 7532.096148462165, 33562.32875342504, 33825.492617610755, 33855.15362115942, 33894.15264618379, 34094.6590534094, 3127.1712996056062, 3291.2594666200152, 3479.860805567774, 3604.873829415958, 3810.314143454177, 27288.0, 29309.56035659467, 29330.0, 29786.10641680753, 30350.0, 3527.8588856445967, 3547.6274991126074, 3549.964500354989, 3598.1724203433096, 3660.04838741929, 257233.0, 258246.0, 258428.0, 259118.0, 259582.70208649026, 53952.190717139274, 54279.45720542823, 54283.45716542863, 54328.45671543313, 54407.455925441034, 32163.356732865213, 33102.0, 33185.502217466754, 34942.301153976776, 56435.0, 14527.201003944672, 14623.122612643276, 14624.122552646875, 14682.045667031609, 14721.926390368193, 10951.83572246417, 11030.944845275722, 11058.391788451552, 11067.391293478937, 11072.557097716162, 17888.55278618045, 17924.372646957294, 17933.372331968578, 18059.458216253533, 18080.819191807914, 6809.557378770364, 6881.621510816853, 6938.826529336807, 6987.065432518354, 7009.264027277062, 783.8549868274388, 876.640577363278, 884.2086332732168, 980.2109302011806, 989.302541708094, 27523.86238068797, 27551.862240688668, 28094.85952570265, 28458.85770571134, 28753.137405877857, 943.056943056943, 987.9753006174903, 988.6037338420408, 1042.1923009667416, 1054.1145437832104, 14587.781183282259, 14669.193194374198, 14694.338754756061, 15066.171360575054, 15154.802553629663, 121282.0, 121745.0, 121789.39105304416, 121904.39047804705, 122105.0, 49945.75027124841, 50415.0, 50514.242286365734, 50547.0, 50670.0, 1019.8572199892021, 1118.7930232906938, 1152.8962393384636, 1161.6340852631395, 1173.7065733566778, 27648.861755691094, 28787.0, 29015.0, 29227.269318266364, 29532.0935812834, 49854.50145498499, 52434.47565524296, 52520.47479525309, 52537.0, 52590.47409526009, 346539.0, 354606.0, 354755.0, 354771.48756818625, 355765.0, 32624.673753263112, 33067.503987439726, 33498.81761232679, 35530.644693552735, 35851.64148358483, 35777.28445431025, 45179.0, 45765.0, 46333.76833115813, 46393.76803115963, 34807.651923480444, 35999.820000899825, 36220.81889590535, 36416.635833641325, 36850.63149368472, 36296.81851590725, 36309.818450908635, 36555.45166822553, 36623.0, 37405.812970934974, 35703.0, 36412.27175456529, 36510.8174459126, 36798.63201368059, 36927.44608830923, 31138.0, 33686.49470257997, 33984.320313593096, 34075.14812129716, 34182.487262690585, 23594.17420390278, 24601.753982459948, 24667.136650217155, 25072.0, 25249.621255680813], "value": 17078.759301273996, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.8532586965214, 368.4896418460433, 374.1357464257565, 383.88483454963506, 387.5543125405783, 15905.363785448582, 17136.742948855775, 17242.310307587697, 17464.82535174648, 17472.475825725218, 16040.83959160408, 16080.517584472454, 16950.49148525546, 17286.13569321535, 17336.046517441537, 4864.7567621618955, 5096.923546146811, 5100.642954993152, 5102.770375333119, 5103.617228707843, 3329.9334013319717, 3462.1690794209335, 4912.656114072016, 5672.461116193955, 5883.911741323884, 6008.849778755544, 6016.879662406749, 6021.367756385582, 6023.30731965823, 6026.578139530213, 5738.684372359518, 5830.125481177836, 5839.503642190419, 5851.912221316684, 5854.063349864001, 5035.420926593452, 5191.558717508979, 5193.246979188034, 5194.480551944813, 5195.090859099642, 21028.0, 21234.89382553093, 21344.0, 21374.465638359165, 21375.358739237694, 1987.1852540458488, 2195.10009855551, 2316.131450705985, 2462.4213309872166, 2506.5989441689426, 12918.289494077822, 13409.329533523285, 13410.396532156077, 13436.932815335958, 14047.297635118306, 1689.6029433083215, 1691.7124088904793, 1696.6945949729045, 1709.1368858726387, 1711.6063305439693, 67039.0, 67116.66441667809, 67648.9999999995, 67972.3202767971, 68701.0, 8376.288015518749, 8935.463872167691, 8954.776130596656, 9039.412438191497, 9258.583363748647, 11898.345590992405, 11938.820917686242, 11947.223430476992, 11955.222910510789, 11990.280583165038, 6820.6930688119155, 6973.302669733088, 6988.37104660583, 7179.964100179465, 7205.927940720526, 6899.310068993061, 6979.69670008906, 7097.751578694721, 7122.572645641175, 7188.065551478275, 3672.6694597486353, 3737.8131093445218, 3820.522434695663, 3854.3447613905705, 3882.2041481496226, 1693.3734518228334, 1707.163489889945, 1744.485376813843, 1839.2275244397385, 1852.2405813616353, 760.84402697448, 821.5235163605095, 827.4869580859785, 829.088003196479, 902.41794042842, 17040.57398565046, 17085.48743537696, 17680.646387072185, 17739.645207095786, 18054.5486362842, 797.6678946159927, 828.763802316337, 834.9707760228363, 870.0646804684989, 870.8476016697052, 9429.622815087458, 9477.952610237042, 9981.251406144569, 10064.245181611408, 10294.948525257476, 380510.0, 387843.0, 388634.0, 391048.0, 392624.0, 78769.60615196887, 80013.59993199997, 80381.59809200917, 81628.18371816324, 82212.58893705493, 880.0627331891387, 880.2561835249204, 883.3198437203317, 924.8797656304637, 946.4699768129869, 15845.445409410615, 16102.114383709008, 16405.343786248894, 16630.168491575616, 16632.50102496927, 35515.0, 35994.28011439704, 36263.27473450569, 36779.63220367762, 41073.0, 51962.740186298826, 52467.0, 52731.47268527265, 53283.73358133184, 53893.0, 7226.687460012746, 7596.734114305973, 7656.4640475168435, 7778.611069446391, 7843.333316667919, 11221.943890280496, 11335.659930202108, 11595.130365222474, 11795.764084718428, 11941.76116477683, 9743.951280243837, 9769.46267955241, 9775.169110625677, 9819.459929703935, 10111.3427627204, 10337.17302615804, 10383.688489345332, 10542.156627469938, 10704.571817127227, 11236.66290011301, 9113.635454582007, 9489.525523723923, 9682.27382946296, 10094.444805535766, 10461.843072353768, 7489.101307843093, 7763.68945242184, 7850.979372681515, 7897.644605992629, 8293.0, 8906.421082629608, 9580.712578622653, 9719.84105165838, 9742.707718768448, 10104.312548904141], "value": 8232.662088081252, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.7987301904716, 1383.439706918698, 1451.9709605807884, 1606.365485633174, 1675.9581010474737, 7471.738489152881, 8053.0, 8069.636866341014, 8074.636641351146, 8076.313513351358, 6622.8675426491445, 7382.261773822615, 7606.847863042736, 7606.885896711554, 7923.484973476734, 2094.7276854008996, 2128.1274677382276, 2129.5634394948997, 2131.9680204796864, 2133.349328454821, 2065.8967051647433, 2101.653227217515, 2107.0202355904503, 2110.3352443980098, 2114.196605289995, 1877.896715680637, 1938.4184744576642, 1939.5054261163455, 1949.9220031198736, 2128.744550653916, 2078.594674038563, 2123.587814103619, 2124.468882779305, 2128.404046866872, 2137.0490131891315, 2135.359392182339, 2149.9897595748025, 2158.665406861939, 2172.73927128744, 2174.9021294041804, 7866.567338796364, 7964.203579642048, 8083.555404452753, 8090.878636820453, 8092.919070809335, 4492.887677808081, 4616.307553866914, 4630.513796051399, 4632.189366860786, 4632.953670463286, 2931.9560206596916, 3024.8638811253545, 3040.6351237851377, 3045.0255918106213, 3072.6312842458824, 936.3024546712743, 950.5912457643211, 962.8555716642489, 971.6987733802473, 975.9560819763057, 10742.086922611586, 10742.570297188182, 10817.296875703132, 10825.350478971286, 10832.0, 4157.521884983205, 4261.850835220753, 4389.714668546534, 4393.472783266028, 4401.317795741666, 8955.194032537103, 8959.238464730572, 8974.461532308082, 8990.505522196212, 9096.636134554543, 4624.1445332613575, 4630.0, 4633.768311584475, 4635.6523260755575, 4637.188492013952, 4042.8989275267763, 4222.071144348239, 4235.385869048971, 4242.109157076987, 4246.8301267949555, 3502.649735026477, 3530.1880567469625, 3609.2059746856175, 3614.29521243355, 3645.872394466181, 1159.4260840883817, 1190.6368557589844, 1199.2670590995926, 1207.2032458577296, 1209.2804781155198, 638.6998110887876, 656.5732274021829, 789.1595450844832, 809.1665584448028, 818.6397984886631, 4977.850664479999, 5019.573336266459, 5166.3025491558155, 5275.155975044051, 5400.83797486076, 821.7124006597643, 843.1863251961886, 865.0830120072743, 871.664409202449, 891.2753821355698, 3510.701040614989, 3511.2977404519197, 3527.188746588299, 3570.0896271450615, 3614.945775813365, 9235.21500672437, 9630.903690963141, 9656.275779316438, 9666.371685840399, 9667.854982175273, 9600.85598716006, 9609.615615375307, 9611.038896110333, 9613.759656008517, 9617.759556011155, 7245.818854528679, 7446.0, 7447.180810110992, 7555.753300705322, 7575.318221360104, 4972.825951091694, 5077.968812475039, 5083.38999320084, 5108.3103780989095, 5151.201563757623, 8764.737057888273, 9095.135962083592, 9173.908260917573, 9178.586963586522, 9195.35632505745, 8663.133686631287, 8902.0, 8950.731478055668, 9255.953720231626, 9431.434113953184, 6560.868782624416, 6824.7952561423235, 6845.418139458202, 7024.297570242935, 7036.9296307036275, 7752.3798096153305, 7840.411969102226, 7870.606469676608, 7897.921020789719, 7900.051993760785, 6598.868022639616, 6652.268250492539, 6654.667266636552, 6871.553349032297, 6878.7248510059035, 6546.705398256994, 6622.0, 6669.0996715444735, 6676.998450232504, 6700.061991321317, 6454.935450645434, 6529.477641788741, 6530.444912182327, 6536.182977127859, 6538.705758240795, 4373.475182978063, 4580.106879158665, 4586.243269860569, 4638.953610463987, 4645.790939407802, 4596.172688916027, 4604.470485894165, 4692.906141877212, 4788.545088216614, 4948.035133149001], "value": 4130.122990924845, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.1371058207653, 302.77594580010793, 309.4260147426523, 315.55034076441063, 317.3383495412066, 4652.627789776819, 4783.258594917785, 4797.928031079532, 4843.951560484394, 4857.489963553829, 4609.331646911196, 4672.64955128365, 4701.694389864657, 4714.9764251178785, 4746.145693775119, 3763.9059023524355, 3770.509833721626, 3780.6786423154067, 3792.431135329702, 3802.90492737681, 3612.1150318172085, 3636.363636363639, 3763.247350529905, 3821.445890345912, 3823.655870971626, 3733.8879833605033, 3759.590153692365, 3773.3208022555928, 3779.5842457329672, 3780.546334439871, 3758.962410375889, 3783.7351385402894, 3785.470034195215, 3790.9620903790883, 3802.8098595070137, 3351.245969656832, 3364.34395292918, 3368.781029233092, 3372.7133193678565, 3383.678550537695, 4448.443044934247, 4543.840965566223, 4584.40402747644, 4606.193916064675, 4643.698159619614, 4250.511191213019, 4255.5957184067465, 4255.659547236213, 4269.231538323101, 4277.443932288814, 4914.262860570907, 4924.901501969976, 4927.556519913225, 4928.162212423897, 4941.950580494185, 3340.448825943716, 3502.2645244498685, 3612.5664920209742, 3631.382664946965, 3647.94528082079, 5089.821856235014, 5095.312132862054, 5107.4892510749, 5109.565686916654, 5122.897542049138, 3610.0, 3618.1859081706407, 3619.438986956973, 3623.1847834237615, 3632.5096112025067, 3668.192997540537, 3668.321360548254, 3673.44898265257, 3716.535433070866, 3717.553893532793, 3999.320115580358, 4186.853460128942, 4209.34755112958, 4211.599898009684, 4227.556106608762, 4965.776540055706, 4968.677035992649, 4987.925181122286, 4990.975045124824, 5005.823631446608, 1207.9094067944939, 1240.7890658588062, 1263.77252094622, 1269.974600508003, 1273.5797186928291, 874.0, 874.8294082653821, 891.0910870911633, 895.9283257339397, 895.9462432254086, 4352.630026447724, 4397.802098905557, 4398.714083584557, 4399.758013309235, 4405.273129933525, 5043.117454445457, 5066.442691303953, 5078.619103567247, 5087.694738315713, 5099.311592934906, 4371.737695738266, 4378.671599630041, 4382.320740285261, 4390.47314322277, 4394.252976993918, 2299.7930186283315, 2403.074816195742, 2418.625113107471, 2422.842515236504, 2422.854628722282, 4308.4399028126145, 4321.9135617287475, 4326.819787456217, 4333.894856811493, 4340.197063543254, 4589.875480507297, 4642.282355528476, 4650.511696271843, 4652.092841895866, 4685.625149987993, 4000.7599544027453, 4281.507626622978, 4294.957050429456, 4296.37702533131, 4309.026771527636, 4489.236829739018, 4493.0789188216995, 4497.797599107983, 4511.481179664381, 4542.931856022228, 4449.955500444954, 4480.9103817922805, 4485.282354823213, 4486.932696009497, 4540.0011997360525, 4112.157007813451, 4288.228118938621, 4325.199838029975, 4329.7835108243835, 4360.86617479464, 3362.6973572377624, 3365.3005232358482, 3369.1913940653576, 3369.7809642373168, 3403.2853100849097, 4625.606823419912, 4628.722276663411, 4638.652101092364, 4652.116097941381, 4999.800007999639, 4778.235482322951, 4930.457649658606, 4991.47589503097, 5010.749462526932, 5025.7989680412375, 4922.4585295616735, 4956.0, 5009.974950125226, 5033.2635240841855, 5051.646384753032, 4732.597729193058, 4892.730899800546, 4919.877003074952, 4933.802647894044, 4977.552020318189, 4948.579370753526, 5010.594914441016, 5025.899482010266, 5048.368953880765, 5048.844472204872, 4905.0, 4917.778699958439, 4935.333729946411, 4949.356583644103, 4953.479884612208], "value": 3666.7888272950618, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.2581099581357, 653.8365408647838, 669.0927918195441, 674.9875187219171, 721.2354903801973, 11168.720781980455, 12770.0, 12846.48614055439, 12890.226586404822, 12891.613251602443, 12184.0, 12260.693482662915, 12275.570355037575, 12279.201851879621, 12280.815787763191, 639.6961443314435, 767.9385649148055, 787.4094429178116, 788.9368850491976, 789.3014682006408, 643.279526929837, 648.6011103171563, 648.6691787188544, 649.2922714241475, 762.8550575390658, 820.1716266570777, 827.9089300176976, 832.1262674192075, 832.9125441828612, 833.5790425834945, 825.7852958230843, 827.4704189318841, 827.8675411934062, 828.6022709099606, 829.0258945738733, 486.47460742398476, 577.7775556410812, 602.2923065398139, 602.9125776762345, 603.5563860562484, 11642.18504704671, 12094.637160885188, 12107.152499325051, 12107.515699372014, 12117.818232726517, 12190.939045304805, 12463.376831158497, 12487.31319777412, 12809.359532023454, 12901.548445804445, 9533.170508925547, 10041.899581004243, 11340.489677964511, 11574.247673901169, 11656.883431165665, 4058.756474611503, 4082.2447847148374, 4088.407180958774, 4090.713650044498, 4307.052448461333, 63788.0, 64313.35686643167, 64743.352566474205, 64979.67510162419, 66803.99794003142, 10032.949835250776, 10114.039166279192, 10123.240756943258, 10159.542820573091, 10214.08073273409, 19308.227670893008, 19692.21231150738, 19698.90150549238, 19719.0, 19737.506562336057, 6900.309969003059, 6953.200381956039, 7064.576125432388, 7224.422046236288, 7271.0, 3665.627160456972, 3677.8712745053795, 3691.2617476504806, 3731.2350968051483, 3897.9805100974313, 3027.2129246396094, 3134.670859559759, 3155.936881262362, 3155.984220078885, 3162.335909459039, 515.4922401434584, 520.1287842863189, 525.6057956532601, 533.4931814775979, 536.0592160757845, 484.26875418118897, 503.7632312812972, 556.2783186039202, 577.9364269930304, 597.3280059932575, 7592.240775922474, 7740.26467485588, 7833.1775163608145, 7837.0, 7841.882371764428, 540.7728753923361, 565.0366125755577, 580.9041508151108, 583.7978686902337, 585.9912101318483, 3542.574891013095, 3714.1643130295465, 3915.1190982028816, 3920.157166209279, 4033.737807042533, 57645.423545764, 74948.0, 105302.0, 114553.42723286331, 114742.0, 63318.68340658267, 85026.63641542858, 86470.56764716136, 89954.0, 91660.0, 82855.72900265356, 83400.74898876643, 84325.0, 84540.0, 85582.57208713917, 7188.101487314086, 7274.199838017708, 7364.374028207662, 7420.257974202536, 7603.9619801900635, 49468.75265623649, 49805.0, 50757.492425075274, 50880.710368032785, 51738.48261517487, 63758.681206595524, 64297.0, 69726.65136674284, 71603.64198178975, 74909.0, 9498.335116542052, 9727.756806079904, 9936.20510359184, 9948.801023979624, 10849.566017359217, 10239.590416383262, 10339.63811266602, 10487.685369438928, 10518.158547316043, 10843.67468975932, 9564.713058608253, 9773.706788796348, 9903.0, 9979.251556133175, 10129.746756331151, 9703.320767546204, 9889.55497002651, 10032.749181270527, 10711.732206694895, 11077.446127693744, 10982.560697572328, 11213.943930280622, 11263.436828158723, 11561.132915031238, 11987.34069626144, 9560.919219500329, 9785.168260697921, 9997.900020999989, 10096.79806403853, 10288.2798204125, 9135.862962055708, 9190.724278271662, 9241.445513269226, 9304.395214311047, 9318.95340523293], "value": 6443.178771323857, "unit": "score"}], "name": "dromaeo_css", "value": 6308.960000289665, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 116.0, 113.0, 110.0, 115.0, 118.0, 114.0, 115.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 127.0, 125.0, 191.0, 125.0, 124.0, 161.0, 123.0, 186.0, 122.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 163.0, 161.0, 163.0, 161.0, 163.0, 161.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 77.0, 116.0, 77.0, 78.0, 76.0, 78.0, 133.0, 75.0], "value": 86.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 141.0, 86.0, 85.0, 87.0, 111.0, 85.0, 85.0, 127.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 65.0, 66.0, 68.0, 72.0, 66.0, 68.0, 65.0], "value": 66.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 66.0, 66.0, 65.0, 66.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 90.0, 83.0, 82.0, 83.0, 82.0, 81.0, 83.0, 83.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 134.0, 133.0, 144.0, 132.0, 141.0, 132.0, 130.0, 155.0, 126.0], "value": 136.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 171.0, 170.0, 177.0, 171.0, 172.0, 169.0, 170.0, 169.0, 167.0], "value": 171.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 68.0, 66.0, 67.0, 67.0, 74.0, 72.0, 66.0, 68.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1552.8000000000002, "alertThreshold": 2.0}]} 00:42:06 INFO - Return code: 0 00:42:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:42:06 INFO - # TBPL SUCCESS # 00:42:06 INFO - Running post-action listener: _resource_record_post_action 00:42:06 INFO - [mozharness: 2016-08-17 07:42:06.607428Z] Finished run-tests step (success) 00:42:06 INFO - Running post-run listener: _resource_record_post_run 00:42:06 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 0; Write bytes: 421519360; Read time: 0; Write time: 741128 00:42:06 INFO - TinderboxPrint: CPU usage
13.1% 00:42:06 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 00:42:06 INFO - TinderboxPrint: I/O write bytes / time
421,519,360 / 741,128 00:42:06 INFO - TinderboxPrint: CPU idle
8,346.5 (86.9%) 00:42:06 INFO - TinderboxPrint: CPU user
1,206.2 (12.6%) 00:42:06 INFO - TinderboxPrint: Swap in / out
0 / 0 00:42:06 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 130318336; Read time: 0; Write time: 175040 00:42:06 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 291201024; Read time: 0; Write time: 566088 00:42:07 INFO - Running post-run listener: _upload_blobber_files 00:42:07 INFO - Blob upload gear active. 00:42:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:42:07 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 00:42:07 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'] 00:42:07 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 00:42:07 INFO - (blobuploader) - INFO - Open directory for files ... 00:42:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:42:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:42:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:42:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:42:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:42:15 INFO - (blobuploader) - INFO - Done attempting. 00:42:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:42:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:42:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:42:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:42:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:42:16 INFO - (blobuploader) - INFO - Done attempting. 00:42:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:42:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:42:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:42:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:42:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:42:17 INFO - (blobuploader) - INFO - Done attempting. 00:42:17 INFO - (blobuploader) - INFO - Iteration through files over. 00:42:17 INFO - Return code: 0 00:42:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:42:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:42:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/113d2f34060ded56cdd9318433273b12945ba6f8320dcd2e0761aeea8e0aed549d2a05d3d0a9c8b40cac0e3494122ab5183936855c4d2b166aae4d2a2cad397b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/98917bd99fa5a4aa6f087f307cfa6fbee0438bea64aca3eac30e218a3827c33e2e627d9dbdb9d2759d62c043dd9e5c80cf4a81f86210e2db2e605ab9be11fc53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/681c0696f39e57889f5737961833ce44d18124c64c391a5e2175d67e30e58a7d33de585d1d8a23fd486a7b80025983b875907a1b9ba69aece5ee030f66dab1b2"} 00:42:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:42:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:42:17 INFO - Contents: 00:42:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/113d2f34060ded56cdd9318433273b12945ba6f8320dcd2e0761aeea8e0aed549d2a05d3d0a9c8b40cac0e3494122ab5183936855c4d2b166aae4d2a2cad397b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/98917bd99fa5a4aa6f087f307cfa6fbee0438bea64aca3eac30e218a3827c33e2e627d9dbdb9d2759d62c043dd9e5c80cf4a81f86210e2db2e605ab9be11fc53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/681c0696f39e57889f5737961833ce44d18124c64c391a5e2175d67e30e58a7d33de585d1d8a23fd486a7b80025983b875907a1b9ba69aece5ee030f66dab1b2"} 00:42:17 INFO - Running post-run listener: copy_logs_to_upload_dir 00:42:17 INFO - Copying logs to upload dir... 00:42:17 INFO - mkdir: /builds/slave/test/build/upload/logs 00:42:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.961418 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-08-17 00:42:16.734948) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 00:42:16.738655) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471417034.628219-1218225099 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/113d2f34060ded56cdd9318433273b12945ba6f8320dcd2e0761aeea8e0aed549d2a05d3d0a9c8b40cac0e3494122ab5183936855c4d2b166aae4d2a2cad397b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/98917bd99fa5a4aa6f087f307cfa6fbee0438bea64aca3eac30e218a3827c33e2e627d9dbdb9d2759d62c043dd9e5c80cf4a81f86210e2db2e605ab9be11fc53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/681c0696f39e57889f5737961833ce44d18124c64c391a5e2175d67e30e58a7d33de585d1d8a23fd486a7b80025983b875907a1b9ba69aece5ee030f66dab1b2"} build_url:https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011173 build_url: 'https://queue.taskcluster.net/v1/task/D-pCWEEQSYmtHEo9I9KBdw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/113d2f34060ded56cdd9318433273b12945ba6f8320dcd2e0761aeea8e0aed549d2a05d3d0a9c8b40cac0e3494122ab5183936855c4d2b166aae4d2a2cad397b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/98917bd99fa5a4aa6f087f307cfa6fbee0438bea64aca3eac30e218a3827c33e2e627d9dbdb9d2759d62c043dd9e5c80cf4a81f86210e2db2e605ab9be11fc53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/681c0696f39e57889f5737961833ce44d18124c64c391a5e2175d67e30e58a7d33de585d1d8a23fd486a7b80025983b875907a1b9ba69aece5ee030f66dab1b2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 00:42:16.763917) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 00:42:16.764321) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471417034.628219-1218225099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 00:42:16.849045) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 00:42:16.849364) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 00:42:16.849637) ========= ========= Total master_lag: 0.20 =========