builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-055 starttime: 1472809902.68 results: success (0) buildid: 20160902012641 builduid: 4593c621147649d2a9008e3702bfe1ea revision: 84bab1407100af7215a1f19df176295fc2287410 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:42.685591) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:42.686107) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:42.686448) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:42.722606) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:42.722945) ========= 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=bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-02 02:51:42-- 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% 219M=0s 2016-09-02 02:51:42 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117325 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:42.860187) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:42.860558) ========= 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=bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016523 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:42.912926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:42.916356) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 84bab1407100af7215a1f19df176295fc2287410 --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 84bab1407100af7215a1f19df176295fc2287410 --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=bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-02 02:51:43,109 truncating revision to first 12 chars 2016-09-02 02:51:43,109 Setting DEBUG logging. 2016-09-02 02:51:43,109 attempt 1/10 2016-09-02 02:51:43,109 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/84bab1407100?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-02 02:51:43,672 unpacking tar archive at: autoland-84bab1407100/testing/mozharness/ program finished with exit code 0 elapsedTime=0.886698 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:43.820825) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:43.821584) ========= script_repo_revision: 84bab1407100af7215a1f19df176295fc2287410 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:43.822683) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:43.823477) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 02:51:43.835136) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-02 02:51:43.835784) ========= /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=bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:51:44 INFO - MultiFileLogger online at 20160902 02:51:44 in /builds/slave/test 02:51:44 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 02:51:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:51:44 INFO - {'append_to_log': False, 02:51:44 INFO - 'base_work_dir': '/builds/slave/test', 02:51:44 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 02:51:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:51:44 INFO - 'branch': 'Autoland-Non-PGO', 02:51:44 INFO - 'buildbot_json_path': 'buildprops.json', 02:51:44 INFO - 'config_files': ('talos/linux_config.py',), 02:51:44 INFO - 'default_actions': ('clobber', 02:51:44 INFO - 'read-buildbot-config', 02:51:44 INFO - 'download-and-extract', 02:51:44 INFO - 'populate-webroot', 02:51:44 INFO - 'create-virtualenv', 02:51:44 INFO - 'install', 02:51:44 INFO - 'run-tests'), 02:51:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:51:44 INFO - 'download_minidump_stackwalk': True, 02:51:44 INFO - 'download_symbols': 'ondemand', 02:51:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:51:44 INFO - 'tooltool.py': '/tools/tooltool.py', 02:51:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:51:44 INFO - '/tools/misc-python/virtualenv.py')}, 02:51:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:51:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:51:44 INFO - 'installer_path': 'installer.exe', 02:51:44 INFO - 'log_level': 'info', 02:51:44 INFO - 'log_name': 'talos', 02:51:44 INFO - 'log_to_console': True, 02:51:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:51:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:51:44 INFO - 'opt_config_files': (), 02:51:44 INFO - 'pip_index': False, 02:51:44 INFO - 'sps_profile': False, 02:51:44 INFO - 'sps_profile_interval': 0, 02:51:44 INFO - 'suite': 'dromaeojs', 02:51:44 INFO - 'system_bits': '32', 02:51:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:51:44 INFO - 'title': 'talos-linux64-ix-055', 02:51:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:51:44 INFO - 'use_talos_json': True, 02:51:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:51:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:51:44 INFO - 'work_dir': 'build'} 02:51:44 INFO - [mozharness: 2016-09-02 09:51:44.123519Z] Running clobber step. 02:51:44 INFO - Running pre-action listener: _resource_record_pre_action 02:51:44 INFO - Running main action method: clobber 02:51:44 INFO - rmtree: /builds/slave/test/build 02:51:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:51:44 INFO - Running post-action listener: _resource_record_post_action 02:51:44 INFO - [mozharness: 2016-09-02 09:51:44.572506Z] Finished clobber step (success) 02:51:44 INFO - [mozharness: 2016-09-02 09:51:44.572609Z] Running read-buildbot-config step. 02:51:44 INFO - Running pre-action listener: _resource_record_pre_action 02:51:44 INFO - Running main action method: read_buildbot_config 02:51:44 INFO - Using buildbot properties: 02:51:44 INFO - { 02:51:44 INFO - "project": "", 02:51:44 INFO - "product": "firefox", 02:51:44 INFO - "script_repo_revision": "production", 02:51:44 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 02:51:44 INFO - "repository": "", 02:51:44 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 02:51:44 INFO - "buildid": "20160902012641", 02:51:44 INFO - "pgo_build": "False", 02:51:44 INFO - "basedir": "/builds/slave/test", 02:51:44 INFO - "buildnumber": 398, 02:51:44 INFO - "slavename": "talos-linux64-ix-055", 02:51:44 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:51:44 INFO - "platform": "ubuntu64_hw", 02:51:44 INFO - "scheduler": "tests-autoland-linux64-talos", 02:51:44 INFO - "branch": "autoland", 02:51:44 INFO - "repo_path": "integration/autoland", 02:51:44 INFO - "revision": "84bab1407100af7215a1f19df176295fc2287410", 02:51:44 INFO - "stage_platform": "linux64", 02:51:44 INFO - "builduid": "4593c621147649d2a9008e3702bfe1ea", 02:51:44 INFO - "slavebuilddir": "test" 02:51:44 INFO - } 02:51:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 02:51:44 INFO - Running post-action listener: _resource_record_post_action 02:51:44 INFO - [mozharness: 2016-09-02 09:51:44.575058Z] Finished read-buildbot-config step (success) 02:51:44 INFO - [mozharness: 2016-09-02 09:51:44.575146Z] Running download-and-extract step. 02:51:44 INFO - Running pre-action listener: _resource_record_pre_action 02:51:44 INFO - Running main action method: download_and_extract 02:51:44 INFO - mkdir: /builds/slave/test/build/tests 02:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:51:44 INFO - trying https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:51:44 INFO - Downloading https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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 02:51:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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 02:51:45 INFO - Downloaded 1361 bytes. 02:51:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:51:45 INFO - Using the following test package requirements: 02:51:45 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 02:51:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:51:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:51:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:51:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:51:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:51:45 INFO - u'jsshell-linux-x86_64.zip'], 02:51:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:51:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:51:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 02:51:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:51:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:51:45 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:51:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:51:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:51:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:51:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:51:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:51:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:51:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:51:45 INFO - trying https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:51:45 INFO - Downloading https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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 02:51:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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 02:51:46 INFO - Downloaded 20335050 bytes. 02:51:46 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 02:51:50 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 02:51:50 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')]} 02:51:50 INFO - trying https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:51:50 INFO - Downloading https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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 02:51:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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 02:51:51 INFO - Downloaded 20335050 bytes. 02:51:51 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 02:51:56 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')]} 02:51:56 INFO - trying https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 02:51:56 INFO - Downloading https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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 02:51:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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 02:51:57 INFO - Downloaded 14413998 bytes. 02:51:57 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 02:51:57 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')]} 02:51:57 INFO - trying https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:51:57 INFO - Downloading https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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 02:51:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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 02:52:00 INFO - Downloaded 55870814 bytes. 02:52:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:52:00 INFO - mkdir: /builds/slave/test/properties 02:52:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:52:00 INFO - Writing to file /builds/slave/test/properties/build_url 02:52:00 INFO - Contents: 02:52:00 INFO - build_url:https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:52:01 INFO - Running post-action listener: _resource_record_post_action 02:52:01 INFO - Running post-action listener: set_extra_try_arguments 02:52:01 INFO - [mozharness: 2016-09-02 09:52:01.027653Z] Finished download-and-extract step (success) 02:52:01 INFO - [mozharness: 2016-09-02 09:52:01.027881Z] Running populate-webroot step. 02:52:01 INFO - Running pre-action listener: _resource_record_pre_action 02:52:01 INFO - Running main action method: populate_webroot 02:52:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:52:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:52:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:52:01 INFO - '--tppagecycles', 02:52:01 INFO - '7'], 02:52:01 INFO - 'tests': ['tsvgm']}}, 02:52:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:52:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:52:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:52:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:52:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:01 INFO - 'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:52:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:52:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:01 INFO - 'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['damp', 'tps']}, 02:52:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:01 INFO - 'tests': ['damp', 'tps']}, 02:52:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['dromaeo_dom']}, 02:52:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:52:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['basic_compositor_video']}, 02:52:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:52:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['a11yr', 02:52:01 INFO - 'ts_paint', 02:52:01 INFO - 'tpaint', 02:52:01 INFO - 'sessionrestore', 02:52:01 INFO - 'sessionrestore_no_auto_restore', 02:52:01 INFO - 'tabpaint']}, 02:52:01 INFO - 'other-e10s': {'tests': ['a11yr', 02:52:01 INFO - 'ts_paint', 02:52:01 INFO - 'tpaint', 02:52:01 INFO - 'sessionrestore', 02:52:01 INFO - 'sessionrestore_no_auto_restore', 02:52:01 INFO - 'tabpaint']}, 02:52:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:52:01 INFO - 'ts_paint', 02:52:01 INFO - 'tpaint', 02:52:01 INFO - 'sessionrestore', 02:52:01 INFO - 'sessionrestore_no_auto_restore', 02:52:01 INFO - 'tabpaint']}, 02:52:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:52:01 INFO - 'ts_paint', 02:52:01 INFO - 'tpaint', 02:52:01 INFO - 'sessionrestore', 02:52:01 INFO - 'sessionrestore_no_auto_restore', 02:52:01 INFO - 'tabpaint']}, 02:52:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['a11yr', 02:52:01 INFO - 'ts_paint', 02:52:01 INFO - 'tpaint', 02:52:01 INFO - 'sessionrestore', 02:52:01 INFO - 'sessionrestore_no_auto_restore', 02:52:01 INFO - 'tabpaint']}, 02:52:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['a11yr', 02:52:01 INFO - 'ts_paint', 02:52:01 INFO - 'tpaint', 02:52:01 INFO - 'sessionrestore', 02:52:01 INFO - 'sessionrestore_no_auto_restore', 02:52:01 INFO - 'tabpaint']}, 02:52:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['tsvgx', 02:52:01 INFO - 'tsvgr_opacity', 02:52:01 INFO - 'tart', 02:52:01 INFO - 'tscrollx', 02:52:01 INFO - 'cart']}, 02:52:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:52:01 INFO - 'tsvgr_opacity', 02:52:01 INFO - 'tart', 02:52:01 INFO - 'tscrollx', 02:52:01 INFO - 'cart']}, 02:52:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:01 INFO - 'talos_options': ['--disable-e10s'], 02:52:01 INFO - 'tests': ['tp5o']}, 02:52:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:01 INFO - 'tests': ['tp5o']}, 02:52:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:01 INFO - 'talos_options': ['--disable-e10s', 02:52:01 INFO - '--xperf_path', 02:52:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:52:01 INFO - 'tests': ['tp5n']}, 02:52:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:01 INFO - 'talos_options': ['--xperf_path', 02:52:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:52:01 INFO - 'tests': ['tp5n']}}, 02:52:01 INFO - 'talos.zip': {'path': '', 02:52:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:52:01 INFO - Running post-action listener: _resource_record_post_action 02:52:01 INFO - [mozharness: 2016-09-02 09:52:01.049591Z] Finished populate-webroot step (success) 02:52:01 INFO - [mozharness: 2016-09-02 09:52:01.049681Z] Running create-virtualenv step. 02:52:01 INFO - Running pre-action listener: _resource_record_pre_action 02:52:01 INFO - Running main action method: create_virtualenv 02:52:01 INFO - Creating virtualenv /builds/slave/test/build/venv 02:52:01 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 02:52:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:52:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:52:01 INFO - Using real prefix '/usr' 02:52:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:52:01 INFO - Installing distribute.............................................................................................................................................................................................done. 02:52:03 INFO - Installing pip.................done. 02:52:03 INFO - Return code: 0 02:52:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:03 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 0x7fceadd9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', '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 02:52:03 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 02:52:03 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 02:52:03 INFO - Using env: {'DISPLAY': ':0', 02:52:03 INFO - 'HOME': '/home/cltbld', 02:52:03 INFO - 'LANG': 'en_US.UTF-8', 02:52:03 INFO - 'LANGUAGE': 'en_US:en', 02:52:03 INFO - 'LOGNAME': 'cltbld', 02:52:03 INFO - 'MAIL': '/var/mail/cltbld', 02:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:03 INFO - 'MOZ_NO_REMOTE': '1', 02:52:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:03 INFO - 'NO_EM_RESTART': '1', 02:52:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:03 INFO - 'PWD': '/builds/slave/test', 02:52:03 INFO - 'SHELL': '/bin/bash', 02:52:03 INFO - 'SHLVL': '1', 02:52:03 INFO - 'TERM': 'linux', 02:52:03 INFO - 'TMOUT': '86400', 02:52:03 INFO - 'USER': 'cltbld', 02:52:03 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:03 INFO - '_': '/tools/buildbot/bin/python'} 02:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:03 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 02:52:03 INFO - Cleaning up... 02:52:03 INFO - Return code: 0 02:52:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:03 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 0x7fceadd9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', '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 02:52:03 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 02:52:03 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 02:52:03 INFO - Using env: {'DISPLAY': ':0', 02:52:03 INFO - 'HOME': '/home/cltbld', 02:52:03 INFO - 'LANG': 'en_US.UTF-8', 02:52:03 INFO - 'LANGUAGE': 'en_US:en', 02:52:03 INFO - 'LOGNAME': 'cltbld', 02:52:03 INFO - 'MAIL': '/var/mail/cltbld', 02:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:03 INFO - 'MOZ_NO_REMOTE': '1', 02:52:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:03 INFO - 'NO_EM_RESTART': '1', 02:52:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:03 INFO - 'PWD': '/builds/slave/test', 02:52:03 INFO - 'SHELL': '/bin/bash', 02:52:03 INFO - 'SHLVL': '1', 02:52:03 INFO - 'TERM': 'linux', 02:52:03 INFO - 'TMOUT': '86400', 02:52:03 INFO - 'USER': 'cltbld', 02:52:03 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:03 INFO - '_': '/tools/buildbot/bin/python'} 02:52:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:04 INFO - Downloading/unpacking psutil>=3.1.1 02:52:04 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 02:52:04 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 02:52:04 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 02:52:04 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 02:52:04 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 02:52:04 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 02:52:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:52:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:52:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:52:06 INFO - Installing collected packages: psutil 02:52:06 INFO - Running setup.py install for psutil 02:52:06 INFO - building 'psutil._psutil_linux' extension 02:52:06 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 02:52:06 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 02:52:06 INFO - building 'psutil._psutil_posix' extension 02:52:06 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 02:52:06 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 02:52:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:52:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:52:06 INFO - Successfully installed psutil 02:52:06 INFO - Cleaning up... 02:52:06 INFO - Return code: 0 02:52:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:52: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')]} 02:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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')]} 02:52:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:06 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 0x7fceadd9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', '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 02:52:06 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 02:52:06 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 02:52:06 INFO - Using env: {'DISPLAY': ':0', 02:52:06 INFO - 'HOME': '/home/cltbld', 02:52:06 INFO - 'LANG': 'en_US.UTF-8', 02:52:06 INFO - 'LANGUAGE': 'en_US:en', 02:52:06 INFO - 'LOGNAME': 'cltbld', 02:52:06 INFO - 'MAIL': '/var/mail/cltbld', 02:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:06 INFO - 'MOZ_NO_REMOTE': '1', 02:52:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:06 INFO - 'NO_EM_RESTART': '1', 02:52:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:06 INFO - 'PWD': '/builds/slave/test', 02:52:06 INFO - 'SHELL': '/bin/bash', 02:52:06 INFO - 'SHLVL': '1', 02:52:06 INFO - 'TERM': 'linux', 02:52:06 INFO - 'TMOUT': '86400', 02:52:06 INFO - 'USER': 'cltbld', 02:52:06 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:06 INFO - '_': '/tools/buildbot/bin/python'} 02:52:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:52:06 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 02:52:06 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 02:52:06 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 02:52:06 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 02:52:06 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 02:52:06 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 02:52:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:52:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:52:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:52:08 INFO - Installing collected packages: mozsystemmonitor 02:52:08 INFO - Running setup.py install for mozsystemmonitor 02:52:08 INFO - Successfully installed mozsystemmonitor 02:52:08 INFO - Cleaning up... 02:52:08 INFO - Return code: 0 02:52:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:52:08 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')]} 02:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:08 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')]} 02:52:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:08 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 0x7fceadd9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', '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 02:52:08 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 02:52:08 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 02:52:08 INFO - Using env: {'DISPLAY': ':0', 02:52:08 INFO - 'HOME': '/home/cltbld', 02:52:08 INFO - 'LANG': 'en_US.UTF-8', 02:52:08 INFO - 'LANGUAGE': 'en_US:en', 02:52:08 INFO - 'LOGNAME': 'cltbld', 02:52:08 INFO - 'MAIL': '/var/mail/cltbld', 02:52:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:08 INFO - 'MOZ_NO_REMOTE': '1', 02:52:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:08 INFO - 'NO_EM_RESTART': '1', 02:52:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:08 INFO - 'PWD': '/builds/slave/test', 02:52:08 INFO - 'SHELL': '/bin/bash', 02:52:08 INFO - 'SHLVL': '1', 02:52:08 INFO - 'TERM': 'linux', 02:52:08 INFO - 'TMOUT': '86400', 02:52:08 INFO - 'USER': 'cltbld', 02:52:08 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:08 INFO - '_': '/tools/buildbot/bin/python'} 02:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:09 INFO - Downloading/unpacking jsonschema==2.5.1 02:52:09 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 02:52:09 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 02:52:09 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 02:52:09 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 02:52:09 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 02:52:09 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 02:52:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:52:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:11 INFO - Downloading functools32-3.2.3-2.tar.gz 02:52:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:52:11 INFO - warning: no files found matching '*.txt' 02:52:11 INFO - no previously-included directories found matching 'build' 02:52:11 INFO - no previously-included directories found matching 'dist' 02:52:11 INFO - no previously-included directories found matching '.git*' 02:52:11 INFO - Installing collected packages: jsonschema, functools32 02:52:11 INFO - Running setup.py install for functools32 02:52:11 INFO - warning: no files found matching '*.txt' 02:52:11 INFO - no previously-included directories found matching 'build' 02:52:11 INFO - no previously-included directories found matching 'dist' 02:52:11 INFO - no previously-included directories found matching '.git*' 02:52:11 INFO - Successfully installed jsonschema functools32 02:52:11 INFO - Cleaning up... 02:52:11 INFO - Return code: 0 02:52:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:52: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')]} 02:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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')]} 02:52:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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', '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 0x7fceadd9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', '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 02:52: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:52: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 functools32==3.2.3-2 02:52:11 INFO - Using env: {'DISPLAY': ':0', 02:52:11 INFO - 'HOME': '/home/cltbld', 02:52:11 INFO - 'LANG': 'en_US.UTF-8', 02:52:11 INFO - 'LANGUAGE': 'en_US:en', 02:52:11 INFO - 'LOGNAME': 'cltbld', 02:52:11 INFO - 'MAIL': '/var/mail/cltbld', 02:52:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:11 INFO - 'MOZ_NO_REMOTE': '1', 02:52:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:11 INFO - 'NO_EM_RESTART': '1', 02:52:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:11 INFO - 'PWD': '/builds/slave/test', 02:52:11 INFO - 'SHELL': '/bin/bash', 02:52:11 INFO - 'SHLVL': '1', 02:52:11 INFO - 'TERM': 'linux', 02:52:11 INFO - 'TMOUT': '86400', 02:52:11 INFO - 'USER': 'cltbld', 02:52:11 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:11 INFO - '_': '/tools/buildbot/bin/python'} 02:52:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:52:11 INFO - Cleaning up... 02:52:11 INFO - Return code: 0 02:52:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:52: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')]} 02:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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')]} 02:52:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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', '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 0x7fceadd9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', '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 02:52: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:52: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 blobuploader==1.2.4 02:52:11 INFO - Using env: {'DISPLAY': ':0', 02:52:11 INFO - 'HOME': '/home/cltbld', 02:52:11 INFO - 'LANG': 'en_US.UTF-8', 02:52:11 INFO - 'LANGUAGE': 'en_US:en', 02:52:11 INFO - 'LOGNAME': 'cltbld', 02:52:11 INFO - 'MAIL': '/var/mail/cltbld', 02:52:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:11 INFO - 'MOZ_NO_REMOTE': '1', 02:52:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:11 INFO - 'NO_EM_RESTART': '1', 02:52:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:11 INFO - 'PWD': '/builds/slave/test', 02:52:11 INFO - 'SHELL': '/bin/bash', 02:52:11 INFO - 'SHLVL': '1', 02:52:11 INFO - 'TERM': 'linux', 02:52:11 INFO - 'TMOUT': '86400', 02:52:11 INFO - 'USER': 'cltbld', 02:52:11 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:11 INFO - '_': '/tools/buildbot/bin/python'} 02:52:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:11 INFO - Downloading/unpacking blobuploader==1.2.4 02:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:13 INFO - Downloading blobuploader-1.2.4.tar.gz 02:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:52:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:52:13 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 02:52:13 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 02:52:13 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 02:52:13 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 02:52:13 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 02:52:13 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 02:52:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:52:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:14 INFO - Downloading docopt-0.6.1.tar.gz 02:52:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:52:14 INFO - Installing collected packages: blobuploader, requests, docopt 02:52:14 INFO - Running setup.py install for blobuploader 02:52:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:52:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:52:14 INFO - Running setup.py install for requests 02:52:15 INFO - Running setup.py install for docopt 02:52:15 INFO - Successfully installed blobuploader requests docopt 02:52:15 INFO - Cleaning up... 02:52:15 INFO - Return code: 0 02:52:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52: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')]} 02:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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')]} 02:52:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:15 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 0x7fceadd9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', '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 02:52:15 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 02:52:15 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 02:52:15 INFO - Using env: {'DISPLAY': ':0', 02:52:15 INFO - 'HOME': '/home/cltbld', 02:52:15 INFO - 'LANG': 'en_US.UTF-8', 02:52:15 INFO - 'LANGUAGE': 'en_US:en', 02:52:15 INFO - 'LOGNAME': 'cltbld', 02:52:15 INFO - 'MAIL': '/var/mail/cltbld', 02:52:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:15 INFO - 'MOZ_NO_REMOTE': '1', 02:52:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:15 INFO - 'NO_EM_RESTART': '1', 02:52:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:15 INFO - 'PWD': '/builds/slave/test', 02:52:15 INFO - 'SHELL': '/bin/bash', 02:52:15 INFO - 'SHLVL': '1', 02:52:15 INFO - 'TERM': 'linux', 02:52:15 INFO - 'TMOUT': '86400', 02:52:15 INFO - 'USER': 'cltbld', 02:52:15 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:15 INFO - '_': '/tools/buildbot/bin/python'} 02:52:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:52:15 INFO - Running setup.py (path:/tmp/pip-pTIgzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:52:15 INFO - Running setup.py (path:/tmp/pip-JdLKeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:52:15 INFO - Running setup.py (path:/tmp/pip-1R_mpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:52:15 INFO - Running setup.py (path:/tmp/pip-cDfxXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:52:15 INFO - Running setup.py (path:/tmp/pip-ehgzf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:52:15 INFO - Running setup.py (path:/tmp/pip-lb_Lck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:52:15 INFO - Running setup.py (path:/tmp/pip-0k1cmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:52:15 INFO - Running setup.py (path:/tmp/pip-KSTVek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:52:15 INFO - Running setup.py (path:/tmp/pip-mEhPDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:52:16 INFO - Running setup.py (path:/tmp/pip-6lhV_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:52:16 INFO - Running setup.py (path:/tmp/pip-zj2FcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:52:16 INFO - Running setup.py (path:/tmp/pip-1ajvqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:52:16 INFO - Running setup.py (path:/tmp/pip-2uMTnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:52:16 INFO - Running setup.py (path:/tmp/pip-1Vgc1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:16 INFO - Running setup.py (path:/tmp/pip-kcACoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:52:16 INFO - Running setup.py (path:/tmp/pip-mVwJm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:52:16 INFO - Running setup.py (path:/tmp/pip-ofc7Lh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:52:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:52:16 INFO - Running setup.py install for manifestparser 02:52:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:52:16 INFO - Running setup.py install for mozcrash 02:52:16 INFO - Running setup.py install for mozdebug 02:52:16 INFO - Running setup.py install for mozdevice 02:52:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:52:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:52:16 INFO - Running setup.py install for mozfile 02:52:17 INFO - Running setup.py install for mozhttpd 02:52:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Running setup.py install for mozinfo 02:52:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Running setup.py install for mozInstall 02:52:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Running setup.py install for mozleak 02:52:17 INFO - Running setup.py install for mozlog 02:52:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Running setup.py install for moznetwork 02:52:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Running setup.py install for mozprocess 02:52:17 INFO - Running setup.py install for mozprofile 02:52:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Running setup.py install for mozrunner 02:52:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:52:18 INFO - Running setup.py install for mozscreenshot 02:52:18 INFO - Running setup.py install for moztest 02:52:18 INFO - Running setup.py install for mozversion 02:52:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:52:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:52:18 INFO - Cleaning up... 02:52:18 INFO - Return code: 0 02:52:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52: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')]} 02:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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')]} 02:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:18 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 0x7fceadd9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', '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 02:52:18 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 02:52:18 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 02:52:18 INFO - Using env: {'DISPLAY': ':0', 02:52:18 INFO - 'HOME': '/home/cltbld', 02:52:18 INFO - 'LANG': 'en_US.UTF-8', 02:52:18 INFO - 'LANGUAGE': 'en_US:en', 02:52:18 INFO - 'LOGNAME': 'cltbld', 02:52:18 INFO - 'MAIL': '/var/mail/cltbld', 02:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:18 INFO - 'MOZ_NO_REMOTE': '1', 02:52:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:18 INFO - 'NO_EM_RESTART': '1', 02:52:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:18 INFO - 'PWD': '/builds/slave/test', 02:52:18 INFO - 'SHELL': '/bin/bash', 02:52:18 INFO - 'SHLVL': '1', 02:52:18 INFO - 'TERM': 'linux', 02:52:18 INFO - 'TMOUT': '86400', 02:52:18 INFO - 'USER': 'cltbld', 02:52:18 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:18 INFO - '_': '/tools/buildbot/bin/python'} 02:52:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:52:18 INFO - Running setup.py (path:/tmp/pip-p_CQow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:52:18 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)) 02:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:52:18 INFO - Running setup.py (path:/tmp/pip-89G0AY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:52:18 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)) 02:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:52:18 INFO - Running setup.py (path:/tmp/pip-kWhmiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:52:18 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)) 02:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:52:18 INFO - Running setup.py (path:/tmp/pip-hAqdbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:52:18 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)) 02:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:52:18 INFO - Running setup.py (path:/tmp/pip-1KTjPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:52:18 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)) 02:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:52:18 INFO - Running setup.py (path:/tmp/pip-u87Tfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:52:19 INFO - Running setup.py (path:/tmp/pip-hoV6al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:52:19 INFO - Running setup.py (path:/tmp/pip-4fN1SW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:52:19 INFO - Running setup.py (path:/tmp/pip-7EUizW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:52:19 INFO - Running setup.py (path:/tmp/pip-ynLefs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:52:19 INFO - Running setup.py (path:/tmp/pip-qP_01k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:52:19 INFO - Running setup.py (path:/tmp/pip-bQI0BZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:52:19 INFO - Running setup.py (path:/tmp/pip-w6mCWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:52:19 INFO - Running setup.py (path:/tmp/pip-p64wz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:19 INFO - Running setup.py (path:/tmp/pip-D9HbOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:52:19 INFO - Running setup.py (path:/tmp/pip-Ob1LcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:52:19 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)) 02:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:52:19 INFO - Running setup.py (path:/tmp/pip-9WoU7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:52:19 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)) 02:52:19 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)) 02:52:19 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)) 02:52:19 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)) 02:52:19 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)) 02:52:19 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)) 02:52:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:21 INFO - Downloading blessings-1.6.tar.gz 02:52:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:52:21 INFO - Installing collected packages: blessings 02:52:21 INFO - Running setup.py install for blessings 02:52:21 INFO - Successfully installed blessings 02:52:21 INFO - Cleaning up... 02:52:21 INFO - Return code: 0 02:52:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:52:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:52:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:52:22 INFO - Reading from file tmpfile_stdout 02:52:22 INFO - Current package versions: 02:52:22 INFO - argparse == 1.2.1 02:52:22 INFO - blessings == 1.6 02:52:22 INFO - blobuploader == 1.2.4 02:52:22 INFO - docopt == 0.6.1 02:52:22 INFO - functools32 == 3.2.3-2 02:52:22 INFO - jsonschema == 2.5.1 02:52:22 INFO - manifestparser == 1.1 02:52:22 INFO - mozInstall == 1.12 02:52:22 INFO - mozcrash == 0.17 02:52:22 INFO - mozdebug == 0.1 02:52:22 INFO - mozdevice == 0.48 02:52:22 INFO - mozfile == 1.2 02:52:22 INFO - mozhttpd == 0.7 02:52:22 INFO - mozinfo == 0.9 02:52:22 INFO - mozleak == 0.1 02:52:22 INFO - mozlog == 3.3 02:52:22 INFO - moznetwork == 0.27 02:52:22 INFO - mozprocess == 0.23 02:52:22 INFO - mozprofile == 0.28 02:52:22 INFO - mozrunner == 6.12 02:52:22 INFO - mozscreenshot == 0.1 02:52:22 INFO - mozsystemmonitor == 0.3 02:52:22 INFO - moztest == 0.7 02:52:22 INFO - mozversion == 1.4 02:52:22 INFO - psutil == 3.1.1 02:52:22 INFO - requests == 1.2.3 02:52:22 INFO - wsgiref == 0.1.2 02:52:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:22 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 0x7fceadd9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', '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 02:52:22 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 02:52:22 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 02:52:22 INFO - Using env: {'DISPLAY': ':0', 02:52:22 INFO - 'HOME': '/home/cltbld', 02:52:22 INFO - 'LANG': 'en_US.UTF-8', 02:52:22 INFO - 'LANGUAGE': 'en_US:en', 02:52:22 INFO - 'LOGNAME': 'cltbld', 02:52:22 INFO - 'MAIL': '/var/mail/cltbld', 02:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:22 INFO - 'MOZ_NO_REMOTE': '1', 02:52:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:22 INFO - 'NO_EM_RESTART': '1', 02:52:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:22 INFO - 'PWD': '/builds/slave/test', 02:52:22 INFO - 'SHELL': '/bin/bash', 02:52:22 INFO - 'SHLVL': '1', 02:52:22 INFO - 'TERM': 'linux', 02:52:22 INFO - 'TMOUT': '86400', 02:52:22 INFO - 'USER': 'cltbld', 02:52:22 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:22 INFO - '_': '/tools/buildbot/bin/python'} 02:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:22 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)) 02:52:22 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)) 02:52:22 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)) 02:52:22 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)) 02:52:22 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)) 02:52:22 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)) 02:52:22 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)) 02:52:22 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)) 02:52:22 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)) 02:52:22 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)) 02:52:22 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)) 02:52:22 INFO - Cleaning up... 02:52:22 INFO - Return code: 0 02:52:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:22 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 0x7fceadd9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', '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 02:52:22 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 02:52:22 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 02:52:22 INFO - Using env: {'DISPLAY': ':0', 02:52:22 INFO - 'HOME': '/home/cltbld', 02:52:22 INFO - 'LANG': 'en_US.UTF-8', 02:52:22 INFO - 'LANGUAGE': 'en_US:en', 02:52:22 INFO - 'LOGNAME': 'cltbld', 02:52:22 INFO - 'MAIL': '/var/mail/cltbld', 02:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:22 INFO - 'MOZ_NO_REMOTE': '1', 02:52:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:22 INFO - 'NO_EM_RESTART': '1', 02:52:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:22 INFO - 'PWD': '/builds/slave/test', 02:52:22 INFO - 'SHELL': '/bin/bash', 02:52:22 INFO - 'SHLVL': '1', 02:52:22 INFO - 'TERM': 'linux', 02:52:22 INFO - 'TMOUT': '86400', 02:52:22 INFO - 'USER': 'cltbld', 02:52:22 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:22 INFO - '_': '/tools/buildbot/bin/python'} 02:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:52:22 INFO - Cleaning up... 02:52:22 INFO - Return code: 0 02:52:22 INFO - Running post-action listener: _resource_record_post_action 02:52:22 INFO - Running post-action listener: _start_resource_monitoring 02:52:22 INFO - Starting resource monitoring. 02:52:22 INFO - [mozharness: 2016-09-02 09:52:22.670704Z] Finished create-virtualenv step (success) 02:52:22 INFO - [mozharness: 2016-09-02 09:52:22.671167Z] Running install step. 02:52:22 INFO - Running pre-action listener: _resource_record_pre_action 02:52:22 INFO - Running main action method: install 02:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:52:22 INFO - Reading from file tmpfile_stdout 02:52:22 INFO - Detecting whether we're running mozinstall >=1.0... 02:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:52:22 INFO - Reading from file tmpfile_stdout 02:52:22 INFO - Output received: 02:52:22 INFO - Usage: mozinstall [options] installer 02:52:22 INFO - Options: 02:52:22 INFO - -h, --help show this help message and exit 02:52:22 INFO - -d DEST, --destination=DEST 02:52:22 INFO - Directory to install application into. [default: 02:52:22 INFO - "/builds/slave/test"] 02:52:22 INFO - --app=APP Application being installed. [default: firefox] 02:52:22 INFO - mkdir: /builds/slave/test/build/application 02:52:22 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'] 02:52:22 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 02:52:39 INFO - Reading from file tmpfile_stdout 02:52:39 INFO - Output received: 02:52:39 INFO - /builds/slave/test/build/application/firefox/firefox 02:52:39 INFO - Running post-action listener: _resource_record_post_action 02:52:39 INFO - [mozharness: 2016-09-02 09:52:39.535446Z] Finished install step (success) 02:52:39 INFO - [mozharness: 2016-09-02 09:52:39.535727Z] Running run-tests step. 02:52:39 INFO - Running pre-action listener: _resource_record_pre_action 02:52:39 INFO - Running main action method: run_tests 02:52:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:52:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:52:39 INFO - Python 2.7.3 02:52:39 INFO - Return code: 0 02:52:39 INFO - grabbing minidump binary from tooltool 02:52:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:39 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 0x1bad090>, '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 0x1bb09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb0e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:52:39 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 02:52:39 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 02:52:39 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 02:52:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:52:39 INFO - Return code: 0 02:52:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:52:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:52:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:52:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:52:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:52:39 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/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 02:52:39 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/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 02:52:39 INFO - Using env: {'DISPLAY': ':0', 02:52:39 INFO - 'HOME': '/home/cltbld', 02:52:39 INFO - 'LANG': 'en_US.UTF-8', 02:52:39 INFO - 'LANGUAGE': 'en_US:en', 02:52:39 INFO - 'LOGNAME': 'cltbld', 02:52:39 INFO - 'MAIL': '/var/mail/cltbld', 02:52:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:52:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:39 INFO - 'MOZ_NO_REMOTE': '1', 02:52:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:52:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:52:39 INFO - 'NO_EM_RESTART': '1', 02:52:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:39 INFO - 'PWD': '/builds/slave/test', 02:52:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:52:39 INFO - 'SHELL': '/bin/bash', 02:52:39 INFO - 'SHLVL': '1', 02:52:39 INFO - 'TERM': 'linux', 02:52:39 INFO - 'TMOUT': '86400', 02:52:39 INFO - 'USER': 'cltbld', 02:52:39 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690', 02:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:39 INFO - '_': '/tools/buildbot/bin/python'} 02:52:39 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/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 02:52:39 INFO - mozversion application_buildid: 20160902012641 02:52:39 INFO - mozversion application_changeset: 84bab1407100af7215a1f19df176295fc2287410 02:52:39 INFO - mozversion application_display_name: Nightly 02:52:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:52:39 INFO - mozversion application_name: Firefox 02:52:39 INFO - mozversion application_remotingname: firefox 02:52:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 02:52:39 INFO - mozversion application_vendor: Mozilla 02:52:39 INFO - mozversion application_version: 51.0a1 02:52:39 INFO - mozversion platform_buildid: 20160902012641 02:52:39 INFO - mozversion platform_changeset: 84bab1407100af7215a1f19df176295fc2287410 02:52:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 02:52:39 INFO - mozversion platform_version: 51.0a1 02:52:39 INFO - using testdate: 1472809959 02:52:39 INFO - actual date: 1472809959 02:52:39 INFO - starting webserver on 'localhost:38131' 02:52:39 INFO - SUITE-START | Running 2 tests 02:52:39 INFO - TEST-START | dromaeo_css 02:52:39 INFO - operating with platform_type : linux_ 02:52:39 INFO - Initialising browser for dromaeo_css test... 02:52:39 INFO - TEST-INFO | started process 9789 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpONNiP9/profile http://localhost:38131/getInfo.html) 02:52:44 INFO - PROCESS | 9789 | __metrics Screen width/height:1600/1200 02:52:44 INFO - PROCESS | 9789 | colorDepth:24 02:52:44 INFO - PROCESS | 9789 | Browser inner width/height: 1024/697 02:52:44 INFO - PROCESS | 9789 | __metrics 02:52:44 INFO - PROCESS | 9789 | 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" 02:52:44 INFO - PROCESS | 9789 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:52:45 INFO - PROCESS | 9789 | console.error: PushService: 02:52:45 INFO - PROCESS | 9789 | stateChangeProcessEnqueue: Error transitioning state 02:52:45 INFO - PROCESS | 9789 | UnknownError 02:52:46 INFO - TEST-INFO | 9789: exit 0 02:52:46 INFO - Browser initialized. 02:52:46 INFO - Running cycle 1/1 for dromaeo_css test... 02:52:46 INFO - TEST-INFO | started process 9872 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpONNiP9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:52:58 INFO - PROCESS | 9872 | RSS: Main: 180420608 02:52:58 INFO - PROCESS | 9872 | 02:53:50 INFO - PROCESS | 9872 | 1472810030297 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] 02:56:02 INFO - PROCESS | 9872 | Cycle 1(1): loaded http://localhost:38131/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38131/tests/dromaeo/cssquery-ext.html) 02:56:03 INFO - PROCESS | 9872 | RSS: Main: 272142336 02:56:03 INFO - PROCESS | 9872 | 02:59:06 INFO - PROCESS | 9872 | Cycle 1(1): loaded http://localhost:38131/tests/dromaeo/cssquery-ext.html (next: http://localhost:38131/tests/dromaeo/cssquery-jquery.html) 02:59:07 INFO - PROCESS | 9872 | RSS: Main: 274554880 02:59:07 INFO - PROCESS | 9872 | 03:02:09 INFO - PROCESS | 9872 | Cycle 1(1): loaded http://localhost:38131/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38131/tests/dromaeo/cssquery-mootools.html) 03:02:10 INFO - PROCESS | 9872 | RSS: Main: 217239552 03:02:10 INFO - PROCESS | 9872 | 03:05:12 INFO - PROCESS | 9872 | Cycle 1(1): loaded http://localhost:38131/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38131/tests/dromaeo/cssquery-prototype.html) 03:05:12 INFO - PROCESS | 9872 | RSS: Main: 222867456 03:05:12 INFO - PROCESS | 9872 | 03:08:15 INFO - PROCESS | 9872 | Cycle 1(1): loaded http://localhost:38131/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38131/tests/dromaeo/cssquery-yui.html) 03:08:16 INFO - PROCESS | 9872 | RSS: Main: 222851072 03:08:16 INFO - PROCESS | 9872 | 03:11:20 INFO - PROCESS | 9872 | Cycle 1(1): loaded http://localhost:38131/tests/dromaeo/cssquery-yui.html (next: http://localhost:38131/tests/dromaeo/cssquery-dojo.html) 03:11:20 INFO - PROCESS | 9872 | RSS: Main: 289665024 03:11:20 INFO - PROCESS | 9872 | 03:11:20 INFO - PROCESS | 9872 | __start_tp_report 03:11:20 INFO - PROCESS | 9872 | _x_x_mozilla_page_load 03:11:20 INFO - PROCESS | 9872 | _x_x_mozilla_page_load_details 03:11:20 INFO - PROCESS | 9872 | |i|pagename|runs| 03:11:20 INFO - PROCESS | 9872 | |0;dojo.html;2607.4654695787367;2610.765031147196;2626.8555229462395;2662.294491959631;2678.812483126182;4619.237825758754;4707.2939059141145;4770.379850619419;4843.200871856148;4891.315215869777;4686.367340409044;4711.128441238373;4714.434267887858;4723.8110475581025;4783.521647835215;4317.244482215599;4337.566243375653;4366.956330436687;4425.269830477967;4519.615832654228;4422.557744225568;4449.532799056101;4463.464384273875;4487.12501062293;4496.370508128848;4263.253930562139;4302.354052257839;4312.353147027971;4344.4352234209355;4358.215521206182;4342.717723347972;4368.060866913597;4374.650027997753;4423.933640995388;4424.513303536608;4356.520782713899;4367.170237654836;4376.190404775127;4420.182266280748;4465.59809617136;4774.856754297377;4832.395950506187;4845.563899249085;4848.903021939542;4897.730624815634;4856.150173719585;4926.876828079291;5030.195168772979;5127.692338459667;5177.534021938006;5175.171972484384;5180.766865491062;5191.0656081905245;5199.350081239845;5200.8959820803375;4817.229243321087;4848.684835485682;4849.199882019498;4854.101991131582;4903.387076615423;5118.846434606968;5175.767090480937;5233.895322093613;5238.449962753933;5317.9202311965355;5000.1249781288125;5031.295618613397;5033.6224783141415;5039.370078740158;5040.218766091261;5118.334616499831;5123.513266239701;5125.461826508237;5125.666831656005;5172.560332371715;4909.975450122798;4984.327115839315;5035.974820125876;5041.344625198701;5106.66806657566;5030.924536131961;5044.192929131322;5045.672031317952;5047;5094.847154585368;4221.7255878367805;4228.70399072062;4228.809703563347;4230;4288.356746488052;3634.345817752777;3664.9450258246147;3704.685101766326;3725.6460636239512;3774.3206222880144;4430.113977204572;4444.866654000385;4447.110577884371;4499.842505512292;4502.752348620792;5113.71874546896;5119.206522988943;5122.590192784568;5127.230915362651;5182.97408512955;4319.33050377192;4415.977920110379;4434.800433980478;4458.398116254329;4470.8435204767675;4555.977220113945;4586.3579098926175;4619.376384188159;4632.745199014086;4636.629069674418;4864.878378040577;4869.707817531031;4869.902601947942;4870.586000189882;4929.531694489017;4920.188168952226;5095;5122.487751224997;5134.383873935151;5157.329547158845;4858.392700912386;4895.04546613407;4930.4822993586595;4938.580220681282;4956.900861982668;5096.133657278346;5096.159133742891;5096.949030509648;5103.157978933435;5149.794008239628;4951.628627852853;5092.898142037065;5158.174692049404;5173.611979101658;5173.870653233699;4979.825706100414;5019.84940451787;5020.673656212334;5023.874403139951;5063.1645778446145;5018.096742586369;5027.7234752087525;5027.798888044584;5036.294918711456;5095.515925987025;4705.647076469347;4925.704457732548;4936.3089167515855;4943.555080042814;4946.406431228275;5065.240213967934;5075.593952483866;5087.949120508895;5126.820561280338;5135.306733591066;4906.95093049065;4907.9263811043575;4913.508649135058;4926.359573255454;4975.975120124376 03:11:20 INFO - PROCESS | 9872 | |1;ext.html;547.1246006389777;551.7434393007251;558.9240711630111;574.5489790514447;574.6982834012146;37424.43863342052;38844.22311553771;39219.41170882439;39249.80375098128;42860;38978;39108;39286.60713392865;39438.21123577527;39451.8438524918;7154.1415030196185;7567.962160189191;7714.884276735826;7721.150673425918;7721.53670779755;7283.890741638879;7485.73799917003;7554.24457554243;9807;10073.294869359148;10131.848022279672;10184.79630407395;10189.949050254738;10194.592216311376;10198.286119971604;10006.249531285146;10057.949710251401;10068.496575171286;10069.345492543034;10074.395536267812;9385.249180065579;9459.385139965949;9464.621415143385;9465.621375144987;9466;37096;37523.24953500942;37554.24891502182;37600.43599346012;37636.62363376386;2823.1953893140358;2825.8587070646386;2863.3271181272594;2942.9040768579907;3144.151079208601;27598.724012759816;27720;27842.3039424014;27945.441091178265;28532;3452.689257966795;3478.739094567892;3518.6305437928904;3558.5907620623443;3649.543807024122;184654.07672961685;184655;185211.99999999866;185314;185398;51322;51854.22218666723;51904.22143667848;51928;51966.22050669167;47960;48435;53590.12434564772;53644.73177634165;53712;14713.411463541552;14982;15494.690106197813;15528.767068493982;15944.441944532118;11222;11246.212765106364;11264.71838204035;11271.605493807678;11275.830862537068;15007.699846003019;16877.578060548585;17472.301107955795;17579.824201758074;17919.37282195117;6608.966955165193;6711.765088221987;6746;6789.422899053537;6903.309669033057;771.3713323641215;883.2977782662798;906.1889608800087;916.225789208118;929.1637526226315;26050.739492604833;26888;27171.72828271692;27456;27526.724732752817;815.3762371785589;883.1962913748458;945.1871390604075;969.5346233807812;1006.642641862143;14190.361433735507;14583.489577864935;14616.342264598119;14864.851351486346;14877.925610371878;122929.38535307266;122983.3850830758;123694.38152809178;123763;123820;46246.53753462422;46593.30110048352;46727;46734.76632616883;46813;998.6404894238068;1119.4850368830266;1120.6357933671427;1146.363768108691;1184.810430331143;25874.482510350063;27317;28114.578281325386;28441.146765597066;28775.5683664741;39269.803650981565;39698;39802;39867.2026559473;40013.39979900362;288480;290878;291106;291567;291675;23652;24723.134690285755;24833.87583062073;25672;25810.967561297337;38784.6121538781;39159.02102447462;39417.802910985265;40039.19921601493;40317.39523907085;34289;34349.48475772915;34380.48429273613;34536.65463345334;34928.47607285842;33765.49351759676;34158.82920585381;34165;34173.316533669684;34333.65666343404;33590.83204583961;34114;34230.801921932616;34412.13969650678;34544;29162.854185728935;31348.529772053895;31683.84158079195;31774.205644859063;31845.5223171648;21711.457213569156;21860.125594976023;22142;22150.88924555367;22206.444838879157 03:11:20 INFO - PROCESS | 9872 | |2;jquery.html;344.7535012466086;365.88291746641085;371.2315506900715;376.9830357633906;396.84721382267816;12131.211471254373;15624.453144139943;15841.445549405773;17232.741508877378;17395.478135655918;16860.83139168608;17090;17091.743623845654;17474;17683.1158442078;4908.067467181242;4937.728424936627;5019.799208031674;5044.82343117991;5049.77276022578;3658.634136586333;4987.950120498803;5073.086844368012;5595.832125036255;5938.782549577348;5960.672163031031;5988.281406231258;5989.221401217857;5993.760249590011;6003.369646187153;5182.326297581328;5439.5376393006645;5460.836174914759;5539.50144486994;5972.850678733023;5386.622936394454;5393.460653934575;5397.4062853086125;5399.62202645815;5402.081646120129;12194.39028048594;12297.69255768604;12625.936870315682;12688.619341419771;12689.619311420673;1794.7487351770762;1821.5355084453513;1884.8397886179691;1920.93276735315;1936.719175719527;9014;9145.81708365829;9269.582868770922;9476.715698529055;9887.209023278121;1640.5488490665043;1671.2228813601735;1682.1337011439118;1686.6289416328389;1711.0760189497657;66226;68768.31231687669;71045.64477177632;71495.2850471499;72026.279737203;9992.25058120644;10103.747406314755;10312.278140530241;10357.689269321932;10459;14109.858901410855;14118;14156.292185390896;14159.221242831743;14162.71674566503;7064.50548461613;7099.183593886769;7242.601656908921;7279.67241474135;7317.5609463432365;6675.966620166965;6904.689288982008;7070.469714771412;7081.221065682771;7101.680424380916;4315.978420107942;4360.149770794728;4392.2752745797225;4475.239209334421;4523.864284071483;1669.2571805546415;1815.110595808071;1830.6064196197615;1854.1285595769966;1876.605912758336;713.5219402999899;824.6083110522454;830.4228561149943;871.1506281375734;875.5753459572103;16594.83405165957;16804.495865124067;16955.15224238783;18593.7210941836;18912.52718682044;834.6244190114472;869.8347314010318;877.912208779117;878.6046279174398;899.9370044096981;9134.954325228331;9324.813503729887;9628.374155679858;9733.172680322252;9843.852342214872;279416;281839;282106;395054;399350;49544.75227623839;51862.740686296325;52293;60910.695446522484;81971;887.3522328700085;906.2613969614874;937.6999360204669;937.9390339627903;941.7598512379436;15598;15781.605459863595;16377.836221637632;16575.502734917973;16709.665806684043;30642.846785765927;30674;37242.25515489729;37842.61188845843;38953.80523097367;52316.47683523116;54306.45693543014;54784.452155477935;55151.17273240985;56713.14930276132;5834.774697313612;6194.5044396449075;6463.7737679181;7512.474126811071;7867.409944254089;10837.891621083689;11102.389368584481;11371.772564548828;12283.140180187302;12354.38228088574;9650.420974741539;9824.968378320173;9893.406395616286;10390.272680912565;10411.063004329646;9453.480058596842;9472.715818525456;9676.661316853877;9789.216862650828;10576.312539684895;9680;10116.494175291353;10242.539085741011;10430.68707938763;10557.366558006544;6354.90467642995;6592.538522303393;6685.765998190234;7096.929030709627;7194;7399.667014984447;10254.025867542572;10326;10385.532651030873;11147.888521114684 03:11:20 INFO - PROCESS | 9872 | |3;mootools.html;1339.7387509435662;1356.274393199638;1422.2462095089606;1658.9502314930555;1674.8743844211688;7034.824129396768;7557.395408367332;7749.341305988998;8205.958970205149;8215.91784082159;7845.37237021037;8134.145914678962;8140.877886831688;8147.9592602037055;8148.103708592065;2240.7535171131203;2260.9095636174534;2265.4449659833363;2267.1498188179435;2268.353519247015;1874.5126267170497;2125.872447653138;2201.6147174244443;2217.246136313653;2241.8206543476563;2213.6347502662043;2236.6980457638174;2237.776222377757;2241.05875532276;2242.6972358731527;2132.8613640113344;2145.463634091477;2173.5978843913767;2177.1835561664375;2185.8579192352445;2131.914723411062;2158.3201291593255;2163.350994701583;2169;2176.4667656424117;8073.596320183968;8136.593170341459;8137.674493020272;8139;8148.877766833502;4529.1621050105505;4598.402207713009;4645.326427668003;4650.697704649187;4656.4412270527755;2965.451391492559;3095.1797773590015;3112.50199968004;3144.7169754722186;3155.4162479941283;883.7878909061843;923.382211430595;926.4395041000165;941.7174847545778;945.6264775413673;10977.451127443595;11002.559897604086;11027;11043.779124417548;11046.116310695124;4172.645325147336;4240.279152544075;4398.978005109954;4428.933565996513;4443.733375997451;9391.859122113174;9394.389364691273;9405.247580193569;9407.952960235156;9412.811743765087;4588.7935042923145;4598.701084429501;4601.4248218972625;4603.378543896598;4606.97696511522;4135.441715368387;4162.250794856895;4166.66666666666;4173.10278290169;4175.979120104441;3668.5781135169264;3677.154254521475;3763.473113764075;3771.26460340231;3832.2718683450057;1060.798448294837;1176.6352430746497;1205.3912774049224;1231.1751126745103;1231.9630411087683;734.3060807536933;815.9551224682581;824.2005254902734;826.7643721539453;842.2041171093381;5301.920471192935;5317.6277660564165;5351.491608297206;5445;5445.591580631469;883.6377085395086;888.0941439731438;907.686848037426;922.1516205091332;924.7377329944577;3770.981145094257;3800.5819359870393;3817.923641527154;3854.614538546179;3987.4218238355274;9342.95328523353;9732;9873.901260987443;9943;9953.601855925683;9852.95073524642;9858.063483969012;9859.26055545837;9867.309288349747;9868.111869931741;7200.819979500449;7409.629518524052;7469.141048779351;7472.327490525879;7482.2517748224745;5447.700376479332;5448.991936491761;5470.91793623104;5560.666360018412;5734.107037991805;8747.65009399617;9031.412958157698;9036.593353298986;9073.092690731139;9221.354505184841;9214.262858971133;9243.260539156985;9396.248300136109;9398.577064031999;9419.246460283024;6315.431611155018;6406.903896441464;6409.070684750592;6412.39082287182;6421.903671445026;7110.3600675939415;7178.28217178278;7346.742863999734;7463.477556570988;7468.365188958782;5469.349979323927;6647;6712.597244165366;6760.087388202726;6896.172459304715;6816.454683625398;6843.281455447106;6855.485838562227;6899.93100069013;6902.8619427612175;6338.144350512622;6447.548671592944;6453.257875344396;6454.419102280817;6460.127882735958;4336.588024137702;4583.404157459642;4593.2191527439745;4664.696794708333;4683.648726345606;4669.906601868011;4674.859754207243;4679.134360143248;4843.0313937211995;4848.272759086024 03:11:20 INFO - PROCESS | 9872 | |4;prototype.html;294.57875238409065;297.91658335666017;306.3413660629647;306.7576614474564;306.95088785794275;4686.399048528074;4781.402324709411;4837.379477874912;4868.367112275399;4891.85324440267;4654.3251228571835;4748;4780.020095880345;4790.688605240665;4816.301636262749;3820.9426858597144;3830;3831.2337532493475;3835.5780864104995;3842.9615703843024;3609.981950090246;3624.2932628137523;3836.079340958178;3838.673712734421;3839.884803455901;3771.981140094296;3787.337215987205;3788.3559794834805;3793.468914351993;3808.428735689641;3780.546334439871;3782.092297848524;3785.482654035063;3786.2238241160494;3792.8103594820286;3370.3427831572853;3372.5615669962995;3374.8650053997812;3377.3751855906735;3378.864845406181;4565.20108980927;4693.460252071021;4697.2484402495775;4718.0092180642105;4723.07899959508;4308.6983911126235;4338.652907767371;4356.782160891975;4359.280718681413;4362.36745671879;5006.172746947374;5013.373328333958;5023.824166154167;5035.77339019745;5039;3560.626134255892;3632.4017432329956;3647.598764135943;3663.249033948034;3682.9263414731554;5245.527902488756;5250.684958902478;5254.290670759437;5258.342707161605;5265.657732247391;3760.9247815043545;3770.9434358484646;3771.6605505504635;3772.396416573335;3775.6224377562025;3747.2318174774105;3830.7318487705593;3832.8884623459444;3833.2333533293454;3841.193349396658;4283.87148385549;4287;4336.436263285753;4340.913181736348;4361.193179261847;5020.59835213182;5029.5724863386295;5031.597472202215;5032.874178145576;5054.9241761373605;1246.9899381501014;1273.5860845225338;1313.2055106660523;1317.611304665107;1324.2120938041849;899.3614533681159;922.041077279635;935.2237642756496;936.5691781780321;942.9486122972972;4446.088551846864;4458.375827384169;4469.687121901501;4479.59683628475;4484;5145.176771716508;5156.83412966555;5159.226116082617;5163.974180129076;5166.302549155891;4458.152950939311;4459.687821852504;4464.843730469418;4474.172278128557;4491.236489796743;2626.9903523804305;2776.458590574859;2779.8332100074063;2784.5962335461245;2786.749192572678;4945.480724523945;4959.876003099951;4961.355023846877;4990;5022.296878437021;5224.947750522446;5351.759170837322;5406.324209473816;5411.377691565521;5447.813029377859;4943.703377797272;4948.356713627206;4954.677945933503;4972.825951091694;4980.153373926513;5228.947710522846;5265.236540701653;5276.208568714724;5276.445973172762;5280.234366016983;4966.776495057658;5026.798928042837;5089.567386772166;5163.896722065613;5177;4909;4918.655694101379;4927.3247095987535;4932.681639629369;4933.55597996182;3601.8019008954757;3617.837197326179;3639.16299251179;3653.06846754076;3654.9634503654624;5052.267421223975;5140.203268493389;5244.527992480543;5246.76389562463;5261.210818377274;4994.875128121826;4998.700077995332;5041.521350268303;5043.9747801260755;5045.142325804549;4989.850304490871;4996.200607902718;4996.775145118551;5012.77442515095;5024.773885175103;4675;4905.7792399342825;4921.704697718148;4950.851474455772;4961.776720047535;5023.623228257822;5028.245763135559;5036.899262014813;5038.420581633159;5038.496150384932;4838.371011768448;4852.3016944069295;4883.682560633548;4885.975570122127;4892.31507588945 03:11:20 INFO - PROCESS | 9872 | |5;yui.html;583.4865318519703;651.9022146678001;709.7054722290248;718.357070421972;744.3672878053655;10514.842277365846;12305.384730763471;12722.554710585131;12806.871931280684;12812.295323757202;13280.335983200825;13915.512957046505;13980.58058258254;14001;14005.229712365815;730.6346826586706;746.2761121711931;747.4692967992704;747.7382915979391;748.2218492767515;590.1236663554618;722.2236096196583;741.4105785900223;743.7843025522592;744.1739668967442;789.8657228271207;795.08168065884;797.5214871077336;798.3613109512366;798.6006996501749;794.7297918707609;795.2882170457466;797.2426195114664;797.6490344248541;798.5049269452918;576.9192313076172;577.3822010448821;578.4302462074853;580.0168714029721;580.3818932836516;12697.936510317482;12717.936410317981;12803.423845926956;12807.487700492062;12833.550825721055;13150.539731109366;13273.336333183302;13456.499932627714;13541.45834166632;13864.979156044736;10421.739456513573;10466.738331541696;10785.5685772569;10847.077998370069;11188.328700277929;3262.755293353008;3289.7861638993627;3363.428217203057;3377.155711072232;3426.3147370525994;65605;66281;66344.33655663478;66457.66771166162;66506.00240996388;9744.756381090388;10056.748581285527;10072.093511583993;10150.796984060426;10379.377237365783;18233.361832335806;18541.35105271309;18584.628307433777;18694;18704.158312875952;6592.24189218246;6842.349976752201;6860.5540639858245;6978.337057979484;7049.718011279491;3700.611435799256;3708.2027364116307;3721.14413684854;3762.398016317376;3809.8095095245126;2762.4198918226994;2885.6104425902654;2986.283292009902;3187.7131058204873;3362.260302733395;556.7244214113958;559.932808063035;563.5294529068274;564.6301672404581;565.8104534980828;493;534.8796520782871;545.4817922973112;548.8161465908886;572.3544808675059;7597.468177227541;7619;7804.9463322451875;7828.686852525949;8117.959410202911;516.5918924049959;568.7355379748437;587.4037851580649;599.2898415377791;608.6104892868568;3787.753796003251;3795.772253664789;3806.980965095212;3806.980965095212;4081.9387709184384;78255;81225.59387203027;100909.43179261438;104882.47558762158;104990.47504762428;76495.61752191203;86998;87773.56113219394;88011.55994219988;88337;74533;75174.2482575189;76834;78168.60915695385;85089.57455212684;7147.428205743424;7454.403647708279;7586.279303466162;7809.2190780921455;7812.296893279632;41898.79050604728;42920.35619465773;43558.693239202876;43685.344719828594;44156.33765493584;57752.71123644355;61290.693546531984;62338;62411.37588624237;65104;10867.456627168767;11228.157888158257;11348.943255283672;11453.141014424118;11547.422628868691;13233.338333083115;13437.86562134366;13447.596572102853;14273.928630356782;14313.856861431252;11131.220814542905;11417.31496110236;11795.11536634773;11816.881831181578;11834.46744896499;10195.898041019494;10502;10532.36805791655;11157.72105697364;11910.225835320676;10780.676579702622;10787.838182427427;11106.722331941766;11403.087752979907;11446.198766086294;9865.950670246602;9974.850377244493;10271.845922311022;10612.893871061191;10634.680959571226;9150.542472876463;9659.855102173333;9809.852852207365;9877.456739879375;9910.603575857174 03:11:20 INFO - PROCESS | 9872 | __end_tp_report 03:11:20 INFO - PROCESS | 9872 | __start_cc_report 03:11:20 INFO - PROCESS | 9872 | _x_x_mozilla_cycle_collect,1009 03:11:20 INFO - PROCESS | 9872 | __end_cc_report 03:11:20 INFO - PROCESS | 9872 | __startTimestamp1472811080260__endTimestamp 03:11:20 INFO - PROCESS | 9872 | 03:11:20 INFO - PROCESS | 9872 | ------- Summary: start ------- 03:11:20 INFO - PROCESS | 9872 | Number of tests: 6 03:11:20 INFO - PROCESS | 9872 | 03:11:20 INFO - PROCESS | 9872 | [#0] dojo.html Cycles:165 Average:4729.80 Median:4903.39 stddev:510.46 (10.4%) stddev-sans-first:484.11 03:11:20 INFO - PROCESS | 9872 | Values: 2607.5 2610.8 2626.9 2662.3 2678.8 4619.2 4707.3 4770.4 4843.2 4891.3 4686.4 4711.1 4714.4 4723.8 4783.5 4317.2 4337.6 4367.0 4425.3 4519.6 4422.6 4449.5 4463.5 4487.1 4496.4 4263.3 4302.4 4312.4 4344.4 4358.2 4342.7 4368.1 4374.7 4423.9 4424.5 4356.5 4367.2 4376.2 4420.2 4465.6 4774.9 4832.4 4845.6 4848.9 4897.7 4856.2 4926.9 5030.2 5127.7 5177.5 5175.2 5180.8 5191.1 5199.4 5200.9 4817.2 4848.7 4849.2 4854.1 4903.4 5118.8 5175.8 5233.9 5238.4 5317.9 5000.1 5031.3 5033.6 5039.4 5040.2 5118.3 5123.5 5125.5 5125.7 5172.6 4910.0 4984.3 5036.0 5041.3 5106.7 5030.9 5044.2 5045.7 5047.0 5094.8 4221.7 4228.7 4228.8 4230.0 4288.4 3634.3 3664.9 3704.7 3725.6 3774.3 4430.1 4444.9 4447.1 4499.8 4502.8 5113.7 5119.2 5122.6 5127.2 5183.0 4319.3 4416.0 4434.8 4458.4 4470.8 4556.0 4586.4 4619.4 4632.7 4636.6 4864.9 4869.7 4869.9 4870.6 4929.5 4920.2 5095.0 5122.5 5134.4 5157.3 4858.4 4895.0 4930.5 4938.6 4956.9 5096.1 5096.2 5096.9 5103.2 5149.8 4951.6 5092.9 5158.2 5173.6 5173.9 4979.8 5019.8 5020.7 5023.9 5063.2 5018.1 5027.7 5027.8 5036.3 5095.5 4705.6 4925.7 4936.3 4943.6 4946.4 5065.2 5075.6 5087.9 5126.8 5135.3 4907.0 4907.9 4913.5 4926.4 4976.0 03:11:20 INFO - PROCESS | 9872 | 03:11:20 INFO - PROCESS | 9872 | [#1] ext.html Cycles:180 Average:36910.33 Median:34169.16 stddev:55272.76 (161.8%) stddev-sans-first:55360.38 03:11:20 INFO - PROCESS | 9872 | Values: 547.1 551.7 558.9 574.5 574.7 37424.4 38844.2 39219.4 39249.8 42860.0 38978.0 39108.0 39286.6 39438.2 39451.8 7154.1 7568.0 7714.9 7721.2 7721.5 7283.9 7485.7 7554.2 9807.0 10073.3 10131.8 10184.8 10189.9 10194.6 10198.3 10006.2 10057.9 10068.5 10069.3 10074.4 9385.2 9459.4 9464.6 9465.6 9466.0 37096.0 37523.2 37554.2 37600.4 37636.6 2823.2 2825.9 2863.3 2942.9 3144.2 27598.7 27720.0 27842.3 27945.4 28532.0 3452.7 3478.7 3518.6 3558.6 3649.5 184654.1 184655.0 185212.0 185314.0 185398.0 51322.0 51854.2 51904.2 51928.0 51966.2 47960.0 48435.0 53590.1 53644.7 53712.0 14713.4 14982.0 15494.7 15528.8 15944.4 11222.0 11246.2 11264.7 11271.6 11275.8 15007.7 16877.6 17472.3 17579.8 17919.4 6609.0 6711.8 6746.0 6789.4 6903.3 771.4 883.3 906.2 916.2 929.2 26050.7 26888.0 27171.7 27456.0 27526.7 815.4 883.2 945.2 969.5 1006.6 14190.4 14583.5 14616.3 14864.9 14877.9 122929.4 122983.4 123694.4 123763.0 123820.0 46246.5 46593.3 46727.0 46734.8 46813.0 998.6 1119.5 1120.6 1146.4 1184.8 25874.5 27317.0 28114.6 28441.1 28775.6 39269.8 39698.0 39802.0 39867.2 40013.4 288480.0 290878.0 291106.0 291567.0 291675.0 23652.0 24723.1 24833.9 25672.0 25811.0 38784.6 39159.0 39417.8 40039.2 40317.4 34289.0 34349.5 34380.5 34536.7 34928.5 33765.5 34158.8 34165.0 34173.3 34333.7 33590.8 34114.0 34230.8 34412.1 34544.0 29162.9 31348.5 31683.8 31774.2 31845.5 21711.5 21860.1 22142.0 22150.9 22206.4 03:11:20 INFO - PROCESS | 9872 | 03:11:20 INFO - PROCESS | 9872 | [#2] jquery.html Cycles:180 Average:21886.35 Median:5032.31 stddev:55111.48 (1095.2%) stddev-sans-first:55242.35 03:11:20 INFO - PROCESS | 9872 | Values: 344.8 365.9 371.2 377.0 396.8 12131.2 15624.5 15841.4 17232.7 17395.5 16860.8 17090.0 17091.7 17474.0 17683.1 4908.1 4937.7 5019.8 5044.8 5049.8 3658.6 4988.0 5073.1 5595.8 5938.8 5960.7 5988.3 5989.2 5993.8 6003.4 5182.3 5439.5 5460.8 5539.5 5972.9 5386.6 5393.5 5397.4 5399.6 5402.1 12194.4 12297.7 12625.9 12688.6 12689.6 1794.7 1821.5 1884.8 1920.9 1936.7 9014.0 9145.8 9269.6 9476.7 9887.2 1640.5 1671.2 1682.1 1686.6 1711.1 66226.0 68768.3 71045.6 71495.3 72026.3 9992.3 10103.7 10312.3 10357.7 10459.0 14109.9 14118.0 14156.3 14159.2 14162.7 7064.5 7099.2 7242.6 7279.7 7317.6 6676.0 6904.7 7070.5 7081.2 7101.7 4316.0 4360.1 4392.3 4475.2 4523.9 1669.3 1815.1 1830.6 1854.1 1876.6 713.5 824.6 830.4 871.2 875.6 16594.8 16804.5 16955.2 18593.7 18912.5 834.6 869.8 877.9 878.6 899.9 9135.0 9324.8 9628.4 9733.2 9843.9 279416.0 281839.0 282106.0 395054.0 399350.0 49544.8 51862.7 52293.0 60910.7 81971.0 887.4 906.3 937.7 937.9 941.8 15598.0 15781.6 16377.8 16575.5 16709.7 30642.8 30674.0 37242.3 37842.6 38953.8 52316.5 54306.5 54784.5 55151.2 56713.1 5834.8 6194.5 6463.8 7512.5 7867.4 10837.9 11102.4 11371.8 12283.1 12354.4 9650.4 9825.0 9893.4 10390.3 10411.1 9453.5 9472.7 9676.7 9789.2 10576.3 9680.0 10116.5 10242.5 10430.7 10557.4 6354.9 6592.5 6685.8 7096.9 7194.0 7399.7 10254.0 10326.0 10385.5 11147.9 03:11:20 INFO - PROCESS | 9872 | 03:11:20 INFO - PROCESS | 9872 | [#3] mootools.html Cycles:180 Average:5226.53 Median:5448.35 stddev:2940.43 (54.0%) stddev-sans-first:2934.17 03:11:20 INFO - PROCESS | 9872 | Values: 1339.7 1356.3 1422.2 1659.0 1674.9 7034.8 7557.4 7749.3 8206.0 8215.9 7845.4 8134.1 8140.9 8148.0 8148.1 2240.8 2260.9 2265.4 2267.1 2268.4 1874.5 2125.9 2201.6 2217.2 2241.8 2213.6 2236.7 2237.8 2241.1 2242.7 2132.9 2145.5 2173.6 2177.2 2185.9 2131.9 2158.3 2163.4 2169.0 2176.5 8073.6 8136.6 8137.7 8139.0 8148.9 4529.2 4598.4 4645.3 4650.7 4656.4 2965.5 3095.2 3112.5 3144.7 3155.4 883.8 923.4 926.4 941.7 945.6 10977.5 11002.6 11027.0 11043.8 11046.1 4172.6 4240.3 4399.0 4428.9 4443.7 9391.9 9394.4 9405.2 9408.0 9412.8 4588.8 4598.7 4601.4 4603.4 4607.0 4135.4 4162.3 4166.7 4173.1 4176.0 3668.6 3677.2 3763.5 3771.3 3832.3 1060.8 1176.6 1205.4 1231.2 1232.0 734.3 816.0 824.2 826.8 842.2 5301.9 5317.6 5351.5 5445.0 5445.6 883.6 888.1 907.7 922.2 924.7 3771.0 3800.6 3817.9 3854.6 3987.4 9343.0 9732.0 9873.9 9943.0 9953.6 9853.0 9858.1 9859.3 9867.3 9868.1 7200.8 7409.6 7469.1 7472.3 7482.3 5447.7 5449.0 5470.9 5560.7 5734.1 8747.7 9031.4 9036.6 9073.1 9221.4 9214.3 9243.3 9396.2 9398.6 9419.2 6315.4 6406.9 6409.1 6412.4 6421.9 7110.4 7178.3 7346.7 7463.5 7468.4 5469.3 6647.0 6712.6 6760.1 6896.2 6816.5 6843.3 6855.5 6899.9 6902.9 6338.1 6447.5 6453.3 6454.4 6460.1 4336.6 4583.4 4593.2 4664.7 4683.6 4669.9 4674.9 4679.1 4843.0 4848.3 03:11:20 INFO - PROCESS | 9872 | 03:11:20 INFO - PROCESS | 9872 | [#4] prototype.html Cycles:180 Average:4210.40 Median:4786.05 stddev:1207.93 (25.2%) stddev-sans-first:1175.01 03:11:20 INFO - PROCESS | 9872 | Values: 294.6 297.9 306.3 306.8 307.0 4686.4 4781.4 4837.4 4868.4 4891.9 4654.3 4748.0 4780.0 4790.7 4816.3 3820.9 3830.0 3831.2 3835.6 3843.0 3610.0 3624.3 3836.1 3838.7 3839.9 3772.0 3787.3 3788.4 3793.5 3808.4 3780.5 3782.1 3785.5 3786.2 3792.8 3370.3 3372.6 3374.9 3377.4 3378.9 4565.2 4693.5 4697.2 4718.0 4723.1 4308.7 4338.7 4356.8 4359.3 4362.4 5006.2 5013.4 5023.8 5035.8 5039.0 3560.6 3632.4 3647.6 3663.2 3682.9 5245.5 5250.7 5254.3 5258.3 5265.7 3760.9 3770.9 3771.7 3772.4 3775.6 3747.2 3830.7 3832.9 3833.2 3841.2 4283.9 4287.0 4336.4 4340.9 4361.2 5020.6 5029.6 5031.6 5032.9 5054.9 1247.0 1273.6 1313.2 1317.6 1324.2 899.4 922.0 935.2 936.6 942.9 4446.1 4458.4 4469.7 4479.6 4484.0 5145.2 5156.8 5159.2 5164.0 5166.3 4458.2 4459.7 4464.8 4474.2 4491.2 2627.0 2776.5 2779.8 2784.6 2786.7 4945.5 4959.9 4961.4 4990.0 5022.3 5224.9 5351.8 5406.3 5411.4 5447.8 4943.7 4948.4 4954.7 4972.8 4980.2 5228.9 5265.2 5276.2 5276.4 5280.2 4966.8 5026.8 5089.6 5163.9 5177.0 4909.0 4918.7 4927.3 4932.7 4933.6 3601.8 3617.8 3639.2 3653.1 3655.0 5052.3 5140.2 5244.5 5246.8 5261.2 4994.9 4998.7 5041.5 5044.0 5045.1 4989.9 4996.2 4996.8 5012.8 5024.8 4675.0 4905.8 4921.7 4950.9 4961.8 5023.6 5028.2 5036.9 5038.4 5038.5 4838.4 4852.3 4883.7 4886.0 4892.3 03:11:20 INFO - PROCESS | 9872 | 03:11:20 INFO - PROCESS | 9872 | [#5] yui.html Cycles:180 Average:17811.04 Median:504.80 stddev:25583.14 (5068.0%) stddev-sans-first:25622.21 03:11:20 INFO - PROCESS | 9872 | Values: 583.5 651.9 709.7 718.4 744.4 10514.8 12305.4 12722.6 12806.9 12812.3 13280.3 13915.5 13980.6 14001.0 14005.2 730.6 746.3 747.5 747.7 748.2 590.1 722.2 741.4 743.8 744.2 789.9 795.1 797.5 798.4 798.6 794.7 795.3 797.2 797.6 798.5 576.9 577.4 578.4 580.0 580.4 12697.9 12717.9 12803.4 12807.5 12833.6 13150.5 13273.3 13456.5 13541.5 13865.0 10421.7 10466.7 10785.6 10847.1 11188.3 3262.8 3289.8 3363.4 3377.2 3426.3 65605.0 66281.0 66344.3 66457.7 66506.0 9744.8 10056.7 10072.1 10150.8 10379.4 18233.4 18541.4 18584.6 18694.0 18704.2 6592.2 6842.3 6860.6 6978.3 7049.7 3700.6 3708.2 3721.1 3762.4 3809.8 2762.4 2885.6 2986.3 3187.7 3362.3 556.7 559.9 563.5 564.6 565.8 493.0 534.9 545.5 548.8 572.4 7597.5 7619.0 7804.9 7828.7 8118.0 516.6 568.7 587.4 599.3 608.6 3787.8 3795.8 3807.0 3807.0 4081.9 78255.0 81225.6 100909.4 104882.5 104990.5 76495.6 86998.0 87773.6 88011.6 88337.0 74533.0 75174.2 76834.0 78168.6 85089.6 7147.4 7454.4 7586.3 7809.2 7812.3 41898.8 42920.4 43558.7 43685.3 44156.3 57752.7 61290.7 62338.0 62411.4 65104.0 10867.5 11228.2 11348.9 11453.1 11547.4 13233.3 13437.9 13447.6 14273.9 14313.9 11131.2 11417.3 11795.1 11816.9 11834.5 10195.9 10502.0 10532.4 11157.7 11910.2 10780.7 10787.8 11106.7 11403.1 11446.2 9866.0 9974.9 10271.8 10612.9 10634.7 9150.5 9659.9 9809.9 9877.5 9910.6 03:11:20 INFO - PROCESS | 9872 | -------- Summary: end -------- 03:11:20 INFO - PROCESS | 9872 | 03:11:21 INFO - TEST-INFO | 9872: exit 0 03:11:21 INFO - TEST-OK | dromaeo_css | took 1121381ms 03:11:21 INFO - TEST-START | kraken 03:11:21 INFO - operating with platform_type : linux_ 03:11:21 INFO - Initialising browser for kraken test... 03:11:21 INFO - TEST-INFO | started process 10179 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4RlBMr/profile http://localhost:38131/getInfo.html) 03:11:25 INFO - PROCESS | 10179 | __metrics Screen width/height:1600/1200 03:11:25 INFO - PROCESS | 10179 | colorDepth:24 03:11:25 INFO - PROCESS | 10179 | Browser inner width/height: 1024/697 03:11:25 INFO - PROCESS | 10179 | __metrics 03:11:26 INFO - PROCESS | 10179 | 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" 03:11:26 INFO - PROCESS | 10179 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:11:26 INFO - PROCESS | 10179 | console.error: PushService: 03:11:26 INFO - PROCESS | 10179 | stateChangeProcessEnqueue: Error transitioning state 03:11:26 INFO - PROCESS | 10179 | UnknownError 03:11:27 INFO - TEST-INFO | 10179: exit 0 03:11:27 INFO - Browser initialized. 03:11:27 INFO - Running cycle 1/1 for kraken test... 03:11:27 INFO - TEST-INFO | started process 10258 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4RlBMr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:11:39 INFO - PROCESS | 10258 | RSS: Main: 180486144 03:11:39 INFO - PROCESS | 10258 | 03:11:42 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38131/tests/kraken/driver.html?testName=audio-beat-detection) 03:11:43 INFO - PROCESS | 10258 | RSS: Main: 234631168 03:11:43 INFO - PROCESS | 10258 | 03:11:46 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38131/tests/kraken/driver.html?testName=audio-dft) 03:11:46 INFO - PROCESS | 10258 | RSS: Main: 221069312 03:11:46 INFO - PROCESS | 10258 | 03:11:50 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38131/tests/kraken/driver.html?testName=audio-fft) 03:11:50 INFO - PROCESS | 10258 | RSS: Main: 305303552 03:11:50 INFO - PROCESS | 10258 | 03:11:52 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38131/tests/kraken/driver.html?testName=audio-oscillator) 03:11:53 INFO - PROCESS | 10258 | RSS: Main: 213614592 03:11:53 INFO - PROCESS | 10258 | 03:11:55 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38131/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:11:56 INFO - PROCESS | 10258 | RSS: Main: 215195648 03:11:56 INFO - PROCESS | 10258 | 03:11:59 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38131/tests/kraken/driver.html?testName=imaging-darkroom) 03:12:00 INFO - PROCESS | 10258 | RSS: Main: 255131648 03:12:00 INFO - PROCESS | 10258 | 03:12:03 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38131/tests/kraken/driver.html?testName=imaging-desaturate) 03:12:04 INFO - PROCESS | 10258 | RSS: Main: 249110528 03:12:04 INFO - PROCESS | 10258 | 03:12:07 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38131/tests/kraken/driver.html?testName=json-parse-financial) 03:12:07 INFO - PROCESS | 10258 | RSS: Main: 251645952 03:12:07 INFO - PROCESS | 10258 | 03:12:09 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38131/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:12:10 INFO - PROCESS | 10258 | RSS: Main: 215597056 03:12:10 INFO - PROCESS | 10258 | 03:12:11 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38131/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:12:12 INFO - PROCESS | 10258 | RSS: Main: 223924224 03:12:12 INFO - PROCESS | 10258 | 03:12:14 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38131/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:12:15 INFO - PROCESS | 10258 | RSS: Main: 222437376 03:12:15 INFO - PROCESS | 10258 | 03:12:18 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38131/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:12:18 INFO - PROCESS | 10258 | RSS: Main: 222433280 03:12:18 INFO - PROCESS | 10258 | 03:12:21 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38131/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:12:22 INFO - PROCESS | 10258 | RSS: Main: 220577792 03:12:22 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | Cycle 1(1): loaded http://localhost:38131/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38131/tests/kraken/driver.html?testName=ai-astar) 03:12:24 INFO - PROCESS | 10258 | RSS: Main: 271609856 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | __start_tp_report 03:12:24 INFO - PROCESS | 10258 | _x_x_mozilla_page_load 03:12:24 INFO - PROCESS | 10258 | _x_x_mozilla_page_load_details 03:12:24 INFO - PROCESS | 10258 | |i|pagename|runs| 03:12:24 INFO - PROCESS | 10258 | |0;ai-astar;114;115;118;113;115;114;114;115;114;129 03:12:24 INFO - PROCESS | 10258 | |1;audio-beat-detection;152;122;152;179;130;151;144;126;188;122 03:12:24 INFO - PROCESS | 10258 | |2;audio-dft;161;162;162;160;161;161;161;162;163;163 03:12:24 INFO - PROCESS | 10258 | |3;audio-fft;76;76;75;165;76;77;75;75;157;99 03:12:24 INFO - PROCESS | 10258 | |4;audio-oscillator;84;85;143;84;84;83;137;83;83;138 03:12:24 INFO - PROCESS | 10258 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;117;117;116 03:12:24 INFO - PROCESS | 10258 | |6;imaging-darkroom;147;147;147;146;145;147;147;148;146;147 03:12:24 INFO - PROCESS | 10258 | |7;imaging-desaturate;95;95;95;95;95;94;95;95;95;94 03:12:24 INFO - PROCESS | 10258 | |8;json-parse-financial;73;64;66;65;65;66;66;65;66;65 03:12:24 INFO - PROCESS | 10258 | |9;json-stringify-tinderbox;67;66;66;66;66;66;66;67;66;67 03:12:24 INFO - PROCESS | 10258 | |10;stanford-crypto-aes;82;82;82;82;82;83;82;82;83;82 03:12:24 INFO - PROCESS | 10258 | |11;stanford-crypto-ccm;133;134;143;143;146;144;133;133;182;134 03:12:24 INFO - PROCESS | 10258 | |12;stanford-crypto-pbkdf2;180;179;177;177;176;174;173;173;175;175 03:12:24 INFO - PROCESS | 10258 | |13;stanford-crypto-sha256-iterative;69;68;70;70;69;71;70;72;69;69 03:12:24 INFO - PROCESS | 10258 | __end_tp_report 03:12:24 INFO - PROCESS | 10258 | __start_cc_report 03:12:24 INFO - PROCESS | 10258 | _x_x_mozilla_cycle_collect,2028 03:12:24 INFO - PROCESS | 10258 | __end_cc_report 03:12:24 INFO - PROCESS | 10258 | __startTimestamp1472811144519__endTimestamp 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | ------- Summary: start ------- 03:12:24 INFO - PROCESS | 10258 | Number of tests: 14 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#0] ai-astar Cycles:10 Average:116.10 Median:115.00 stddev:4.72 (4.1%) stddev-sans-first:4.95 03:12:24 INFO - PROCESS | 10258 | Values: 114.0 115.0 118.0 113.0 115.0 114.0 114.0 115.0 114.0 129.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#1] audio-beat-detection Cycles:10 Average:146.60 Median:151.50 stddev:22.99 (15.2%) stddev-sans-first:24.31 03:12:24 INFO - PROCESS | 10258 | Values: 152.0 122.0 152.0 179.0 130.0 151.0 144.0 126.0 188.0 122.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 03:12:24 INFO - PROCESS | 10258 | Values: 161.0 162.0 162.0 160.0 161.0 161.0 161.0 162.0 163.0 163.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#3] audio-fft Cycles:10 Average:95.10 Median:76.00 stddev:35.54 (46.8%) stddev-sans-first:37.02 03:12:24 INFO - PROCESS | 10258 | Values: 76.0 76.0 75.0 165.0 76.0 77.0 75.0 75.0 157.0 99.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#4] audio-oscillator Cycles:10 Average:100.40 Median:83.50 stddev:26.92 (32.2%) stddev-sans-first:27.89 03:12:24 INFO - PROCESS | 10258 | Values: 84.0 85.0 143.0 84.0 84.0 83.0 137.0 83.0 83.0 138.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:12:24 INFO - PROCESS | 10258 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 03:12:24 INFO - PROCESS | 10258 | Values: 147.0 147.0 147.0 146.0 145.0 147.0 147.0 148.0 146.0 147.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:12:24 INFO - PROCESS | 10258 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:66.00 stddev:2.51 (3.8%) stddev-sans-first:0.71 03:12:24 INFO - PROCESS | 10258 | Values: 73.0 64.0 66.0 65.0 65.0 66.0 66.0 65.0 66.0 65.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 03:12:24 INFO - PROCESS | 10258 | Values: 67.0 66.0 66.0 66.0 66.0 66.0 66.0 67.0 66.0 67.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:82.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 03:12:24 INFO - PROCESS | 10258 | Values: 82.0 82.0 82.0 82.0 82.0 83.0 82.0 82.0 83.0 82.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#11] stanford-crypto-ccm Cycles:10 Average:142.50 Median:143.00 stddev:14.87 (10.4%) stddev-sans-first:15.37 03:12:24 INFO - PROCESS | 10258 | Values: 133.0 134.0 143.0 143.0 146.0 144.0 133.0 133.0 182.0 134.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:176.50 stddev:2.38 (1.3%) stddev-sans-first:2.01 03:12:24 INFO - PROCESS | 10258 | Values: 180.0 179.0 177.0 177.0 176.0 174.0 173.0 173.0 175.0 175.0 03:12:24 INFO - PROCESS | 10258 | 03:12:24 INFO - PROCESS | 10258 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 03:12:24 INFO - PROCESS | 10258 | Values: 69.0 68.0 70.0 70.0 69.0 71.0 70.0 72.0 69.0 69.0 03:12:24 INFO - PROCESS | 10258 | -------- Summary: end -------- 03:12:24 INFO - PROCESS | 10258 | 03:12:25 INFO - TEST-INFO | 10258: exit 0 03:12:25 INFO - TEST-OK | kraken | took 64493ms 03:12:25 INFO - SUITE-END | took 1185s 03:12:26 INFO - Completed test suite (00:19:46) 03:12:26 INFO - javascript benchmark 03:12:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2607.4654695787367, 2610.765031147196, 2626.8555229462395, 2662.294491959631, 2678.812483126182, 4619.237825758754, 4707.2939059141145, 4770.379850619419, 4843.200871856148, 4891.315215869777, 4686.367340409044, 4711.128441238373, 4714.434267887858, 4723.8110475581025, 4783.521647835215, 4317.244482215599, 4337.566243375653, 4366.956330436687, 4425.269830477967, 4519.615832654228, 4422.557744225568, 4449.532799056101, 4463.464384273875, 4487.12501062293, 4496.370508128848, 4263.253930562139, 4302.354052257839, 4312.353147027971, 4344.4352234209355, 4358.215521206182, 4342.717723347972, 4368.060866913597, 4374.650027997753, 4423.933640995388, 4424.513303536608, 4356.520782713899, 4367.170237654836, 4376.190404775127, 4420.182266280748, 4465.59809617136, 4774.856754297377, 4832.395950506187, 4845.563899249085, 4848.903021939542, 4897.730624815634, 4856.150173719585, 4926.876828079291, 5030.195168772979, 5127.692338459667, 5177.534021938006, 5175.171972484384, 5180.766865491062, 5191.0656081905245, 5199.350081239845, 5200.8959820803375, 4817.229243321087, 4848.684835485682, 4849.199882019498, 4854.101991131582, 4903.387076615423, 5118.846434606968, 5175.767090480937, 5233.895322093613, 5238.449962753933, 5317.9202311965355, 5000.1249781288125, 5031.295618613397, 5033.6224783141415, 5039.370078740158, 5040.218766091261, 5118.334616499831, 5123.513266239701, 5125.461826508237, 5125.666831656005, 5172.560332371715, 4909.975450122798, 4984.327115839315, 5035.974820125876, 5041.344625198701, 5106.66806657566, 5030.924536131961, 5044.192929131322, 5045.672031317952, 5047.0, 5094.847154585368, 4221.7255878367805, 4228.70399072062, 4228.809703563347, 4230.0, 4288.356746488052, 3634.345817752777, 3664.9450258246147, 3704.685101766326, 3725.6460636239512, 3774.3206222880144, 4430.113977204572, 4444.866654000385, 4447.110577884371, 4499.842505512292, 4502.752348620792, 5113.71874546896, 5119.206522988943, 5122.590192784568, 5127.230915362651, 5182.97408512955, 4319.33050377192, 4415.977920110379, 4434.800433980478, 4458.398116254329, 4470.8435204767675, 4555.977220113945, 4586.3579098926175, 4619.376384188159, 4632.745199014086, 4636.629069674418, 4864.878378040577, 4869.707817531031, 4869.902601947942, 4870.586000189882, 4929.531694489017, 4920.188168952226, 5095.0, 5122.487751224997, 5134.383873935151, 5157.329547158845, 4858.392700912386, 4895.04546613407, 4930.4822993586595, 4938.580220681282, 4956.900861982668, 5096.133657278346, 5096.159133742891, 5096.949030509648, 5103.157978933435, 5149.794008239628, 4951.628627852853, 5092.898142037065, 5158.174692049404, 5173.611979101658, 5173.870653233699, 4979.825706100414, 5019.84940451787, 5020.673656212334, 5023.874403139951, 5063.1645778446145, 5018.096742586369, 5027.7234752087525, 5027.798888044584, 5036.294918711456, 5095.515925987025, 4705.647076469347, 4925.704457732548, 4936.3089167515855, 4943.555080042814, 4946.406431228275, 5065.240213967934, 5075.593952483866, 5087.949120508895, 5126.820561280338, 5135.306733591066, 4906.95093049065, 4907.9263811043575, 4913.508649135058, 4926.359573255454, 4975.975120124376], "value": 4696.078489610724, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.1246006389777, 551.7434393007251, 558.9240711630111, 574.5489790514447, 574.6982834012146, 37424.43863342052, 38844.22311553771, 39219.41170882439, 39249.80375098128, 42860.0, 38978.0, 39108.0, 39286.60713392865, 39438.21123577527, 39451.8438524918, 7154.1415030196185, 7567.962160189191, 7714.884276735826, 7721.150673425918, 7721.53670779755, 7283.890741638879, 7485.73799917003, 7554.24457554243, 9807.0, 10073.294869359148, 10131.848022279672, 10184.79630407395, 10189.949050254738, 10194.592216311376, 10198.286119971604, 10006.249531285146, 10057.949710251401, 10068.496575171286, 10069.345492543034, 10074.395536267812, 9385.249180065579, 9459.385139965949, 9464.621415143385, 9465.621375144987, 9466.0, 37096.0, 37523.24953500942, 37554.24891502182, 37600.43599346012, 37636.62363376386, 2823.1953893140358, 2825.8587070646386, 2863.3271181272594, 2942.9040768579907, 3144.151079208601, 27598.724012759816, 27720.0, 27842.3039424014, 27945.441091178265, 28532.0, 3452.689257966795, 3478.739094567892, 3518.6305437928904, 3558.5907620623443, 3649.543807024122, 184654.07672961685, 184655.0, 185211.99999999866, 185314.0, 185398.0, 51322.0, 51854.22218666723, 51904.22143667848, 51928.0, 51966.22050669167, 47960.0, 48435.0, 53590.12434564772, 53644.73177634165, 53712.0, 14713.411463541552, 14982.0, 15494.690106197813, 15528.767068493982, 15944.441944532118, 11222.0, 11246.212765106364, 11264.71838204035, 11271.605493807678, 11275.830862537068, 15007.699846003019, 16877.578060548585, 17472.301107955795, 17579.824201758074, 17919.37282195117, 6608.966955165193, 6711.765088221987, 6746.0, 6789.422899053537, 6903.309669033057, 771.3713323641215, 883.2977782662798, 906.1889608800087, 916.225789208118, 929.1637526226315, 26050.739492604833, 26888.0, 27171.72828271692, 27456.0, 27526.724732752817, 815.3762371785589, 883.1962913748458, 945.1871390604075, 969.5346233807812, 1006.642641862143, 14190.361433735507, 14583.489577864935, 14616.342264598119, 14864.851351486346, 14877.925610371878, 122929.38535307266, 122983.3850830758, 123694.38152809178, 123763.0, 123820.0, 46246.53753462422, 46593.30110048352, 46727.0, 46734.76632616883, 46813.0, 998.6404894238068, 1119.4850368830266, 1120.6357933671427, 1146.363768108691, 1184.810430331143, 25874.482510350063, 27317.0, 28114.578281325386, 28441.146765597066, 28775.5683664741, 39269.803650981565, 39698.0, 39802.0, 39867.2026559473, 40013.39979900362, 288480.0, 290878.0, 291106.0, 291567.0, 291675.0, 23652.0, 24723.134690285755, 24833.87583062073, 25672.0, 25810.967561297337, 38784.6121538781, 39159.02102447462, 39417.802910985265, 40039.19921601493, 40317.39523907085, 34289.0, 34349.48475772915, 34380.48429273613, 34536.65463345334, 34928.47607285842, 33765.49351759676, 34158.82920585381, 34165.0, 34173.316533669684, 34333.65666343404, 33590.83204583961, 34114.0, 34230.801921932616, 34412.13969650678, 34544.0, 29162.854185728935, 31348.529772053895, 31683.84158079195, 31774.205644859063, 31845.5223171648, 21711.457213569156, 21860.125594976023, 22142.0, 22150.88924555367, 22206.444838879157], "value": 16692.029128499136, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.7535012466086, 365.88291746641085, 371.2315506900715, 376.9830357633906, 396.84721382267816, 12131.211471254373, 15624.453144139943, 15841.445549405773, 17232.741508877378, 17395.478135655918, 16860.83139168608, 17090.0, 17091.743623845654, 17474.0, 17683.1158442078, 4908.067467181242, 4937.728424936627, 5019.799208031674, 5044.82343117991, 5049.77276022578, 3658.634136586333, 4987.950120498803, 5073.086844368012, 5595.832125036255, 5938.782549577348, 5960.672163031031, 5988.281406231258, 5989.221401217857, 5993.760249590011, 6003.369646187153, 5182.326297581328, 5439.5376393006645, 5460.836174914759, 5539.50144486994, 5972.850678733023, 5386.622936394454, 5393.460653934575, 5397.4062853086125, 5399.62202645815, 5402.081646120129, 12194.39028048594, 12297.69255768604, 12625.936870315682, 12688.619341419771, 12689.619311420673, 1794.7487351770762, 1821.5355084453513, 1884.8397886179691, 1920.93276735315, 1936.719175719527, 9014.0, 9145.81708365829, 9269.582868770922, 9476.715698529055, 9887.209023278121, 1640.5488490665043, 1671.2228813601735, 1682.1337011439118, 1686.6289416328389, 1711.0760189497657, 66226.0, 68768.31231687669, 71045.64477177632, 71495.2850471499, 72026.279737203, 9992.25058120644, 10103.747406314755, 10312.278140530241, 10357.689269321932, 10459.0, 14109.858901410855, 14118.0, 14156.292185390896, 14159.221242831743, 14162.71674566503, 7064.50548461613, 7099.183593886769, 7242.601656908921, 7279.67241474135, 7317.5609463432365, 6675.966620166965, 6904.689288982008, 7070.469714771412, 7081.221065682771, 7101.680424380916, 4315.978420107942, 4360.149770794728, 4392.2752745797225, 4475.239209334421, 4523.864284071483, 1669.2571805546415, 1815.110595808071, 1830.6064196197615, 1854.1285595769966, 1876.605912758336, 713.5219402999899, 824.6083110522454, 830.4228561149943, 871.1506281375734, 875.5753459572103, 16594.83405165957, 16804.495865124067, 16955.15224238783, 18593.7210941836, 18912.52718682044, 834.6244190114472, 869.8347314010318, 877.912208779117, 878.6046279174398, 899.9370044096981, 9134.954325228331, 9324.813503729887, 9628.374155679858, 9733.172680322252, 9843.852342214872, 279416.0, 281839.0, 282106.0, 395054.0, 399350.0, 49544.75227623839, 51862.740686296325, 52293.0, 60910.695446522484, 81971.0, 887.3522328700085, 906.2613969614874, 937.6999360204669, 937.9390339627903, 941.7598512379436, 15598.0, 15781.605459863595, 16377.836221637632, 16575.502734917973, 16709.665806684043, 30642.846785765927, 30674.0, 37242.25515489729, 37842.61188845843, 38953.80523097367, 52316.47683523116, 54306.45693543014, 54784.452155477935, 55151.17273240985, 56713.14930276132, 5834.774697313612, 6194.5044396449075, 6463.7737679181, 7512.474126811071, 7867.409944254089, 10837.891621083689, 11102.389368584481, 11371.772564548828, 12283.140180187302, 12354.38228088574, 9650.420974741539, 9824.968378320173, 9893.406395616286, 10390.272680912565, 10411.063004329646, 9453.480058596842, 9472.715818525456, 9676.661316853877, 9789.216862650828, 10576.312539684895, 9680.0, 10116.494175291353, 10242.539085741011, 10430.68707938763, 10557.366558006544, 6354.90467642995, 6592.538522303393, 6685.765998190234, 7096.929030709627, 7194.0, 7399.667014984447, 10254.025867542572, 10326.0, 10385.532651030873, 11147.888521114684], "value": 7932.413310872247, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.7387509435662, 1356.274393199638, 1422.2462095089606, 1658.9502314930555, 1674.8743844211688, 7034.824129396768, 7557.395408367332, 7749.341305988998, 8205.958970205149, 8215.91784082159, 7845.37237021037, 8134.145914678962, 8140.877886831688, 8147.9592602037055, 8148.103708592065, 2240.7535171131203, 2260.9095636174534, 2265.4449659833363, 2267.1498188179435, 2268.353519247015, 1874.5126267170497, 2125.872447653138, 2201.6147174244443, 2217.246136313653, 2241.8206543476563, 2213.6347502662043, 2236.6980457638174, 2237.776222377757, 2241.05875532276, 2242.6972358731527, 2132.8613640113344, 2145.463634091477, 2173.5978843913767, 2177.1835561664375, 2185.8579192352445, 2131.914723411062, 2158.3201291593255, 2163.350994701583, 2169.0, 2176.4667656424117, 8073.596320183968, 8136.593170341459, 8137.674493020272, 8139.0, 8148.877766833502, 4529.1621050105505, 4598.402207713009, 4645.326427668003, 4650.697704649187, 4656.4412270527755, 2965.451391492559, 3095.1797773590015, 3112.50199968004, 3144.7169754722186, 3155.4162479941283, 883.7878909061843, 923.382211430595, 926.4395041000165, 941.7174847545778, 945.6264775413673, 10977.451127443595, 11002.559897604086, 11027.0, 11043.779124417548, 11046.116310695124, 4172.645325147336, 4240.279152544075, 4398.978005109954, 4428.933565996513, 4443.733375997451, 9391.859122113174, 9394.389364691273, 9405.247580193569, 9407.952960235156, 9412.811743765087, 4588.7935042923145, 4598.701084429501, 4601.4248218972625, 4603.378543896598, 4606.97696511522, 4135.441715368387, 4162.250794856895, 4166.66666666666, 4173.10278290169, 4175.979120104441, 3668.5781135169264, 3677.154254521475, 3763.473113764075, 3771.26460340231, 3832.2718683450057, 1060.798448294837, 1176.6352430746497, 1205.3912774049224, 1231.1751126745103, 1231.9630411087683, 734.3060807536933, 815.9551224682581, 824.2005254902734, 826.7643721539453, 842.2041171093381, 5301.920471192935, 5317.6277660564165, 5351.491608297206, 5445.0, 5445.591580631469, 883.6377085395086, 888.0941439731438, 907.686848037426, 922.1516205091332, 924.7377329944577, 3770.981145094257, 3800.5819359870393, 3817.923641527154, 3854.614538546179, 3987.4218238355274, 9342.95328523353, 9732.0, 9873.901260987443, 9943.0, 9953.601855925683, 9852.95073524642, 9858.063483969012, 9859.26055545837, 9867.309288349747, 9868.111869931741, 7200.819979500449, 7409.629518524052, 7469.141048779351, 7472.327490525879, 7482.2517748224745, 5447.700376479332, 5448.991936491761, 5470.91793623104, 5560.666360018412, 5734.107037991805, 8747.65009399617, 9031.412958157698, 9036.593353298986, 9073.092690731139, 9221.354505184841, 9214.262858971133, 9243.260539156985, 9396.248300136109, 9398.577064031999, 9419.246460283024, 6315.431611155018, 6406.903896441464, 6409.070684750592, 6412.39082287182, 6421.903671445026, 7110.3600675939415, 7178.28217178278, 7346.742863999734, 7463.477556570988, 7468.365188958782, 5469.349979323927, 6647.0, 6712.597244165366, 6760.087388202726, 6896.172459304715, 6816.454683625398, 6843.281455447106, 6855.485838562227, 6899.93100069013, 6902.8619427612175, 6338.144350512622, 6447.548671592944, 6453.257875344396, 6454.419102280817, 6460.127882735958, 4336.588024137702, 4583.404157459642, 4593.2191527439745, 4664.696794708333, 4683.648726345606, 4669.906601868011, 4674.859754207243, 4679.134360143248, 4843.0313937211995, 4848.272759086024], "value": 4203.2729565778845, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.57875238409065, 297.91658335666017, 306.3413660629647, 306.7576614474564, 306.95088785794275, 4686.399048528074, 4781.402324709411, 4837.379477874912, 4868.367112275399, 4891.85324440267, 4654.3251228571835, 4748.0, 4780.020095880345, 4790.688605240665, 4816.301636262749, 3820.9426858597144, 3830.0, 3831.2337532493475, 3835.5780864104995, 3842.9615703843024, 3609.981950090246, 3624.2932628137523, 3836.079340958178, 3838.673712734421, 3839.884803455901, 3771.981140094296, 3787.337215987205, 3788.3559794834805, 3793.468914351993, 3808.428735689641, 3780.546334439871, 3782.092297848524, 3785.482654035063, 3786.2238241160494, 3792.8103594820286, 3370.3427831572853, 3372.5615669962995, 3374.8650053997812, 3377.3751855906735, 3378.864845406181, 4565.20108980927, 4693.460252071021, 4697.2484402495775, 4718.0092180642105, 4723.07899959508, 4308.6983911126235, 4338.652907767371, 4356.782160891975, 4359.280718681413, 4362.36745671879, 5006.172746947374, 5013.373328333958, 5023.824166154167, 5035.77339019745, 5039.0, 3560.626134255892, 3632.4017432329956, 3647.598764135943, 3663.249033948034, 3682.9263414731554, 5245.527902488756, 5250.684958902478, 5254.290670759437, 5258.342707161605, 5265.657732247391, 3760.9247815043545, 3770.9434358484646, 3771.6605505504635, 3772.396416573335, 3775.6224377562025, 3747.2318174774105, 3830.7318487705593, 3832.8884623459444, 3833.2333533293454, 3841.193349396658, 4283.87148385549, 4287.0, 4336.436263285753, 4340.913181736348, 4361.193179261847, 5020.59835213182, 5029.5724863386295, 5031.597472202215, 5032.874178145576, 5054.9241761373605, 1246.9899381501014, 1273.5860845225338, 1313.2055106660523, 1317.611304665107, 1324.2120938041849, 899.3614533681159, 922.041077279635, 935.2237642756496, 936.5691781780321, 942.9486122972972, 4446.088551846864, 4458.375827384169, 4469.687121901501, 4479.59683628475, 4484.0, 5145.176771716508, 5156.83412966555, 5159.226116082617, 5163.974180129076, 5166.302549155891, 4458.152950939311, 4459.687821852504, 4464.843730469418, 4474.172278128557, 4491.236489796743, 2626.9903523804305, 2776.458590574859, 2779.8332100074063, 2784.5962335461245, 2786.749192572678, 4945.480724523945, 4959.876003099951, 4961.355023846877, 4990.0, 5022.296878437021, 5224.947750522446, 5351.759170837322, 5406.324209473816, 5411.377691565521, 5447.813029377859, 4943.703377797272, 4948.356713627206, 4954.677945933503, 4972.825951091694, 4980.153373926513, 5228.947710522846, 5265.236540701653, 5276.208568714724, 5276.445973172762, 5280.234366016983, 4966.776495057658, 5026.798928042837, 5089.567386772166, 5163.896722065613, 5177.0, 4909.0, 4918.655694101379, 4927.3247095987535, 4932.681639629369, 4933.55597996182, 3601.8019008954757, 3617.837197326179, 3639.16299251179, 3653.06846754076, 3654.9634503654624, 5052.267421223975, 5140.203268493389, 5244.527992480543, 5246.76389562463, 5261.210818377274, 4994.875128121826, 4998.700077995332, 5041.521350268303, 5043.9747801260755, 5045.142325804549, 4989.850304490871, 4996.200607902718, 4996.775145118551, 5012.77442515095, 5024.773885175103, 4675.0, 4905.7792399342825, 4921.704697718148, 4950.851474455772, 4961.776720047535, 5023.623228257822, 5028.245763135559, 5036.899262014813, 5038.420581633159, 5038.496150384932, 4838.371011768448, 4852.3016944069295, 4883.682560633548, 4885.975570122127, 4892.31507588945], "value": 3830.861718984886, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.4865318519703, 651.9022146678001, 709.7054722290248, 718.357070421972, 744.3672878053655, 10514.842277365846, 12305.384730763471, 12722.554710585131, 12806.871931280684, 12812.295323757202, 13280.335983200825, 13915.512957046505, 13980.58058258254, 14001.0, 14005.229712365815, 730.6346826586706, 746.2761121711931, 747.4692967992704, 747.7382915979391, 748.2218492767515, 590.1236663554618, 722.2236096196583, 741.4105785900223, 743.7843025522592, 744.1739668967442, 789.8657228271207, 795.08168065884, 797.5214871077336, 798.3613109512366, 798.6006996501749, 794.7297918707609, 795.2882170457466, 797.2426195114664, 797.6490344248541, 798.5049269452918, 576.9192313076172, 577.3822010448821, 578.4302462074853, 580.0168714029721, 580.3818932836516, 12697.936510317482, 12717.936410317981, 12803.423845926956, 12807.487700492062, 12833.550825721055, 13150.539731109366, 13273.336333183302, 13456.499932627714, 13541.45834166632, 13864.979156044736, 10421.739456513573, 10466.738331541696, 10785.5685772569, 10847.077998370069, 11188.328700277929, 3262.755293353008, 3289.7861638993627, 3363.428217203057, 3377.155711072232, 3426.3147370525994, 65605.0, 66281.0, 66344.33655663478, 66457.66771166162, 66506.00240996388, 9744.756381090388, 10056.748581285527, 10072.093511583993, 10150.796984060426, 10379.377237365783, 18233.361832335806, 18541.35105271309, 18584.628307433777, 18694.0, 18704.158312875952, 6592.24189218246, 6842.349976752201, 6860.5540639858245, 6978.337057979484, 7049.718011279491, 3700.611435799256, 3708.2027364116307, 3721.14413684854, 3762.398016317376, 3809.8095095245126, 2762.4198918226994, 2885.6104425902654, 2986.283292009902, 3187.7131058204873, 3362.260302733395, 556.7244214113958, 559.932808063035, 563.5294529068274, 564.6301672404581, 565.8104534980828, 493.0, 534.8796520782871, 545.4817922973112, 548.8161465908886, 572.3544808675059, 7597.468177227541, 7619.0, 7804.9463322451875, 7828.686852525949, 8117.959410202911, 516.5918924049959, 568.7355379748437, 587.4037851580649, 599.2898415377791, 608.6104892868568, 3787.753796003251, 3795.772253664789, 3806.980965095212, 3806.980965095212, 4081.9387709184384, 78255.0, 81225.59387203027, 100909.43179261438, 104882.47558762158, 104990.47504762428, 76495.61752191203, 86998.0, 87773.56113219394, 88011.55994219988, 88337.0, 74533.0, 75174.2482575189, 76834.0, 78168.60915695385, 85089.57455212684, 7147.428205743424, 7454.403647708279, 7586.279303466162, 7809.2190780921455, 7812.296893279632, 41898.79050604728, 42920.35619465773, 43558.693239202876, 43685.344719828594, 44156.33765493584, 57752.71123644355, 61290.693546531984, 62338.0, 62411.37588624237, 65104.0, 10867.456627168767, 11228.157888158257, 11348.943255283672, 11453.141014424118, 11547.422628868691, 13233.338333083115, 13437.86562134366, 13447.596572102853, 14273.928630356782, 14313.856861431252, 11131.220814542905, 11417.31496110236, 11795.11536634773, 11816.881831181578, 11834.46744896499, 10195.898041019494, 10502.0, 10532.36805791655, 11157.72105697364, 11910.225835320676, 10780.676579702622, 10787.838182427427, 11106.722331941766, 11403.087752979907, 11446.198766086294, 9865.950670246602, 9974.850377244493, 10271.845922311022, 10612.893871061191, 10634.680959571226, 9150.542472876463, 9659.855102173333, 9809.852852207365, 9877.456739879375, 9910.603575857174], "value": 6508.480450337202, "unit": "score"}], "name": "dromaeo_css", "value": 6343.708661109286, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 118.0, 113.0, 115.0, 114.0, 114.0, 115.0, 114.0, 129.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 122.0, 152.0, 179.0, 130.0, 151.0, 144.0, 126.0, 188.0, 122.0], "value": 146.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 160.0, 161.0, 161.0, 161.0, 162.0, 163.0, 163.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 165.0, 76.0, 77.0, 75.0, 75.0, 157.0, 99.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 143.0, 84.0, 84.0, 83.0, 137.0, 83.0, 83.0, 138.0], "value": 100.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 145.0, 147.0, 147.0, 148.0, 146.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 64.0, 66.0, 65.0, 65.0, 66.0, 66.0, 65.0, 66.0, 65.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 67.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 82.0, 82.0, 83.0, 82.0, 82.0, 83.0, 82.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 134.0, 143.0, 143.0, 146.0, 144.0, 133.0, 133.0, 182.0, 134.0], "value": 142.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 179.0, 177.0, 177.0, 176.0, 174.0, 173.0, 173.0, 175.0, 175.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 70.0, 70.0, 69.0, 71.0, 70.0, 72.0, 69.0, 69.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1580.8, "alertThreshold": 2.0}]} 03:12:26 INFO - Return code: 0 03:12:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:12:26 INFO - # TBPL SUCCESS # 03:12:26 INFO - Running post-action listener: _resource_record_post_action 03:12:26 INFO - [mozharness: 2016-09-02 10:12:26.314798Z] Finished run-tests step (success) 03:12:26 INFO - Running post-run listener: _resource_record_post_run 03:12:26 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 151552; Write bytes: 418004992; Read time: 188; Write time: 219624 03:12:26 INFO - TinderboxPrint: CPU usage
13.0% 03:12:26 INFO - TinderboxPrint: I/O read bytes / time
151,552 / 188 03:12:26 INFO - TinderboxPrint: I/O write bytes / time
418,004,992 / 219,624 03:12:26 INFO - TinderboxPrint: CPU idle
8,347.2 (87.0%) 03:12:26 INFO - TinderboxPrint: CPU user
1,201.8 (12.5%) 03:12:26 INFO - TinderboxPrint: Swap in / out
0 / 0 03:12:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114356224; Read time: 0; Write time: 74020 03:12:26 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 151552; Write bytes: 292319232; Read time: 188; Write time: 143484 03:12:26 INFO - Running post-run listener: _upload_blobber_files 03:12:26 INFO - Blob upload gear active. 03:12:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:12:26 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 03:12:26 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'] 03:12:26 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 03:12:27 INFO - (blobuploader) - INFO - Open directory for files ... 03:12:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:12:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:12:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:12:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:12:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:12:28 INFO - (blobuploader) - INFO - Done attempting. 03:12:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:12:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:12:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:12:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:12:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:12:28 INFO - (blobuploader) - INFO - Done attempting. 03:12:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:12:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:12:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:12:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:12:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:12:31 INFO - (blobuploader) - INFO - Done attempting. 03:12:31 INFO - (blobuploader) - INFO - Iteration through files over. 03:12:31 INFO - Return code: 0 03:12:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:12:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:12:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fcc6cadbdb464f5bb86dea53449a96a3d5161ab5aff01bcdc3212d60b6b13f85c353159a713cc633ac58b162634c2f5b2e8c7cae5cb693ab273937387b4eb472", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9a31e5240209837456bb23c6a55f46355f0f4c8f6cd9a975e0f51277710aceb0277032157597915de53302bc18a2484cda3d39de11e56cd146d22a2fe7b11886", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/32b074852856ee1dad34e65e047f447b9b3061158207418ef62dfc3f209027a403ecd825f8a4d634ef11bc8ac6127be61a27f473abf1b0bb46494bd56e16eeb0"} 03:12:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:12:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:12:31 INFO - Contents: 03:12:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fcc6cadbdb464f5bb86dea53449a96a3d5161ab5aff01bcdc3212d60b6b13f85c353159a713cc633ac58b162634c2f5b2e8c7cae5cb693ab273937387b4eb472", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9a31e5240209837456bb23c6a55f46355f0f4c8f6cd9a975e0f51277710aceb0277032157597915de53302bc18a2484cda3d39de11e56cd146d22a2fe7b11886", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/32b074852856ee1dad34e65e047f447b9b3061158207418ef62dfc3f209027a403ecd825f8a4d634ef11bc8ac6127be61a27f473abf1b0bb46494bd56e16eeb0"} 03:12:31 INFO - Running post-run listener: copy_logs_to_upload_dir 03:12:31 INFO - Copying logs to upload dir... 03:12:31 INFO - mkdir: /builds/slave/test/build/upload/logs 03:12:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.892193 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-02 03:12:31.741603) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 03:12:31.745556) ========= 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=bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690 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/fcc6cadbdb464f5bb86dea53449a96a3d5161ab5aff01bcdc3212d60b6b13f85c353159a713cc633ac58b162634c2f5b2e8c7cae5cb693ab273937387b4eb472", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9a31e5240209837456bb23c6a55f46355f0f4c8f6cd9a975e0f51277710aceb0277032157597915de53302bc18a2484cda3d39de11e56cd146d22a2fe7b11886", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/32b074852856ee1dad34e65e047f447b9b3061158207418ef62dfc3f209027a403ecd825f8a4d634ef11bc8ac6127be61a27f473abf1b0bb46494bd56e16eeb0"} build_url:https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011398 build_url: 'https://queue.taskcluster.net/v1/task/dPhicf3jTDC7fGGWU91WgQ/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/fcc6cadbdb464f5bb86dea53449a96a3d5161ab5aff01bcdc3212d60b6b13f85c353159a713cc633ac58b162634c2f5b2e8c7cae5cb693ab273937387b4eb472", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9a31e5240209837456bb23c6a55f46355f0f4c8f6cd9a975e0f51277710aceb0277032157597915de53302bc18a2484cda3d39de11e56cd146d22a2fe7b11886", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/32b074852856ee1dad34e65e047f447b9b3061158207418ef62dfc3f209027a403ecd825f8a4d634ef11bc8ac6127be61a27f473abf1b0bb46494bd56e16eeb0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 03:12:31.771806) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 03:12:31.772167) ========= 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=bb9b54edd4a927dc735d112f000001d9-1472808988.352284-1914705690 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 03:12:31.856748) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 03:12:31.857218) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 03:12:31.857552) ========= ========= Total master_lag: 0.18 =========