builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1468461900.63 results: success (0) buildid: 20160713173001 builduid: 79e950d3dff84e988b9ab4bda7c9991c revision: 31cab0ba8bbf1d88b08abeae45bcdf317645355a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:00.631296) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:00.631697) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:00.631963) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:00.654935) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:00.655196) ========= 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=03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-13 19:05:00-- 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% 42.5M=0s 2016-07-13 19:05:00 (42.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.157661 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:00.828511) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:00.828803) ========= 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=03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021087 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:00.879630) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 19:05:00.879951) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 31cab0ba8bbf1d88b08abeae45bcdf317645355a --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 31cab0ba8bbf1d88b08abeae45bcdf317645355a --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=03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-13 19:05:01,044 truncating revision to first 12 chars 2016-07-13 19:05:01,044 Setting DEBUG logging. 2016-07-13 19:05:01,044 attempt 1/10 2016-07-13 19:05:01,044 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/31cab0ba8bbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 19:05:02,152 unpacking tar archive at: fx-team-31cab0ba8bbf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.436797 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 19:05:02.331588) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:02.331922) ========= script_repo_revision: 31cab0ba8bbf1d88b08abeae45bcdf317645355a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:02.332333) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:02.332630) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 19:05:02.342133) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-07-13 19:05:02.342526) ========= /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=03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:05:02 INFO - MultiFileLogger online at 20160713 19:05:02 in /builds/slave/test 19:05:02 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 19:05:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:05:02 INFO - {'append_to_log': False, 19:05:02 INFO - 'base_work_dir': '/builds/slave/test', 19:05:02 INFO - 'blob_upload_branch': 'Fx-Team', 19:05:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:05:02 INFO - 'branch': 'Fx-Team', 19:05:02 INFO - 'buildbot_json_path': 'buildprops.json', 19:05:02 INFO - 'config_files': ('talos/linux_config.py',), 19:05:02 INFO - 'default_actions': ('clobber', 19:05:02 INFO - 'read-buildbot-config', 19:05:02 INFO - 'download-and-extract', 19:05:02 INFO - 'populate-webroot', 19:05:02 INFO - 'create-virtualenv', 19:05:02 INFO - 'install', 19:05:02 INFO - 'run-tests'), 19:05:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:05:02 INFO - 'download_minidump_stackwalk': True, 19:05:02 INFO - 'download_symbols': 'ondemand', 19:05:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:05:02 INFO - 'tooltool.py': '/tools/tooltool.py', 19:05:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:05:02 INFO - '/tools/misc-python/virtualenv.py')}, 19:05:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:05:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:05:02 INFO - 'installer_path': 'installer.exe', 19:05:02 INFO - 'log_level': 'info', 19:05:02 INFO - 'log_name': 'talos', 19:05:02 INFO - 'log_to_console': True, 19:05:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:05:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:05:02 INFO - 'opt_config_files': (), 19:05:02 INFO - 'pip_index': False, 19:05:02 INFO - 'sps_profile': False, 19:05:02 INFO - 'sps_profile_interval': 0, 19:05:02 INFO - 'suite': 'dromaeojs', 19:05:02 INFO - 'system_bits': '32', 19:05:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:05:02 INFO - 'title': 'talos-linux64-ix-047', 19:05:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:05:02 INFO - 'use_talos_json': True, 19:05:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:05:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:05:02 INFO - 'work_dir': 'build'} 19:05:02 INFO - ##### 19:05:02 INFO - ##### Running clobber step. 19:05:02 INFO - ##### 19:05:02 INFO - Running pre-action listener: _resource_record_pre_action 19:05:02 INFO - Running main action method: clobber 19:05:02 INFO - rmtree: /builds/slave/test/build 19:05:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:05:03 INFO - Running post-action listener: _resource_record_post_action 19:05:03 INFO - ##### 19:05:03 INFO - ##### Finished clobber step (success) 19:05:03 INFO - ##### 19:05:03 INFO - ##### 19:05:03 INFO - ##### Running read-buildbot-config step. 19:05:03 INFO - ##### 19:05:03 INFO - Running pre-action listener: _resource_record_pre_action 19:05:03 INFO - Running main action method: read_buildbot_config 19:05:03 INFO - Using buildbot properties: 19:05:03 INFO - { 19:05:03 INFO - "project": "", 19:05:03 INFO - "product": "firefox", 19:05:03 INFO - "script_repo_revision": "production", 19:05:03 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 19:05:03 INFO - "repository": "", 19:05:03 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 19:05:03 INFO - "buildid": "20160713173001", 19:05:03 INFO - "pgo_build": "True", 19:05:03 INFO - "basedir": "/builds/slave/test", 19:05:03 INFO - "buildnumber": 283, 19:05:03 INFO - "slavename": "talos-linux64-ix-047", 19:05:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:05:03 INFO - "platform": "ubuntu64_hw", 19:05:03 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 19:05:03 INFO - "branch": "fx-team", 19:05:03 INFO - "repo_path": "integration/fx-team", 19:05:03 INFO - "revision": "31cab0ba8bbf1d88b08abeae45bcdf317645355a", 19:05:03 INFO - "stage_platform": "linux64-pgo", 19:05:03 INFO - "builduid": "79e950d3dff84e988b9ab4bda7c9991c", 19:05:03 INFO - "slavebuilddir": "test" 19:05:03 INFO - } 19:05:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 19:05:03 INFO - Running post-action listener: _resource_record_post_action 19:05:03 INFO - ##### 19:05:03 INFO - ##### Finished read-buildbot-config step (success) 19:05:03 INFO - ##### 19:05:03 INFO - ##### 19:05:03 INFO - ##### Running download-and-extract step. 19:05:03 INFO - ##### 19:05:03 INFO - Running pre-action listener: _resource_record_pre_action 19:05:03 INFO - Running main action method: download_and_extract 19:05:03 INFO - mkdir: /builds/slave/test/build/tests 19:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:05:03 INFO - https://queue.taskcluster.net/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:05:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:05:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:05:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/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 19:05:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/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 19:05:03 INFO - Downloaded 1361 bytes. 19:05:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:05:03 INFO - Using the following test package requirements: 19:05:03 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 19:05:03 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:05:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:05:03 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:05:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:05:03 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:05:03 INFO - u'jsshell-linux-x86_64.zip'], 19:05:03 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:05:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:05:03 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 19:05:03 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:05:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:05:03 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:05:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:05:03 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:05:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:05:03 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:05:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:05:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:05:03 INFO - https://queue.taskcluster.net/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:05:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:05:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:05:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/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 19:05:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/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 19:05:04 INFO - Downloaded 24326434 bytes. 19:05:04 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'] 19:05:04 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 19:05:06 INFO - Return code: 0 19:05:06 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 19:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:05:06 INFO - https://queue.taskcluster.net/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:05:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:05:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:05:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/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 19:05:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/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 19:05:07 INFO - Downloaded 24326434 bytes. 19:05:07 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'] 19:05:07 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 19:05:08 INFO - Return code: 0 19:05: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')]} 19:05:08 INFO - https://queue.taskcluster.net/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:05:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:05:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:05:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/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 19:05:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/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 19:05:10 INFO - Downloaded 14403317 bytes. 19:05:10 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'] 19:05:10 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 19:05:10 INFO - Return code: 0 19:05: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')]} 19:05:10 INFO - https://queue.taskcluster.net/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:05:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:05:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:05:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/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 19:05:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q54gf0HhTlaPGphDdcez8A/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 19:05:13 INFO - Downloaded 60806469 bytes. 19:05:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:05:13 INFO - mkdir: /builds/slave/test/properties 19:05:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:05:13 INFO - Writing to file /builds/slave/test/properties/build_url 19:05:13 INFO - Contents: 19:05:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:05:14 INFO - Running post-action listener: _resource_record_post_action 19:05:14 INFO - Running post-action listener: set_extra_try_arguments 19:05:14 INFO - ##### 19:05:14 INFO - ##### Finished download-and-extract step (success) 19:05:14 INFO - ##### 19:05:14 INFO - ##### 19:05:14 INFO - ##### Running populate-webroot step. 19:05:14 INFO - ##### 19:05:14 INFO - Running pre-action listener: _resource_record_pre_action 19:05:14 INFO - Running main action method: populate_webroot 19:05:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:05:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:05:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:05:14 INFO - '--tppagecycles', 19:05:14 INFO - '7'], 19:05:14 INFO - 'tests': ['tsvgm']}}, 19:05:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:05:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:05:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:05:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:05:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:05:14 INFO - 'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:05:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:05:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:05:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:05:14 INFO - 'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['damp', 'tps']}, 19:05:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:05:14 INFO - 'tests': ['damp', 'tps']}, 19:05:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['dromaeo_dom']}, 19:05:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:05:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['basic_compositor_video']}, 19:05:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:05:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['a11yr', 19:05:14 INFO - 'ts_paint', 19:05:14 INFO - 'tpaint', 19:05:14 INFO - 'sessionrestore', 19:05:14 INFO - 'sessionrestore_no_auto_restore', 19:05:14 INFO - 'tabpaint']}, 19:05:14 INFO - 'other-e10s': {'tests': ['a11yr', 19:05:14 INFO - 'ts_paint', 19:05:14 INFO - 'tpaint', 19:05:14 INFO - 'sessionrestore', 19:05:14 INFO - 'sessionrestore_no_auto_restore', 19:05:14 INFO - 'tabpaint']}, 19:05:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:05:14 INFO - 'ts_paint', 19:05:14 INFO - 'tpaint', 19:05:14 INFO - 'sessionrestore', 19:05:14 INFO - 'sessionrestore_no_auto_restore', 19:05:14 INFO - 'tabpaint']}, 19:05:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:05:14 INFO - 'ts_paint', 19:05:14 INFO - 'tpaint', 19:05:14 INFO - 'sessionrestore', 19:05:14 INFO - 'sessionrestore_no_auto_restore', 19:05:14 INFO - 'tabpaint']}, 19:05:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['a11yr', 19:05:14 INFO - 'ts_paint', 19:05:14 INFO - 'tpaint', 19:05:14 INFO - 'sessionrestore', 19:05:14 INFO - 'sessionrestore_no_auto_restore', 19:05:14 INFO - 'tabpaint']}, 19:05:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['a11yr', 19:05:14 INFO - 'ts_paint', 19:05:14 INFO - 'tpaint', 19:05:14 INFO - 'sessionrestore', 19:05:14 INFO - 'sessionrestore_no_auto_restore', 19:05:14 INFO - 'tabpaint']}, 19:05:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['tsvgx', 19:05:14 INFO - 'tsvgr_opacity', 19:05:14 INFO - 'tart', 19:05:14 INFO - 'tscrollx', 19:05:14 INFO - 'cart']}, 19:05:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:05:14 INFO - 'tsvgr_opacity', 19:05:14 INFO - 'tart', 19:05:14 INFO - 'tscrollx', 19:05:14 INFO - 'cart']}, 19:05:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:05:14 INFO - 'talos_options': ['--disable-e10s'], 19:05:14 INFO - 'tests': ['tp5o']}, 19:05:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:05:14 INFO - 'tests': ['tp5o']}, 19:05:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:05:14 INFO - 'talos_options': ['--disable-e10s', 19:05:14 INFO - '--xperf_path', 19:05:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:05:14 INFO - 'tests': ['tp5n']}, 19:05:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:05:14 INFO - 'talos_options': ['--xperf_path', 19:05:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:05:14 INFO - 'tests': ['tp5n']}}, 19:05:14 INFO - 'talos.zip': {'path': '', 19:05:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:05:14 INFO - Running post-action listener: _resource_record_post_action 19:05:14 INFO - ##### 19:05:14 INFO - ##### Finished populate-webroot step (success) 19:05:14 INFO - ##### 19:05:14 INFO - ##### 19:05:14 INFO - ##### Running create-virtualenv step. 19:05:14 INFO - ##### 19:05:14 INFO - Running pre-action listener: _resource_record_pre_action 19:05:14 INFO - Running main action method: create_virtualenv 19:05:14 INFO - Creating virtualenv /builds/slave/test/build/venv 19:05:14 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 19:05:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:05:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:05:14 INFO - Using real prefix '/usr' 19:05:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:05:14 INFO - Installing distribute.............................................................................................................................................................................................done. 19:05:16 INFO - Installing pip.................done. 19:05:16 INFO - Return code: 0 19:05:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:05:16 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')]} 19:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:16 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')]} 19:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:16 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 0x140a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c93b0>, '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 0x16ca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ca740>, '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': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', '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 19:05:16 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 19:05:16 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 19:05:16 INFO - Using env: {'DISPLAY': ':0', 19:05:16 INFO - 'HOME': '/home/cltbld', 19:05:16 INFO - 'LANG': 'en_US.UTF-8', 19:05:16 INFO - 'LANGUAGE': 'en_US:en', 19:05:16 INFO - 'LOGNAME': 'cltbld', 19:05:16 INFO - 'MAIL': '/var/mail/cltbld', 19:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:16 INFO - 'MOZ_NO_REMOTE': '1', 19:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:16 INFO - 'NO_EM_RESTART': '1', 19:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:16 INFO - 'PWD': '/builds/slave/test', 19:05:16 INFO - 'SHELL': '/bin/bash', 19:05:16 INFO - 'SHLVL': '1', 19:05:16 INFO - 'TERM': 'linux', 19:05:16 INFO - 'TMOUT': '86400', 19:05:16 INFO - 'USER': 'cltbld', 19:05:16 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', 19:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:16 INFO - '_': '/tools/buildbot/bin/python'} 19:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:16 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 19:05:16 INFO - Cleaning up... 19:05:16 INFO - Return code: 0 19:05:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:05:16 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')]} 19:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:16 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')]} 19:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:16 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 0x140a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c93b0>, '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 0x16ca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ca740>, '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': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', '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 19:05:16 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 19:05:16 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 19:05:16 INFO - Using env: {'DISPLAY': ':0', 19:05:16 INFO - 'HOME': '/home/cltbld', 19:05:16 INFO - 'LANG': 'en_US.UTF-8', 19:05:16 INFO - 'LANGUAGE': 'en_US:en', 19:05:16 INFO - 'LOGNAME': 'cltbld', 19:05:16 INFO - 'MAIL': '/var/mail/cltbld', 19:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:16 INFO - 'MOZ_NO_REMOTE': '1', 19:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:16 INFO - 'NO_EM_RESTART': '1', 19:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:16 INFO - 'PWD': '/builds/slave/test', 19:05:16 INFO - 'SHELL': '/bin/bash', 19:05:16 INFO - 'SHLVL': '1', 19:05:16 INFO - 'TERM': 'linux', 19:05:16 INFO - 'TMOUT': '86400', 19:05:16 INFO - 'USER': 'cltbld', 19:05:16 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', 19:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:16 INFO - '_': '/tools/buildbot/bin/python'} 19:05:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:17 INFO - Downloading/unpacking psutil>=3.1.1 19:05:17 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 19:05:17 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 19:05:17 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 19:05:17 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 19:05:17 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 19:05:17 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 19:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:05:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:05:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:05:19 INFO - Installing collected packages: psutil 19:05:19 INFO - Running setup.py install for psutil 19:05:19 INFO - building 'psutil._psutil_linux' extension 19:05:19 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 19:05:19 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 19:05:19 INFO - building 'psutil._psutil_posix' extension 19:05:19 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 19:05:19 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 19:05:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:05:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:05:19 INFO - Successfully installed psutil 19:05:19 INFO - Cleaning up... 19:05:19 INFO - Return code: 0 19:05:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:19 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 0x140a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c93b0>, '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 0x16ca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ca740>, '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': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', '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 19:05:19 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 19:05:19 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 19:05:19 INFO - Using env: {'DISPLAY': ':0', 19:05:19 INFO - 'HOME': '/home/cltbld', 19:05:19 INFO - 'LANG': 'en_US.UTF-8', 19:05:19 INFO - 'LANGUAGE': 'en_US:en', 19:05:19 INFO - 'LOGNAME': 'cltbld', 19:05:19 INFO - 'MAIL': '/var/mail/cltbld', 19:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:19 INFO - 'MOZ_NO_REMOTE': '1', 19:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:19 INFO - 'NO_EM_RESTART': '1', 19:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:19 INFO - 'PWD': '/builds/slave/test', 19:05:19 INFO - 'SHELL': '/bin/bash', 19:05:19 INFO - 'SHLVL': '1', 19:05:19 INFO - 'TERM': 'linux', 19:05:19 INFO - 'TMOUT': '86400', 19:05:19 INFO - 'USER': 'cltbld', 19:05:19 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', 19:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:19 INFO - '_': '/tools/buildbot/bin/python'} 19:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:05:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:05:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:05:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:05:21 INFO - Installing collected packages: mozsystemmonitor 19:05:21 INFO - Running setup.py install for mozsystemmonitor 19:05:21 INFO - Successfully installed mozsystemmonitor 19:05:21 INFO - Cleaning up... 19:05:21 INFO - Return code: 0 19:05:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:05:21 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')]} 19:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:21 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')]} 19:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:21 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 0x140a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c93b0>, '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 0x16ca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ca740>, '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': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', '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 19:05:21 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 19:05:21 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 19:05:21 INFO - Using env: {'DISPLAY': ':0', 19:05:21 INFO - 'HOME': '/home/cltbld', 19:05:21 INFO - 'LANG': 'en_US.UTF-8', 19:05:21 INFO - 'LANGUAGE': 'en_US:en', 19:05:21 INFO - 'LOGNAME': 'cltbld', 19:05:21 INFO - 'MAIL': '/var/mail/cltbld', 19:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:21 INFO - 'MOZ_NO_REMOTE': '1', 19:05:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:21 INFO - 'NO_EM_RESTART': '1', 19:05:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:21 INFO - 'PWD': '/builds/slave/test', 19:05:21 INFO - 'SHELL': '/bin/bash', 19:05:21 INFO - 'SHLVL': '1', 19:05:21 INFO - 'TERM': 'linux', 19:05:21 INFO - 'TMOUT': '86400', 19:05:21 INFO - 'USER': 'cltbld', 19:05:21 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', 19:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:21 INFO - '_': '/tools/buildbot/bin/python'} 19:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:22 INFO - Downloading/unpacking jsonschema==2.5.1 19:05:22 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 19:05:22 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 19:05:22 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 19:05:22 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 19:05:22 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 19:05:22 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 19:05:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:05:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:05:24 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 19:05:24 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 19:05:24 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 19:05:24 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 19:05:24 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 19:05:24 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 19:05:24 INFO - Downloading functools32-3.2.3-2.tar.gz 19:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:05:24 INFO - warning: no files found matching '*.txt' 19:05:24 INFO - no previously-included directories found matching 'build' 19:05:24 INFO - no previously-included directories found matching 'dist' 19:05:24 INFO - no previously-included directories found matching '.git*' 19:05:24 INFO - Installing collected packages: jsonschema, functools32 19:05:24 INFO - Running setup.py install for functools32 19:05:24 INFO - warning: no files found matching '*.txt' 19:05:24 INFO - no previously-included directories found matching 'build' 19:05:24 INFO - no previously-included directories found matching 'dist' 19:05:24 INFO - no previously-included directories found matching '.git*' 19:05:24 INFO - Successfully installed jsonschema functools32 19:05:24 INFO - Cleaning up... 19:05:24 INFO - Return code: 0 19:05:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:05: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')]} 19:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05: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')]} 19:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05: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', '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 0x140a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c93b0>, '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 0x16ca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ca740>, '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': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', '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 19:05: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:05: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 blobuploader==1.2.4 19:05:24 INFO - Using env: {'DISPLAY': ':0', 19:05:24 INFO - 'HOME': '/home/cltbld', 19:05:24 INFO - 'LANG': 'en_US.UTF-8', 19:05:24 INFO - 'LANGUAGE': 'en_US:en', 19:05:24 INFO - 'LOGNAME': 'cltbld', 19:05:24 INFO - 'MAIL': '/var/mail/cltbld', 19:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:24 INFO - 'MOZ_NO_REMOTE': '1', 19:05:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:24 INFO - 'NO_EM_RESTART': '1', 19:05:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:24 INFO - 'PWD': '/builds/slave/test', 19:05:24 INFO - 'SHELL': '/bin/bash', 19:05:24 INFO - 'SHLVL': '1', 19:05:24 INFO - 'TERM': 'linux', 19:05:24 INFO - 'TMOUT': '86400', 19:05:24 INFO - 'USER': 'cltbld', 19:05:24 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', 19:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:24 INFO - '_': '/tools/buildbot/bin/python'} 19:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:24 INFO - Downloading/unpacking blobuploader==1.2.4 19:05:24 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 19:05:24 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 19:05:24 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 19:05:24 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 19:05:24 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 19:05:24 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 19:05:26 INFO - Downloading blobuploader-1.2.4.tar.gz 19:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:05:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:05:26 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 19:05:26 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 19:05:26 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 19:05:26 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 19:05:26 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 19:05:26 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 19:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:05:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:05:27 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 19:05:27 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 19:05:27 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 19:05:27 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 19:05:27 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 19:05:27 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 19:05:27 INFO - Downloading docopt-0.6.1.tar.gz 19:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:05:27 INFO - Installing collected packages: blobuploader, requests, docopt 19:05:27 INFO - Running setup.py install for blobuploader 19:05:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:05:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:05:27 INFO - Running setup.py install for requests 19:05:27 INFO - Running setup.py install for docopt 19:05:27 INFO - Successfully installed blobuploader requests docopt 19:05:27 INFO - Cleaning up... 19:05:27 INFO - Return code: 0 19:05:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:27 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 0x140a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c93b0>, '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 0x16ca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ca740>, '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': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', '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 19:05:27 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 19:05:27 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 19:05:27 INFO - Using env: {'DISPLAY': ':0', 19:05:27 INFO - 'HOME': '/home/cltbld', 19:05:27 INFO - 'LANG': 'en_US.UTF-8', 19:05:27 INFO - 'LANGUAGE': 'en_US:en', 19:05:27 INFO - 'LOGNAME': 'cltbld', 19:05:27 INFO - 'MAIL': '/var/mail/cltbld', 19:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:27 INFO - 'MOZ_NO_REMOTE': '1', 19:05:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:27 INFO - 'NO_EM_RESTART': '1', 19:05:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:27 INFO - 'PWD': '/builds/slave/test', 19:05:27 INFO - 'SHELL': '/bin/bash', 19:05:27 INFO - 'SHLVL': '1', 19:05:27 INFO - 'TERM': 'linux', 19:05:27 INFO - 'TMOUT': '86400', 19:05:27 INFO - 'USER': 'cltbld', 19:05:27 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', 19:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:27 INFO - '_': '/tools/buildbot/bin/python'} 19:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:05:28 INFO - Running setup.py (path:/tmp/pip-5oZh9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:05:28 INFO - Running setup.py (path:/tmp/pip-B_pUlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:05:28 INFO - Running setup.py (path:/tmp/pip-5yFniw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:05:28 INFO - Running setup.py (path:/tmp/pip-1KSv94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:05:28 INFO - Running setup.py (path:/tmp/pip-7E18jX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:05:28 INFO - Running setup.py (path:/tmp/pip-5MLPIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:05:28 INFO - Running setup.py (path:/tmp/pip-WqGxqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:05:28 INFO - Running setup.py (path:/tmp/pip-dZxLFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:05:28 INFO - Running setup.py (path:/tmp/pip-qm_a3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:05:28 INFO - Running setup.py (path:/tmp/pip-mIW4Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:05:28 INFO - Running setup.py (path:/tmp/pip-UIBjzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:05:28 INFO - Running setup.py (path:/tmp/pip-8qFRVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:05:29 INFO - Running setup.py (path:/tmp/pip-on9X9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:05:29 INFO - Running setup.py (path:/tmp/pip-EfUEg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:05:29 INFO - Running setup.py (path:/tmp/pip-3N97oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:05:29 INFO - Running setup.py (path:/tmp/pip-5ZKM6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:05:29 INFO - Running setup.py (path:/tmp/pip-xJBweg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:05:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:05:29 INFO - Running setup.py install for manifestparser 19:05:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:05:29 INFO - Running setup.py install for mozcrash 19:05:29 INFO - Running setup.py install for mozdebug 19:05:29 INFO - Running setup.py install for mozdevice 19:05:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:05:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:05:29 INFO - Running setup.py install for mozfile 19:05:29 INFO - Running setup.py install for mozhttpd 19:05:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Running setup.py install for mozinfo 19:05:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Running setup.py install for mozInstall 19:05:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Running setup.py install for mozleak 19:05:30 INFO - Running setup.py install for mozlog 19:05:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Running setup.py install for moznetwork 19:05:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Running setup.py install for mozprocess 19:05:30 INFO - Running setup.py install for mozprofile 19:05:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Running setup.py install for mozrunner 19:05:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:05:30 INFO - Running setup.py install for mozscreenshot 19:05:30 INFO - Running setup.py install for moztest 19:05:31 INFO - Running setup.py install for mozversion 19:05:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:05:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:05:31 INFO - Cleaning up... 19:05:31 INFO - Return code: 0 19:05:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:31 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 0x140a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c93b0>, '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 0x16ca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ca740>, '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': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', '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 19:05:31 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 19:05:31 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 19:05:31 INFO - Using env: {'DISPLAY': ':0', 19:05:31 INFO - 'HOME': '/home/cltbld', 19:05:31 INFO - 'LANG': 'en_US.UTF-8', 19:05:31 INFO - 'LANGUAGE': 'en_US:en', 19:05:31 INFO - 'LOGNAME': 'cltbld', 19:05:31 INFO - 'MAIL': '/var/mail/cltbld', 19:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:31 INFO - 'MOZ_NO_REMOTE': '1', 19:05:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:31 INFO - 'NO_EM_RESTART': '1', 19:05:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:31 INFO - 'PWD': '/builds/slave/test', 19:05:31 INFO - 'SHELL': '/bin/bash', 19:05:31 INFO - 'SHLVL': '1', 19:05:31 INFO - 'TERM': 'linux', 19:05:31 INFO - 'TMOUT': '86400', 19:05:31 INFO - 'USER': 'cltbld', 19:05:31 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', 19:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:31 INFO - '_': '/tools/buildbot/bin/python'} 19:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:05:31 INFO - Running setup.py (path:/tmp/pip-2h2VQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:05:31 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)) 19:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:05:31 INFO - Running setup.py (path:/tmp/pip-bHQuhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:05:31 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)) 19:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:05:31 INFO - Running setup.py (path:/tmp/pip-0xwzqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:05:31 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)) 19:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:05:31 INFO - Running setup.py (path:/tmp/pip-7NTcrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:05:31 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)) 19:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:05:31 INFO - Running setup.py (path:/tmp/pip-KkHe_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:05:31 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)) 19:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:05:31 INFO - Running setup.py (path:/tmp/pip-HLH7hZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:05:31 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)) 19:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:05:31 INFO - Running setup.py (path:/tmp/pip-ImNtma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:05:31 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)) 19:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:05:31 INFO - Running setup.py (path:/tmp/pip-V9E_CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:05:32 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)) 19:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:05:32 INFO - Running setup.py (path:/tmp/pip-yhX5MQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:05:32 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)) 19:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:05:32 INFO - Running setup.py (path:/tmp/pip-UPUgYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:05:32 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)) 19:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:05:32 INFO - Running setup.py (path:/tmp/pip-L2nIwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:05:32 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)) 19:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:05:32 INFO - Running setup.py (path:/tmp/pip-vuVYWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:05:32 INFO - Running setup.py (path:/tmp/pip-btiL1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:05:32 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)) 19:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:05:32 INFO - Running setup.py (path:/tmp/pip-117pgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:05:32 INFO - Running setup.py (path:/tmp/pip-KqU8d7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:05:32 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)) 19:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:05:32 INFO - Running setup.py (path:/tmp/pip-qGVd0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:05:32 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)) 19:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:05:32 INFO - Running setup.py (path:/tmp/pip-Szg6fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:05:32 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)) 19:05:32 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)) 19:05:32 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)) 19:05:32 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)) 19:05:32 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)) 19:05:32 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)) 19:05:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:05:32 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 19:05:32 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 19:05:32 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 19:05:32 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 19:05:32 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 19:05:32 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 19:05:34 INFO - Downloading blessings-1.6.tar.gz 19:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:05:34 INFO - Installing collected packages: blessings 19:05:34 INFO - Running setup.py install for blessings 19:05:34 INFO - Successfully installed blessings 19:05:34 INFO - Cleaning up... 19:05:34 INFO - Return code: 0 19:05:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:05:35 INFO - Reading from file tmpfile_stdout 19:05:35 INFO - Current package versions: 19:05:35 INFO - argparse == 1.2.1 19:05:35 INFO - blessings == 1.6 19:05:35 INFO - blobuploader == 1.2.4 19:05:35 INFO - docopt == 0.6.1 19:05:35 INFO - functools32 == 3.2.3-2 19:05:35 INFO - jsonschema == 2.5.1 19:05:35 INFO - manifestparser == 1.1 19:05:35 INFO - mozInstall == 1.12 19:05:35 INFO - mozcrash == 0.17 19:05:35 INFO - mozdebug == 0.1 19:05:35 INFO - mozdevice == 0.48 19:05:35 INFO - mozfile == 1.2 19:05:35 INFO - mozhttpd == 0.7 19:05:35 INFO - mozinfo == 0.9 19:05:35 INFO - mozleak == 0.1 19:05:35 INFO - mozlog == 3.2 19:05:35 INFO - moznetwork == 0.27 19:05:35 INFO - mozprocess == 0.23 19:05:35 INFO - mozprofile == 0.28 19:05:35 INFO - mozrunner == 6.12 19:05:35 INFO - mozscreenshot == 0.1 19:05:35 INFO - mozsystemmonitor == 0.3 19:05:35 INFO - moztest == 0.7 19:05:35 INFO - mozversion == 1.4 19:05:35 INFO - psutil == 3.1.1 19:05:35 INFO - requests == 1.2.3 19:05:35 INFO - wsgiref == 0.1.2 19:05:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:05:35 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')]} 19:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:35 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')]} 19:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:35 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 0x140a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c93b0>, '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 0x16ca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ca740>, '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': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', '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 19:05:35 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 19:05:35 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 19:05:35 INFO - Using env: {'DISPLAY': ':0', 19:05:35 INFO - 'HOME': '/home/cltbld', 19:05:35 INFO - 'LANG': 'en_US.UTF-8', 19:05:35 INFO - 'LANGUAGE': 'en_US:en', 19:05:35 INFO - 'LOGNAME': 'cltbld', 19:05:35 INFO - 'MAIL': '/var/mail/cltbld', 19:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:35 INFO - 'MOZ_NO_REMOTE': '1', 19:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:35 INFO - 'NO_EM_RESTART': '1', 19:05:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:35 INFO - 'PWD': '/builds/slave/test', 19:05:35 INFO - 'SHELL': '/bin/bash', 19:05:35 INFO - 'SHLVL': '1', 19:05:35 INFO - 'TERM': 'linux', 19:05:35 INFO - 'TMOUT': '86400', 19:05:35 INFO - 'USER': 'cltbld', 19:05:35 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', 19:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:35 INFO - '_': '/tools/buildbot/bin/python'} 19:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:35 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)) 19:05:35 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)) 19:05:35 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)) 19:05:35 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)) 19:05:35 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)) 19:05:35 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)) 19:05:35 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)) 19:05:35 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)) 19:05:35 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)) 19:05:35 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)) 19:05:35 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)) 19:05:35 INFO - Cleaning up... 19:05:35 INFO - Return code: 0 19:05:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:05:35 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')]} 19:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:35 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')]} 19:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:35 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 0x140a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c93b0>, '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 0x16ca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ca740>, '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': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', '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 19:05:35 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 19:05:35 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 19:05:35 INFO - Using env: {'DISPLAY': ':0', 19:05:35 INFO - 'HOME': '/home/cltbld', 19:05:35 INFO - 'LANG': 'en_US.UTF-8', 19:05:35 INFO - 'LANGUAGE': 'en_US:en', 19:05:35 INFO - 'LOGNAME': 'cltbld', 19:05:35 INFO - 'MAIL': '/var/mail/cltbld', 19:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:35 INFO - 'MOZ_NO_REMOTE': '1', 19:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:35 INFO - 'NO_EM_RESTART': '1', 19:05:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:35 INFO - 'PWD': '/builds/slave/test', 19:05:35 INFO - 'SHELL': '/bin/bash', 19:05:35 INFO - 'SHLVL': '1', 19:05:35 INFO - 'TERM': 'linux', 19:05:35 INFO - 'TMOUT': '86400', 19:05:35 INFO - 'USER': 'cltbld', 19:05:35 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', 19:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:35 INFO - '_': '/tools/buildbot/bin/python'} 19:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:05:35 INFO - Cleaning up... 19:05:35 INFO - Return code: 0 19:05:35 INFO - Running post-action listener: _resource_record_post_action 19:05:35 INFO - Running post-action listener: _start_resource_monitoring 19:05:35 INFO - Starting resource monitoring. 19:05:35 INFO - ##### 19:05:35 INFO - ##### Finished create-virtualenv step (success) 19:05:35 INFO - ##### 19:05:35 INFO - ##### 19:05:35 INFO - ##### Running install step. 19:05:35 INFO - ##### 19:05:35 INFO - Running pre-action listener: _resource_record_pre_action 19:05:35 INFO - Running main action method: install 19:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:05:35 INFO - Reading from file tmpfile_stdout 19:05:35 INFO - Detecting whether we're running mozinstall >=1.0... 19:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:05:35 INFO - Reading from file tmpfile_stdout 19:05:35 INFO - Output received: 19:05:35 INFO - Usage: mozinstall [options] installer 19:05:35 INFO - Options: 19:05:35 INFO - -h, --help show this help message and exit 19:05:35 INFO - -d DEST, --destination=DEST 19:05:35 INFO - Directory to install application into. [default: 19:05:35 INFO - "/builds/slave/test"] 19:05:35 INFO - --app=APP Application being installed. [default: firefox] 19:05:35 INFO - mkdir: /builds/slave/test/build/application 19:05:35 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'] 19:05:35 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 19:05:53 INFO - Reading from file tmpfile_stdout 19:05:53 INFO - Output received: 19:05:53 INFO - /builds/slave/test/build/application/firefox/firefox 19:05:53 INFO - Running post-action listener: _resource_record_post_action 19:05:53 INFO - ##### 19:05:53 INFO - ##### Finished install step (success) 19:05:53 INFO - ##### 19:05:53 INFO - ##### 19:05:53 INFO - ##### Running run-tests step. 19:05:53 INFO - ##### 19:05:53 INFO - Running pre-action listener: _resource_record_pre_action 19:05:53 INFO - Running main action method: run_tests 19:05:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:05:53 INFO - Python 2.7.3 19:05:53 INFO - Return code: 0 19:05:53 INFO - grabbing minidump binary from tooltool 19:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:05:53 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 0x16c93b0>, '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 0x16ca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ca740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:05:53 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 19:05:53 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 19:05:53 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 19:05:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:05:53 INFO - Return code: 0 19:05:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:05:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:05:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:05:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:05:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:05:53 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/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 19:05:53 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/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 19:05:53 INFO - Using env: {'DISPLAY': ':0', 19:05:53 INFO - 'HOME': '/home/cltbld', 19:05:53 INFO - 'LANG': 'en_US.UTF-8', 19:05:53 INFO - 'LANGUAGE': 'en_US:en', 19:05:53 INFO - 'LOGNAME': 'cltbld', 19:05:53 INFO - 'MAIL': '/var/mail/cltbld', 19:05:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:05:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:53 INFO - 'MOZ_NO_REMOTE': '1', 19:05:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:05:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:53 INFO - 'NO_EM_RESTART': '1', 19:05:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:53 INFO - 'PWD': '/builds/slave/test', 19:05:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:05:53 INFO - 'SHELL': '/bin/bash', 19:05:53 INFO - 'SHLVL': '1', 19:05:53 INFO - 'TERM': 'linux', 19:05:53 INFO - 'TMOUT': '86400', 19:05:53 INFO - 'USER': 'cltbld', 19:05:53 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313', 19:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:53 INFO - '_': '/tools/buildbot/bin/python'} 19:05:53 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/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 19:05:53 INFO - mozversion application_buildid: 20160713173001 19:05:53 INFO - mozversion application_changeset: 31cab0ba8bbf1d88b08abeae45bcdf317645355a 19:05:53 INFO - mozversion application_display_name: Nightly 19:05:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:05:53 INFO - mozversion application_name: Firefox 19:05:53 INFO - mozversion application_remotingname: firefox 19:05:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:05:53 INFO - mozversion application_vendor: Mozilla 19:05:53 INFO - mozversion application_version: 50.0a1 19:05:53 INFO - mozversion platform_buildid: 20160713173001 19:05:53 INFO - mozversion platform_changeset: 31cab0ba8bbf1d88b08abeae45bcdf317645355a 19:05:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:05:53 INFO - mozversion platform_version: 50.0a1 19:05:53 INFO - using testdate: 1468461953 19:05:53 INFO - actual date: 1468461953 19:05:53 INFO - starting webserver on 'localhost:49961' 19:05:53 INFO - SUITE-START | Running 2 tests 19:05:53 INFO - TEST-START | dromaeo_css 19:05:53 INFO - operating with platform_type : linux_ 19:05:53 INFO - Initialising browser for dromaeo_css test... 19:05:53 INFO - TEST-INFO | started process 27327 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBPulNq/profile http://localhost:49961/getInfo.html) 19:05:57 INFO - PROCESS | 27327 | __metrics Screen width/height:1600/1200 19:05:57 INFO - PROCESS | 27327 | colorDepth:24 19:05:57 INFO - PROCESS | 27327 | Browser inner width/height: 1024/697 19:05:57 INFO - PROCESS | 27327 | __metrics 19:05:58 INFO - PROCESS | 27327 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:05:58 INFO - PROCESS | 27327 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:05:58 INFO - PROCESS | 27327 | console.error: PushService: 19:05:58 INFO - PROCESS | 27327 | stateChangeProcessEnqueue: Error transitioning state 19:05:58 INFO - PROCESS | 27327 | UnknownError 19:05:59 INFO - TEST-INFO | 27327: exit 0 19:05:59 INFO - Browser initialized. 19:05:59 INFO - Running cycle 1/1 for dromaeo_css test... 19:05:59 INFO - TEST-INFO | started process 27408 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBPulNq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:06:11 INFO - PROCESS | 27408 | RSS: Main: 157634560 19:06:11 INFO - PROCESS | 27408 | 19:07:01 INFO - PROCESS | 27408 | 1468462021838 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] 19:09:11 INFO - PROCESS | 27408 | Cycle 1(1): loaded http://localhost:49961/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49961/tests/dromaeo/cssquery-ext.html) 19:09:12 INFO - PROCESS | 27408 | RSS: Main: 247037952 19:09:12 INFO - PROCESS | 27408 | 19:12:14 INFO - PROCESS | 27408 | Cycle 1(1): loaded http://localhost:49961/tests/dromaeo/cssquery-ext.html (next: http://localhost:49961/tests/dromaeo/cssquery-jquery.html) 19:12:14 INFO - PROCESS | 27408 | RSS: Main: 244473856 19:12:14 INFO - PROCESS | 27408 | 19:15:16 INFO - PROCESS | 27408 | Cycle 1(1): loaded http://localhost:49961/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49961/tests/dromaeo/cssquery-mootools.html) 19:15:17 INFO - PROCESS | 27408 | RSS: Main: 244920320 19:15:17 INFO - PROCESS | 27408 | 19:18:19 INFO - PROCESS | 27408 | Cycle 1(1): loaded http://localhost:49961/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49961/tests/dromaeo/cssquery-prototype.html) 19:18:20 INFO - PROCESS | 27408 | RSS: Main: 238653440 19:18:20 INFO - PROCESS | 27408 | 19:21:22 INFO - PROCESS | 27408 | Cycle 1(1): loaded http://localhost:49961/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49961/tests/dromaeo/cssquery-yui.html) 19:21:23 INFO - PROCESS | 27408 | RSS: Main: 205197312 19:21:23 INFO - PROCESS | 27408 | 19:24:26 INFO - PROCESS | 27408 | Cycle 1(1): loaded http://localhost:49961/tests/dromaeo/cssquery-yui.html (next: http://localhost:49961/tests/dromaeo/cssquery-dojo.html) 19:24:26 INFO - PROCESS | 27408 | RSS: Main: 281120768 19:24:26 INFO - PROCESS | 27408 | 19:24:26 INFO - PROCESS | 27408 | __start_tp_report 19:24:26 INFO - PROCESS | 27408 | _x_x_mozilla_page_load 19:24:26 INFO - PROCESS | 27408 | _x_x_mozilla_page_load_details 19:24:26 INFO - PROCESS | 27408 | |i|pagename|runs| 19:24:26 INFO - PROCESS | 27408 | |0;dojo.html;3087.9073627791167;3091.70628790265;3117.06488053584;3117.360941007094;3157.873685052598;5351.117065684167;5416.674999500023;5453.890922181559;5564.415736347686;5588.888222235558;5415.241866138744;5416.539594134503;5476.67139971602;5477.917831232535;5496.203050557667;4910.729909854966;4966.329545511364;5001.299818025479;5012.8496145115705;5116.590672746172;5022.874428139307;5027.899442011176;5063.46833582474;5081.64428490004;5086.84739457815;4814.87962800931;4835.08133454646;4885.902281954376;4962.751862406865;4971.552560269557;4891.535304146136;4894.265860120975;4912.312276281324;4918.458969513351;4992.925106123411;4910.337104490919;4947.431045429749;4950.455449900508;4975.577075948549;4976.850694479172;5251.4485978972425;5393.056215162331;5420.945790542083;5469.124940009579;5472.945270547323;5870.090136028923;5884.146798714162;5891.440313170242;5933.910991335133;5943.70281485924;5962.552808539377;5963.970180149115;5975;5988.9401105989255;5995.070764031582;5316.6544174628525;5423.349198096214;5469.261649677323;5478.123500239943;5510.421405752419;6052.694515306098;6053.85181794665;6055.5163537702365;6100.115483254903;6111.236095488064;5607.243022191952;5840.88318233633;5843.094320380348;5859.062549991981;5860.560457965669;5793.449622285876;5829.358770535237;5853.239078919798;5894.204282421904;5896.7641294348605;5864;5877.617954832922;5880.059190529495;5884.352721200665;5887.735051922674;5694.288213973254;5789.073748200268;5835.416458354215;5882.088276317178;5897.705114744245;4323.891902702458;4390.824367025347;4393.934090988638;4427.158839820424;4431.667624928144;3917.490726205632;3928.46965659638;3938.940915886264;3946.131850992834;3966.186931678999;5393.6763794172475;5447;5465.562754979592;5594.272744543184;5615.157726341082;5822;5851.765929362778;5892.852678683067;5897.085951677411;5951.553633477506;5553.972230138824;5570.832875013756;5572.554195664418;5573.470520300646;5574.247476590608;4839.080574690797;4941.629377796679;4947.752612369367;4956.5786908112495;5014.648974571818;6103.761967187076;6110.862540392742;6114.236541168587;6127.097902969301;6145.463634091477;5960.940390596039;5961.105834124916;5961.165436838933;5962.956482615438;5981.043033114681;5869.383714709894;6013.879722405615;6034;6060.424259695322;6141.416565426277;5753.798617048383;5798.768049277982;5807.970960145172;5810.622309549865;5812.360640329476;5904.468597826216;5908.556858235564;5910.497607703222;5910.822675319747;5915.112733090071;5447.863803404788;5644.9435505644415;5671.234383358194;5689.459501347365;5728.713564321851;5701.201831743476;5702.258706368146;5703.173039909273;5707.6575405475805;5712.542996560348;5457.836264912059;5635.520980716685;5645.096784514458;5646.85882852932;5647.774089036392;5697.601167918206;5758.049921762862;5781.710914454176;5799.8550036249435;5868.67722275279;5674.0354139797155;5679.091345384883;5680.857978550569;5683.857903552444;5689.431056894277 19:24:26 INFO - PROCESS | 27408 | |1;ext.html;589.8112603966731;598.4763332084426;602.659497383978;611.9449249567535;613.957023008389;44183.55816441835;45865.77067114668;45866.770666146665;48662.75668621652;48701.75649121749;46587.53412465874;46975;46994.76502617491;47117.76441117789;47146;9003.099690030993;9147.771305717344;9151.130642588943;9157.31320150988;9157.679481218158;8918;9013.278937684969;9021.09789021096;9055.818883622323;9056.909430905705;9132.817343653123;9162.816743665122;9169.449833010007;9170.312226582999;9173.770655733626;8946.284297256203;9049.638014479413;9060.682876099338;9103.81792364149;9104;8919.375643704943;8967.327450441244;8977.955110224408;8978.640854365818;8995.415298005673;43734;44117.558824411666;44146.117077658586;44291.77854110709;44504;3595.622459641726;3652.7077833773233;3666.2667466506805;3802.695784337274;4067.430559721641;33974.490382644035;34000;34231.82884085589;34283.31433371344;35144.648553514395;4104.322786740185;4413.801378937956;4474.418325617681;4486.753228572427;4557.931631025537;311605;397283;398235;398387;399299;62122.378776212565;62415.68792156101;62657.06014409787;62669.68665156645;62942.68528657327;57076.42923570794;64190;65599.67200163969;65654.34345656578;70065.64967175234;14451.78322325166;14538.345774440175;14573.344199511048;14575.489857855137;14584.343704533321;11025.61410350634;12126.757464850654;12217.633470995885;12218.327991960528;12220.816687749691;19472;19577.944315846915;19691.661877514132;20218.292359767336;20460.590788184152;6866.862662746817;7005.334493223136;7072.5402848814665;7154.713811447484;7321.74373896911;1013.5388398278766;1022.3456987527989;1103.1230172013268;1109.6892869996414;1120.6694025262568;33260.43965349732;33955.32089358248;33986.32027359439;34066;34340.31319373599;1100.785346857355;1123.2361993844265;1130.886911308879;1132.3432409202642;1153.3368313219933;17098.91450542764;17736.91131544334;17858.91070544639;17860.55348616269;17913.19390627425;615429;618353;619394;625112;625153;60478;60558.39441605616;60665.393346066856;60693;60724.69637651784;1212.5816593275306;1264.399410280128;1342.8858547023613;1343.2746316988912;1365.7541642504445;30652;34747.13132171716;35061.82469087638;35134.30316270335;35254.64745352514;52506.73746631242;52788.20817687661;53161.202581960526;53167.734161328946;53195.46804531905;423755;427142;428073;430956;431974;23181.88409057944;28164.15507534777;28182.29544261328;28477.715222847506;29489.705102948697;49594;49779.75110124571;49845.75077124591;50376.74811625919;51172.232416514526;42219;42464.78767606142;43103.568964309954;43346.133077338905;44302.778486108655;42497.78751106225;42819.14361712811;43414.34878476762;44043.33934991042;44101.33847992218;41331.84118526692;42281;42281;42557.787211064984;42648.36027459529;35968.28063438664;38201.42697859479;38280.80859595684;38723.22553548857;39067.21865562616;28891.5666265002;29190;29413;29487.705122949355;29658.40683186281 19:24:26 INFO - PROCESS | 27408 | |2;jquery.html;376.42219193537926;389.33618181001384;391.4421948723069;402.1173524114566;421.6416046360594;14632.414703411863;17938.73091903621;19472.902635486804;20173;20209.49476263094;18986.52533686655;19022.334218302363;19170.61658766824;19183.808161918376;19245.326413575527;5559.388467268597;5658.632188907708;5716.028275193227;5757.568182386317;5761.4814666679995;5453.754581043862;5571.164325351189;5601.467860553262;5621.213030175779;5625.831225063254;5414.729263536808;5591.888162236753;5605.27131472908;5607.607467477278;5610.495055445009;5580.581456390767;5580.637258578179;5610.158476228598;5627.7467513961765;5632.295963004624;4782.378290822206;4951.653384263103;4953.876153096166;5075.822346217865;5076.111680455905;22718.54562908749;23182.1886233981;23266.767332326628;23272.185473508514;23286;2259.559385919746;2375.8812059397133;2698.9190324290107;2956.7338939495335;3039.8784048638026;10560.94719526405;12216.205946613441;14237.572872813833;14435.061720988102;14850.480233191895;1734.210934025028;1829.4877434318412;1831.8168183181576;1835.1374853818684;1864.3008871673123;84483;85799;86882.56558717166;86894.56552717258;92293;10325.690229293134;10768.784624307627;11091.889081109248;11184.105271578253;11472.4837382318;15456.304466299043;15531.378744850306;15559.45541906028;15570.922145389428;15574.377024919104;7744.3417309528195;7780.260875216845;7803.141654418009;7849.175836537195;7894.368450523944;7221.458390620619;7389.150247721581;7402.222766609536;7733.49732267412;8019;4551.931721024121;4647;4648.883777905579;4658.95341046592;4711.623070154379;1845.7323688065424;1917.472695008856;1922.5001499610203;1945.2705235536673;1950.8536859735577;981.4209616326259;991.4150651115876;993.1161266472845;1055.8310670292717;1077.6713102503654;19470.70793938092;19657.508562286057;19998.70001949972;20020.69968950467;20139.899300503697;1018.2057994764095;1027.3989716016065;1028.861103750999;1032.4115254304975;1079.1959989807644;10508.579656813641;11039.39283339424;11073.72315692098;11113.611023614134;11150.219484635998;422853;424353;427254;542756;549734;97507.51246243865;102827.4858625702;102852.48573757234;102982.38382112698;104021;1037.8443233514881;1058.730023843915;1077.9676609701721;1080.2762149359949;1082.7022568793645;17246.568835779206;17566.473005809847;17777.46667599974;17890;17928.462146135636;42064.78967605143;43039.139217214855;43051.56948430601;44549.55450445454;45117.774411127735;60120;61290;61658;63931.36068639254;70469.29530704832;8482.279006284534;8530.957345213234;8551.230389264996;8615.784605384915;8798.604062817061;9492.57283422234;13635.386407611482;13669;13714.683645045603;14147.292635367985;11741.941290293495;12104.878951210376;12213.633590992284;12230.694232644255;12313.44589493457;11794.174407791716;11948.462319195483;12013.819792702941;12020.939895300467;12164.452599632861;11786.46960886745;11812.940935295268;11960.521579136737;11981.940090299493;12255.325957072446;7687.730929417444;8034.076081250497;8661.090585488684;8879.91120088791;8980.012198658012;12252.08109391817;12373.876261237272;12496.812547811973;12504.687382815502;12567.748645027232 19:24:26 INFO - PROCESS | 27408 | |3;mootools.html;1311.1674086954793;1463.4512057978259;1510.192047254719;1648.7526870969352;1855.072463768116;8602.225799678035;8967.686130985418;8984.371094023421;9240.26077913767;9245.953770231155;8954.37319387643;9159.496227707474;9163.129502697233;9166.495842728647;9168.404053736502;2039.9898000509977;2229.732432108141;2290.3701482092392;2347.671326014355;2373.7151541815006;2333.498297865967;2339.181286549711;2346.8239882008834;2346.8709220992837;2353.49399879026;2313.6298192289237;2317.9072837086496;2372.1341710275797;2375.738668746445;2375.95248095038;2026.6453370660076;2169.8155656769036;2174.282486779359;2224.5439684864564;2234.430220293831;2207.7461091974305;2223.844330896838;2233.7207849018873;2244.9102035918545;2252.3693365857584;9095;9114.361994660376;9168.908310916871;9193.034731353178;9195.862062069074;5151.922721159186;5281.313429254211;5291.550218231455;5299.894002119974;5300.41695413508;3217.131374528864;3330.6669333066743;3403.795772253648;3405.1487128217946;3426.9657303426898;1114.2256131988272;1114.2645853736576;1121.3776354123459;1126.1553834624033;1130.9660710178707;12549.686257843627;12553.623391298184;12571.371431428484;12704.6823829405;12708.936455317664;4625.722456652612;4638.350630911675;4805.495422980606;4853.635977301717;4859.1496488114435;10580.10069144116;10581.62964296246;10667.359958402365;10668.466576671291;10670.25308228432;5167.819126330561;5168.483151684876;5205.45342739007;5206.947930520722;5211.348581427322;4335.523092459827;4705.294205869079;4829.541193586604;4831.51684831514;4917.483664215278;4046.817285012799;4055.6755459563165;4083.571225021389;4113.85601503946;4128.050548373891;1165.0679456434818;1196.5811965811897;1232.4946771823688;1260.1179174577833;1277.393238211842;856.7172832965107;908.04655112132;996.6760802738994;1017.3234798858824;1042.9634962776267;6362;6478.352164783484;6520.771772988018;6554.377871020309;6593.8351541210895;933.6778811310087;948.4167237149104;993.2203220471895;1019.3883669798211;1020.2756043209256;4488.798004089824;4524.366588677588;4527.61515271199;4539.7049191802425;4544.091181763661;10809.135269178447;10842.29525080883;11231.831522527169;11244.044256238149;11253.156013299036;11238.550457981753;11396.943015285036;11466.08271338291;11491.54033838637;11494.942525287488;8437.451565648213;8621;8621.353398495012;8624.094470080676;8628.26659733917;6277.811665650038;6451.225852897683;6542.4964658615;6596.472282217315;6657.101291325609;10140.695779126343;10436.425996570262;10561.313514621525;10571.312864663772;10672.733181670521;10419.322744021614;10506.632267870587;10828.566857325619;10852.720928604766;10883.564657413615;7725.806854828675;8326.70856520019;8582.356323275655;8611.397202195787;8796.076411976906;8769.342299327704;8796.296296296154;8823.558822059;8857;8870.2460290876;7582.165961744314;7713.305802477804;7853.646585903533;8014.238647328494;8024.358051355995;7686.769396918102;7910.208979102274;7929.365650747811;7965.243301886311;8017.037955445384;7181.425485961006;7330.743423980135;7507.62461876893;7509.774706758806;7511.286427789351;5477.698726570077;5525.171224316385;5546.861328466821;5552.833414997395;5597.160425936142;5506.504414602705;5549.944500555104;5584.525315348241;5677.51741102011;5739.713014349182 19:24:26 INFO - PROCESS | 27408 | |4;prototype.html;296.4042275027195;306.17180526675344;316.6437757522787;325.9674032596741;326.46133879099483;5291.386127231193;5336.466353364662;5353;5359.410464848864;5442.591805614585;5001.8499445016705;5127.3078134451835;5214.348206474191;5247.790088396469;5261.368635763704;4087.81604827782;4133.73130746502;4140.213359461708;4144.772037537934;4160.147169830193;4159.542450330461;4166.625003749662;4179.226843034034;4180.6237438630515;4184.8535301264465;3726.0881065623284;3926.528816542019;4034.838606455738;4042.151148258869;4109.773962432065;4074.653654439376;4085.8978525536804;4091.9795401022952;4092.1815636872598;4097.344424892003;3612.1150318171954;3617.5297211362617;3619.239959608485;3621.3843646580144;3639.1448009717697;4956.330895329121;5140.640155189139;5161.7161056141895;5167.534921857051;5171.715555644438;4641.698289611165;4654.418197725285;4656.3946686930485;4668.6731928765;4684.180268453041;5525.364583072957;5554.222408862763;5570.498655121018;5594.2447769551;5604.187392828017;3833.6358045985585;3837.424386342043;3877.418387241908;3954.2882281189372;3962.0689138052535;5649.180868774046;5718.170865224519;5751.664034859771;5781.306243250795;5787.884242315172;4026.778527181033;4049.6152865477734;4055.858044968412;4056.6146216109423;4061.5735347788645;3883.2815929053218;4095.344744840811;4095.5494895561465;4105.44576482177;4107.342825147962;4665.95334046662;4687.718736875799;4691.601213896857;4697.107549565571;4707;5528.668279903219;5572.916406253909;5575.804846830341;5593.440655934455;5632.352279487829;1349.6288520656701;1379.5792283353474;1383.702503961653;1389.7359501694646;1393.7351903138574;856.3491746272878;861.3023451004706;863.9416714524657;868.68727258187;873;4811.735354555533;4817.421909370828;4830.275458681155;4833.235868907824;4836.564709176191;5065.645404821627;5066.949330506648;5070.884405430799;5093.592512599057;5106.591472682251;4604.700694454849;4769.546893045155;4786.946871688223;4807.927881081787;4825.445073816486;2687.986560067187;2690.3946612012533;2696.7572918437436;2698.7706044986003;2701.405690748032;5181.165937206515;5194.428612852583;5202.791888324425;5212.765425555859;5226.719453733859;4843.636727245471;5056.671316364498;5119.564836988823;5120.769565369567;5131.152784997473;5184.688918664892;5201.947980520146;5232.502912223409;5233.398159211663;5276.467295698087;4956.7769450375545;5000.424951130666;5017.3191980685415;5089.592832573311;5128.205128205098;4773.66584339093;4962.429320628174;4987.775550100182;4994.375703037121;5042.596592272536;5108.3103780989095;5114.363403710777;5128.8461346159675;5170.302009228706;5187.481251874782;3597.964020359763;3599.3521166190335;3613.4218525035867;3623.6195199503672;3711.8678802965355;5512.5314348280845;5657.8019769307875;5661.867626474639;5708.600397972102;5739.220841539136;5529.502344788988;5579.963313390665;5582.4138465462165;5589.888202236014;5620.494155526022;5322.41453440129;5518.420565840529;5527.419620939743;5551.306086739158;5578.553715702653;5394.973025134849;5447.591430642638;5453.754581043783;5459.153831156177;5467.753951072132;5558.221848941071;5576.804811831566;5582.776688932559;5608.270924779753;5616.887662246814;5216.556592689664;5443.564514838724;5449.209864569536;5457.317835270591;5469.890602188014 19:24:26 INFO - PROCESS | 27408 | |5;yui.html;706.487796347648;721.1202333153551;830.8213734047179;862.5816479007684;865.6148014133709;14349.282535873217;14666.926665366658;14764.48324308648;15583.298751556178;15753.842461575396;14105.647358816035;14220.217888016155;14243.928780356084;14247.287635618204;14253.643658908533;675.2572170612311;784.0669603172236;809.0857331215733;827.7806381308958;828.9088200297963;836.393614629395;847.6948298612494;848.0374774630808;848.2874385516166;848.4739461533858;883.6509578716403;889.7508697564692;889.862071378934;890.2432932007806;890.8708237305586;712.0707476742848;847.5592691800291;860.2515811244195;880.8062226310202;884.2130503851578;627.5262177056339;627.6077451592755;628.8144997225829;629.1255155334044;629.3234772619452;13842.723145537033;13905.235212063333;13977.301134943213;14006.509772158028;14138.929305353511;13166.934165329209;13593.184408935496;13610.455581776718;13753.518626848012;13858.653533661638;12915.160514566616;13005.21968681882;13139.60581182566;13246.801297980537;13331.86668133316;4539.114872599845;4606.838760643395;4615.561521655471;4710.26990816424;4731.33761273422;77429.61285193595;79204.99999999942;79233;79437;80016.59991700063;10297.639582614573;10499.842502362471;10526.684199474028;11720.648380548597;11831.349275789742;21490.892545537172;21775.564488710137;21798.673019904712;21804;21804.127834886745;8301.626426810808;8364.37267204962;8560.914390856136;8623.698170564001;8666.78333041679;4642.930356044662;4767.833125840579;4801.5438533339275;4819.6867203632355;4873.390826146731;3482.4428091505742;3601.837917293728;3604.351216780952;3609.3503169429227;3610.3862343401684;668.7993601919404;674.9966250168816;680.1226417920826;681.0159319782965;689.9448044156455;623.8689875126274;683.7504310926475;715.2561336210387;729.6315360742791;737.881938889775;9645.855312170323;9935.453550054672;10018.198544116452;10105;10158.288919775545;670.718298314709;692.7609974558769;697.3758486154965;712.2699233285923;775.8797386405116;4963.90072198554;4969.428515720666;5041.571466425322;5068.239764035424;5081.8475445736685;67205;67822.66088669524;68073.65963170152;68127.65936170287;89556;104900;105144.47427762812;105429.47285263524;105739.47130264454;106133.4693326544;63228;69481.65259173671;73026.63486682638;86848.56575717081;89059.10940890638;9722.951385243028;9848.310618256653;9900.554475048784;9933.3543319684;9945.403275803475;37327.81336093302;41643.7917810409;49132.508674912795;50353.74823125861;51034.489655102974;45342.54657453383;45527.08945821131;59040;76280.61859690666;76485.61757191179;11702;12215.450304736485;12232.449539770554;12240.693982650506;12648.936755316165;13962.441502339792;14246.857531424554;14335.56993290203;14930.402783888523;15002.849971500582;10357.585696572052;11687.357195353998;11840.70398240051;12324;13091.738165236833;14149.858501414854;14432.927835360755;14586.4894728684;15260.160691162093;15322.157281349633;14084.577462676136;14229.50196743109;14770.778438323649;14981.101133932;15090.698186036438;10861.121802722442;11113.277636953571;11301.378424186749;11460.255083419552;11472;11138.832917506083;11171;11506.194566380273;11570.132240082195;11618.477168527268 19:24:26 INFO - PROCESS | 27408 | __end_tp_report 19:24:26 INFO - PROCESS | 27408 | __start_cc_report 19:24:26 INFO - PROCESS | 27408 | _x_x_mozilla_cycle_collect,776 19:24:26 INFO - PROCESS | 27408 | __end_cc_report 19:24:26 INFO - PROCESS | 27408 | __startTimestamp1468463066302__endTimestamp 19:24:26 INFO - PROCESS | 27408 | 19:24:26 INFO - PROCESS | 27408 | ------- Summary: start ------- 19:24:26 INFO - PROCESS | 27408 | Number of tests: 6 19:24:26 INFO - PROCESS | 27408 | 19:24:26 INFO - PROCESS | 27408 | [#0] dojo.html Cycles:165 Average:5438.75 Median:5647.77 stddev:647.37 (11.5%) stddev-sans-first:622.53 19:24:26 INFO - PROCESS | 27408 | Values: 3087.9 3091.7 3117.1 3117.4 3157.9 5351.1 5416.7 5453.9 5564.4 5588.9 5415.2 5416.5 5476.7 5477.9 5496.2 4910.7 4966.3 5001.3 5012.8 5116.6 5022.9 5027.9 5063.5 5081.6 5086.8 4814.9 4835.1 4885.9 4962.8 4971.6 4891.5 4894.3 4912.3 4918.5 4992.9 4910.3 4947.4 4950.5 4975.6 4976.9 5251.4 5393.1 5420.9 5469.1 5472.9 5870.1 5884.1 5891.4 5933.9 5943.7 5962.6 5964.0 5975.0 5988.9 5995.1 5316.7 5423.3 5469.3 5478.1 5510.4 6052.7 6053.9 6055.5 6100.1 6111.2 5607.2 5840.9 5843.1 5859.1 5860.6 5793.4 5829.4 5853.2 5894.2 5896.8 5864.0 5877.6 5880.1 5884.4 5887.7 5694.3 5789.1 5835.4 5882.1 5897.7 4323.9 4390.8 4393.9 4427.2 4431.7 3917.5 3928.5 3938.9 3946.1 3966.2 5393.7 5447.0 5465.6 5594.3 5615.2 5822.0 5851.8 5892.9 5897.1 5951.6 5554.0 5570.8 5572.6 5573.5 5574.2 4839.1 4941.6 4947.8 4956.6 5014.6 6103.8 6110.9 6114.2 6127.1 6145.5 5960.9 5961.1 5961.2 5963.0 5981.0 5869.4 6013.9 6034.0 6060.4 6141.4 5753.8 5798.8 5808.0 5810.6 5812.4 5904.5 5908.6 5910.5 5910.8 5915.1 5447.9 5644.9 5671.2 5689.5 5728.7 5701.2 5702.3 5703.2 5707.7 5712.5 5457.8 5635.5 5645.1 5646.9 5647.8 5697.6 5758.0 5781.7 5799.9 5868.7 5674.0 5679.1 5680.9 5683.9 5689.4 19:24:26 INFO - PROCESS | 27408 | 19:24:26 INFO - PROCESS | 27408 | [#1] ext.html Cycles:180 Average:64260.24 Median:237660.07 stddev:129841.38 (54.6%) stddev-sans-first:130117.62 19:24:26 INFO - PROCESS | 27408 | Values: 589.8 598.5 602.7 611.9 614.0 44183.6 45865.8 45866.8 48662.8 48701.8 46587.5 46975.0 46994.8 47117.8 47146.0 9003.1 9147.8 9151.1 9157.3 9157.7 8918.0 9013.3 9021.1 9055.8 9056.9 9132.8 9162.8 9169.4 9170.3 9173.8 8946.3 9049.6 9060.7 9103.8 9104.0 8919.4 8967.3 8978.0 8978.6 8995.4 43734.0 44117.6 44146.1 44291.8 44504.0 3595.6 3652.7 3666.3 3802.7 4067.4 33974.5 34000.0 34231.8 34283.3 35144.6 4104.3 4413.8 4474.4 4486.8 4557.9 311605.0 397283.0 398235.0 398387.0 399299.0 62122.4 62415.7 62657.1 62669.7 62942.7 57076.4 64190.0 65599.7 65654.3 70065.6 14451.8 14538.3 14573.3 14575.5 14584.3 11025.6 12126.8 12217.6 12218.3 12220.8 19472.0 19577.9 19691.7 20218.3 20460.6 6866.9 7005.3 7072.5 7154.7 7321.7 1013.5 1022.3 1103.1 1109.7 1120.7 33260.4 33955.3 33986.3 34066.0 34340.3 1100.8 1123.2 1130.9 1132.3 1153.3 17098.9 17736.9 17858.9 17860.6 17913.2 615429.0 618353.0 619394.0 625112.0 625153.0 60478.0 60558.4 60665.4 60693.0 60724.7 1212.6 1264.4 1342.9 1343.3 1365.8 30652.0 34747.1 35061.8 35134.3 35254.6 52506.7 52788.2 53161.2 53167.7 53195.5 423755.0 427142.0 428073.0 430956.0 431974.0 23181.9 28164.2 28182.3 28477.7 29489.7 49594.0 49779.8 49845.8 50376.7 51172.2 42219.0 42464.8 43103.6 43346.1 44302.8 42497.8 42819.1 43414.3 44043.3 44101.3 41331.8 42281.0 42281.0 42557.8 42648.4 35968.3 38201.4 38280.8 38723.2 39067.2 28891.6 29190.0 29413.0 29487.7 29658.4 19:24:26 INFO - PROCESS | 27408 | 19:24:26 INFO - PROCESS | 27408 | [#2] jquery.html Cycles:180 Average:29294.87 Median:12489.05 stddev:79200.09 (634.2%) stddev-sans-first:79392.50 19:24:26 INFO - PROCESS | 27408 | Values: 376.4 389.3 391.4 402.1 421.6 14632.4 17938.7 19472.9 20173.0 20209.5 18986.5 19022.3 19170.6 19183.8 19245.3 5559.4 5658.6 5716.0 5757.6 5761.5 5453.8 5571.2 5601.5 5621.2 5625.8 5414.7 5591.9 5605.3 5607.6 5610.5 5580.6 5580.6 5610.2 5627.7 5632.3 4782.4 4951.7 4953.9 5075.8 5076.1 22718.5 23182.2 23266.8 23272.2 23286.0 2259.6 2375.9 2698.9 2956.7 3039.9 10560.9 12216.2 14237.6 14435.1 14850.5 1734.2 1829.5 1831.8 1835.1 1864.3 84483.0 85799.0 86882.6 86894.6 92293.0 10325.7 10768.8 11091.9 11184.1 11472.5 15456.3 15531.4 15559.5 15570.9 15574.4 7744.3 7780.3 7803.1 7849.2 7894.4 7221.5 7389.2 7402.2 7733.5 8019.0 4551.9 4647.0 4648.9 4659.0 4711.6 1845.7 1917.5 1922.5 1945.3 1950.9 981.4 991.4 993.1 1055.8 1077.7 19470.7 19657.5 19998.7 20020.7 20139.9 1018.2 1027.4 1028.9 1032.4 1079.2 10508.6 11039.4 11073.7 11113.6 11150.2 422853.0 424353.0 427254.0 542756.0 549734.0 97507.5 102827.5 102852.5 102982.4 104021.0 1037.8 1058.7 1078.0 1080.3 1082.7 17246.6 17566.5 17777.5 17890.0 17928.5 42064.8 43039.1 43051.6 44549.6 45117.8 60120.0 61290.0 61658.0 63931.4 70469.3 8482.3 8531.0 8551.2 8615.8 8798.6 9492.6 13635.4 13669.0 13714.7 14147.3 11741.9 12104.9 12213.6 12230.7 12313.4 11794.2 11948.5 12013.8 12020.9 12164.5 11786.5 11812.9 11960.5 11981.9 12255.3 7687.7 8034.1 8661.1 8879.9 8980.0 12252.1 12373.9 12496.8 12504.7 12567.7 19:24:26 INFO - PROCESS | 27408 | 19:24:26 INFO - PROCESS | 27408 | [#3] mootools.html Cycles:180 Average:6012.08 Median:4632.04 stddev:3448.47 (74.4%) stddev-sans-first:3440.05 19:24:26 INFO - PROCESS | 27408 | Values: 1311.2 1463.5 1510.2 1648.8 1855.1 8602.2 8967.7 8984.4 9240.3 9246.0 8954.4 9159.5 9163.1 9166.5 9168.4 2040.0 2229.7 2290.4 2347.7 2373.7 2333.5 2339.2 2346.8 2346.9 2353.5 2313.6 2317.9 2372.1 2375.7 2376.0 2026.6 2169.8 2174.3 2224.5 2234.4 2207.7 2223.8 2233.7 2244.9 2252.4 9095.0 9114.4 9168.9 9193.0 9195.9 5151.9 5281.3 5291.6 5299.9 5300.4 3217.1 3330.7 3403.8 3405.1 3427.0 1114.2 1114.3 1121.4 1126.2 1131.0 12549.7 12553.6 12571.4 12704.7 12708.9 4625.7 4638.4 4805.5 4853.6 4859.1 10580.1 10581.6 10667.4 10668.5 10670.3 5167.8 5168.5 5205.5 5206.9 5211.3 4335.5 4705.3 4829.5 4831.5 4917.5 4046.8 4055.7 4083.6 4113.9 4128.1 1165.1 1196.6 1232.5 1260.1 1277.4 856.7 908.0 996.7 1017.3 1043.0 6362.0 6478.4 6520.8 6554.4 6593.8 933.7 948.4 993.2 1019.4 1020.3 4488.8 4524.4 4527.6 4539.7 4544.1 10809.1 10842.3 11231.8 11244.0 11253.2 11238.6 11396.9 11466.1 11491.5 11494.9 8437.5 8621.0 8621.4 8624.1 8628.3 6277.8 6451.2 6542.5 6596.5 6657.1 10140.7 10436.4 10561.3 10571.3 10672.7 10419.3 10506.6 10828.6 10852.7 10883.6 7725.8 8326.7 8582.4 8611.4 8796.1 8769.3 8796.3 8823.6 8857.0 8870.2 7582.2 7713.3 7853.6 8014.2 8024.4 7686.8 7910.2 7929.4 7965.2 8017.0 7181.4 7330.7 7507.6 7509.8 7511.3 5477.7 5525.2 5546.9 5552.8 5597.2 5506.5 5549.9 5584.5 5677.5 5739.7 19:24:26 INFO - PROCESS | 27408 | 19:24:26 INFO - PROCESS | 27408 | [#4] prototype.html Cycles:180 Average:4478.13 Median:5049.63 stddev:1310.05 (25.9%) stddev-sans-first:1275.57 19:24:26 INFO - PROCESS | 27408 | Values: 296.4 306.2 316.6 326.0 326.5 5291.4 5336.5 5353.0 5359.4 5442.6 5001.8 5127.3 5214.3 5247.8 5261.4 4087.8 4133.7 4140.2 4144.8 4160.1 4159.5 4166.6 4179.2 4180.6 4184.9 3726.1 3926.5 4034.8 4042.2 4109.8 4074.7 4085.9 4092.0 4092.2 4097.3 3612.1 3617.5 3619.2 3621.4 3639.1 4956.3 5140.6 5161.7 5167.5 5171.7 4641.7 4654.4 4656.4 4668.7 4684.2 5525.4 5554.2 5570.5 5594.2 5604.2 3833.6 3837.4 3877.4 3954.3 3962.1 5649.2 5718.2 5751.7 5781.3 5787.9 4026.8 4049.6 4055.9 4056.6 4061.6 3883.3 4095.3 4095.5 4105.4 4107.3 4666.0 4687.7 4691.6 4697.1 4707.0 5528.7 5572.9 5575.8 5593.4 5632.4 1349.6 1379.6 1383.7 1389.7 1393.7 856.3 861.3 863.9 868.7 873.0 4811.7 4817.4 4830.3 4833.2 4836.6 5065.6 5066.9 5070.9 5093.6 5106.6 4604.7 4769.5 4786.9 4807.9 4825.4 2688.0 2690.4 2696.8 2698.8 2701.4 5181.2 5194.4 5202.8 5212.8 5226.7 4843.6 5056.7 5119.6 5120.8 5131.2 5184.7 5201.9 5232.5 5233.4 5276.5 4956.8 5000.4 5017.3 5089.6 5128.2 4773.7 4962.4 4987.8 4994.4 5042.6 5108.3 5114.4 5128.8 5170.3 5187.5 3598.0 3599.4 3613.4 3623.6 3711.9 5512.5 5657.8 5661.9 5708.6 5739.2 5529.5 5580.0 5582.4 5589.9 5620.5 5322.4 5518.4 5527.4 5551.3 5578.6 5395.0 5447.6 5453.8 5459.2 5467.8 5558.2 5576.8 5582.8 5608.3 5616.9 5216.6 5443.6 5449.2 5457.3 5469.9 19:24:26 INFO - PROCESS | 27408 | 19:24:26 INFO - PROCESS | 27408 | [#5] yui.html Cycles:180 Average:19053.64 Median:4676.60 stddev:26228.81 (560.9%) stddev-sans-first:26266.20 19:24:26 INFO - PROCESS | 27408 | Values: 706.5 721.1 830.8 862.6 865.6 14349.3 14666.9 14764.5 15583.3 15753.8 14105.6 14220.2 14243.9 14247.3 14253.6 675.3 784.1 809.1 827.8 828.9 836.4 847.7 848.0 848.3 848.5 883.7 889.8 889.9 890.2 890.9 712.1 847.6 860.3 880.8 884.2 627.5 627.6 628.8 629.1 629.3 13842.7 13905.2 13977.3 14006.5 14138.9 13166.9 13593.2 13610.5 13753.5 13858.7 12915.2 13005.2 13139.6 13246.8 13331.9 4539.1 4606.8 4615.6 4710.3 4731.3 77429.6 79205.0 79233.0 79437.0 80016.6 10297.6 10499.8 10526.7 11720.6 11831.3 21490.9 21775.6 21798.7 21804.0 21804.1 8301.6 8364.4 8560.9 8623.7 8666.8 4642.9 4767.8 4801.5 4819.7 4873.4 3482.4 3601.8 3604.4 3609.4 3610.4 668.8 675.0 680.1 681.0 689.9 623.9 683.8 715.3 729.6 737.9 9645.9 9935.5 10018.2 10105.0 10158.3 670.7 692.8 697.4 712.3 775.9 4963.9 4969.4 5041.6 5068.2 5081.8 67205.0 67822.7 68073.7 68127.7 89556.0 104900.0 105144.5 105429.5 105739.5 106133.5 63228.0 69481.7 73026.6 86848.6 89059.1 9723.0 9848.3 9900.6 9933.4 9945.4 37327.8 41643.8 49132.5 50353.7 51034.5 45342.5 45527.1 59040.0 76280.6 76485.6 11702.0 12215.5 12232.4 12240.7 12648.9 13962.4 14246.9 14335.6 14930.4 15002.8 10357.6 11687.4 11840.7 12324.0 13091.7 14149.9 14432.9 14586.5 15260.2 15322.2 14084.6 14229.5 14770.8 14981.1 15090.7 10861.1 11113.3 11301.4 11460.3 11472.0 11138.8 11171.0 11506.2 11570.1 11618.5 19:24:26 INFO - PROCESS | 27408 | -------- Summary: end -------- 19:24:26 INFO - PROCESS | 27408 | 19:24:27 INFO - TEST-INFO | 27408: exit 0 19:24:27 INFO - TEST-OK | dromaeo_css | took 1113814ms 19:24:27 INFO - TEST-START | kraken 19:24:27 INFO - operating with platform_type : linux_ 19:24:27 INFO - Initialising browser for kraken test... 19:24:27 INFO - TEST-INFO | started process 27600 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIlmMDm/profile http://localhost:49961/getInfo.html) 19:24:31 INFO - PROCESS | 27600 | __metrics Screen width/height:1600/1200 19:24:31 INFO - PROCESS | 27600 | colorDepth:24 19:24:31 INFO - PROCESS | 27600 | Browser inner width/height: 1024/697 19:24:31 INFO - PROCESS | 27600 | __metrics 19:24:32 INFO - PROCESS | 27600 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:24:32 INFO - PROCESS | 27600 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:24:33 INFO - TEST-INFO | 27600: exit 0 19:24:33 INFO - Browser initialized. 19:24:33 INFO - Running cycle 1/1 for kraken test... 19:24:33 INFO - TEST-INFO | started process 27682 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIlmMDm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:24:44 INFO - PROCESS | 27682 | RSS: Main: 157921280 19:24:44 INFO - PROCESS | 27682 | 19:24:47 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49961/tests/kraken/driver.html?testName=audio-beat-detection) 19:24:48 INFO - PROCESS | 27682 | RSS: Main: 215543808 19:24:48 INFO - PROCESS | 27682 | 19:24:50 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49961/tests/kraken/driver.html?testName=audio-dft) 19:24:51 INFO - PROCESS | 27682 | RSS: Main: 203599872 19:24:51 INFO - PROCESS | 27682 | 19:24:54 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49961/tests/kraken/driver.html?testName=audio-fft) 19:24:54 INFO - PROCESS | 27682 | RSS: Main: 269524992 19:24:54 INFO - PROCESS | 27682 | 19:24:56 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49961/tests/kraken/driver.html?testName=audio-oscillator) 19:24:57 INFO - PROCESS | 27682 | RSS: Main: 180699136 19:24:57 INFO - PROCESS | 27682 | 19:24:59 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49961/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:25:00 INFO - PROCESS | 27682 | RSS: Main: 184594432 19:25:00 INFO - PROCESS | 27682 | 19:25:03 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49961/tests/kraken/driver.html?testName=imaging-darkroom) 19:25:03 INFO - PROCESS | 27682 | RSS: Main: 219148288 19:25:03 INFO - PROCESS | 27682 | 19:25:07 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49961/tests/kraken/driver.html?testName=imaging-desaturate) 19:25:07 INFO - PROCESS | 27682 | RSS: Main: 221327360 19:25:07 INFO - PROCESS | 27682 | 19:25:10 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49961/tests/kraken/driver.html?testName=json-parse-financial) 19:25:11 INFO - PROCESS | 27682 | RSS: Main: 221388800 19:25:11 INFO - PROCESS | 27682 | 19:25:12 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49961/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:25:13 INFO - PROCESS | 27682 | RSS: Main: 208781312 19:25:13 INFO - PROCESS | 27682 | 19:25:15 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49961/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:25:15 INFO - PROCESS | 27682 | RSS: Main: 215486464 19:25:15 INFO - PROCESS | 27682 | 19:25:17 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49961/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:25:18 INFO - PROCESS | 27682 | RSS: Main: 212037632 19:25:18 INFO - PROCESS | 27682 | 19:25:20 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49961/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:25:21 INFO - PROCESS | 27682 | RSS: Main: 223842304 19:25:21 INFO - PROCESS | 27682 | 19:25:24 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49961/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:25:24 INFO - PROCESS | 27682 | RSS: Main: 225394688 19:25:24 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | Cycle 1(1): loaded http://localhost:49961/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49961/tests/kraken/driver.html?testName=ai-astar) 19:25:26 INFO - PROCESS | 27682 | RSS: Main: 267591680 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | __start_tp_report 19:25:26 INFO - PROCESS | 27682 | _x_x_mozilla_page_load 19:25:26 INFO - PROCESS | 27682 | _x_x_mozilla_page_load_details 19:25:26 INFO - PROCESS | 27682 | |i|pagename|runs| 19:25:26 INFO - PROCESS | 27682 | |0;ai-astar;100;113;110;109;111;110;109;110;110;110 19:25:26 INFO - PROCESS | 27682 | |1;audio-beat-detection;155;117;118;175;123;119;147;117;150;121 19:25:26 INFO - PROCESS | 27682 | |2;audio-dft;161;162;160;161;160;161;161;161;162;162 19:25:26 INFO - PROCESS | 27682 | |3;audio-fft;75;74;75;124;74;76;74;75;102;76 19:25:26 INFO - PROCESS | 27682 | |4;audio-oscillator;82;84;124;82;83;84;112;83;84;132 19:25:26 INFO - PROCESS | 27682 | |5;imaging-gaussian-blur;117;117;116;117;119;116;117;116;116;117 19:25:26 INFO - PROCESS | 27682 | |6;imaging-darkroom;146;147;145;146;145;147;146;146;146;146 19:25:26 INFO - PROCESS | 27682 | |7;imaging-desaturate;95;95;95;95;94;94;94;95;95;94 19:25:26 INFO - PROCESS | 27682 | |8;json-parse-financial;51;50;52;51;52;52;50;52;51;51 19:25:26 INFO - PROCESS | 27682 | |9;json-stringify-tinderbox;62;62;62;63;63;63;63;64;68;62 19:25:26 INFO - PROCESS | 27682 | |10;stanford-crypto-aes;81;75;75;74;74;77;80;76;74;77 19:25:26 INFO - PROCESS | 27682 | |11;stanford-crypto-ccm;125;127;116;117;118;117;119;126;158;119 19:25:26 INFO - PROCESS | 27682 | |12;stanford-crypto-pbkdf2;152;153;156;156;156;153;155;154;153;156 19:25:26 INFO - PROCESS | 27682 | |13;stanford-crypto-sha256-iterative;66;62;63;62;62;61;65;61;61;65 19:25:26 INFO - PROCESS | 27682 | __end_tp_report 19:25:26 INFO - PROCESS | 27682 | __start_cc_report 19:25:26 INFO - PROCESS | 27682 | _x_x_mozilla_cycle_collect,1631 19:25:26 INFO - PROCESS | 27682 | __end_cc_report 19:25:26 INFO - PROCESS | 27682 | __startTimestamp1468463126666__endTimestamp 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | ------- Summary: start ------- 19:25:26 INFO - PROCESS | 27682 | Number of tests: 14 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.00 stddev:3.43 (3.1%) stddev-sans-first:1.20 19:25:26 INFO - PROCESS | 27682 | Values: 100.0 113.0 110.0 109.0 111.0 110.0 109.0 110.0 110.0 110.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:135.00 stddev:20.80 (15.4%) stddev-sans-first:20.66 19:25:26 INFO - PROCESS | 27682 | Values: 155.0 117.0 118.0 175.0 123.0 119.0 147.0 117.0 150.0 121.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 19:25:26 INFO - PROCESS | 27682 | Values: 161.0 162.0 160.0 161.0 160.0 161.0 161.0 161.0 162.0 162.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#3] audio-fft Cycles:10 Average:82.50 Median:75.00 stddev:16.91 (22.5%) stddev-sans-first:17.71 19:25:26 INFO - PROCESS | 27682 | Values: 75.0 74.0 75.0 124.0 74.0 76.0 74.0 75.0 102.0 76.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:83.00 stddev:19.69 (23.7%) stddev-sans-first:20.31 19:25:26 INFO - PROCESS | 27682 | Values: 82.0 84.0 124.0 82.0 83.0 84.0 112.0 83.0 84.0 132.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 19:25:26 INFO - PROCESS | 27682 | Values: 117.0 117.0 116.0 117.0 119.0 116.0 117.0 116.0 116.0 117.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 19:25:26 INFO - PROCESS | 27682 | Values: 146.0 147.0 145.0 146.0 145.0 147.0 146.0 146.0 146.0 146.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 19:25:26 INFO - PROCESS | 27682 | Values: 95.0 95.0 95.0 95.0 94.0 94.0 94.0 95.0 95.0 94.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.50 stddev:0.79 (1.5%) stddev-sans-first:0.83 19:25:26 INFO - PROCESS | 27682 | Values: 51.0 50.0 52.0 51.0 52.0 52.0 50.0 52.0 51.0 51.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:1.81 (2.9%) stddev-sans-first:1.87 19:25:26 INFO - PROCESS | 27682 | Values: 62.0 62.0 62.0 63.0 63.0 63.0 63.0 64.0 68.0 62.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:76.50 stddev:2.50 (3.3%) stddev-sans-first:1.99 19:25:26 INFO - PROCESS | 27682 | Values: 81.0 75.0 75.0 74.0 74.0 77.0 80.0 76.0 74.0 77.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#11] stanford-crypto-ccm Cycles:10 Average:124.20 Median:122.00 stddev:12.55 (10.3%) stddev-sans-first:13.31 19:25:26 INFO - PROCESS | 27682 | Values: 125.0 127.0 116.0 117.0 118.0 117.0 119.0 126.0 158.0 119.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.40 Median:155.50 stddev:1.58 (1.0%) stddev-sans-first:1.41 19:25:26 INFO - PROCESS | 27682 | Values: 152.0 153.0 156.0 156.0 156.0 153.0 155.0 154.0 153.0 156.0 19:25:26 INFO - PROCESS | 27682 | 19:25:26 INFO - PROCESS | 27682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:62.50 stddev:1.87 (3.0%) stddev-sans-first:1.59 19:25:26 INFO - PROCESS | 27682 | Values: 66.0 62.0 63.0 62.0 62.0 61.0 65.0 61.0 61.0 65.0 19:25:26 INFO - PROCESS | 27682 | -------- Summary: end -------- 19:25:26 INFO - PROCESS | 27682 | 19:25:27 INFO - TEST-INFO | 27682: exit 0 19:25:27 INFO - TEST-OK | kraken | took 60511ms 19:25:27 INFO - SUITE-END | took 1174s 19:25:27 INFO - Completed test suite (00:19:34) 19:25:27 INFO - javascript benchmark 19:25:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3087.9073627791167, 3091.70628790265, 3117.06488053584, 3117.360941007094, 3157.873685052598, 5351.117065684167, 5416.674999500023, 5453.890922181559, 5564.415736347686, 5588.888222235558, 5415.241866138744, 5416.539594134503, 5476.67139971602, 5477.917831232535, 5496.203050557667, 4910.729909854966, 4966.329545511364, 5001.299818025479, 5012.8496145115705, 5116.590672746172, 5022.874428139307, 5027.899442011176, 5063.46833582474, 5081.64428490004, 5086.84739457815, 4814.87962800931, 4835.08133454646, 4885.902281954376, 4962.751862406865, 4971.552560269557, 4891.535304146136, 4894.265860120975, 4912.312276281324, 4918.458969513351, 4992.925106123411, 4910.337104490919, 4947.431045429749, 4950.455449900508, 4975.577075948549, 4976.850694479172, 5251.4485978972425, 5393.056215162331, 5420.945790542083, 5469.124940009579, 5472.945270547323, 5870.090136028923, 5884.146798714162, 5891.440313170242, 5933.910991335133, 5943.70281485924, 5962.552808539377, 5963.970180149115, 5975.0, 5988.9401105989255, 5995.070764031582, 5316.6544174628525, 5423.349198096214, 5469.261649677323, 5478.123500239943, 5510.421405752419, 6052.694515306098, 6053.85181794665, 6055.5163537702365, 6100.115483254903, 6111.236095488064, 5607.243022191952, 5840.88318233633, 5843.094320380348, 5859.062549991981, 5860.560457965669, 5793.449622285876, 5829.358770535237, 5853.239078919798, 5894.204282421904, 5896.7641294348605, 5864.0, 5877.617954832922, 5880.059190529495, 5884.352721200665, 5887.735051922674, 5694.288213973254, 5789.073748200268, 5835.416458354215, 5882.088276317178, 5897.705114744245, 4323.891902702458, 4390.824367025347, 4393.934090988638, 4427.158839820424, 4431.667624928144, 3917.490726205632, 3928.46965659638, 3938.940915886264, 3946.131850992834, 3966.186931678999, 5393.6763794172475, 5447.0, 5465.562754979592, 5594.272744543184, 5615.157726341082, 5822.0, 5851.765929362778, 5892.852678683067, 5897.085951677411, 5951.553633477506, 5553.972230138824, 5570.832875013756, 5572.554195664418, 5573.470520300646, 5574.247476590608, 4839.080574690797, 4941.629377796679, 4947.752612369367, 4956.5786908112495, 5014.648974571818, 6103.761967187076, 6110.862540392742, 6114.236541168587, 6127.097902969301, 6145.463634091477, 5960.940390596039, 5961.105834124916, 5961.165436838933, 5962.956482615438, 5981.043033114681, 5869.383714709894, 6013.879722405615, 6034.0, 6060.424259695322, 6141.416565426277, 5753.798617048383, 5798.768049277982, 5807.970960145172, 5810.622309549865, 5812.360640329476, 5904.468597826216, 5908.556858235564, 5910.497607703222, 5910.822675319747, 5915.112733090071, 5447.863803404788, 5644.9435505644415, 5671.234383358194, 5689.459501347365, 5728.713564321851, 5701.201831743476, 5702.258706368146, 5703.173039909273, 5707.6575405475805, 5712.542996560348, 5457.836264912059, 5635.520980716685, 5645.096784514458, 5646.85882852932, 5647.774089036392, 5697.601167918206, 5758.049921762862, 5781.710914454176, 5799.8550036249435, 5868.67722275279, 5674.0354139797155, 5679.091345384883, 5680.857978550569, 5683.857903552444, 5689.431056894277], "value": 5392.921175705653, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.8112603966731, 598.4763332084426, 602.659497383978, 611.9449249567535, 613.957023008389, 44183.55816441835, 45865.77067114668, 45866.770666146665, 48662.75668621652, 48701.75649121749, 46587.53412465874, 46975.0, 46994.76502617491, 47117.76441117789, 47146.0, 9003.099690030993, 9147.771305717344, 9151.130642588943, 9157.31320150988, 9157.679481218158, 8918.0, 9013.278937684969, 9021.09789021096, 9055.818883622323, 9056.909430905705, 9132.817343653123, 9162.816743665122, 9169.449833010007, 9170.312226582999, 9173.770655733626, 8946.284297256203, 9049.638014479413, 9060.682876099338, 9103.81792364149, 9104.0, 8919.375643704943, 8967.327450441244, 8977.955110224408, 8978.640854365818, 8995.415298005673, 43734.0, 44117.558824411666, 44146.117077658586, 44291.77854110709, 44504.0, 3595.622459641726, 3652.7077833773233, 3666.2667466506805, 3802.695784337274, 4067.430559721641, 33974.490382644035, 34000.0, 34231.82884085589, 34283.31433371344, 35144.648553514395, 4104.322786740185, 4413.801378937956, 4474.418325617681, 4486.753228572427, 4557.931631025537, 311605.0, 397283.0, 398235.0, 398387.0, 399299.0, 62122.378776212565, 62415.68792156101, 62657.06014409787, 62669.68665156645, 62942.68528657327, 57076.42923570794, 64190.0, 65599.67200163969, 65654.34345656578, 70065.64967175234, 14451.78322325166, 14538.345774440175, 14573.344199511048, 14575.489857855137, 14584.343704533321, 11025.61410350634, 12126.757464850654, 12217.633470995885, 12218.327991960528, 12220.816687749691, 19472.0, 19577.944315846915, 19691.661877514132, 20218.292359767336, 20460.590788184152, 6866.862662746817, 7005.334493223136, 7072.5402848814665, 7154.713811447484, 7321.74373896911, 1013.5388398278766, 1022.3456987527989, 1103.1230172013268, 1109.6892869996414, 1120.6694025262568, 33260.43965349732, 33955.32089358248, 33986.32027359439, 34066.0, 34340.31319373599, 1100.785346857355, 1123.2361993844265, 1130.886911308879, 1132.3432409202642, 1153.3368313219933, 17098.91450542764, 17736.91131544334, 17858.91070544639, 17860.55348616269, 17913.19390627425, 615429.0, 618353.0, 619394.0, 625112.0, 625153.0, 60478.0, 60558.39441605616, 60665.393346066856, 60693.0, 60724.69637651784, 1212.5816593275306, 1264.399410280128, 1342.8858547023613, 1343.2746316988912, 1365.7541642504445, 30652.0, 34747.13132171716, 35061.82469087638, 35134.30316270335, 35254.64745352514, 52506.73746631242, 52788.20817687661, 53161.202581960526, 53167.734161328946, 53195.46804531905, 423755.0, 427142.0, 428073.0, 430956.0, 431974.0, 23181.88409057944, 28164.15507534777, 28182.29544261328, 28477.715222847506, 29489.705102948697, 49594.0, 49779.75110124571, 49845.75077124591, 50376.74811625919, 51172.232416514526, 42219.0, 42464.78767606142, 43103.568964309954, 43346.133077338905, 44302.778486108655, 42497.78751106225, 42819.14361712811, 43414.34878476762, 44043.33934991042, 44101.33847992218, 41331.84118526692, 42281.0, 42281.0, 42557.787211064984, 42648.36027459529, 35968.28063438664, 38201.42697859479, 38280.80859595684, 38723.22553548857, 39067.21865562616, 28891.5666265002, 29190.0, 29413.0, 29487.705122949355, 29658.40683186281], "value": 20749.48394651741, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.42219193537926, 389.33618181001384, 391.4421948723069, 402.1173524114566, 421.6416046360594, 14632.414703411863, 17938.73091903621, 19472.902635486804, 20173.0, 20209.49476263094, 18986.52533686655, 19022.334218302363, 19170.61658766824, 19183.808161918376, 19245.326413575527, 5559.388467268597, 5658.632188907708, 5716.028275193227, 5757.568182386317, 5761.4814666679995, 5453.754581043862, 5571.164325351189, 5601.467860553262, 5621.213030175779, 5625.831225063254, 5414.729263536808, 5591.888162236753, 5605.27131472908, 5607.607467477278, 5610.495055445009, 5580.581456390767, 5580.637258578179, 5610.158476228598, 5627.7467513961765, 5632.295963004624, 4782.378290822206, 4951.653384263103, 4953.876153096166, 5075.822346217865, 5076.111680455905, 22718.54562908749, 23182.1886233981, 23266.767332326628, 23272.185473508514, 23286.0, 2259.559385919746, 2375.8812059397133, 2698.9190324290107, 2956.7338939495335, 3039.8784048638026, 10560.94719526405, 12216.205946613441, 14237.572872813833, 14435.061720988102, 14850.480233191895, 1734.210934025028, 1829.4877434318412, 1831.8168183181576, 1835.1374853818684, 1864.3008871673123, 84483.0, 85799.0, 86882.56558717166, 86894.56552717258, 92293.0, 10325.690229293134, 10768.784624307627, 11091.889081109248, 11184.105271578253, 11472.4837382318, 15456.304466299043, 15531.378744850306, 15559.45541906028, 15570.922145389428, 15574.377024919104, 7744.3417309528195, 7780.260875216845, 7803.141654418009, 7849.175836537195, 7894.368450523944, 7221.458390620619, 7389.150247721581, 7402.222766609536, 7733.49732267412, 8019.0, 4551.931721024121, 4647.0, 4648.883777905579, 4658.95341046592, 4711.623070154379, 1845.7323688065424, 1917.472695008856, 1922.5001499610203, 1945.2705235536673, 1950.8536859735577, 981.4209616326259, 991.4150651115876, 993.1161266472845, 1055.8310670292717, 1077.6713102503654, 19470.70793938092, 19657.508562286057, 19998.70001949972, 20020.69968950467, 20139.899300503697, 1018.2057994764095, 1027.3989716016065, 1028.861103750999, 1032.4115254304975, 1079.1959989807644, 10508.579656813641, 11039.39283339424, 11073.72315692098, 11113.611023614134, 11150.219484635998, 422853.0, 424353.0, 427254.0, 542756.0, 549734.0, 97507.51246243865, 102827.4858625702, 102852.48573757234, 102982.38382112698, 104021.0, 1037.8443233514881, 1058.730023843915, 1077.9676609701721, 1080.2762149359949, 1082.7022568793645, 17246.568835779206, 17566.473005809847, 17777.46667599974, 17890.0, 17928.462146135636, 42064.78967605143, 43039.139217214855, 43051.56948430601, 44549.55450445454, 45117.774411127735, 60120.0, 61290.0, 61658.0, 63931.36068639254, 70469.29530704832, 8482.279006284534, 8530.957345213234, 8551.230389264996, 8615.784605384915, 8798.604062817061, 9492.57283422234, 13635.386407611482, 13669.0, 13714.683645045603, 14147.292635367985, 11741.941290293495, 12104.878951210376, 12213.633590992284, 12230.694232644255, 12313.44589493457, 11794.174407791716, 11948.462319195483, 12013.819792702941, 12020.939895300467, 12164.452599632861, 11786.46960886745, 11812.940935295268, 11960.521579136737, 11981.940090299493, 12255.325957072446, 7687.730929417444, 8034.076081250497, 8661.090585488684, 8879.91120088791, 8980.012198658012, 12252.08109391817, 12373.876261237272, 12496.812547811973, 12504.687382815502, 12567.748645027232], "value": 9367.290527578509, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.1674086954793, 1463.4512057978259, 1510.192047254719, 1648.7526870969352, 1855.072463768116, 8602.225799678035, 8967.686130985418, 8984.371094023421, 9240.26077913767, 9245.953770231155, 8954.37319387643, 9159.496227707474, 9163.129502697233, 9166.495842728647, 9168.404053736502, 2039.9898000509977, 2229.732432108141, 2290.3701482092392, 2347.671326014355, 2373.7151541815006, 2333.498297865967, 2339.181286549711, 2346.8239882008834, 2346.8709220992837, 2353.49399879026, 2313.6298192289237, 2317.9072837086496, 2372.1341710275797, 2375.738668746445, 2375.95248095038, 2026.6453370660076, 2169.8155656769036, 2174.282486779359, 2224.5439684864564, 2234.430220293831, 2207.7461091974305, 2223.844330896838, 2233.7207849018873, 2244.9102035918545, 2252.3693365857584, 9095.0, 9114.361994660376, 9168.908310916871, 9193.034731353178, 9195.862062069074, 5151.922721159186, 5281.313429254211, 5291.550218231455, 5299.894002119974, 5300.41695413508, 3217.131374528864, 3330.6669333066743, 3403.795772253648, 3405.1487128217946, 3426.9657303426898, 1114.2256131988272, 1114.2645853736576, 1121.3776354123459, 1126.1553834624033, 1130.9660710178707, 12549.686257843627, 12553.623391298184, 12571.371431428484, 12704.6823829405, 12708.936455317664, 4625.722456652612, 4638.350630911675, 4805.495422980606, 4853.635977301717, 4859.1496488114435, 10580.10069144116, 10581.62964296246, 10667.359958402365, 10668.466576671291, 10670.25308228432, 5167.819126330561, 5168.483151684876, 5205.45342739007, 5206.947930520722, 5211.348581427322, 4335.523092459827, 4705.294205869079, 4829.541193586604, 4831.51684831514, 4917.483664215278, 4046.817285012799, 4055.6755459563165, 4083.571225021389, 4113.85601503946, 4128.050548373891, 1165.0679456434818, 1196.5811965811897, 1232.4946771823688, 1260.1179174577833, 1277.393238211842, 856.7172832965107, 908.04655112132, 996.6760802738994, 1017.3234798858824, 1042.9634962776267, 6362.0, 6478.352164783484, 6520.771772988018, 6554.377871020309, 6593.8351541210895, 933.6778811310087, 948.4167237149104, 993.2203220471895, 1019.3883669798211, 1020.2756043209256, 4488.798004089824, 4524.366588677588, 4527.61515271199, 4539.7049191802425, 4544.091181763661, 10809.135269178447, 10842.29525080883, 11231.831522527169, 11244.044256238149, 11253.156013299036, 11238.550457981753, 11396.943015285036, 11466.08271338291, 11491.54033838637, 11494.942525287488, 8437.451565648213, 8621.0, 8621.353398495012, 8624.094470080676, 8628.26659733917, 6277.811665650038, 6451.225852897683, 6542.4964658615, 6596.472282217315, 6657.101291325609, 10140.695779126343, 10436.425996570262, 10561.313514621525, 10571.312864663772, 10672.733181670521, 10419.322744021614, 10506.632267870587, 10828.566857325619, 10852.720928604766, 10883.564657413615, 7725.806854828675, 8326.70856520019, 8582.356323275655, 8611.397202195787, 8796.076411976906, 8769.342299327704, 8796.296296296154, 8823.558822059, 8857.0, 8870.2460290876, 7582.165961744314, 7713.305802477804, 7853.646585903533, 8014.238647328494, 8024.358051355995, 7686.769396918102, 7910.208979102274, 7929.365650747811, 7965.243301886311, 8017.037955445384, 7181.425485961006, 7330.743423980135, 7507.62461876893, 7509.774706758806, 7511.286427789351, 5477.698726570077, 5525.171224316385, 5546.861328466821, 5552.833414997395, 5597.160425936142, 5506.504414602705, 5549.944500555104, 5584.525315348241, 5677.51741102011, 5739.713014349182], "value": 4764.670559148918, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.4042275027195, 306.17180526675344, 316.6437757522787, 325.9674032596741, 326.46133879099483, 5291.386127231193, 5336.466353364662, 5353.0, 5359.410464848864, 5442.591805614585, 5001.8499445016705, 5127.3078134451835, 5214.348206474191, 5247.790088396469, 5261.368635763704, 4087.81604827782, 4133.73130746502, 4140.213359461708, 4144.772037537934, 4160.147169830193, 4159.542450330461, 4166.625003749662, 4179.226843034034, 4180.6237438630515, 4184.8535301264465, 3726.0881065623284, 3926.528816542019, 4034.838606455738, 4042.151148258869, 4109.773962432065, 4074.653654439376, 4085.8978525536804, 4091.9795401022952, 4092.1815636872598, 4097.344424892003, 3612.1150318171954, 3617.5297211362617, 3619.239959608485, 3621.3843646580144, 3639.1448009717697, 4956.330895329121, 5140.640155189139, 5161.7161056141895, 5167.534921857051, 5171.715555644438, 4641.698289611165, 4654.418197725285, 4656.3946686930485, 4668.6731928765, 4684.180268453041, 5525.364583072957, 5554.222408862763, 5570.498655121018, 5594.2447769551, 5604.187392828017, 3833.6358045985585, 3837.424386342043, 3877.418387241908, 3954.2882281189372, 3962.0689138052535, 5649.180868774046, 5718.170865224519, 5751.664034859771, 5781.306243250795, 5787.884242315172, 4026.778527181033, 4049.6152865477734, 4055.858044968412, 4056.6146216109423, 4061.5735347788645, 3883.2815929053218, 4095.344744840811, 4095.5494895561465, 4105.44576482177, 4107.342825147962, 4665.95334046662, 4687.718736875799, 4691.601213896857, 4697.107549565571, 4707.0, 5528.668279903219, 5572.916406253909, 5575.804846830341, 5593.440655934455, 5632.352279487829, 1349.6288520656701, 1379.5792283353474, 1383.702503961653, 1389.7359501694646, 1393.7351903138574, 856.3491746272878, 861.3023451004706, 863.9416714524657, 868.68727258187, 873.0, 4811.735354555533, 4817.421909370828, 4830.275458681155, 4833.235868907824, 4836.564709176191, 5065.645404821627, 5066.949330506648, 5070.884405430799, 5093.592512599057, 5106.591472682251, 4604.700694454849, 4769.546893045155, 4786.946871688223, 4807.927881081787, 4825.445073816486, 2687.986560067187, 2690.3946612012533, 2696.7572918437436, 2698.7706044986003, 2701.405690748032, 5181.165937206515, 5194.428612852583, 5202.791888324425, 5212.765425555859, 5226.719453733859, 4843.636727245471, 5056.671316364498, 5119.564836988823, 5120.769565369567, 5131.152784997473, 5184.688918664892, 5201.947980520146, 5232.502912223409, 5233.398159211663, 5276.467295698087, 4956.7769450375545, 5000.424951130666, 5017.3191980685415, 5089.592832573311, 5128.205128205098, 4773.66584339093, 4962.429320628174, 4987.775550100182, 4994.375703037121, 5042.596592272536, 5108.3103780989095, 5114.363403710777, 5128.8461346159675, 5170.302009228706, 5187.481251874782, 3597.964020359763, 3599.3521166190335, 3613.4218525035867, 3623.6195199503672, 3711.8678802965355, 5512.5314348280845, 5657.8019769307875, 5661.867626474639, 5708.600397972102, 5739.220841539136, 5529.502344788988, 5579.963313390665, 5582.4138465462165, 5589.888202236014, 5620.494155526022, 5322.41453440129, 5518.420565840529, 5527.419620939743, 5551.306086739158, 5578.553715702653, 5394.973025134849, 5447.591430642638, 5453.754581043783, 5459.153831156177, 5467.753951072132, 5558.221848941071, 5576.804811831566, 5582.776688932559, 5608.270924779753, 5616.887662246814, 5216.556592689664, 5443.564514838724, 5449.209864569536, 5457.317835270591, 5469.890602188014], "value": 4055.408430436899, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.487796347648, 721.1202333153551, 830.8213734047179, 862.5816479007684, 865.6148014133709, 14349.282535873217, 14666.926665366658, 14764.48324308648, 15583.298751556178, 15753.842461575396, 14105.647358816035, 14220.217888016155, 14243.928780356084, 14247.287635618204, 14253.643658908533, 675.2572170612311, 784.0669603172236, 809.0857331215733, 827.7806381308958, 828.9088200297963, 836.393614629395, 847.6948298612494, 848.0374774630808, 848.2874385516166, 848.4739461533858, 883.6509578716403, 889.7508697564692, 889.862071378934, 890.2432932007806, 890.8708237305586, 712.0707476742848, 847.5592691800291, 860.2515811244195, 880.8062226310202, 884.2130503851578, 627.5262177056339, 627.6077451592755, 628.8144997225829, 629.1255155334044, 629.3234772619452, 13842.723145537033, 13905.235212063333, 13977.301134943213, 14006.509772158028, 14138.929305353511, 13166.934165329209, 13593.184408935496, 13610.455581776718, 13753.518626848012, 13858.653533661638, 12915.160514566616, 13005.21968681882, 13139.60581182566, 13246.801297980537, 13331.86668133316, 4539.114872599845, 4606.838760643395, 4615.561521655471, 4710.26990816424, 4731.33761273422, 77429.61285193595, 79204.99999999942, 79233.0, 79437.0, 80016.59991700063, 10297.639582614573, 10499.842502362471, 10526.684199474028, 11720.648380548597, 11831.349275789742, 21490.892545537172, 21775.564488710137, 21798.673019904712, 21804.0, 21804.127834886745, 8301.626426810808, 8364.37267204962, 8560.914390856136, 8623.698170564001, 8666.78333041679, 4642.930356044662, 4767.833125840579, 4801.5438533339275, 4819.6867203632355, 4873.390826146731, 3482.4428091505742, 3601.837917293728, 3604.351216780952, 3609.3503169429227, 3610.3862343401684, 668.7993601919404, 674.9966250168816, 680.1226417920826, 681.0159319782965, 689.9448044156455, 623.8689875126274, 683.7504310926475, 715.2561336210387, 729.6315360742791, 737.881938889775, 9645.855312170323, 9935.453550054672, 10018.198544116452, 10105.0, 10158.288919775545, 670.718298314709, 692.7609974558769, 697.3758486154965, 712.2699233285923, 775.8797386405116, 4963.90072198554, 4969.428515720666, 5041.571466425322, 5068.239764035424, 5081.8475445736685, 67205.0, 67822.66088669524, 68073.65963170152, 68127.65936170287, 89556.0, 104900.0, 105144.47427762812, 105429.47285263524, 105739.47130264454, 106133.4693326544, 63228.0, 69481.65259173671, 73026.63486682638, 86848.56575717081, 89059.10940890638, 9722.951385243028, 9848.310618256653, 9900.554475048784, 9933.3543319684, 9945.403275803475, 37327.81336093302, 41643.7917810409, 49132.508674912795, 50353.74823125861, 51034.489655102974, 45342.54657453383, 45527.08945821131, 59040.0, 76280.61859690666, 76485.61757191179, 11702.0, 12215.450304736485, 12232.449539770554, 12240.693982650506, 12648.936755316165, 13962.441502339792, 14246.857531424554, 14335.56993290203, 14930.402783888523, 15002.849971500582, 10357.585696572052, 11687.357195353998, 11840.70398240051, 12324.0, 13091.738165236833, 14149.858501414854, 14432.927835360755, 14586.4894728684, 15260.160691162093, 15322.157281349633, 14084.577462676136, 14229.50196743109, 14770.778438323649, 14981.101133932, 15090.698186036438, 10861.121802722442, 11113.277636953571, 11301.378424186749, 11460.255083419552, 11472.0, 11138.832917506083, 11171.0, 11506.194566380273, 11570.132240082195, 11618.477168527268], "value": 7461.804963293777, "unit": "score"}], "name": "dromaeo_css", "value": 7298.506398309437, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 110.0, 109.0, 111.0, 110.0, 109.0, 110.0, 110.0, 110.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 117.0, 118.0, 175.0, 123.0, 119.0, 147.0, 117.0, 150.0, 121.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 160.0, 161.0, 161.0, 161.0, 162.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 124.0, 74.0, 76.0, 74.0, 75.0, 102.0, 76.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 124.0, 82.0, 83.0, 84.0, 112.0, 83.0, 84.0, 132.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 119.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 146.0, 145.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 52.0, 51.0, 52.0, 52.0, 50.0, 52.0, 51.0, 51.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 63.0, 63.0, 63.0, 63.0, 64.0, 68.0, 62.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 75.0, 75.0, 74.0, 74.0, 77.0, 80.0, 76.0, 74.0, 77.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 116.0, 117.0, 118.0, 117.0, 119.0, 126.0, 158.0, 119.0], "value": 124.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 156.0, 156.0, 156.0, 153.0, 155.0, 154.0, 153.0, 156.0], "value": 154.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 63.0, 62.0, 62.0, 61.0, 65.0, 61.0, 61.0, 65.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1471.5, "alertThreshold": 2.0}]} 19:25:27 INFO - Return code: 0 19:25:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:25:27 INFO - # TBPL SUCCESS # 19:25:27 INFO - Running post-action listener: _resource_record_post_action 19:25:27 INFO - ##### 19:25:27 INFO - ##### Finished run-tests step (success) 19:25:27 INFO - ##### 19:25:27 INFO - Running post-run listener: _resource_record_post_run 19:25:28 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 561926144; Read time: 16; Write time: 411144 19:25:28 INFO - TinderboxPrint: CPU usage
13.0% 19:25:28 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:25:28 INFO - TinderboxPrint: I/O write bytes / time
561,926,144 / 411,144 19:25:28 INFO - TinderboxPrint: CPU idle
8,272.1 (86.9%) 19:25:28 INFO - TinderboxPrint: CPU user
1,197.2 (12.6%) 19:25:28 INFO - TinderboxPrint: Swap in / out
0 / 0 19:25:28 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 269815808; Read time: 0; Write time: 214112 19:25:28 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 292110336; Read time: 0; Write time: 197032 19:25:28 INFO - Running post-run listener: _upload_blobber_files 19:25:28 INFO - Blob upload gear active. 19:25:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:25:28 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 19:25:28 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'] 19:25:28 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 19:25:28 INFO - (blobuploader) - INFO - Open directory for files ... 19:25:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:25:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:25:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:32 INFO - (blobuploader) - INFO - Done attempting. 19:25:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:25:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:25:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:33 INFO - (blobuploader) - INFO - Done attempting. 19:25:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:25:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:25:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:34 INFO - (blobuploader) - INFO - Done attempting. 19:25:34 INFO - (blobuploader) - INFO - Iteration through files over. 19:25:34 INFO - Return code: 0 19:25:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:25:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:25:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4a3becfcec4f2d531e20eee32a4e3942818a7d04de9b7ac181425226bc574f54f1f71a4b3b21cb236c5a642e1d8681efdc2dc750d5ce2b017ada3371ac2c1d67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdbe0c14063a110a2b5851580a1e75eb6a46ea1f85dc51b160861521a0857b90f5fa93be4c29a7135c9bf8a4d6aef3ce4936855e46d25330f7df9d9545f2bc5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/266e6902ee26e9a8d2ac1965814c56462a3365ca7357118dd18f146b20301e63b23c89e3eacf1573c8d13260b1a625d8c9dee1f495073c25b9c501632163350f"} 19:25:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:25:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:25:34 INFO - Contents: 19:25:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4a3becfcec4f2d531e20eee32a4e3942818a7d04de9b7ac181425226bc574f54f1f71a4b3b21cb236c5a642e1d8681efdc2dc750d5ce2b017ada3371ac2c1d67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdbe0c14063a110a2b5851580a1e75eb6a46ea1f85dc51b160861521a0857b90f5fa93be4c29a7135c9bf8a4d6aef3ce4936855e46d25330f7df9d9545f2bc5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/266e6902ee26e9a8d2ac1965814c56462a3365ca7357118dd18f146b20301e63b23c89e3eacf1573c8d13260b1a625d8c9dee1f495073c25b9c501632163350f"} 19:25:34 INFO - Running post-run listener: copy_logs_to_upload_dir 19:25:34 INFO - Copying logs to upload dir... 19:25:34 INFO - mkdir: /builds/slave/test/build/upload/logs 19:25:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.240354 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-07-13 19:25:34.605747) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 19:25:34.609054) ========= 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=03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313 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/4a3becfcec4f2d531e20eee32a4e3942818a7d04de9b7ac181425226bc574f54f1f71a4b3b21cb236c5a642e1d8681efdc2dc750d5ce2b017ada3371ac2c1d67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdbe0c14063a110a2b5851580a1e75eb6a46ea1f85dc51b160861521a0857b90f5fa93be4c29a7135c9bf8a4d6aef3ce4936855e46d25330f7df9d9545f2bc5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/266e6902ee26e9a8d2ac1965814c56462a3365ca7357118dd18f146b20301e63b23c89e3eacf1573c8d13260b1a625d8c9dee1f495073c25b9c501632163350f"} build_url:https://queue.taskcluster.net/v1/task/Q54gf0HhTlaPGphDdcez8A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/Q54gf0HhTlaPGphDdcez8A/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/4a3becfcec4f2d531e20eee32a4e3942818a7d04de9b7ac181425226bc574f54f1f71a4b3b21cb236c5a642e1d8681efdc2dc750d5ce2b017ada3371ac2c1d67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdbe0c14063a110a2b5851580a1e75eb6a46ea1f85dc51b160861521a0857b90f5fa93be4c29a7135c9bf8a4d6aef3ce4936855e46d25330f7df9d9545f2bc5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/266e6902ee26e9a8d2ac1965814c56462a3365ca7357118dd18f146b20301e63b23c89e3eacf1573c8d13260b1a625d8c9dee1f495073c25b9c501632163350f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 19:25:34.634741) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 19:25:34.635086) ========= 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=03627c79f6bb3d1578c4a402000001ba-1468461883.490538-1555929313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004459 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 19:25:34.717576) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 19:25:34.717918) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 19:25:34.718229) ========= ========= Total master_lag: 0.18 =========