builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1467734571.97 results: success (0) buildid: 20160701203027 builduid: f95e7fc787e24cbe9783c4e965f64579 revision: 49a1d28776b4a2867bb8818dfab61c352cc02f49 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:51.965492) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:51.966413) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:51.967137) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:52.137583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:52.137977) ========= 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=523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-05 09:02:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.6M=0s 2016-07-05 09:02:52 (47.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081682 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:52.235065) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:52.235445) ========= 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=523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019156 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:52.285566) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 09:02:52.286036) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 49a1d28776b4a2867bb8818dfab61c352cc02f49 --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 49a1d28776b4a2867bb8818dfab61c352cc02f49 --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=523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-05 09:02:52,448 truncating revision to first 12 chars 2016-07-05 09:02:52,448 Setting DEBUG logging. 2016-07-05 09:02:52,448 attempt 1/10 2016-07-05 09:02:52,448 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/49a1d28776b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 09:02:53,191 unpacking tar archive at: fx-team-49a1d28776b4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.142353 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 09:02:53.442816) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:53.443212) ========= script_repo_revision: 49a1d28776b4a2867bb8818dfab61c352cc02f49 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:53.443714) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:53.444047) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 09:02:53.453818) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-07-05 09:02:53.454145) ========= /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=523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:02:53 INFO - MultiFileLogger online at 20160705 09:02:53 in /builds/slave/test 09:02:53 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 09:02:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:02:53 INFO - {'append_to_log': False, 09:02:53 INFO - 'base_work_dir': '/builds/slave/test', 09:02:53 INFO - 'blob_upload_branch': 'Fx-Team', 09:02:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:02:53 INFO - 'branch': 'Fx-Team', 09:02:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:02:53 INFO - 'config_files': ('talos/linux_config.py',), 09:02:53 INFO - 'default_actions': ('clobber', 09:02:53 INFO - 'read-buildbot-config', 09:02:53 INFO - 'download-and-extract', 09:02:53 INFO - 'populate-webroot', 09:02:53 INFO - 'create-virtualenv', 09:02:53 INFO - 'install', 09:02:53 INFO - 'run-tests'), 09:02:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:02:53 INFO - 'download_minidump_stackwalk': True, 09:02:53 INFO - 'download_symbols': 'ondemand', 09:02:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:02:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:02:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:02:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:02:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:02:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:02:53 INFO - 'installer_path': 'installer.exe', 09:02:53 INFO - 'log_level': 'info', 09:02:53 INFO - 'log_name': 'talos', 09:02:53 INFO - 'log_to_console': True, 09:02:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:02:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:02:53 INFO - 'opt_config_files': (), 09:02:53 INFO - 'pip_index': False, 09:02:53 INFO - 'sps_profile': False, 09:02:53 INFO - 'sps_profile_interval': 0, 09:02:53 INFO - 'suite': 'dromaeojs', 09:02:53 INFO - 'system_bits': '32', 09:02:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:02:53 INFO - 'title': 'talos-linux64-ix-065', 09:02:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:02:53 INFO - 'use_talos_json': True, 09:02:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:02:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:02:53 INFO - 'work_dir': 'build'} 09:02:53 INFO - ##### 09:02:53 INFO - ##### Running clobber step. 09:02:53 INFO - ##### 09:02:53 INFO - Running pre-action listener: _resource_record_pre_action 09:02:53 INFO - Running main action method: clobber 09:02:53 INFO - rmtree: /builds/slave/test/build 09:02:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:02:54 INFO - Running post-action listener: _resource_record_post_action 09:02:54 INFO - ##### 09:02:54 INFO - ##### Finished clobber step (success) 09:02:54 INFO - ##### 09:02:54 INFO - ##### 09:02:54 INFO - ##### Running read-buildbot-config step. 09:02:54 INFO - ##### 09:02:54 INFO - Running pre-action listener: _resource_record_pre_action 09:02:54 INFO - Running main action method: read_buildbot_config 09:02:54 INFO - Using buildbot properties: 09:02:54 INFO - { 09:02:54 INFO - "project": "", 09:02:54 INFO - "product": "firefox", 09:02:54 INFO - "script_repo_revision": "production", 09:02:54 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:02:54 INFO - "repository": "", 09:02:54 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 09:02:54 INFO - "buildid": "20160701203027", 09:02:54 INFO - "pgo_build": "True", 09:02:54 INFO - "basedir": "/builds/slave/test", 09:02:54 INFO - "buildnumber": 858, 09:02:54 INFO - "slavename": "talos-linux64-ix-065", 09:02:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:02:54 INFO - "platform": "ubuntu64_hw", 09:02:54 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 09:02:54 INFO - "branch": "fx-team", 09:02:54 INFO - "repo_path": "integration/fx-team", 09:02:54 INFO - "revision": "49a1d28776b4a2867bb8818dfab61c352cc02f49", 09:02:54 INFO - "stage_platform": "linux64-pgo", 09:02:54 INFO - "builduid": "f95e7fc787e24cbe9783c4e965f64579", 09:02:54 INFO - "slavebuilddir": "test" 09:02:54 INFO - } 09:02:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:02:54 INFO - Running post-action listener: _resource_record_post_action 09:02:54 INFO - ##### 09:02:54 INFO - ##### Finished read-buildbot-config step (success) 09:02:54 INFO - ##### 09:02:54 INFO - ##### 09:02:54 INFO - ##### Running download-and-extract step. 09:02:54 INFO - ##### 09:02:54 INFO - Running pre-action listener: _resource_record_pre_action 09:02:54 INFO - Running main action method: download_and_extract 09:02:54 INFO - mkdir: /builds/slave/test/build/tests 09:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:54 INFO - https://queue.taskcluster.net/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:02:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:02:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:02:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:02:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:02:54 INFO - Downloaded 1361 bytes. 09:02:54 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:02:54 INFO - Using the following test package requirements: 09:02:54 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:02:54 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:02:54 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:02:54 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:54 INFO - u'jsshell-linux-x86_64.zip'], 09:02:54 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:02:54 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:02:54 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:02:54 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:02:54 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:02:54 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:02:54 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:54 INFO - https://queue.taskcluster.net/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:02:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:02:55 INFO - Downloaded 24270673 bytes. 09:02:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:02:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:02:57 INFO - Return code: 0 09:02:57 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:57 INFO - https://queue.taskcluster.net/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:02:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:02:58 INFO - Downloaded 24270673 bytes. 09:02:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:02:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:02:59 INFO - Return code: 0 09:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:59 INFO - https://queue.taskcluster.net/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:02:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:02:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:02:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:02:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:03:00 INFO - Downloaded 14405242 bytes. 09:03:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:03:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:03:00 INFO - Return code: 0 09:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:00 INFO - https://queue.taskcluster.net/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:03:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:03:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:03:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:03:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:03:02 INFO - Downloaded 60333257 bytes. 09:03:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:03:02 INFO - mkdir: /builds/slave/test/properties 09:03:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:03:02 INFO - Writing to file /builds/slave/test/properties/build_url 09:03:02 INFO - Contents: 09:03:02 INFO - build_url:https://queue.taskcluster.net/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:03:03 INFO - Running post-action listener: _resource_record_post_action 09:03:03 INFO - Running post-action listener: set_extra_try_arguments 09:03:03 INFO - ##### 09:03:03 INFO - ##### Finished download-and-extract step (success) 09:03:03 INFO - ##### 09:03:03 INFO - ##### 09:03:03 INFO - ##### Running populate-webroot step. 09:03:03 INFO - ##### 09:03:03 INFO - Running pre-action listener: _resource_record_pre_action 09:03:03 INFO - Running main action method: populate_webroot 09:03:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:03:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:03:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:03:03 INFO - '--tppagecycles', 09:03:03 INFO - '7'], 09:03:03 INFO - 'tests': ['tsvgm']}}, 09:03:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:03:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:03:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:03:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:03:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:03 INFO - 'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:03:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:03:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:03 INFO - 'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['damp', 'tps']}, 09:03:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:03 INFO - 'tests': ['damp', 'tps']}, 09:03:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['dromaeo_dom']}, 09:03:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:03:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['basic_compositor_video']}, 09:03:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:03:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['a11yr', 09:03:03 INFO - 'ts_paint', 09:03:03 INFO - 'tpaint', 09:03:03 INFO - 'sessionrestore', 09:03:03 INFO - 'sessionrestore_no_auto_restore', 09:03:03 INFO - 'tabpaint']}, 09:03:03 INFO - 'other-e10s': {'tests': ['a11yr', 09:03:03 INFO - 'ts_paint', 09:03:03 INFO - 'tpaint', 09:03:03 INFO - 'sessionrestore', 09:03:03 INFO - 'sessionrestore_no_auto_restore', 09:03:03 INFO - 'tabpaint']}, 09:03:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:03:03 INFO - 'ts_paint', 09:03:03 INFO - 'tpaint', 09:03:03 INFO - 'sessionrestore', 09:03:03 INFO - 'sessionrestore_no_auto_restore', 09:03:03 INFO - 'tabpaint']}, 09:03:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:03:03 INFO - 'ts_paint', 09:03:03 INFO - 'tpaint', 09:03:03 INFO - 'sessionrestore', 09:03:03 INFO - 'sessionrestore_no_auto_restore', 09:03:03 INFO - 'tabpaint']}, 09:03:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['a11yr', 09:03:03 INFO - 'ts_paint', 09:03:03 INFO - 'tpaint', 09:03:03 INFO - 'sessionrestore', 09:03:03 INFO - 'sessionrestore_no_auto_restore', 09:03:03 INFO - 'tabpaint']}, 09:03:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['a11yr', 09:03:03 INFO - 'ts_paint', 09:03:03 INFO - 'tpaint', 09:03:03 INFO - 'sessionrestore', 09:03:03 INFO - 'sessionrestore_no_auto_restore', 09:03:03 INFO - 'tabpaint']}, 09:03:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['tsvgx', 09:03:03 INFO - 'tsvgr_opacity', 09:03:03 INFO - 'tart', 09:03:03 INFO - 'tscrollx', 09:03:03 INFO - 'cart']}, 09:03:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:03:03 INFO - 'tsvgr_opacity', 09:03:03 INFO - 'tart', 09:03:03 INFO - 'tscrollx', 09:03:03 INFO - 'cart']}, 09:03:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:03 INFO - 'talos_options': ['--disable-e10s'], 09:03:03 INFO - 'tests': ['tp5o']}, 09:03:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:03 INFO - 'tests': ['tp5o']}, 09:03:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:03 INFO - 'talos_options': ['--disable-e10s', 09:03:03 INFO - '--xperf_path', 09:03:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:03:03 INFO - 'tests': ['tp5n']}, 09:03:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:03 INFO - 'talos_options': ['--xperf_path', 09:03:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:03:03 INFO - 'tests': ['tp5n']}}, 09:03:03 INFO - 'talos.zip': {'path': '', 09:03:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:03:03 INFO - Running post-action listener: _resource_record_post_action 09:03:03 INFO - ##### 09:03:03 INFO - ##### Finished populate-webroot step (success) 09:03:03 INFO - ##### 09:03:03 INFO - ##### 09:03:03 INFO - ##### Running create-virtualenv step. 09:03:03 INFO - ##### 09:03:03 INFO - Running pre-action listener: _resource_record_pre_action 09:03:03 INFO - Running main action method: create_virtualenv 09:03:03 INFO - Creating virtualenv /builds/slave/test/build/venv 09:03:03 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 09:03:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:03:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:03:03 INFO - Using real prefix '/usr' 09:03:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:03:03 INFO - Installing distribute.............................................................................................................................................................................................done. 09:03:05 INFO - Installing pip.................done. 09:03:05 INFO - Return code: 0 09:03:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:05 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 0x298f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4e3b0>, '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 0x2c4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f740>, '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': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', '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 09:03:05 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 09:03:05 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 09:03:05 INFO - Using env: {'DISPLAY': ':0', 09:03:05 INFO - 'HOME': '/home/cltbld', 09:03:05 INFO - 'LANG': 'en_US.UTF-8', 09:03:05 INFO - 'LANGUAGE': 'en_US:en', 09:03:05 INFO - 'LOGNAME': 'cltbld', 09:03:05 INFO - 'MAIL': '/var/mail/cltbld', 09:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:05 INFO - 'MOZ_NO_REMOTE': '1', 09:03:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:05 INFO - 'NO_EM_RESTART': '1', 09:03:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:05 INFO - 'PWD': '/builds/slave/test', 09:03:05 INFO - 'SHELL': '/bin/bash', 09:03:05 INFO - 'SHLVL': '1', 09:03:05 INFO - 'TERM': 'linux', 09:03:05 INFO - 'TMOUT': '86400', 09:03:05 INFO - 'USER': 'cltbld', 09:03:05 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', 09:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:05 INFO - '_': '/tools/buildbot/bin/python'} 09:03:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:05 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 09:03:05 INFO - Cleaning up... 09:03:05 INFO - Return code: 0 09:03:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:05 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 0x298f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4e3b0>, '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 0x2c4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f740>, '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': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', '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 09:03:05 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 09:03:05 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 09:03:05 INFO - Using env: {'DISPLAY': ':0', 09:03:05 INFO - 'HOME': '/home/cltbld', 09:03:05 INFO - 'LANG': 'en_US.UTF-8', 09:03:05 INFO - 'LANGUAGE': 'en_US:en', 09:03:05 INFO - 'LOGNAME': 'cltbld', 09:03:05 INFO - 'MAIL': '/var/mail/cltbld', 09:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:05 INFO - 'MOZ_NO_REMOTE': '1', 09:03:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:05 INFO - 'NO_EM_RESTART': '1', 09:03:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:05 INFO - 'PWD': '/builds/slave/test', 09:03:05 INFO - 'SHELL': '/bin/bash', 09:03:05 INFO - 'SHLVL': '1', 09:03:05 INFO - 'TERM': 'linux', 09:03:05 INFO - 'TMOUT': '86400', 09:03:05 INFO - 'USER': 'cltbld', 09:03:05 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', 09:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:05 INFO - '_': '/tools/buildbot/bin/python'} 09:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:06 INFO - Downloading/unpacking psutil>=3.1.1 09:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:03:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:03:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:03:08 INFO - Installing collected packages: psutil 09:03:08 INFO - Running setup.py install for psutil 09:03:08 INFO - building 'psutil._psutil_linux' extension 09:03:08 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 09:03:08 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 09:03:08 INFO - building 'psutil._psutil_posix' extension 09:03:08 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 09:03:08 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 09:03:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:03:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:03:08 INFO - Successfully installed psutil 09:03:08 INFO - Cleaning up... 09:03:08 INFO - Return code: 0 09:03:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4e3b0>, '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 0x2c4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f740>, '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': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', '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 09:03:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:03:08 INFO - Using env: {'DISPLAY': ':0', 09:03:08 INFO - 'HOME': '/home/cltbld', 09:03:08 INFO - 'LANG': 'en_US.UTF-8', 09:03:08 INFO - 'LANGUAGE': 'en_US:en', 09:03:08 INFO - 'LOGNAME': 'cltbld', 09:03:08 INFO - 'MAIL': '/var/mail/cltbld', 09:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:08 INFO - 'MOZ_NO_REMOTE': '1', 09:03:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:08 INFO - 'NO_EM_RESTART': '1', 09:03:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:08 INFO - 'PWD': '/builds/slave/test', 09:03:08 INFO - 'SHELL': '/bin/bash', 09:03:08 INFO - 'SHLVL': '1', 09:03:08 INFO - 'TERM': 'linux', 09:03:08 INFO - 'TMOUT': '86400', 09:03:08 INFO - 'USER': 'cltbld', 09:03:08 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', 09:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:08 INFO - '_': '/tools/buildbot/bin/python'} 09:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:03:08 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 09:03:08 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 09:03:08 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 09:03:08 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 09:03:08 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 09:03:08 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 09:03:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:03:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:03:10 INFO - Installing collected packages: mozsystemmonitor 09:03:10 INFO - Running setup.py install for mozsystemmonitor 09:03:10 INFO - Successfully installed mozsystemmonitor 09:03:10 INFO - Cleaning up... 09:03:10 INFO - Return code: 0 09:03:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:10 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 0x298f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4e3b0>, '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 0x2c4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f740>, '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': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', '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 09:03:10 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 09:03:10 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 09:03:10 INFO - Using env: {'DISPLAY': ':0', 09:03:10 INFO - 'HOME': '/home/cltbld', 09:03:10 INFO - 'LANG': 'en_US.UTF-8', 09:03:10 INFO - 'LANGUAGE': 'en_US:en', 09:03:10 INFO - 'LOGNAME': 'cltbld', 09:03:10 INFO - 'MAIL': '/var/mail/cltbld', 09:03:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:10 INFO - 'MOZ_NO_REMOTE': '1', 09:03:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:10 INFO - 'NO_EM_RESTART': '1', 09:03:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:10 INFO - 'PWD': '/builds/slave/test', 09:03:10 INFO - 'SHELL': '/bin/bash', 09:03:10 INFO - 'SHLVL': '1', 09:03:10 INFO - 'TERM': 'linux', 09:03:10 INFO - 'TMOUT': '86400', 09:03:10 INFO - 'USER': 'cltbld', 09:03:10 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', 09:03:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:10 INFO - '_': '/tools/buildbot/bin/python'} 09:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:11 INFO - Downloading/unpacking jsonschema==2.5.1 09:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:03:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:13 INFO - Downloading functools32-3.2.3-2.tar.gz 09:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:03:13 INFO - warning: no files found matching '*.txt' 09:03:13 INFO - no previously-included directories found matching 'build' 09:03:13 INFO - no previously-included directories found matching 'dist' 09:03:13 INFO - no previously-included directories found matching '.git*' 09:03:13 INFO - Installing collected packages: jsonschema, functools32 09:03:13 INFO - Running setup.py install for functools32 09:03:13 INFO - warning: no files found matching '*.txt' 09:03:13 INFO - no previously-included directories found matching 'build' 09:03:13 INFO - no previously-included directories found matching 'dist' 09:03:13 INFO - no previously-included directories found matching '.git*' 09:03:13 INFO - Successfully installed jsonschema functools32 09:03:13 INFO - Cleaning up... 09:03:13 INFO - Return code: 0 09:03:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:13 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 0x298f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4e3b0>, '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 0x2c4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f740>, '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': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', '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 09:03:13 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 09:03:13 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 09:03:13 INFO - Using env: {'DISPLAY': ':0', 09:03:13 INFO - 'HOME': '/home/cltbld', 09:03:13 INFO - 'LANG': 'en_US.UTF-8', 09:03:13 INFO - 'LANGUAGE': 'en_US:en', 09:03:13 INFO - 'LOGNAME': 'cltbld', 09:03:13 INFO - 'MAIL': '/var/mail/cltbld', 09:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:13 INFO - 'MOZ_NO_REMOTE': '1', 09:03:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:13 INFO - 'NO_EM_RESTART': '1', 09:03:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:13 INFO - 'PWD': '/builds/slave/test', 09:03:13 INFO - 'SHELL': '/bin/bash', 09:03:13 INFO - 'SHLVL': '1', 09:03:13 INFO - 'TERM': 'linux', 09:03:13 INFO - 'TMOUT': '86400', 09:03:13 INFO - 'USER': 'cltbld', 09:03:13 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', 09:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:13 INFO - '_': '/tools/buildbot/bin/python'} 09:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:13 INFO - Downloading/unpacking blobuploader==1.2.4 09:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:15 INFO - Downloading blobuploader-1.2.4.tar.gz 09:03:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:03:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:03:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:03:16 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 09:03:16 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 09:03:16 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 09:03:16 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 09:03:16 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 09:03:16 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 09:03:16 INFO - Downloading docopt-0.6.1.tar.gz 09:03:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:03:16 INFO - Installing collected packages: blobuploader, requests, docopt 09:03:16 INFO - Running setup.py install for blobuploader 09:03:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:03:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:03:16 INFO - Running setup.py install for requests 09:03:16 INFO - Running setup.py install for docopt 09:03:16 INFO - Successfully installed blobuploader requests docopt 09:03:16 INFO - Cleaning up... 09:03:17 INFO - Return code: 0 09:03:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:17 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 0x298f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4e3b0>, '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 0x2c4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f740>, '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': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', '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 09:03:17 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 09:03:17 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 09:03:17 INFO - Using env: {'DISPLAY': ':0', 09:03:17 INFO - 'HOME': '/home/cltbld', 09:03:17 INFO - 'LANG': 'en_US.UTF-8', 09:03:17 INFO - 'LANGUAGE': 'en_US:en', 09:03:17 INFO - 'LOGNAME': 'cltbld', 09:03:17 INFO - 'MAIL': '/var/mail/cltbld', 09:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:17 INFO - 'MOZ_NO_REMOTE': '1', 09:03:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:17 INFO - 'NO_EM_RESTART': '1', 09:03:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:17 INFO - 'PWD': '/builds/slave/test', 09:03:17 INFO - 'SHELL': '/bin/bash', 09:03:17 INFO - 'SHLVL': '1', 09:03:17 INFO - 'TERM': 'linux', 09:03:17 INFO - 'TMOUT': '86400', 09:03:17 INFO - 'USER': 'cltbld', 09:03:17 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', 09:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:17 INFO - '_': '/tools/buildbot/bin/python'} 09:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:17 INFO - Running setup.py (path:/tmp/pip-LBPVd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:17 INFO - Running setup.py (path:/tmp/pip-HntLht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:17 INFO - Running setup.py (path:/tmp/pip-AaS8xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:17 INFO - Running setup.py (path:/tmp/pip-4cxe_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:17 INFO - Running setup.py (path:/tmp/pip-Zg1KgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:17 INFO - Running setup.py (path:/tmp/pip-tzyGyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:17 INFO - Running setup.py (path:/tmp/pip-XJMV_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:17 INFO - Running setup.py (path:/tmp/pip-7zaQK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:17 INFO - Running setup.py (path:/tmp/pip-vj00Nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:17 INFO - Running setup.py (path:/tmp/pip-OuvRK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:17 INFO - Running setup.py (path:/tmp/pip-T9Tep4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:18 INFO - Running setup.py (path:/tmp/pip-7eL93P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:18 INFO - Running setup.py (path:/tmp/pip-fm4tSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:18 INFO - Running setup.py (path:/tmp/pip-Ao6Ce2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:18 INFO - Running setup.py (path:/tmp/pip-ykWT29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:18 INFO - Running setup.py (path:/tmp/pip-gFqb9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:18 INFO - Running setup.py (path:/tmp/pip-_eHRfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:03:18 INFO - Running setup.py install for manifestparser 09:03:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:03:18 INFO - Running setup.py install for mozcrash 09:03:18 INFO - Running setup.py install for mozdebug 09:03:18 INFO - Running setup.py install for mozdevice 09:03:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:03:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:03:18 INFO - Running setup.py install for mozfile 09:03:19 INFO - Running setup.py install for mozhttpd 09:03:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Running setup.py install for mozinfo 09:03:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Running setup.py install for mozInstall 09:03:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Running setup.py install for mozleak 09:03:19 INFO - Running setup.py install for mozlog 09:03:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Running setup.py install for moznetwork 09:03:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Running setup.py install for mozprocess 09:03:19 INFO - Running setup.py install for mozprofile 09:03:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Running setup.py install for mozrunner 09:03:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:03:19 INFO - Running setup.py install for mozscreenshot 09:03:20 INFO - Running setup.py install for moztest 09:03:20 INFO - Running setup.py install for mozversion 09:03:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:03:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:03:20 INFO - Cleaning up... 09:03:20 INFO - Return code: 0 09:03:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:20 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 0x298f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4e3b0>, '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 0x2c4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f740>, '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': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', '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 09:03:20 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 09:03:20 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 09:03:20 INFO - Using env: {'DISPLAY': ':0', 09:03:20 INFO - 'HOME': '/home/cltbld', 09:03:20 INFO - 'LANG': 'en_US.UTF-8', 09:03:20 INFO - 'LANGUAGE': 'en_US:en', 09:03:20 INFO - 'LOGNAME': 'cltbld', 09:03:20 INFO - 'MAIL': '/var/mail/cltbld', 09:03:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:20 INFO - 'MOZ_NO_REMOTE': '1', 09:03:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:20 INFO - 'NO_EM_RESTART': '1', 09:03:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:20 INFO - 'PWD': '/builds/slave/test', 09:03:20 INFO - 'SHELL': '/bin/bash', 09:03:20 INFO - 'SHLVL': '1', 09:03:20 INFO - 'TERM': 'linux', 09:03:20 INFO - 'TMOUT': '86400', 09:03:20 INFO - 'USER': 'cltbld', 09:03:20 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', 09:03:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:20 INFO - '_': '/tools/buildbot/bin/python'} 09:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:20 INFO - Running setup.py (path:/tmp/pip-z9fn1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:20 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)) 09:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:20 INFO - Running setup.py (path:/tmp/pip-5ZWYat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:20 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)) 09:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:20 INFO - Running setup.py (path:/tmp/pip-iPqbb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:20 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)) 09:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:20 INFO - Running setup.py (path:/tmp/pip-_MCul6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:20 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)) 09:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:20 INFO - Running setup.py (path:/tmp/pip-Vj84Il-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:20 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)) 09:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:20 INFO - Running setup.py (path:/tmp/pip-QYJpYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:20 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)) 09:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:20 INFO - Running setup.py (path:/tmp/pip-NAAa1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:21 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)) 09:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:21 INFO - Running setup.py (path:/tmp/pip-OVnFyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:21 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)) 09:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:21 INFO - Running setup.py (path:/tmp/pip-uXmpix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:21 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)) 09:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:21 INFO - Running setup.py (path:/tmp/pip-cSFijY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:21 INFO - Running setup.py (path:/tmp/pip-SKhpfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:21 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)) 09:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:21 INFO - Running setup.py (path:/tmp/pip-_FtTMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 09:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:21 INFO - Running setup.py (path:/tmp/pip-L5BXLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:21 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)) 09:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:21 INFO - Running setup.py (path:/tmp/pip-VnhMbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 09:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:21 INFO - Running setup.py (path:/tmp/pip-7Fdh50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:21 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)) 09:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:21 INFO - Running setup.py (path:/tmp/pip-WdSZgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:21 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)) 09:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:21 INFO - Running setup.py (path:/tmp/pip-Vxi8du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:21 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)) 09:03:21 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)) 09:03:21 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)) 09:03:21 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)) 09:03:21 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)) 09:03:21 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)) 09:03:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:23 INFO - Downloading blessings-1.6.tar.gz 09:03:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:03:23 INFO - Installing collected packages: blessings 09:03:23 INFO - Running setup.py install for blessings 09:03:23 INFO - Successfully installed blessings 09:03:23 INFO - Cleaning up... 09:03:24 INFO - Return code: 0 09:03:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:03:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:24 INFO - Reading from file tmpfile_stdout 09:03:24 INFO - Current package versions: 09:03:24 INFO - argparse == 1.2.1 09:03:24 INFO - blessings == 1.6 09:03:24 INFO - blobuploader == 1.2.4 09:03:24 INFO - docopt == 0.6.1 09:03:24 INFO - functools32 == 3.2.3-2 09:03:24 INFO - jsonschema == 2.5.1 09:03:24 INFO - manifestparser == 1.1 09:03:24 INFO - mozInstall == 1.12 09:03:24 INFO - mozcrash == 0.17 09:03:24 INFO - mozdebug == 0.1 09:03:24 INFO - mozdevice == 0.48 09:03:24 INFO - mozfile == 1.2 09:03:24 INFO - mozhttpd == 0.7 09:03:24 INFO - mozinfo == 0.9 09:03:24 INFO - mozleak == 0.1 09:03:24 INFO - mozlog == 3.2 09:03:24 INFO - moznetwork == 0.27 09:03:24 INFO - mozprocess == 0.22 09:03:24 INFO - mozprofile == 0.28 09:03:24 INFO - mozrunner == 6.11 09:03:24 INFO - mozscreenshot == 0.1 09:03:24 INFO - mozsystemmonitor == 0.3 09:03:24 INFO - moztest == 0.7 09:03:24 INFO - mozversion == 1.4 09:03:24 INFO - psutil == 3.1.1 09:03:24 INFO - requests == 1.2.3 09:03:24 INFO - wsgiref == 0.1.2 09:03:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:24 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 0x298f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4e3b0>, '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 0x2c4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f740>, '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': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', '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 09:03:24 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 09:03:24 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 09:03:24 INFO - Using env: {'DISPLAY': ':0', 09:03:24 INFO - 'HOME': '/home/cltbld', 09:03:24 INFO - 'LANG': 'en_US.UTF-8', 09:03:24 INFO - 'LANGUAGE': 'en_US:en', 09:03:24 INFO - 'LOGNAME': 'cltbld', 09:03:24 INFO - 'MAIL': '/var/mail/cltbld', 09:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:24 INFO - 'MOZ_NO_REMOTE': '1', 09:03:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:24 INFO - 'NO_EM_RESTART': '1', 09:03:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:24 INFO - 'PWD': '/builds/slave/test', 09:03:24 INFO - 'SHELL': '/bin/bash', 09:03:24 INFO - 'SHLVL': '1', 09:03:24 INFO - 'TERM': 'linux', 09:03:24 INFO - 'TMOUT': '86400', 09:03:24 INFO - 'USER': 'cltbld', 09:03:24 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', 09:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:24 INFO - '_': '/tools/buildbot/bin/python'} 09:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:24 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)) 09:03:24 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)) 09:03:24 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)) 09:03:24 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)) 09:03:24 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)) 09:03:24 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)) 09:03:24 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)) 09:03:24 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)) 09:03:24 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)) 09:03:24 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)) 09:03:24 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)) 09:03:24 INFO - Cleaning up... 09:03:24 INFO - Return code: 0 09:03:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:24 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 0x298f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4e3b0>, '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 0x2c4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f740>, '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': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', '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 09:03:24 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 09:03:24 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 09:03:24 INFO - Using env: {'DISPLAY': ':0', 09:03:24 INFO - 'HOME': '/home/cltbld', 09:03:24 INFO - 'LANG': 'en_US.UTF-8', 09:03:24 INFO - 'LANGUAGE': 'en_US:en', 09:03:24 INFO - 'LOGNAME': 'cltbld', 09:03:24 INFO - 'MAIL': '/var/mail/cltbld', 09:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:24 INFO - 'MOZ_NO_REMOTE': '1', 09:03:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:24 INFO - 'NO_EM_RESTART': '1', 09:03:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:24 INFO - 'PWD': '/builds/slave/test', 09:03:24 INFO - 'SHELL': '/bin/bash', 09:03:24 INFO - 'SHLVL': '1', 09:03:24 INFO - 'TERM': 'linux', 09:03:24 INFO - 'TMOUT': '86400', 09:03:24 INFO - 'USER': 'cltbld', 09:03:24 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', 09:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:24 INFO - '_': '/tools/buildbot/bin/python'} 09:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:03:24 INFO - Cleaning up... 09:03:24 INFO - Return code: 0 09:03:24 INFO - Running post-action listener: _resource_record_post_action 09:03:24 INFO - Running post-action listener: _start_resource_monitoring 09:03:24 INFO - Starting resource monitoring. 09:03:24 INFO - ##### 09:03:24 INFO - ##### Finished create-virtualenv step (success) 09:03:24 INFO - ##### 09:03:24 INFO - ##### 09:03:24 INFO - ##### Running install step. 09:03:24 INFO - ##### 09:03:24 INFO - Running pre-action listener: _resource_record_pre_action 09:03:24 INFO - Running main action method: install 09:03:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:24 INFO - Reading from file tmpfile_stdout 09:03:24 INFO - Detecting whether we're running mozinstall >=1.0... 09:03:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:03:24 INFO - Reading from file tmpfile_stdout 09:03:24 INFO - Output received: 09:03:24 INFO - Usage: mozinstall [options] installer 09:03:24 INFO - Options: 09:03:24 INFO - -h, --help show this help message and exit 09:03:24 INFO - -d DEST, --destination=DEST 09:03:24 INFO - Directory to install application into. [default: 09:03:24 INFO - "/builds/slave/test"] 09:03:24 INFO - --app=APP Application being installed. [default: firefox] 09:03:24 INFO - mkdir: /builds/slave/test/build/application 09:03:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:03:42 INFO - Reading from file tmpfile_stdout 09:03:42 INFO - Output received: 09:03:42 INFO - /builds/slave/test/build/application/firefox/firefox 09:03:42 INFO - Running post-action listener: _resource_record_post_action 09:03:42 INFO - ##### 09:03:42 INFO - ##### Finished install step (success) 09:03:42 INFO - ##### 09:03:42 INFO - ##### 09:03:42 INFO - ##### Running run-tests step. 09:03:42 INFO - ##### 09:03:42 INFO - Running pre-action listener: _resource_record_pre_action 09:03:42 INFO - Running main action method: run_tests 09:03:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:42 INFO - Python 2.7.3 09:03:42 INFO - Return code: 0 09:03:42 INFO - grabbing minidump binary from tooltool 09:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:42 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 0x2c4e3b0>, '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 0x2c4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:42 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 09:03:42 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 09:03:42 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 09:03:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:42 INFO - Return code: 0 09:03:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:03:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:03:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:42 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/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 09:03:42 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/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 09:03:42 INFO - Using env: {'DISPLAY': ':0', 09:03:42 INFO - 'HOME': '/home/cltbld', 09:03:42 INFO - 'LANG': 'en_US.UTF-8', 09:03:42 INFO - 'LANGUAGE': 'en_US:en', 09:03:42 INFO - 'LOGNAME': 'cltbld', 09:03:42 INFO - 'MAIL': '/var/mail/cltbld', 09:03:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:03:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:42 INFO - 'MOZ_NO_REMOTE': '1', 09:03:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:42 INFO - 'NO_EM_RESTART': '1', 09:03:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:42 INFO - 'PWD': '/builds/slave/test', 09:03:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:42 INFO - 'SHELL': '/bin/bash', 09:03:42 INFO - 'SHLVL': '1', 09:03:42 INFO - 'TERM': 'linux', 09:03:42 INFO - 'TMOUT': '86400', 09:03:42 INFO - 'USER': 'cltbld', 09:03:42 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987', 09:03:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:42 INFO - '_': '/tools/buildbot/bin/python'} 09:03:42 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/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 09:03:42 INFO - mozversion application_buildid: 20160701203027 09:03:42 INFO - mozversion application_changeset: 49a1d28776b4a2867bb8818dfab61c352cc02f49 09:03:42 INFO - mozversion application_display_name: Nightly 09:03:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:42 INFO - mozversion application_name: Firefox 09:03:42 INFO - mozversion application_remotingname: firefox 09:03:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:03:42 INFO - mozversion application_vendor: Mozilla 09:03:42 INFO - mozversion application_version: 50.0a1 09:03:42 INFO - mozversion platform_buildid: 20160701203027 09:03:42 INFO - mozversion platform_changeset: 49a1d28776b4a2867bb8818dfab61c352cc02f49 09:03:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:03:42 INFO - mozversion platform_version: 50.0a1 09:03:42 INFO - using testdate: 1467734622 09:03:42 INFO - actual date: 1467734622 09:03:42 INFO - starting webserver on 'localhost:52435' 09:03:42 INFO - SUITE-START | Running 2 tests 09:03:42 INFO - TEST-START | dromaeo_css 09:03:42 INFO - operating with platform_type : linux_ 09:03:42 INFO - Initialising browser for dromaeo_css test... 09:03:42 INFO - TEST-INFO | started process 28267 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5c3qQh/profile http://localhost:52435/getInfo.html) 09:03:46 INFO - PROCESS | 28267 | __metrics Screen width/height:1600/1200 09:03:46 INFO - PROCESS | 28267 | colorDepth:24 09:03:46 INFO - PROCESS | 28267 | Browser inner width/height: 1024/697 09:03:46 INFO - PROCESS | 28267 | __metrics 09:03:47 INFO - PROCESS | 28267 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:03:48 INFO - TEST-INFO | 28267: exit 0 09:03:48 INFO - Browser initialized. 09:03:48 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:48 INFO - TEST-INFO | started process 28351 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5c3qQh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:00 INFO - PROCESS | 28351 | RSS: Main: 157605888 09:04:00 INFO - PROCESS | 28351 | 09:04:51 INFO - PROCESS | 28351 | 1467734691824 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] 09:06:59 INFO - PROCESS | 28351 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52435/tests/dromaeo/cssquery-ext.html) 09:07:00 INFO - PROCESS | 28351 | RSS: Main: 242659328 09:07:00 INFO - PROCESS | 28351 | 09:10:03 INFO - PROCESS | 28351 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-ext.html (next: http://localhost:52435/tests/dromaeo/cssquery-jquery.html) 09:10:03 INFO - PROCESS | 28351 | RSS: Main: 241471488 09:10:03 INFO - PROCESS | 28351 | 09:13:05 INFO - PROCESS | 28351 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52435/tests/dromaeo/cssquery-mootools.html) 09:13:06 INFO - PROCESS | 28351 | RSS: Main: 238202880 09:13:06 INFO - PROCESS | 28351 | 09:16:08 INFO - PROCESS | 28351 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52435/tests/dromaeo/cssquery-prototype.html) 09:16:08 INFO - PROCESS | 28351 | RSS: Main: 239423488 09:16:08 INFO - PROCESS | 28351 | 09:19:11 INFO - PROCESS | 28351 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52435/tests/dromaeo/cssquery-yui.html) 09:19:12 INFO - PROCESS | 28351 | RSS: Main: 227627008 09:19:12 INFO - PROCESS | 28351 | 09:22:15 INFO - PROCESS | 28351 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-yui.html (next: http://localhost:52435/tests/dromaeo/cssquery-dojo.html) 09:22:15 INFO - PROCESS | 28351 | RSS: Main: 272801792 09:22:15 INFO - PROCESS | 28351 | 09:22:15 INFO - PROCESS | 28351 | __start_tp_report 09:22:15 INFO - PROCESS | 28351 | _x_x_mozilla_page_load 09:22:15 INFO - PROCESS | 28351 | _x_x_mozilla_page_load_details 09:22:15 INFO - PROCESS | 28351 | |i|pagename|runs| 09:22:15 INFO - PROCESS | 28351 | |0;dojo.html;3082.9691703082976;3111.4866047102228;3116.4390409726248;3138.0742680909157;3141.27750617358;5181.533661970422;5190.455002224769;5235.554977826884;5252.474752524741;5276.393214780302;5264.157734762439;5284.788608455667;5290.603204759639;5312.150055991042;5319.6808191508535;4752.310914917343;4793.856184314476;4830.686005409655;4834.250691142878;5005.874853128665;4915.361003069596;4918.532739389752;4928.901421971558;4984.177610694231;4996.725180115092;4755.90488190236;4761.619070474354;4798.640101992364;4807.351007613962;4809.278608208797;4783.7129772213775;4791.616670666338;4864.683795553313;4867.026594681078;4900.191468407708;4836.854894353175;4838.733869637169;4855.053264613402;4857.53853383928;4910.631702622318;5180.197069454241;5262.105442074857;5367.516923476866;5426.75579598915;5440.211169380457;5755.913661295084;5784.161296611967;5794.188813566104;5819.912701309484;5820.156077368758;5576.888462230773;5806.767729290823;5847.473727364515;5861.501772349313;5867.442592953663;5297.602679798992;5341.252224688546;5358.196270559409;5424.538914192299;5427.050266203399;5872.236609240771;5877.382874798084;5880.94119058804;5882.23530940975;5896.7641294348605;5448.83653490396;5499.725013749377;5637.605367624309;5654.208410822488;5656.2929633795775;5629;5678.943210567924;5683.374828768832;5726.9713651431475;5756.510696590753;5656.91514627281;5708.971455142698;5711.200431939532;5712.257406537206;5752.827415177551;5494.917576236359;5568.52667523257;5623.550115990711;5728.914066289009;5747.597668163188;4339.5443478434945;4347.282698354736;4347.586979236968;4379.1022840317655;4390.956090439118;3880.2239552089695;3889.688824894058;3895.35726605107;3926.1951299983434;3927.7250592458254;5288.788448462018;5298.15229563268;5370.113931201387;5382.381026182039;5424.783008679688;5768.653880767168;5775.768969241268;5777.595568310262;5783.161441590946;5815.098659707752;5441.2654291670115;5442.374126975369;5446.291982042309;5449.99175152598;5453.863653408696;4824.421069471686;4855.684380515255;4863.489333619989;4896.657233993657;5007.624428167902;5856.824295271149;5879.647221166743;5895.616784908967;5896.08610665339;5897.911531327033;5703.287089113861;5714.599978001666;5716.056850619601;5716.514096301861;5719.742611582572;5739.598228124159;5818.8254352369495;5857.092150716646;5908.202392676933;5944.762209511744;5651.378348381592;5654.773809047592;5655.208270842168;5661.971690141523;5665.631733937281;5697;5745.2818397700285;5758.049921762862;5758.740856661377;5760.078387458154;5463.043967305785;5475.616706830484;5537.612367134423;5588.217649528988;5592;5574.191742197439;5586.608937374344;5592.245046918614;5593.888122237451;5602.2717046783655;5528.9723551381985;5539.307586551681;5543.8891222176135;5544.7782088716;5588.580856435702;5474.151506516497;5485.286912701324;5543.833684989457;5562.721863906708;5599.748011339581;5455.754491047992;5457.290552228185;5458.099413596712;5466.508014278734;5467.753951072291 09:22:15 INFO - PROCESS | 28351 | |1;ext.html;593.7773334999375;616.2112496005113;628.5254632752271;629.4649547884297;637.7480895046453;31367.529487057684;41722.79138604306;42473.36289955653;42569.36145957813;42574;44400.3339949901;44755.10489790202;44892.32661510072;44892.3266151008;44916;8893.42192757472;9019.819603607924;9022.684206052789;9036.909630903705;9043.683471078513;8991.595378207963;9063.50150741709;9068.36521443496;9073.682421115262;9078.546072696372;9102;9136.360454768168;9144.817103657957;9146.405483643575;9158.221551168126;9036.728898133066;9048.547572621408;9070.183683468487;9081.273498120134;9086;8960.68637597681;9020.00779914209;9026.86459703105;9031.096890310982;9043.32175086871;41661.58338416607;41887.790561047;42052.78973605143;42111.57888421107;42126.57873421257;3322.4517954537473;3376.69609735125;3505.474178873164;3686.4654625079215;3711.851525938959;32993.835030824936;33113.50329745056;33399;33550.664493355245;34607.82696086504;3640.690541303992;3641.6904563112166;3654.4701018352457;3734.7572407793677;3794.753341032824;344155;344333;346586;347220;348101;50333;55646;56911;57342.426575733705;57476.42523574794;55405.72297138489;61531.38468615346;61540.69229653823;61600.69199653973;61694.69152654208;16168.595785105232;16332.428365007168;16355.672886542441;16356.182190890408;16364.509064728078;10431;12015.098867584966;12341.568045118378;12884.871151288555;13041.804372934414;17028.318867245172;17041.659166816593;17148.314067437164;17150.056746879043;17256.309747609954;6686.5653732507235;6692.230393504712;6751.662416879137;6805.958212536219;6813.4889883257965;948.3029972969862;951.1059603972383;1017.5268500147467;1053.5627714498398;1136.4373985769566;30668.69331306703;32515.512267316008;32738.508922366185;32775.67224327774;32955.34089318248;1099.5546803544578;1142.9827453566334;1154.9307041577365;1166.0438440478895;1171.566520387462;17703.29186832538;18009.769824519757;18220.817791822177;18861.245550178115;18873;533924;534965;537583;539219;541691;57204;58843.70578147082;59171.704141479015;59225;59227.70386148042;1225.3995542184443;1339.6115126613367;1354.2213227394288;1367.3436750359683;1399.069618703571;31277.68722312748;33304.66695333113;33327;33583.32833343368;34029.14927126742;50599;50790.492095078574;51152;51282.820405142564;51322.48677513177;405421;406633;406729;407237;407500;24827.255182344557;27571.448571028868;28314.575281370384;28335.858320708263;29946.700532995263;46003.53996459993;46815;46854.76572617252;47594.28608570805;48067.75966120147;39915.2685648365;40264.79867600643;40814.79592602018;40932;41482.79258603688;38024;39261;39277.60722392739;39723.80138099407;39887.40168897527;39955.005624296966;40090.79954600208;41055.58944410637;41137.58862411457;41339;33428;36037.27925441529;36628;37163.44254836126;37247.44128838124;27161.45677086487;27315.180544583694;27390.45219095647;27549.31126721848;27853.86073069622 09:22:15 INFO - PROCESS | 28351 | |2;jquery.html;389.4314301120364;405.3372735577332;406.2301937827816;409.25311306864967;410.4336016297511;18330;19765.80234197661;19932.501687457803;19967.800321996776;21286.467838304052;16719.247633856474;16928.74606880895;18051.81948180521;19686.901565492153;19739.21043158272;5463.562914966793;5532.917006244909;5703;5714.857128571777;5745.856353591173;5630.268065151525;5632.155176723483;5676.318841738997;5690.4351303391695;5696.943030569703;5454.263674403965;5636.577256705764;5646.18130371096;5661.150827375906;5666.858328541778;5524.33707955046;5597.972010139964;5614;5618.381977982419;5654.660720356771;4870.0746858096845;5066.544011039025;5077.568406685399;5078.64449488536;5078.720670363128;23888;24148.275551733477;24633.63049554258;24890.377740556632;24903;2135.9871960431337;2365.597848365782;2403.1228601560306;2465.901363945458;2712.9457410851674;10103.84844227337;10108.44403557804;10243.94878025605;10560.841587376195;10583.788324233548;1900.0594705620806;1943.3684052682934;1944.5235917200378;1959.2750682247517;1999.720039194514;85186;88106.55946720226;88801;88825.11748825066;88990.99999999936;11361.94319028416;11686.18196726221;11722.648320550397;11748.35384053868;11999.88000120005;16205.189740512928;16230;16252.268647910872;16308.673826523403;16333.83666163347;8011.759647210593;8424.747257582283;8499.915000850036;8500;8586.527740974307;8060.637271322805;8091.312238459679;8160.30637395828;8280.420370574122;8295.253427191581;4960.057589058068;4964.801407943714;4983.053219888209;5117.287757903737;5124.92312615311;2004.629143608437;2016.7932986551455;2047.0890453747938;2064.5251592133895;2084.8853313067625;900.3112618846587;969.8545218217183;1017.7913527726798;1046.398320965448;1051.453244312953;19456.902715486332;20157.092930818148;20249.29127480531;20271.594568108554;20493;964.3249725192394;982.1700662939804;1009.6970908727352;1051.1748277602046;1054.1145437832258;10729.195310351755;10860.945695271474;12179.878201217874;12269.754604907852;12285.754284914252;465360;469397;469637;471060;472560;98309;98614;99491.50254248828;101407;102508;1025.251566356564;1025.4513835098187;1044.1907521670767;1049.7008352619466;1060.6764936694383;18140.637187256445;18337.816621834143;18618.44144675662;18680.34618788336;18837.6232475347;43195;43268.78365608152;44277.77861110674;44573.55426445694;45118.548814512746;53587.464125358245;54486.727566361915;56112.719436402556;60389;63987;7794.883076753967;7966.203379661987;8045.195480451908;8491.660333586588;9086.954565227132;10074.748131296776;13296.534621288209;13846.86153138496;14091.718165636836;14697.706045879237;12215.572454964034;12387.256764593792;12544;12552.372381381078;12563.497460101858;12031.63905082849;12159.209651372632;12247.968405111305;12304.507819687113;12313.753724925631;12478.313692746622;12482.126251162332;12492.37538123072;12529.436175371948;12608.684782880135;7874.755694627004;7984.321332686554;8188.05837328691;8261.297789687944;8267.710630127916;11358.488868001125;12165.756684866075;12314.199577027464;12657.11400201977;12714.554990575285 09:22:15 INFO - PROCESS | 28351 | |3;mootools.html;1329.8271224740781;1429.0425414971967;1454.418232706917;1580.2572790788327;1791.2208189437588;7934.603269836507;8810.647574097036;9111.043340449256;9133.451992880431;9223;8897.154770296811;9057.773555661111;9061.139191776787;9064.77338066547;9075.36472446929;2292.5644127615783;2328.196772113618;2331.755165707602;2333.6616190652344;2334.5214231082587;2300.5973954557967;2310.1221535816367;2330.822934418125;2347.5187586544716;2354.3290162303747;2282.2582660635276;2297.287840769367;2299.0458959531775;2326.6277395616703;2326.697529321186;2239.328201539548;2266.9433264168365;2277.487565297803;2282.851614645051;2283.2122917593406;2260.288009277089;2268.138107519149;2271.7728227177317;2273.2043784675398;2275.988620056906;8626.353023523261;8912.955435222782;9075.63697452101;9138;9170.220531254852;5330.520253177233;5418.214358917935;5435.972820135914;5441.700706461143;5442.863928401782;3389.1357703785625;3449.120474279068;3478.4086705260165;3478.9826050869838;3531.8057506837113;1124.2074337591978;1134.7843909657138;1135.9943200284029;1143.022715578177;1144.1361771862191;13181.538646147339;13198.14212076212;13221.801672974912;13250.204987700865;13263.137896036727;4509.729416235036;4615.192341340252;4769.117713223065;4775.06886157196;4785.521447855256;10214.795704085876;10218.642347517802;10223.846642300372;10255.179585633132;10257.076863082359;5456.508914197741;5465.289512363367;5467.6719396836315;5473.917891231635;5479.479449452296;4213.094184750232;4386.846460373871;4537.047220083753;4857.9028419431415;5122.154844450699;3887.475190849199;3925.823337949799;3951.249262640089;3974.8870316311477;4022.9798851005557;1253.943572539238;1272.8918041966351;1275.5344299330677;1283.4288741509938;1290.3612711707765;889.7997950461095;922.0548937339281;952.0574631115146;977.203579083045;1017.5065093429681;6171.259448866164;6193.628382297076;6240.469560087444;6302.212223472066;6333.303336632966;981.3081777346953;1011.6509804117715;1014.5840205515703;1035.3839465518156;1040.380973320888;4189.371594260824;4327.870163895025;4383.868483945486;4466.553344665507;4467.173572889026;11099.667009989713;11690.590829321103;11851.348175850238;11872.940635296942;11896.762064758655;11375.772484550263;11647.825282620768;11669.124815638861;11683.824742628867;11749.236299640666;8291.419600628018;8664.696735614223;8668.523231222218;8766.38635295536;8834.646614135363;6356.237251529661;6553.541252112306;6616.206055273398;6738.35985581369;6741.730330786713;10255.179585633281;10593.152547796311;10654.680359589225;10663.146948243966;10672.733181670521;9898.307118501636;10500.789984200426;10945.288556243819;10955.945220274167;10991.505382257978;7852.214778522102;8140.145284745254;8177.713780017671;8190.1400352964465;8192.426530142833;8624.396292259733;8787.34094942869;8800.691975780817;8815.735527934172;8824.823503530022;7879.566623835515;8105.148959359182;8185.590720464072;8245.95877020611;8254.025169448094;7735.6518956648215;7764.650590723318;7794.22057794216;7875.763727088197;7876.133625301327;7236.565806051653;7263.564186148848;7289.562626242442;7295.7081716732855;7336.7798966031105;5208.374995000624;5354.598405119554;5469.179623056573;5572.554195664256;5573.637713548606;5438.673679579238;5520;5611.663300202001;5659.236003139688;5678.176664383724 09:22:15 INFO - PROCESS | 28351 | |4;prototype.html;334.62689101651665;343.5516650770744;352.2022618768488;352.79537868036533;353.48920809430354;5341.919871201931;5381.19282107684;5403.189521571766;5436.401995780461;5453.072977593809;5329.893402131955;5330.200469929503;5330.973345133269;5360.303160589127;5373.038840823101;4088.3049881520215;4243.681723870706;4266.594673506028;4279.422277992478;4309.547497512763;3929.35165697661;4219.5358510563965;4219.831206751726;4228.788560571975;4229.429027081335;4155.667546596265;4177.206330797154;4194.161167766443;4196.979015104936;4233.259179643565;4014.6788256939376;4140.660526802347;4238.788060596958;4239.660827133822;4250.638695710868;3720.144366795625;3722.8510859565586;3725.4784330193793;3735.2156047230114;3746.19456816783;5196.402413722432;5228.424873263938;5245;5266.8683282917855;5294.549963253128;4747.216709242971;4851.466338702739;4858.757062146914;4863.781129849165;4865.367502224688;5583.944160558383;5586.832395028156;5597.748101335409;5601.859953501154;5674.205611214434;4073.5926407359025;4109.609587089221;4115.34154535276;4120.505539335299;4133.503979522477;5292.126799078147;5604.187392828057;5830.32951210612;5832.387599302097;5839;4182.372644103409;4186.539480657125;4188.727732697365;4198.349255865346;4200.6219440250525;4264.936025959613;4275.914481710411;4278.165757677223;4279.293916503804;4281.314989601711;4539.568740969603;4817.253325734517;4838.806447742121;4840.7337596432535;4841.9031619367415;5617.269754931833;5632.60571759981;5640.674441506243;5673.234113394721;5678.432156784289;1437.4034775568023;1456.4392708807186;1458.846821083792;1459.7300348696701;1473.786300986351;912.564383007222;933.4492649336923;933.9813203735887;934.1545900959643;949.6343907595625;4817.927731084036;4873.707577545359;4877.366222240022;4894.902101957941;4908.3619129513645;5180.083369153362;5182.611304152204;5189.792408303701;5217.973910130425;5220.686758794484;4648.8000710192355;4882.95117048825;4937.876553086202;4953;4964.131277026506;2731.808773385884;2753.2979090331837;2779.2634951737805;2779.7498225159834;2784.944301114007;5317.112425089115;5321.813736519281;5324.334458192726;5357.2053362124125;5366.892662146814;5226.216067589892;5252.789888404421;5524.4199359066715;5941.376155503696;5968.1644569760265;5920.615160014499;5958.225430693982;6000.339962604023;6003.98866379759;6005.549583781234;5621.297337832771;5782.826515204551;5826.941730582809;5869.4717475427415;5893.734781934738;5704.344000440003;5719.399463056319;5727.599068065196;5730;5732;5621.578381621476;5691.402402747817;5869.383714710065;5952.732127054206;6037.426444487766;4100.118474527991;4116;4127.504699436087;4134;4148.253314403436;5664.546836253172;5775.48020678141;5842.1820945066875;5848.473637372486;5850.3857095006115;5564.610477266552;5577.52591029767;5583.748731307182;5586.497215250647;5598.1322894951345;5496.77380660833;5525.226468294361;5593.552515798645;5666.291713535808;5674.687892165805;5344.904294619671;5469.917951230815;5552.888942221214;5570.192322113352;5581.832545023656;5574.136008918624;5581.6371935824045;5596.524295434933;5598.888022239614;5651.406602306863;5395.325584301962;5497.972510137583;5530.834074977757;5595.916061259166;5598.272224610774 09:22:15 INFO - PROCESS | 28351 | |5;yui.html;662.1623646087697;680.3978479046044;681.8465845184834;687.6596084937956;734.3941248470011;13345.733085338288;14754;15157.393704251817;15388.15365154916;15424.92287538562;14506.129632222073;14650.780238296435;14753.704925901502;14800.6299842504;14955.401783928657;665.6272487407043;781.2539025230919;802.9759107226793;817.8323443694059;818.6602559937619;822.7572866004513;824.0688022534528;825.8307047055339;826.5454000299841;827.4166712467696;860.7977125375531;867.9479231246146;868.110187058264;868.8349213649386;869.3914260017981;702.034702284359;831.1522247307772;861.4831101339147;861.9913800861974;862.6635612111281;627.9434850863444;630.1839118341712;630.6058713304185;630.7477009196313;632.2507828223523;14022;14125;14256.287185640676;14296.356663950148;14315.57053288403;13616.319184040758;13978.370973306226;13989.790153147711;14011.859881401157;14294.785578216335;12631.684207894785;12771.872281277161;12810.167339122927;12945;13398.732025359535;4414.293713005904;4510.684252102355;4538.205813982539;4630.398048253739;4649.581537661593;73705;74368;74382.25617743861;74510.88233676553;74511.62744186244;10968.341899486057;11051.889481105247;11214.383208923588;11362.261453005529;11530.481128349244;20637.793622063888;20655.79344206569;20656.58686826285;20665.483362916046;20698;8437.873431898526;8485.660573577112;8518.659253629909;8521.44610600309;8720.825583488295;4783.473817880031;4800.183968725325;4823.131836269435;4964.478729733398;5062.417821950523;3458.4985177149174;3565.090901820019;3569.1077230692326;3574.2136729919375;3575.4994300797907;530.9017831701148;618.8669436071266;626.6709977261974;627.1282916745636;649.9025146228009;547.8301726464808;612.6324205476678;617.996910015447;671.77495538994;684.6816230452863;9988.400695958266;10147.137493313003;10196.898031019744;10200.540975656113;10401.375917444977;547.2721280696743;557.4676184244037;608.2062907905226;651.5406638319973;654.4110300729382;4726.787294571753;4977.178765503724;5018.924716129261;5024.799008039638;5028.522290382408;84880;118862.72670106783;126949;127354;128874.35562822125;109811;111966;113303;113933;115194;98384;98873.50563247282;99663.50168249113;100541;101644;9722.319437639297;9734.221262299141;9740.951295243478;9759.374922238649;9828.26288028415;47924.7603761979;55747.44252557585;57089.71455142864;57438;59568.702156488944;57634;61444;75474.62262688871;76766.46467070738;78184;11516.42417879086;11541.019013383957;12114.394280285775;12149.817752733892;13487;13739;14783;15272.236388180769;15589.298481568583;15703.450379236672;14257.144571325754;14977.326020328894;15119.697606048037;15124.54626361211;15533.533993980198;13070.673233169247;14191.432342706175;14376.065555738844;14412.991090623556;14715.337809798371;13644.823632763346;13701.383437745126;13955.441782328999;14000.929995349958;14124.646883827987;10622.628208012682;10665.733356666145;10804.783904322027;11001.339919604849;11725.179237453638;10232.744181395525;10247.948760256151;10462.790744184922;11707.356095414834;12154.75690486203 09:22:15 INFO - PROCESS | 28351 | __end_tp_report 09:22:15 INFO - PROCESS | 28351 | __start_cc_report 09:22:15 INFO - PROCESS | 28351 | _x_x_mozilla_cycle_collect,1032 09:22:15 INFO - PROCESS | 28351 | __end_cc_report 09:22:15 INFO - PROCESS | 28351 | __startTimestamp1467735735446__endTimestamp 09:22:15 INFO - PROCESS | 28351 | 09:22:15 INFO - PROCESS | 28351 | ------- Summary: start ------- 09:22:15 INFO - PROCESS | 28351 | Number of tests: 6 09:22:15 INFO - PROCESS | 28351 | 09:22:15 INFO - PROCESS | 28351 | [#0] dojo.html Cycles:165 Average:5301.99 Median:5474.15 stddev:601.60 (11.0%) stddev-sans-first:577.71 09:22:15 INFO - PROCESS | 28351 | Values: 3083.0 3111.5 3116.4 3138.1 3141.3 5181.5 5190.5 5235.6 5252.5 5276.4 5264.2 5284.8 5290.6 5312.2 5319.7 4752.3 4793.9 4830.7 4834.3 5005.9 4915.4 4918.5 4928.9 4984.2 4996.7 4755.9 4761.6 4798.6 4807.4 4809.3 4783.7 4791.6 4864.7 4867.0 4900.2 4836.9 4838.7 4855.1 4857.5 4910.6 5180.2 5262.1 5367.5 5426.8 5440.2 5755.9 5784.2 5794.2 5819.9 5820.2 5576.9 5806.8 5847.5 5861.5 5867.4 5297.6 5341.3 5358.2 5424.5 5427.1 5872.2 5877.4 5880.9 5882.2 5896.8 5448.8 5499.7 5637.6 5654.2 5656.3 5629.0 5678.9 5683.4 5727.0 5756.5 5656.9 5709.0 5711.2 5712.3 5752.8 5494.9 5568.5 5623.6 5728.9 5747.6 4339.5 4347.3 4347.6 4379.1 4391.0 3880.2 3889.7 3895.4 3926.2 3927.7 5288.8 5298.2 5370.1 5382.4 5424.8 5768.7 5775.8 5777.6 5783.2 5815.1 5441.3 5442.4 5446.3 5450.0 5453.9 4824.4 4855.7 4863.5 4896.7 5007.6 5856.8 5879.6 5895.6 5896.1 5897.9 5703.3 5714.6 5716.1 5716.5 5719.7 5739.6 5818.8 5857.1 5908.2 5944.8 5651.4 5654.8 5655.2 5662.0 5665.6 5697.0 5745.3 5758.0 5758.7 5760.1 5463.0 5475.6 5537.6 5588.2 5592.0 5574.2 5586.6 5592.2 5593.9 5602.3 5529.0 5539.3 5543.9 5544.8 5588.6 5474.2 5485.3 5543.8 5562.7 5599.7 5455.8 5457.3 5458.1 5466.5 5467.8 09:22:15 INFO - PROCESS | 28351 | 09:22:15 INFO - PROCESS | 28351 | [#1] ext.html Cycles:180 Average:59114.06 Median:406983.00 stddev:115932.34 (28.5%) stddev-sans-first:116174.30 09:22:15 INFO - PROCESS | 28351 | Values: 593.8 616.2 628.5 629.5 637.7 31367.5 41722.8 42473.4 42569.4 42574.0 44400.3 44755.1 44892.3 44892.3 44916.0 8893.4 9019.8 9022.7 9036.9 9043.7 8991.6 9063.5 9068.4 9073.7 9078.5 9102.0 9136.4 9144.8 9146.4 9158.2 9036.7 9048.5 9070.2 9081.3 9086.0 8960.7 9020.0 9026.9 9031.1 9043.3 41661.6 41887.8 42052.8 42111.6 42126.6 3322.5 3376.7 3505.5 3686.5 3711.9 32993.8 33113.5 33399.0 33550.7 34607.8 3640.7 3641.7 3654.5 3734.8 3794.8 344155.0 344333.0 346586.0 347220.0 348101.0 50333.0 55646.0 56911.0 57342.4 57476.4 55405.7 61531.4 61540.7 61600.7 61694.7 16168.6 16332.4 16355.7 16356.2 16364.5 10431.0 12015.1 12341.6 12884.9 13041.8 17028.3 17041.7 17148.3 17150.1 17256.3 6686.6 6692.2 6751.7 6806.0 6813.5 948.3 951.1 1017.5 1053.6 1136.4 30668.7 32515.5 32738.5 32775.7 32955.3 1099.6 1143.0 1154.9 1166.0 1171.6 17703.3 18009.8 18220.8 18861.2 18873.0 533924.0 534965.0 537583.0 539219.0 541691.0 57204.0 58843.7 59171.7 59225.0 59227.7 1225.4 1339.6 1354.2 1367.3 1399.1 31277.7 33304.7 33327.0 33583.3 34029.1 50599.0 50790.5 51152.0 51282.8 51322.5 405421.0 406633.0 406729.0 407237.0 407500.0 24827.3 27571.4 28314.6 28335.9 29946.7 46003.5 46815.0 46854.8 47594.3 48067.8 39915.3 40264.8 40814.8 40932.0 41482.8 38024.0 39261.0 39277.6 39723.8 39887.4 39955.0 40090.8 41055.6 41137.6 41339.0 33428.0 36037.3 36628.0 37163.4 37247.4 27161.5 27315.2 27390.5 27549.3 27853.9 09:22:15 INFO - PROCESS | 28351 | 09:22:15 INFO - PROCESS | 28351 | [#2] jquery.html Cycles:180 Average:29129.69 Median:13145.56 stddev:77857.28 (592.3%) stddev-sans-first:78045.79 09:22:15 INFO - PROCESS | 28351 | Values: 389.4 405.3 406.2 409.3 410.4 18330.0 19765.8 19932.5 19967.8 21286.5 16719.2 16928.7 18051.8 19686.9 19739.2 5463.6 5532.9 5703.0 5714.9 5745.9 5630.3 5632.2 5676.3 5690.4 5696.9 5454.3 5636.6 5646.2 5661.2 5666.9 5524.3 5598.0 5614.0 5618.4 5654.7 4870.1 5066.5 5077.6 5078.6 5078.7 23888.0 24148.3 24633.6 24890.4 24903.0 2136.0 2365.6 2403.1 2465.9 2712.9 10103.8 10108.4 10243.9 10560.8 10583.8 1900.1 1943.4 1944.5 1959.3 1999.7 85186.0 88106.6 88801.0 88825.1 88991.0 11361.9 11686.2 11722.6 11748.4 11999.9 16205.2 16230.0 16252.3 16308.7 16333.8 8011.8 8424.7 8499.9 8500.0 8586.5 8060.6 8091.3 8160.3 8280.4 8295.3 4960.1 4964.8 4983.1 5117.3 5124.9 2004.6 2016.8 2047.1 2064.5 2084.9 900.3 969.9 1017.8 1046.4 1051.5 19456.9 20157.1 20249.3 20271.6 20493.0 964.3 982.2 1009.7 1051.2 1054.1 10729.2 10860.9 12179.9 12269.8 12285.8 465360.0 469397.0 469637.0 471060.0 472560.0 98309.0 98614.0 99491.5 101407.0 102508.0 1025.3 1025.5 1044.2 1049.7 1060.7 18140.6 18337.8 18618.4 18680.3 18837.6 43195.0 43268.8 44277.8 44573.6 45118.5 53587.5 54486.7 56112.7 60389.0 63987.0 7794.9 7966.2 8045.2 8491.7 9087.0 10074.7 13296.5 13846.9 14091.7 14697.7 12215.6 12387.3 12544.0 12552.4 12563.5 12031.6 12159.2 12248.0 12304.5 12313.8 12478.3 12482.1 12492.4 12529.4 12608.7 7874.8 7984.3 8188.1 8261.3 8267.7 11358.5 12165.8 12314.2 12657.1 12714.6 09:22:15 INFO - PROCESS | 28351 | 09:22:15 INFO - PROCESS | 28351 | [#3] mootools.html Cycles:180 Average:6023.53 Median:4488.45 stddev:3487.89 (77.7%) stddev-sans-first:3479.84 09:22:15 INFO - PROCESS | 28351 | Values: 1329.8 1429.0 1454.4 1580.3 1791.2 7934.6 8810.6 9111.0 9133.5 9223.0 8897.2 9057.8 9061.1 9064.8 9075.4 2292.6 2328.2 2331.8 2333.7 2334.5 2300.6 2310.1 2330.8 2347.5 2354.3 2282.3 2297.3 2299.0 2326.6 2326.7 2239.3 2266.9 2277.5 2282.9 2283.2 2260.3 2268.1 2271.8 2273.2 2276.0 8626.4 8913.0 9075.6 9138.0 9170.2 5330.5 5418.2 5436.0 5441.7 5442.9 3389.1 3449.1 3478.4 3479.0 3531.8 1124.2 1134.8 1136.0 1143.0 1144.1 13181.5 13198.1 13221.8 13250.2 13263.1 4509.7 4615.2 4769.1 4775.1 4785.5 10214.8 10218.6 10223.8 10255.2 10257.1 5456.5 5465.3 5467.7 5473.9 5479.5 4213.1 4386.8 4537.0 4857.9 5122.2 3887.5 3925.8 3951.2 3974.9 4023.0 1253.9 1272.9 1275.5 1283.4 1290.4 889.8 922.1 952.1 977.2 1017.5 6171.3 6193.6 6240.5 6302.2 6333.3 981.3 1011.7 1014.6 1035.4 1040.4 4189.4 4327.9 4383.9 4466.6 4467.2 11099.7 11690.6 11851.3 11872.9 11896.8 11375.8 11647.8 11669.1 11683.8 11749.2 8291.4 8664.7 8668.5 8766.4 8834.6 6356.2 6553.5 6616.2 6738.4 6741.7 10255.2 10593.2 10654.7 10663.1 10672.7 9898.3 10500.8 10945.3 10955.9 10991.5 7852.2 8140.1 8177.7 8190.1 8192.4 8624.4 8787.3 8800.7 8815.7 8824.8 7879.6 8105.1 8185.6 8246.0 8254.0 7735.7 7764.7 7794.2 7875.8 7876.1 7236.6 7263.6 7289.6 7295.7 7336.8 5208.4 5354.6 5469.2 5572.6 5573.6 5438.7 5520.0 5611.7 5659.2 5678.2 09:22:15 INFO - PROCESS | 28351 | 09:22:15 INFO - PROCESS | 28351 | [#4] prototype.html Cycles:180 Average:4668.14 Median:5279.50 stddev:1360.04 (25.8%) stddev-sans-first:1324.39 09:22:15 INFO - PROCESS | 28351 | Values: 334.6 343.6 352.2 352.8 353.5 5341.9 5381.2 5403.2 5436.4 5453.1 5329.9 5330.2 5331.0 5360.3 5373.0 4088.3 4243.7 4266.6 4279.4 4309.5 3929.4 4219.5 4219.8 4228.8 4229.4 4155.7 4177.2 4194.2 4197.0 4233.3 4014.7 4140.7 4238.8 4239.7 4250.6 3720.1 3722.9 3725.5 3735.2 3746.2 5196.4 5228.4 5245.0 5266.9 5294.5 4747.2 4851.5 4858.8 4863.8 4865.4 5583.9 5586.8 5597.7 5601.9 5674.2 4073.6 4109.6 4115.3 4120.5 4133.5 5292.1 5604.2 5830.3 5832.4 5839.0 4182.4 4186.5 4188.7 4198.3 4200.6 4264.9 4275.9 4278.2 4279.3 4281.3 4539.6 4817.3 4838.8 4840.7 4841.9 5617.3 5632.6 5640.7 5673.2 5678.4 1437.4 1456.4 1458.8 1459.7 1473.8 912.6 933.4 934.0 934.2 949.6 4817.9 4873.7 4877.4 4894.9 4908.4 5180.1 5182.6 5189.8 5218.0 5220.7 4648.8 4883.0 4937.9 4953.0 4964.1 2731.8 2753.3 2779.3 2779.7 2784.9 5317.1 5321.8 5324.3 5357.2 5366.9 5226.2 5252.8 5524.4 5941.4 5968.2 5920.6 5958.2 6000.3 6004.0 6005.5 5621.3 5782.8 5826.9 5869.5 5893.7 5704.3 5719.4 5727.6 5730.0 5732.0 5621.6 5691.4 5869.4 5952.7 6037.4 4100.1 4116.0 4127.5 4134.0 4148.3 5664.5 5775.5 5842.2 5848.5 5850.4 5564.6 5577.5 5583.7 5586.5 5598.1 5496.8 5525.2 5593.6 5666.3 5674.7 5344.9 5469.9 5552.9 5570.2 5581.8 5574.1 5581.6 5596.5 5598.9 5651.4 5395.3 5498.0 5530.8 5595.9 5598.3 09:22:15 INFO - PROCESS | 28351 | 09:22:15 INFO - PROCESS | 28351 | [#5] yui.html Cycles:180 Average:21592.91 Median:4462.49 stddev:32018.35 (717.5%) stddev-sans-first:32069.60 09:22:15 INFO - PROCESS | 28351 | Values: 662.2 680.4 681.8 687.7 734.4 13345.7 14754.0 15157.4 15388.2 15424.9 14506.1 14650.8 14753.7 14800.6 14955.4 665.6 781.3 803.0 817.8 818.7 822.8 824.1 825.8 826.5 827.4 860.8 867.9 868.1 868.8 869.4 702.0 831.2 861.5 862.0 862.7 627.9 630.2 630.6 630.7 632.3 14022.0 14125.0 14256.3 14296.4 14315.6 13616.3 13978.4 13989.8 14011.9 14294.8 12631.7 12771.9 12810.2 12945.0 13398.7 4414.3 4510.7 4538.2 4630.4 4649.6 73705.0 74368.0 74382.3 74510.9 74511.6 10968.3 11051.9 11214.4 11362.3 11530.5 20637.8 20655.8 20656.6 20665.5 20698.0 8437.9 8485.7 8518.7 8521.4 8720.8 4783.5 4800.2 4823.1 4964.5 5062.4 3458.5 3565.1 3569.1 3574.2 3575.5 530.9 618.9 626.7 627.1 649.9 547.8 612.6 618.0 671.8 684.7 9988.4 10147.1 10196.9 10200.5 10401.4 547.3 557.5 608.2 651.5 654.4 4726.8 4977.2 5018.9 5024.8 5028.5 84880.0 118862.7 126949.0 127354.0 128874.4 109811.0 111966.0 113303.0 113933.0 115194.0 98384.0 98873.5 99663.5 100541.0 101644.0 9722.3 9734.2 9741.0 9759.4 9828.3 47924.8 55747.4 57089.7 57438.0 59568.7 57634.0 61444.0 75474.6 76766.5 78184.0 11516.4 11541.0 12114.4 12149.8 13487.0 13739.0 14783.0 15272.2 15589.3 15703.5 14257.1 14977.3 15119.7 15124.5 15533.5 13070.7 14191.4 14376.1 14413.0 14715.3 13644.8 13701.4 13955.4 14000.9 14124.6 10622.6 10665.7 10804.8 11001.3 11725.2 10232.7 10247.9 10462.8 11707.4 12154.8 09:22:15 INFO - PROCESS | 28351 | -------- Summary: end -------- 09:22:15 INFO - PROCESS | 28351 | 09:22:16 INFO - TEST-INFO | 28351: exit 0 09:22:16 INFO - TEST-OK | dromaeo_css | took 1113742ms 09:22:16 INFO - TEST-START | kraken 09:22:16 INFO - operating with platform_type : linux_ 09:22:16 INFO - Initialising browser for kraken test... 09:22:16 INFO - TEST-INFO | started process 28547 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdWgQsR/profile http://localhost:52435/getInfo.html) 09:22:20 INFO - PROCESS | 28547 | __metrics Screen width/height:1600/1200 09:22:20 INFO - PROCESS | 28547 | colorDepth:24 09:22:20 INFO - PROCESS | 28547 | Browser inner width/height: 1024/697 09:22:20 INFO - PROCESS | 28547 | __metrics 09:22:21 INFO - PROCESS | 28547 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:22 INFO - TEST-INFO | 28547: exit 0 09:22:22 INFO - Browser initialized. 09:22:22 INFO - Running cycle 1/1 for kraken test... 09:22:22 INFO - TEST-INFO | started process 28631 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdWgQsR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:33 INFO - PROCESS | 28631 | RSS: Main: 157716480 09:22:33 INFO - PROCESS | 28631 | 09:22:36 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52435/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:37 INFO - PROCESS | 28631 | RSS: Main: 215400448 09:22:37 INFO - PROCESS | 28631 | 09:22:39 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52435/tests/kraken/driver.html?testName=audio-dft) 09:22:40 INFO - PROCESS | 28631 | RSS: Main: 203177984 09:22:40 INFO - PROCESS | 28631 | 09:22:43 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52435/tests/kraken/driver.html?testName=audio-fft) 09:22:44 INFO - PROCESS | 28631 | RSS: Main: 268738560 09:22:44 INFO - PROCESS | 28631 | 09:22:46 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52435/tests/kraken/driver.html?testName=audio-oscillator) 09:22:47 INFO - PROCESS | 28631 | RSS: Main: 180518912 09:22:47 INFO - PROCESS | 28631 | 09:22:49 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52435/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:49 INFO - PROCESS | 28631 | RSS: Main: 185217024 09:22:49 INFO - PROCESS | 28631 | 09:22:52 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52435/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:53 INFO - PROCESS | 28631 | RSS: Main: 218783744 09:22:53 INFO - PROCESS | 28631 | 09:22:56 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52435/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:57 INFO - PROCESS | 28631 | RSS: Main: 221130752 09:22:57 INFO - PROCESS | 28631 | 09:23:00 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52435/tests/kraken/driver.html?testName=json-parse-financial) 09:23:00 INFO - PROCESS | 28631 | RSS: Main: 221339648 09:23:00 INFO - PROCESS | 28631 | 09:23:02 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52435/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:23:02 INFO - PROCESS | 28631 | RSS: Main: 209182720 09:23:02 INFO - PROCESS | 28631 | 09:23:04 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:23:05 INFO - PROCESS | 28631 | RSS: Main: 212873216 09:23:05 INFO - PROCESS | 28631 | 09:23:07 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:23:07 INFO - PROCESS | 28631 | RSS: Main: 211660800 09:23:07 INFO - PROCESS | 28631 | 09:23:10 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:23:11 INFO - PROCESS | 28631 | RSS: Main: 225951744 09:23:11 INFO - PROCESS | 28631 | 09:23:13 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:23:14 INFO - PROCESS | 28631 | RSS: Main: 224768000 09:23:14 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52435/tests/kraken/driver.html?testName=ai-astar) 09:23:16 INFO - PROCESS | 28631 | RSS: Main: 267337728 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | __start_tp_report 09:23:16 INFO - PROCESS | 28631 | _x_x_mozilla_page_load 09:23:16 INFO - PROCESS | 28631 | _x_x_mozilla_page_load_details 09:23:16 INFO - PROCESS | 28631 | |i|pagename|runs| 09:23:16 INFO - PROCESS | 28631 | |0;ai-astar;101;110;110;110;110;110;110;110;110;110 09:23:16 INFO - PROCESS | 28631 | |1;audio-beat-detection;150;121;121;178;118;121;142;116;156;120 09:23:16 INFO - PROCESS | 28631 | |2;audio-dft;160;160;162;162;160;160;160;163;161;161 09:23:16 INFO - PROCESS | 28631 | |3;audio-fft;85;74;75;127;75;75;74;74;105;74 09:23:16 INFO - PROCESS | 28631 | |4;audio-oscillator;84;84;117;84;85;84;116;93;85;131 09:23:16 INFO - PROCESS | 28631 | |5;imaging-gaussian-blur;118;117;116;117;116;117;117;117;118;117 09:23:16 INFO - PROCESS | 28631 | |6;imaging-darkroom;145;147;146;145;145;145;145;145;145;145 09:23:16 INFO - PROCESS | 28631 | |7;imaging-desaturate;96;95;95;95;96;95;95;96;96;95 09:23:16 INFO - PROCESS | 28631 | |8;json-parse-financial;55;52;52;52;52;53;52;52;53;52 09:23:16 INFO - PROCESS | 28631 | |9;json-stringify-tinderbox;59;60;61;60;60;60;60;60;60;61 09:23:16 INFO - PROCESS | 28631 | |10;stanford-crypto-aes;76;76;74;75;75;75;77;82;82;77 09:23:16 INFO - PROCESS | 28631 | |11;stanford-crypto-ccm;119;122;125;117;118;122;122;127;160;131 09:23:16 INFO - PROCESS | 28631 | |12;stanford-crypto-pbkdf2;164;162;157;160;158;162;160;159;156;157 09:23:16 INFO - PROCESS | 28631 | |13;stanford-crypto-sha256-iterative;63;62;62;61;62;63;62;62;62;62 09:23:16 INFO - PROCESS | 28631 | __end_tp_report 09:23:16 INFO - PROCESS | 28631 | __start_cc_report 09:23:16 INFO - PROCESS | 28631 | _x_x_mozilla_cycle_collect,1700 09:23:16 INFO - PROCESS | 28631 | __end_cc_report 09:23:16 INFO - PROCESS | 28631 | __startTimestamp1467735796295__endTimestamp 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | ------- Summary: start ------- 09:23:16 INFO - PROCESS | 28631 | Number of tests: 14 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.00 stddev:2.85 (2.6%) stddev-sans-first:0.00 09:23:16 INFO - PROCESS | 28631 | Values: 101.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:131.50 stddev:21.14 (16.1%) stddev-sans-first:21.65 09:23:16 INFO - PROCESS | 28631 | Values: 150.0 121.0 121.0 178.0 118.0 121.0 142.0 116.0 156.0 120.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 09:23:16 INFO - PROCESS | 28631 | Values: 160.0 160.0 162.0 162.0 160.0 160.0 160.0 163.0 161.0 161.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#3] audio-fft Cycles:10 Average:83.80 Median:74.50 stddev:18.05 (24.2%) stddev-sans-first:19.14 09:23:16 INFO - PROCESS | 28631 | Values: 85.0 74.0 75.0 127.0 75.0 75.0 74.0 74.0 105.0 74.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:84.00 stddev:17.93 (21.3%) stddev-sans-first:18.45 09:23:16 INFO - PROCESS | 28631 | Values: 84.0 84.0 117.0 84.0 85.0 84.0 116.0 93.0 85.0 131.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 09:23:16 INFO - PROCESS | 28631 | Values: 118.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 118.0 117.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:23:16 INFO - PROCESS | 28631 | Values: 145.0 147.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 09:23:16 INFO - PROCESS | 28631 | Values: 96.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 95.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.00 stddev:0.97 (1.9%) stddev-sans-first:0.44 09:23:16 INFO - PROCESS | 28631 | Values: 55.0 52.0 52.0 52.0 52.0 53.0 52.0 52.0 53.0 52.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 09:23:16 INFO - PROCESS | 28631 | Values: 59.0 60.0 61.0 60.0 60.0 60.0 60.0 60.0 60.0 61.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:76.50 stddev:2.85 (3.7%) stddev-sans-first:3.00 09:23:16 INFO - PROCESS | 28631 | Values: 76.0 76.0 74.0 75.0 75.0 75.0 77.0 82.0 82.0 77.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:123.50 stddev:12.58 (10.2%) stddev-sans-first:13.06 09:23:16 INFO - PROCESS | 28631 | Values: 119.0 122.0 125.0 117.0 118.0 122.0 122.0 127.0 160.0 131.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.50 Median:160.00 stddev:2.59 (1.6%) stddev-sans-first:2.18 09:23:16 INFO - PROCESS | 28631 | Values: 164.0 162.0 157.0 160.0 158.0 162.0 160.0 159.0 156.0 157.0 09:23:16 INFO - PROCESS | 28631 | 09:23:16 INFO - PROCESS | 28631 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 09:23:16 INFO - PROCESS | 28631 | Values: 63.0 62.0 62.0 61.0 62.0 63.0 62.0 62.0 62.0 62.0 09:23:16 INFO - PROCESS | 28631 | -------- Summary: end -------- 09:23:16 INFO - PROCESS | 28631 | 09:23:17 INFO - TEST-INFO | 28631: exit 0 09:23:17 INFO - TEST-OK | kraken | took 61080ms 09:23:17 INFO - SUITE-END | took 1174s 09:23:17 INFO - Completed test suite (00:19:35) 09:23:17 INFO - javascript benchmark 09:23:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3082.9691703082976, 3111.4866047102228, 3116.4390409726248, 3138.0742680909157, 3141.27750617358, 5181.533661970422, 5190.455002224769, 5235.554977826884, 5252.474752524741, 5276.393214780302, 5264.157734762439, 5284.788608455667, 5290.603204759639, 5312.150055991042, 5319.6808191508535, 4752.310914917343, 4793.856184314476, 4830.686005409655, 4834.250691142878, 5005.874853128665, 4915.361003069596, 4918.532739389752, 4928.901421971558, 4984.177610694231, 4996.725180115092, 4755.90488190236, 4761.619070474354, 4798.640101992364, 4807.351007613962, 4809.278608208797, 4783.7129772213775, 4791.616670666338, 4864.683795553313, 4867.026594681078, 4900.191468407708, 4836.854894353175, 4838.733869637169, 4855.053264613402, 4857.53853383928, 4910.631702622318, 5180.197069454241, 5262.105442074857, 5367.516923476866, 5426.75579598915, 5440.211169380457, 5755.913661295084, 5784.161296611967, 5794.188813566104, 5819.912701309484, 5820.156077368758, 5576.888462230773, 5806.767729290823, 5847.473727364515, 5861.501772349313, 5867.442592953663, 5297.602679798992, 5341.252224688546, 5358.196270559409, 5424.538914192299, 5427.050266203399, 5872.236609240771, 5877.382874798084, 5880.94119058804, 5882.23530940975, 5896.7641294348605, 5448.83653490396, 5499.725013749377, 5637.605367624309, 5654.208410822488, 5656.2929633795775, 5629.0, 5678.943210567924, 5683.374828768832, 5726.9713651431475, 5756.510696590753, 5656.91514627281, 5708.971455142698, 5711.200431939532, 5712.257406537206, 5752.827415177551, 5494.917576236359, 5568.52667523257, 5623.550115990711, 5728.914066289009, 5747.597668163188, 4339.5443478434945, 4347.282698354736, 4347.586979236968, 4379.1022840317655, 4390.956090439118, 3880.2239552089695, 3889.688824894058, 3895.35726605107, 3926.1951299983434, 3927.7250592458254, 5288.788448462018, 5298.15229563268, 5370.113931201387, 5382.381026182039, 5424.783008679688, 5768.653880767168, 5775.768969241268, 5777.595568310262, 5783.161441590946, 5815.098659707752, 5441.2654291670115, 5442.374126975369, 5446.291982042309, 5449.99175152598, 5453.863653408696, 4824.421069471686, 4855.684380515255, 4863.489333619989, 4896.657233993657, 5007.624428167902, 5856.824295271149, 5879.647221166743, 5895.616784908967, 5896.08610665339, 5897.911531327033, 5703.287089113861, 5714.599978001666, 5716.056850619601, 5716.514096301861, 5719.742611582572, 5739.598228124159, 5818.8254352369495, 5857.092150716646, 5908.202392676933, 5944.762209511744, 5651.378348381592, 5654.773809047592, 5655.208270842168, 5661.971690141523, 5665.631733937281, 5697.0, 5745.2818397700285, 5758.049921762862, 5758.740856661377, 5760.078387458154, 5463.043967305785, 5475.616706830484, 5537.612367134423, 5588.217649528988, 5592.0, 5574.191742197439, 5586.608937374344, 5592.245046918614, 5593.888122237451, 5602.2717046783655, 5528.9723551381985, 5539.307586551681, 5543.8891222176135, 5544.7782088716, 5588.580856435702, 5474.151506516497, 5485.286912701324, 5543.833684989457, 5562.721863906708, 5599.748011339581, 5455.754491047992, 5457.290552228185, 5458.099413596712, 5466.508014278734, 5467.753951072291], "value": 5261.656417459112, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.7773334999375, 616.2112496005113, 628.5254632752271, 629.4649547884297, 637.7480895046453, 31367.529487057684, 41722.79138604306, 42473.36289955653, 42569.36145957813, 42574.0, 44400.3339949901, 44755.10489790202, 44892.32661510072, 44892.3266151008, 44916.0, 8893.42192757472, 9019.819603607924, 9022.684206052789, 9036.909630903705, 9043.683471078513, 8991.595378207963, 9063.50150741709, 9068.36521443496, 9073.682421115262, 9078.546072696372, 9102.0, 9136.360454768168, 9144.817103657957, 9146.405483643575, 9158.221551168126, 9036.728898133066, 9048.547572621408, 9070.183683468487, 9081.273498120134, 9086.0, 8960.68637597681, 9020.00779914209, 9026.86459703105, 9031.096890310982, 9043.32175086871, 41661.58338416607, 41887.790561047, 42052.78973605143, 42111.57888421107, 42126.57873421257, 3322.4517954537473, 3376.69609735125, 3505.474178873164, 3686.4654625079215, 3711.851525938959, 32993.835030824936, 33113.50329745056, 33399.0, 33550.664493355245, 34607.82696086504, 3640.690541303992, 3641.6904563112166, 3654.4701018352457, 3734.7572407793677, 3794.753341032824, 344155.0, 344333.0, 346586.0, 347220.0, 348101.0, 50333.0, 55646.0, 56911.0, 57342.426575733705, 57476.42523574794, 55405.72297138489, 61531.38468615346, 61540.69229653823, 61600.69199653973, 61694.69152654208, 16168.595785105232, 16332.428365007168, 16355.672886542441, 16356.182190890408, 16364.509064728078, 10431.0, 12015.098867584966, 12341.568045118378, 12884.871151288555, 13041.804372934414, 17028.318867245172, 17041.659166816593, 17148.314067437164, 17150.056746879043, 17256.309747609954, 6686.5653732507235, 6692.230393504712, 6751.662416879137, 6805.958212536219, 6813.4889883257965, 948.3029972969862, 951.1059603972383, 1017.5268500147467, 1053.5627714498398, 1136.4373985769566, 30668.69331306703, 32515.512267316008, 32738.508922366185, 32775.67224327774, 32955.34089318248, 1099.5546803544578, 1142.9827453566334, 1154.9307041577365, 1166.0438440478895, 1171.566520387462, 17703.29186832538, 18009.769824519757, 18220.817791822177, 18861.245550178115, 18873.0, 533924.0, 534965.0, 537583.0, 539219.0, 541691.0, 57204.0, 58843.70578147082, 59171.704141479015, 59225.0, 59227.70386148042, 1225.3995542184443, 1339.6115126613367, 1354.2213227394288, 1367.3436750359683, 1399.069618703571, 31277.68722312748, 33304.66695333113, 33327.0, 33583.32833343368, 34029.14927126742, 50599.0, 50790.492095078574, 51152.0, 51282.820405142564, 51322.48677513177, 405421.0, 406633.0, 406729.0, 407237.0, 407500.0, 24827.255182344557, 27571.448571028868, 28314.575281370384, 28335.858320708263, 29946.700532995263, 46003.53996459993, 46815.0, 46854.76572617252, 47594.28608570805, 48067.75966120147, 39915.2685648365, 40264.79867600643, 40814.79592602018, 40932.0, 41482.79258603688, 38024.0, 39261.0, 39277.60722392739, 39723.80138099407, 39887.40168897527, 39955.005624296966, 40090.79954600208, 41055.58944410637, 41137.58862411457, 41339.0, 33428.0, 36037.27925441529, 36628.0, 37163.44254836126, 37247.44128838124, 27161.45677086487, 27315.180544583694, 27390.45219095647, 27549.31126721848, 27853.86073069622], "value": 19889.796853141444, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.4314301120364, 405.3372735577332, 406.2301937827816, 409.25311306864967, 410.4336016297511, 18330.0, 19765.80234197661, 19932.501687457803, 19967.800321996776, 21286.467838304052, 16719.247633856474, 16928.74606880895, 18051.81948180521, 19686.901565492153, 19739.21043158272, 5463.562914966793, 5532.917006244909, 5703.0, 5714.857128571777, 5745.856353591173, 5630.268065151525, 5632.155176723483, 5676.318841738997, 5690.4351303391695, 5696.943030569703, 5454.263674403965, 5636.577256705764, 5646.18130371096, 5661.150827375906, 5666.858328541778, 5524.33707955046, 5597.972010139964, 5614.0, 5618.381977982419, 5654.660720356771, 4870.0746858096845, 5066.544011039025, 5077.568406685399, 5078.64449488536, 5078.720670363128, 23888.0, 24148.275551733477, 24633.63049554258, 24890.377740556632, 24903.0, 2135.9871960431337, 2365.597848365782, 2403.1228601560306, 2465.901363945458, 2712.9457410851674, 10103.84844227337, 10108.44403557804, 10243.94878025605, 10560.841587376195, 10583.788324233548, 1900.0594705620806, 1943.3684052682934, 1944.5235917200378, 1959.2750682247517, 1999.720039194514, 85186.0, 88106.55946720226, 88801.0, 88825.11748825066, 88990.99999999936, 11361.94319028416, 11686.18196726221, 11722.648320550397, 11748.35384053868, 11999.88000120005, 16205.189740512928, 16230.0, 16252.268647910872, 16308.673826523403, 16333.83666163347, 8011.759647210593, 8424.747257582283, 8499.915000850036, 8500.0, 8586.527740974307, 8060.637271322805, 8091.312238459679, 8160.30637395828, 8280.420370574122, 8295.253427191581, 4960.057589058068, 4964.801407943714, 4983.053219888209, 5117.287757903737, 5124.92312615311, 2004.629143608437, 2016.7932986551455, 2047.0890453747938, 2064.5251592133895, 2084.8853313067625, 900.3112618846587, 969.8545218217183, 1017.7913527726798, 1046.398320965448, 1051.453244312953, 19456.902715486332, 20157.092930818148, 20249.29127480531, 20271.594568108554, 20493.0, 964.3249725192394, 982.1700662939804, 1009.6970908727352, 1051.1748277602046, 1054.1145437832258, 10729.195310351755, 10860.945695271474, 12179.878201217874, 12269.754604907852, 12285.754284914252, 465360.0, 469397.0, 469637.0, 471060.0, 472560.0, 98309.0, 98614.0, 99491.50254248828, 101407.0, 102508.0, 1025.251566356564, 1025.4513835098187, 1044.1907521670767, 1049.7008352619466, 1060.6764936694383, 18140.637187256445, 18337.816621834143, 18618.44144675662, 18680.34618788336, 18837.6232475347, 43195.0, 43268.78365608152, 44277.77861110674, 44573.55426445694, 45118.548814512746, 53587.464125358245, 54486.727566361915, 56112.719436402556, 60389.0, 63987.0, 7794.883076753967, 7966.203379661987, 8045.195480451908, 8491.660333586588, 9086.954565227132, 10074.748131296776, 13296.534621288209, 13846.86153138496, 14091.718165636836, 14697.706045879237, 12215.572454964034, 12387.256764593792, 12544.0, 12552.372381381078, 12563.497460101858, 12031.63905082849, 12159.209651372632, 12247.968405111305, 12304.507819687113, 12313.753724925631, 12478.313692746622, 12482.126251162332, 12492.37538123072, 12529.436175371948, 12608.684782880135, 7874.755694627004, 7984.321332686554, 8188.05837328691, 8261.297789687944, 8267.710630127916, 11358.488868001125, 12165.756684866075, 12314.199577027464, 12657.11400201977, 12714.554990575285], "value": 9424.057891478838, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.8271224740781, 1429.0425414971967, 1454.418232706917, 1580.2572790788327, 1791.2208189437588, 7934.603269836507, 8810.647574097036, 9111.043340449256, 9133.451992880431, 9223.0, 8897.154770296811, 9057.773555661111, 9061.139191776787, 9064.77338066547, 9075.36472446929, 2292.5644127615783, 2328.196772113618, 2331.755165707602, 2333.6616190652344, 2334.5214231082587, 2300.5973954557967, 2310.1221535816367, 2330.822934418125, 2347.5187586544716, 2354.3290162303747, 2282.2582660635276, 2297.287840769367, 2299.0458959531775, 2326.6277395616703, 2326.697529321186, 2239.328201539548, 2266.9433264168365, 2277.487565297803, 2282.851614645051, 2283.2122917593406, 2260.288009277089, 2268.138107519149, 2271.7728227177317, 2273.2043784675398, 2275.988620056906, 8626.353023523261, 8912.955435222782, 9075.63697452101, 9138.0, 9170.220531254852, 5330.520253177233, 5418.214358917935, 5435.972820135914, 5441.700706461143, 5442.863928401782, 3389.1357703785625, 3449.120474279068, 3478.4086705260165, 3478.9826050869838, 3531.8057506837113, 1124.2074337591978, 1134.7843909657138, 1135.9943200284029, 1143.022715578177, 1144.1361771862191, 13181.538646147339, 13198.14212076212, 13221.801672974912, 13250.204987700865, 13263.137896036727, 4509.729416235036, 4615.192341340252, 4769.117713223065, 4775.06886157196, 4785.521447855256, 10214.795704085876, 10218.642347517802, 10223.846642300372, 10255.179585633132, 10257.076863082359, 5456.508914197741, 5465.289512363367, 5467.6719396836315, 5473.917891231635, 5479.479449452296, 4213.094184750232, 4386.846460373871, 4537.047220083753, 4857.9028419431415, 5122.154844450699, 3887.475190849199, 3925.823337949799, 3951.249262640089, 3974.8870316311477, 4022.9798851005557, 1253.943572539238, 1272.8918041966351, 1275.5344299330677, 1283.4288741509938, 1290.3612711707765, 889.7997950461095, 922.0548937339281, 952.0574631115146, 977.203579083045, 1017.5065093429681, 6171.259448866164, 6193.628382297076, 6240.469560087444, 6302.212223472066, 6333.303336632966, 981.3081777346953, 1011.6509804117715, 1014.5840205515703, 1035.3839465518156, 1040.380973320888, 4189.371594260824, 4327.870163895025, 4383.868483945486, 4466.553344665507, 4467.173572889026, 11099.667009989713, 11690.590829321103, 11851.348175850238, 11872.940635296942, 11896.762064758655, 11375.772484550263, 11647.825282620768, 11669.124815638861, 11683.824742628867, 11749.236299640666, 8291.419600628018, 8664.696735614223, 8668.523231222218, 8766.38635295536, 8834.646614135363, 6356.237251529661, 6553.541252112306, 6616.206055273398, 6738.35985581369, 6741.730330786713, 10255.179585633281, 10593.152547796311, 10654.680359589225, 10663.146948243966, 10672.733181670521, 9898.307118501636, 10500.789984200426, 10945.288556243819, 10955.945220274167, 10991.505382257978, 7852.214778522102, 8140.145284745254, 8177.713780017671, 8190.1400352964465, 8192.426530142833, 8624.396292259733, 8787.34094942869, 8800.691975780817, 8815.735527934172, 8824.823503530022, 7879.566623835515, 8105.148959359182, 8185.590720464072, 8245.95877020611, 8254.025169448094, 7735.6518956648215, 7764.650590723318, 7794.22057794216, 7875.763727088197, 7876.133625301327, 7236.565806051653, 7263.564186148848, 7289.562626242442, 7295.7081716732855, 7336.7798966031105, 5208.374995000624, 5354.598405119554, 5469.179623056573, 5572.554195664256, 5573.637713548606, 5438.673679579238, 5520.0, 5611.663300202001, 5659.236003139688, 5678.176664383724], "value": 4775.632592306635, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.62689101651665, 343.5516650770744, 352.2022618768488, 352.79537868036533, 353.48920809430354, 5341.919871201931, 5381.19282107684, 5403.189521571766, 5436.401995780461, 5453.072977593809, 5329.893402131955, 5330.200469929503, 5330.973345133269, 5360.303160589127, 5373.038840823101, 4088.3049881520215, 4243.681723870706, 4266.594673506028, 4279.422277992478, 4309.547497512763, 3929.35165697661, 4219.5358510563965, 4219.831206751726, 4228.788560571975, 4229.429027081335, 4155.667546596265, 4177.206330797154, 4194.161167766443, 4196.979015104936, 4233.259179643565, 4014.6788256939376, 4140.660526802347, 4238.788060596958, 4239.660827133822, 4250.638695710868, 3720.144366795625, 3722.8510859565586, 3725.4784330193793, 3735.2156047230114, 3746.19456816783, 5196.402413722432, 5228.424873263938, 5245.0, 5266.8683282917855, 5294.549963253128, 4747.216709242971, 4851.466338702739, 4858.757062146914, 4863.781129849165, 4865.367502224688, 5583.944160558383, 5586.832395028156, 5597.748101335409, 5601.859953501154, 5674.205611214434, 4073.5926407359025, 4109.609587089221, 4115.34154535276, 4120.505539335299, 4133.503979522477, 5292.126799078147, 5604.187392828057, 5830.32951210612, 5832.387599302097, 5839.0, 4182.372644103409, 4186.539480657125, 4188.727732697365, 4198.349255865346, 4200.6219440250525, 4264.936025959613, 4275.914481710411, 4278.165757677223, 4279.293916503804, 4281.314989601711, 4539.568740969603, 4817.253325734517, 4838.806447742121, 4840.7337596432535, 4841.9031619367415, 5617.269754931833, 5632.60571759981, 5640.674441506243, 5673.234113394721, 5678.432156784289, 1437.4034775568023, 1456.4392708807186, 1458.846821083792, 1459.7300348696701, 1473.786300986351, 912.564383007222, 933.4492649336923, 933.9813203735887, 934.1545900959643, 949.6343907595625, 4817.927731084036, 4873.707577545359, 4877.366222240022, 4894.902101957941, 4908.3619129513645, 5180.083369153362, 5182.611304152204, 5189.792408303701, 5217.973910130425, 5220.686758794484, 4648.8000710192355, 4882.95117048825, 4937.876553086202, 4953.0, 4964.131277026506, 2731.808773385884, 2753.2979090331837, 2779.2634951737805, 2779.7498225159834, 2784.944301114007, 5317.112425089115, 5321.813736519281, 5324.334458192726, 5357.2053362124125, 5366.892662146814, 5226.216067589892, 5252.789888404421, 5524.4199359066715, 5941.376155503696, 5968.1644569760265, 5920.615160014499, 5958.225430693982, 6000.339962604023, 6003.98866379759, 6005.549583781234, 5621.297337832771, 5782.826515204551, 5826.941730582809, 5869.4717475427415, 5893.734781934738, 5704.344000440003, 5719.399463056319, 5727.599068065196, 5730.0, 5732.0, 5621.578381621476, 5691.402402747817, 5869.383714710065, 5952.732127054206, 6037.426444487766, 4100.118474527991, 4116.0, 4127.504699436087, 4134.0, 4148.253314403436, 5664.546836253172, 5775.48020678141, 5842.1820945066875, 5848.473637372486, 5850.3857095006115, 5564.610477266552, 5577.52591029767, 5583.748731307182, 5586.497215250647, 5598.1322894951345, 5496.77380660833, 5525.226468294361, 5593.552515798645, 5666.291713535808, 5674.687892165805, 5344.904294619671, 5469.917951230815, 5552.888942221214, 5570.192322113352, 5581.832545023656, 5574.136008918624, 5581.6371935824045, 5596.524295434933, 5598.888022239614, 5651.406602306863, 5395.325584301962, 5497.972510137583, 5530.834074977757, 5595.916061259166, 5598.272224610774], "value": 4238.312518178944, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.1623646087697, 680.3978479046044, 681.8465845184834, 687.6596084937956, 734.3941248470011, 13345.733085338288, 14754.0, 15157.393704251817, 15388.15365154916, 15424.92287538562, 14506.129632222073, 14650.780238296435, 14753.704925901502, 14800.6299842504, 14955.401783928657, 665.6272487407043, 781.2539025230919, 802.9759107226793, 817.8323443694059, 818.6602559937619, 822.7572866004513, 824.0688022534528, 825.8307047055339, 826.5454000299841, 827.4166712467696, 860.7977125375531, 867.9479231246146, 868.110187058264, 868.8349213649386, 869.3914260017981, 702.034702284359, 831.1522247307772, 861.4831101339147, 861.9913800861974, 862.6635612111281, 627.9434850863444, 630.1839118341712, 630.6058713304185, 630.7477009196313, 632.2507828223523, 14022.0, 14125.0, 14256.287185640676, 14296.356663950148, 14315.57053288403, 13616.319184040758, 13978.370973306226, 13989.790153147711, 14011.859881401157, 14294.785578216335, 12631.684207894785, 12771.872281277161, 12810.167339122927, 12945.0, 13398.732025359535, 4414.293713005904, 4510.684252102355, 4538.205813982539, 4630.398048253739, 4649.581537661593, 73705.0, 74368.0, 74382.25617743861, 74510.88233676553, 74511.62744186244, 10968.341899486057, 11051.889481105247, 11214.383208923588, 11362.261453005529, 11530.481128349244, 20637.793622063888, 20655.79344206569, 20656.58686826285, 20665.483362916046, 20698.0, 8437.873431898526, 8485.660573577112, 8518.659253629909, 8521.44610600309, 8720.825583488295, 4783.473817880031, 4800.183968725325, 4823.131836269435, 4964.478729733398, 5062.417821950523, 3458.4985177149174, 3565.090901820019, 3569.1077230692326, 3574.2136729919375, 3575.4994300797907, 530.9017831701148, 618.8669436071266, 626.6709977261974, 627.1282916745636, 649.9025146228009, 547.8301726464808, 612.6324205476678, 617.996910015447, 671.77495538994, 684.6816230452863, 9988.400695958266, 10147.137493313003, 10196.898031019744, 10200.540975656113, 10401.375917444977, 547.2721280696743, 557.4676184244037, 608.2062907905226, 651.5406638319973, 654.4110300729382, 4726.787294571753, 4977.178765503724, 5018.924716129261, 5024.799008039638, 5028.522290382408, 84880.0, 118862.72670106783, 126949.0, 127354.0, 128874.35562822125, 109811.0, 111966.0, 113303.0, 113933.0, 115194.0, 98384.0, 98873.50563247282, 99663.50168249113, 100541.0, 101644.0, 9722.319437639297, 9734.221262299141, 9740.951295243478, 9759.374922238649, 9828.26288028415, 47924.7603761979, 55747.44252557585, 57089.71455142864, 57438.0, 59568.702156488944, 57634.0, 61444.0, 75474.62262688871, 76766.46467070738, 78184.0, 11516.42417879086, 11541.019013383957, 12114.394280285775, 12149.817752733892, 13487.0, 13739.0, 14783.0, 15272.236388180769, 15589.298481568583, 15703.450379236672, 14257.144571325754, 14977.326020328894, 15119.697606048037, 15124.54626361211, 15533.533993980198, 13070.673233169247, 14191.432342706175, 14376.065555738844, 14412.991090623556, 14715.337809798371, 13644.823632763346, 13701.383437745126, 13955.441782328999, 14000.929995349958, 14124.646883827987, 10622.628208012682, 10665.733356666145, 10804.783904322027, 11001.339919604849, 11725.179237453638, 10232.744181395525, 10247.948760256151, 10462.790744184922, 11707.356095414834, 12154.75690486203], "value": 7592.4817357803895, "unit": "score"}], "name": "dromaeo_css", "value": 7301.976585682784, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 121.0, 121.0, 178.0, 118.0, 121.0, 142.0, 116.0, 156.0, 120.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 162.0, 160.0, 160.0, 160.0, 163.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 74.0, 75.0, 127.0, 75.0, 75.0, 74.0, 74.0, 105.0, 74.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 117.0, 84.0, 85.0, 84.0, 116.0, 93.0, 85.0, 131.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 52.0, 52.0, 52.0, 53.0, 52.0, 52.0, 53.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 74.0, 75.0, 75.0, 75.0, 77.0, 82.0, 82.0, 77.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 122.0, 125.0, 117.0, 118.0, 122.0, 122.0, 127.0, 160.0, 131.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 157.0, 160.0, 158.0, 162.0, 160.0, 159.0, 156.0, 157.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 61.0, 62.0, 63.0, 62.0, 62.0, 62.0, 62.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1479.5, "alertThreshold": 2.0}]} 09:23:17 INFO - Return code: 0 09:23:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:17 INFO - # TBPL SUCCESS # 09:23:17 INFO - Running post-action listener: _resource_record_post_action 09:23:17 INFO - ##### 09:23:17 INFO - ##### Finished run-tests step (success) 09:23:17 INFO - ##### 09:23:17 INFO - Running post-run listener: _resource_record_post_run 09:23:17 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 8192; Write bytes: 556126208; Read time: 12; Write time: 577324 09:23:17 INFO - TinderboxPrint: CPU usage
13.0% 09:23:17 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 12 09:23:17 INFO - TinderboxPrint: I/O write bytes / time
556,126,208 / 577,324 09:23:17 INFO - TinderboxPrint: CPU idle
8,273.5 (87.0%) 09:23:17 INFO - TinderboxPrint: CPU user
1,196.4 (12.6%) 09:23:17 INFO - TinderboxPrint: Swap in / out
0 / 0 09:23:17 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 260091904; Read time: 0; Write time: 325896 09:23:17 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 296034304; Read time: 0; Write time: 251428 09:23:18 INFO - Running post-run listener: _upload_blobber_files 09:23:18 INFO - Blob upload gear active. 09:23:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:23:18 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'] 09:23:18 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 09:23:18 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:21 INFO - (blobuploader) - INFO - Done attempting. 09:23:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:23:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:23:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:22 INFO - (blobuploader) - INFO - Done attempting. 09:23:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:23:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:23:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:23 INFO - (blobuploader) - INFO - Done attempting. 09:23:23 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:23 INFO - Return code: 0 09:23:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb11f3bba92a67edbb6164755aed1ce16c708bbb0f59b5a7b50214242830eb138057f7b51435b14adfbad2bdbd4a34e34964afd1ca0bf9ad8e1a27a76a2dbcbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6080fe34f6b4c68f1dd067d4692b47bdb059c6d1219c0d7d4b53f82b7927612f1d30d71dc3d925946ff7077f25bb4069d2b7e8a26f6feef736f9c2d6703ad613", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7421cae3d5885a9c4f4568b87641e532022f2860cff18c2eba5d37ea648a74bcd5b9590d7a59e877a7cf08a6daf8d65ce4d9729782d404443eb11de7b3dd9025"} 09:23:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:23 INFO - Contents: 09:23:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb11f3bba92a67edbb6164755aed1ce16c708bbb0f59b5a7b50214242830eb138057f7b51435b14adfbad2bdbd4a34e34964afd1ca0bf9ad8e1a27a76a2dbcbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6080fe34f6b4c68f1dd067d4692b47bdb059c6d1219c0d7d4b53f82b7927612f1d30d71dc3d925946ff7077f25bb4069d2b7e8a26f6feef736f9c2d6703ad613", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7421cae3d5885a9c4f4568b87641e532022f2860cff18c2eba5d37ea648a74bcd5b9590d7a59e877a7cf08a6daf8d65ce4d9729782d404443eb11de7b3dd9025"} 09:23:23 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:23 INFO - Copying logs to upload dir... 09:23:23 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.903430 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-07-05 09:23:23.369887) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 09:23:23.373798) ========= 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=523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987 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/eb11f3bba92a67edbb6164755aed1ce16c708bbb0f59b5a7b50214242830eb138057f7b51435b14adfbad2bdbd4a34e34964afd1ca0bf9ad8e1a27a76a2dbcbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6080fe34f6b4c68f1dd067d4692b47bdb059c6d1219c0d7d4b53f82b7927612f1d30d71dc3d925946ff7077f25bb4069d2b7e8a26f6feef736f9c2d6703ad613", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7421cae3d5885a9c4f4568b87641e532022f2860cff18c2eba5d37ea648a74bcd5b9590d7a59e877a7cf08a6daf8d65ce4d9729782d404443eb11de7b3dd9025"} build_url:https://queue.taskcluster.net/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/WFJtLOp0TtGwEM13Qt08Dg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb11f3bba92a67edbb6164755aed1ce16c708bbb0f59b5a7b50214242830eb138057f7b51435b14adfbad2bdbd4a34e34964afd1ca0bf9ad8e1a27a76a2dbcbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6080fe34f6b4c68f1dd067d4692b47bdb059c6d1219c0d7d4b53f82b7927612f1d30d71dc3d925946ff7077f25bb4069d2b7e8a26f6feef736f9c2d6703ad613", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7421cae3d5885a9c4f4568b87641e532022f2860cff18c2eba5d37ea648a74bcd5b9590d7a59e877a7cf08a6daf8d65ce4d9729782d404443eb11de7b3dd9025"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 09:23:23.403831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:23:23.404188) ========= 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=523ca41ca1e0623de333ad52000001bf-1467734565.447082-1879186987 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004501 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:23:23.490747) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 09:23:23.491534) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 09:23:23.492230) ========= ========= Total master_lag: 0.17 =========