builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-060 starttime: 1472684090.32 results: success (0) buildid: 20160831150821 builduid: 88397871fd02443db141eee09d2d8804 revision: dfc059b71968f935273c490d9db8e3bb6173cae3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:50.320221) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:50.320639) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:50.320955) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:50.364350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:50.364668) ========= 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=337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-31 15:54:50-- 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% 227M=0s 2016-08-31 15:54:50 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.206062 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:50.705020) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:50.705368) ========= 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=337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022021 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:50.774324) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-31 15:54:50.774704) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev dfc059b71968f935273c490d9db8e3bb6173cae3 --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 dfc059b71968f935273c490d9db8e3bb6173cae3 --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=337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-31 15:54:50,833 truncating revision to first 12 chars 2016-08-31 15:54:50,833 Setting DEBUG logging. 2016-08-31 15:54:50,833 attempt 1/10 2016-08-31 15:54:50,834 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/dfc059b71968?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 15:54:51,534 unpacking tar archive at: autoland-dfc059b71968/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980557 ========= master_lag: 1.39 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-31 15:54:53.145036) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:53.145390) ========= script_repo_revision: dfc059b71968f935273c490d9db8e3bb6173cae3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:53.145837) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:53.146168) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 15:54:53.174596) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-08-31 15:54:53.174921) ========= /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=337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:54:53 INFO - MultiFileLogger online at 20160831 15:54:53 in /builds/slave/test 15:54:53 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 15:54:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:54:53 INFO - {'append_to_log': False, 15:54:53 INFO - 'base_work_dir': '/builds/slave/test', 15:54:53 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 15:54:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:54:53 INFO - 'branch': 'Autoland-Non-PGO', 15:54:53 INFO - 'buildbot_json_path': 'buildprops.json', 15:54:53 INFO - 'config_files': ('talos/linux_config.py',), 15:54:53 INFO - 'default_actions': ('clobber', 15:54:53 INFO - 'read-buildbot-config', 15:54:53 INFO - 'download-and-extract', 15:54:53 INFO - 'populate-webroot', 15:54:53 INFO - 'create-virtualenv', 15:54:53 INFO - 'install', 15:54:53 INFO - 'run-tests'), 15:54:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:54:53 INFO - 'download_minidump_stackwalk': True, 15:54:53 INFO - 'download_symbols': 'ondemand', 15:54:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:54:53 INFO - 'tooltool.py': '/tools/tooltool.py', 15:54:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:54:53 INFO - '/tools/misc-python/virtualenv.py')}, 15:54:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:54:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:54:53 INFO - 'installer_path': 'installer.exe', 15:54:53 INFO - 'log_level': 'info', 15:54:53 INFO - 'log_name': 'talos', 15:54:53 INFO - 'log_to_console': True, 15:54:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:54:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:54:53 INFO - 'opt_config_files': (), 15:54:53 INFO - 'pip_index': False, 15:54:53 INFO - 'sps_profile': False, 15:54:53 INFO - 'sps_profile_interval': 0, 15:54:53 INFO - 'suite': 'dromaeojs', 15:54:53 INFO - 'system_bits': '32', 15:54:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:54:53 INFO - 'title': 'talos-linux64-ix-060', 15:54:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:54:53 INFO - 'use_talos_json': True, 15:54:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:54:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:54:53 INFO - 'work_dir': 'build'} 15:54:53 INFO - [mozharness: 2016-08-31 22:54:53.406304Z] Running clobber step. 15:54:53 INFO - Running pre-action listener: _resource_record_pre_action 15:54:53 INFO - Running main action method: clobber 15:54:53 INFO - rmtree: /builds/slave/test/build 15:54:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:54:53 INFO - Running post-action listener: _resource_record_post_action 15:54:53 INFO - [mozharness: 2016-08-31 22:54:53.825131Z] Finished clobber step (success) 15:54:53 INFO - [mozharness: 2016-08-31 22:54:53.825234Z] Running read-buildbot-config step. 15:54:53 INFO - Running pre-action listener: _resource_record_pre_action 15:54:53 INFO - Running main action method: read_buildbot_config 15:54:53 INFO - Using buildbot properties: 15:54:53 INFO - { 15:54:53 INFO - "project": "", 15:54:53 INFO - "product": "firefox", 15:54:53 INFO - "script_repo_revision": "production", 15:54:53 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 15:54:53 INFO - "repository": "", 15:54:53 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 15:54:53 INFO - "buildid": "20160831150821", 15:54:53 INFO - "pgo_build": "False", 15:54:53 INFO - "basedir": "/builds/slave/test", 15:54:53 INFO - "buildnumber": 584, 15:54:53 INFO - "slavename": "talos-linux64-ix-060", 15:54:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:54:53 INFO - "platform": "ubuntu64_hw", 15:54:53 INFO - "scheduler": "tests-autoland-linux64-talos", 15:54:53 INFO - "branch": "autoland", 15:54:53 INFO - "repo_path": "integration/autoland", 15:54:53 INFO - "revision": "dfc059b71968f935273c490d9db8e3bb6173cae3", 15:54:53 INFO - "stage_platform": "linux64", 15:54:53 INFO - "builduid": "88397871fd02443db141eee09d2d8804", 15:54:53 INFO - "slavebuilddir": "test" 15:54:53 INFO - } 15:54:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 15:54:53 INFO - Running post-action listener: _resource_record_post_action 15:54:53 INFO - [mozharness: 2016-08-31 22:54:53.827691Z] Finished read-buildbot-config step (success) 15:54:53 INFO - [mozharness: 2016-08-31 22:54:53.827782Z] Running download-and-extract step. 15:54:53 INFO - Running pre-action listener: _resource_record_pre_action 15:54:53 INFO - Running main action method: download_and_extract 15:54:53 INFO - mkdir: /builds/slave/test/build/tests 15:54:53 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')]} 15:54:53 INFO - trying https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:54:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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 15:54:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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 15:54:54 INFO - Downloaded 1361 bytes. 15:54:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:54:54 INFO - Using the following test package requirements: 15:54:54 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:54:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:54:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:54:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:54:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:54:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:54:54 INFO - u'jsshell-linux-x86_64.zip'], 15:54:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:54:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:54:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:54:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:54:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:54:54 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:54:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:54:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:54:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:54:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:54:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:54:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:54:54 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')]} 15:54:54 INFO - trying https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:54:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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 15:54:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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 15:54:55 INFO - Downloaded 20330846 bytes. 15:54:55 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 15:54:59 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 15:54:59 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')]} 15:54:59 INFO - trying https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:54:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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 15:54:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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 15:55:00 INFO - Downloaded 20330846 bytes. 15:55:00 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 15:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:05 INFO - trying https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:55:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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 15:55:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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 15:55:06 INFO - Downloaded 14413998 bytes. 15:55:06 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 15:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:06 INFO - trying https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:55:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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 15:55:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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 15:55:07 INFO - Downloaded 55867205 bytes. 15:55:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:55:07 INFO - mkdir: /builds/slave/test/properties 15:55:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:55:07 INFO - Writing to file /builds/slave/test/properties/build_url 15:55:07 INFO - Contents: 15:55:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:55:08 INFO - Running post-action listener: _resource_record_post_action 15:55:08 INFO - Running post-action listener: set_extra_try_arguments 15:55:08 INFO - [mozharness: 2016-08-31 22:55:08.594104Z] Finished download-and-extract step (success) 15:55:08 INFO - [mozharness: 2016-08-31 22:55:08.594333Z] Running populate-webroot step. 15:55:08 INFO - Running pre-action listener: _resource_record_pre_action 15:55:08 INFO - Running main action method: populate_webroot 15:55:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:55:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:55:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:55:08 INFO - '--tppagecycles', 15:55:08 INFO - '7'], 15:55:08 INFO - 'tests': ['tsvgm']}}, 15:55:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:55:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:55:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:55:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:55:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:08 INFO - 'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:08 INFO - 'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['damp', 'tps']}, 15:55:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:08 INFO - 'tests': ['damp', 'tps']}, 15:55:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['dromaeo_dom']}, 15:55:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:55:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['basic_compositor_video']}, 15:55:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:55:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['a11yr', 15:55:08 INFO - 'ts_paint', 15:55:08 INFO - 'tpaint', 15:55:08 INFO - 'sessionrestore', 15:55:08 INFO - 'sessionrestore_no_auto_restore', 15:55:08 INFO - 'tabpaint']}, 15:55:08 INFO - 'other-e10s': {'tests': ['a11yr', 15:55:08 INFO - 'ts_paint', 15:55:08 INFO - 'tpaint', 15:55:08 INFO - 'sessionrestore', 15:55:08 INFO - 'sessionrestore_no_auto_restore', 15:55:08 INFO - 'tabpaint']}, 15:55:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:55:08 INFO - 'ts_paint', 15:55:08 INFO - 'tpaint', 15:55:08 INFO - 'sessionrestore', 15:55:08 INFO - 'sessionrestore_no_auto_restore', 15:55:08 INFO - 'tabpaint']}, 15:55:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:55:08 INFO - 'ts_paint', 15:55:08 INFO - 'tpaint', 15:55:08 INFO - 'sessionrestore', 15:55:08 INFO - 'sessionrestore_no_auto_restore', 15:55:08 INFO - 'tabpaint']}, 15:55:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['a11yr', 15:55:08 INFO - 'ts_paint', 15:55:08 INFO - 'tpaint', 15:55:08 INFO - 'sessionrestore', 15:55:08 INFO - 'sessionrestore_no_auto_restore', 15:55:08 INFO - 'tabpaint']}, 15:55:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['a11yr', 15:55:08 INFO - 'ts_paint', 15:55:08 INFO - 'tpaint', 15:55:08 INFO - 'sessionrestore', 15:55:08 INFO - 'sessionrestore_no_auto_restore', 15:55:08 INFO - 'tabpaint']}, 15:55:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['tsvgx', 15:55:08 INFO - 'tsvgr_opacity', 15:55:08 INFO - 'tart', 15:55:08 INFO - 'tscrollx', 15:55:08 INFO - 'cart']}, 15:55:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:55:08 INFO - 'tsvgr_opacity', 15:55:08 INFO - 'tart', 15:55:08 INFO - 'tscrollx', 15:55:08 INFO - 'cart']}, 15:55:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:08 INFO - 'talos_options': ['--disable-e10s'], 15:55:08 INFO - 'tests': ['tp5o']}, 15:55:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:08 INFO - 'tests': ['tp5o']}, 15:55:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:08 INFO - 'talos_options': ['--disable-e10s', 15:55:08 INFO - '--xperf_path', 15:55:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:08 INFO - 'tests': ['tp5n']}, 15:55:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:08 INFO - 'talos_options': ['--xperf_path', 15:55:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:08 INFO - 'tests': ['tp5n']}}, 15:55:08 INFO - 'talos.zip': {'path': '', 15:55:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:55:08 INFO - Running post-action listener: _resource_record_post_action 15:55:08 INFO - [mozharness: 2016-08-31 22:55:08.615961Z] Finished populate-webroot step (success) 15:55:08 INFO - [mozharness: 2016-08-31 22:55:08.616051Z] Running create-virtualenv step. 15:55:08 INFO - Running pre-action listener: _resource_record_pre_action 15:55:08 INFO - Running main action method: create_virtualenv 15:55:08 INFO - Creating virtualenv /builds/slave/test/build/venv 15:55:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:55:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:55:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:55:08 INFO - Using real prefix '/usr' 15:55:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:55:09 INFO - Installing distribute.............................................................................................................................................................................................done. 15:55:11 INFO - Installing pip.................done. 15:55:11 INFO - Return code: 0 15:55:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b2a4cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c20230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, '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': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', '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 15:55:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:55:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:55:11 INFO - Using env: {'DISPLAY': ':0', 15:55:11 INFO - 'HOME': '/home/cltbld', 15:55:11 INFO - 'LANG': 'en_US.UTF-8', 15:55:11 INFO - 'LANGUAGE': 'en_US:en', 15:55:11 INFO - 'LOGNAME': 'cltbld', 15:55:11 INFO - 'MAIL': '/var/mail/cltbld', 15:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:11 INFO - 'MOZ_NO_REMOTE': '1', 15:55:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:11 INFO - 'NO_EM_RESTART': '1', 15:55:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:11 INFO - 'PWD': '/builds/slave/test', 15:55:11 INFO - 'SHELL': '/bin/bash', 15:55:11 INFO - 'SHLVL': '1', 15:55:11 INFO - 'TERM': 'linux', 15:55:11 INFO - 'TMOUT': '86400', 15:55:11 INFO - 'USER': 'cltbld', 15:55:11 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:11 INFO - '_': '/tools/buildbot/bin/python'} 15:55:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:12 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 15:55:12 INFO - Cleaning up... 15:55:12 INFO - Return code: 0 15:55:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:12 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 0x7f4b2a4cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c20230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, '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': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', '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 15:55:12 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 15:55:12 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 15:55:12 INFO - Using env: {'DISPLAY': ':0', 15:55:12 INFO - 'HOME': '/home/cltbld', 15:55:12 INFO - 'LANG': 'en_US.UTF-8', 15:55:12 INFO - 'LANGUAGE': 'en_US:en', 15:55:12 INFO - 'LOGNAME': 'cltbld', 15:55:12 INFO - 'MAIL': '/var/mail/cltbld', 15:55:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:12 INFO - 'MOZ_NO_REMOTE': '1', 15:55:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:12 INFO - 'NO_EM_RESTART': '1', 15:55:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:12 INFO - 'PWD': '/builds/slave/test', 15:55:12 INFO - 'SHELL': '/bin/bash', 15:55:12 INFO - 'SHLVL': '1', 15:55:12 INFO - 'TERM': 'linux', 15:55:12 INFO - 'TMOUT': '86400', 15:55:12 INFO - 'USER': 'cltbld', 15:55:12 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:12 INFO - '_': '/tools/buildbot/bin/python'} 15:55:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:12 INFO - Downloading/unpacking psutil>=3.1.1 15:55:12 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 15:55:12 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 15:55:12 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 15:55:12 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 15:55:12 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 15:55:12 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 15:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:55:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:14 INFO - Installing collected packages: psutil 15:55:14 INFO - Running setup.py install for psutil 15:55:14 INFO - building 'psutil._psutil_linux' extension 15:55:14 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 15:55:15 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 15:55:15 INFO - building 'psutil._psutil_posix' extension 15:55:15 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 15:55:15 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 15:55:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:15 INFO - Successfully installed psutil 15:55:15 INFO - Cleaning up... 15:55:15 INFO - Return code: 0 15:55:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:55:15 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')]} 15:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:15 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')]} 15:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:15 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 0x7f4b2a4cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c20230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, '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': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', '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 15:55:15 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 15:55:15 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 15:55:15 INFO - Using env: {'DISPLAY': ':0', 15:55:15 INFO - 'HOME': '/home/cltbld', 15:55:15 INFO - 'LANG': 'en_US.UTF-8', 15:55:15 INFO - 'LANGUAGE': 'en_US:en', 15:55:15 INFO - 'LOGNAME': 'cltbld', 15:55:15 INFO - 'MAIL': '/var/mail/cltbld', 15:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:15 INFO - 'MOZ_NO_REMOTE': '1', 15:55:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:15 INFO - 'NO_EM_RESTART': '1', 15:55:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:15 INFO - 'PWD': '/builds/slave/test', 15:55:15 INFO - 'SHELL': '/bin/bash', 15:55:15 INFO - 'SHLVL': '1', 15:55:15 INFO - 'TERM': 'linux', 15:55:15 INFO - 'TMOUT': '86400', 15:55:15 INFO - 'USER': 'cltbld', 15:55:15 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:15 INFO - '_': '/tools/buildbot/bin/python'} 15:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:55:15 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 15:55:15 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 15:55:15 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 15:55:15 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 15:55:15 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 15:55:15 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 15:55:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:55:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:55:17 INFO - Installing collected packages: mozsystemmonitor 15:55:17 INFO - Running setup.py install for mozsystemmonitor 15:55:17 INFO - Successfully installed mozsystemmonitor 15:55:17 INFO - Cleaning up... 15:55:18 INFO - Return code: 0 15:55:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b2a4cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c20230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, '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': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', '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 15:55:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:55:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:55:18 INFO - Using env: {'DISPLAY': ':0', 15:55:18 INFO - 'HOME': '/home/cltbld', 15:55:18 INFO - 'LANG': 'en_US.UTF-8', 15:55:18 INFO - 'LANGUAGE': 'en_US:en', 15:55:18 INFO - 'LOGNAME': 'cltbld', 15:55:18 INFO - 'MAIL': '/var/mail/cltbld', 15:55:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:18 INFO - 'MOZ_NO_REMOTE': '1', 15:55:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:18 INFO - 'NO_EM_RESTART': '1', 15:55:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:18 INFO - 'PWD': '/builds/slave/test', 15:55:18 INFO - 'SHELL': '/bin/bash', 15:55:18 INFO - 'SHLVL': '1', 15:55:18 INFO - 'TERM': 'linux', 15:55:18 INFO - 'TMOUT': '86400', 15:55:18 INFO - 'USER': 'cltbld', 15:55:18 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:18 INFO - '_': '/tools/buildbot/bin/python'} 15:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:18 INFO - Downloading/unpacking jsonschema==2.5.1 15:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:55:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:55:20 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 15:55:20 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 15:55:20 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 15:55:20 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 15:55:20 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 15:55:20 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 15:55:20 INFO - Downloading functools32-3.2.3-2.tar.gz 15:55:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:55:20 INFO - warning: no files found matching '*.txt' 15:55:20 INFO - no previously-included directories found matching 'build' 15:55:20 INFO - no previously-included directories found matching 'dist' 15:55:20 INFO - no previously-included directories found matching '.git*' 15:55:20 INFO - Installing collected packages: jsonschema, functools32 15:55:20 INFO - Running setup.py install for functools32 15:55:20 INFO - warning: no files found matching '*.txt' 15:55:20 INFO - no previously-included directories found matching 'build' 15:55:20 INFO - no previously-included directories found matching 'dist' 15:55:20 INFO - no previously-included directories found matching '.git*' 15:55:20 INFO - Successfully installed jsonschema functools32 15:55:20 INFO - Cleaning up... 15:55:20 INFO - Return code: 0 15:55:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:55:20 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')]} 15:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:20 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')]} 15:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:20 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 0x7f4b2a4cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c20230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, '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': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', '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 15:55:20 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 15:55:20 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 15:55:20 INFO - Using env: {'DISPLAY': ':0', 15:55:20 INFO - 'HOME': '/home/cltbld', 15:55:20 INFO - 'LANG': 'en_US.UTF-8', 15:55:20 INFO - 'LANGUAGE': 'en_US:en', 15:55:20 INFO - 'LOGNAME': 'cltbld', 15:55:20 INFO - 'MAIL': '/var/mail/cltbld', 15:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:20 INFO - 'MOZ_NO_REMOTE': '1', 15:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:20 INFO - 'NO_EM_RESTART': '1', 15:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:20 INFO - 'PWD': '/builds/slave/test', 15:55:20 INFO - 'SHELL': '/bin/bash', 15:55:20 INFO - 'SHLVL': '1', 15:55:20 INFO - 'TERM': 'linux', 15:55:20 INFO - 'TMOUT': '86400', 15:55:20 INFO - 'USER': 'cltbld', 15:55:20 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:20 INFO - '_': '/tools/buildbot/bin/python'} 15:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:55:20 INFO - Cleaning up... 15:55:20 INFO - Return code: 0 15:55:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:55:20 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')]} 15:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:20 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')]} 15:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:20 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 0x7f4b2a4cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c20230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, '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': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', '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 15:55:20 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 15:55:20 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 15:55:20 INFO - Using env: {'DISPLAY': ':0', 15:55:20 INFO - 'HOME': '/home/cltbld', 15:55:20 INFO - 'LANG': 'en_US.UTF-8', 15:55:20 INFO - 'LANGUAGE': 'en_US:en', 15:55:20 INFO - 'LOGNAME': 'cltbld', 15:55:20 INFO - 'MAIL': '/var/mail/cltbld', 15:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:20 INFO - 'MOZ_NO_REMOTE': '1', 15:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:20 INFO - 'NO_EM_RESTART': '1', 15:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:20 INFO - 'PWD': '/builds/slave/test', 15:55:20 INFO - 'SHELL': '/bin/bash', 15:55:20 INFO - 'SHLVL': '1', 15:55:20 INFO - 'TERM': 'linux', 15:55:20 INFO - 'TMOUT': '86400', 15:55:20 INFO - 'USER': 'cltbld', 15:55:20 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:20 INFO - '_': '/tools/buildbot/bin/python'} 15:55:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:21 INFO - Downloading/unpacking blobuploader==1.2.4 15:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:22 INFO - Downloading blobuploader-1.2.4.tar.gz 15:55:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:55:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:55:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:23 INFO - Downloading docopt-0.6.1.tar.gz 15:55:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:55:23 INFO - Installing collected packages: blobuploader, requests, docopt 15:55:23 INFO - Running setup.py install for blobuploader 15:55:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:55:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:55:23 INFO - Running setup.py install for requests 15:55:24 INFO - Running setup.py install for docopt 15:55:24 INFO - Successfully installed blobuploader requests docopt 15:55:24 INFO - Cleaning up... 15:55:24 INFO - Return code: 0 15:55:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:24 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 0x7f4b2a4cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c20230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, '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': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', '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 15:55:24 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 15:55:24 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 15:55:24 INFO - Using env: {'DISPLAY': ':0', 15:55:24 INFO - 'HOME': '/home/cltbld', 15:55:24 INFO - 'LANG': 'en_US.UTF-8', 15:55:24 INFO - 'LANGUAGE': 'en_US:en', 15:55:24 INFO - 'LOGNAME': 'cltbld', 15:55:24 INFO - 'MAIL': '/var/mail/cltbld', 15:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:24 INFO - 'MOZ_NO_REMOTE': '1', 15:55:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:24 INFO - 'NO_EM_RESTART': '1', 15:55:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:24 INFO - 'PWD': '/builds/slave/test', 15:55:24 INFO - 'SHELL': '/bin/bash', 15:55:24 INFO - 'SHLVL': '1', 15:55:24 INFO - 'TERM': 'linux', 15:55:24 INFO - 'TMOUT': '86400', 15:55:24 INFO - 'USER': 'cltbld', 15:55:24 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:24 INFO - '_': '/tools/buildbot/bin/python'} 15:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:24 INFO - Running setup.py (path:/tmp/pip-nZ5JEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:24 INFO - Running setup.py (path:/tmp/pip-uGF1Zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:24 INFO - Running setup.py (path:/tmp/pip-ZvITvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:24 INFO - Running setup.py (path:/tmp/pip-fz08cC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:24 INFO - Running setup.py (path:/tmp/pip-MYPW1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:24 INFO - Running setup.py (path:/tmp/pip-x6vrrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:24 INFO - Running setup.py (path:/tmp/pip-K5yP0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:25 INFO - Running setup.py (path:/tmp/pip-GS23Te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:25 INFO - Running setup.py (path:/tmp/pip-PDdK_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:25 INFO - Running setup.py (path:/tmp/pip-9OinXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:25 INFO - Running setup.py (path:/tmp/pip-snIyVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:25 INFO - Running setup.py (path:/tmp/pip-feVUnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:25 INFO - Running setup.py (path:/tmp/pip-9EL5En-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:25 INFO - Running setup.py (path:/tmp/pip-M0KBuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:25 INFO - Running setup.py (path:/tmp/pip-lDE3WY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:25 INFO - Running setup.py (path:/tmp/pip-DvjvFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:25 INFO - Running setup.py (path:/tmp/pip-EYpNRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:55:25 INFO - Running setup.py install for manifestparser 15:55:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:55:25 INFO - Running setup.py install for mozcrash 15:55:25 INFO - Running setup.py install for mozdebug 15:55:26 INFO - Running setup.py install for mozdevice 15:55:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for mozfile 15:55:26 INFO - Running setup.py install for mozhttpd 15:55:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for mozinfo 15:55:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for mozInstall 15:55:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for mozleak 15:55:26 INFO - Running setup.py install for mozlog 15:55:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for moznetwork 15:55:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for mozprocess 15:55:27 INFO - Running setup.py install for mozprofile 15:55:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Running setup.py install for mozrunner 15:55:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Running setup.py install for mozscreenshot 15:55:27 INFO - Running setup.py install for moztest 15:55:27 INFO - Running setup.py install for mozversion 15:55:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:55:27 INFO - Cleaning up... 15:55:27 INFO - Return code: 0 15:55:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55: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')]} 15:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55: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')]} 15:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:27 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 0x7f4b2a4cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c20230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, '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': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', '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 15:55:27 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 15:55:27 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 15:55:27 INFO - Using env: {'DISPLAY': ':0', 15:55:27 INFO - 'HOME': '/home/cltbld', 15:55:27 INFO - 'LANG': 'en_US.UTF-8', 15:55:27 INFO - 'LANGUAGE': 'en_US:en', 15:55:27 INFO - 'LOGNAME': 'cltbld', 15:55:27 INFO - 'MAIL': '/var/mail/cltbld', 15:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:27 INFO - 'MOZ_NO_REMOTE': '1', 15:55:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:27 INFO - 'NO_EM_RESTART': '1', 15:55:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:27 INFO - 'PWD': '/builds/slave/test', 15:55:27 INFO - 'SHELL': '/bin/bash', 15:55:27 INFO - 'SHLVL': '1', 15:55:27 INFO - 'TERM': 'linux', 15:55:27 INFO - 'TMOUT': '86400', 15:55:27 INFO - 'USER': 'cltbld', 15:55:27 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:27 INFO - '_': '/tools/buildbot/bin/python'} 15:55:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:27 INFO - Running setup.py (path:/tmp/pip-ToWlyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:27 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)) 15:55:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:27 INFO - Running setup.py (path:/tmp/pip-GiB3qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:27 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)) 15:55:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:27 INFO - Running setup.py (path:/tmp/pip-pZvwl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:28 INFO - Running setup.py (path:/tmp/pip-hAtbxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:28 INFO - Running setup.py (path:/tmp/pip-xQdfN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:28 INFO - Running setup.py (path:/tmp/pip-cNG7lL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:28 INFO - Running setup.py (path:/tmp/pip-9dMWlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:28 INFO - Running setup.py (path:/tmp/pip-F6jQOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:28 INFO - Running setup.py (path:/tmp/pip-vV6jCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:28 INFO - Running setup.py (path:/tmp/pip-6tBYTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:28 INFO - Running setup.py (path:/tmp/pip-c7c6aA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:28 INFO - Running setup.py (path:/tmp/pip-A4_WL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:28 INFO - Running setup.py (path:/tmp/pip-IahWGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:28 INFO - Running setup.py (path:/tmp/pip-x6ovwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:28 INFO - Running setup.py (path:/tmp/pip-V_jFmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:28 INFO - Running setup.py (path:/tmp/pip-nWEhJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:28 INFO - Running setup.py (path:/tmp/pip-3JAaVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:55:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:30 INFO - Downloading blessings-1.6.tar.gz 15:55:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:55:31 INFO - Installing collected packages: blessings 15:55:31 INFO - Running setup.py install for blessings 15:55:31 INFO - Successfully installed blessings 15:55:31 INFO - Cleaning up... 15:55:31 INFO - Return code: 0 15:55:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:55:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:31 INFO - Reading from file tmpfile_stdout 15:55:31 INFO - Current package versions: 15:55:31 INFO - argparse == 1.2.1 15:55:31 INFO - blessings == 1.6 15:55:31 INFO - blobuploader == 1.2.4 15:55:31 INFO - docopt == 0.6.1 15:55:31 INFO - functools32 == 3.2.3-2 15:55:31 INFO - jsonschema == 2.5.1 15:55:31 INFO - manifestparser == 1.1 15:55:31 INFO - mozInstall == 1.12 15:55:31 INFO - mozcrash == 0.17 15:55:31 INFO - mozdebug == 0.1 15:55:31 INFO - mozdevice == 0.48 15:55:31 INFO - mozfile == 1.2 15:55:31 INFO - mozhttpd == 0.7 15:55:31 INFO - mozinfo == 0.9 15:55:31 INFO - mozleak == 0.1 15:55:31 INFO - mozlog == 3.3 15:55:31 INFO - moznetwork == 0.27 15:55:31 INFO - mozprocess == 0.23 15:55:31 INFO - mozprofile == 0.28 15:55:31 INFO - mozrunner == 6.12 15:55:31 INFO - mozscreenshot == 0.1 15:55:31 INFO - mozsystemmonitor == 0.3 15:55:31 INFO - moztest == 0.7 15:55:31 INFO - mozversion == 1.4 15:55:31 INFO - psutil == 3.1.1 15:55:31 INFO - requests == 1.2.3 15:55:31 INFO - wsgiref == 0.1.2 15:55:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:31 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')]} 15:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:31 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')]} 15:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:31 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 0x7f4b2a4cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c20230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, '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': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', '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 15:55:31 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 15:55:31 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 15:55:31 INFO - Using env: {'DISPLAY': ':0', 15:55:31 INFO - 'HOME': '/home/cltbld', 15:55:31 INFO - 'LANG': 'en_US.UTF-8', 15:55:31 INFO - 'LANGUAGE': 'en_US:en', 15:55:31 INFO - 'LOGNAME': 'cltbld', 15:55:31 INFO - 'MAIL': '/var/mail/cltbld', 15:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:31 INFO - 'MOZ_NO_REMOTE': '1', 15:55:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:31 INFO - 'NO_EM_RESTART': '1', 15:55:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:31 INFO - 'PWD': '/builds/slave/test', 15:55:31 INFO - 'SHELL': '/bin/bash', 15:55:31 INFO - 'SHLVL': '1', 15:55:31 INFO - 'TERM': 'linux', 15:55:31 INFO - 'TMOUT': '86400', 15:55:31 INFO - 'USER': 'cltbld', 15:55:31 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:31 INFO - '_': '/tools/buildbot/bin/python'} 15:55:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:31 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)) 15:55:31 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)) 15:55:31 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)) 15:55:31 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)) 15:55:31 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)) 15:55:31 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)) 15:55:31 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)) 15:55:31 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)) 15:55:31 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)) 15:55:31 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)) 15:55:31 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)) 15:55:31 INFO - Cleaning up... 15:55:31 INFO - Return code: 0 15:55:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:55:31 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')]} 15:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:31 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')]} 15:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:31 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 0x7f4b2a4cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c20230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, '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': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', '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 15:55:31 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 15:55:31 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 15:55:31 INFO - Using env: {'DISPLAY': ':0', 15:55:31 INFO - 'HOME': '/home/cltbld', 15:55:31 INFO - 'LANG': 'en_US.UTF-8', 15:55:31 INFO - 'LANGUAGE': 'en_US:en', 15:55:31 INFO - 'LOGNAME': 'cltbld', 15:55:31 INFO - 'MAIL': '/var/mail/cltbld', 15:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:31 INFO - 'MOZ_NO_REMOTE': '1', 15:55:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:31 INFO - 'NO_EM_RESTART': '1', 15:55:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:31 INFO - 'PWD': '/builds/slave/test', 15:55:31 INFO - 'SHELL': '/bin/bash', 15:55:31 INFO - 'SHLVL': '1', 15:55:31 INFO - 'TERM': 'linux', 15:55:31 INFO - 'TMOUT': '86400', 15:55:31 INFO - 'USER': 'cltbld', 15:55:31 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:31 INFO - '_': '/tools/buildbot/bin/python'} 15:55:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:55:31 INFO - Cleaning up... 15:55:31 INFO - Return code: 0 15:55:31 INFO - Running post-action listener: _resource_record_post_action 15:55:31 INFO - Running post-action listener: _start_resource_monitoring 15:55:31 INFO - Starting resource monitoring. 15:55:31 INFO - [mozharness: 2016-08-31 22:55:31.927253Z] Finished create-virtualenv step (success) 15:55:31 INFO - [mozharness: 2016-08-31 22:55:31.928317Z] Running install step. 15:55:31 INFO - Running pre-action listener: _resource_record_pre_action 15:55:31 INFO - Running main action method: install 15:55:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:32 INFO - Reading from file tmpfile_stdout 15:55:32 INFO - Detecting whether we're running mozinstall >=1.0... 15:55:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:55:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:55:32 INFO - Reading from file tmpfile_stdout 15:55:32 INFO - Output received: 15:55:32 INFO - Usage: mozinstall [options] installer 15:55:32 INFO - Options: 15:55:32 INFO - -h, --help show this help message and exit 15:55:32 INFO - -d DEST, --destination=DEST 15:55:32 INFO - Directory to install application into. [default: 15:55:32 INFO - "/builds/slave/test"] 15:55:32 INFO - --app=APP Application being installed. [default: firefox] 15:55:32 INFO - mkdir: /builds/slave/test/build/application 15:55:32 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'] 15:55:32 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 15:55:48 INFO - Reading from file tmpfile_stdout 15:55:48 INFO - Output received: 15:55:48 INFO - /builds/slave/test/build/application/firefox/firefox 15:55:48 INFO - Running post-action listener: _resource_record_post_action 15:55:48 INFO - [mozharness: 2016-08-31 22:55:48.371060Z] Finished install step (success) 15:55:48 INFO - [mozharness: 2016-08-31 22:55:48.371346Z] Running run-tests step. 15:55:48 INFO - Running pre-action listener: _resource_record_pre_action 15:55:48 INFO - Running main action method: run_tests 15:55:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:55:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:55:48 INFO - Python 2.7.3 15:55:48 INFO - Return code: 0 15:55:48 INFO - grabbing minidump binary from tooltool 15:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:48 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c1a090>, '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 0x2c1d9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c1de40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:55:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:55:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:55:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:55:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:55:48 INFO - Return code: 0 15:55:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:55:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:55:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:55:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:55:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:55:48 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/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 15:55:48 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/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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 15:55:48 INFO - Using env: {'DISPLAY': ':0', 15:55:48 INFO - 'HOME': '/home/cltbld', 15:55:48 INFO - 'LANG': 'en_US.UTF-8', 15:55:48 INFO - 'LANGUAGE': 'en_US:en', 15:55:48 INFO - 'LOGNAME': 'cltbld', 15:55:48 INFO - 'MAIL': '/var/mail/cltbld', 15:55:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:55:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:48 INFO - 'MOZ_NO_REMOTE': '1', 15:55:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:55:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:48 INFO - 'NO_EM_RESTART': '1', 15:55:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:48 INFO - 'PWD': '/builds/slave/test', 15:55:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:55:48 INFO - 'SHELL': '/bin/bash', 15:55:48 INFO - 'SHLVL': '1', 15:55:48 INFO - 'TERM': 'linux', 15:55:48 INFO - 'TMOUT': '86400', 15:55:48 INFO - 'USER': 'cltbld', 15:55:48 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797', 15:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:48 INFO - '_': '/tools/buildbot/bin/python'} 15:55:48 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/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 15:55:48 INFO - mozversion application_buildid: 20160831150821 15:55:48 INFO - mozversion application_changeset: dfc059b71968f935273c490d9db8e3bb6173cae3 15:55:48 INFO - mozversion application_display_name: Nightly 15:55:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:55:48 INFO - mozversion application_name: Firefox 15:55:48 INFO - mozversion application_remotingname: firefox 15:55:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:55:48 INFO - mozversion application_vendor: Mozilla 15:55:48 INFO - mozversion application_version: 51.0a1 15:55:48 INFO - mozversion platform_buildid: 20160831150821 15:55:48 INFO - mozversion platform_changeset: dfc059b71968f935273c490d9db8e3bb6173cae3 15:55:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:55:48 INFO - mozversion platform_version: 51.0a1 15:55:48 INFO - using testdate: 1472684148 15:55:48 INFO - actual date: 1472684148 15:55:48 INFO - starting webserver on 'localhost:41299' 15:55:48 INFO - SUITE-START | Running 2 tests 15:55:48 INFO - TEST-START | dromaeo_css 15:55:48 INFO - operating with platform_type : linux_ 15:55:48 INFO - Initialising browser for dromaeo_css test... 15:55:48 INFO - TEST-INFO | started process 3294 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4PL3dG/profile http://localhost:41299/getInfo.html) 15:55:53 INFO - PROCESS | 3294 | __metrics Screen width/height:1600/1200 15:55:53 INFO - PROCESS | 3294 | colorDepth:24 15:55:53 INFO - PROCESS | 3294 | Browser inner width/height: 1024/697 15:55:53 INFO - PROCESS | 3294 | __metrics 15:55:54 INFO - PROCESS | 3294 | 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" 15:55:54 INFO - PROCESS | 3294 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:55:55 INFO - TEST-INFO | 3294: exit 0 15:55:55 INFO - Browser initialized. 15:55:55 INFO - Running cycle 1/1 for dromaeo_css test... 15:55:55 INFO - TEST-INFO | started process 3374 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4PL3dG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:56:07 INFO - PROCESS | 3374 | RSS: Main: 182263808 15:56:07 INFO - PROCESS | 3374 | 15:56:59 INFO - PROCESS | 3374 | 1472684219475 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] 15:59:11 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:41299/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41299/tests/dromaeo/cssquery-ext.html) 15:59:12 INFO - PROCESS | 3374 | RSS: Main: 266391552 15:59:12 INFO - PROCESS | 3374 | 16:02:15 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:41299/tests/dromaeo/cssquery-ext.html (next: http://localhost:41299/tests/dromaeo/cssquery-jquery.html) 16:02:16 INFO - PROCESS | 3374 | RSS: Main: 275701760 16:02:16 INFO - PROCESS | 3374 | 16:05:18 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:41299/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41299/tests/dromaeo/cssquery-mootools.html) 16:05:19 INFO - PROCESS | 3374 | RSS: Main: 219136000 16:05:19 INFO - PROCESS | 3374 | 16:08:21 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:41299/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41299/tests/dromaeo/cssquery-prototype.html) 16:08:21 INFO - PROCESS | 3374 | RSS: Main: 219648000 16:08:21 INFO - PROCESS | 3374 | 16:11:25 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:41299/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41299/tests/dromaeo/cssquery-yui.html) 16:11:25 INFO - PROCESS | 3374 | RSS: Main: 219799552 16:11:25 INFO - PROCESS | 3374 | 16:14:28 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:41299/tests/dromaeo/cssquery-yui.html (next: http://localhost:41299/tests/dromaeo/cssquery-dojo.html) 16:14:28 INFO - PROCESS | 3374 | RSS: Main: 312619008 16:14:28 INFO - PROCESS | 3374 | 16:14:28 INFO - PROCESS | 3374 | __start_tp_report 16:14:28 INFO - PROCESS | 3374 | _x_x_mozilla_page_load 16:14:28 INFO - PROCESS | 3374 | _x_x_mozilla_page_load_details 16:14:28 INFO - PROCESS | 3374 | |i|pagename|runs| 16:14:28 INFO - PROCESS | 3374 | |0;dojo.html;2563.7820785233253;2566.0762125634774;2594.4551644154726;2599.974000259998;2616.2805228562142;4559.384483094789;4640.211164102102;4642.32686260492;4719.174144524707;4768.952310476894;4622.352870598119;4627.3984382030285;4638.234691275936;4656.557627025427;4674.345591617168;4207.810648520808;4218.6203241708245;4308.353746937969;4338.97830510847;4386.166628340621;4315.503717072545;4346.261135606938;4386.824527018915;4397.802098905541;4399.472063352402;4218;4259.382389553529;4277.700560960734;4281.97859010703;4373.693841431101;4203.369494575808;4249.1714115747445;4290.485141783006;4297.290946993742;4307.353896915456;4233.322668373076;4253.3619957006385;4266.466691663542;4358.433403657536;4364.192624364503;4668.276417155346;4690.7654617269;4698.718076915362;4698.976505117486;4752.358431611723;4875.634327425421;4944.184209605411;4995.750212489397;5002.874928126789;5031.522005409517;4987.975060124712;5001.474845141281;5013.849584512471;5016.147254966664;5078.61910356721;4678.532146785328;4704.788284527204;4709.340692303081;4709.387779588632;4717.127331443694;4978.8755281118265;5021;5022.096022715946;5045.217991211368;5136.049830781318;4815.518448155227;4841.194742656689;4846;4849.80600775965;4897.338859253955;4931.211006238985;4932.235503496964;4934.210526315772;4934.8766280843265;4983.875403114878;4812.35033270511;4871.050145221648;4909.386326709161;4916.631252656065;4921.163402221631;4907.828226012072;4913.213885778258;4914.459409464956;4917.557419832161;4940.7035577865445;4150.481189851269;4151.647109995684;4153.6677065834665;4198.181354635878;4202.075543380452;3608.7473876828894;3615.060084378081;3634.055145662147;3638;3690.095926498025;4275.080857615628;4292.141571685675;4331.4152589400655;4352.39066530686;4354.433923589913;4990.3762029746285;4995.400551933719;5001.574866136347;5006.499350064964;5066.620003499752;4166.687498437629;4236.661067114624;4277.893052673646;4298.09739954613;4303.569643035671;4412.735235885857;4426.247537918562;4485.215087359699;4504.324351347259;4511.932321015187;4649.86050418488;4668.0430511744335;4671.626269898332;4677.368555244999;4730.574248317668;4835.2505361669;4962.826301079445;4987.326710894128;4992.850214493571;5000.324956131021;4704.717716936994;4748.216544270157;4753.95246047549;4760.190767569452;4763.476017638126;4953.504649535162;4956.950430495793;4961;4966.726830024383;5032.6980381177245;4810.182269014346;4973.079980203674;5037.92443113361;5041.395032596112;5098.566621837185;4806.158922188673;4872.43966943806;4878.902421951612;4880.487548807466;4936.284238785284;4866.172750632471;4889.2910527972535;4889.828855990023;4889.853304400874;4949.604031677385;4669.60308373792;4808.783604737865;4818.71087734737;4836.758162091952;4873.317735517101;4807.254875494304;4824.49342818999;4856.878578035577;4869.853904382874;4947.505249475023;4760.333553302471;4767.523247675204;4769.66612337133;4770.522947705202;4829.73436460998 16:14:28 INFO - PROCESS | 3374 | |1;ext.html;556.5825630776917;564.7007086244289;570.0167211560059;578.3291381996885;579.4611011759065;27382.178534643943;29894;37442.81278593607;38682.806585967104;38695.226095478145;39680.20639587207;40127.59872401275;40186.59813401865;40198;40276.39585406221;7580.772576822704;7727.38180945523;7733.883991740128;7735.187805280449;7736.883946740775;7117.181524124714;7443.739469118582;9509.667161649344;9848.950755246215;9857.260705447085;9615.759606009835;9684.806303873918;9690.127888490035;9692.07925247104;9695.321327507078;9787.755306117333;9836.163926066292;9841.950790246003;9851.655192068314;9854.40873547586;9752.26857985654;9824.852627210597;9826.754331141707;9828.459434731087;9830.606775728962;36150.81924590386;36406.271874562626;36557.45163822545;36675.449868252;36710.26579468395;2763.115802943059;2843.516602177614;2912.7815413843837;3373.308471763283;3525.700315473188;27577;27720.584191237147;28103;28175.436491270266;29119.85440072786;3177.5551422800827;3187.9203019924457;3220.935581288361;3237.465818140004;3374.3926093303194;248484;249274;249587;249721;249784.9999999982;30030.699693003226;30889.69110308868;47738.52261477341;47985;48080.27879581809;32759.181020474203;32840;33022.17444563905;33034.66965330364;33044.339113217604;12095;12206.450709718085;12206.572769953009;12224.816627750592;12231.510739570318;9600.66397676078;9683.66107186245;9717.854232186522;9731.805363892825;9732;7507.8498430031095;7730.304272615492;7739.148693643695;7792.844143117106;7868.567228802356;3028.4094601552924;3062.494688376393;3082.8766849326225;3100.255938574771;3148.5592017117624;804.7947773317767;897.555709923579;911.7766147293822;919.9310051746146;946.4794363100267;26659.33351666185;27128;27793.444131117263;29098.854505727337;29654.258643534085;909.2771246858763;914.4284821986258;944.1927152284835;954.9761255968656;1015.3806178231273;13317.66705832362;14420.855791442162;14446.85553144476;14580.635484112981;14673.413063477341;401086;406470;407225;407406;408506;45565.08869822585;45829.31256031162;45835.54164458379;45900.540994590294;45983.77008115005;1094.0864378244096;1103.87857335693;1107.224942540209;1122.0294445304753;1166.1661911733152;19371;24567.631485528087;25362.239132826046;27275.181744547695;27656.86171569129;43713;44528.77735611301;44658;44824.55175448204;44913;353612;354965;356567;356589;356828;23738.287851364486;23941.401464963514;24148.87925560431;24196.39509012289;24477.87761061183;40216.396754048124;40579;40665.59334406618;41070.794646027774;41459.7927010363;33689.831550842086;34178.14554636154;34516.309673806885;37526.43710344292;38445.6155438442;33750.49374259437;33989.32021359509;34377.65622343744;34575.30849382948;34754.82622586871;33916.660833392336;34263.657363427046;34508.79219227315;34946.30107397889;35373.82313088521;28633;32158.19604509906;32451.350972979937;32525.837370812995;32987.67012329942;24890.253292401256;24984.500309994062;25111.623325649765;25133;25482.108126215495 16:14:28 INFO - PROCESS | 3374 | |2;jquery.html;349.8600559776091;367.3552914634817;373.67303609341826;377.5092379906121;390.9374500079988;12149.271043737372;15903.920480397595;16020.59948501288;16062.196890155505;16963.57591060224;13343.399547020383;13547.864521354784;13932.860671393282;14102.576922692335;14203;4555.794989225493;4912.70523768575;4978.800847966077;5129.743512824362;5145.794168233266;4988.900221995558;5110.66780659256;5113.718745468997;5117.820876269331;5118.6416950813455;4810.591099756507;5074.188129899198;5085.0338435697095;5085.110105731501;5085.669431486942;5046.924296135542;5128.948710512903;5129.692218466904;5134.717590532519;5138.511841375063;4377.956220437819;4630.259158534618;4630.305454181899;4634.050019745944;4635.142498637762;19855.90072049645;20377.388678339674;20530.17879284827;20895.5820883583;20943.790562094335;1853.8516918646476;1872.5131465818986;1955.8630895837298;2050.8105298926585;2164.512984578473;12857.935710321388;12878.742425151539;13101.803472947913;13605.931970340183;13815.447382104703;1620.602952276688;1681.201429321075;1684.3178512702375;1690.9323627054903;1742.259539695637;68468.3153168467;69258.65370673165;69482.65258673675;69655.65172174157;70419.64790176068;9733.367331123454;9816.214702823758;9958.601655933682;10023.398596084258;10155.492225388849;13428.731425371438;13429.731405371838;13434.790868821852;13440.865591344156;13456.865431345757;6728.125343705287;6737.494687898427;6868.884540117417;6917;6968.3728464438445;6515.706793194223;6575.210974683068;6757.459403247728;6789.490788190906;6822;4192;4322.330038843984;4335.804888780012;4346.913061738811;4466.6873318867365;1808.9005104719367;1851.129968914608;1852.7869295030973;1856.5080253732772;1865.5988962373156;743.22333161846;838.266516797802;847.5592691800352;910.4218821048681;928.8467402878449;16205.837941620433;16465.506034818976;16855.241514131892;17573.20920558578;17615.64768704619;842.3651941870808;883.394874510962;892.8258989496986;909.1584342271358;953.1183655118987;9182.540872956326;9433.858492122623;9632.47021413815;9811.950940245395;9822.557984890696;368764;371048;381112;381631;383801;75092;75990;78515.60742196253;80164.59917700374;80229;908.3323757038571;916.8808054952947;925.6436272035315;930.3673502018557;936.4802534593366;14552.345144468736;14634.195119268543;14996.775048374502;15255;15528.835337349517;34927.9521614352;35620.46569301411;35848.82075589606;36316;36417;46843.29735053909;48983.755081224364;50458.24312635387;50549.74725126351;50592.49407506025;7640.656170472454;7810.140884502814;7812.492188007761;7893.131755506774;7899.565523896241;9391.906080939103;11129.16531260175;11279.210455268052;11430.028447581715;12153.331566763913;10232.386056836614;10422.530986105492;10433.113185379329;10585.776986832543;10724.731881702708;10005.799884002425;10356.948215258875;10380.480975951323;10382.636607718694;10404.47977601132;10049.949750251495;10291.742706432398;10431.16550675929;10496.895031049897;10805.189610779065;7383.741569045058;7849.882251766114;7876.763697089096;8077.555734434663;8182.549959752271;8816.559172041243;10013;10091.899081009096;10269.486525673537;10511.369317840648 16:14:28 INFO - PROCESS | 3374 | |3;mootools.html;1202.3387137074608;1310.55441150009;1352.188686787927;1396.7206558688258;1636.7217573012588;6504.44712199463;7625.123110842254;7795.922040779584;7848.764537063883;8107;7874.291313781759;7933.523988560691;7935.9603201984055;7944.563049032301;7951.40364472664;2207.2716003718733;2235.172985995176;2236.083205885587;2236.340279617517;2238.820894328458;2149.8065174134326;2192.8355373347063;2207.7350717913873;2208.7901649343285;2211.635080211763;2135.9786402135937;2144.185209620342;2147.849650524456;2148.6239908016037;2197.9340619781433;2111.419359676086;2115.788421157887;2126.3089495913814;2131.6482780341225;2134.7011418401435;2069.7205877206534;2160.5030842906062;2181.2801775414077;2188.8577242479187;2190.69330293759;7862.213778622149;7913.920860791433;7940.483868548526;7940.523568585845;7941.36469082472;4284.785760711952;4415.315626077963;4428.247197976351;4429.247028005247;4432.933505997413;2976.300569366197;3105.503119500869;3135.04381163745;3145.339478709474;3161.525771134325;940.6378544260441;959.7840485890689;960.5581432541039;978.9510524473748;983.4738414947968;10775.892241077567;10800.675979720621;10834.566617335297;10835.674929752042;10924.726881828017;4100.220958017967;4137.358709400048;4196.5803419658405;4197.118605092904;4244.2996905510245;8637.179467950535;8643.394962352575;8653.221210091122;8655.26430253423;8664.133586641285;4523.208438523246;4527.4340707411575;4527.705699129546;4529.252673308933;4533.365328853963;3642.5993140754495;3727.3663477208397;4104.958950410517;4199.160167966358;4230.365445183247;3414.334204830034;3454.896353109411;3527.647235276503;3538.6992105671306;3568.5004099425582;1040.2094408249634;1059.2267644619317;1062.5377960587016;1065.504540388723;1071.844582535528;702.364360253971;776.0454640791745;779.6998155710093;802.9718959836378;859.5487369131256;5247.501487358695;5267.420583735786;5462.890742185134;5535.363433205233;5536.114221724505;765.276813411321;793.714262865368;814.8207394373228;837.0833936839273;862.5816479007676;3577.8211089445945;3783.1109689223013;3835.290471262825;3850.3454412749898;3870.4000879863665;9754.365966212174;10156.593736250466;10184.287099903084;10184.338018028804;10201.38791672502;10107.98920107998;10113.595456181818;10134.138598219086;10145.528898309703;10155.339902906435;7122.537035092702;7294.124705035323;7321.414286857145;7324.414046876237;7335.4131669466315;5205.219217117311;5246.344206974129;5349.545288650508;5479;5551.25058117149;8801.16388943075;8950.373473856767;9051.954740226256;9068.954655226682;9196.586153623237;9238.722838314861;9340.112689294507;9397.85903211466;9418.199453046567;9436.292278079309;6359.173307470184;6463.967680161569;6497.382748638872;6500.219973603198;6511.120998665309;7315.926840731525;7371;7378.815529611803;7477.775666730006;7575.393968482618;6639;6646.302138275605;6684.43182329488;6710.16122984627;6724.865502689821;6326.9683651581445;6432.710528026344;6459.289478157305;6628.668566571556;6630.171228596426;6085.695715214133;6285.811425657238;6354.3963323484195;6496.74013039492;6515;4620.768961551977;4662.510436404129;4755.83354582588;4762.857114286577;4837.95162048375;4535.931961020521;4616;4662.53374662531;4668.066386722601;4668.416447944007 16:14:28 INFO - PROCESS | 3374 | |4;prototype.html;294.25553350024444;299.707784909713;303.11792683291617;307.68308642098646;309.0373486589275;4716.528347165286;4723.740194289313;4738.834140805073;4745.976270118645;4751.8812029699275;4657.976710116444;4681.85954421367;4682.648801339905;4689.32004859296;4692.85921422357;3760.75555088919;3762.3039737648487;3769.4722738816586;3778.8299526521237;3799.164183879542;3672.614375490575;3781.825450366037;3791.829367678461;3798.8670396536127;3805.6574908258253;3717.7955212463303;3722.3113723961155;3724.888253352404;3742.0644838790304;3762.322781899257;3694.371956767356;3714.3685573452444;3716.6840818530186;3719.702423806089;3721.4603882436895;3097.2832600879037;3186.4423725847882;3285.129440698217;3299.4226010448315;3322.5514555534937;4535.591796738277;4548.0903819235955;4563.931541026887;4588.105319462706;4601.976990115061;4227.302495088306;4247.939749221625;4258.5528519505315;4262.936055959163;4268.658507319407;4633.49031606523;4734.455537613184;4931.991485194028;4932.865440948566;4981.181868617965;3591.1560783215923;3609.2601016791496;3611.2416392557593;3621.3481573316794;3621.9275614487565;5154.793808247666;5167.398106586932;5169.896602067975;5174.818881339173;5235.685858848519;3638.1086633774894;3710.1263861750185;3732.346839303111;3739.9252014959548;3743.981280093582;3747.8126093695205;3757.1734218471893;3759.191773768653;3764.134249122717;3782.7705995551555;4216.831326746896;4226.598473145046;4256.851010214689;4258.382534532538;4262.957370426318;4872;4881.975590122027;4897.583705385011;4902.28916807061;4903.975480122648;1233.1183204009176;1296.9092163548462;1305.536534530228;1306.137948953686;1306.6145487081333;826.3843436639742;839.290799274612;858.7805316450655;859.5702148925537;869.4913475616835;3874.593167717349;3886.6113388661474;3904.7852368119457;3935.763854168759;3946.3291240489184;4439.5138722315605;4448.777561121931;4451.287793952952;4476.4180656514445;4521.88695282614;3874.3607304794955;3881.331027658078;3893.8053097345587;3951.7036222283446;3959.1883663848844;2303.527776805751;2357.2574638988663;2384.070212617081;2384.213209640849;2403.663487111806;4260.360945858146;4556.157110934421;4634.397528321297;4762.857114286577;4791.47293797682;5177.689338639693;5210.6613070150315;5218.530332270114;5237.083510385667;5251.474852514794;4742.57316841479;4760.190767569521;4760.881192919592;4768.904621907612;4771.95228047715;4918.631102667386;5024.598032157492;5049.924251136309;5063.240513922941;5075.543201111917;4949.232868905326;4954.59702044501;4955.950440495549;4961.652684312064;4961.70229786214;4763.880902977453;4777.283407488904;4780.4741478436645;4785.425748910153;4786.353842231254;3618.2582570572445;3726.571444283942;3726.757760745543;3740.1958578905665;3764.378877485184;5084.694918304914;5103.311053007945;5108;5121.513456221653;5134.8973020540125;4987.10232158215;5002.399712034434;5008.373953255843;5023.874403139805;5025.824096156617;4654.790534425892;4966.776495057803;4976.552110310089;4979.302897594267;4982.4768399317545;4888.6577939543895;4889.437714662859;4905;4920.606351491943;4920.6555541111775;5021.748912554431;5022.146235139962;5041.344625198701;5042.722650254272;5052.242163675478;4753.453352864465;4801.815174095848;4857.809836590058;4859.70841749482;4863.7324947128245 16:14:28 INFO - PROCESS | 3374 | |5;yui.html;589.1427972300304;622.1911515030461;666.8266250774797;673.0341959288421;727.4071631620233;11930.642080737582;13013.08908376414;13025.283609401478;13057.281849498286;13058.412371443284;12207.389630518483;12266.754664906719;12312.753744925118;12406.255624662526;12529.310887901162;594.5184400635477;730.8903664450348;733.0616810482564;748.6930358552994;751.3763576231714;739.7263012685314;750.9361704255144;751.6955632968629;751.7857410637969;751.8308380614372;791.584418180457;792.3463142907096;793;793.4009822583942;793.5516433215215;627.8775638750445;763.7861398808343;783.4046124945025;783.6160281462071;786.7049856303886;575.4188269847484;575.7178982298685;576.5963825322291;576.7058800011982;577.0680351232729;12531.812022819666;12549.058820588401;12580.056495762854;12591.24452532851;12610.873891261062;12561.811572826415;12750.553730619476;13094;13100.541481048118;13581.11722738019;11427.828582571268;11625.825612615818;11757.941210293979;11873.703157421134;12063.396830158457;4141.192467468844;4144.689148313889;4151.12826306476;4340.348947657877;4350.804213810355;59514.702426488024;59610.779790142915;62307.37692623107;62606;62689.68655156695;10038.799224015625;10163.542640581192;10325.896741032493;10413.11488523484;10721.142308615292;19040.809591903904;19051.714224286647;19052.80947190538;19062;19069.523261918563;6782.864342713118;6893.103896493524;6915.723371065101;7092.4680648951535;7121.608311542915;3797.696184305304;3838.1747924196434;3849.4610754494392;3874;3931.6461518463475;2633.7761290290155;2644.7090820009785;2722.1153125234378;2726.795490338193;2734.53512902807;464.6236548395743;472.8085125524168;475.67416319821035;480.17889409110813;483.6541872561136;466.4495894844049;475.66227978135913;478.5262590035909;538.7737150396842;544.7385255077585;7008.264132266038;7445.776626701099;7467.327940485382;7467.834328044914;7572.9360645561765;529.629259518331;544.6623093681942;557.0975317898098;569.4191924237338;570.8354955890021;3050.871177664234;3088.0118362123903;3145.8269795161486;3371.780834245766;3471.965280347164;82729.17270827336;100721.49639251757;103819.48090259501;106345.46827265814;106391.46804266084;51077.23384149241;66721.66639166774;83484;85727;89324.5533772327;73924.99999999892;80541.5972920155;80695.59652201818;81411;82134.58932705298;5878.647281163144;6039.909401359071;6055.212822333068;6265.592736472115;6363.172787537591;45476.8630784233;47018.29472557983;47299.290510643055;48484.3030493931;49154;59334;68199;69052.65473672599;70122;73171.63414182895;9356.578953947224;10023.74940626461;10036.347637403545;10129.341592796445;10301.072903438726;10399.376037437778;10507.684769456928;10628.787424251626;10645.148388129086;10725.195610329101;7628.847423051397;7785.961070194613;7982.441229113906;8016.318612917968;8030.678772849021;9550.37922535033;9614.18279446255;9665.9033409665;10237.74405639865;10315.742106447098;9579.616815327308;9608.183304418922;9640.373375730554;9749.56126974302;9991.700248992542;8639.654413823628;8724.65101395937;8928.285737140883;8945;9150.084991500797;8222.506649601042;8247.907034861926;8258.752237432887;8401.621927013177;8641.135886411308 16:14:28 INFO - PROCESS | 3374 | __end_tp_report 16:14:28 INFO - PROCESS | 3374 | __start_cc_report 16:14:28 INFO - PROCESS | 3374 | _x_x_mozilla_cycle_collect,1086 16:14:28 INFO - PROCESS | 3374 | __end_cc_report 16:14:28 INFO - PROCESS | 3374 | __startTimestamp1472685268803__endTimestamp 16:14:28 INFO - PROCESS | 3374 | 16:14:28 INFO - PROCESS | 3374 | ------- Summary: start ------- 16:14:28 INFO - PROCESS | 3374 | Number of tests: 6 16:14:28 INFO - PROCESS | 3374 | 16:14:28 INFO - PROCESS | 3374 | [#0] dojo.html Cycles:165 Average:4602.06 Median:4753.95 stddev:484.73 (10.2%) stddev-sans-first:459.09 16:14:28 INFO - PROCESS | 3374 | Values: 2563.8 2566.1 2594.5 2600.0 2616.3 4559.4 4640.2 4642.3 4719.2 4769.0 4622.4 4627.4 4638.2 4656.6 4674.3 4207.8 4218.6 4308.4 4339.0 4386.2 4315.5 4346.3 4386.8 4397.8 4399.5 4218.0 4259.4 4277.7 4282.0 4373.7 4203.4 4249.2 4290.5 4297.3 4307.4 4233.3 4253.4 4266.5 4358.4 4364.2 4668.3 4690.8 4698.7 4699.0 4752.4 4875.6 4944.2 4995.8 5002.9 5031.5 4988.0 5001.5 5013.8 5016.1 5078.6 4678.5 4704.8 4709.3 4709.4 4717.1 4978.9 5021.0 5022.1 5045.2 5136.0 4815.5 4841.2 4846.0 4849.8 4897.3 4931.2 4932.2 4934.2 4934.9 4983.9 4812.4 4871.1 4909.4 4916.6 4921.2 4907.8 4913.2 4914.5 4917.6 4940.7 4150.5 4151.6 4153.7 4198.2 4202.1 3608.7 3615.1 3634.1 3638.0 3690.1 4275.1 4292.1 4331.4 4352.4 4354.4 4990.4 4995.4 5001.6 5006.5 5066.6 4166.7 4236.7 4277.9 4298.1 4303.6 4412.7 4426.2 4485.2 4504.3 4511.9 4649.9 4668.0 4671.6 4677.4 4730.6 4835.3 4962.8 4987.3 4992.9 5000.3 4704.7 4748.2 4754.0 4760.2 4763.5 4953.5 4957.0 4961.0 4966.7 5032.7 4810.2 4973.1 5037.9 5041.4 5098.6 4806.2 4872.4 4878.9 4880.5 4936.3 4866.2 4889.3 4889.8 4889.9 4949.6 4669.6 4808.8 4818.7 4836.8 4873.3 4807.3 4824.5 4856.9 4869.9 4947.5 4760.3 4767.5 4769.7 4770.5 4829.7 16:14:28 INFO - PROCESS | 3374 | 16:14:28 INFO - PROCESS | 3374 | [#1] ext.html Cycles:180 Average:47124.38 Median:356578.00 stddev:90852.81 (25.5%) stddev-sans-first:91040.40 16:14:28 INFO - PROCESS | 3374 | Values: 556.6 564.7 570.0 578.3 579.5 27382.2 29894.0 37442.8 38682.8 38695.2 39680.2 40127.6 40186.6 40198.0 40276.4 7580.8 7727.4 7733.9 7735.2 7736.9 7117.2 7443.7 9509.7 9849.0 9857.3 9615.8 9684.8 9690.1 9692.1 9695.3 9787.8 9836.2 9842.0 9851.7 9854.4 9752.3 9824.9 9826.8 9828.5 9830.6 36150.8 36406.3 36557.5 36675.4 36710.3 2763.1 2843.5 2912.8 3373.3 3525.7 27577.0 27720.6 28103.0 28175.4 29119.9 3177.6 3187.9 3220.9 3237.5 3374.4 248484.0 249274.0 249587.0 249721.0 249785.0 30030.7 30889.7 47738.5 47985.0 48080.3 32759.2 32840.0 33022.2 33034.7 33044.3 12095.0 12206.5 12206.6 12224.8 12231.5 9600.7 9683.7 9717.9 9731.8 9732.0 7507.8 7730.3 7739.1 7792.8 7868.6 3028.4 3062.5 3082.9 3100.3 3148.6 804.8 897.6 911.8 919.9 946.5 26659.3 27128.0 27793.4 29098.9 29654.3 909.3 914.4 944.2 955.0 1015.4 13317.7 14420.9 14446.9 14580.6 14673.4 401086.0 406470.0 407225.0 407406.0 408506.0 45565.1 45829.3 45835.5 45900.5 45983.8 1094.1 1103.9 1107.2 1122.0 1166.2 19371.0 24567.6 25362.2 27275.2 27656.9 43713.0 44528.8 44658.0 44824.6 44913.0 353612.0 354965.0 356567.0 356589.0 356828.0 23738.3 23941.4 24148.9 24196.4 24477.9 40216.4 40579.0 40665.6 41070.8 41459.8 33689.8 34178.1 34516.3 37526.4 38445.6 33750.5 33989.3 34377.7 34575.3 34754.8 33916.7 34263.7 34508.8 34946.3 35373.8 28633.0 32158.2 32451.4 32525.8 32987.7 24890.3 24984.5 25111.6 25133.0 25482.1 16:14:28 INFO - PROCESS | 3374 | 16:14:28 INFO - PROCESS | 3374 | [#2] jquery.html Cycles:180 Average:23806.46 Median:4257.17 stddev:62410.72 (1466.0%) stddev-sans-first:62560.94 16:14:28 INFO - PROCESS | 3374 | Values: 349.9 367.4 373.7 377.5 390.9 12149.3 15903.9 16020.6 16062.2 16963.6 13343.4 13547.9 13932.9 14102.6 14203.0 4555.8 4912.7 4978.8 5129.7 5145.8 4988.9 5110.7 5113.7 5117.8 5118.6 4810.6 5074.2 5085.0 5085.1 5085.7 5046.9 5128.9 5129.7 5134.7 5138.5 4378.0 4630.3 4630.3 4634.1 4635.1 19855.9 20377.4 20530.2 20895.6 20943.8 1853.9 1872.5 1955.9 2050.8 2164.5 12857.9 12878.7 13101.8 13605.9 13815.4 1620.6 1681.2 1684.3 1690.9 1742.3 68468.3 69258.7 69482.7 69655.7 70419.6 9733.4 9816.2 9958.6 10023.4 10155.5 13428.7 13429.7 13434.8 13440.9 13456.9 6728.1 6737.5 6868.9 6917.0 6968.4 6515.7 6575.2 6757.5 6789.5 6822.0 4192.0 4322.3 4335.8 4346.9 4466.7 1808.9 1851.1 1852.8 1856.5 1865.6 743.2 838.3 847.6 910.4 928.8 16205.8 16465.5 16855.2 17573.2 17615.6 842.4 883.4 892.8 909.2 953.1 9182.5 9433.9 9632.5 9812.0 9822.6 368764.0 371048.0 381112.0 381631.0 383801.0 75092.0 75990.0 78515.6 80164.6 80229.0 908.3 916.9 925.6 930.4 936.5 14552.3 14634.2 14996.8 15255.0 15528.8 34928.0 35620.5 35848.8 36316.0 36417.0 46843.3 48983.8 50458.2 50549.7 50592.5 7640.7 7810.1 7812.5 7893.1 7899.6 9391.9 11129.2 11279.2 11430.0 12153.3 10232.4 10422.5 10433.1 10585.8 10724.7 10005.8 10356.9 10380.5 10382.6 10404.5 10049.9 10291.7 10431.2 10496.9 10805.2 7383.7 7849.9 7876.8 8077.6 8182.5 8816.6 10013.0 10091.9 10269.5 10511.4 16:14:28 INFO - PROCESS | 3374 | 16:14:28 INFO - PROCESS | 3374 | [#3] mootools.html Cycles:180 Average:5157.80 Median:4712.12 stddev:2936.42 (62.3%) stddev-sans-first:2929.61 16:14:28 INFO - PROCESS | 3374 | Values: 1202.3 1310.6 1352.2 1396.7 1636.7 6504.4 7625.1 7795.9 7848.8 8107.0 7874.3 7933.5 7936.0 7944.6 7951.4 2207.3 2235.2 2236.1 2236.3 2238.8 2149.8 2192.8 2207.7 2208.8 2211.6 2136.0 2144.2 2147.8 2148.6 2197.9 2111.4 2115.8 2126.3 2131.6 2134.7 2069.7 2160.5 2181.3 2188.9 2190.7 7862.2 7913.9 7940.5 7940.5 7941.4 4284.8 4415.3 4428.2 4429.2 4432.9 2976.3 3105.5 3135.0 3145.3 3161.5 940.6 959.8 960.6 979.0 983.5 10775.9 10800.7 10834.6 10835.7 10924.7 4100.2 4137.4 4196.6 4197.1 4244.3 8637.2 8643.4 8653.2 8655.3 8664.1 4523.2 4527.4 4527.7 4529.3 4533.4 3642.6 3727.4 4105.0 4199.2 4230.4 3414.3 3454.9 3527.6 3538.7 3568.5 1040.2 1059.2 1062.5 1065.5 1071.8 702.4 776.0 779.7 803.0 859.5 5247.5 5267.4 5462.9 5535.4 5536.1 765.3 793.7 814.8 837.1 862.6 3577.8 3783.1 3835.3 3850.3 3870.4 9754.4 10156.6 10184.3 10184.3 10201.4 10108.0 10113.6 10134.1 10145.5 10155.3 7122.5 7294.1 7321.4 7324.4 7335.4 5205.2 5246.3 5349.5 5479.0 5551.3 8801.2 8950.4 9052.0 9069.0 9196.6 9238.7 9340.1 9397.9 9418.2 9436.3 6359.2 6464.0 6497.4 6500.2 6511.1 7315.9 7371.0 7378.8 7477.8 7575.4 6639.0 6646.3 6684.4 6710.2 6724.9 6327.0 6432.7 6459.3 6628.7 6630.2 6085.7 6285.8 6354.4 6496.7 6515.0 4620.8 4662.5 4755.8 4762.9 4838.0 4535.9 4616.0 4662.5 4668.1 4668.4 16:14:28 INFO - PROCESS | 3374 | 16:14:28 INFO - PROCESS | 3374 | [#4] prototype.html Cycles:180 Average:4074.14 Median:4595.04 stddev:1181.30 (25.7%) stddev-sans-first:1150.04 16:14:28 INFO - PROCESS | 3374 | Values: 294.3 299.7 303.1 307.7 309.0 4716.5 4723.7 4738.8 4746.0 4751.9 4658.0 4681.9 4682.6 4689.3 4692.9 3760.8 3762.3 3769.5 3778.8 3799.2 3672.6 3781.8 3791.8 3798.9 3805.7 3717.8 3722.3 3724.9 3742.1 3762.3 3694.4 3714.4 3716.7 3719.7 3721.5 3097.3 3186.4 3285.1 3299.4 3322.6 4535.6 4548.1 4563.9 4588.1 4602.0 4227.3 4247.9 4258.6 4262.9 4268.7 4633.5 4734.5 4932.0 4932.9 4981.2 3591.2 3609.3 3611.2 3621.3 3621.9 5154.8 5167.4 5169.9 5174.8 5235.7 3638.1 3710.1 3732.3 3739.9 3744.0 3747.8 3757.2 3759.2 3764.1 3782.8 4216.8 4226.6 4256.9 4258.4 4263.0 4872.0 4882.0 4897.6 4902.3 4904.0 1233.1 1296.9 1305.5 1306.1 1306.6 826.4 839.3 858.8 859.6 869.5 3874.6 3886.6 3904.8 3935.8 3946.3 4439.5 4448.8 4451.3 4476.4 4521.9 3874.4 3881.3 3893.8 3951.7 3959.2 2303.5 2357.3 2384.1 2384.2 2403.7 4260.4 4556.2 4634.4 4762.9 4791.5 5177.7 5210.7 5218.5 5237.1 5251.5 4742.6 4760.2 4760.9 4768.9 4772.0 4918.6 5024.6 5049.9 5063.2 5075.5 4949.2 4954.6 4956.0 4961.7 4961.7 4763.9 4777.3 4780.5 4785.4 4786.4 3618.3 3726.6 3726.8 3740.2 3764.4 5084.7 5103.3 5108.0 5121.5 5134.9 4987.1 5002.4 5008.4 5023.9 5025.8 4654.8 4966.8 4976.6 4979.3 4982.5 4888.7 4889.4 4905.0 4920.6 4920.7 5021.7 5022.1 5041.3 5042.7 5052.2 4753.5 4801.8 4857.8 4859.7 4863.7 16:14:28 INFO - PROCESS | 3374 | 16:14:28 INFO - PROCESS | 3374 | [#5] yui.html Cycles:180 Average:17478.07 Median:573.13 stddev:25917.28 (4522.1%) stddev-sans-first:25958.96 16:14:28 INFO - PROCESS | 3374 | Values: 589.1 622.2 666.8 673.0 727.4 11930.6 13013.1 13025.3 13057.3 13058.4 12207.4 12266.8 12312.8 12406.3 12529.3 594.5 730.9 733.1 748.7 751.4 739.7 750.9 751.7 751.8 751.8 791.6 792.3 793.0 793.4 793.6 627.9 763.8 783.4 783.6 786.7 575.4 575.7 576.6 576.7 577.1 12531.8 12549.1 12580.1 12591.2 12610.9 12561.8 12750.6 13094.0 13100.5 13581.1 11427.8 11625.8 11757.9 11873.7 12063.4 4141.2 4144.7 4151.1 4340.3 4350.8 59514.7 59610.8 62307.4 62606.0 62689.7 10038.8 10163.5 10325.9 10413.1 10721.1 19040.8 19051.7 19052.8 19062.0 19069.5 6782.9 6893.1 6915.7 7092.5 7121.6 3797.7 3838.2 3849.5 3874.0 3931.6 2633.8 2644.7 2722.1 2726.8 2734.5 464.6 472.8 475.7 480.2 483.7 466.4 475.7 478.5 538.8 544.7 7008.3 7445.8 7467.3 7467.8 7572.9 529.6 544.7 557.1 569.4 570.8 3050.9 3088.0 3145.8 3371.8 3472.0 82729.2 100721.5 103819.5 106345.5 106391.5 51077.2 66721.7 83484.0 85727.0 89324.6 73925.0 80541.6 80695.6 81411.0 82134.6 5878.6 6039.9 6055.2 6265.6 6363.2 45476.9 47018.3 47299.3 48484.3 49154.0 59334.0 68199.0 69052.7 70122.0 73171.6 9356.6 10023.7 10036.3 10129.3 10301.1 10399.4 10507.7 10628.8 10645.1 10725.2 7628.8 7786.0 7982.4 8016.3 8030.7 9550.4 9614.2 9665.9 10237.7 10315.7 9579.6 9608.2 9640.4 9749.6 9991.7 8639.7 8724.7 8928.3 8945.0 9150.1 8222.5 8247.9 8258.8 8401.6 8641.1 16:14:28 INFO - PROCESS | 3374 | -------- Summary: end -------- 16:14:28 INFO - PROCESS | 3374 | 16:14:29 INFO - TEST-INFO | 3374: exit 0 16:14:29 INFO - TEST-OK | dromaeo_css | took 1121043ms 16:14:29 INFO - TEST-START | kraken 16:14:29 INFO - operating with platform_type : linux_ 16:14:29 INFO - Initialising browser for kraken test... 16:14:29 INFO - TEST-INFO | started process 3685 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcqv6fr/profile http://localhost:41299/getInfo.html) 16:14:34 INFO - PROCESS | 3685 | __metrics Screen width/height:1600/1200 16:14:34 INFO - PROCESS | 3685 | colorDepth:24 16:14:34 INFO - PROCESS | 3685 | Browser inner width/height: 1024/697 16:14:34 INFO - PROCESS | 3685 | __metrics 16:14:35 INFO - PROCESS | 3685 | 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" 16:14:35 INFO - PROCESS | 3685 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:14:36 INFO - TEST-INFO | 3685: exit 0 16:14:36 INFO - Browser initialized. 16:14:36 INFO - Running cycle 1/1 for kraken test... 16:14:36 INFO - TEST-INFO | started process 3765 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcqv6fr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:14:48 INFO - PROCESS | 3765 | RSS: Main: 181387264 16:14:48 INFO - PROCESS | 3765 | 16:14:51 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41299/tests/kraken/driver.html?testName=audio-beat-detection) 16:14:52 INFO - PROCESS | 3765 | RSS: Main: 235196416 16:14:52 INFO - PROCESS | 3765 | 16:14:55 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41299/tests/kraken/driver.html?testName=audio-dft) 16:14:55 INFO - PROCESS | 3765 | RSS: Main: 226152448 16:14:55 INFO - PROCESS | 3765 | 16:14:58 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41299/tests/kraken/driver.html?testName=audio-fft) 16:14:59 INFO - PROCESS | 3765 | RSS: Main: 304480256 16:14:59 INFO - PROCESS | 3765 | 16:15:01 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41299/tests/kraken/driver.html?testName=audio-oscillator) 16:15:02 INFO - PROCESS | 3765 | RSS: Main: 213102592 16:15:02 INFO - PROCESS | 3765 | 16:15:04 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41299/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:15:05 INFO - PROCESS | 3765 | RSS: Main: 215629824 16:15:05 INFO - PROCESS | 3765 | 16:15:08 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41299/tests/kraken/driver.html?testName=imaging-darkroom) 16:15:08 INFO - PROCESS | 3765 | RSS: Main: 254095360 16:15:08 INFO - PROCESS | 3765 | 16:15:12 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41299/tests/kraken/driver.html?testName=imaging-desaturate) 16:15:12 INFO - PROCESS | 3765 | RSS: Main: 247095296 16:15:12 INFO - PROCESS | 3765 | 16:15:15 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41299/tests/kraken/driver.html?testName=json-parse-financial) 16:15:16 INFO - PROCESS | 3765 | RSS: Main: 250179584 16:15:16 INFO - PROCESS | 3765 | 16:15:18 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41299/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:15:18 INFO - PROCESS | 3765 | RSS: Main: 214786048 16:15:19 INFO - PROCESS | 3765 | 16:15:20 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41299/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:15:21 INFO - PROCESS | 3765 | RSS: Main: 221929472 16:15:21 INFO - PROCESS | 3765 | 16:15:23 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41299/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:15:24 INFO - PROCESS | 3765 | RSS: Main: 223338496 16:15:24 INFO - PROCESS | 3765 | 16:15:27 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41299/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:15:28 INFO - PROCESS | 3765 | RSS: Main: 224137216 16:15:28 INFO - PROCESS | 3765 | 16:15:30 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41299/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:15:31 INFO - PROCESS | 3765 | RSS: Main: 222003200 16:15:31 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | Cycle 1(1): loaded http://localhost:41299/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41299/tests/kraken/driver.html?testName=ai-astar) 16:15:33 INFO - PROCESS | 3765 | RSS: Main: 273072128 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | __start_tp_report 16:15:33 INFO - PROCESS | 3765 | _x_x_mozilla_page_load 16:15:33 INFO - PROCESS | 3765 | _x_x_mozilla_page_load_details 16:15:33 INFO - PROCESS | 3765 | |i|pagename|runs| 16:15:33 INFO - PROCESS | 3765 | |0;ai-astar;113;114;131;114;114;114;114;114;115;116 16:15:33 INFO - PROCESS | 3765 | |1;audio-beat-detection;151;124;157;180;132;151;154;123;197;122 16:15:33 INFO - PROCESS | 3765 | |2;audio-dft;162;161;161;160;161;161;162;162;162;161 16:15:33 INFO - PROCESS | 3765 | |3;audio-fft;76;76;76;157;77;75;76;76;164;76 16:15:33 INFO - PROCESS | 3765 | |4;audio-oscillator;84;85;134;84;84;85;136;84;84;131 16:15:33 INFO - PROCESS | 3765 | |5;imaging-gaussian-blur;118;117;117;118;118;118;117;117;116;117 16:15:33 INFO - PROCESS | 3765 | |6;imaging-darkroom;147;147;147;147;147;147;147;146;146;148 16:15:33 INFO - PROCESS | 3765 | |7;imaging-desaturate;95;95;95;94;94;95;94;95;95;94 16:15:33 INFO - PROCESS | 3765 | |8;json-parse-financial;63;64;65;64;65;65;65;64;65;65 16:15:33 INFO - PROCESS | 3765 | |9;json-stringify-tinderbox;66;66;66;66;67;66;67;68;67;68 16:15:33 INFO - PROCESS | 3765 | |10;stanford-crypto-aes;92;85;89;83;83;92;84;91;88;83 16:15:33 INFO - PROCESS | 3765 | |11;stanford-crypto-ccm;141;143;134;133;132;131;132;132;183;136 16:15:33 INFO - PROCESS | 3765 | |12;stanford-crypto-pbkdf2;177;178;176;175;174;175;178;177;182;178 16:15:33 INFO - PROCESS | 3765 | |13;stanford-crypto-sha256-iterative;70;69;69;69;68;68;67;68;68;67 16:15:33 INFO - PROCESS | 3765 | __end_tp_report 16:15:33 INFO - PROCESS | 3765 | __start_cc_report 16:15:33 INFO - PROCESS | 3765 | _x_x_mozilla_cycle_collect,2006 16:15:33 INFO - PROCESS | 3765 | __end_cc_report 16:15:33 INFO - PROCESS | 3765 | __startTimestamp1472685333605__endTimestamp 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | ------- Summary: start ------- 16:15:33 INFO - PROCESS | 3765 | Number of tests: 14 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#0] ai-astar Cycles:10 Average:115.90 Median:114.00 stddev:5.36 (4.7%) stddev-sans-first:5.59 16:15:33 INFO - PROCESS | 3765 | Values: 113.0 114.0 131.0 114.0 114.0 114.0 114.0 114.0 115.0 116.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#1] audio-beat-detection Cycles:10 Average:149.10 Median:152.50 stddev:25.12 (16.5%) stddev-sans-first:26.64 16:15:33 INFO - PROCESS | 3765 | Values: 151.0 124.0 157.0 180.0 132.0 151.0 154.0 123.0 197.0 122.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.67 (0.4%) stddev-sans-first:0.67 16:15:33 INFO - PROCESS | 3765 | Values: 162.0 161.0 161.0 160.0 161.0 161.0 162.0 162.0 162.0 161.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#3] audio-fft Cycles:10 Average:92.90 Median:76.00 stddev:35.67 (46.9%) stddev-sans-first:37.31 16:15:33 INFO - PROCESS | 3765 | Values: 76.0 76.0 76.0 157.0 77.0 75.0 76.0 76.0 164.0 76.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#4] audio-oscillator Cycles:10 Average:99.10 Median:84.00 stddev:23.89 (28.4%) stddev-sans-first:24.70 16:15:33 INFO - PROCESS | 3765 | Values: 84.0 85.0 134.0 84.0 84.0 85.0 136.0 84.0 84.0 131.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.50 stddev:0.67 (0.6%) stddev-sans-first:0.67 16:15:33 INFO - PROCESS | 3765 | Values: 118.0 117.0 117.0 118.0 118.0 118.0 117.0 117.0 116.0 117.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 16:15:33 INFO - PROCESS | 3765 | Values: 147.0 147.0 147.0 147.0 147.0 147.0 147.0 146.0 146.0 148.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:15:33 INFO - PROCESS | 3765 | Values: 95.0 95.0 95.0 94.0 94.0 95.0 94.0 95.0 95.0 94.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:65.00 stddev:0.71 (1.1%) stddev-sans-first:0.50 16:15:33 INFO - PROCESS | 3765 | Values: 63.0 64.0 65.0 64.0 65.0 65.0 65.0 64.0 65.0 65.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.83 16:15:33 INFO - PROCESS | 3765 | Values: 66.0 66.0 66.0 66.0 67.0 66.0 67.0 68.0 67.0 68.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:88.50 stddev:3.83 (4.3%) stddev-sans-first:3.61 16:15:33 INFO - PROCESS | 3765 | Values: 92.0 85.0 89.0 83.0 83.0 92.0 84.0 91.0 88.0 83.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#11] stanford-crypto-ccm Cycles:10 Average:139.70 Median:135.00 stddev:15.75 (11.7%) stddev-sans-first:16.70 16:15:33 INFO - PROCESS | 3765 | Values: 141.0 143.0 134.0 133.0 132.0 131.0 132.0 132.0 183.0 136.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.00 Median:177.50 stddev:2.26 (1.3%) stddev-sans-first:2.40 16:15:33 INFO - PROCESS | 3765 | Values: 177.0 178.0 176.0 175.0 174.0 175.0 178.0 177.0 182.0 178.0 16:15:33 INFO - PROCESS | 3765 | 16:15:33 INFO - PROCESS | 3765 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:0.95 (1.4%) stddev-sans-first:0.78 16:15:33 INFO - PROCESS | 3765 | Values: 70.0 69.0 69.0 69.0 68.0 68.0 67.0 68.0 68.0 67.0 16:15:33 INFO - PROCESS | 3765 | -------- Summary: end -------- 16:15:33 INFO - PROCESS | 3765 | 16:15:34 INFO - TEST-INFO | 3765: exit 0 16:15:34 INFO - TEST-OK | kraken | took 65158ms 16:15:34 INFO - SUITE-END | took 1186s 16:15:35 INFO - Completed test suite (00:19:46) 16:15:35 INFO - javascript benchmark 16:15:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2563.7820785233253, 2566.0762125634774, 2594.4551644154726, 2599.974000259998, 2616.2805228562142, 4559.384483094789, 4640.211164102102, 4642.32686260492, 4719.174144524707, 4768.952310476894, 4622.352870598119, 4627.3984382030285, 4638.234691275936, 4656.557627025427, 4674.345591617168, 4207.810648520808, 4218.6203241708245, 4308.353746937969, 4338.97830510847, 4386.166628340621, 4315.503717072545, 4346.261135606938, 4386.824527018915, 4397.802098905541, 4399.472063352402, 4218.0, 4259.382389553529, 4277.700560960734, 4281.97859010703, 4373.693841431101, 4203.369494575808, 4249.1714115747445, 4290.485141783006, 4297.290946993742, 4307.353896915456, 4233.322668373076, 4253.3619957006385, 4266.466691663542, 4358.433403657536, 4364.192624364503, 4668.276417155346, 4690.7654617269, 4698.718076915362, 4698.976505117486, 4752.358431611723, 4875.634327425421, 4944.184209605411, 4995.750212489397, 5002.874928126789, 5031.522005409517, 4987.975060124712, 5001.474845141281, 5013.849584512471, 5016.147254966664, 5078.61910356721, 4678.532146785328, 4704.788284527204, 4709.340692303081, 4709.387779588632, 4717.127331443694, 4978.8755281118265, 5021.0, 5022.096022715946, 5045.217991211368, 5136.049830781318, 4815.518448155227, 4841.194742656689, 4846.0, 4849.80600775965, 4897.338859253955, 4931.211006238985, 4932.235503496964, 4934.210526315772, 4934.8766280843265, 4983.875403114878, 4812.35033270511, 4871.050145221648, 4909.386326709161, 4916.631252656065, 4921.163402221631, 4907.828226012072, 4913.213885778258, 4914.459409464956, 4917.557419832161, 4940.7035577865445, 4150.481189851269, 4151.647109995684, 4153.6677065834665, 4198.181354635878, 4202.075543380452, 3608.7473876828894, 3615.060084378081, 3634.055145662147, 3638.0, 3690.095926498025, 4275.080857615628, 4292.141571685675, 4331.4152589400655, 4352.39066530686, 4354.433923589913, 4990.3762029746285, 4995.400551933719, 5001.574866136347, 5006.499350064964, 5066.620003499752, 4166.687498437629, 4236.661067114624, 4277.893052673646, 4298.09739954613, 4303.569643035671, 4412.735235885857, 4426.247537918562, 4485.215087359699, 4504.324351347259, 4511.932321015187, 4649.86050418488, 4668.0430511744335, 4671.626269898332, 4677.368555244999, 4730.574248317668, 4835.2505361669, 4962.826301079445, 4987.326710894128, 4992.850214493571, 5000.324956131021, 4704.717716936994, 4748.216544270157, 4753.95246047549, 4760.190767569452, 4763.476017638126, 4953.504649535162, 4956.950430495793, 4961.0, 4966.726830024383, 5032.6980381177245, 4810.182269014346, 4973.079980203674, 5037.92443113361, 5041.395032596112, 5098.566621837185, 4806.158922188673, 4872.43966943806, 4878.902421951612, 4880.487548807466, 4936.284238785284, 4866.172750632471, 4889.2910527972535, 4889.828855990023, 4889.853304400874, 4949.604031677385, 4669.60308373792, 4808.783604737865, 4818.71087734737, 4836.758162091952, 4873.317735517101, 4807.254875494304, 4824.49342818999, 4856.878578035577, 4869.853904382874, 4947.505249475023, 4760.333553302471, 4767.523247675204, 4769.66612337133, 4770.522947705202, 4829.73436460998], "value": 4570.748829020758, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.5825630776917, 564.7007086244289, 570.0167211560059, 578.3291381996885, 579.4611011759065, 27382.178534643943, 29894.0, 37442.81278593607, 38682.806585967104, 38695.226095478145, 39680.20639587207, 40127.59872401275, 40186.59813401865, 40198.0, 40276.39585406221, 7580.772576822704, 7727.38180945523, 7733.883991740128, 7735.187805280449, 7736.883946740775, 7117.181524124714, 7443.739469118582, 9509.667161649344, 9848.950755246215, 9857.260705447085, 9615.759606009835, 9684.806303873918, 9690.127888490035, 9692.07925247104, 9695.321327507078, 9787.755306117333, 9836.163926066292, 9841.950790246003, 9851.655192068314, 9854.40873547586, 9752.26857985654, 9824.852627210597, 9826.754331141707, 9828.459434731087, 9830.606775728962, 36150.81924590386, 36406.271874562626, 36557.45163822545, 36675.449868252, 36710.26579468395, 2763.115802943059, 2843.516602177614, 2912.7815413843837, 3373.308471763283, 3525.700315473188, 27577.0, 27720.584191237147, 28103.0, 28175.436491270266, 29119.85440072786, 3177.5551422800827, 3187.9203019924457, 3220.935581288361, 3237.465818140004, 3374.3926093303194, 248484.0, 249274.0, 249587.0, 249721.0, 249784.9999999982, 30030.699693003226, 30889.69110308868, 47738.52261477341, 47985.0, 48080.27879581809, 32759.181020474203, 32840.0, 33022.17444563905, 33034.66965330364, 33044.339113217604, 12095.0, 12206.450709718085, 12206.572769953009, 12224.816627750592, 12231.510739570318, 9600.66397676078, 9683.66107186245, 9717.854232186522, 9731.805363892825, 9732.0, 7507.8498430031095, 7730.304272615492, 7739.148693643695, 7792.844143117106, 7868.567228802356, 3028.4094601552924, 3062.494688376393, 3082.8766849326225, 3100.255938574771, 3148.5592017117624, 804.7947773317767, 897.555709923579, 911.7766147293822, 919.9310051746146, 946.4794363100267, 26659.33351666185, 27128.0, 27793.444131117263, 29098.854505727337, 29654.258643534085, 909.2771246858763, 914.4284821986258, 944.1927152284835, 954.9761255968656, 1015.3806178231273, 13317.66705832362, 14420.855791442162, 14446.85553144476, 14580.635484112981, 14673.413063477341, 401086.0, 406470.0, 407225.0, 407406.0, 408506.0, 45565.08869822585, 45829.31256031162, 45835.54164458379, 45900.540994590294, 45983.77008115005, 1094.0864378244096, 1103.87857335693, 1107.224942540209, 1122.0294445304753, 1166.1661911733152, 19371.0, 24567.631485528087, 25362.239132826046, 27275.181744547695, 27656.86171569129, 43713.0, 44528.77735611301, 44658.0, 44824.55175448204, 44913.0, 353612.0, 354965.0, 356567.0, 356589.0, 356828.0, 23738.287851364486, 23941.401464963514, 24148.87925560431, 24196.39509012289, 24477.87761061183, 40216.396754048124, 40579.0, 40665.59334406618, 41070.794646027774, 41459.7927010363, 33689.831550842086, 34178.14554636154, 34516.309673806885, 37526.43710344292, 38445.6155438442, 33750.49374259437, 33989.32021359509, 34377.65622343744, 34575.30849382948, 34754.82622586871, 33916.660833392336, 34263.657363427046, 34508.79219227315, 34946.30107397889, 35373.82313088521, 28633.0, 32158.19604509906, 32451.350972979937, 32525.837370812995, 32987.67012329942, 24890.253292401256, 24984.500309994062, 25111.623325649765, 25133.0, 25482.108126215495], "value": 16313.13276695852, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.8600559776091, 367.3552914634817, 373.67303609341826, 377.5092379906121, 390.9374500079988, 12149.271043737372, 15903.920480397595, 16020.59948501288, 16062.196890155505, 16963.57591060224, 13343.399547020383, 13547.864521354784, 13932.860671393282, 14102.576922692335, 14203.0, 4555.794989225493, 4912.70523768575, 4978.800847966077, 5129.743512824362, 5145.794168233266, 4988.900221995558, 5110.66780659256, 5113.718745468997, 5117.820876269331, 5118.6416950813455, 4810.591099756507, 5074.188129899198, 5085.0338435697095, 5085.110105731501, 5085.669431486942, 5046.924296135542, 5128.948710512903, 5129.692218466904, 5134.717590532519, 5138.511841375063, 4377.956220437819, 4630.259158534618, 4630.305454181899, 4634.050019745944, 4635.142498637762, 19855.90072049645, 20377.388678339674, 20530.17879284827, 20895.5820883583, 20943.790562094335, 1853.8516918646476, 1872.5131465818986, 1955.8630895837298, 2050.8105298926585, 2164.512984578473, 12857.935710321388, 12878.742425151539, 13101.803472947913, 13605.931970340183, 13815.447382104703, 1620.602952276688, 1681.201429321075, 1684.3178512702375, 1690.9323627054903, 1742.259539695637, 68468.3153168467, 69258.65370673165, 69482.65258673675, 69655.65172174157, 70419.64790176068, 9733.367331123454, 9816.214702823758, 9958.601655933682, 10023.398596084258, 10155.492225388849, 13428.731425371438, 13429.731405371838, 13434.790868821852, 13440.865591344156, 13456.865431345757, 6728.125343705287, 6737.494687898427, 6868.884540117417, 6917.0, 6968.3728464438445, 6515.706793194223, 6575.210974683068, 6757.459403247728, 6789.490788190906, 6822.0, 4192.0, 4322.330038843984, 4335.804888780012, 4346.913061738811, 4466.6873318867365, 1808.9005104719367, 1851.129968914608, 1852.7869295030973, 1856.5080253732772, 1865.5988962373156, 743.22333161846, 838.266516797802, 847.5592691800352, 910.4218821048681, 928.8467402878449, 16205.837941620433, 16465.506034818976, 16855.241514131892, 17573.20920558578, 17615.64768704619, 842.3651941870808, 883.394874510962, 892.8258989496986, 909.1584342271358, 953.1183655118987, 9182.540872956326, 9433.858492122623, 9632.47021413815, 9811.950940245395, 9822.557984890696, 368764.0, 371048.0, 381112.0, 381631.0, 383801.0, 75092.0, 75990.0, 78515.60742196253, 80164.59917700374, 80229.0, 908.3323757038571, 916.8808054952947, 925.6436272035315, 930.3673502018557, 936.4802534593366, 14552.345144468736, 14634.195119268543, 14996.775048374502, 15255.0, 15528.835337349517, 34927.9521614352, 35620.46569301411, 35848.82075589606, 36316.0, 36417.0, 46843.29735053909, 48983.755081224364, 50458.24312635387, 50549.74725126351, 50592.49407506025, 7640.656170472454, 7810.140884502814, 7812.492188007761, 7893.131755506774, 7899.565523896241, 9391.906080939103, 11129.16531260175, 11279.210455268052, 11430.028447581715, 12153.331566763913, 10232.386056836614, 10422.530986105492, 10433.113185379329, 10585.776986832543, 10724.731881702708, 10005.799884002425, 10356.948215258875, 10380.480975951323, 10382.636607718694, 10404.47977601132, 10049.949750251495, 10291.742706432398, 10431.16550675929, 10496.895031049897, 10805.189610779065, 7383.741569045058, 7849.882251766114, 7876.763697089096, 8077.555734434663, 8182.549959752271, 8816.559172041243, 10013.0, 10091.899081009096, 10269.486525673537, 10511.369317840648], "value": 8095.775040614926, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1202.3387137074608, 1310.55441150009, 1352.188686787927, 1396.7206558688258, 1636.7217573012588, 6504.44712199463, 7625.123110842254, 7795.922040779584, 7848.764537063883, 8107.0, 7874.291313781759, 7933.523988560691, 7935.9603201984055, 7944.563049032301, 7951.40364472664, 2207.2716003718733, 2235.172985995176, 2236.083205885587, 2236.340279617517, 2238.820894328458, 2149.8065174134326, 2192.8355373347063, 2207.7350717913873, 2208.7901649343285, 2211.635080211763, 2135.9786402135937, 2144.185209620342, 2147.849650524456, 2148.6239908016037, 2197.9340619781433, 2111.419359676086, 2115.788421157887, 2126.3089495913814, 2131.6482780341225, 2134.7011418401435, 2069.7205877206534, 2160.5030842906062, 2181.2801775414077, 2188.8577242479187, 2190.69330293759, 7862.213778622149, 7913.920860791433, 7940.483868548526, 7940.523568585845, 7941.36469082472, 4284.785760711952, 4415.315626077963, 4428.247197976351, 4429.247028005247, 4432.933505997413, 2976.300569366197, 3105.503119500869, 3135.04381163745, 3145.339478709474, 3161.525771134325, 940.6378544260441, 959.7840485890689, 960.5581432541039, 978.9510524473748, 983.4738414947968, 10775.892241077567, 10800.675979720621, 10834.566617335297, 10835.674929752042, 10924.726881828017, 4100.220958017967, 4137.358709400048, 4196.5803419658405, 4197.118605092904, 4244.2996905510245, 8637.179467950535, 8643.394962352575, 8653.221210091122, 8655.26430253423, 8664.133586641285, 4523.208438523246, 4527.4340707411575, 4527.705699129546, 4529.252673308933, 4533.365328853963, 3642.5993140754495, 3727.3663477208397, 4104.958950410517, 4199.160167966358, 4230.365445183247, 3414.334204830034, 3454.896353109411, 3527.647235276503, 3538.6992105671306, 3568.5004099425582, 1040.2094408249634, 1059.2267644619317, 1062.5377960587016, 1065.504540388723, 1071.844582535528, 702.364360253971, 776.0454640791745, 779.6998155710093, 802.9718959836378, 859.5487369131256, 5247.501487358695, 5267.420583735786, 5462.890742185134, 5535.363433205233, 5536.114221724505, 765.276813411321, 793.714262865368, 814.8207394373228, 837.0833936839273, 862.5816479007676, 3577.8211089445945, 3783.1109689223013, 3835.290471262825, 3850.3454412749898, 3870.4000879863665, 9754.365966212174, 10156.593736250466, 10184.287099903084, 10184.338018028804, 10201.38791672502, 10107.98920107998, 10113.595456181818, 10134.138598219086, 10145.528898309703, 10155.339902906435, 7122.537035092702, 7294.124705035323, 7321.414286857145, 7324.414046876237, 7335.4131669466315, 5205.219217117311, 5246.344206974129, 5349.545288650508, 5479.0, 5551.25058117149, 8801.16388943075, 8950.373473856767, 9051.954740226256, 9068.954655226682, 9196.586153623237, 9238.722838314861, 9340.112689294507, 9397.85903211466, 9418.199453046567, 9436.292278079309, 6359.173307470184, 6463.967680161569, 6497.382748638872, 6500.219973603198, 6511.120998665309, 7315.926840731525, 7371.0, 7378.815529611803, 7477.775666730006, 7575.393968482618, 6639.0, 6646.302138275605, 6684.43182329488, 6710.16122984627, 6724.865502689821, 6326.9683651581445, 6432.710528026344, 6459.289478157305, 6628.668566571556, 6630.171228596426, 6085.695715214133, 6285.811425657238, 6354.3963323484195, 6496.74013039492, 6515.0, 4620.768961551977, 4662.510436404129, 4755.83354582588, 4762.857114286577, 4837.95162048375, 4535.931961020521, 4616.0, 4662.53374662531, 4668.066386722601, 4668.416447944007], "value": 4122.711877788506, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.25553350024444, 299.707784909713, 303.11792683291617, 307.68308642098646, 309.0373486589275, 4716.528347165286, 4723.740194289313, 4738.834140805073, 4745.976270118645, 4751.8812029699275, 4657.976710116444, 4681.85954421367, 4682.648801339905, 4689.32004859296, 4692.85921422357, 3760.75555088919, 3762.3039737648487, 3769.4722738816586, 3778.8299526521237, 3799.164183879542, 3672.614375490575, 3781.825450366037, 3791.829367678461, 3798.8670396536127, 3805.6574908258253, 3717.7955212463303, 3722.3113723961155, 3724.888253352404, 3742.0644838790304, 3762.322781899257, 3694.371956767356, 3714.3685573452444, 3716.6840818530186, 3719.702423806089, 3721.4603882436895, 3097.2832600879037, 3186.4423725847882, 3285.129440698217, 3299.4226010448315, 3322.5514555534937, 4535.591796738277, 4548.0903819235955, 4563.931541026887, 4588.105319462706, 4601.976990115061, 4227.302495088306, 4247.939749221625, 4258.5528519505315, 4262.936055959163, 4268.658507319407, 4633.49031606523, 4734.455537613184, 4931.991485194028, 4932.865440948566, 4981.181868617965, 3591.1560783215923, 3609.2601016791496, 3611.2416392557593, 3621.3481573316794, 3621.9275614487565, 5154.793808247666, 5167.398106586932, 5169.896602067975, 5174.818881339173, 5235.685858848519, 3638.1086633774894, 3710.1263861750185, 3732.346839303111, 3739.9252014959548, 3743.981280093582, 3747.8126093695205, 3757.1734218471893, 3759.191773768653, 3764.134249122717, 3782.7705995551555, 4216.831326746896, 4226.598473145046, 4256.851010214689, 4258.382534532538, 4262.957370426318, 4872.0, 4881.975590122027, 4897.583705385011, 4902.28916807061, 4903.975480122648, 1233.1183204009176, 1296.9092163548462, 1305.536534530228, 1306.137948953686, 1306.6145487081333, 826.3843436639742, 839.290799274612, 858.7805316450655, 859.5702148925537, 869.4913475616835, 3874.593167717349, 3886.6113388661474, 3904.7852368119457, 3935.763854168759, 3946.3291240489184, 4439.5138722315605, 4448.777561121931, 4451.287793952952, 4476.4180656514445, 4521.88695282614, 3874.3607304794955, 3881.331027658078, 3893.8053097345587, 3951.7036222283446, 3959.1883663848844, 2303.527776805751, 2357.2574638988663, 2384.070212617081, 2384.213209640849, 2403.663487111806, 4260.360945858146, 4556.157110934421, 4634.397528321297, 4762.857114286577, 4791.47293797682, 5177.689338639693, 5210.6613070150315, 5218.530332270114, 5237.083510385667, 5251.474852514794, 4742.57316841479, 4760.190767569521, 4760.881192919592, 4768.904621907612, 4771.95228047715, 4918.631102667386, 5024.598032157492, 5049.924251136309, 5063.240513922941, 5075.543201111917, 4949.232868905326, 4954.59702044501, 4955.950440495549, 4961.652684312064, 4961.70229786214, 4763.880902977453, 4777.283407488904, 4780.4741478436645, 4785.425748910153, 4786.353842231254, 3618.2582570572445, 3726.571444283942, 3726.757760745543, 3740.1958578905665, 3764.378877485184, 5084.694918304914, 5103.311053007945, 5108.0, 5121.513456221653, 5134.8973020540125, 4987.10232158215, 5002.399712034434, 5008.373953255843, 5023.874403139805, 5025.824096156617, 4654.790534425892, 4966.776495057803, 4976.552110310089, 4979.302897594267, 4982.4768399317545, 4888.6577939543895, 4889.437714662859, 4905.0, 4920.606351491943, 4920.6555541111775, 5021.748912554431, 5022.146235139962, 5041.344625198701, 5042.722650254272, 5052.242163675478, 4753.453352864465, 4801.815174095848, 4857.809836590058, 4859.70841749482, 4863.7324947128245], "value": 3703.5616299162466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.1427972300304, 622.1911515030461, 666.8266250774797, 673.0341959288421, 727.4071631620233, 11930.642080737582, 13013.08908376414, 13025.283609401478, 13057.281849498286, 13058.412371443284, 12207.389630518483, 12266.754664906719, 12312.753744925118, 12406.255624662526, 12529.310887901162, 594.5184400635477, 730.8903664450348, 733.0616810482564, 748.6930358552994, 751.3763576231714, 739.7263012685314, 750.9361704255144, 751.6955632968629, 751.7857410637969, 751.8308380614372, 791.584418180457, 792.3463142907096, 793.0, 793.4009822583942, 793.5516433215215, 627.8775638750445, 763.7861398808343, 783.4046124945025, 783.6160281462071, 786.7049856303886, 575.4188269847484, 575.7178982298685, 576.5963825322291, 576.7058800011982, 577.0680351232729, 12531.812022819666, 12549.058820588401, 12580.056495762854, 12591.24452532851, 12610.873891261062, 12561.811572826415, 12750.553730619476, 13094.0, 13100.541481048118, 13581.11722738019, 11427.828582571268, 11625.825612615818, 11757.941210293979, 11873.703157421134, 12063.396830158457, 4141.192467468844, 4144.689148313889, 4151.12826306476, 4340.348947657877, 4350.804213810355, 59514.702426488024, 59610.779790142915, 62307.37692623107, 62606.0, 62689.68655156695, 10038.799224015625, 10163.542640581192, 10325.896741032493, 10413.11488523484, 10721.142308615292, 19040.809591903904, 19051.714224286647, 19052.80947190538, 19062.0, 19069.523261918563, 6782.864342713118, 6893.103896493524, 6915.723371065101, 7092.4680648951535, 7121.608311542915, 3797.696184305304, 3838.1747924196434, 3849.4610754494392, 3874.0, 3931.6461518463475, 2633.7761290290155, 2644.7090820009785, 2722.1153125234378, 2726.795490338193, 2734.53512902807, 464.6236548395743, 472.8085125524168, 475.67416319821035, 480.17889409110813, 483.6541872561136, 466.4495894844049, 475.66227978135913, 478.5262590035909, 538.7737150396842, 544.7385255077585, 7008.264132266038, 7445.776626701099, 7467.327940485382, 7467.834328044914, 7572.9360645561765, 529.629259518331, 544.6623093681942, 557.0975317898098, 569.4191924237338, 570.8354955890021, 3050.871177664234, 3088.0118362123903, 3145.8269795161486, 3371.780834245766, 3471.965280347164, 82729.17270827336, 100721.49639251757, 103819.48090259501, 106345.46827265814, 106391.46804266084, 51077.23384149241, 66721.66639166774, 83484.0, 85727.0, 89324.5533772327, 73924.99999999892, 80541.5972920155, 80695.59652201818, 81411.0, 82134.58932705298, 5878.647281163144, 6039.909401359071, 6055.212822333068, 6265.592736472115, 6363.172787537591, 45476.8630784233, 47018.29472557983, 47299.290510643055, 48484.3030493931, 49154.0, 59334.0, 68199.0, 69052.65473672599, 70122.0, 73171.63414182895, 9356.578953947224, 10023.74940626461, 10036.347637403545, 10129.341592796445, 10301.072903438726, 10399.376037437778, 10507.684769456928, 10628.787424251626, 10645.148388129086, 10725.195610329101, 7628.847423051397, 7785.961070194613, 7982.441229113906, 8016.318612917968, 8030.678772849021, 9550.37922535033, 9614.18279446255, 9665.9033409665, 10237.74405639865, 10315.742106447098, 9579.616815327308, 9608.183304418922, 9640.373375730554, 9749.56126974302, 9991.700248992542, 8639.654413823628, 8724.65101395937, 8928.285737140883, 8945.0, 9150.084991500797, 8222.506649601042, 8247.907034861926, 8258.752237432887, 8401.621927013177, 8641.135886411308], "value": 6180.8432618702955, "unit": "score"}], "name": "dromaeo_css", "value": 6203.168616388954, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 131.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 116.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 157.0, 180.0, 132.0, 151.0, 154.0, 123.0, 197.0, 122.0], "value": 149.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 160.0, 161.0, 161.0, 162.0, 162.0, 162.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 157.0, 77.0, 75.0, 76.0, 76.0, 164.0, 76.0], "value": 92.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 134.0, 84.0, 84.0, 85.0, 136.0, 84.0, 84.0, 131.0], "value": 99.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 117.0, 116.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 148.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 65.0, 64.0, 65.0, 65.0, 65.0, 64.0, 65.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 67.0, 68.0, 67.0, 68.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 85.0, 89.0, 83.0, 83.0, 92.0, 84.0, 91.0, 88.0, 83.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 143.0, 134.0, 133.0, 132.0, 131.0, 132.0, 132.0, 183.0, 136.0], "value": 139.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 176.0, 175.0, 174.0, 175.0, 178.0, 177.0, 182.0, 178.0], "value": 177.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 69.0, 68.0, 68.0, 67.0, 68.0, 68.0, 67.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1580.3, "alertThreshold": 2.0}]} 16:15:35 INFO - Return code: 0 16:15:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:15:35 INFO - # TBPL SUCCESS # 16:15:35 INFO - Running post-action listener: _resource_record_post_action 16:15:35 INFO - [mozharness: 2016-08-31 23:15:35.417079Z] Finished run-tests step (success) 16:15:35 INFO - Running post-run listener: _resource_record_post_run 16:15:35 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 7843840; Write bytes: 415334400; Read time: 1696; Write time: 287484 16:15:35 INFO - TinderboxPrint: CPU usage
13.1% 16:15:35 INFO - TinderboxPrint: I/O read bytes / time
7,843,840 / 1,696 16:15:35 INFO - TinderboxPrint: I/O write bytes / time
415,334,400 / 287,484 16:15:35 INFO - TinderboxPrint: CPU idle
8,343.9 (86.9%) 16:15:35 INFO - TinderboxPrint: CPU user
1,203.6 (12.5%) 16:15:35 INFO - TinderboxPrint: Swap in / out
0 / 0 16:15:35 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 92827648; Read time: 36; Write time: 58444 16:15:35 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 5603328; Write bytes: 322506752; Read time: 1456; Write time: 229040 16:15:36 INFO - Running post-run listener: _upload_blobber_files 16:15:36 INFO - Blob upload gear active. 16:15:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:15:36 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 16:15:36 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'] 16:15:36 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 16:15:36 INFO - (blobuploader) - INFO - Open directory for files ... 16:15:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:15:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:15:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:37 INFO - (blobuploader) - INFO - Done attempting. 16:15:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:15:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:15:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:38 INFO - (blobuploader) - INFO - Done attempting. 16:15:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:15:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:15:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:41 INFO - (blobuploader) - INFO - Done attempting. 16:15:41 INFO - (blobuploader) - INFO - Iteration through files over. 16:15:41 INFO - Return code: 0 16:15:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:15:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:15:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cef1f3ffdb3ad511572b9dea4a340d25efd40e709cc1f9f09f45f369050941fe8359607b0a11335bdd7b3d33bedfccc84c5b189fb6bf15df6a6fa93161ac92b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/360c5c4a94f5f0838750e15f50db54db74b8cc53bc91984be5163fb73bc1d3ab1278eb94e4cf0caf91b2e6256813ea21aa0488fbbbff1dd26d555a438f62bacb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6ba5d13eefdc1c0528567111e43ef21d247f6c42debce602f297668a3d745250b54d67a3b0038d3e03c6de73b2ca65724a25981eccb0b1faa0b25a6059eb4a73"} 16:15:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:15:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:15:41 INFO - Contents: 16:15:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cef1f3ffdb3ad511572b9dea4a340d25efd40e709cc1f9f09f45f369050941fe8359607b0a11335bdd7b3d33bedfccc84c5b189fb6bf15df6a6fa93161ac92b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/360c5c4a94f5f0838750e15f50db54db74b8cc53bc91984be5163fb73bc1d3ab1278eb94e4cf0caf91b2e6256813ea21aa0488fbbbff1dd26d555a438f62bacb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6ba5d13eefdc1c0528567111e43ef21d247f6c42debce602f297668a3d745250b54d67a3b0038d3e03c6de73b2ca65724a25981eccb0b1faa0b25a6059eb4a73"} 16:15:41 INFO - Running post-run listener: copy_logs_to_upload_dir 16:15:41 INFO - Copying logs to upload dir... 16:15:41 INFO - mkdir: /builds/slave/test/build/upload/logs 16:15:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.629919 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-08-31 16:15:41.826512) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 16:15:41.830572) ========= 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=337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797 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/cef1f3ffdb3ad511572b9dea4a340d25efd40e709cc1f9f09f45f369050941fe8359607b0a11335bdd7b3d33bedfccc84c5b189fb6bf15df6a6fa93161ac92b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/360c5c4a94f5f0838750e15f50db54db74b8cc53bc91984be5163fb73bc1d3ab1278eb94e4cf0caf91b2e6256813ea21aa0488fbbbff1dd26d555a438f62bacb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6ba5d13eefdc1c0528567111e43ef21d247f6c42debce602f297668a3d745250b54d67a3b0038d3e03c6de73b2ca65724a25981eccb0b1faa0b25a6059eb4a73"} build_url:https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://queue.taskcluster.net/v1/task/Us_gw_OZQHqXVFNa_v7KOA/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/cef1f3ffdb3ad511572b9dea4a340d25efd40e709cc1f9f09f45f369050941fe8359607b0a11335bdd7b3d33bedfccc84c5b189fb6bf15df6a6fa93161ac92b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/360c5c4a94f5f0838750e15f50db54db74b8cc53bc91984be5163fb73bc1d3ab1278eb94e4cf0caf91b2e6256813ea21aa0488fbbbff1dd26d555a438f62bacb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6ba5d13eefdc1c0528567111e43ef21d247f6c42debce602f297668a3d745250b54d67a3b0038d3e03c6de73b2ca65724a25981eccb0b1faa0b25a6059eb4a73"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 16:15:41.860602) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 16:15:41.861001) ========= 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=337fe3f4b1febe37b85fccc40000019e-1472683407.618998-14581797 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004430 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 16:15:41.947200) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 16:15:41.947640) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 16:15:41.948111) ========= ========= Total master_lag: 1.69 =========