builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1472512749.99 results: success (0) buildid: 20160829143007 builduid: 0f0c1efa8727474a9030ef8adf4cf444 revision: 578a16bf30e47ceb2aba7c5fd53b7eaba9829610 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 16:19:09.985575) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 16:19:09.985946) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-08-29 16:19:09.986260) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-08-29 16:19:11.894552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:19:11.894871) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1472511271.894139-1171538664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-29 16:19:12-- 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% 70.6M=0s 2016-08-29 16:19:12 (70.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115337 ========= master_lag: 0.52 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:19:12.530157) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:19:12.530461) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1472511271.894139-1171538664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015622 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:19:12.581518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 16:19:12.581829) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 578a16bf30e47ceb2aba7c5fd53b7eaba9829610 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 578a16bf30e47ceb2aba7c5fd53b7eaba9829610 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1472511271.894139-1171538664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-29 16:19:12,742 truncating revision to first 12 chars 2016-08-29 16:19:12,742 Setting DEBUG logging. 2016-08-29 16:19:12,742 attempt 1/10 2016-08-29 16:19:12,742 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/578a16bf30e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 16:19:13,476 unpacking tar archive at: fx-team-578a16bf30e4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.119347 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 16:19:13.722504) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 16:19:13.722822) ========= script_repo_revision: 578a16bf30e47ceb2aba7c5fd53b7eaba9829610 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 16:19:13.723373) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 16:19:13.723660) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 16:19:13.733076) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-29 16:19:13.733356) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1472511271.894139-1171538664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:19:13 INFO - MultiFileLogger online at 20160829 16:19:13 in /builds/slave/test 16:19:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:19:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:19:13 INFO - {'append_to_log': False, 16:19:13 INFO - 'base_work_dir': '/builds/slave/test', 16:19:13 INFO - 'blob_upload_branch': 'Fx-Team', 16:19:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:19:13 INFO - 'branch': 'Fx-Team', 16:19:13 INFO - 'buildbot_json_path': 'buildprops.json', 16:19:13 INFO - 'config_files': ('talos/linux_config.py',), 16:19:13 INFO - 'default_actions': ('clobber', 16:19:13 INFO - 'read-buildbot-config', 16:19:13 INFO - 'download-and-extract', 16:19:13 INFO - 'populate-webroot', 16:19:13 INFO - 'create-virtualenv', 16:19:13 INFO - 'install', 16:19:13 INFO - 'run-tests'), 16:19:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:19:13 INFO - 'download_minidump_stackwalk': True, 16:19:13 INFO - 'download_symbols': 'ondemand', 16:19:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:19:13 INFO - 'tooltool.py': '/tools/tooltool.py', 16:19:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:19:13 INFO - '/tools/misc-python/virtualenv.py')}, 16:19:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:19:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:19:13 INFO - 'installer_path': 'installer.exe', 16:19:13 INFO - 'log_level': 'info', 16:19:13 INFO - 'log_name': 'talos', 16:19:13 INFO - 'log_to_console': True, 16:19:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:19:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:19:13 INFO - 'opt_config_files': (), 16:19:13 INFO - 'pip_index': False, 16:19:13 INFO - 'sps_profile': False, 16:19:13 INFO - 'sps_profile_interval': 0, 16:19:13 INFO - 'suite': 'dromaeojs', 16:19:13 INFO - 'system_bits': '32', 16:19:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:19:13 INFO - 'title': 'talos-linux64-ix-032', 16:19:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:19:13 INFO - 'use_talos_json': True, 16:19:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:19:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:19:13 INFO - 'work_dir': 'build'} 16:19:13 INFO - [mozharness: 2016-08-29 23:19:13.989788Z] Running clobber step. 16:19:13 INFO - Running pre-action listener: _resource_record_pre_action 16:19:13 INFO - Running main action method: clobber 16:19:13 INFO - rmtree: /builds/slave/test/build 16:19:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:19:14 INFO - Running post-action listener: _resource_record_post_action 16:19:14 INFO - [mozharness: 2016-08-29 23:19:14.450437Z] Finished clobber step (success) 16:19:14 INFO - [mozharness: 2016-08-29 23:19:14.450541Z] Running read-buildbot-config step. 16:19:14 INFO - Running pre-action listener: _resource_record_pre_action 16:19:14 INFO - Running main action method: read_buildbot_config 16:19:14 INFO - Using buildbot properties: 16:19:14 INFO - { 16:19:14 INFO - "project": "", 16:19:14 INFO - "product": "firefox", 16:19:14 INFO - "script_repo_revision": "production", 16:19:14 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 16:19:14 INFO - "repository": "", 16:19:14 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 16:19:14 INFO - "buildid": "20160829143007", 16:19:14 INFO - "pgo_build": "True", 16:19:14 INFO - "basedir": "/builds/slave/test", 16:19:14 INFO - "buildnumber": 404, 16:19:14 INFO - "slavename": "talos-linux64-ix-032", 16:19:14 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:19:14 INFO - "platform": "ubuntu64_hw", 16:19:14 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 16:19:14 INFO - "branch": "fx-team", 16:19:14 INFO - "repo_path": "integration/fx-team", 16:19:14 INFO - "revision": "578a16bf30e47ceb2aba7c5fd53b7eaba9829610", 16:19:14 INFO - "stage_platform": "linux64-pgo", 16:19:14 INFO - "builduid": "0f0c1efa8727474a9030ef8adf4cf444", 16:19:14 INFO - "slavebuilddir": "test" 16:19:14 INFO - } 16:19:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:19:14 INFO - Running post-action listener: _resource_record_post_action 16:19:14 INFO - [mozharness: 2016-08-29 23:19:14.452993Z] Finished read-buildbot-config step (success) 16:19:14 INFO - [mozharness: 2016-08-29 23:19:14.453084Z] Running download-and-extract step. 16:19:14 INFO - Running pre-action listener: _resource_record_pre_action 16:19:14 INFO - Running main action method: download_and_extract 16:19:14 INFO - mkdir: /builds/slave/test/build/tests 16:19:14 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')]} 16:19:14 INFO - trying https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:19:14 INFO - Downloading https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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 16:19:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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 16:19:15 INFO - Downloaded 1361 bytes. 16:19:15 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:19:15 INFO - Using the following test package requirements: 16:19:15 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:19:15 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:19:15 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:19:15 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:15 INFO - u'jsshell-linux-x86_64.zip'], 16:19:15 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:19:15 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:19:15 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:19:15 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:19:15 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:19:15 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:19:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:19: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')]} 16:19:15 INFO - trying https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:19:15 INFO - Downloading https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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 16:19:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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 16:19:15 INFO - Downloaded 21876614 bytes. 16:19:15 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 16:19:20 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 16:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:20 INFO - trying https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:19:20 INFO - Downloading https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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 16:19:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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 16:19:20 INFO - Downloaded 21876614 bytes. 16:19:20 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 16:19:25 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')]} 16:19:25 INFO - trying https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:19:25 INFO - Downloading https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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 16:19:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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 16:19:26 INFO - Downloaded 14413998 bytes. 16:19:26 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 16:19:26 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')]} 16:19:26 INFO - trying https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:19:26 INFO - Downloading https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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 16:19:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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 16:19:27 INFO - Downloaded 59746643 bytes. 16:19:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:19:27 INFO - mkdir: /builds/slave/test/properties 16:19:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:19:27 INFO - Writing to file /builds/slave/test/properties/build_url 16:19:27 INFO - Contents: 16:19:27 INFO - build_url:https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:19:28 INFO - Running post-action listener: _resource_record_post_action 16:19:28 INFO - Running post-action listener: set_extra_try_arguments 16:19:28 INFO - [mozharness: 2016-08-29 23:19:28.495865Z] Finished download-and-extract step (success) 16:19:28 INFO - [mozharness: 2016-08-29 23:19:28.496092Z] Running populate-webroot step. 16:19:28 INFO - Running pre-action listener: _resource_record_pre_action 16:19:28 INFO - Running main action method: populate_webroot 16:19:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:19:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:19:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:19:28 INFO - '--tppagecycles', 16:19:28 INFO - '7'], 16:19:28 INFO - 'tests': ['tsvgm']}}, 16:19:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:19:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:19:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:19:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:19:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:28 INFO - 'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:28 INFO - 'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['damp', 'tps']}, 16:19:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:28 INFO - 'tests': ['damp', 'tps']}, 16:19:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['dromaeo_dom']}, 16:19:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:19:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['basic_compositor_video']}, 16:19:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:19:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['a11yr', 16:19:28 INFO - 'ts_paint', 16:19:28 INFO - 'tpaint', 16:19:28 INFO - 'sessionrestore', 16:19:28 INFO - 'sessionrestore_no_auto_restore', 16:19:28 INFO - 'tabpaint']}, 16:19:28 INFO - 'other-e10s': {'tests': ['a11yr', 16:19:28 INFO - 'ts_paint', 16:19:28 INFO - 'tpaint', 16:19:28 INFO - 'sessionrestore', 16:19:28 INFO - 'sessionrestore_no_auto_restore', 16:19:28 INFO - 'tabpaint']}, 16:19:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:19:28 INFO - 'ts_paint', 16:19:28 INFO - 'tpaint', 16:19:28 INFO - 'sessionrestore', 16:19:28 INFO - 'sessionrestore_no_auto_restore', 16:19:28 INFO - 'tabpaint']}, 16:19:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:19:28 INFO - 'ts_paint', 16:19:28 INFO - 'tpaint', 16:19:28 INFO - 'sessionrestore', 16:19:28 INFO - 'sessionrestore_no_auto_restore', 16:19:28 INFO - 'tabpaint']}, 16:19:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['a11yr', 16:19:28 INFO - 'ts_paint', 16:19:28 INFO - 'tpaint', 16:19:28 INFO - 'sessionrestore', 16:19:28 INFO - 'sessionrestore_no_auto_restore', 16:19:28 INFO - 'tabpaint']}, 16:19:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['a11yr', 16:19:28 INFO - 'ts_paint', 16:19:28 INFO - 'tpaint', 16:19:28 INFO - 'sessionrestore', 16:19:28 INFO - 'sessionrestore_no_auto_restore', 16:19:28 INFO - 'tabpaint']}, 16:19:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['tsvgx', 16:19:28 INFO - 'tsvgr_opacity', 16:19:28 INFO - 'tart', 16:19:28 INFO - 'tscrollx', 16:19:28 INFO - 'cart']}, 16:19:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:19:28 INFO - 'tsvgr_opacity', 16:19:28 INFO - 'tart', 16:19:28 INFO - 'tscrollx', 16:19:28 INFO - 'cart']}, 16:19:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:28 INFO - 'talos_options': ['--disable-e10s'], 16:19:28 INFO - 'tests': ['tp5o']}, 16:19:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:28 INFO - 'tests': ['tp5o']}, 16:19:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:28 INFO - 'talos_options': ['--disable-e10s', 16:19:28 INFO - '--xperf_path', 16:19:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:28 INFO - 'tests': ['tp5n']}, 16:19:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:28 INFO - 'talos_options': ['--xperf_path', 16:19:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:28 INFO - 'tests': ['tp5n']}}, 16:19:28 INFO - 'talos.zip': {'path': '', 16:19:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:19:28 INFO - Running post-action listener: _resource_record_post_action 16:19:28 INFO - [mozharness: 2016-08-29 23:19:28.517650Z] Finished populate-webroot step (success) 16:19:28 INFO - [mozharness: 2016-08-29 23:19:28.517739Z] Running create-virtualenv step. 16:19:28 INFO - Running pre-action listener: _resource_record_pre_action 16:19:28 INFO - Running main action method: create_virtualenv 16:19:28 INFO - Creating virtualenv /builds/slave/test/build/venv 16:19:28 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 16:19:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:19:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:19:28 INFO - Using real prefix '/usr' 16:19:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:19:29 INFO - Installing distribute.............................................................................................................................................................................................done. 16:19:31 INFO - Installing pip.................done. 16:19:31 INFO - Return code: 0 16:19:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f38c6c82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b358d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', '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 16:19:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:19:31 INFO - Using env: {'DISPLAY': ':0', 16:19:31 INFO - 'HOME': '/home/cltbld', 16:19:31 INFO - 'LANG': 'en_US.UTF-8', 16:19:31 INFO - 'LANGUAGE': 'en_US:en', 16:19:31 INFO - 'LOGNAME': 'cltbld', 16:19:31 INFO - 'MAIL': '/var/mail/cltbld', 16:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:31 INFO - 'MOZ_NO_REMOTE': '1', 16:19:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:31 INFO - 'NO_EM_RESTART': '1', 16:19:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:31 INFO - 'PWD': '/builds/slave/test', 16:19:31 INFO - 'SHELL': '/bin/bash', 16:19:31 INFO - 'SHLVL': '1', 16:19:31 INFO - 'TERM': 'linux', 16:19:31 INFO - 'TMOUT': '86400', 16:19:31 INFO - 'USER': 'cltbld', 16:19:31 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:31 INFO - '_': '/tools/buildbot/bin/python'} 16:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:31 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 16:19:31 INFO - Cleaning up... 16:19:31 INFO - Return code: 0 16:19:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f38c6c82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b358d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', '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 16:19:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:19:31 INFO - Using env: {'DISPLAY': ':0', 16:19:31 INFO - 'HOME': '/home/cltbld', 16:19:31 INFO - 'LANG': 'en_US.UTF-8', 16:19:31 INFO - 'LANGUAGE': 'en_US:en', 16:19:31 INFO - 'LOGNAME': 'cltbld', 16:19:31 INFO - 'MAIL': '/var/mail/cltbld', 16:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:31 INFO - 'MOZ_NO_REMOTE': '1', 16:19:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:31 INFO - 'NO_EM_RESTART': '1', 16:19:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:31 INFO - 'PWD': '/builds/slave/test', 16:19:31 INFO - 'SHELL': '/bin/bash', 16:19:31 INFO - 'SHLVL': '1', 16:19:31 INFO - 'TERM': 'linux', 16:19:31 INFO - 'TMOUT': '86400', 16:19:31 INFO - 'USER': 'cltbld', 16:19:31 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:31 INFO - '_': '/tools/buildbot/bin/python'} 16:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:31 INFO - Downloading/unpacking psutil>=3.1.1 16:19:31 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 16:19:31 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 16:19:31 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 16:19:31 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 16:19:31 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 16:19:31 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 16:19:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:19:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:33 INFO - Installing collected packages: psutil 16:19:33 INFO - Running setup.py install for psutil 16:19:33 INFO - building 'psutil._psutil_linux' extension 16:19:33 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 16:19:33 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 16:19:33 INFO - building 'psutil._psutil_posix' extension 16:19:33 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 16:19:33 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 16:19:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:34 INFO - Successfully installed psutil 16:19:34 INFO - Cleaning up... 16:19:34 INFO - Return code: 0 16:19:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f38c6c82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b358d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', '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 16:19:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:19:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:19:34 INFO - Using env: {'DISPLAY': ':0', 16:19:34 INFO - 'HOME': '/home/cltbld', 16:19:34 INFO - 'LANG': 'en_US.UTF-8', 16:19:34 INFO - 'LANGUAGE': 'en_US:en', 16:19:34 INFO - 'LOGNAME': 'cltbld', 16:19:34 INFO - 'MAIL': '/var/mail/cltbld', 16:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:34 INFO - 'MOZ_NO_REMOTE': '1', 16:19:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:34 INFO - 'NO_EM_RESTART': '1', 16:19:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:34 INFO - 'PWD': '/builds/slave/test', 16:19:34 INFO - 'SHELL': '/bin/bash', 16:19:34 INFO - 'SHLVL': '1', 16:19:34 INFO - 'TERM': 'linux', 16:19:34 INFO - 'TMOUT': '86400', 16:19:34 INFO - 'USER': 'cltbld', 16:19:34 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:34 INFO - '_': '/tools/buildbot/bin/python'} 16:19:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:19:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:19:36 INFO - Installing collected packages: mozsystemmonitor 16:19:36 INFO - Running setup.py install for mozsystemmonitor 16:19:36 INFO - Successfully installed mozsystemmonitor 16:19:36 INFO - Cleaning up... 16:19:36 INFO - Return code: 0 16:19:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:19:36 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')]} 16:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:36 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')]} 16:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:36 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 0x7f38c6c82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b358d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', '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 16:19:36 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 16:19:36 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 16:19:36 INFO - Using env: {'DISPLAY': ':0', 16:19:36 INFO - 'HOME': '/home/cltbld', 16:19:36 INFO - 'LANG': 'en_US.UTF-8', 16:19:36 INFO - 'LANGUAGE': 'en_US:en', 16:19:36 INFO - 'LOGNAME': 'cltbld', 16:19:36 INFO - 'MAIL': '/var/mail/cltbld', 16:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:36 INFO - 'MOZ_NO_REMOTE': '1', 16:19:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:36 INFO - 'NO_EM_RESTART': '1', 16:19:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:36 INFO - 'PWD': '/builds/slave/test', 16:19:36 INFO - 'SHELL': '/bin/bash', 16:19:36 INFO - 'SHLVL': '1', 16:19:36 INFO - 'TERM': 'linux', 16:19:36 INFO - 'TMOUT': '86400', 16:19:36 INFO - 'USER': 'cltbld', 16:19:36 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:36 INFO - '_': '/tools/buildbot/bin/python'} 16:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:36 INFO - Downloading/unpacking jsonschema==2.5.1 16:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:19:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:19:38 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 16:19:38 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 16:19:38 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 16:19:38 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 16:19:38 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 16:19:38 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 16:19:38 INFO - Downloading functools32-3.2.3-2.tar.gz 16:19:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:19:38 INFO - warning: no files found matching '*.txt' 16:19:38 INFO - no previously-included directories found matching 'build' 16:19:38 INFO - no previously-included directories found matching 'dist' 16:19:38 INFO - no previously-included directories found matching '.git*' 16:19:38 INFO - Installing collected packages: jsonschema, functools32 16:19:38 INFO - Running setup.py install for functools32 16:19:38 INFO - warning: no files found matching '*.txt' 16:19:38 INFO - no previously-included directories found matching 'build' 16:19:38 INFO - no previously-included directories found matching 'dist' 16:19:38 INFO - no previously-included directories found matching '.git*' 16:19:38 INFO - Successfully installed jsonschema functools32 16:19:38 INFO - Cleaning up... 16:19:38 INFO - Return code: 0 16:19:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:38 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 0x7f38c6c82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b358d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', '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 16:19:38 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 16:19:38 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 16:19:38 INFO - Using env: {'DISPLAY': ':0', 16:19:38 INFO - 'HOME': '/home/cltbld', 16:19:38 INFO - 'LANG': 'en_US.UTF-8', 16:19:38 INFO - 'LANGUAGE': 'en_US:en', 16:19:38 INFO - 'LOGNAME': 'cltbld', 16:19:38 INFO - 'MAIL': '/var/mail/cltbld', 16:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:38 INFO - 'MOZ_NO_REMOTE': '1', 16:19:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:38 INFO - 'NO_EM_RESTART': '1', 16:19:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:38 INFO - 'PWD': '/builds/slave/test', 16:19:38 INFO - 'SHELL': '/bin/bash', 16:19:38 INFO - 'SHLVL': '1', 16:19:38 INFO - 'TERM': 'linux', 16:19:38 INFO - 'TMOUT': '86400', 16:19:38 INFO - 'USER': 'cltbld', 16:19:38 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:38 INFO - '_': '/tools/buildbot/bin/python'} 16:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:19:39 INFO - Cleaning up... 16:19:39 INFO - Return code: 0 16:19:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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')]} 16:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f38c6c82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b358d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', '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 16:19:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:19:39 INFO - Using env: {'DISPLAY': ':0', 16:19:39 INFO - 'HOME': '/home/cltbld', 16:19:39 INFO - 'LANG': 'en_US.UTF-8', 16:19:39 INFO - 'LANGUAGE': 'en_US:en', 16:19:39 INFO - 'LOGNAME': 'cltbld', 16:19:39 INFO - 'MAIL': '/var/mail/cltbld', 16:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:39 INFO - 'MOZ_NO_REMOTE': '1', 16:19:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:39 INFO - 'NO_EM_RESTART': '1', 16:19:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:39 INFO - 'PWD': '/builds/slave/test', 16:19:39 INFO - 'SHELL': '/bin/bash', 16:19:39 INFO - 'SHLVL': '1', 16:19:39 INFO - 'TERM': 'linux', 16:19:39 INFO - 'TMOUT': '86400', 16:19:39 INFO - 'USER': 'cltbld', 16:19:39 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:39 INFO - '_': '/tools/buildbot/bin/python'} 16:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:39 INFO - Downloading/unpacking blobuploader==1.2.4 16:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:41 INFO - Downloading blobuploader-1.2.4.tar.gz 16:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:19:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:19:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:41 INFO - Downloading docopt-0.6.1.tar.gz 16:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:19:42 INFO - Installing collected packages: blobuploader, requests, docopt 16:19:42 INFO - Running setup.py install for blobuploader 16:19:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:19:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:19:42 INFO - Running setup.py install for requests 16:19:42 INFO - Running setup.py install for docopt 16:19:42 INFO - Successfully installed blobuploader requests docopt 16:19:42 INFO - Cleaning up... 16:19:42 INFO - Return code: 0 16:19:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:42 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 0x7f38c6c82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b358d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', '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 16:19:42 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 16:19:42 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 16:19:42 INFO - Using env: {'DISPLAY': ':0', 16:19:42 INFO - 'HOME': '/home/cltbld', 16:19:42 INFO - 'LANG': 'en_US.UTF-8', 16:19:42 INFO - 'LANGUAGE': 'en_US:en', 16:19:42 INFO - 'LOGNAME': 'cltbld', 16:19:42 INFO - 'MAIL': '/var/mail/cltbld', 16:19:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:42 INFO - 'MOZ_NO_REMOTE': '1', 16:19:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:42 INFO - 'NO_EM_RESTART': '1', 16:19:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:42 INFO - 'PWD': '/builds/slave/test', 16:19:42 INFO - 'SHELL': '/bin/bash', 16:19:42 INFO - 'SHLVL': '1', 16:19:42 INFO - 'TERM': 'linux', 16:19:42 INFO - 'TMOUT': '86400', 16:19:42 INFO - 'USER': 'cltbld', 16:19:42 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:19:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:42 INFO - '_': '/tools/buildbot/bin/python'} 16:19:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:42 INFO - Running setup.py (path:/tmp/pip-B2pK0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:42 INFO - Running setup.py (path:/tmp/pip-OXOF7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:43 INFO - Running setup.py (path:/tmp/pip-ZabWiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:43 INFO - Running setup.py (path:/tmp/pip-94B4nD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:43 INFO - Running setup.py (path:/tmp/pip-nxK7TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:43 INFO - Running setup.py (path:/tmp/pip-uNPNQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:43 INFO - Running setup.py (path:/tmp/pip-JFCCxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:43 INFO - Running setup.py (path:/tmp/pip-HuzeWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:43 INFO - Running setup.py (path:/tmp/pip-GT1p5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:43 INFO - Running setup.py (path:/tmp/pip-GOe965-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:43 INFO - Running setup.py (path:/tmp/pip-A0sRFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:43 INFO - Running setup.py (path:/tmp/pip-OpBMOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:43 INFO - Running setup.py (path:/tmp/pip-YgOYex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:43 INFO - Running setup.py (path:/tmp/pip-DrYayr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:43 INFO - Running setup.py (path:/tmp/pip-qQixLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:44 INFO - Running setup.py (path:/tmp/pip-h3Hs7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:44 INFO - Running setup.py (path:/tmp/pip-BRofam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:19:44 INFO - Running setup.py install for manifestparser 16:19:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:19:44 INFO - Running setup.py install for mozcrash 16:19:44 INFO - Running setup.py install for mozdebug 16:19:44 INFO - Running setup.py install for mozdevice 16:19:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:19:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:19:44 INFO - Running setup.py install for mozfile 16:19:44 INFO - Running setup.py install for mozhttpd 16:19:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:19:44 INFO - Running setup.py install for mozinfo 16:19:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:19:44 INFO - Running setup.py install for mozInstall 16:19:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:19:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:19:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:19:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:19:44 INFO - Running setup.py install for mozleak 16:19:45 INFO - Running setup.py install for mozlog 16:19:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:19:45 INFO - Running setup.py install for moznetwork 16:19:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:19:45 INFO - Running setup.py install for mozprocess 16:19:45 INFO - Running setup.py install for mozprofile 16:19:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:19:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:19:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:19:45 INFO - Running setup.py install for mozrunner 16:19:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:19:45 INFO - Running setup.py install for mozscreenshot 16:19:45 INFO - Running setup.py install for moztest 16:19:45 INFO - Running setup.py install for mozversion 16:19:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:19:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:19:45 INFO - Cleaning up... 16:19:45 INFO - Return code: 0 16:19:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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')]} 16:19:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:45 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 0x7f38c6c82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b358d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', '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 16:19:45 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 16:19:45 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 16:19:45 INFO - Using env: {'DISPLAY': ':0', 16:19:45 INFO - 'HOME': '/home/cltbld', 16:19:45 INFO - 'LANG': 'en_US.UTF-8', 16:19:45 INFO - 'LANGUAGE': 'en_US:en', 16:19:45 INFO - 'LOGNAME': 'cltbld', 16:19:45 INFO - 'MAIL': '/var/mail/cltbld', 16:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:45 INFO - 'MOZ_NO_REMOTE': '1', 16:19:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:45 INFO - 'NO_EM_RESTART': '1', 16:19:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:45 INFO - 'PWD': '/builds/slave/test', 16:19:45 INFO - 'SHELL': '/bin/bash', 16:19:45 INFO - 'SHLVL': '1', 16:19:45 INFO - 'TERM': 'linux', 16:19:45 INFO - 'TMOUT': '86400', 16:19:45 INFO - 'USER': 'cltbld', 16:19:45 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:45 INFO - '_': '/tools/buildbot/bin/python'} 16:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:46 INFO - Running setup.py (path:/tmp/pip-r_Cqh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:46 INFO - Running setup.py (path:/tmp/pip-7aCGLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:46 INFO - Running setup.py (path:/tmp/pip-aVvkQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:46 INFO - Running setup.py (path:/tmp/pip-G7suIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:46 INFO - Running setup.py (path:/tmp/pip-zGbJ0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:46 INFO - Running setup.py (path:/tmp/pip-W71KOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:46 INFO - Running setup.py (path:/tmp/pip-FDfIwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:46 INFO - Running setup.py (path:/tmp/pip-ximC2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:46 INFO - Running setup.py (path:/tmp/pip-uTmTFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:46 INFO - Running setup.py (path:/tmp/pip-KjEuug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:46 INFO - Running setup.py (path:/tmp/pip-G4v5jN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:46 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)) 16:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:46 INFO - Running setup.py (path:/tmp/pip-aoOX3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:47 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)) 16:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:47 INFO - Running setup.py (path:/tmp/pip-qlHngN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:47 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)) 16:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:47 INFO - Running setup.py (path:/tmp/pip-mFhxrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:47 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)) 16:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:47 INFO - Running setup.py (path:/tmp/pip-rQD5U_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:47 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)) 16:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:47 INFO - Running setup.py (path:/tmp/pip-VIoAAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:47 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)) 16:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:47 INFO - Running setup.py (path:/tmp/pip-21k2p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:47 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)) 16:19:47 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)) 16:19:47 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)) 16:19:47 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)) 16:19:47 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)) 16:19:47 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)) 16:19:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:49 INFO - Downloading blessings-1.6.tar.gz 16:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:19:49 INFO - Installing collected packages: blessings 16:19:49 INFO - Running setup.py install for blessings 16:19:49 INFO - Successfully installed blessings 16:19:49 INFO - Cleaning up... 16:19:49 INFO - Return code: 0 16:19:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:19:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:49 INFO - Reading from file tmpfile_stdout 16:19:49 INFO - Current package versions: 16:19:49 INFO - argparse == 1.2.1 16:19:49 INFO - blessings == 1.6 16:19:49 INFO - blobuploader == 1.2.4 16:19:49 INFO - docopt == 0.6.1 16:19:49 INFO - functools32 == 3.2.3-2 16:19:49 INFO - jsonschema == 2.5.1 16:19:49 INFO - manifestparser == 1.1 16:19:49 INFO - mozInstall == 1.12 16:19:49 INFO - mozcrash == 0.17 16:19:49 INFO - mozdebug == 0.1 16:19:49 INFO - mozdevice == 0.48 16:19:49 INFO - mozfile == 1.2 16:19:49 INFO - mozhttpd == 0.7 16:19:49 INFO - mozinfo == 0.9 16:19:49 INFO - mozleak == 0.1 16:19:49 INFO - mozlog == 3.3 16:19:49 INFO - moznetwork == 0.27 16:19:49 INFO - mozprocess == 0.23 16:19:49 INFO - mozprofile == 0.28 16:19:49 INFO - mozrunner == 6.12 16:19:49 INFO - mozscreenshot == 0.1 16:19:49 INFO - mozsystemmonitor == 0.3 16:19:49 INFO - moztest == 0.7 16:19:49 INFO - mozversion == 1.4 16:19:49 INFO - psutil == 3.1.1 16:19:49 INFO - requests == 1.2.3 16:19:49 INFO - wsgiref == 0.1.2 16:19:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:49 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 0x7f38c6c82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b358d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', '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 16:19:49 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 16:19:49 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 16:19:49 INFO - Using env: {'DISPLAY': ':0', 16:19:49 INFO - 'HOME': '/home/cltbld', 16:19:49 INFO - 'LANG': 'en_US.UTF-8', 16:19:49 INFO - 'LANGUAGE': 'en_US:en', 16:19:49 INFO - 'LOGNAME': 'cltbld', 16:19:49 INFO - 'MAIL': '/var/mail/cltbld', 16:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:49 INFO - 'MOZ_NO_REMOTE': '1', 16:19:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:49 INFO - 'NO_EM_RESTART': '1', 16:19:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:49 INFO - 'PWD': '/builds/slave/test', 16:19:49 INFO - 'SHELL': '/bin/bash', 16:19:49 INFO - 'SHLVL': '1', 16:19:49 INFO - 'TERM': 'linux', 16:19:49 INFO - 'TMOUT': '86400', 16:19:49 INFO - 'USER': 'cltbld', 16:19:49 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:49 INFO - '_': '/tools/buildbot/bin/python'} 16:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:49 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)) 16:19:49 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)) 16:19:49 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)) 16:19:49 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)) 16:19:49 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)) 16:19:49 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)) 16:19:49 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)) 16:19:49 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)) 16:19:49 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)) 16:19:49 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)) 16:19:49 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)) 16:19:49 INFO - Cleaning up... 16:19:49 INFO - Return code: 0 16:19:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f38c6c82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b358d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', '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 16:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:19:49 INFO - Using env: {'DISPLAY': ':0', 16:19:49 INFO - 'HOME': '/home/cltbld', 16:19:49 INFO - 'LANG': 'en_US.UTF-8', 16:19:49 INFO - 'LANGUAGE': 'en_US:en', 16:19:49 INFO - 'LOGNAME': 'cltbld', 16:19:49 INFO - 'MAIL': '/var/mail/cltbld', 16:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:49 INFO - 'MOZ_NO_REMOTE': '1', 16:19:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:49 INFO - 'NO_EM_RESTART': '1', 16:19:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:49 INFO - 'PWD': '/builds/slave/test', 16:19:49 INFO - 'SHELL': '/bin/bash', 16:19:49 INFO - 'SHLVL': '1', 16:19:49 INFO - 'TERM': 'linux', 16:19:49 INFO - 'TMOUT': '86400', 16:19:49 INFO - 'USER': 'cltbld', 16:19:49 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:49 INFO - '_': '/tools/buildbot/bin/python'} 16:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:19:50 INFO - Cleaning up... 16:19:50 INFO - Return code: 0 16:19:50 INFO - Running post-action listener: _resource_record_post_action 16:19:50 INFO - Running post-action listener: _start_resource_monitoring 16:19:50 INFO - Starting resource monitoring. 16:19:50 INFO - [mozharness: 2016-08-29 23:19:50.245542Z] Finished create-virtualenv step (success) 16:19:50 INFO - [mozharness: 2016-08-29 23:19:50.246004Z] Running install step. 16:19:50 INFO - Running pre-action listener: _resource_record_pre_action 16:19:50 INFO - Running main action method: install 16:19:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:50 INFO - Reading from file tmpfile_stdout 16:19:50 INFO - Detecting whether we're running mozinstall >=1.0... 16:19:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:19:50 INFO - Reading from file tmpfile_stdout 16:19:50 INFO - Output received: 16:19:50 INFO - Usage: mozinstall [options] installer 16:19:50 INFO - Options: 16:19:50 INFO - -h, --help show this help message and exit 16:19:50 INFO - -d DEST, --destination=DEST 16:19:50 INFO - Directory to install application into. [default: 16:19:50 INFO - "/builds/slave/test"] 16:19:50 INFO - --app=APP Application being installed. [default: firefox] 16:19:50 INFO - mkdir: /builds/slave/test/build/application 16:19:50 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'] 16:19:50 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 16:20:07 INFO - Reading from file tmpfile_stdout 16:20:07 INFO - Output received: 16:20:07 INFO - /builds/slave/test/build/application/firefox/firefox 16:20:07 INFO - Running post-action listener: _resource_record_post_action 16:20:07 INFO - [mozharness: 2016-08-29 23:20:07.570520Z] Finished install step (success) 16:20:07 INFO - [mozharness: 2016-08-29 23:20:07.570867Z] Running run-tests step. 16:20:07 INFO - Running pre-action listener: _resource_record_pre_action 16:20:07 INFO - Running main action method: run_tests 16:20:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:20:07 INFO - Python 2.7.3 16:20:07 INFO - Return code: 0 16:20:07 INFO - grabbing minidump binary from tooltool 16:20:07 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')]} 16:20:07 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 0x1bc4760>, '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 0x1bc5660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bc5af0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:20:07 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 16:20:07 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 16:20:07 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 16:20:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:20:07 INFO - Return code: 0 16:20:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:20:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:20:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:20:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:20:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:20:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:20:07 INFO - Using env: {'DISPLAY': ':0', 16:20:07 INFO - 'HOME': '/home/cltbld', 16:20:07 INFO - 'LANG': 'en_US.UTF-8', 16:20:07 INFO - 'LANGUAGE': 'en_US:en', 16:20:07 INFO - 'LOGNAME': 'cltbld', 16:20:07 INFO - 'MAIL': '/var/mail/cltbld', 16:20:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:20:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:07 INFO - 'MOZ_NO_REMOTE': '1', 16:20:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:20:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:07 INFO - 'NO_EM_RESTART': '1', 16:20:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:07 INFO - 'PWD': '/builds/slave/test', 16:20:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:20:07 INFO - 'SHELL': '/bin/bash', 16:20:07 INFO - 'SHLVL': '1', 16:20:07 INFO - 'TERM': 'linux', 16:20:07 INFO - 'TMOUT': '86400', 16:20:07 INFO - 'USER': 'cltbld', 16:20:07 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472511271.894139-1171538664', 16:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:07 INFO - '_': '/tools/buildbot/bin/python'} 16:20:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:20:07 INFO - mozversion application_buildid: 20160829143007 16:20:07 INFO - mozversion application_changeset: 578a16bf30e47ceb2aba7c5fd53b7eaba9829610 16:20:07 INFO - mozversion application_display_name: Nightly 16:20:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:20:07 INFO - mozversion application_name: Firefox 16:20:07 INFO - mozversion application_remotingname: firefox 16:20:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:20:07 INFO - mozversion application_vendor: Mozilla 16:20:07 INFO - mozversion application_version: 51.0a1 16:20:07 INFO - mozversion platform_buildid: 20160829143007 16:20:07 INFO - mozversion platform_changeset: 578a16bf30e47ceb2aba7c5fd53b7eaba9829610 16:20:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:20:07 INFO - mozversion platform_version: 51.0a1 16:20:07 INFO - using testdate: 1472512807 16:20:07 INFO - actual date: 1472512807 16:20:07 INFO - starting webserver on 'localhost:33258' 16:20:07 INFO - SUITE-START | Running 2 tests 16:20:07 INFO - TEST-START | dromaeo_css 16:20:07 INFO - operating with platform_type : linux_ 16:20:07 INFO - Initialising browser for dromaeo_css test... 16:20:07 INFO - TEST-INFO | started process 19865 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZwcfz7/profile http://localhost:33258/getInfo.html) 16:20:12 INFO - PROCESS | 19865 | __metrics Screen width/height:1600/1200 16:20:12 INFO - PROCESS | 19865 | colorDepth:24 16:20:12 INFO - PROCESS | 19865 | Browser inner width/height: 1024/697 16:20:12 INFO - PROCESS | 19865 | __metrics 16:20:13 INFO - PROCESS | 19865 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:20:13 INFO - PROCESS | 19865 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:20:13 INFO - PROCESS | 19865 | console.error: PushService: 16:20:13 INFO - PROCESS | 19865 | stateChangeProcessEnqueue: Error transitioning state 16:20:13 INFO - PROCESS | 19865 | UnknownError 16:20:14 INFO - TEST-INFO | 19865: exit 0 16:20:14 INFO - Browser initialized. 16:20:14 INFO - Running cycle 1/1 for dromaeo_css test... 16:20:14 INFO - TEST-INFO | started process 19948 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZwcfz7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:26 INFO - PROCESS | 19948 | RSS: Main: 179953664 16:20:26 INFO - PROCESS | 19948 | 16:21:18 INFO - PROCESS | 19948 | 1472512878163 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] 16:23:28 INFO - PROCESS | 19948 | Cycle 1(1): loaded http://localhost:33258/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33258/tests/dromaeo/cssquery-ext.html) 16:23:29 INFO - PROCESS | 19948 | RSS: Main: 313110528 16:23:29 INFO - PROCESS | 19948 | 16:26:32 INFO - PROCESS | 19948 | Cycle 1(1): loaded http://localhost:33258/tests/dromaeo/cssquery-ext.html (next: http://localhost:33258/tests/dromaeo/cssquery-jquery.html) 16:26:32 INFO - PROCESS | 19948 | RSS: Main: 272416768 16:26:32 INFO - PROCESS | 19948 | 16:29:34 INFO - PROCESS | 19948 | Cycle 1(1): loaded http://localhost:33258/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33258/tests/dromaeo/cssquery-mootools.html) 16:29:35 INFO - PROCESS | 19948 | RSS: Main: 274952192 16:29:35 INFO - PROCESS | 19948 | 16:32:37 INFO - PROCESS | 19948 | Cycle 1(1): loaded http://localhost:33258/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33258/tests/dromaeo/cssquery-prototype.html) 16:32:37 INFO - PROCESS | 19948 | RSS: Main: 215511040 16:32:37 INFO - PROCESS | 19948 | 16:35:40 INFO - PROCESS | 19948 | Cycle 1(1): loaded http://localhost:33258/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33258/tests/dromaeo/cssquery-yui.html) 16:35:41 INFO - PROCESS | 19948 | RSS: Main: 217796608 16:35:41 INFO - PROCESS | 19948 | 16:38:43 INFO - PROCESS | 19948 | Cycle 1(1): loaded http://localhost:33258/tests/dromaeo/cssquery-yui.html (next: http://localhost:33258/tests/dromaeo/cssquery-dojo.html) 16:38:44 INFO - PROCESS | 19948 | RSS: Main: 309997568 16:38:44 INFO - PROCESS | 19948 | 16:38:44 INFO - PROCESS | 19948 | __start_tp_report 16:38:44 INFO - PROCESS | 19948 | _x_x_mozilla_page_load 16:38:44 INFO - PROCESS | 19948 | _x_x_mozilla_page_load_details 16:38:44 INFO - PROCESS | 19948 | |i|pagename|runs| 16:38:44 INFO - PROCESS | 19948 | |0;dojo.html;3100.1784527100317;3102.348506813569;3124.750019998402;3156.9210769730767;3185.888493902714;5388.353397592295;5420.864478388043;5441;5485.725713714313;5532.861678458041;5423.918641220384;5493.917591236134;5503.422140675221;5522.66863988161;5565.193047008182;4971.428285747131;4998.425181104174;5017.347744793172;5033.496650334955;5065.949340506603;5025.195968645017;5066.695998240099;5139.177731562951;5143.974280128613;5147.356580427447;5068.771905264253;5091.185410334348;5091.821786237482;5098.719570423644;5135.332406787131;4960.677555958851;4976.975115124437;4985.052839960396;4993.600511959071;5104.438511763721;5014.247862820606;5014.84955451337;5028.145215313369;5102.234664800272;5103.770330335144;5466.781328746885;5558.527525160366;5568.1091025435735;5569.470900264469;5569.832905012856;5751.108578170349;5779.133130030487;5926.377730338339;5927.2590926134235;5931.110333449974;5923.17075609415;5932.851678708023;5934.1098835174635;5943;5946.464818166386;5445.727713614304;5447.673139611636;5469.288992430958;5509.00837849187;5532;6059.909101363483;6067.575269731165;6069.302030266487;6069.757209711562;6073.210482637229;5511.311086114236;5706.029974904276;5732.856678582985;5735.684537350402;5801.796937107265;5787.768489260382;5836.328822185418;5837.824865254049;5838.737256823453;5894.852628684232;5816.47651711348;5819.79630712923;5822.039363504974;5825.38833422493;5829.446202610745;5637.295338082739;5760.683162426107;5761.596688231783;5835.183074369592;5835.503982161564;4493.0339976905125;4504.662150338738;4509.143262780061;4509.481409637868;4510.120526497301;4049.1699201663587;4056.7363121397016;4060.3300455425115;4060.7766572838777;4061.1268577256033;5496.395396506381;5511.641743286674;5527.281453411031;5579.13523403873;5658.094704847287;5941.257342832146;5954.761809527657;5980.401959803985;6012.368701286302;6017.398260174035;5535.363433205152;5583.106702927512;5585.525230355384;5596.86007849807;5599.132134519156;4956.628252881048;5063.240513922868;5064.088464076428;5070.442251352349;5124.871878203;5957.880842383128;5987.700614969322;6007.249093863267;6015.699215039318;6093.69531523422;5845.912311315248;5849.502792262705;5855.092460668603;5861.560382971379;5864.091065884794;5821.185034095145;5990.430909063632;5998.370171131968;6016.518678505621;6017.488513476228;5649.745761440663;5698.715064246854;5718.51392631631;5747.712614369349;5818.970905145447;5843.357230704704;5851.736671849862;5852.7658893643775;5853.443922827325;5855.180274761622;5616.775328986795;5685.943140568706;5713.8285851424525;5720.656760594377;5720.799772007959;5685.289338832646;5745.482906538432;5763.94236057634;5773.393793651774;5841;5719.28508936383;5755.913661295001;5763.625364351304;5766.077427611562;5768.596198266081;5659.660420374791;5795.188673585617;5849.883002340014;5849.970750146222;5862.618929769722;5585.413531579125;5642.6614403135945;5658.632188907708;5667.2349232854685;5719.084946408555 16:38:44 INFO - PROCESS | 19948 | |1;ext.html;552.8940236912116;591.3229352391512;603.3363300369595;611.6604635847497;618.165476606581;32784.344313113754;41898.58101418985;42807.143857122835;42865.35701964473;46629.76685116578;45634;46055.53944460554;46061.769691151494;46102.30846537305;46106.538934610646;8093.190680931889;8745.825083498343;8935.776605584848;8944.373893827402;8946.507942063185;10439.321444106108;10777.407242601654;10780.622678206264;11193.944030279838;11202.607908723196;11027.503762330714;11115.777684446306;11123.110151187886;11127.554897804079;11130.554777808878;10850.945745271263;10920.12638988879;10949.671509854637;10950.780984380308;10960;10658.733531661694;10742.731431714192;10745.032947034726;10749.247552671317;10750.301230419998;43156.27374905;43476.130477390594;43608.3458748119;43610.781946090065;43643.78178109121;3467.289205712823;3507.2459421224303;3544.9822750886337;3588.6052534221217;3590.964090359089;32611;32994.34011319784;33088.6691133088;33410;34158.923117680344;4125.628693417607;4292.678049146295;4293.699441039129;4306.6124048835445;4419.734815911055;354226;355278;355560;356063;356104.2194789035;59357.70321148453;59620.70189649024;59777.70111149416;59927;60040.69979650074;36983.815080924425;37581.62418375836;37785.00977319205;39909.80045099814;39917.2016559673;15945.920270398574;16082.276297566637;16100.516984490485;16102.75845862313;16104.114273715059;10569.735756605993;11882.346470944181;11948.402579870972;12011.939940300243;12116.878831211576;19000.52498687544;19180.808191917902;19306;19340.613187736166;19448.319308824124;6905.75829845953;7056.223815380304;7068.92931070693;7125.3230943060325;7313.122425308997;915.9587818548048;1009.369144284822;1022.2844009193445;1038.7922415516775;1143.2111842828422;32974.50538241928;33020.31535020846;33238;33395.83302083474;33492.330153397284;1085.7014321061772;1087.6519513755522;1091.2579445976812;1111.7387413957713;1126.6282126898104;16800.57998550046;17324.740128898076;17326.566835829206;17382.56543586395;17452.127393630522;542974;544579;547378;548165;548171;58891.705541472016;59589;59622.10566841501;59692.70153649291;59760.70119649374;1193.773183095209;1286.7233544787916;1318.3276528970293;1318.756030134428;1332.733453309342;29571.85214073916;33559.496607551395;34015.65984340125;34220.31559368849;34227.4865877007;50274.49725502698;50674.239886400996;50706.23940640967;50792.746036269586;50860.23709644284;383877;384551;385050;385159;385806;28676.42647147087;29325.26686832761;29417.11748647544;29739.405211896075;30486.542701859045;48297.517024830704;48480;48680.756596216794;48833.755831222035;49125.754371227915;40417;40935.795321024394;41395.793021034704;41921.58078419177;42915.356269655356;40434.33919251248;40523.79738101408;40557.1888562233;41161.79419102885;42404.36393454162;40189;41107.58892411038;41348.793256033525;41679.58320416757;41913.1617367657;34919.476207856394;37264;37452.812735936146;37986.62013379831;38490.23019539649;27873.303167420978;28190.577141342485;28602.856985714938;28618;28736.137915862557 16:38:44 INFO - PROCESS | 19948 | |2;jquery.html;376.48421662322625;402.40645048553387;404.1573319628574;404.1734652635361;421.84180932150446;15924.124173170485;19283.22867085315;19413.805861941375;19668.803311966876;20640.896795516;19406.805931940675;19710.70433943492;19910.701339479918;20117.19531218753;20225.089870955806;5154.381474223099;5616.101423772207;5748.770049198027;5792.189093526909;5825.533957283428;5719.513841323493;5790.189373487715;5796.391378905197;5797.01450753373;5799.971000145015;5554.389017208104;5720.771169153229;5731.512821410164;5738.368779434259;5760.510356619692;5665.8583535411535;5697.316322041361;5739.885202295931;5742.6841523716175;5746.7413966371405;5023.6232282578585;5118.053160165381;5147.613928955306;5152.149895267276;5152.381714194321;23624.76375236243;23798.881005595034;24133.155339563207;24215.636765448533;24289.635655465183;2397.137030668958;2410.3612542676206;2444.327809852287;2476.182859656309;2878.870450829718;13995.86004139966;14608.926955365261;14964.251787410694;15161.772573411408;15231.61920951974;1790.8477779388768;1852.1850385830191;1859.6280743851148;1921.5772530043369;1953.3163392812544;78480.21519784843;79738.20261797366;79832.60083699544;80697.59651201706;80812.59593702052;10641.787164256672;10688.251822372515;10831.404272765074;11025.724356890982;11323.320600763816;15477.303521341566;15484.845151548341;15491.84508154904;15493.225338733018;15514.612134696723;7749.690012399441;8028.277455029075;8066.475679080841;8346.540940248224;8350.33197344211;7589.924100758922;7662.1571627120975;7670.578118203441;7856.607169641495;7928.405369597303;6502.479801615859;6598.802035938929;6618.569792963442;6648.83377915556;6760.7295708172105;1823.7811462624304;1848.4269876338378;1879.586490971984;1884.8963307018246;1965.0764140853776;736.3740820302806;982.29274922056;987.5062468765617;992.533509250651;1113.563700792869;21455.57088858214;22086.779132208794;22092.33722988313;22175;22399.664005039936;943.5188054092463;965.8647789309503;972;983.6458874805064;1051.4217180550665;11369.886301137049;12208.694782630506;12214.633560993185;12354.876451235552;12418.379081045912;485148;496138;496203;498636;500413;87829.12170878338;88828.5558572216;89275;91563;92540;1024.8257796174669;1030.2891005206384;1033.3541536539512;1044.7910417916446;1068.5191663751343;18379.632407352045;18412.907935460236;18917.43247702571;19092.427227183205;19343.226270949006;40637.59362406338;42176.57823421727;42511.1497770049;42655;43974.560254398326;56307.436925630216;56367;57497.42502574921;59320;59588.404115958285;8684.609192586222;8907.55462226899;8995.505247211204;9008.144226298491;9325.487098209664;13503.257320847448;13629.795553066513;14012.719745604827;14343.856561434251;14560.271986400849;11823.940880295544;11950.940245298718;12343.197692150341;12526.749465010831;12557;10958;11579.363135027654;11819.818018198112;12349.691257718629;12393.442295096922;11983.820242696193;11998.76002479963;12099.274043557414;12135.878641213474;12279.815802762787;8436.493810371398;8497.192766687154;8549.786255343666;8559.57202139878;8850.778730531789;10854.511546980562;11605.593804216813;11673.416329183678;12059.397030148633;12369.567065152676 16:38:44 INFO - PROCESS | 19948 | |3;mootools.html;1439.1365180891466;1475.9114453132809;1602.2149146918007;1735.314550752452;1763.708988016978;7938.404619653535;8460.450070745397;8731.388802783807;9199.356045076847;9252.629894804208;8707.390482666216;9071.274298056156;9079.31905107118;9084.50035248061;9086.318526110535;2401.0875867170453;2403.471236328005;2409.409694624819;2417.867017314047;2428.0287884846025;2093.5394213273057;2387.3673476528734;2397.5924092904247;2401.903923843044;2456.2262887190477;2483.3543278747475;2512.6733524641772;2518.8614626195554;2519.508695804319;2521.281434791085;2367.715874095102;2443.242594795619;2443.7434069422634;2453.092355828337;2459.1638842793445;2388.940276493084;2403.6875206223253;2457.778799907999;2466.4080620651093;2467.4078221226955;9076.77308067297;9219.400738952014;9236.168744812932;9240.445573265559;9326.207272381856;5337.199420086979;5492.780288788444;5502.532284755801;5504.724763761836;5507.807226747044;3357.244619960489;3462.740294477899;3561.94657080144;3566.946495802565;3578.8926332210076;1189.1675826921103;1233.2477188914754;1240.2248594628356;1258.874112588743;1273.2805964629954;12702;12746.235225886476;12758.61724148277;12767.42546585406;12803.167794093353;4737.928931066037;4752.239641657318;4813.700300918732;4856.708597484162;4931.260310953386;10650.467476626138;10673.519691613896;10677.14582833387;10677.9466102669;10695.090917272119;5405.702686352289;5423.213634023124;5431.266778984866;5433.022056029874;5438.91841622376;4852.344933434012;4874.926876096862;4958.479359667183;4964.652474326835;4965;3996.260691771973;3996.2806694794635;4082.408050832613;4093.5497095319492;4125.1749650070105;1135.0465608888574;1224.7856625090574;1235.0675240193775;1242.372601836067;1253.8871501564904;868.6959564152622;952.8142012307534;954.7708549947962;980.5678333930736;988.5798535622332;6028.608140470856;6241.7191226394925;6262.060690896402;6290.308066112724;6400.839979000562;942.1002942190192;949.515746969037;997.2321312589411;1010.161565900301;1014.9188064954786;4120.567340429272;4238.7032907696785;4299.441072660534;4300.118475712471;4379.671524635665;10962.123030157569;11400.885991140149;11449.198556100993;11450.083993280517;11455.770884582262;11120.38837863909;11243.887561124284;11346.773064538664;11350.886491135148;11424.828627570592;8327.750167494985;8400.621972011275;8410.495370277802;8415.032271288757;8418.326533877274;6159.630422174684;6204.81385558434;6230.470410015199;6236.251649802053;6304.275008373912;10560.260781745512;10603.257771955889;10617.893821062;10633.149348052291;10642.201834862262;10395.792084158124;10408.843867342148;10611.257211995086;10635.787284254116;10681.732956675834;7387.593682347522;7548.8490230196185;7575.431842611716;7583.203763604963;7583.445393694169;8410.957945210235;8430.789230269293;8500.53247071417;8502.36232282569;8541.658333666584;6303.747850085945;7230.13238411373;7282.344588986804;7287.854242915006;7675.462717609714;7201.3158749918675;7256.637168141678;7351.41188704913;7568.470207085451;7578.204288549843;6789.15135608049;6826.249112597511;6879.380855723009;6895.931040689529;6926.099607051051;4787.640926930424;4849.2726091086615;4925.655204135676;4935.62982776286;5058.848234552969;5096.949030509796;5097.057044446789;5194.532492075731;5274.841754747364;5362.812301569583 16:38:44 INFO - PROCESS | 19948 | |4;prototype.html;329.76916158688925;338.80518701746496;340.0749960108504;340.627013420305;342.68130638506193;5365.168398898172;5384.542313903313;5398.13629819229;5405.756740946657;5480.342358916925;5399.217113518537;5416.539594134493;5473.28847249857;5483.287172667558;5484.698341591211;4165.500139983206;4249.681273904454;4259.616634502894;4285.378620100084;4287.820849266461;4229.302165142748;4253.957460425387;4273.316269396897;4290.742555446684;4292.59220374064;4186.451013125148;4195.244855925932;4204.5585213552595;4207.200631879949;4214.115035842477;4030.3148464760916;4050.10897602527;4183.6234738873645;4203.432536607549;4212.199682060399;3725.925481490355;3732.2908647356917;3745.887623371303;3750.324941510527;3770.8883025505806;5238.947610523884;5250.159974404115;5251.947480525184;5259.447757985433;5275.129603615399;4347.260965635849;4395.450568678915;4768.332433459318;4789.49710280419;4828.830990915301;5556.944430555724;5563.496426608042;5573.4394369576585;5581.832545023656;5605.710686542118;3998.5601583825755;4001.239764444746;4014.959850401488;4025.496812898388;4026.416169655384;5720.856978575527;5739.139129130622;5757.539396848242;5770.163326317703;5778.301886792457;4048.8785336439955;4060.959390406058;4068.9186216275507;4085.610892296633;4116.0121570822785;4138.02756352257;4143.461350024538;4149.688773342012;4156.56356082613;4167.166566686675;4515.932261016087;4728.4562275338085;4755.904881902412;4757.26262429324;4785.564330700846;5637.23897273871;5637.689927054052;5643.774249030075;5646.717664116778;5666.2350582670815;1410.4358256697403;1424.9002569820013;1429.4353730276482;1433.6989232261133;1436.3392839294042;921.9861702074475;935.9812803743887;939.6617217801722;977.2426369563503;1023.7798873242289;5407.432219616962;5440.619156659075;5461.672299662033;5469.535089517356;5472.808451704251;5711.571632127607;5741.770329186869;5751.482366587027;5775.364709881909;5783.479486846204;5227.634065615371;5373.892522149535;5390.8921821563345;5424.674519528841;5473.67157970523;2926.297688554774;3029.0004298581416;3071.447139514909;3085.41377138343;3102.4105419970133;5956.136360227742;5958.463738263577;5968.4926781224085;5975.43233392827;5979.431953964367;5571.93838279839;5882.617629854046;5910.290765108129;5919.892056670283;5935.643861368331;5969.746353265863;5977.402259774075;5990.520758339322;6011.458968692838;6013.398660133952;5572.721363931706;5812.21535092774;5813.680247586254;5838.386969368155;5842.824715258549;5650.152477128464;5660.32076150848;5697.7151142443545;5723.599348045764;5741.569382296428;5939.1982082420045;5966.552508561788;5972.283326000734;5975.40245975399;5996.5502587305255;4022.557518672885;4080.918381632291;4102.220578090153;4111.1366613011605;4168.2080404723;5778.624389414675;5790.841831633606;5793.304803423616;5801.30239438663;5807.128930660435;5597.608167428241;5655.660660360391;5683.175939488834;5683.7442315095095;5707.400722924199;5505.697186654773;5598.1602759586385;5616.85957851057;5629.183768353484;5647.943520564741;5568.721563921868;5599.552035837041;5612.803551875828;5618.4662457066515;5625.521830644441;5497.340319161726;5679.290088738908;5712.200291959046;5714.714264286686;5723.627964182315;5518.144687573433;5530.834074977757;5550.888982220414;5551.722413879371;5556.16657501378 16:38:44 INFO - PROCESS | 19948 | |5;yui.html;655.5181941273166;740.2301606329416;824.2828738997072;843.5529169540146;849.927756140728;15343.769843452343;16934.83065169348;16939.98360098392;16955.406560770374;16959.491215263548;14314.069585476936;14375.856241437583;14383.352749126288;14388.49640262591;14409.78385324221;650.3171669746771;776.0570906348785;776.0803447914226;792.4809249941269;800.7117437722444;791.3510921044746;801.3148757812074;802.0415603353995;802.1657476224719;802.2338666573438;853.6884037326362;855.1918437076964;855.2260204514924;855.2943821347383;855.5209082913589;679.4394624434861;817.0807841178674;835.1690068381998;848.6605357856845;849.0024221539697;617.4103730936945;617.6448542088317;618.1099217127368;618.4959258204549;619.071392910634;14014.369353379123;14047.78928316076;14054.578362649137;14073.085249458854;14076.648083797885;14214.289285535682;14489.78265326021;14516.854831451656;14557.927210363987;14879.33043013067;13527;13599.252041137734;13632.795508067387;13673.521426750116;13753.79369309451;4415.072834704716;4451.243288640939;4472.306792447176;4550.158220729142;4563.155816174019;77188;77464;77526.22473775246;77697.22302776956;77984.99999999943;11199.94400028011;11203.663890083311;11496.885031149748;11570.94214528922;12066.638000859988;21837.781622183575;22005.22981695633;22028.449288767908;22284.888575557343;22611.773882261296;8711.60797764102;8900.243479304332;8963.103689630985;8992;9056.36605437613;4930.704157750546;4947.594946797906;5030.119729047402;5059.215821547666;5060.114479965991;3391.3895498810452;3492.6681965213265;3499.755017148826;3502.3695734767475;3507.7544571880235;606.0817860940679;608.4949491921694;616.3620652624512;617.6355949989528;630.2657404124144;700.7302188657403;704.9330313620197;727.5271073802071;736.3887972982519;745.4893398022372;8296.294814940677;9132.22376098039;9287.860682089775;9379.5310234488;9597.280203984728;594.3848117198679;622.2657264427928;623.0683644455434;668.2849351194272;668.304962838652;4206.26390381682;4259.424977628042;4301.397804307399;4341.261985462479;4370.584794444522;63866;64781;67060.6646966762;67091;68615.31384686189;91289;91694;93934;94167.82076985695;94699;53220.201696974575;53923;54752.45247547553;81540;83614.58192709119;9404.294677899048;9757.26820488452;10244.948775256076;10402.791944161227;10650.254482186172;50402;51439.22841157461;52316.47683523116;52497.737511312196;53408.46591534035;44544.77727611341;44978;69515;71183;72249.27750722425;13033.739325213632;13436;13460.528881489101;13474.93262533681;13806.585802425943;15014.92492537574;15384.69230615359;15461.613459663598;15499.612509687347;15614.21928903573;13344.332783360598;13362.933185334012;13590.59228223155;13665.79501307501;13817.516386926409;13898.51355202563;14332.713345732818;14598.416063357772;14743.631409214426;14758.48345307952;13606.251656159006;13695.452181913011;13858.099223550436;14140.43438262458;14250.501232456814;11338.489767960296;11563.30620162793;11724.589639362923;11893.881061189624;12241.571544995883;12056.457459414523;12150.756984860076;12164.391780410768;12336.568220112253;12400.31798251105 16:38:44 INFO - PROCESS | 19948 | __end_tp_report 16:38:44 INFO - PROCESS | 19948 | __start_cc_report 16:38:44 INFO - PROCESS | 19948 | _x_x_mozilla_cycle_collect,1118 16:38:44 INFO - PROCESS | 19948 | __end_cc_report 16:38:44 INFO - PROCESS | 19948 | __startTimestamp1472513924137__endTimestamp 16:38:44 INFO - PROCESS | 19948 | 16:38:44 INFO - PROCESS | 19948 | ------- Summary: start ------- 16:38:44 INFO - PROCESS | 19948 | Number of tests: 6 16:38:44 INFO - PROCESS | 19948 | 16:38:44 INFO - PROCESS | 19948 | [#0] dojo.html Cycles:165 Average:5460.61 Median:5659.66 stddev:609.13 (10.8%) stddev-sans-first:582.17 16:38:44 INFO - PROCESS | 19948 | Values: 3100.2 3102.3 3124.8 3156.9 3185.9 5388.4 5420.9 5441.0 5485.7 5532.9 5423.9 5493.9 5503.4 5522.7 5565.2 4971.4 4998.4 5017.3 5033.5 5065.9 5025.2 5066.7 5139.2 5144.0 5147.4 5068.8 5091.2 5091.8 5098.7 5135.3 4960.7 4977.0 4985.1 4993.6 5104.4 5014.2 5014.8 5028.1 5102.2 5103.8 5466.8 5558.5 5568.1 5569.5 5569.8 5751.1 5779.1 5926.4 5927.3 5931.1 5923.2 5932.9 5934.1 5943.0 5946.5 5445.7 5447.7 5469.3 5509.0 5532.0 6059.9 6067.6 6069.3 6069.8 6073.2 5511.3 5706.0 5732.9 5735.7 5801.8 5787.8 5836.3 5837.8 5838.7 5894.9 5816.5 5819.8 5822.0 5825.4 5829.4 5637.3 5760.7 5761.6 5835.2 5835.5 4493.0 4504.7 4509.1 4509.5 4510.1 4049.2 4056.7 4060.3 4060.8 4061.1 5496.4 5511.6 5527.3 5579.1 5658.1 5941.3 5954.8 5980.4 6012.4 6017.4 5535.4 5583.1 5585.5 5596.9 5599.1 4956.6 5063.2 5064.1 5070.4 5124.9 5957.9 5987.7 6007.2 6015.7 6093.7 5845.9 5849.5 5855.1 5861.6 5864.1 5821.2 5990.4 5998.4 6016.5 6017.5 5649.7 5698.7 5718.5 5747.7 5819.0 5843.4 5851.7 5852.8 5853.4 5855.2 5616.8 5685.9 5713.8 5720.7 5720.8 5685.3 5745.5 5763.9 5773.4 5841.0 5719.3 5755.9 5763.6 5766.1 5768.6 5659.7 5795.2 5849.9 5850.0 5862.6 5585.4 5642.7 5658.6 5667.2 5719.1 16:38:44 INFO - PROCESS | 19948 | 16:38:44 INFO - PROCESS | 19948 | [#1] ext.html Cycles:180 Average:59128.80 Median:20287.39 stddev:115728.49 (570.4%) stddev-sans-first:115969.57 16:38:44 INFO - PROCESS | 19948 | Values: 552.9 591.3 603.3 611.7 618.2 32784.3 41898.6 42807.1 42865.4 46629.8 45634.0 46055.5 46061.8 46102.3 46106.5 8093.2 8745.8 8935.8 8944.4 8946.5 10439.3 10777.4 10780.6 11193.9 11202.6 11027.5 11115.8 11123.1 11127.6 11130.6 10850.9 10920.1 10949.7 10950.8 10960.0 10658.7 10742.7 10745.0 10749.2 10750.3 43156.3 43476.1 43608.3 43610.8 43643.8 3467.3 3507.2 3545.0 3588.6 3591.0 32611.0 32994.3 33088.7 33410.0 34158.9 4125.6 4292.7 4293.7 4306.6 4419.7 354226.0 355278.0 355560.0 356063.0 356104.2 59357.7 59620.7 59777.7 59927.0 60040.7 36983.8 37581.6 37785.0 39909.8 39917.2 15945.9 16082.3 16100.5 16102.8 16104.1 10569.7 11882.3 11948.4 12011.9 12116.9 19000.5 19180.8 19306.0 19340.6 19448.3 6905.8 7056.2 7068.9 7125.3 7313.1 916.0 1009.4 1022.3 1038.8 1143.2 32974.5 33020.3 33238.0 33395.8 33492.3 1085.7 1087.7 1091.3 1111.7 1126.6 16800.6 17324.7 17326.6 17382.6 17452.1 542974.0 544579.0 547378.0 548165.0 548171.0 58891.7 59589.0 59622.1 59692.7 59760.7 1193.8 1286.7 1318.3 1318.8 1332.7 29571.9 33559.5 34015.7 34220.3 34227.5 50274.5 50674.2 50706.2 50792.7 50860.2 383877.0 384551.0 385050.0 385159.0 385806.0 28676.4 29325.3 29417.1 29739.4 30486.5 48297.5 48480.0 48680.8 48833.8 49125.8 40417.0 40935.8 41395.8 41921.6 42915.4 40434.3 40523.8 40557.2 41161.8 42404.4 40189.0 41107.6 41348.8 41679.6 41913.2 34919.5 37264.0 37452.8 37986.6 38490.2 27873.3 28190.6 28602.9 28618.0 28736.1 16:38:44 INFO - PROCESS | 19948 | 16:38:44 INFO - PROCESS | 19948 | [#2] jquery.html Cycles:180 Average:29500.29 Median:2677.53 stddev:81506.39 (3044.1%) stddev-sans-first:81705.70 16:38:44 INFO - PROCESS | 19948 | Values: 376.5 402.4 404.2 404.2 421.8 15924.1 19283.2 19413.8 19668.8 20640.9 19406.8 19710.7 19910.7 20117.2 20225.1 5154.4 5616.1 5748.8 5792.2 5825.5 5719.5 5790.2 5796.4 5797.0 5800.0 5554.4 5720.8 5731.5 5738.4 5760.5 5665.9 5697.3 5739.9 5742.7 5746.7 5023.6 5118.1 5147.6 5152.1 5152.4 23624.8 23798.9 24133.2 24215.6 24289.6 2397.1 2410.4 2444.3 2476.2 2878.9 13995.9 14608.9 14964.3 15161.8 15231.6 1790.8 1852.2 1859.6 1921.6 1953.3 78480.2 79738.2 79832.6 80697.6 80812.6 10641.8 10688.3 10831.4 11025.7 11323.3 15477.3 15484.8 15491.8 15493.2 15514.6 7749.7 8028.3 8066.5 8346.5 8350.3 7589.9 7662.2 7670.6 7856.6 7928.4 6502.5 6598.8 6618.6 6648.8 6760.7 1823.8 1848.4 1879.6 1884.9 1965.1 736.4 982.3 987.5 992.5 1113.6 21455.6 22086.8 22092.3 22175.0 22399.7 943.5 965.9 972.0 983.6 1051.4 11369.9 12208.7 12214.6 12354.9 12418.4 485148.0 496138.0 496203.0 498636.0 500413.0 87829.1 88828.6 89275.0 91563.0 92540.0 1024.8 1030.3 1033.4 1044.8 1068.5 18379.6 18412.9 18917.4 19092.4 19343.2 40637.6 42176.6 42511.1 42655.0 43974.6 56307.4 56367.0 57497.4 59320.0 59588.4 8684.6 8907.6 8995.5 9008.1 9325.5 13503.3 13629.8 14012.7 14343.9 14560.3 11823.9 11950.9 12343.2 12526.7 12557.0 10958.0 11579.4 11819.8 12349.7 12393.4 11983.8 11998.8 12099.3 12135.9 12279.8 8436.5 8497.2 8549.8 8559.6 8850.8 10854.5 11605.6 11673.4 12059.4 12369.6 16:38:44 INFO - PROCESS | 19948 | 16:38:44 INFO - PROCESS | 19948 | [#3] mootools.html Cycles:180 Average:5921.65 Median:4831.49 stddev:3379.91 (70.0%) stddev-sans-first:3372.60 16:38:44 INFO - PROCESS | 19948 | Values: 1439.1 1475.9 1602.2 1735.3 1763.7 7938.4 8460.5 8731.4 9199.4 9252.6 8707.4 9071.3 9079.3 9084.5 9086.3 2401.1 2403.5 2409.4 2417.9 2428.0 2093.5 2387.4 2397.6 2401.9 2456.2 2483.4 2512.7 2518.9 2519.5 2521.3 2367.7 2443.2 2443.7 2453.1 2459.2 2388.9 2403.7 2457.8 2466.4 2467.4 9076.8 9219.4 9236.2 9240.4 9326.2 5337.2 5492.8 5502.5 5504.7 5507.8 3357.2 3462.7 3561.9 3566.9 3578.9 1189.2 1233.2 1240.2 1258.9 1273.3 12702.0 12746.2 12758.6 12767.4 12803.2 4737.9 4752.2 4813.7 4856.7 4931.3 10650.5 10673.5 10677.1 10677.9 10695.1 5405.7 5423.2 5431.3 5433.0 5438.9 4852.3 4874.9 4958.5 4964.7 4965.0 3996.3 3996.3 4082.4 4093.5 4125.2 1135.0 1224.8 1235.1 1242.4 1253.9 868.7 952.8 954.8 980.6 988.6 6028.6 6241.7 6262.1 6290.3 6400.8 942.1 949.5 997.2 1010.2 1014.9 4120.6 4238.7 4299.4 4300.1 4379.7 10962.1 11400.9 11449.2 11450.1 11455.8 11120.4 11243.9 11346.8 11350.9 11424.8 8327.8 8400.6 8410.5 8415.0 8418.3 6159.6 6204.8 6230.5 6236.3 6304.3 10560.3 10603.3 10617.9 10633.1 10642.2 10395.8 10408.8 10611.3 10635.8 10681.7 7387.6 7548.8 7575.4 7583.2 7583.4 8411.0 8430.8 8500.5 8502.4 8541.7 6303.7 7230.1 7282.3 7287.9 7675.5 7201.3 7256.6 7351.4 7568.5 7578.2 6789.2 6826.2 6879.4 6895.9 6926.1 4787.6 4849.3 4925.7 4935.6 5058.8 5096.9 5097.1 5194.5 5274.8 5362.8 16:38:44 INFO - PROCESS | 19948 | 16:38:44 INFO - PROCESS | 19948 | [#4] prototype.html Cycles:180 Average:4745.18 Median:5473.05 stddev:1397.77 (25.5%) stddev-sans-first:1361.84 16:38:44 INFO - PROCESS | 19948 | Values: 329.8 338.8 340.1 340.6 342.7 5365.2 5384.5 5398.1 5405.8 5480.3 5399.2 5416.5 5473.3 5483.3 5484.7 4165.5 4249.7 4259.6 4285.4 4287.8 4229.3 4254.0 4273.3 4290.7 4292.6 4186.5 4195.2 4204.6 4207.2 4214.1 4030.3 4050.1 4183.6 4203.4 4212.2 3725.9 3732.3 3745.9 3750.3 3770.9 5238.9 5250.2 5251.9 5259.4 5275.1 4347.3 4395.5 4768.3 4789.5 4828.8 5556.9 5563.5 5573.4 5581.8 5605.7 3998.6 4001.2 4015.0 4025.5 4026.4 5720.9 5739.1 5757.5 5770.2 5778.3 4048.9 4061.0 4068.9 4085.6 4116.0 4138.0 4143.5 4149.7 4156.6 4167.2 4515.9 4728.5 4755.9 4757.3 4785.6 5637.2 5637.7 5643.8 5646.7 5666.2 1410.4 1424.9 1429.4 1433.7 1436.3 922.0 936.0 939.7 977.2 1023.8 5407.4 5440.6 5461.7 5469.5 5472.8 5711.6 5741.8 5751.5 5775.4 5783.5 5227.6 5373.9 5390.9 5424.7 5473.7 2926.3 3029.0 3071.4 3085.4 3102.4 5956.1 5958.5 5968.5 5975.4 5979.4 5571.9 5882.6 5910.3 5919.9 5935.6 5969.7 5977.4 5990.5 6011.5 6013.4 5572.7 5812.2 5813.7 5838.4 5842.8 5650.2 5660.3 5697.7 5723.6 5741.6 5939.2 5966.6 5972.3 5975.4 5996.6 4022.6 4080.9 4102.2 4111.1 4168.2 5778.6 5790.8 5793.3 5801.3 5807.1 5597.6 5655.7 5683.2 5683.7 5707.4 5505.7 5598.2 5616.9 5629.2 5647.9 5568.7 5599.6 5612.8 5618.5 5625.5 5497.3 5679.3 5712.2 5714.7 5723.6 5518.1 5530.8 5550.9 5551.7 5556.2 16:38:44 INFO - PROCESS | 19948 | 16:38:44 INFO - PROCESS | 19948 | [#5] yui.html Cycles:180 Average:18571.60 Median:4939.15 stddev:24233.81 (490.6%) stddev-sans-first:24264.45 16:38:44 INFO - PROCESS | 19948 | Values: 655.5 740.2 824.3 843.6 849.9 15343.8 16934.8 16940.0 16955.4 16959.5 14314.1 14375.9 14383.4 14388.5 14409.8 650.3 776.1 776.1 792.5 800.7 791.4 801.3 802.0 802.2 802.2 853.7 855.2 855.2 855.3 855.5 679.4 817.1 835.2 848.7 849.0 617.4 617.6 618.1 618.5 619.1 14014.4 14047.8 14054.6 14073.1 14076.6 14214.3 14489.8 14516.9 14557.9 14879.3 13527.0 13599.3 13632.8 13673.5 13753.8 4415.1 4451.2 4472.3 4550.2 4563.2 77188.0 77464.0 77526.2 77697.2 77985.0 11199.9 11203.7 11496.9 11570.9 12066.6 21837.8 22005.2 22028.4 22284.9 22611.8 8711.6 8900.2 8963.1 8992.0 9056.4 4930.7 4947.6 5030.1 5059.2 5060.1 3391.4 3492.7 3499.8 3502.4 3507.8 606.1 608.5 616.4 617.6 630.3 700.7 704.9 727.5 736.4 745.5 8296.3 9132.2 9287.9 9379.5 9597.3 594.4 622.3 623.1 668.3 668.3 4206.3 4259.4 4301.4 4341.3 4370.6 63866.0 64781.0 67060.7 67091.0 68615.3 91289.0 91694.0 93934.0 94167.8 94699.0 53220.2 53923.0 54752.5 81540.0 83614.6 9404.3 9757.3 10244.9 10402.8 10650.3 50402.0 51439.2 52316.5 52497.7 53408.5 44544.8 44978.0 69515.0 71183.0 72249.3 13033.7 13436.0 13460.5 13474.9 13806.6 15014.9 15384.7 15461.6 15499.6 15614.2 13344.3 13362.9 13590.6 13665.8 13817.5 13898.5 14332.7 14598.4 14743.6 14758.5 13606.3 13695.5 13858.1 14140.4 14250.5 11338.5 11563.3 11724.6 11893.9 12241.6 12056.5 12150.8 12164.4 12336.6 12400.3 16:38:44 INFO - PROCESS | 19948 | -------- Summary: end -------- 16:38:44 INFO - PROCESS | 19948 | 16:38:45 INFO - TEST-INFO | 19948: exit 0 16:38:45 INFO - TEST-OK | dromaeo_css | took 1117279ms 16:38:45 INFO - TEST-START | kraken 16:38:45 INFO - operating with platform_type : linux_ 16:38:45 INFO - Initialising browser for kraken test... 16:38:45 INFO - TEST-INFO | started process 20251 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvvMJrR/profile http://localhost:33258/getInfo.html) 16:38:49 INFO - PROCESS | 20251 | __metrics Screen width/height:1600/1200 16:38:49 INFO - PROCESS | 20251 | colorDepth:24 16:38:49 INFO - PROCESS | 20251 | Browser inner width/height: 1024/697 16:38:49 INFO - PROCESS | 20251 | __metrics 16:38:50 INFO - PROCESS | 20251 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:38:50 INFO - PROCESS | 20251 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:38:51 INFO - PROCESS | 20251 | console.error: PushService: 16:38:51 INFO - PROCESS | 20251 | stateChangeProcessEnqueue: Error transitioning state 16:38:51 INFO - PROCESS | 20251 | UnknownError 16:38:51 INFO - TEST-INFO | 20251: exit 0 16:38:51 INFO - Browser initialized. 16:38:51 INFO - Running cycle 1/1 for kraken test... 16:38:51 INFO - TEST-INFO | started process 20334 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvvMJrR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:39:03 INFO - PROCESS | 20334 | RSS: Main: 179761152 16:39:03 INFO - PROCESS | 20334 | 16:39:06 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33258/tests/kraken/driver.html?testName=audio-beat-detection) 16:39:07 INFO - PROCESS | 20334 | RSS: Main: 233488384 16:39:07 INFO - PROCESS | 20334 | 16:39:09 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33258/tests/kraken/driver.html?testName=audio-dft) 16:39:10 INFO - PROCESS | 20334 | RSS: Main: 224358400 16:39:10 INFO - PROCESS | 20334 | 16:39:13 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33258/tests/kraken/driver.html?testName=audio-fft) 16:39:14 INFO - PROCESS | 20334 | RSS: Main: 301256704 16:39:14 INFO - PROCESS | 20334 | 16:39:16 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33258/tests/kraken/driver.html?testName=audio-oscillator) 16:39:16 INFO - PROCESS | 20334 | RSS: Main: 211582976 16:39:16 INFO - PROCESS | 20334 | 16:39:19 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33258/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:39:19 INFO - PROCESS | 20334 | RSS: Main: 213721088 16:39:19 INFO - PROCESS | 20334 | 16:39:22 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33258/tests/kraken/driver.html?testName=imaging-darkroom) 16:39:23 INFO - PROCESS | 20334 | RSS: Main: 252329984 16:39:23 INFO - PROCESS | 20334 | 16:39:26 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33258/tests/kraken/driver.html?testName=imaging-desaturate) 16:39:27 INFO - PROCESS | 20334 | RSS: Main: 250793984 16:39:27 INFO - PROCESS | 20334 | 16:39:29 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33258/tests/kraken/driver.html?testName=json-parse-financial) 16:39:30 INFO - PROCESS | 20334 | RSS: Main: 250560512 16:39:30 INFO - PROCESS | 20334 | 16:39:32 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33258/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:39:32 INFO - PROCESS | 20334 | RSS: Main: 213413888 16:39:32 INFO - PROCESS | 20334 | 16:39:34 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33258/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:39:35 INFO - PROCESS | 20334 | RSS: Main: 220893184 16:39:35 INFO - PROCESS | 20334 | 16:39:37 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33258/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:39:37 INFO - PROCESS | 20334 | RSS: Main: 221974528 16:39:37 INFO - PROCESS | 20334 | 16:39:40 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:39:40 INFO - PROCESS | 20334 | RSS: Main: 223698944 16:39:40 INFO - PROCESS | 20334 | 16:39:43 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:39:44 INFO - PROCESS | 20334 | RSS: Main: 220606464 16:39:44 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | Cycle 1(1): loaded http://localhost:33258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33258/tests/kraken/driver.html?testName=ai-astar) 16:39:46 INFO - PROCESS | 20334 | RSS: Main: 271572992 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | __start_tp_report 16:39:46 INFO - PROCESS | 20334 | _x_x_mozilla_page_load 16:39:46 INFO - PROCESS | 20334 | _x_x_mozilla_page_load_details 16:39:46 INFO - PROCESS | 20334 | |i|pagename|runs| 16:39:46 INFO - PROCESS | 20334 | |0;ai-astar;113;114;135;113;114;113;111;111;112;111 16:39:46 INFO - PROCESS | 20334 | |1;audio-beat-detection;150;120;148;171;120;150;151;118;186;116 16:39:46 INFO - PROCESS | 20334 | |2;audio-dft;162;161;161;161;162;162;163;161;161;162 16:39:46 INFO - PROCESS | 20334 | |3;audio-fft;74;75;75;173;77;73;75;76;166;77 16:39:46 INFO - PROCESS | 20334 | |4;audio-oscillator;82;82;150;83;83;81;146;82;82;138 16:39:46 INFO - PROCESS | 20334 | |5;imaging-gaussian-blur;116;117;117;117;117;117;116;117;116;117 16:39:46 INFO - PROCESS | 20334 | |6;imaging-darkroom;146;146;145;146;146;145;146;146;146;145 16:39:46 INFO - PROCESS | 20334 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 16:39:46 INFO - PROCESS | 20334 | |8;json-parse-financial;52;52;53;52;54;53;54;52;53;52 16:39:46 INFO - PROCESS | 20334 | |9;json-stringify-tinderbox;62;63;63;63;63;62;63;63;63;67 16:39:46 INFO - PROCESS | 20334 | |10;stanford-crypto-aes;89;83;94;81;82;81;79;86;80;79 16:39:46 INFO - PROCESS | 20334 | |11;stanford-crypto-ccm;125;125;122;124;123;133;125;127;163;133 16:39:46 INFO - PROCESS | 20334 | |12;stanford-crypto-pbkdf2;168;170;163;174;166;170;169;167;164;170 16:39:46 INFO - PROCESS | 20334 | |13;stanford-crypto-sha256-iterative;68;66;65;65;64;65;64;64;63;66 16:39:46 INFO - PROCESS | 20334 | __end_tp_report 16:39:46 INFO - PROCESS | 20334 | __start_cc_report 16:39:46 INFO - PROCESS | 20334 | _x_x_mozilla_cycle_collect,1735 16:39:46 INFO - PROCESS | 20334 | __end_cc_report 16:39:46 INFO - PROCESS | 20334 | __startTimestamp1472513986264__endTimestamp 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | ------- Summary: start ------- 16:39:46 INFO - PROCESS | 20334 | Number of tests: 14 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#0] ai-astar Cycles:10 Average:114.70 Median:113.00 stddev:7.23 (6.4%) stddev-sans-first:7.64 16:39:46 INFO - PROCESS | 20334 | Values: 113.0 114.0 135.0 113.0 114.0 113.0 111.0 111.0 112.0 111.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#1] audio-beat-detection Cycles:10 Average:143.00 Median:150.00 stddev:24.11 (16.1%) stddev-sans-first:25.44 16:39:46 INFO - PROCESS | 20334 | Values: 150.0 120.0 148.0 171.0 120.0 150.0 151.0 118.0 186.0 116.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.70 (0.4%) stddev-sans-first:0.73 16:39:46 INFO - PROCESS | 20334 | Values: 162.0 161.0 161.0 161.0 162.0 162.0 163.0 161.0 161.0 162.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#3] audio-fft Cycles:10 Average:94.10 Median:75.00 stddev:39.79 (53.1%) stddev-sans-first:41.54 16:39:46 INFO - PROCESS | 20334 | Values: 74.0 75.0 75.0 173.0 77.0 73.0 75.0 76.0 166.0 77.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#4] audio-oscillator Cycles:10 Average:100.90 Median:82.00 stddev:30.34 (37.0%) stddev-sans-first:31.40 16:39:46 INFO - PROCESS | 20334 | Values: 82.0 82.0 150.0 83.0 83.0 81.0 146.0 82.0 82.0 138.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 16:39:46 INFO - PROCESS | 20334 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 16:39:46 INFO - PROCESS | 20334 | Values: 146.0 146.0 145.0 146.0 146.0 145.0 146.0 146.0 146.0 145.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 16:39:46 INFO - PROCESS | 20334 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:0.82 (1.6%) stddev-sans-first:0.83 16:39:46 INFO - PROCESS | 20334 | Values: 52.0 52.0 53.0 52.0 54.0 53.0 54.0 52.0 53.0 52.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:1.40 (2.2%) stddev-sans-first:1.41 16:39:46 INFO - PROCESS | 20334 | Values: 62.0 63.0 63.0 63.0 63.0 62.0 63.0 63.0 63.0 67.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:82.50 stddev:4.88 (5.9%) stddev-sans-first:4.74 16:39:46 INFO - PROCESS | 20334 | Values: 89.0 83.0 94.0 81.0 82.0 81.0 79.0 86.0 80.0 79.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#11] stanford-crypto-ccm Cycles:10 Average:130.00 Median:126.00 stddev:12.20 (9.7%) stddev-sans-first:12.81 16:39:46 INFO - PROCESS | 20334 | Values: 125.0 125.0 122.0 124.0 123.0 133.0 125.0 127.0 163.0 133.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.10 Median:169.50 stddev:3.25 (1.9%) stddev-sans-first:3.44 16:39:46 INFO - PROCESS | 20334 | Values: 168.0 170.0 163.0 174.0 166.0 170.0 169.0 167.0 164.0 170.0 16:39:46 INFO - PROCESS | 20334 | 16:39:46 INFO - PROCESS | 20334 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:1.41 (2.2%) stddev-sans-first:1.00 16:39:46 INFO - PROCESS | 20334 | Values: 68.0 66.0 65.0 65.0 64.0 65.0 64.0 64.0 63.0 66.0 16:39:46 INFO - PROCESS | 20334 | -------- Summary: end -------- 16:39:46 INFO - PROCESS | 20334 | 16:39:47 INFO - TEST-INFO | 20334: exit 0 16:39:47 INFO - TEST-OK | kraken | took 62389ms 16:39:47 INFO - SUITE-END | took 1179s 16:39:47 INFO - Completed test suite (00:19:40) 16:39:47 INFO - javascript benchmark 16:39:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3100.1784527100317, 3102.348506813569, 3124.750019998402, 3156.9210769730767, 3185.888493902714, 5388.353397592295, 5420.864478388043, 5441.0, 5485.725713714313, 5532.861678458041, 5423.918641220384, 5493.917591236134, 5503.422140675221, 5522.66863988161, 5565.193047008182, 4971.428285747131, 4998.425181104174, 5017.347744793172, 5033.496650334955, 5065.949340506603, 5025.195968645017, 5066.695998240099, 5139.177731562951, 5143.974280128613, 5147.356580427447, 5068.771905264253, 5091.185410334348, 5091.821786237482, 5098.719570423644, 5135.332406787131, 4960.677555958851, 4976.975115124437, 4985.052839960396, 4993.600511959071, 5104.438511763721, 5014.247862820606, 5014.84955451337, 5028.145215313369, 5102.234664800272, 5103.770330335144, 5466.781328746885, 5558.527525160366, 5568.1091025435735, 5569.470900264469, 5569.832905012856, 5751.108578170349, 5779.133130030487, 5926.377730338339, 5927.2590926134235, 5931.110333449974, 5923.17075609415, 5932.851678708023, 5934.1098835174635, 5943.0, 5946.464818166386, 5445.727713614304, 5447.673139611636, 5469.288992430958, 5509.00837849187, 5532.0, 6059.909101363483, 6067.575269731165, 6069.302030266487, 6069.757209711562, 6073.210482637229, 5511.311086114236, 5706.029974904276, 5732.856678582985, 5735.684537350402, 5801.796937107265, 5787.768489260382, 5836.328822185418, 5837.824865254049, 5838.737256823453, 5894.852628684232, 5816.47651711348, 5819.79630712923, 5822.039363504974, 5825.38833422493, 5829.446202610745, 5637.295338082739, 5760.683162426107, 5761.596688231783, 5835.183074369592, 5835.503982161564, 4493.0339976905125, 4504.662150338738, 4509.143262780061, 4509.481409637868, 4510.120526497301, 4049.1699201663587, 4056.7363121397016, 4060.3300455425115, 4060.7766572838777, 4061.1268577256033, 5496.395396506381, 5511.641743286674, 5527.281453411031, 5579.13523403873, 5658.094704847287, 5941.257342832146, 5954.761809527657, 5980.401959803985, 6012.368701286302, 6017.398260174035, 5535.363433205152, 5583.106702927512, 5585.525230355384, 5596.86007849807, 5599.132134519156, 4956.628252881048, 5063.240513922868, 5064.088464076428, 5070.442251352349, 5124.871878203, 5957.880842383128, 5987.700614969322, 6007.249093863267, 6015.699215039318, 6093.69531523422, 5845.912311315248, 5849.502792262705, 5855.092460668603, 5861.560382971379, 5864.091065884794, 5821.185034095145, 5990.430909063632, 5998.370171131968, 6016.518678505621, 6017.488513476228, 5649.745761440663, 5698.715064246854, 5718.51392631631, 5747.712614369349, 5818.970905145447, 5843.357230704704, 5851.736671849862, 5852.7658893643775, 5853.443922827325, 5855.180274761622, 5616.775328986795, 5685.943140568706, 5713.8285851424525, 5720.656760594377, 5720.799772007959, 5685.289338832646, 5745.482906538432, 5763.94236057634, 5773.393793651774, 5841.0, 5719.28508936383, 5755.913661295001, 5763.625364351304, 5766.077427611562, 5768.596198266081, 5659.660420374791, 5795.188673585617, 5849.883002340014, 5849.970750146222, 5862.618929769722, 5585.413531579125, 5642.6614403135945, 5658.632188907708, 5667.2349232854685, 5719.084946408555], "value": 5419.586008927316, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.8940236912116, 591.3229352391512, 603.3363300369595, 611.6604635847497, 618.165476606581, 32784.344313113754, 41898.58101418985, 42807.143857122835, 42865.35701964473, 46629.76685116578, 45634.0, 46055.53944460554, 46061.769691151494, 46102.30846537305, 46106.538934610646, 8093.190680931889, 8745.825083498343, 8935.776605584848, 8944.373893827402, 8946.507942063185, 10439.321444106108, 10777.407242601654, 10780.622678206264, 11193.944030279838, 11202.607908723196, 11027.503762330714, 11115.777684446306, 11123.110151187886, 11127.554897804079, 11130.554777808878, 10850.945745271263, 10920.12638988879, 10949.671509854637, 10950.780984380308, 10960.0, 10658.733531661694, 10742.731431714192, 10745.032947034726, 10749.247552671317, 10750.301230419998, 43156.27374905, 43476.130477390594, 43608.3458748119, 43610.781946090065, 43643.78178109121, 3467.289205712823, 3507.2459421224303, 3544.9822750886337, 3588.6052534221217, 3590.964090359089, 32611.0, 32994.34011319784, 33088.6691133088, 33410.0, 34158.923117680344, 4125.628693417607, 4292.678049146295, 4293.699441039129, 4306.6124048835445, 4419.734815911055, 354226.0, 355278.0, 355560.0, 356063.0, 356104.2194789035, 59357.70321148453, 59620.70189649024, 59777.70111149416, 59927.0, 60040.69979650074, 36983.815080924425, 37581.62418375836, 37785.00977319205, 39909.80045099814, 39917.2016559673, 15945.920270398574, 16082.276297566637, 16100.516984490485, 16102.75845862313, 16104.114273715059, 10569.735756605993, 11882.346470944181, 11948.402579870972, 12011.939940300243, 12116.878831211576, 19000.52498687544, 19180.808191917902, 19306.0, 19340.613187736166, 19448.319308824124, 6905.75829845953, 7056.223815380304, 7068.92931070693, 7125.3230943060325, 7313.122425308997, 915.9587818548048, 1009.369144284822, 1022.2844009193445, 1038.7922415516775, 1143.2111842828422, 32974.50538241928, 33020.31535020846, 33238.0, 33395.83302083474, 33492.330153397284, 1085.7014321061772, 1087.6519513755522, 1091.2579445976812, 1111.7387413957713, 1126.6282126898104, 16800.57998550046, 17324.740128898076, 17326.566835829206, 17382.56543586395, 17452.127393630522, 542974.0, 544579.0, 547378.0, 548165.0, 548171.0, 58891.705541472016, 59589.0, 59622.10566841501, 59692.70153649291, 59760.70119649374, 1193.773183095209, 1286.7233544787916, 1318.3276528970293, 1318.756030134428, 1332.733453309342, 29571.85214073916, 33559.496607551395, 34015.65984340125, 34220.31559368849, 34227.4865877007, 50274.49725502698, 50674.239886400996, 50706.23940640967, 50792.746036269586, 50860.23709644284, 383877.0, 384551.0, 385050.0, 385159.0, 385806.0, 28676.42647147087, 29325.26686832761, 29417.11748647544, 29739.405211896075, 30486.542701859045, 48297.517024830704, 48480.0, 48680.756596216794, 48833.755831222035, 49125.754371227915, 40417.0, 40935.795321024394, 41395.793021034704, 41921.58078419177, 42915.356269655356, 40434.33919251248, 40523.79738101408, 40557.1888562233, 41161.79419102885, 42404.36393454162, 40189.0, 41107.58892411038, 41348.793256033525, 41679.58320416757, 41913.1617367657, 34919.476207856394, 37264.0, 37452.812735936146, 37986.62013379831, 38490.23019539649, 27873.303167420978, 28190.577141342485, 28602.856985714938, 28618.0, 28736.137915862557], "value": 20318.48149141047, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.48421662322625, 402.40645048553387, 404.1573319628574, 404.1734652635361, 421.84180932150446, 15924.124173170485, 19283.22867085315, 19413.805861941375, 19668.803311966876, 20640.896795516, 19406.805931940675, 19710.70433943492, 19910.701339479918, 20117.19531218753, 20225.089870955806, 5154.381474223099, 5616.101423772207, 5748.770049198027, 5792.189093526909, 5825.533957283428, 5719.513841323493, 5790.189373487715, 5796.391378905197, 5797.01450753373, 5799.971000145015, 5554.389017208104, 5720.771169153229, 5731.512821410164, 5738.368779434259, 5760.510356619692, 5665.8583535411535, 5697.316322041361, 5739.885202295931, 5742.6841523716175, 5746.7413966371405, 5023.6232282578585, 5118.053160165381, 5147.613928955306, 5152.149895267276, 5152.381714194321, 23624.76375236243, 23798.881005595034, 24133.155339563207, 24215.636765448533, 24289.635655465183, 2397.137030668958, 2410.3612542676206, 2444.327809852287, 2476.182859656309, 2878.870450829718, 13995.86004139966, 14608.926955365261, 14964.251787410694, 15161.772573411408, 15231.61920951974, 1790.8477779388768, 1852.1850385830191, 1859.6280743851148, 1921.5772530043369, 1953.3163392812544, 78480.21519784843, 79738.20261797366, 79832.60083699544, 80697.59651201706, 80812.59593702052, 10641.787164256672, 10688.251822372515, 10831.404272765074, 11025.724356890982, 11323.320600763816, 15477.303521341566, 15484.845151548341, 15491.84508154904, 15493.225338733018, 15514.612134696723, 7749.690012399441, 8028.277455029075, 8066.475679080841, 8346.540940248224, 8350.33197344211, 7589.924100758922, 7662.1571627120975, 7670.578118203441, 7856.607169641495, 7928.405369597303, 6502.479801615859, 6598.802035938929, 6618.569792963442, 6648.83377915556, 6760.7295708172105, 1823.7811462624304, 1848.4269876338378, 1879.586490971984, 1884.8963307018246, 1965.0764140853776, 736.3740820302806, 982.29274922056, 987.5062468765617, 992.533509250651, 1113.563700792869, 21455.57088858214, 22086.779132208794, 22092.33722988313, 22175.0, 22399.664005039936, 943.5188054092463, 965.8647789309503, 972.0, 983.6458874805064, 1051.4217180550665, 11369.886301137049, 12208.694782630506, 12214.633560993185, 12354.876451235552, 12418.379081045912, 485148.0, 496138.0, 496203.0, 498636.0, 500413.0, 87829.12170878338, 88828.5558572216, 89275.0, 91563.0, 92540.0, 1024.8257796174669, 1030.2891005206384, 1033.3541536539512, 1044.7910417916446, 1068.5191663751343, 18379.632407352045, 18412.907935460236, 18917.43247702571, 19092.427227183205, 19343.226270949006, 40637.59362406338, 42176.57823421727, 42511.1497770049, 42655.0, 43974.560254398326, 56307.436925630216, 56367.0, 57497.42502574921, 59320.0, 59588.404115958285, 8684.609192586222, 8907.55462226899, 8995.505247211204, 9008.144226298491, 9325.487098209664, 13503.257320847448, 13629.795553066513, 14012.719745604827, 14343.856561434251, 14560.271986400849, 11823.940880295544, 11950.940245298718, 12343.197692150341, 12526.749465010831, 12557.0, 10958.0, 11579.363135027654, 11819.818018198112, 12349.691257718629, 12393.442295096922, 11983.820242696193, 11998.76002479963, 12099.274043557414, 12135.878641213474, 12279.815802762787, 8436.493810371398, 8497.192766687154, 8549.786255343666, 8559.57202139878, 8850.778730531789, 10854.511546980562, 11605.593804216813, 11673.416329183678, 12059.397030148633, 12369.567065152676], "value": 9528.494592131652, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.1365180891466, 1475.9114453132809, 1602.2149146918007, 1735.314550752452, 1763.708988016978, 7938.404619653535, 8460.450070745397, 8731.388802783807, 9199.356045076847, 9252.629894804208, 8707.390482666216, 9071.274298056156, 9079.31905107118, 9084.50035248061, 9086.318526110535, 2401.0875867170453, 2403.471236328005, 2409.409694624819, 2417.867017314047, 2428.0287884846025, 2093.5394213273057, 2387.3673476528734, 2397.5924092904247, 2401.903923843044, 2456.2262887190477, 2483.3543278747475, 2512.6733524641772, 2518.8614626195554, 2519.508695804319, 2521.281434791085, 2367.715874095102, 2443.242594795619, 2443.7434069422634, 2453.092355828337, 2459.1638842793445, 2388.940276493084, 2403.6875206223253, 2457.778799907999, 2466.4080620651093, 2467.4078221226955, 9076.77308067297, 9219.400738952014, 9236.168744812932, 9240.445573265559, 9326.207272381856, 5337.199420086979, 5492.780288788444, 5502.532284755801, 5504.724763761836, 5507.807226747044, 3357.244619960489, 3462.740294477899, 3561.94657080144, 3566.946495802565, 3578.8926332210076, 1189.1675826921103, 1233.2477188914754, 1240.2248594628356, 1258.874112588743, 1273.2805964629954, 12702.0, 12746.235225886476, 12758.61724148277, 12767.42546585406, 12803.167794093353, 4737.928931066037, 4752.239641657318, 4813.700300918732, 4856.708597484162, 4931.260310953386, 10650.467476626138, 10673.519691613896, 10677.14582833387, 10677.9466102669, 10695.090917272119, 5405.702686352289, 5423.213634023124, 5431.266778984866, 5433.022056029874, 5438.91841622376, 4852.344933434012, 4874.926876096862, 4958.479359667183, 4964.652474326835, 4965.0, 3996.260691771973, 3996.2806694794635, 4082.408050832613, 4093.5497095319492, 4125.1749650070105, 1135.0465608888574, 1224.7856625090574, 1235.0675240193775, 1242.372601836067, 1253.8871501564904, 868.6959564152622, 952.8142012307534, 954.7708549947962, 980.5678333930736, 988.5798535622332, 6028.608140470856, 6241.7191226394925, 6262.060690896402, 6290.308066112724, 6400.839979000562, 942.1002942190192, 949.515746969037, 997.2321312589411, 1010.161565900301, 1014.9188064954786, 4120.567340429272, 4238.7032907696785, 4299.441072660534, 4300.118475712471, 4379.671524635665, 10962.123030157569, 11400.885991140149, 11449.198556100993, 11450.083993280517, 11455.770884582262, 11120.38837863909, 11243.887561124284, 11346.773064538664, 11350.886491135148, 11424.828627570592, 8327.750167494985, 8400.621972011275, 8410.495370277802, 8415.032271288757, 8418.326533877274, 6159.630422174684, 6204.81385558434, 6230.470410015199, 6236.251649802053, 6304.275008373912, 10560.260781745512, 10603.257771955889, 10617.893821062, 10633.149348052291, 10642.201834862262, 10395.792084158124, 10408.843867342148, 10611.257211995086, 10635.787284254116, 10681.732956675834, 7387.593682347522, 7548.8490230196185, 7575.431842611716, 7583.203763604963, 7583.445393694169, 8410.957945210235, 8430.789230269293, 8500.53247071417, 8502.36232282569, 8541.658333666584, 6303.747850085945, 7230.13238411373, 7282.344588986804, 7287.854242915006, 7675.462717609714, 7201.3158749918675, 7256.637168141678, 7351.41188704913, 7568.470207085451, 7578.204288549843, 6789.15135608049, 6826.249112597511, 6879.380855723009, 6895.931040689529, 6926.099607051051, 4787.640926930424, 4849.2726091086615, 4925.655204135676, 4935.62982776286, 5058.848234552969, 5096.949030509796, 5097.057044446789, 5194.532492075731, 5274.841754747364, 5362.812301569583], "value": 4746.206362638252, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.76916158688925, 338.80518701746496, 340.0749960108504, 340.627013420305, 342.68130638506193, 5365.168398898172, 5384.542313903313, 5398.13629819229, 5405.756740946657, 5480.342358916925, 5399.217113518537, 5416.539594134493, 5473.28847249857, 5483.287172667558, 5484.698341591211, 4165.500139983206, 4249.681273904454, 4259.616634502894, 4285.378620100084, 4287.820849266461, 4229.302165142748, 4253.957460425387, 4273.316269396897, 4290.742555446684, 4292.59220374064, 4186.451013125148, 4195.244855925932, 4204.5585213552595, 4207.200631879949, 4214.115035842477, 4030.3148464760916, 4050.10897602527, 4183.6234738873645, 4203.432536607549, 4212.199682060399, 3725.925481490355, 3732.2908647356917, 3745.887623371303, 3750.324941510527, 3770.8883025505806, 5238.947610523884, 5250.159974404115, 5251.947480525184, 5259.447757985433, 5275.129603615399, 4347.260965635849, 4395.450568678915, 4768.332433459318, 4789.49710280419, 4828.830990915301, 5556.944430555724, 5563.496426608042, 5573.4394369576585, 5581.832545023656, 5605.710686542118, 3998.5601583825755, 4001.239764444746, 4014.959850401488, 4025.496812898388, 4026.416169655384, 5720.856978575527, 5739.139129130622, 5757.539396848242, 5770.163326317703, 5778.301886792457, 4048.8785336439955, 4060.959390406058, 4068.9186216275507, 4085.610892296633, 4116.0121570822785, 4138.02756352257, 4143.461350024538, 4149.688773342012, 4156.56356082613, 4167.166566686675, 4515.932261016087, 4728.4562275338085, 4755.904881902412, 4757.26262429324, 4785.564330700846, 5637.23897273871, 5637.689927054052, 5643.774249030075, 5646.717664116778, 5666.2350582670815, 1410.4358256697403, 1424.9002569820013, 1429.4353730276482, 1433.6989232261133, 1436.3392839294042, 921.9861702074475, 935.9812803743887, 939.6617217801722, 977.2426369563503, 1023.7798873242289, 5407.432219616962, 5440.619156659075, 5461.672299662033, 5469.535089517356, 5472.808451704251, 5711.571632127607, 5741.770329186869, 5751.482366587027, 5775.364709881909, 5783.479486846204, 5227.634065615371, 5373.892522149535, 5390.8921821563345, 5424.674519528841, 5473.67157970523, 2926.297688554774, 3029.0004298581416, 3071.447139514909, 3085.41377138343, 3102.4105419970133, 5956.136360227742, 5958.463738263577, 5968.4926781224085, 5975.43233392827, 5979.431953964367, 5571.93838279839, 5882.617629854046, 5910.290765108129, 5919.892056670283, 5935.643861368331, 5969.746353265863, 5977.402259774075, 5990.520758339322, 6011.458968692838, 6013.398660133952, 5572.721363931706, 5812.21535092774, 5813.680247586254, 5838.386969368155, 5842.824715258549, 5650.152477128464, 5660.32076150848, 5697.7151142443545, 5723.599348045764, 5741.569382296428, 5939.1982082420045, 5966.552508561788, 5972.283326000734, 5975.40245975399, 5996.5502587305255, 4022.557518672885, 4080.918381632291, 4102.220578090153, 4111.1366613011605, 4168.2080404723, 5778.624389414675, 5790.841831633606, 5793.304803423616, 5801.30239438663, 5807.128930660435, 5597.608167428241, 5655.660660360391, 5683.175939488834, 5683.7442315095095, 5707.400722924199, 5505.697186654773, 5598.1602759586385, 5616.85957851057, 5629.183768353484, 5647.943520564741, 5568.721563921868, 5599.552035837041, 5612.803551875828, 5618.4662457066515, 5625.521830644441, 5497.340319161726, 5679.290088738908, 5712.200291959046, 5714.714264286686, 5723.627964182315, 5518.144687573433, 5530.834074977757, 5550.888982220414, 5551.722413879371, 5556.16657501378], "value": 4299.49920409972, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.5181941273166, 740.2301606329416, 824.2828738997072, 843.5529169540146, 849.927756140728, 15343.769843452343, 16934.83065169348, 16939.98360098392, 16955.406560770374, 16959.491215263548, 14314.069585476936, 14375.856241437583, 14383.352749126288, 14388.49640262591, 14409.78385324221, 650.3171669746771, 776.0570906348785, 776.0803447914226, 792.4809249941269, 800.7117437722444, 791.3510921044746, 801.3148757812074, 802.0415603353995, 802.1657476224719, 802.2338666573438, 853.6884037326362, 855.1918437076964, 855.2260204514924, 855.2943821347383, 855.5209082913589, 679.4394624434861, 817.0807841178674, 835.1690068381998, 848.6605357856845, 849.0024221539697, 617.4103730936945, 617.6448542088317, 618.1099217127368, 618.4959258204549, 619.071392910634, 14014.369353379123, 14047.78928316076, 14054.578362649137, 14073.085249458854, 14076.648083797885, 14214.289285535682, 14489.78265326021, 14516.854831451656, 14557.927210363987, 14879.33043013067, 13527.0, 13599.252041137734, 13632.795508067387, 13673.521426750116, 13753.79369309451, 4415.072834704716, 4451.243288640939, 4472.306792447176, 4550.158220729142, 4563.155816174019, 77188.0, 77464.0, 77526.22473775246, 77697.22302776956, 77984.99999999943, 11199.94400028011, 11203.663890083311, 11496.885031149748, 11570.94214528922, 12066.638000859988, 21837.781622183575, 22005.22981695633, 22028.449288767908, 22284.888575557343, 22611.773882261296, 8711.60797764102, 8900.243479304332, 8963.103689630985, 8992.0, 9056.36605437613, 4930.704157750546, 4947.594946797906, 5030.119729047402, 5059.215821547666, 5060.114479965991, 3391.3895498810452, 3492.6681965213265, 3499.755017148826, 3502.3695734767475, 3507.7544571880235, 606.0817860940679, 608.4949491921694, 616.3620652624512, 617.6355949989528, 630.2657404124144, 700.7302188657403, 704.9330313620197, 727.5271073802071, 736.3887972982519, 745.4893398022372, 8296.294814940677, 9132.22376098039, 9287.860682089775, 9379.5310234488, 9597.280203984728, 594.3848117198679, 622.2657264427928, 623.0683644455434, 668.2849351194272, 668.304962838652, 4206.26390381682, 4259.424977628042, 4301.397804307399, 4341.261985462479, 4370.584794444522, 63866.0, 64781.0, 67060.6646966762, 67091.0, 68615.31384686189, 91289.0, 91694.0, 93934.0, 94167.82076985695, 94699.0, 53220.201696974575, 53923.0, 54752.45247547553, 81540.0, 83614.58192709119, 9404.294677899048, 9757.26820488452, 10244.948775256076, 10402.791944161227, 10650.254482186172, 50402.0, 51439.22841157461, 52316.47683523116, 52497.737511312196, 53408.46591534035, 44544.77727611341, 44978.0, 69515.0, 71183.0, 72249.27750722425, 13033.739325213632, 13436.0, 13460.528881489101, 13474.93262533681, 13806.585802425943, 15014.92492537574, 15384.69230615359, 15461.613459663598, 15499.612509687347, 15614.21928903573, 13344.332783360598, 13362.933185334012, 13590.59228223155, 13665.79501307501, 13817.516386926409, 13898.51355202563, 14332.713345732818, 14598.416063357772, 14743.631409214426, 14758.48345307952, 13606.251656159006, 13695.452181913011, 13858.099223550436, 14140.43438262458, 14250.501232456814, 11338.489767960296, 11563.30620162793, 11724.589639362923, 11893.881061189624, 12241.571544995883, 12056.457459414523, 12150.756984860076, 12164.391780410768, 12336.568220112253, 12400.31798251105], "value": 7420.731320742608, "unit": "score"}], "name": "dromaeo_css", "value": 7359.632868721688, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 135.0, 113.0, 114.0, 113.0, 111.0, 111.0, 112.0, 111.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 120.0, 148.0, 171.0, 120.0, 150.0, 151.0, 118.0, 186.0, 116.0], "value": 143.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 162.0, 162.0, 163.0, 161.0, 161.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 173.0, 77.0, 73.0, 75.0, 76.0, 166.0, 77.0], "value": 94.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 150.0, 83.0, 83.0, 81.0, 146.0, 82.0, 82.0, 138.0], "value": 100.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 52.0, 54.0, 53.0, 54.0, 52.0, 53.0, 52.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 63.0, 63.0, 62.0, 63.0, 63.0, 63.0, 67.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 94.0, 81.0, 82.0, 81.0, 79.0, 86.0, 80.0, 79.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 125.0, 122.0, 124.0, 123.0, 133.0, 125.0, 127.0, 163.0, 133.0], "value": 130.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 163.0, 174.0, 166.0, 170.0, 169.0, 167.0, 164.0, 170.0], "value": 168.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 65.0, 65.0, 64.0, 65.0, 64.0, 64.0, 63.0, 66.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1534.1000000000001, "alertThreshold": 2.0}]} 16:39:47 INFO - Return code: 0 16:39:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:39:48 INFO - # TBPL SUCCESS # 16:39:48 INFO - Running post-action listener: _resource_record_post_action 16:39:48 INFO - [mozharness: 2016-08-29 23:39:48.073370Z] Finished run-tests step (success) 16:39:48 INFO - Running post-run listener: _resource_record_post_run 16:39:48 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406962176; Read time: 12; Write time: 592040 16:39:48 INFO - TinderboxPrint: CPU usage
13.1% 16:39:48 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:39:48 INFO - TinderboxPrint: I/O write bytes / time
406,962,176 / 592,040 16:39:48 INFO - TinderboxPrint: CPU idle
8,301.1 (86.9%) 16:39:48 INFO - TinderboxPrint: CPU user
1,196.3 (12.5%) 16:39:48 INFO - TinderboxPrint: Swap in / out
0 / 0 16:39:48 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 118398976; Read time: 0; Write time: 241132 16:39:48 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 288563200; Read time: 0; Write time: 350908 16:39:48 INFO - Running post-run listener: _upload_blobber_files 16:39:48 INFO - Blob upload gear active. 16:39:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:39:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:39:48 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:39:48 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:39:49 INFO - (blobuploader) - INFO - Open directory for files ... 16:39:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:39:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:39:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:53 INFO - (blobuploader) - INFO - Done attempting. 16:39:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:39:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:39:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:54 INFO - (blobuploader) - INFO - Done attempting. 16:39:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:39:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:39:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:55 INFO - (blobuploader) - INFO - Done attempting. 16:39:55 INFO - (blobuploader) - INFO - Iteration through files over. 16:39:55 INFO - Return code: 0 16:39:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:39:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:39:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/935b31494a4760805593ece6012d390d31b426e5a3e504a43ac6c1e34d2c0eee620947e49ac95fb2a14a3cba393cd9b49ec6b7819e041e322cda78deb96096ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01eb699baca5bb8dfb16ddcbfc7a4c520e2ae1ed3eaf1bececc5891037b2fe5a79a9baef3a89992e3581d9a4b9685ea63079f3358c06d35264746da6ff882ad9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70d698214841a1123ca24096826c9db323eb5e794c601a3c49450db252018deb91a856031113020524857e861cd617859550addaf3a99266c759ad7192941bf8"} 16:39:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:39:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:39:55 INFO - Contents: 16:39:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/935b31494a4760805593ece6012d390d31b426e5a3e504a43ac6c1e34d2c0eee620947e49ac95fb2a14a3cba393cd9b49ec6b7819e041e322cda78deb96096ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01eb699baca5bb8dfb16ddcbfc7a4c520e2ae1ed3eaf1bececc5891037b2fe5a79a9baef3a89992e3581d9a4b9685ea63079f3358c06d35264746da6ff882ad9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70d698214841a1123ca24096826c9db323eb5e794c601a3c49450db252018deb91a856031113020524857e861cd617859550addaf3a99266c759ad7192941bf8"} 16:39:55 INFO - Running post-run listener: copy_logs_to_upload_dir 16:39:55 INFO - Copying logs to upload dir... 16:39:55 INFO - mkdir: /builds/slave/test/build/upload/logs 16:39:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.360116 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-29 16:39:55.109730) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 16:39:55.113567) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1472511271.894139-1171538664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/935b31494a4760805593ece6012d390d31b426e5a3e504a43ac6c1e34d2c0eee620947e49ac95fb2a14a3cba393cd9b49ec6b7819e041e322cda78deb96096ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01eb699baca5bb8dfb16ddcbfc7a4c520e2ae1ed3eaf1bececc5891037b2fe5a79a9baef3a89992e3581d9a4b9685ea63079f3358c06d35264746da6ff882ad9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70d698214841a1123ca24096826c9db323eb5e794c601a3c49450db252018deb91a856031113020524857e861cd617859550addaf3a99266c759ad7192941bf8"} build_url:https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011385 build_url: 'https://queue.taskcluster.net/v1/task/fVmFULvXTIqPMYfJkhKKBw/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/Fx-Team/sha512/935b31494a4760805593ece6012d390d31b426e5a3e504a43ac6c1e34d2c0eee620947e49ac95fb2a14a3cba393cd9b49ec6b7819e041e322cda78deb96096ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01eb699baca5bb8dfb16ddcbfc7a4c520e2ae1ed3eaf1bececc5891037b2fe5a79a9baef3a89992e3581d9a4b9685ea63079f3358c06d35264746da6ff882ad9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70d698214841a1123ca24096826c9db323eb5e794c601a3c49450db252018deb91a856031113020524857e861cd617859550addaf3a99266c759ad7192941bf8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 16:39:55.142295) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:39:55.142673) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1472511271.894139-1171538664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:39:55.226472) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 16:39:55.226844) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 16:39:55.227246) ========= ========= Total master_lag: 0.69 =========