builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1469099745.36 results: success (0) buildid: 20160721013053 builduid: 5eea57702cd640139e1f7c0364126b00 revision: 79b1ba1f1f4bcc68801315438fa632e6c916549e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:45.361114) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:45.361639) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:45.362012) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:45.377284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:45.377612) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 04:15:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-07-21 04:15:46 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134617 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:45.526531) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:45.526906) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019298 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:45.576547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 04:15:45.576944) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 79b1ba1f1f4bcc68801315438fa632e6c916549e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 79b1ba1f1f4bcc68801315438fa632e6c916549e --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=e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 04:15:46,170 truncating revision to first 12 chars 2016-07-21 04:15:46,170 Setting DEBUG logging. 2016-07-21 04:15:46,170 attempt 1/10 2016-07-21 04:15:46,170 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/79b1ba1f1f4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 04:15:46,984 unpacking tar archive at: mozilla-inbound-79b1ba1f1f4b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.240144 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 04:15:46.831867) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:46.832265) ========= script_repo_revision: 79b1ba1f1f4bcc68801315438fa632e6c916549e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:46.832729) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:46.833034) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 04:15:46.847119) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-21 04:15:46.847469) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:15:47 INFO - MultiFileLogger online at 20160721 04:15:47 in /builds/slave/test 04:15:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 04:15:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:15:47 INFO - {'append_to_log': False, 04:15:47 INFO - 'base_work_dir': '/builds/slave/test', 04:15:47 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 04:15:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:15:47 INFO - 'branch': 'Mozilla-Inbound', 04:15:47 INFO - 'buildbot_json_path': 'buildprops.json', 04:15:47 INFO - 'config_files': ('talos/linux_config.py',), 04:15:47 INFO - 'default_actions': ('clobber', 04:15:47 INFO - 'read-buildbot-config', 04:15:47 INFO - 'download-and-extract', 04:15:47 INFO - 'populate-webroot', 04:15:47 INFO - 'create-virtualenv', 04:15:47 INFO - 'install', 04:15:47 INFO - 'run-tests'), 04:15:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:15:47 INFO - 'download_minidump_stackwalk': True, 04:15:47 INFO - 'download_symbols': 'ondemand', 04:15:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:15:47 INFO - 'tooltool.py': '/tools/tooltool.py', 04:15:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:15:47 INFO - '/tools/misc-python/virtualenv.py')}, 04:15:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:15:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:15:47 INFO - 'installer_path': 'installer.exe', 04:15:47 INFO - 'log_level': 'info', 04:15:47 INFO - 'log_name': 'talos', 04:15:47 INFO - 'log_to_console': True, 04:15:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:15:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:15:47 INFO - 'opt_config_files': (), 04:15:47 INFO - 'pip_index': False, 04:15:47 INFO - 'sps_profile': False, 04:15:47 INFO - 'sps_profile_interval': 0, 04:15:47 INFO - 'suite': 'dromaeojs', 04:15:47 INFO - 'system_bits': '32', 04:15:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:15:47 INFO - 'title': 'talos-linux64-ix-067', 04:15:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:15:47 INFO - 'use_talos_json': True, 04:15:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:15:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:15:47 INFO - 'work_dir': 'build'} 04:15:47 INFO - [mozharness: 2016-07-21 11:15:47.537525Z] Running clobber step. 04:15:47 INFO - Running pre-action listener: _resource_record_pre_action 04:15:47 INFO - Running main action method: clobber 04:15:47 INFO - rmtree: /builds/slave/test/build 04:15:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:15:48 INFO - Running post-action listener: _resource_record_post_action 04:15:48 INFO - [mozharness: 2016-07-21 11:15:48.536529Z] Finished clobber step (success) 04:15:48 INFO - [mozharness: 2016-07-21 11:15:48.536632Z] Running read-buildbot-config step. 04:15:48 INFO - Running pre-action listener: _resource_record_pre_action 04:15:48 INFO - Running main action method: read_buildbot_config 04:15:48 INFO - Using buildbot properties: 04:15:48 INFO - { 04:15:48 INFO - "project": "", 04:15:48 INFO - "product": "firefox", 04:15:48 INFO - "script_repo_revision": "production", 04:15:48 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 04:15:48 INFO - "repository": "", 04:15:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 04:15:48 INFO - "buildid": "20160721013053", 04:15:48 INFO - "pgo_build": "True", 04:15:48 INFO - "basedir": "/builds/slave/test", 04:15:48 INFO - "buildnumber": 2101, 04:15:48 INFO - "slavename": "talos-linux64-ix-067", 04:15:48 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:15:48 INFO - "platform": "ubuntu64_hw", 04:15:48 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 04:15:48 INFO - "branch": "mozilla-inbound", 04:15:48 INFO - "repo_path": "integration/mozilla-inbound", 04:15:48 INFO - "revision": "79b1ba1f1f4bcc68801315438fa632e6c916549e", 04:15:48 INFO - "stage_platform": "linux64-pgo", 04:15:48 INFO - "builduid": "5eea57702cd640139e1f7c0364126b00", 04:15:48 INFO - "slavebuilddir": "test" 04:15:48 INFO - } 04:15:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:15:48 INFO - Running post-action listener: _resource_record_post_action 04:15:48 INFO - [mozharness: 2016-07-21 11:15:48.539091Z] Finished read-buildbot-config step (success) 04:15:48 INFO - [mozharness: 2016-07-21 11:15:48.539181Z] Running download-and-extract step. 04:15:48 INFO - Running pre-action listener: _resource_record_pre_action 04:15:48 INFO - Running main action method: download_and_extract 04:15:48 INFO - mkdir: /builds/slave/test/build/tests 04:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:15:48 INFO - https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:15:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:15:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:15:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/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 04:15:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/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 04:15:49 INFO - Downloaded 1361 bytes. 04:15:49 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:15:49 INFO - Using the following test package requirements: 04:15:49 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:15:49 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:15:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:15:49 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:15:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:15:49 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:15:49 INFO - u'jsshell-linux-x86_64.zip'], 04:15:49 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:15:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:15:49 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:15:49 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:15:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:15:49 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:15:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:15:49 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:15:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:15:49 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:15:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:15:49 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:15:49 INFO - https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:15:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:15:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:15:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/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 04:15:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/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 04:15:50 INFO - Downloaded 24347469 bytes. 04:15:50 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'] 04:15:50 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 04:15:51 INFO - Return code: 0 04:15:51 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 04:15:51 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')]} 04:15:51 INFO - https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:15:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:15:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:15:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/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 04:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/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 04:15:52 INFO - Downloaded 24347469 bytes. 04:15:52 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'] 04:15:52 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 04:15:53 INFO - Return code: 0 04:15: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')]} 04:15:53 INFO - https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:15:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:15:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:15:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/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 04:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/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 04:15:54 INFO - Downloaded 14403317 bytes. 04:15:54 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'] 04:15:54 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 04:15:54 INFO - Return code: 0 04:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:54 INFO - https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:15:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:15:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:15:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/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 04:15:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UTA_XSR8QZWITG-yR4Soag/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 04:15:56 INFO - Downloaded 60923273 bytes. 04:15:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:15:56 INFO - mkdir: /builds/slave/test/properties 04:15:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:15:56 INFO - Writing to file /builds/slave/test/properties/build_url 04:15:56 INFO - Contents: 04:15:56 INFO - build_url:https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:15:56 INFO - Running post-action listener: _resource_record_post_action 04:15:56 INFO - Running post-action listener: set_extra_try_arguments 04:15:56 INFO - [mozharness: 2016-07-21 11:15:56.963090Z] Finished download-and-extract step (success) 04:15:56 INFO - [mozharness: 2016-07-21 11:15:56.963327Z] Running populate-webroot step. 04:15:56 INFO - Running pre-action listener: _resource_record_pre_action 04:15:56 INFO - Running main action method: populate_webroot 04:15:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:15:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:15:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:15:56 INFO - '--tppagecycles', 04:15:56 INFO - '7'], 04:15:56 INFO - 'tests': ['tsvgm']}}, 04:15:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:15:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:15:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:15:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:15:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:56 INFO - 'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:15:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:15:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:56 INFO - 'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['damp', 'tps']}, 04:15:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:56 INFO - 'tests': ['damp', 'tps']}, 04:15:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['dromaeo_dom']}, 04:15:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:15:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['basic_compositor_video']}, 04:15:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:15:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['a11yr', 04:15:56 INFO - 'ts_paint', 04:15:56 INFO - 'tpaint', 04:15:56 INFO - 'sessionrestore', 04:15:56 INFO - 'sessionrestore_no_auto_restore', 04:15:56 INFO - 'tabpaint']}, 04:15:56 INFO - 'other-e10s': {'tests': ['a11yr', 04:15:56 INFO - 'ts_paint', 04:15:56 INFO - 'tpaint', 04:15:56 INFO - 'sessionrestore', 04:15:56 INFO - 'sessionrestore_no_auto_restore', 04:15:56 INFO - 'tabpaint']}, 04:15:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:15:56 INFO - 'ts_paint', 04:15:56 INFO - 'tpaint', 04:15:56 INFO - 'sessionrestore', 04:15:56 INFO - 'sessionrestore_no_auto_restore', 04:15:56 INFO - 'tabpaint']}, 04:15:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:15:56 INFO - 'ts_paint', 04:15:56 INFO - 'tpaint', 04:15:56 INFO - 'sessionrestore', 04:15:56 INFO - 'sessionrestore_no_auto_restore', 04:15:56 INFO - 'tabpaint']}, 04:15:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['a11yr', 04:15:56 INFO - 'ts_paint', 04:15:56 INFO - 'tpaint', 04:15:56 INFO - 'sessionrestore', 04:15:56 INFO - 'sessionrestore_no_auto_restore', 04:15:56 INFO - 'tabpaint']}, 04:15:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['a11yr', 04:15:56 INFO - 'ts_paint', 04:15:56 INFO - 'tpaint', 04:15:56 INFO - 'sessionrestore', 04:15:56 INFO - 'sessionrestore_no_auto_restore', 04:15:56 INFO - 'tabpaint']}, 04:15:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['tsvgx', 04:15:56 INFO - 'tsvgr_opacity', 04:15:56 INFO - 'tart', 04:15:56 INFO - 'tscrollx', 04:15:56 INFO - 'cart']}, 04:15:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:15:56 INFO - 'tsvgr_opacity', 04:15:56 INFO - 'tart', 04:15:56 INFO - 'tscrollx', 04:15:56 INFO - 'cart']}, 04:15:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:56 INFO - 'talos_options': ['--disable-e10s'], 04:15:56 INFO - 'tests': ['tp5o']}, 04:15:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:56 INFO - 'tests': ['tp5o']}, 04:15:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:56 INFO - 'talos_options': ['--disable-e10s', 04:15:56 INFO - '--xperf_path', 04:15:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:15:56 INFO - 'tests': ['tp5n']}, 04:15:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:56 INFO - 'talos_options': ['--xperf_path', 04:15:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:15:56 INFO - 'tests': ['tp5n']}}, 04:15:56 INFO - 'talos.zip': {'path': '', 04:15:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:15:56 INFO - Running post-action listener: _resource_record_post_action 04:15:56 INFO - [mozharness: 2016-07-21 11:15:56.980636Z] Finished populate-webroot step (success) 04:15:56 INFO - [mozharness: 2016-07-21 11:15:56.980726Z] Running create-virtualenv step. 04:15:56 INFO - Running pre-action listener: _resource_record_pre_action 04:15:56 INFO - Running main action method: create_virtualenv 04:15:56 INFO - Creating virtualenv /builds/slave/test/build/venv 04:15:56 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 04:15:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:15:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:15:57 INFO - Using real prefix '/usr' 04:15:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:15:57 INFO - Installing distribute.............................................................................................................................................................................................done. 04:15:59 INFO - Installing pip.................done. 04:15:59 INFO - Return code: 0 04:15:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:59 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 0xd15030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd43b0>, '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 0xfd52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd5740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', '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 04:15:59 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 04:15:59 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 04:15:59 INFO - Using env: {'DISPLAY': ':0', 04:15:59 INFO - 'HOME': '/home/cltbld', 04:15:59 INFO - 'LANG': 'en_US.UTF-8', 04:15:59 INFO - 'LANGUAGE': 'en_US:en', 04:15:59 INFO - 'LOGNAME': 'cltbld', 04:15:59 INFO - 'MAIL': '/var/mail/cltbld', 04:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:59 INFO - 'MOZ_NO_REMOTE': '1', 04:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:59 INFO - 'NO_EM_RESTART': '1', 04:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:59 INFO - 'PWD': '/builds/slave/test', 04:15:59 INFO - 'SHELL': '/bin/bash', 04:15:59 INFO - 'SHLVL': '1', 04:15:59 INFO - 'TERM': 'linux', 04:15:59 INFO - 'TMOUT': '86400', 04:15:59 INFO - 'USER': 'cltbld', 04:15:59 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', 04:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:59 INFO - '_': '/tools/buildbot/bin/python'} 04:15:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:59 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 04:15:59 INFO - Cleaning up... 04:15:59 INFO - Return code: 0 04:15:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:59 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 0xd15030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd43b0>, '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 0xfd52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd5740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', '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 04:15:59 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 04:15:59 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 04:15:59 INFO - Using env: {'DISPLAY': ':0', 04:15:59 INFO - 'HOME': '/home/cltbld', 04:15:59 INFO - 'LANG': 'en_US.UTF-8', 04:15:59 INFO - 'LANGUAGE': 'en_US:en', 04:15:59 INFO - 'LOGNAME': 'cltbld', 04:15:59 INFO - 'MAIL': '/var/mail/cltbld', 04:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:59 INFO - 'MOZ_NO_REMOTE': '1', 04:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:59 INFO - 'NO_EM_RESTART': '1', 04:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:59 INFO - 'PWD': '/builds/slave/test', 04:15:59 INFO - 'SHELL': '/bin/bash', 04:15:59 INFO - 'SHLVL': '1', 04:15:59 INFO - 'TERM': 'linux', 04:15:59 INFO - 'TMOUT': '86400', 04:15:59 INFO - 'USER': 'cltbld', 04:15:59 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', 04:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:59 INFO - '_': '/tools/buildbot/bin/python'} 04:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:00 INFO - Downloading/unpacking psutil>=3.1.1 04:16:00 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 04:16:00 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 04:16:00 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 04:16:00 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 04:16:00 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 04:16:00 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 04:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:16:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:16:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:16:02 INFO - Installing collected packages: psutil 04:16:02 INFO - Running setup.py install for psutil 04:16:02 INFO - building 'psutil._psutil_linux' extension 04:16:02 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 04:16:02 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 04:16:02 INFO - building 'psutil._psutil_posix' extension 04:16:02 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 04:16:02 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 04:16:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:16:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:16:02 INFO - Successfully installed psutil 04:16:02 INFO - Cleaning up... 04:16:02 INFO - Return code: 0 04:16:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:16:02 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')]} 04:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:02 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')]} 04:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:02 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 0xd15030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd43b0>, '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 0xfd52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd5740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', '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 04:16:02 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 04:16:02 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 04:16:02 INFO - Using env: {'DISPLAY': ':0', 04:16:02 INFO - 'HOME': '/home/cltbld', 04:16:02 INFO - 'LANG': 'en_US.UTF-8', 04:16:02 INFO - 'LANGUAGE': 'en_US:en', 04:16:02 INFO - 'LOGNAME': 'cltbld', 04:16:02 INFO - 'MAIL': '/var/mail/cltbld', 04:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:02 INFO - 'MOZ_NO_REMOTE': '1', 04:16:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:02 INFO - 'NO_EM_RESTART': '1', 04:16:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:02 INFO - 'PWD': '/builds/slave/test', 04:16:02 INFO - 'SHELL': '/bin/bash', 04:16:02 INFO - 'SHLVL': '1', 04:16:02 INFO - 'TERM': 'linux', 04:16:02 INFO - 'TMOUT': '86400', 04:16:02 INFO - 'USER': 'cltbld', 04:16:02 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', 04:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:02 INFO - '_': '/tools/buildbot/bin/python'} 04:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:16:02 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 04:16:02 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 04:16:02 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 04:16:02 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 04:16:02 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 04:16:02 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 04:16:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:16:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:16:04 INFO - Installing collected packages: mozsystemmonitor 04:16:04 INFO - Running setup.py install for mozsystemmonitor 04:16:04 INFO - Successfully installed mozsystemmonitor 04:16:04 INFO - Cleaning up... 04:16:05 INFO - Return code: 0 04:16:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd15030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd43b0>, '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 0xfd52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd5740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', '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 04:16:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:16:05 INFO - Using env: {'DISPLAY': ':0', 04:16:05 INFO - 'HOME': '/home/cltbld', 04:16:05 INFO - 'LANG': 'en_US.UTF-8', 04:16:05 INFO - 'LANGUAGE': 'en_US:en', 04:16:05 INFO - 'LOGNAME': 'cltbld', 04:16:05 INFO - 'MAIL': '/var/mail/cltbld', 04:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:05 INFO - 'MOZ_NO_REMOTE': '1', 04:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:05 INFO - 'NO_EM_RESTART': '1', 04:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:05 INFO - 'PWD': '/builds/slave/test', 04:16:05 INFO - 'SHELL': '/bin/bash', 04:16:05 INFO - 'SHLVL': '1', 04:16:05 INFO - 'TERM': 'linux', 04:16:05 INFO - 'TMOUT': '86400', 04:16:05 INFO - 'USER': 'cltbld', 04:16:05 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', 04:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:05 INFO - '_': '/tools/buildbot/bin/python'} 04:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:05 INFO - Downloading/unpacking jsonschema==2.5.1 04:16:05 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 04:16:05 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 04:16:05 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 04:16:05 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 04:16:05 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 04:16:05 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 04:16:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:16:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:16:07 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 04:16:07 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 04:16:07 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 04:16:07 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 04:16:07 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 04:16:07 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 04:16:07 INFO - Downloading functools32-3.2.3-2.tar.gz 04:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:16:07 INFO - warning: no files found matching '*.txt' 04:16:07 INFO - no previously-included directories found matching 'build' 04:16:07 INFO - no previously-included directories found matching 'dist' 04:16:07 INFO - no previously-included directories found matching '.git*' 04:16:07 INFO - Installing collected packages: jsonschema, functools32 04:16:07 INFO - Running setup.py install for functools32 04:16:07 INFO - warning: no files found matching '*.txt' 04:16:07 INFO - no previously-included directories found matching 'build' 04:16:07 INFO - no previously-included directories found matching 'dist' 04:16:07 INFO - no previously-included directories found matching '.git*' 04:16:07 INFO - Successfully installed jsonschema functools32 04:16:07 INFO - Cleaning up... 04:16:07 INFO - Return code: 0 04:16:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:07 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 0xd15030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd43b0>, '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 0xfd52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd5740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', '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 04:16:07 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 04:16:07 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 04:16:07 INFO - Using env: {'DISPLAY': ':0', 04:16:07 INFO - 'HOME': '/home/cltbld', 04:16:07 INFO - 'LANG': 'en_US.UTF-8', 04:16:07 INFO - 'LANGUAGE': 'en_US:en', 04:16:07 INFO - 'LOGNAME': 'cltbld', 04:16:07 INFO - 'MAIL': '/var/mail/cltbld', 04:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:07 INFO - 'MOZ_NO_REMOTE': '1', 04:16:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:07 INFO - 'NO_EM_RESTART': '1', 04:16:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:07 INFO - 'PWD': '/builds/slave/test', 04:16:07 INFO - 'SHELL': '/bin/bash', 04:16:07 INFO - 'SHLVL': '1', 04:16:07 INFO - 'TERM': 'linux', 04:16:07 INFO - 'TMOUT': '86400', 04:16:07 INFO - 'USER': 'cltbld', 04:16:07 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', 04:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:07 INFO - '_': '/tools/buildbot/bin/python'} 04:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:07 INFO - Downloading/unpacking blobuploader==1.2.4 04:16:07 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 04:16:07 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 04:16:07 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 04:16:07 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 04:16:07 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 04:16:07 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 04:16:09 INFO - Downloading blobuploader-1.2.4.tar.gz 04:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:16:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:16:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:16:10 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 04:16:10 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 04:16:10 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 04:16:10 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 04:16:10 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 04:16:10 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 04:16:10 INFO - Downloading docopt-0.6.1.tar.gz 04:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:16:10 INFO - Installing collected packages: blobuploader, requests, docopt 04:16:10 INFO - Running setup.py install for blobuploader 04:16:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:16:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:16:10 INFO - Running setup.py install for requests 04:16:11 INFO - Running setup.py install for docopt 04:16:11 INFO - Successfully installed blobuploader requests docopt 04:16:11 INFO - Cleaning up... 04:16:11 INFO - Return code: 0 04:16:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:11 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 0xd15030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd43b0>, '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 0xfd52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd5740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', '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 04:16:11 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 04:16:11 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 04:16:11 INFO - Using env: {'DISPLAY': ':0', 04:16:11 INFO - 'HOME': '/home/cltbld', 04:16:11 INFO - 'LANG': 'en_US.UTF-8', 04:16:11 INFO - 'LANGUAGE': 'en_US:en', 04:16:11 INFO - 'LOGNAME': 'cltbld', 04:16:11 INFO - 'MAIL': '/var/mail/cltbld', 04:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:11 INFO - 'MOZ_NO_REMOTE': '1', 04:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:11 INFO - 'NO_EM_RESTART': '1', 04:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:11 INFO - 'PWD': '/builds/slave/test', 04:16:11 INFO - 'SHELL': '/bin/bash', 04:16:11 INFO - 'SHLVL': '1', 04:16:11 INFO - 'TERM': 'linux', 04:16:11 INFO - 'TMOUT': '86400', 04:16:11 INFO - 'USER': 'cltbld', 04:16:11 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', 04:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:11 INFO - '_': '/tools/buildbot/bin/python'} 04:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:16:11 INFO - Running setup.py (path:/tmp/pip-TBeuvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:16:11 INFO - Running setup.py (path:/tmp/pip-TaJjXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:16:11 INFO - Running setup.py (path:/tmp/pip-gdKPBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:16:11 INFO - Running setup.py (path:/tmp/pip-dGaE1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:16:11 INFO - Running setup.py (path:/tmp/pip-mpllMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:16:11 INFO - Running setup.py (path:/tmp/pip-hWF_oK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:16:11 INFO - Running setup.py (path:/tmp/pip-AVf_tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:16:11 INFO - Running setup.py (path:/tmp/pip-Oz6703-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:16:11 INFO - Running setup.py (path:/tmp/pip-Whf6te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:16:12 INFO - Running setup.py (path:/tmp/pip-R7mwT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:16:12 INFO - Running setup.py (path:/tmp/pip-k_4mEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:16:12 INFO - Running setup.py (path:/tmp/pip-n4AUPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:16:12 INFO - Running setup.py (path:/tmp/pip-AtWxDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:16:12 INFO - Running setup.py (path:/tmp/pip-vMvrMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:12 INFO - Running setup.py (path:/tmp/pip-1N21MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:16:12 INFO - Running setup.py (path:/tmp/pip-HlirDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:16:12 INFO - Running setup.py (path:/tmp/pip-MqoU1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:16:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:16:12 INFO - Running setup.py install for manifestparser 04:16:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:16:12 INFO - Running setup.py install for mozcrash 04:16:12 INFO - Running setup.py install for mozdebug 04:16:12 INFO - Running setup.py install for mozdevice 04:16:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Running setup.py install for mozfile 04:16:13 INFO - Running setup.py install for mozhttpd 04:16:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Running setup.py install for mozinfo 04:16:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Running setup.py install for mozInstall 04:16:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Running setup.py install for mozleak 04:16:13 INFO - Running setup.py install for mozlog 04:16:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Running setup.py install for moznetwork 04:16:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Running setup.py install for mozprocess 04:16:13 INFO - Running setup.py install for mozprofile 04:16:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:16:13 INFO - Running setup.py install for mozrunner 04:16:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:16:14 INFO - Running setup.py install for mozscreenshot 04:16:14 INFO - Running setup.py install for moztest 04:16:14 INFO - Running setup.py install for mozversion 04:16:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:16:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:16:14 INFO - Cleaning up... 04:16:14 INFO - Return code: 0 04:16:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:14 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 0xd15030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd43b0>, '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 0xfd52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd5740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', '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 04:16:14 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 04:16:14 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 04:16:14 INFO - Using env: {'DISPLAY': ':0', 04:16:14 INFO - 'HOME': '/home/cltbld', 04:16:14 INFO - 'LANG': 'en_US.UTF-8', 04:16:14 INFO - 'LANGUAGE': 'en_US:en', 04:16:14 INFO - 'LOGNAME': 'cltbld', 04:16:14 INFO - 'MAIL': '/var/mail/cltbld', 04:16:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:14 INFO - 'MOZ_NO_REMOTE': '1', 04:16:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:14 INFO - 'NO_EM_RESTART': '1', 04:16:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:14 INFO - 'PWD': '/builds/slave/test', 04:16:14 INFO - 'SHELL': '/bin/bash', 04:16:14 INFO - 'SHLVL': '1', 04:16:14 INFO - 'TERM': 'linux', 04:16:14 INFO - 'TMOUT': '86400', 04:16:14 INFO - 'USER': 'cltbld', 04:16:14 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', 04:16:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:14 INFO - '_': '/tools/buildbot/bin/python'} 04:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:16:14 INFO - Running setup.py (path:/tmp/pip-ypzlO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:16:14 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)) 04:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:16:14 INFO - Running setup.py (path:/tmp/pip-4XKQac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:16:14 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)) 04:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:16:14 INFO - Running setup.py (path:/tmp/pip-T7HW1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:16:14 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)) 04:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:16:14 INFO - Running setup.py (path:/tmp/pip-ZK5RDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:16:14 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)) 04:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:16:14 INFO - Running setup.py (path:/tmp/pip-BPBiKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:16:15 INFO - Running setup.py (path:/tmp/pip-vLJ34x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:16:15 INFO - Running setup.py (path:/tmp/pip-yRevUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:16:15 INFO - Running setup.py (path:/tmp/pip-rC2VD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:16:15 INFO - Running setup.py (path:/tmp/pip-C3ptPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:16:15 INFO - Running setup.py (path:/tmp/pip-Mal30z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:16:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:16:15 INFO - Running setup.py (path:/tmp/pip-7EQvXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:16:15 INFO - Running setup.py (path:/tmp/pip-UXwmmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:16:15 INFO - Running setup.py (path:/tmp/pip-e1RXm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:16:15 INFO - Running setup.py (path:/tmp/pip-r3kCLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:15 INFO - Running setup.py (path:/tmp/pip-PACDrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:16:15 INFO - Running setup.py (path:/tmp/pip-aebhY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:16:15 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)) 04:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:16:15 INFO - Running setup.py (path:/tmp/pip-Ar_8e3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:16:15 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)) 04:16:15 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)) 04:16:15 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)) 04:16:15 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)) 04:16:15 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)) 04:16:15 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)) 04:16:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:17 INFO - Downloading blessings-1.6.tar.gz 04:16:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:16:17 INFO - Installing collected packages: blessings 04:16:17 INFO - Running setup.py install for blessings 04:16:18 INFO - Successfully installed blessings 04:16:18 INFO - Cleaning up... 04:16:18 INFO - Return code: 0 04:16:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:16:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:18 INFO - Reading from file tmpfile_stdout 04:16:18 INFO - Current package versions: 04:16:18 INFO - argparse == 1.2.1 04:16:18 INFO - blessings == 1.6 04:16:18 INFO - blobuploader == 1.2.4 04:16:18 INFO - docopt == 0.6.1 04:16:18 INFO - functools32 == 3.2.3-2 04:16:18 INFO - jsonschema == 2.5.1 04:16:18 INFO - manifestparser == 1.1 04:16:18 INFO - mozInstall == 1.12 04:16:18 INFO - mozcrash == 0.17 04:16:18 INFO - mozdebug == 0.1 04:16:18 INFO - mozdevice == 0.48 04:16:18 INFO - mozfile == 1.2 04:16:18 INFO - mozhttpd == 0.7 04:16:18 INFO - mozinfo == 0.9 04:16:18 INFO - mozleak == 0.1 04:16:18 INFO - mozlog == 3.3 04:16:18 INFO - moznetwork == 0.27 04:16:18 INFO - mozprocess == 0.23 04:16:18 INFO - mozprofile == 0.28 04:16:18 INFO - mozrunner == 6.12 04:16:18 INFO - mozscreenshot == 0.1 04:16:18 INFO - mozsystemmonitor == 0.3 04:16:18 INFO - moztest == 0.7 04:16:18 INFO - mozversion == 1.4 04:16:18 INFO - psutil == 3.1.1 04:16:18 INFO - requests == 1.2.3 04:16:18 INFO - wsgiref == 0.1.2 04:16:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:18 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 0xd15030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd43b0>, '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 0xfd52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd5740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', '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 04:16:18 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 04:16:18 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 04:16:18 INFO - Using env: {'DISPLAY': ':0', 04:16:18 INFO - 'HOME': '/home/cltbld', 04:16:18 INFO - 'LANG': 'en_US.UTF-8', 04:16:18 INFO - 'LANGUAGE': 'en_US:en', 04:16:18 INFO - 'LOGNAME': 'cltbld', 04:16:18 INFO - 'MAIL': '/var/mail/cltbld', 04:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:18 INFO - 'MOZ_NO_REMOTE': '1', 04:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:18 INFO - 'NO_EM_RESTART': '1', 04:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:18 INFO - 'PWD': '/builds/slave/test', 04:16:18 INFO - 'SHELL': '/bin/bash', 04:16:18 INFO - 'SHLVL': '1', 04:16:18 INFO - 'TERM': 'linux', 04:16:18 INFO - 'TMOUT': '86400', 04:16:18 INFO - 'USER': 'cltbld', 04:16:18 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', 04:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:18 INFO - '_': '/tools/buildbot/bin/python'} 04:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:18 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)) 04:16:18 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)) 04:16:18 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)) 04:16:18 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)) 04:16:18 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)) 04:16:18 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)) 04:16:18 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)) 04:16:18 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)) 04:16:18 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)) 04:16:18 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)) 04:16:18 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)) 04:16:18 INFO - Cleaning up... 04:16:18 INFO - Return code: 0 04:16:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd15030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd43b0>, '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 0xfd52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd5740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', '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 04:16:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:16:18 INFO - Using env: {'DISPLAY': ':0', 04:16:18 INFO - 'HOME': '/home/cltbld', 04:16:18 INFO - 'LANG': 'en_US.UTF-8', 04:16:18 INFO - 'LANGUAGE': 'en_US:en', 04:16:18 INFO - 'LOGNAME': 'cltbld', 04:16:18 INFO - 'MAIL': '/var/mail/cltbld', 04:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:18 INFO - 'MOZ_NO_REMOTE': '1', 04:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:18 INFO - 'NO_EM_RESTART': '1', 04:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:18 INFO - 'PWD': '/builds/slave/test', 04:16:18 INFO - 'SHELL': '/bin/bash', 04:16:18 INFO - 'SHLVL': '1', 04:16:18 INFO - 'TERM': 'linux', 04:16:18 INFO - 'TMOUT': '86400', 04:16:18 INFO - 'USER': 'cltbld', 04:16:18 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', 04:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:18 INFO - '_': '/tools/buildbot/bin/python'} 04:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:16:18 INFO - Cleaning up... 04:16:18 INFO - Return code: 0 04:16:18 INFO - Running post-action listener: _resource_record_post_action 04:16:18 INFO - Running post-action listener: _start_resource_monitoring 04:16:18 INFO - Starting resource monitoring. 04:16:18 INFO - [mozharness: 2016-07-21 11:16:18.751445Z] Finished create-virtualenv step (success) 04:16:18 INFO - [mozharness: 2016-07-21 11:16:18.751938Z] Running install step. 04:16:18 INFO - Running pre-action listener: _resource_record_pre_action 04:16:18 INFO - Running main action method: install 04:16:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:18 INFO - Reading from file tmpfile_stdout 04:16:18 INFO - Detecting whether we're running mozinstall >=1.0... 04:16:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:16:19 INFO - Reading from file tmpfile_stdout 04:16:19 INFO - Output received: 04:16:19 INFO - Usage: mozinstall [options] installer 04:16:19 INFO - Options: 04:16:19 INFO - -h, --help show this help message and exit 04:16:19 INFO - -d DEST, --destination=DEST 04:16:19 INFO - Directory to install application into. [default: 04:16:19 INFO - "/builds/slave/test"] 04:16:19 INFO - --app=APP Application being installed. [default: firefox] 04:16:19 INFO - mkdir: /builds/slave/test/build/application 04:16:19 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'] 04:16:19 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 04:16:36 INFO - Reading from file tmpfile_stdout 04:16:36 INFO - Output received: 04:16:36 INFO - /builds/slave/test/build/application/firefox/firefox 04:16:36 INFO - Running post-action listener: _resource_record_post_action 04:16:36 INFO - [mozharness: 2016-07-21 11:16:36.324981Z] Finished install step (success) 04:16:36 INFO - [mozharness: 2016-07-21 11:16:36.325268Z] Running run-tests step. 04:16:36 INFO - Running pre-action listener: _resource_record_pre_action 04:16:36 INFO - Running main action method: run_tests 04:16:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:16:36 INFO - Python 2.7.3 04:16:36 INFO - Return code: 0 04:16:36 INFO - grabbing minidump binary from tooltool 04:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:16:36 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 0xfd43b0>, '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 0xfd52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfd5740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:16:36 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 04:16:36 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 04:16:36 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 04:16:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:16:36 INFO - Return code: 0 04:16:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:16:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:16:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:16:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:16:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:16:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 04:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 04:16:36 INFO - Using env: {'DISPLAY': ':0', 04:16:36 INFO - 'HOME': '/home/cltbld', 04:16:36 INFO - 'LANG': 'en_US.UTF-8', 04:16:36 INFO - 'LANGUAGE': 'en_US:en', 04:16:36 INFO - 'LOGNAME': 'cltbld', 04:16:36 INFO - 'MAIL': '/var/mail/cltbld', 04:16:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:16:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:36 INFO - 'MOZ_NO_REMOTE': '1', 04:16:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:36 INFO - 'NO_EM_RESTART': '1', 04:16:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:36 INFO - 'PWD': '/builds/slave/test', 04:16:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:16:36 INFO - 'SHELL': '/bin/bash', 04:16:36 INFO - 'SHLVL': '1', 04:16:36 INFO - 'TERM': 'linux', 04:16:36 INFO - 'TMOUT': '86400', 04:16:36 INFO - 'USER': 'cltbld', 04:16:36 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996', 04:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:36 INFO - '_': '/tools/buildbot/bin/python'} 04:16:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 04:16:36 INFO - mozversion application_buildid: 20160721013053 04:16:36 INFO - mozversion application_changeset: 79b1ba1f1f4bcc68801315438fa632e6c916549e 04:16:36 INFO - mozversion application_display_name: Nightly 04:16:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:16:36 INFO - mozversion application_name: Firefox 04:16:36 INFO - mozversion application_remotingname: firefox 04:16:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:16:36 INFO - mozversion application_vendor: Mozilla 04:16:36 INFO - mozversion application_version: 50.0a1 04:16:36 INFO - mozversion platform_buildid: 20160721013053 04:16:36 INFO - mozversion platform_changeset: 79b1ba1f1f4bcc68801315438fa632e6c916549e 04:16:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:16:36 INFO - mozversion platform_version: 50.0a1 04:16:36 INFO - using testdate: 1469099796 04:16:36 INFO - actual date: 1469099796 04:16:36 INFO - starting webserver on 'localhost:54989' 04:16:36 INFO - SUITE-START | Running 2 tests 04:16:36 INFO - TEST-START | dromaeo_css 04:16:36 INFO - operating with platform_type : linux_ 04:16:36 INFO - Initialising browser for dromaeo_css test... 04:16:36 INFO - TEST-INFO | started process 5168 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOHdzVe/profile http://localhost:54989/getInfo.html) 04:16:40 INFO - PROCESS | 5168 | __metrics Screen width/height:1600/1200 04:16:40 INFO - PROCESS | 5168 | colorDepth:24 04:16:40 INFO - PROCESS | 5168 | Browser inner width/height: 1024/697 04:16:40 INFO - PROCESS | 5168 | __metrics 04:16:41 INFO - PROCESS | 5168 | 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" 04:16:41 INFO - PROCESS | 5168 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:16:42 INFO - TEST-INFO | 5168: exit 0 04:16:42 INFO - Browser initialized. 04:16:42 INFO - Running cycle 1/1 for dromaeo_css test... 04:16:42 INFO - TEST-INFO | started process 5248 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOHdzVe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:16:53 INFO - PROCESS | 5248 | RSS: Main: 157257728 04:16:53 INFO - PROCESS | 5248 | 04:17:44 INFO - PROCESS | 5248 | 1469099864844 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] 04:19:53 INFO - PROCESS | 5248 | Cycle 1(1): loaded http://localhost:54989/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54989/tests/dromaeo/cssquery-ext.html) 04:19:54 INFO - PROCESS | 5248 | RSS: Main: 279130112 04:19:54 INFO - PROCESS | 5248 | 04:22:56 INFO - PROCESS | 5248 | Cycle 1(1): loaded http://localhost:54989/tests/dromaeo/cssquery-ext.html (next: http://localhost:54989/tests/dromaeo/cssquery-jquery.html) 04:22:57 INFO - PROCESS | 5248 | RSS: Main: 240455680 04:22:57 INFO - PROCESS | 5248 | 04:25:59 INFO - PROCESS | 5248 | Cycle 1(1): loaded http://localhost:54989/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54989/tests/dromaeo/cssquery-mootools.html) 04:25:59 INFO - PROCESS | 5248 | RSS: Main: 186290176 04:25:59 INFO - PROCESS | 5248 | 04:29:01 INFO - PROCESS | 5248 | Cycle 1(1): loaded http://localhost:54989/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54989/tests/dromaeo/cssquery-prototype.html) 04:29:02 INFO - PROCESS | 5248 | RSS: Main: 226418688 04:29:02 INFO - PROCESS | 5248 | 04:32:05 INFO - PROCESS | 5248 | Cycle 1(1): loaded http://localhost:54989/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54989/tests/dromaeo/cssquery-yui.html) 04:32:05 INFO - PROCESS | 5248 | RSS: Main: 184905728 04:32:05 INFO - PROCESS | 5248 | 04:35:08 INFO - PROCESS | 5248 | Cycle 1(1): loaded http://localhost:54989/tests/dromaeo/cssquery-yui.html (next: http://localhost:54989/tests/dromaeo/cssquery-dojo.html) 04:35:09 INFO - PROCESS | 5248 | RSS: Main: 279764992 04:35:09 INFO - PROCESS | 5248 | 04:35:09 INFO - PROCESS | 5248 | __start_tp_report 04:35:09 INFO - PROCESS | 5248 | _x_x_mozilla_page_load 04:35:09 INFO - PROCESS | 5248 | _x_x_mozilla_page_load_details 04:35:09 INFO - PROCESS | 5248 | |i|pagename|runs| 04:35:09 INFO - PROCESS | 5248 | |0;dojo.html;3004.0236923000025;3023.4104349651816;3044.6955304469543;3075.954175580302;3076.6461856886463;5157.406898206707;5206.661566998143;5245.8688532786655;5253.947460525394;5303.759891184559;5267.26258323834;5272.525472707455;5345.037893179227;5391.676499410039;5414.756335964881;4786.353842231298;4814.614830813544;4826.276058591205;4877.170880950229;4980.925286120693;4915.3855768029;4950.702957822542;4953.306537084811;4957.553820156185;4965.255211718253;4786.282057691356;4827.951720482785;4845.248986407112;4908.460069392382;4926.926096108561;4886.486918873503;4892.755362231874;4895.069936712013;4896.436909755388;4899.167141585904;4790.137775200463;4837.975810120927;4863.343448634424;4868.099401610713;4875.098106850244;5219.765110570033;5277.947220527784;5285.048691235576;5357.955198736248;5368;5654.688992105433;5715.485606295453;5725.971370143165;5728.541716662657;5730.856728581777;5565.721713914288;5726.255586773735;5748.741306641211;5767.826965191051;5794.420557944214;5174.327337446146;5265.473452654742;5324.627276090714;5325.4142044375085;5402.432744561843;5836.503897168704;5847.619904706181;5890.852728681817;5920.703964801743;5930.584859059824;5439.5376393006245;5510.91733623996;5657.57568182388;5669.574781891375;5680.034394152921;5604.355499117572;5685.658860468385;5685.744141513642;5688.63023903445;5691.686957217393;5612.382637909826;5612.691301978349;5613.663180209201;5616.438356164351;5661.66030038199;5372.70450125239;5567;5630.85922851932;5633.605647604629;5643.181738647873;4335.479742430865;4344.9131017380105;4385.386045953569;4385.824567017284;4388.451443569554;3867.9226415471535;3881.4954055972544;3884.8057597120032;3904.3557812960544;3920.7843568604;5263.421023687391;5366.946330536644;5420.810271640474;5424.674519528841;5429.6742195467605;5654.349749778746;5686.118651609007;5711.171880077365;5735.913961290584;5763.6541807491685;5373.516383525502;5414.91877621836;5426.050441172779;5433.429489903582;5439.782408703608;4821.132196204717;4938.950610493849;4953.554180123806;4978.6017118630425;5019.372578427697;5756.0502517084215;5813.215215945877;5819.854503637358;5865.618734782225;5919.437653422918;5689.118186681071;5692.231548741032;5693.4875861172695;5697.287839020122;5703.429657034263;5641.9435805641415;5855.765769369177;5865.120231965239;5868.2664666916635;5871.647701137946;5227.947720522746;5561.221429000024;5602.327720673546;5629.605927585029;5698.316202055614;5682.6306290091015;5724.112762521816;5731.7420716067045;5735.311762588516;5738.340090889435;5431.918521222264;5622.550195984393;5628.718564071862;5630.268065151504;5632.605717599728;5361.812336568201;5524.1989911463415;5559.63862348946;5578.079616863172;5645.548356131418;5569.443055694398;5571.582131340247;5578.051731205624;5584.134459158837;5588.441155884541;5482.862928426662;5549.0289199389135;5607.2149899014985;5612.961602103624;5669.829905102853;5408.59435542328;5461.699606521679;5476.561875049907;5477.3427188737605;5551.361593416808 04:35:09 INFO - PROCESS | 5248 | |1;ext.html;558.3690429814309;585.8096118761403;616.346672527121;628.7422156915666;646.3213625693027;43133.568664313345;43842.342364864555;44161.77919110408;44332.55667443325;48138;45281.77359113208;45698.771506142504;45766.54233457665;45770;45851.0829783405;9124;9289.396189247676;9289.581968811386;9295.11696388842;9317.161455469006;9117.361784675042;9183.54082295886;9212.585433655502;9228.67699630513;9235.953820230889;9314.115159059878;9343.112404321579;9352.953235233814;9356.578953947055;9359;9180.908190918106;9209.355345125843;9247.49138797366;9256.814863702688;9272.258219342437;9014.504202268872;9067.727968160965;9082.909170908339;9089.500077495735;9091.272698184195;42793;43264;43283.70148895538;43416.78291608553;43485.34771978391;3346.3813024426413;3542.752007359486;3559.857605695769;3571.8392672329805;3669.082729317671;33490.1627459313;33918.83040584806;33958.15104622379;33989.83005084959;34032.149196270046;4294.398784170218;4299.871003869889;4301.440812694361;4341.652667786601;4428.756418396987;265192;266098;266531;266572.9999999981;267121;53691.46308536865;59405;59581;59648.70175649094;59909.40090599126;61536.69231653813;61645.38354616486;61785.691071545254;61816.69091654513;61931;17339.56651083733;17572.38496652611;17574.64850702979;17575.824241757673;17590.736138957927;13393.263370514715;13450.394732237073;13523.729525409437;13527.120737152054;13538.05233633656;14756.335964881606;16917.323307067825;16943.15284235783;17017.234224459928;17044.403445879336;5139.434662187156;5823.679697616672;5880.617759845596;5994.880102397928;6025.728842202111;945.6312038305067;1030.6959446963165;1053.6364954090827;1169.6666450061691;1191.9463624136936;32363.19092022718;32505.34989300248;32616.510752338734;32804.507932381035;33883.66116338854;1101.9944900275607;1139.1513322574735;1164.4294295795;1178.5992762460633;1217.9939100304618;17294;17411.47765567035;17502.649947000988;17544.649107017787;17757.822421775876;545309;550748;550935;550948;551524;60126.699366502886;60188.69905650444;60350.69824650937;60401;60511;1204.656672848234;1324.9867501325057;1348.9595312140455;1372.293268966483;1379.9103058301177;32189.517157242193;33827.4925876117;34308.48537271893;34444.727750863625;34463.65536344705;51589.742051289504;52055;52140.21789673082;52384.738076309375;52450.73774631255;328049;332405;333815;334606;334678;35565.82217088898;36551;36660.45009324916;36691.63308366989;39113;43889.78055109704;47638.76180619213;48045.5195448055;48908.2663760051;49625.50374496209;40678.47418250879;42774;42872;43208;43222.567774321855;42455.575444245165;42947.35578966256;43142.56857431385;43193;43700.78149609359;41376.58623413727;41900;42281.57718422776;42505.57494425016;42743.14513709771;34427;37320.44019339766;37457;37470;38304.42543361908;28537.28656783597;28702;28799;28866.855665721538;28922.710772892002 04:35:09 INFO - PROCESS | 5248 | |2;jquery.html;385.1718804570172;392.1412107484608;394.4044492815848;403.7295012341731;421.0988484642865;12950.81885335556;18627.254909803607;20317.288894888683;20409;20794;14375.64060898478;18604.90697546514;19172.904135479337;19182.61634767304;19567.706484402745;5733.512651424623;5911.142884281777;5921.378255283198;5924.703764811764;5943.316518600352;5815;5891.381404952483;5895.911561326584;5900.262467191601;5901.05583106703;4185.8534951276715;6288.308286088527;6503.674816259192;6575.4082132608055;6664.466842652576;5688.772449102031;5932.377100404482;6650.23522294935;6656.101426307459;6656.400923916871;5847.532197424239;5849.64902105875;5849.88300233993;5857.4435428634215;5859.941400585983;14080.859191408057;21496.892515537478;22244.888775556017;22430.327090187322;23546;2220.2118248022043;2294.1740973249616;2351.165336305604;2657.6810782706007;2846.558783388578;9785.706428807147;10050.195984321237;10469.162467002621;10590.78818423635;10772.4613769312;1872.1201035513286;1896.5448292409721;1912.416712902564;1923.8749481283671;1963;78885;84846;86147.56926215392;88169;89290;10053.69838904834;10152.695419137437;10201.693949181536;10442.477876106317;10483.947580262049;13843.515476958259;13845.723085538233;13848.861511384757;13853.515126970507;13863.722725545433;7885.526868387915;8119.75640730779;8148.10370859205;8175.509469431853;8271.627776750061;8175.754727358189;8179.877301840477;8180.877286840702;8457.365697572706;8497.532635705096;4044.8988775280263;4273.957260427418;4292.334688123346;4337.240982827992;4401.581849724271;1814.7096464565607;1846.5199048247553;1910.990445047766;1922.9230830766892;1972.7928567500494;443.6295693096301;921.6083164654995;1052.810494111052;1077.5258886090096;1118.608487029533;11603.419829008515;11783.116266279892;11845.466953987092;12266.509339626493;12699.174553653984;478.6788463839827;481.1891962043967;485.4490153675604;486.6763602204565;932.9346945713735;9438.339316247933;10771.730706732238;10825.13398928084;10935.890641093647;11010.39442830636;389710;391749;393501;529651;531531;59090.81818363609;86054.56972715097;103552.48223758832;106421.46789266005;110177;996.8305388084043;1010.7422607235108;1043.5095505112736;1053.657561292583;1067.1036329483272;17338.826611733723;17782.37761678335;18191.272349106417;18234.45296641103;18512.259509619464;42976.35535467033;43747.34378984381;44708.976192142865;45143.548564513934;45352.54647453483;61217.38782612117;62269.37730622636;62380.68809655923;63448;64515.67742161259;8094.51432914027;8369.204925532065;8390.160983901562;8582.699605513777;8735.1701588349;9182.96962002551;12823.551175708806;13237.867621323663;13350.532731354357;13479.3934272956;11463.369514676773;11541.249818761753;11767.646970590897;11780.52877884875;11956.880431195577;11311.547538098384;11368.602098926498;11418.885811141783;11420.885791141982;11574.47914843851;11264.831027534758;11551.133664974992;11700.765984680429;11994;12149.271043737404;8325.833483330178;8458.435189489965;8504.702335418231;8551.786205344915;8580.613872375634;11223.494942727433;11270.267432616854;11326.943365283121;11518.596849110241;11870.703232419259 04:35:09 INFO - PROCESS | 5248 | |3;mootools.html;1333.819934308868;1498.8576085271898;1539.9692006159878;1689.771880796092;1804.864635152363;8353.081161072278;8668.219860212579;8677.30581553475;8900.1544853239;8912.688055918044;8766.123387661231;8844.425112367691;8862.158094980983;8862.423942443736;8866.645334186642;2001.35956493922;2249.4826189976316;2368.052778888441;2389.7849193572574;2390.713114426271;2351.729551101628;2374.5963186258377;2378.8334816562847;2378.8691621960784;2380.2026321182425;2313.0747700919683;2321.918732844351;2343.039353864916;2344.249840051176;2354.3172479980785;1943.1644392911044;2246.539459410817;2282.931512054641;2290.7823756743;2291.140822191678;2329.3827135808856;2367.739548649647;2369.028698233716;2371.3004663624274;2373.774491423312;8809.779755506164;8904.376693631448;8911;8919.420237684595;8933.28533717301;5114.105031619451;5229.764660590283;5237.711925844077;5258.368995720538;5265.973670131663;3199.408109499727;3314.7182489488423;3352.63121056686;3360.428727116396;3371.8482668279985;1026.5483187397597;1070.1331921143899;1073.0342691577564;1080.7892460970113;1091.1434523898781;12498.375081245993;12544.74910501794;12571.622851314476;12590.622281331574;12604.432800523999;4545.909081818345;4560.931586026212;4600.125976064537;4601.700889442175;4646.651501137429;10583.84124238137;10589.15286777056;10594.788104238027;10613;10618;5229.398619158769;5232;5232.659877107976;5238.188080847474;5241.0828105081455;4094.3653733671326;4809.326694262806;4822.638302127354;4826.251930950708;4938.555530002317;3960.5049368828895;3997.560268370477;4065.410515475299;4094.0379010932406;4100.36444351126;1233.6915771057236;1243.1484433163312;1250.9624711258678;1264.2667252993242;1277.3677029870273;869.5956380283199;962.0956301076965;970.2286682087757;970.3838062830154;1008.6116845014575;5896.616719913278;6217;6240.095186197976;6261.342559031327;6360;424.4418589554787;424.83006797281337;437.62801618624553;448.6948874765126;449.4157595126323;4128.4632997710705;4160.729552579133;4167.333226683716;4179.895502612459;4208.7685177314925;10707.946460267649;10955.233133680726;11028.558857645765;11031.72420689473;11059.22585419029;10966.780664386668;10971.890281097247;10976.835347469794;10988.011079002928;11036.944815275872;7524;7919.683212671429;7925.0489941207425;8034.758957231293;8048.959755201304;6143.109249158758;6240.843978900564;6344.841378965378;6362.23653161605;6587.538872278895;10010.599576016877;10174.69475915694;10200.591976320864;10295.940811837512;10416.895831041897;10341.689749307232;10536.367817930948;10566.577336906437;10576.894231057899;10589.894101059199;7595.506292091218;7774.727884524241;7848.332891704269;7960.601969901598;8126.796830079295;8858.955705221433;8906.688265910661;8923.821523569623;8941.329400294873;8946.642134314554;7491.8501629966;7772.961135194288;7819.374450044096;7927.088384835818;7929.920700792918;7580.583067931317;7607.543547387175;7644.159142494433;7661.425393095428;7817.452778305692;6922.8615427692175;6980.267071957574;6989.26612705679;6996.930030699627;7000.894986575104;5302.621318457152;5331.946680533145;5342.572594192533;5390.407055223844;5406.0809662356705;5238.266642669953;5307.230451584422;5345.278387417648;5384.6499977501335;5431.266778984945 04:35:09 INFO - PROCESS | 5248 | |4;prototype.html;320.5736370627066;335.9160209947513;338.48381218641583;339.87084907735056;344.63640858893865;5245.711485868281;5292.973535132319;5295.549878260348;5302.814401495948;5320.6275560710765;4940.677881395738;5021.397432308128;5229.660072095311;5233.764480598373;5313.468653134684;4117.661759928021;4119.073208528072;4120.887360412687;4140.710150289481;4141.792910354486;4136.172765446908;4156.376543518481;4168;4175.645070169039;4181.665466762652;3876.530470501867;3952.170044290702;3993.800309984489;4100.8107648781815;4131.111810960643;4096;4109.753414795122;4124.175164967019;4131.690123240754;4148.336266197409;3611.638836116367;3670.4494325851338;3672.8347224374966;3674.375356189454;3680.5399325084363;4946.554810067075;4998.500149985009;5018.222175562793;5027.547520723152;5045.621578381636;4680.063987202573;4680.953190468086;4691.695039822401;4697.765111744399;4711.269753188227;5434.646747961409;5440.524561324588;5466.289382380265;5476.2333273341765;5508.091164957777;3951.585083566242;4019.979900100481;4037.354023356249;4060.47213862199;4068.511778586559;5641.492265696066;5646.096624540054;5654.37801841797;5666.12175112854;5681.57388195887;4132.607402296837;4144.067584793458;4151.91696166075;4157.521884983205;4159.792010399528;4081.8979525511504;4155.355919832431;4195.286801243796;4200.453940987713;4202.180574787887;4635.95364046362;4636.675432719677;4691.81232750693;4702.36518070063;4706.294055891643;5410.836670115944;5424.7016414096815;5436.565074794007;5440.6735595864375;5460.563154947595;1407.71141915907;1421.580633713057;1437.8705916467359;1438.0149597525728;1442.7980082788417;929.934904556688;943.3915124744501;947.6398968392105;957.4829592020232;961.5048250151178;4756.240191129305;4757.762111894461;4763.476017638057;4776.8566942991765;4798.712881676063;4741.691790033637;4963.354763880745;4974.37820272466;5031.396232452129;5051.494850514919;4748.738819364969;4761.9047619047815;4772.546608072227;4783.330333753277;4838.467768545457;2600;2689.6906855711454;2694.609281654149;2703.445793612305;2720.530913306792;5039.7228152451235;5055.9241611375855;5056.673929992245;5069.161269688912;5074.16276314404;5033.295338652591;5052.595792336604;5073.720945347967;5086.634714857609;5115.2582875483595;4825.276208568673;5026.421961474477;5062.367204099488;5085.796568137233;5163.845084647466;4926.729029903247;4969.471675110712;4997.775100120431;5021.372328458942;5026.69305980455;4884.877878052935;4905.945221777335;4931.654784165217;4951.306817045688;5000.549950504472;4779.499880163045;5073.477956612927;5075.7969681212335;5094.879126592144;5105.213175388584;3668.376376016137;3672.1554042569824;3708.925821483609;3740.3080430120513;3749.6062913393703;5027.321311622884;5381.973090134525;5418.945810541844;5612.961602103624;5658.632188907708;5487.75305111279;5507.917381239205;5528.143137813645;5528.590209496552;5534.308211473566;5232.215167724872;5449.455054494519;5493.7802487900035;5515.283013208257;5516.503514683698;5488.32606055157;5490.917636235539;5518.530924871431;5521.585881058856;5542.61201715876;5552.361478430033;5576.9163462547285;5598.916016259678;5599.776008959596;5603.299587551556;5183.274341592105;5368.57051435892;5430.321209848769;5530.5022547970875;5536.307961504812 04:35:09 INFO - PROCESS | 5248 | |5;yui.html;741.1883987034197;758.6737702787799;795.2286282306164;837.5686521441455;854.6581367453022;13646.658833529154;14643.707125857476;14792.334344954475;14814.185219812905;14817.407303707838;13486.12340197889;13644.658883527893;13657.658558536017;13666.248356340422;13668.794968075486;640.810960766575;761.4555592751168;783.7100272899036;786.685325869651;787.7439832054605;771.1671394893501;794.9801254968643;797.0156856282487;813.0974618173823;813.8494378539959;860.3590325207729;862.0431321233426;863.935204859635;864.0797550608581;864.5029108262743;667.5794249622755;859.2953777902122;860.1828263150032;860.6557377049168;860.7977125375531;600.8948434024028;616.1866336435907;618.5237367227255;628.5788521690479;628.8930881750068;12517.499300027988;12530.185537940004;12532.812007819799;12540.498380064786;12596.433160507799;14035.71928561423;14663.193524356157;14704.705905881929;14715.190664513448;14729.263536823222;13100;13389.397477113456;13487.325633718374;13626.591202263948;14040.1575905446;4449;4465.553444655541;4477.968157996781;4480.5743454372105;4524.728516289;70459.64770176167;70558;71117;71122;71206.64396677984;10351.120154786928;10704.839427408438;11250.324980501033;11455.140864435201;11810.055195584333;21877.781222187576;21892.78107218939;21909.780902190774;21914.780852191594;21915.917987791454;8231.547264900488;8485.406021578554;8522.914770852336;8570.442921210102;8693.304535637133;4835.758212089381;4878.487758785347;4977.776000080005;5066.290719299301;5158.458361872024;3571.74997750155;3661.9816900915325;3667.1932174921476;3667.7249206309634;3670.6512881276235;604.1843511259764;604.782278379783;618.3970628637043;621.7171187109855;625.0499241153422;702.8418605813652;706.5336877660822;723.8516104198627;728.5774250934551;736.1056316575387;9487.430754154773;9821.410715357102;9828.655997040212;9862.802743945082;9978.700638980843;648.5622205011692;649.1690635985946;659.1990731261541;693.9757108501178;749.9400047996147;4720.268358404453;4728.794157489818;4826.95173048265;4839.080574690797;4851.441373862998;59664.70167649134;60425;98996.01003990012;112870;116061;72723.63638181775;86929.56535217284;91872.54063729639;93176.53411733033;93840;53250.20124698133;81184.5644630662;83470;85383;86994.56502717572;9104.408213466104;9272.258219342571;9276.211522020703;9281.92929047113;9292.16370526656;51587.48412515977;51862;51911.74044129755;53052.20421693755;53558.464415355345;43698.78150609354;43972.34041489444;65435.672821635584;76326.23673763192;76550;11987.22083064598;12170.148089633994;12237.326947018;12462.688432789253;12900.612981610566;11345.659630211107;11584.304941703525;11748.88251117478;12031.518739250332;12352.814707779571;12123.393830308625;12177.33024683651;12355;12516.31160286193;12927.224366538037;12021.519139234333;12134.817977730165;12209.556341105132;12302.81545776832;12524.311162886128;12425.62723118308;12994.80507792365;13018.739625207254;13038.282894440898;13051.412686428943;9964;10208.90190046153;10316.226283028653;10619;10890.945545272223;10413.739656508647;10453.26827122094;10453.372797632166;10495.265331426726;10548.72563718141 04:35:09 INFO - PROCESS | 5248 | __end_tp_report 04:35:09 INFO - PROCESS | 5248 | __start_cc_report 04:35:09 INFO - PROCESS | 5248 | _x_x_mozilla_cycle_collect,1059 04:35:09 INFO - PROCESS | 5248 | __end_cc_report 04:35:09 INFO - PROCESS | 5248 | __startTimestamp1469100909037__endTimestamp 04:35:09 INFO - PROCESS | 5248 | 04:35:09 INFO - PROCESS | 5248 | ------- Summary: start ------- 04:35:09 INFO - PROCESS | 5248 | Number of tests: 6 04:35:09 INFO - PROCESS | 5248 | 04:35:09 INFO - PROCESS | 5248 | [#0] dojo.html Cycles:165 Average:5287.38 Median:5476.56 stddev:595.48 (10.9%) stddev-sans-first:569.73 04:35:09 INFO - PROCESS | 5248 | Values: 3004.0 3023.4 3044.7 3076.0 3076.6 5157.4 5206.7 5245.9 5253.9 5303.8 5267.3 5272.5 5345.0 5391.7 5414.8 4786.4 4814.6 4826.3 4877.2 4980.9 4915.4 4950.7 4953.3 4957.6 4965.3 4786.3 4828.0 4845.2 4908.5 4926.9 4886.5 4892.8 4895.1 4896.4 4899.2 4790.1 4838.0 4863.3 4868.1 4875.1 5219.8 5277.9 5285.0 5358.0 5368.0 5654.7 5715.5 5726.0 5728.5 5730.9 5565.7 5726.3 5748.7 5767.8 5794.4 5174.3 5265.5 5324.6 5325.4 5402.4 5836.5 5847.6 5890.9 5920.7 5930.6 5439.5 5510.9 5657.6 5669.6 5680.0 5604.4 5685.7 5685.7 5688.6 5691.7 5612.4 5612.7 5613.7 5616.4 5661.7 5372.7 5567.0 5630.9 5633.6 5643.2 4335.5 4344.9 4385.4 4385.8 4388.5 3867.9 3881.5 3884.8 3904.4 3920.8 5263.4 5366.9 5420.8 5424.7 5429.7 5654.3 5686.1 5711.2 5735.9 5763.7 5373.5 5414.9 5426.1 5433.4 5439.8 4821.1 4939.0 4953.6 4978.6 5019.4 5756.1 5813.2 5819.9 5865.6 5919.4 5689.1 5692.2 5693.5 5697.3 5703.4 5641.9 5855.8 5865.1 5868.3 5871.6 5227.9 5561.2 5602.3 5629.6 5698.3 5682.6 5724.1 5731.7 5735.3 5738.3 5431.9 5622.6 5628.7 5630.3 5632.6 5361.8 5524.2 5559.6 5578.1 5645.5 5569.4 5571.6 5578.1 5584.1 5588.4 5482.9 5549.0 5607.2 5613.0 5669.8 5408.6 5461.7 5476.6 5477.3 5551.4 04:35:09 INFO - PROCESS | 5248 | 04:35:09 INFO - PROCESS | 5248 | [#1] ext.html Cycles:180 Average:56189.34 Median:23623.61 stddev:106507.85 (450.9%) stddev-sans-first:106724.73 04:35:09 INFO - PROCESS | 5248 | Values: 558.4 585.8 616.3 628.7 646.3 43133.6 43842.3 44161.8 44332.6 48138.0 45281.8 45698.8 45766.5 45770.0 45851.1 9124.0 9289.4 9289.6 9295.1 9317.2 9117.4 9183.5 9212.6 9228.7 9236.0 9314.1 9343.1 9353.0 9356.6 9359.0 9180.9 9209.4 9247.5 9256.8 9272.3 9014.5 9067.7 9082.9 9089.5 9091.3 42793.0 43264.0 43283.7 43416.8 43485.3 3346.4 3542.8 3559.9 3571.8 3669.1 33490.2 33918.8 33958.2 33989.8 34032.1 4294.4 4299.9 4301.4 4341.7 4428.8 265192.0 266098.0 266531.0 266573.0 267121.0 53691.5 59405.0 59581.0 59648.7 59909.4 61536.7 61645.4 61785.7 61816.7 61931.0 17339.6 17572.4 17574.6 17575.8 17590.7 13393.3 13450.4 13523.7 13527.1 13538.1 14756.3 16917.3 16943.2 17017.2 17044.4 5139.4 5823.7 5880.6 5994.9 6025.7 945.6 1030.7 1053.6 1169.7 1191.9 32363.2 32505.3 32616.5 32804.5 33883.7 1102.0 1139.2 1164.4 1178.6 1218.0 17294.0 17411.5 17502.6 17544.6 17757.8 545309.0 550748.0 550935.0 550948.0 551524.0 60126.7 60188.7 60350.7 60401.0 60511.0 1204.7 1325.0 1349.0 1372.3 1379.9 32189.5 33827.5 34308.5 34444.7 34463.7 51589.7 52055.0 52140.2 52384.7 52450.7 328049.0 332405.0 333815.0 334606.0 334678.0 35565.8 36551.0 36660.5 36691.6 39113.0 43889.8 47638.8 48045.5 48908.3 49625.5 40678.5 42774.0 42872.0 43208.0 43222.6 42455.6 42947.4 43142.6 43193.0 43700.8 41376.6 41900.0 42281.6 42505.6 42743.1 34427.0 37320.4 37457.0 37470.0 38304.4 28537.3 28702.0 28799.0 28866.9 28922.7 04:35:09 INFO - PROCESS | 5248 | 04:35:09 INFO - PROCESS | 5248 | [#2] jquery.html Cycles:180 Average:27807.63 Median:2752.12 stddev:75183.51 (2731.8%) stddev-sans-first:75366.23 04:35:09 INFO - PROCESS | 5248 | Values: 385.2 392.1 394.4 403.7 421.1 12950.8 18627.3 20317.3 20409.0 20794.0 14375.6 18604.9 19172.9 19182.6 19567.7 5733.5 5911.1 5921.4 5924.7 5943.3 5815.0 5891.4 5895.9 5900.3 5901.1 4185.9 6288.3 6503.7 6575.4 6664.5 5688.8 5932.4 6650.2 6656.1 6656.4 5847.5 5849.6 5849.9 5857.4 5859.9 14080.9 21496.9 22244.9 22430.3 23546.0 2220.2 2294.2 2351.2 2657.7 2846.6 9785.7 10050.2 10469.2 10590.8 10772.5 1872.1 1896.5 1912.4 1923.9 1963.0 78885.0 84846.0 86147.6 88169.0 89290.0 10053.7 10152.7 10201.7 10442.5 10483.9 13843.5 13845.7 13848.9 13853.5 13863.7 7885.5 8119.8 8148.1 8175.5 8271.6 8175.8 8179.9 8180.9 8457.4 8497.5 4044.9 4274.0 4292.3 4337.2 4401.6 1814.7 1846.5 1911.0 1922.9 1972.8 443.6 921.6 1052.8 1077.5 1118.6 11603.4 11783.1 11845.5 12266.5 12699.2 478.7 481.2 485.4 486.7 932.9 9438.3 10771.7 10825.1 10935.9 11010.4 389710.0 391749.0 393501.0 529651.0 531531.0 59090.8 86054.6 103552.5 106421.5 110177.0 996.8 1010.7 1043.5 1053.7 1067.1 17338.8 17782.4 18191.3 18234.5 18512.3 42976.4 43747.3 44709.0 45143.5 45352.5 61217.4 62269.4 62380.7 63448.0 64515.7 8094.5 8369.2 8390.2 8582.7 8735.2 9183.0 12823.6 13237.9 13350.5 13479.4 11463.4 11541.2 11767.6 11780.5 11956.9 11311.5 11368.6 11418.9 11420.9 11574.5 11264.8 11551.1 11700.8 11994.0 12149.3 8325.8 8458.4 8504.7 8551.8 8580.6 11223.5 11270.3 11326.9 11518.6 11870.7 04:35:09 INFO - PROCESS | 5248 | 04:35:09 INFO - PROCESS | 5248 | [#3] mootools.html Cycles:180 Average:5853.80 Median:449.06 stddev:3379.47 (752.6%) stddev-sans-first:3371.87 04:35:09 INFO - PROCESS | 5248 | Values: 1333.8 1498.9 1540.0 1689.8 1804.9 8353.1 8668.2 8677.3 8900.2 8912.7 8766.1 8844.4 8862.2 8862.4 8866.6 2001.4 2249.5 2368.1 2389.8 2390.7 2351.7 2374.6 2378.8 2378.9 2380.2 2313.1 2321.9 2343.0 2344.2 2354.3 1943.2 2246.5 2282.9 2290.8 2291.1 2329.4 2367.7 2369.0 2371.3 2373.8 8809.8 8904.4 8911.0 8919.4 8933.3 5114.1 5229.8 5237.7 5258.4 5266.0 3199.4 3314.7 3352.6 3360.4 3371.8 1026.5 1070.1 1073.0 1080.8 1091.1 12498.4 12544.7 12571.6 12590.6 12604.4 4545.9 4560.9 4600.1 4601.7 4646.7 10583.8 10589.2 10594.8 10613.0 10618.0 5229.4 5232.0 5232.7 5238.2 5241.1 4094.4 4809.3 4822.6 4826.3 4938.6 3960.5 3997.6 4065.4 4094.0 4100.4 1233.7 1243.1 1251.0 1264.3 1277.4 869.6 962.1 970.2 970.4 1008.6 5896.6 6217.0 6240.1 6261.3 6360.0 424.4 424.8 437.6 448.7 449.4 4128.5 4160.7 4167.3 4179.9 4208.8 10707.9 10955.2 11028.6 11031.7 11059.2 10966.8 10971.9 10976.8 10988.0 11036.9 7524.0 7919.7 7925.0 8034.8 8049.0 6143.1 6240.8 6344.8 6362.2 6587.5 10010.6 10174.7 10200.6 10295.9 10416.9 10341.7 10536.4 10566.6 10576.9 10589.9 7595.5 7774.7 7848.3 7960.6 8126.8 8859.0 8906.7 8923.8 8941.3 8946.6 7491.9 7773.0 7819.4 7927.1 7929.9 7580.6 7607.5 7644.2 7661.4 7817.5 6922.9 6980.3 6989.3 6996.9 7000.9 5302.6 5331.9 5342.6 5390.4 5406.1 5238.3 5307.2 5345.3 5384.6 5431.3 04:35:09 INFO - PROCESS | 5248 | 04:35:09 INFO - PROCESS | 5248 | [#4] prototype.html Cycles:180 Average:4455.81 Median:4966.41 stddev:1267.44 (25.5%) stddev-sans-first:1232.40 04:35:09 INFO - PROCESS | 5248 | Values: 320.6 335.9 338.5 339.9 344.6 5245.7 5293.0 5295.5 5302.8 5320.6 4940.7 5021.4 5229.7 5233.8 5313.5 4117.7 4119.1 4120.9 4140.7 4141.8 4136.2 4156.4 4168.0 4175.6 4181.7 3876.5 3952.2 3993.8 4100.8 4131.1 4096.0 4109.8 4124.2 4131.7 4148.3 3611.6 3670.4 3672.8 3674.4 3680.5 4946.6 4998.5 5018.2 5027.5 5045.6 4680.1 4681.0 4691.7 4697.8 4711.3 5434.6 5440.5 5466.3 5476.2 5508.1 3951.6 4020.0 4037.4 4060.5 4068.5 5641.5 5646.1 5654.4 5666.1 5681.6 4132.6 4144.1 4151.9 4157.5 4159.8 4081.9 4155.4 4195.3 4200.5 4202.2 4636.0 4636.7 4691.8 4702.4 4706.3 5410.8 5424.7 5436.6 5440.7 5460.6 1407.7 1421.6 1437.9 1438.0 1442.8 929.9 943.4 947.6 957.5 961.5 4756.2 4757.8 4763.5 4776.9 4798.7 4741.7 4963.4 4974.4 5031.4 5051.5 4748.7 4761.9 4772.5 4783.3 4838.5 2600.0 2689.7 2694.6 2703.4 2720.5 5039.7 5055.9 5056.7 5069.2 5074.2 5033.3 5052.6 5073.7 5086.6 5115.3 4825.3 5026.4 5062.4 5085.8 5163.8 4926.7 4969.5 4997.8 5021.4 5026.7 4884.9 4905.9 4931.7 4951.3 5000.5 4779.5 5073.5 5075.8 5094.9 5105.2 3668.4 3672.2 3708.9 3740.3 3749.6 5027.3 5382.0 5418.9 5613.0 5658.6 5487.8 5507.9 5528.1 5528.6 5534.3 5232.2 5449.5 5493.8 5515.3 5516.5 5488.3 5490.9 5518.5 5521.6 5542.6 5552.4 5576.9 5598.9 5599.8 5603.3 5183.3 5368.6 5430.3 5530.5 5536.3 04:35:09 INFO - PROCESS | 5248 | 04:35:09 INFO - PROCESS | 5248 | [#5] yui.html Cycles:180 Average:18788.62 Median:5022.03 stddev:26186.56 (521.4%) stddev-sans-first:26224.96 04:35:09 INFO - PROCESS | 5248 | Values: 741.2 758.7 795.2 837.6 854.7 13646.7 14643.7 14792.3 14814.2 14817.4 13486.1 13644.7 13657.7 13666.2 13668.8 640.8 761.5 783.7 786.7 787.7 771.2 795.0 797.0 813.1 813.8 860.4 862.0 863.9 864.1 864.5 667.6 859.3 860.2 860.7 860.8 600.9 616.2 618.5 628.6 628.9 12517.5 12530.2 12532.8 12540.5 12596.4 14035.7 14663.2 14704.7 14715.2 14729.3 13100.0 13389.4 13487.3 13626.6 14040.2 4449.0 4465.6 4478.0 4480.6 4524.7 70459.6 70558.0 71117.0 71122.0 71206.6 10351.1 10704.8 11250.3 11455.1 11810.1 21877.8 21892.8 21909.8 21914.8 21915.9 8231.5 8485.4 8522.9 8570.4 8693.3 4835.8 4878.5 4977.8 5066.3 5158.5 3571.7 3662.0 3667.2 3667.7 3670.7 604.2 604.8 618.4 621.7 625.0 702.8 706.5 723.9 728.6 736.1 9487.4 9821.4 9828.7 9862.8 9978.7 648.6 649.2 659.2 694.0 749.9 4720.3 4728.8 4827.0 4839.1 4851.4 59664.7 60425.0 98996.0 112870.0 116061.0 72723.6 86929.6 91872.5 93176.5 93840.0 53250.2 81184.6 83470.0 85383.0 86994.6 9104.4 9272.3 9276.2 9281.9 9292.2 51587.5 51862.0 51911.7 53052.2 53558.5 43698.8 43972.3 65435.7 76326.2 76550.0 11987.2 12170.1 12237.3 12462.7 12900.6 11345.7 11584.3 11748.9 12031.5 12352.8 12123.4 12177.3 12355.0 12516.3 12927.2 12021.5 12134.8 12209.6 12302.8 12524.3 12425.6 12994.8 13018.7 13038.3 13051.4 9964.0 10208.9 10316.2 10619.0 10890.9 10413.7 10453.3 10453.4 10495.3 10548.7 04:35:09 INFO - PROCESS | 5248 | -------- Summary: end -------- 04:35:09 INFO - PROCESS | 5248 | 04:35:09 INFO - TEST-INFO | 5248: exit 0 04:35:09 INFO - TEST-OK | dromaeo_css | took 1113176ms 04:35:09 INFO - TEST-START | kraken 04:35:09 INFO - operating with platform_type : linux_ 04:35:09 INFO - Initialising browser for kraken test... 04:35:09 INFO - TEST-INFO | started process 5439 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNuGOKl/profile http://localhost:54989/getInfo.html) 04:35:13 INFO - PROCESS | 5439 | __metrics Screen width/height:1600/1200 04:35:13 INFO - PROCESS | 5439 | colorDepth:24 04:35:13 INFO - PROCESS | 5439 | Browser inner width/height: 1024/697 04:35:13 INFO - PROCESS | 5439 | __metrics 04:35:14 INFO - PROCESS | 5439 | 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" 04:35:14 INFO - PROCESS | 5439 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:35:15 INFO - TEST-INFO | 5439: exit 0 04:35:15 INFO - Browser initialized. 04:35:15 INFO - Running cycle 1/1 for kraken test... 04:35:15 INFO - TEST-INFO | started process 5522 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNuGOKl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:35:26 INFO - PROCESS | 5522 | RSS: Main: 157384704 04:35:26 INFO - PROCESS | 5522 | 04:35:29 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54989/tests/kraken/driver.html?testName=audio-beat-detection) 04:35:30 INFO - PROCESS | 5522 | RSS: Main: 213782528 04:35:30 INFO - PROCESS | 5522 | 04:35:32 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54989/tests/kraken/driver.html?testName=audio-dft) 04:35:33 INFO - PROCESS | 5522 | RSS: Main: 192917504 04:35:33 INFO - PROCESS | 5522 | 04:35:36 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54989/tests/kraken/driver.html?testName=audio-fft) 04:35:37 INFO - PROCESS | 5522 | RSS: Main: 270794752 04:35:37 INFO - PROCESS | 5522 | 04:35:39 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54989/tests/kraken/driver.html?testName=audio-oscillator) 04:35:40 INFO - PROCESS | 5522 | RSS: Main: 180924416 04:35:40 INFO - PROCESS | 5522 | 04:35:42 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54989/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:35:42 INFO - PROCESS | 5522 | RSS: Main: 185081856 04:35:42 INFO - PROCESS | 5522 | 04:35:45 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54989/tests/kraken/driver.html?testName=imaging-darkroom) 04:35:46 INFO - PROCESS | 5522 | RSS: Main: 219889664 04:35:46 INFO - PROCESS | 5522 | 04:35:49 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54989/tests/kraken/driver.html?testName=imaging-desaturate) 04:35:50 INFO - PROCESS | 5522 | RSS: Main: 220426240 04:35:50 INFO - PROCESS | 5522 | 04:35:53 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54989/tests/kraken/driver.html?testName=json-parse-financial) 04:35:53 INFO - PROCESS | 5522 | RSS: Main: 219856896 04:35:53 INFO - PROCESS | 5522 | 04:35:55 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54989/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:35:55 INFO - PROCESS | 5522 | RSS: Main: 184827904 04:35:55 INFO - PROCESS | 5522 | 04:35:57 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54989/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:35:58 INFO - PROCESS | 5522 | RSS: Main: 189247488 04:35:58 INFO - PROCESS | 5522 | 04:36:00 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54989/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:36:00 INFO - PROCESS | 5522 | RSS: Main: 195543040 04:36:00 INFO - PROCESS | 5522 | 04:36:03 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:36:04 INFO - PROCESS | 5522 | RSS: Main: 190226432 04:36:04 INFO - PROCESS | 5522 | 04:36:06 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:36:07 INFO - PROCESS | 5522 | RSS: Main: 193724416 04:36:07 INFO - PROCESS | 5522 | 04:36:08 INFO - PROCESS | 5522 | Cycle 1(1): loaded http://localhost:54989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54989/tests/kraken/driver.html?testName=ai-astar) 04:36:09 INFO - PROCESS | 5522 | RSS: Main: 244105216 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | __start_tp_report 04:36:09 INFO - PROCESS | 5522 | _x_x_mozilla_page_load 04:36:09 INFO - PROCESS | 5522 | _x_x_mozilla_page_load_details 04:36:09 INFO - PROCESS | 5522 | |i|pagename|runs| 04:36:09 INFO - PROCESS | 5522 | |0;ai-astar;101;111;111;110;111;112;110;109;109;110 04:36:09 INFO - PROCESS | 5522 | |1;audio-beat-detection;146;116;120;191;119;120;143;118;151;118 04:36:09 INFO - PROCESS | 5522 | |2;audio-dft;161;159;160;160;159;159;161;162;162;160 04:36:09 INFO - PROCESS | 5522 | |3;audio-fft;75;75;76;115;76;76;75;74;113;74 04:36:09 INFO - PROCESS | 5522 | |4;audio-oscillator;84;84;116;83;83;84;119;85;84;136 04:36:09 INFO - PROCESS | 5522 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;116;117;117 04:36:09 INFO - PROCESS | 5522 | |6;imaging-darkroom;146;145;146;145;145;146;145;145;145;146 04:36:09 INFO - PROCESS | 5522 | |7;imaging-desaturate;94;95;95;95;95;95;94;95;95;94 04:36:09 INFO - PROCESS | 5522 | |8;json-parse-financial;52;50;51;50;50;50;51;52;50;51 04:36:09 INFO - PROCESS | 5522 | |9;json-stringify-tinderbox;63;64;63;63;64;64;64;63;64;64 04:36:09 INFO - PROCESS | 5522 | |10;stanford-crypto-aes;76;76;75;76;76;77;78;76;78;80 04:36:09 INFO - PROCESS | 5522 | |11;stanford-crypto-ccm;120;125;138;119;117;120;121;127;175;121 04:36:09 INFO - PROCESS | 5522 | |12;stanford-crypto-pbkdf2;158;157;156;157;159;162;157;155;161;158 04:36:09 INFO - PROCESS | 5522 | |13;stanford-crypto-sha256-iterative;63;63;64;63;63;64;63;65;66;61 04:36:09 INFO - PROCESS | 5522 | __end_tp_report 04:36:09 INFO - PROCESS | 5522 | __start_cc_report 04:36:09 INFO - PROCESS | 5522 | _x_x_mozilla_cycle_collect,1666 04:36:09 INFO - PROCESS | 5522 | __end_cc_report 04:36:09 INFO - PROCESS | 5522 | __startTimestamp1469100969217__endTimestamp 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | ------- Summary: start ------- 04:36:09 INFO - PROCESS | 5522 | Number of tests: 14 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.50 stddev:3.10 (2.8%) stddev-sans-first:1.00 04:36:09 INFO - PROCESS | 5522 | Values: 101.0 111.0 111.0 110.0 111.0 112.0 110.0 109.0 109.0 110.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:131.50 stddev:24.07 (18.3%) stddev-sans-first:25.15 04:36:09 INFO - PROCESS | 5522 | Values: 146.0 116.0 120.0 191.0 119.0 120.0 143.0 118.0 151.0 118.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:1.16 (0.7%) stddev-sans-first:1.20 04:36:09 INFO - PROCESS | 5522 | Values: 161.0 159.0 160.0 160.0 159.0 159.0 161.0 162.0 162.0 160.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.00 stddev:16.41 (21.9%) stddev-sans-first:17.16 04:36:09 INFO - PROCESS | 5522 | Values: 75.0 75.0 76.0 115.0 76.0 76.0 75.0 74.0 113.0 74.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:84.00 stddev:19.90 (23.7%) stddev-sans-first:20.64 04:36:09 INFO - PROCESS | 5522 | Values: 84.0 84.0 116.0 83.0 83.0 84.0 119.0 85.0 84.0 136.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 04:36:09 INFO - PROCESS | 5522 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 04:36:09 INFO - PROCESS | 5522 | Values: 146.0 145.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 04:36:09 INFO - PROCESS | 5522 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.82 (1.6%) stddev-sans-first:0.73 04:36:09 INFO - PROCESS | 5522 | Values: 52.0 50.0 51.0 50.0 50.0 50.0 51.0 52.0 50.0 51.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:64.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 04:36:09 INFO - PROCESS | 5522 | Values: 63.0 64.0 63.0 63.0 64.0 64.0 64.0 63.0 64.0 64.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#10] stanford-crypto-aes Cycles:10 Average:76.80 Median:76.50 stddev:1.48 (1.9%) stddev-sans-first:1.54 04:36:09 INFO - PROCESS | 5522 | Values: 76.0 76.0 75.0 76.0 76.0 77.0 78.0 76.0 78.0 80.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:123.00 stddev:17.47 (14.2%) stddev-sans-first:18.27 04:36:09 INFO - PROCESS | 5522 | Values: 120.0 125.0 138.0 119.0 117.0 120.0 121.0 127.0 175.0 121.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.00 Median:158.00 stddev:2.16 (1.4%) stddev-sans-first:2.29 04:36:09 INFO - PROCESS | 5522 | Values: 158.0 157.0 156.0 157.0 159.0 162.0 157.0 155.0 161.0 158.0 04:36:09 INFO - PROCESS | 5522 | 04:36:09 INFO - PROCESS | 5522 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.50 stddev:1.35 (2.1%) stddev-sans-first:1.42 04:36:09 INFO - PROCESS | 5522 | Values: 63.0 63.0 64.0 63.0 63.0 64.0 63.0 65.0 66.0 61.0 04:36:09 INFO - PROCESS | 5522 | -------- Summary: end -------- 04:36:09 INFO - PROCESS | 5522 | 04:36:10 INFO - TEST-INFO | 5522: exit 0 04:36:10 INFO - TEST-OK | kraken | took 60442ms 04:36:10 INFO - SUITE-END | took 1173s 04:36:10 INFO - Completed test suite (00:19:33) 04:36:10 INFO - javascript benchmark 04:36:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3004.0236923000025, 3023.4104349651816, 3044.6955304469543, 3075.954175580302, 3076.6461856886463, 5157.406898206707, 5206.661566998143, 5245.8688532786655, 5253.947460525394, 5303.759891184559, 5267.26258323834, 5272.525472707455, 5345.037893179227, 5391.676499410039, 5414.756335964881, 4786.353842231298, 4814.614830813544, 4826.276058591205, 4877.170880950229, 4980.925286120693, 4915.3855768029, 4950.702957822542, 4953.306537084811, 4957.553820156185, 4965.255211718253, 4786.282057691356, 4827.951720482785, 4845.248986407112, 4908.460069392382, 4926.926096108561, 4886.486918873503, 4892.755362231874, 4895.069936712013, 4896.436909755388, 4899.167141585904, 4790.137775200463, 4837.975810120927, 4863.343448634424, 4868.099401610713, 4875.098106850244, 5219.765110570033, 5277.947220527784, 5285.048691235576, 5357.955198736248, 5368.0, 5654.688992105433, 5715.485606295453, 5725.971370143165, 5728.541716662657, 5730.856728581777, 5565.721713914288, 5726.255586773735, 5748.741306641211, 5767.826965191051, 5794.420557944214, 5174.327337446146, 5265.473452654742, 5324.627276090714, 5325.4142044375085, 5402.432744561843, 5836.503897168704, 5847.619904706181, 5890.852728681817, 5920.703964801743, 5930.584859059824, 5439.5376393006245, 5510.91733623996, 5657.57568182388, 5669.574781891375, 5680.034394152921, 5604.355499117572, 5685.658860468385, 5685.744141513642, 5688.63023903445, 5691.686957217393, 5612.382637909826, 5612.691301978349, 5613.663180209201, 5616.438356164351, 5661.66030038199, 5372.70450125239, 5567.0, 5630.85922851932, 5633.605647604629, 5643.181738647873, 4335.479742430865, 4344.9131017380105, 4385.386045953569, 4385.824567017284, 4388.451443569554, 3867.9226415471535, 3881.4954055972544, 3884.8057597120032, 3904.3557812960544, 3920.7843568604, 5263.421023687391, 5366.946330536644, 5420.810271640474, 5424.674519528841, 5429.6742195467605, 5654.349749778746, 5686.118651609007, 5711.171880077365, 5735.913961290584, 5763.6541807491685, 5373.516383525502, 5414.91877621836, 5426.050441172779, 5433.429489903582, 5439.782408703608, 4821.132196204717, 4938.950610493849, 4953.554180123806, 4978.6017118630425, 5019.372578427697, 5756.0502517084215, 5813.215215945877, 5819.854503637358, 5865.618734782225, 5919.437653422918, 5689.118186681071, 5692.231548741032, 5693.4875861172695, 5697.287839020122, 5703.429657034263, 5641.9435805641415, 5855.765769369177, 5865.120231965239, 5868.2664666916635, 5871.647701137946, 5227.947720522746, 5561.221429000024, 5602.327720673546, 5629.605927585029, 5698.316202055614, 5682.6306290091015, 5724.112762521816, 5731.7420716067045, 5735.311762588516, 5738.340090889435, 5431.918521222264, 5622.550195984393, 5628.718564071862, 5630.268065151504, 5632.605717599728, 5361.812336568201, 5524.1989911463415, 5559.63862348946, 5578.079616863172, 5645.548356131418, 5569.443055694398, 5571.582131340247, 5578.051731205624, 5584.134459158837, 5588.441155884541, 5482.862928426662, 5549.0289199389135, 5607.2149899014985, 5612.961602103624, 5669.829905102853, 5408.59435542328, 5461.699606521679, 5476.561875049907, 5477.3427188737605, 5551.361593416808], "value": 5247.220953861391, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.3690429814309, 585.8096118761403, 616.346672527121, 628.7422156915666, 646.3213625693027, 43133.568664313345, 43842.342364864555, 44161.77919110408, 44332.55667443325, 48138.0, 45281.77359113208, 45698.771506142504, 45766.54233457665, 45770.0, 45851.0829783405, 9124.0, 9289.396189247676, 9289.581968811386, 9295.11696388842, 9317.161455469006, 9117.361784675042, 9183.54082295886, 9212.585433655502, 9228.67699630513, 9235.953820230889, 9314.115159059878, 9343.112404321579, 9352.953235233814, 9356.578953947055, 9359.0, 9180.908190918106, 9209.355345125843, 9247.49138797366, 9256.814863702688, 9272.258219342437, 9014.504202268872, 9067.727968160965, 9082.909170908339, 9089.500077495735, 9091.272698184195, 42793.0, 43264.0, 43283.70148895538, 43416.78291608553, 43485.34771978391, 3346.3813024426413, 3542.752007359486, 3559.857605695769, 3571.8392672329805, 3669.082729317671, 33490.1627459313, 33918.83040584806, 33958.15104622379, 33989.83005084959, 34032.149196270046, 4294.398784170218, 4299.871003869889, 4301.440812694361, 4341.652667786601, 4428.756418396987, 265192.0, 266098.0, 266531.0, 266572.9999999981, 267121.0, 53691.46308536865, 59405.0, 59581.0, 59648.70175649094, 59909.40090599126, 61536.69231653813, 61645.38354616486, 61785.691071545254, 61816.69091654513, 61931.0, 17339.56651083733, 17572.38496652611, 17574.64850702979, 17575.824241757673, 17590.736138957927, 13393.263370514715, 13450.394732237073, 13523.729525409437, 13527.120737152054, 13538.05233633656, 14756.335964881606, 16917.323307067825, 16943.15284235783, 17017.234224459928, 17044.403445879336, 5139.434662187156, 5823.679697616672, 5880.617759845596, 5994.880102397928, 6025.728842202111, 945.6312038305067, 1030.6959446963165, 1053.6364954090827, 1169.6666450061691, 1191.9463624136936, 32363.19092022718, 32505.34989300248, 32616.510752338734, 32804.507932381035, 33883.66116338854, 1101.9944900275607, 1139.1513322574735, 1164.4294295795, 1178.5992762460633, 1217.9939100304618, 17294.0, 17411.47765567035, 17502.649947000988, 17544.649107017787, 17757.822421775876, 545309.0, 550748.0, 550935.0, 550948.0, 551524.0, 60126.699366502886, 60188.69905650444, 60350.69824650937, 60401.0, 60511.0, 1204.656672848234, 1324.9867501325057, 1348.9595312140455, 1372.293268966483, 1379.9103058301177, 32189.517157242193, 33827.4925876117, 34308.48537271893, 34444.727750863625, 34463.65536344705, 51589.742051289504, 52055.0, 52140.21789673082, 52384.738076309375, 52450.73774631255, 328049.0, 332405.0, 333815.0, 334606.0, 334678.0, 35565.82217088898, 36551.0, 36660.45009324916, 36691.63308366989, 39113.0, 43889.78055109704, 47638.76180619213, 48045.5195448055, 48908.2663760051, 49625.50374496209, 40678.47418250879, 42774.0, 42872.0, 43208.0, 43222.567774321855, 42455.575444245165, 42947.35578966256, 43142.56857431385, 43193.0, 43700.78149609359, 41376.58623413727, 41900.0, 42281.57718422776, 42505.57494425016, 42743.14513709771, 34427.0, 37320.44019339766, 37457.0, 37470.0, 38304.42543361908, 28537.28656783597, 28702.0, 28799.0, 28866.855665721538, 28922.710772892002], "value": 20255.84194142095, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.1718804570172, 392.1412107484608, 394.4044492815848, 403.7295012341731, 421.0988484642865, 12950.81885335556, 18627.254909803607, 20317.288894888683, 20409.0, 20794.0, 14375.64060898478, 18604.90697546514, 19172.904135479337, 19182.61634767304, 19567.706484402745, 5733.512651424623, 5911.142884281777, 5921.378255283198, 5924.703764811764, 5943.316518600352, 5815.0, 5891.381404952483, 5895.911561326584, 5900.262467191601, 5901.05583106703, 4185.8534951276715, 6288.308286088527, 6503.674816259192, 6575.4082132608055, 6664.466842652576, 5688.772449102031, 5932.377100404482, 6650.23522294935, 6656.101426307459, 6656.400923916871, 5847.532197424239, 5849.64902105875, 5849.88300233993, 5857.4435428634215, 5859.941400585983, 14080.859191408057, 21496.892515537478, 22244.888775556017, 22430.327090187322, 23546.0, 2220.2118248022043, 2294.1740973249616, 2351.165336305604, 2657.6810782706007, 2846.558783388578, 9785.706428807147, 10050.195984321237, 10469.162467002621, 10590.78818423635, 10772.4613769312, 1872.1201035513286, 1896.5448292409721, 1912.416712902564, 1923.8749481283671, 1963.0, 78885.0, 84846.0, 86147.56926215392, 88169.0, 89290.0, 10053.69838904834, 10152.695419137437, 10201.693949181536, 10442.477876106317, 10483.947580262049, 13843.515476958259, 13845.723085538233, 13848.861511384757, 13853.515126970507, 13863.722725545433, 7885.526868387915, 8119.75640730779, 8148.10370859205, 8175.509469431853, 8271.627776750061, 8175.754727358189, 8179.877301840477, 8180.877286840702, 8457.365697572706, 8497.532635705096, 4044.8988775280263, 4273.957260427418, 4292.334688123346, 4337.240982827992, 4401.581849724271, 1814.7096464565607, 1846.5199048247553, 1910.990445047766, 1922.9230830766892, 1972.7928567500494, 443.6295693096301, 921.6083164654995, 1052.810494111052, 1077.5258886090096, 1118.608487029533, 11603.419829008515, 11783.116266279892, 11845.466953987092, 12266.509339626493, 12699.174553653984, 478.6788463839827, 481.1891962043967, 485.4490153675604, 486.6763602204565, 932.9346945713735, 9438.339316247933, 10771.730706732238, 10825.13398928084, 10935.890641093647, 11010.39442830636, 389710.0, 391749.0, 393501.0, 529651.0, 531531.0, 59090.81818363609, 86054.56972715097, 103552.48223758832, 106421.46789266005, 110177.0, 996.8305388084043, 1010.7422607235108, 1043.5095505112736, 1053.657561292583, 1067.1036329483272, 17338.826611733723, 17782.37761678335, 18191.272349106417, 18234.45296641103, 18512.259509619464, 42976.35535467033, 43747.34378984381, 44708.976192142865, 45143.548564513934, 45352.54647453483, 61217.38782612117, 62269.37730622636, 62380.68809655923, 63448.0, 64515.67742161259, 8094.51432914027, 8369.204925532065, 8390.160983901562, 8582.699605513777, 8735.1701588349, 9182.96962002551, 12823.551175708806, 13237.867621323663, 13350.532731354357, 13479.3934272956, 11463.369514676773, 11541.249818761753, 11767.646970590897, 11780.52877884875, 11956.880431195577, 11311.547538098384, 11368.602098926498, 11418.885811141783, 11420.885791141982, 11574.47914843851, 11264.831027534758, 11551.133664974992, 11700.765984680429, 11994.0, 12149.271043737404, 8325.833483330178, 8458.435189489965, 8504.702335418231, 8551.786205344915, 8580.613872375634, 11223.494942727433, 11270.267432616854, 11326.943365283121, 11518.596849110241, 11870.703232419259], "value": 8921.48554228181, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.819934308868, 1498.8576085271898, 1539.9692006159878, 1689.771880796092, 1804.864635152363, 8353.081161072278, 8668.219860212579, 8677.30581553475, 8900.1544853239, 8912.688055918044, 8766.123387661231, 8844.425112367691, 8862.158094980983, 8862.423942443736, 8866.645334186642, 2001.35956493922, 2249.4826189976316, 2368.052778888441, 2389.7849193572574, 2390.713114426271, 2351.729551101628, 2374.5963186258377, 2378.8334816562847, 2378.8691621960784, 2380.2026321182425, 2313.0747700919683, 2321.918732844351, 2343.039353864916, 2344.249840051176, 2354.3172479980785, 1943.1644392911044, 2246.539459410817, 2282.931512054641, 2290.7823756743, 2291.140822191678, 2329.3827135808856, 2367.739548649647, 2369.028698233716, 2371.3004663624274, 2373.774491423312, 8809.779755506164, 8904.376693631448, 8911.0, 8919.420237684595, 8933.28533717301, 5114.105031619451, 5229.764660590283, 5237.711925844077, 5258.368995720538, 5265.973670131663, 3199.408109499727, 3314.7182489488423, 3352.63121056686, 3360.428727116396, 3371.8482668279985, 1026.5483187397597, 1070.1331921143899, 1073.0342691577564, 1080.7892460970113, 1091.1434523898781, 12498.375081245993, 12544.74910501794, 12571.622851314476, 12590.622281331574, 12604.432800523999, 4545.909081818345, 4560.931586026212, 4600.125976064537, 4601.700889442175, 4646.651501137429, 10583.84124238137, 10589.15286777056, 10594.788104238027, 10613.0, 10618.0, 5229.398619158769, 5232.0, 5232.659877107976, 5238.188080847474, 5241.0828105081455, 4094.3653733671326, 4809.326694262806, 4822.638302127354, 4826.251930950708, 4938.555530002317, 3960.5049368828895, 3997.560268370477, 4065.410515475299, 4094.0379010932406, 4100.36444351126, 1233.6915771057236, 1243.1484433163312, 1250.9624711258678, 1264.2667252993242, 1277.3677029870273, 869.5956380283199, 962.0956301076965, 970.2286682087757, 970.3838062830154, 1008.6116845014575, 5896.616719913278, 6217.0, 6240.095186197976, 6261.342559031327, 6360.0, 424.4418589554787, 424.83006797281337, 437.62801618624553, 448.6948874765126, 449.4157595126323, 4128.4632997710705, 4160.729552579133, 4167.333226683716, 4179.895502612459, 4208.7685177314925, 10707.946460267649, 10955.233133680726, 11028.558857645765, 11031.72420689473, 11059.22585419029, 10966.780664386668, 10971.890281097247, 10976.835347469794, 10988.011079002928, 11036.944815275872, 7524.0, 7919.683212671429, 7925.0489941207425, 8034.758957231293, 8048.959755201304, 6143.109249158758, 6240.843978900564, 6344.841378965378, 6362.23653161605, 6587.538872278895, 10010.599576016877, 10174.69475915694, 10200.591976320864, 10295.940811837512, 10416.895831041897, 10341.689749307232, 10536.367817930948, 10566.577336906437, 10576.894231057899, 10589.894101059199, 7595.506292091218, 7774.727884524241, 7848.332891704269, 7960.601969901598, 8126.796830079295, 8858.955705221433, 8906.688265910661, 8923.821523569623, 8941.329400294873, 8946.642134314554, 7491.8501629966, 7772.961135194288, 7819.374450044096, 7927.088384835818, 7929.920700792918, 7580.583067931317, 7607.543547387175, 7644.159142494433, 7661.425393095428, 7817.452778305692, 6922.8615427692175, 6980.267071957574, 6989.26612705679, 6996.930030699627, 7000.894986575104, 5302.621318457152, 5331.946680533145, 5342.572594192533, 5390.407055223844, 5406.0809662356705, 5238.266642669953, 5307.230451584422, 5345.278387417648, 5384.6499977501335, 5431.266778984945], "value": 4573.41643466457, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.5736370627066, 335.9160209947513, 338.48381218641583, 339.87084907735056, 344.63640858893865, 5245.711485868281, 5292.973535132319, 5295.549878260348, 5302.814401495948, 5320.6275560710765, 4940.677881395738, 5021.397432308128, 5229.660072095311, 5233.764480598373, 5313.468653134684, 4117.661759928021, 4119.073208528072, 4120.887360412687, 4140.710150289481, 4141.792910354486, 4136.172765446908, 4156.376543518481, 4168.0, 4175.645070169039, 4181.665466762652, 3876.530470501867, 3952.170044290702, 3993.800309984489, 4100.8107648781815, 4131.111810960643, 4096.0, 4109.753414795122, 4124.175164967019, 4131.690123240754, 4148.336266197409, 3611.638836116367, 3670.4494325851338, 3672.8347224374966, 3674.375356189454, 3680.5399325084363, 4946.554810067075, 4998.500149985009, 5018.222175562793, 5027.547520723152, 5045.621578381636, 4680.063987202573, 4680.953190468086, 4691.695039822401, 4697.765111744399, 4711.269753188227, 5434.646747961409, 5440.524561324588, 5466.289382380265, 5476.2333273341765, 5508.091164957777, 3951.585083566242, 4019.979900100481, 4037.354023356249, 4060.47213862199, 4068.511778586559, 5641.492265696066, 5646.096624540054, 5654.37801841797, 5666.12175112854, 5681.57388195887, 4132.607402296837, 4144.067584793458, 4151.91696166075, 4157.521884983205, 4159.792010399528, 4081.8979525511504, 4155.355919832431, 4195.286801243796, 4200.453940987713, 4202.180574787887, 4635.95364046362, 4636.675432719677, 4691.81232750693, 4702.36518070063, 4706.294055891643, 5410.836670115944, 5424.7016414096815, 5436.565074794007, 5440.6735595864375, 5460.563154947595, 1407.71141915907, 1421.580633713057, 1437.8705916467359, 1438.0149597525728, 1442.7980082788417, 929.934904556688, 943.3915124744501, 947.6398968392105, 957.4829592020232, 961.5048250151178, 4756.240191129305, 4757.762111894461, 4763.476017638057, 4776.8566942991765, 4798.712881676063, 4741.691790033637, 4963.354763880745, 4974.37820272466, 5031.396232452129, 5051.494850514919, 4748.738819364969, 4761.9047619047815, 4772.546608072227, 4783.330333753277, 4838.467768545457, 2600.0, 2689.6906855711454, 2694.609281654149, 2703.445793612305, 2720.530913306792, 5039.7228152451235, 5055.9241611375855, 5056.673929992245, 5069.161269688912, 5074.16276314404, 5033.295338652591, 5052.595792336604, 5073.720945347967, 5086.634714857609, 5115.2582875483595, 4825.276208568673, 5026.421961474477, 5062.367204099488, 5085.796568137233, 5163.845084647466, 4926.729029903247, 4969.471675110712, 4997.775100120431, 5021.372328458942, 5026.69305980455, 4884.877878052935, 4905.945221777335, 4931.654784165217, 4951.306817045688, 5000.549950504472, 4779.499880163045, 5073.477956612927, 5075.7969681212335, 5094.879126592144, 5105.213175388584, 3668.376376016137, 3672.1554042569824, 3708.925821483609, 3740.3080430120513, 3749.6062913393703, 5027.321311622884, 5381.973090134525, 5418.945810541844, 5612.961602103624, 5658.632188907708, 5487.75305111279, 5507.917381239205, 5528.143137813645, 5528.590209496552, 5534.308211473566, 5232.215167724872, 5449.455054494519, 5493.7802487900035, 5515.283013208257, 5516.503514683698, 5488.32606055157, 5490.917636235539, 5518.530924871431, 5521.585881058856, 5542.61201715876, 5552.361478430033, 5576.9163462547285, 5598.916016259678, 5599.776008959596, 5603.299587551556, 5183.274341592105, 5368.57051435892, 5430.321209848769, 5530.5022547970875, 5536.307961504812], "value": 4060.2839324088663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.1883987034197, 758.6737702787799, 795.2286282306164, 837.5686521441455, 854.6581367453022, 13646.658833529154, 14643.707125857476, 14792.334344954475, 14814.185219812905, 14817.407303707838, 13486.12340197889, 13644.658883527893, 13657.658558536017, 13666.248356340422, 13668.794968075486, 640.810960766575, 761.4555592751168, 783.7100272899036, 786.685325869651, 787.7439832054605, 771.1671394893501, 794.9801254968643, 797.0156856282487, 813.0974618173823, 813.8494378539959, 860.3590325207729, 862.0431321233426, 863.935204859635, 864.0797550608581, 864.5029108262743, 667.5794249622755, 859.2953777902122, 860.1828263150032, 860.6557377049168, 860.7977125375531, 600.8948434024028, 616.1866336435907, 618.5237367227255, 628.5788521690479, 628.8930881750068, 12517.499300027988, 12530.185537940004, 12532.812007819799, 12540.498380064786, 12596.433160507799, 14035.71928561423, 14663.193524356157, 14704.705905881929, 14715.190664513448, 14729.263536823222, 13100.0, 13389.397477113456, 13487.325633718374, 13626.591202263948, 14040.1575905446, 4449.0, 4465.553444655541, 4477.968157996781, 4480.5743454372105, 4524.728516289, 70459.64770176167, 70558.0, 71117.0, 71122.0, 71206.64396677984, 10351.120154786928, 10704.839427408438, 11250.324980501033, 11455.140864435201, 11810.055195584333, 21877.781222187576, 21892.78107218939, 21909.780902190774, 21914.780852191594, 21915.917987791454, 8231.547264900488, 8485.406021578554, 8522.914770852336, 8570.442921210102, 8693.304535637133, 4835.758212089381, 4878.487758785347, 4977.776000080005, 5066.290719299301, 5158.458361872024, 3571.74997750155, 3661.9816900915325, 3667.1932174921476, 3667.7249206309634, 3670.6512881276235, 604.1843511259764, 604.782278379783, 618.3970628637043, 621.7171187109855, 625.0499241153422, 702.8418605813652, 706.5336877660822, 723.8516104198627, 728.5774250934551, 736.1056316575387, 9487.430754154773, 9821.410715357102, 9828.655997040212, 9862.802743945082, 9978.700638980843, 648.5622205011692, 649.1690635985946, 659.1990731261541, 693.9757108501178, 749.9400047996147, 4720.268358404453, 4728.794157489818, 4826.95173048265, 4839.080574690797, 4851.441373862998, 59664.70167649134, 60425.0, 98996.01003990012, 112870.0, 116061.0, 72723.63638181775, 86929.56535217284, 91872.54063729639, 93176.53411733033, 93840.0, 53250.20124698133, 81184.5644630662, 83470.0, 85383.0, 86994.56502717572, 9104.408213466104, 9272.258219342571, 9276.211522020703, 9281.92929047113, 9292.16370526656, 51587.48412515977, 51862.0, 51911.74044129755, 53052.20421693755, 53558.464415355345, 43698.78150609354, 43972.34041489444, 65435.672821635584, 76326.23673763192, 76550.0, 11987.22083064598, 12170.148089633994, 12237.326947018, 12462.688432789253, 12900.612981610566, 11345.659630211107, 11584.304941703525, 11748.88251117478, 12031.518739250332, 12352.814707779571, 12123.393830308625, 12177.33024683651, 12355.0, 12516.31160286193, 12927.224366538037, 12021.519139234333, 12134.817977730165, 12209.556341105132, 12302.81545776832, 12524.311162886128, 12425.62723118308, 12994.80507792365, 13018.739625207254, 13038.282894440898, 13051.412686428943, 9964.0, 10208.90190046153, 10316.226283028653, 10619.0, 10890.945545272223, 10413.739656508647, 10453.26827122094, 10453.372797632166, 10495.265331426726, 10548.72563718141], "value": 7261.673535859139, "unit": "score"}], "name": "dromaeo_css", "value": 7097.951561539921, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 111.0, 110.0, 111.0, 112.0, 110.0, 109.0, 109.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 120.0, 191.0, 119.0, 120.0, 143.0, 118.0, 151.0, 118.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 160.0, 159.0, 159.0, 161.0, 162.0, 162.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 115.0, 76.0, 76.0, 75.0, 74.0, 113.0, 74.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 116.0, 83.0, 83.0, 84.0, 119.0, 85.0, 84.0, 136.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 50.0, 50.0, 50.0, 51.0, 52.0, 50.0, 51.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 64.0, 64.0, 64.0, 63.0, 64.0, 64.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 76.0, 76.0, 77.0, 78.0, 76.0, 78.0, 80.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 125.0, 138.0, 119.0, 117.0, 120.0, 121.0, 127.0, 175.0, 121.0], "value": 128.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 157.0, 156.0, 157.0, 159.0, 162.0, 157.0, 155.0, 161.0, 158.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 63.0, 63.0, 64.0, 63.0, 65.0, 66.0, 61.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1480.6, "alertThreshold": 2.0}]} 04:36:10 INFO - Return code: 0 04:36:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:36:10 INFO - # TBPL SUCCESS # 04:36:10 INFO - Running post-action listener: _resource_record_post_action 04:36:10 INFO - [mozharness: 2016-07-21 11:36:10.506729Z] Finished run-tests step (success) 04:36:10 INFO - Running post-run listener: _resource_record_post_run 04:36:10 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 558690304; Read time: 16; Write time: 402668 04:36:10 INFO - TinderboxPrint: CPU usage
13.1% 04:36:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 04:36:10 INFO - TinderboxPrint: I/O write bytes / time
558,690,304 / 402,668 04:36:10 INFO - TinderboxPrint: CPU idle
8,261.9 (86.9%) 04:36:10 INFO - TinderboxPrint: CPU user
1,198.4 (12.6%) 04:36:10 INFO - TinderboxPrint: Swap in / out
0 / 0 04:36:10 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 259575808; Read time: 0; Write time: 245700 04:36:10 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 299114496; Read time: 0; Write time: 156968 04:36:11 INFO - Running post-run listener: _upload_blobber_files 04:36:11 INFO - Blob upload gear active. 04:36:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:36:11 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 04:36:11 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:36:11 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:36:11 INFO - (blobuploader) - INFO - Open directory for files ... 04:36:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:36:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:32 INFO - (blobuploader) - INFO - Done attempting. 04:36:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:36:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:36:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:33 INFO - (blobuploader) - INFO - Done attempting. 04:36:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:36:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:36:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:33 INFO - (blobuploader) - INFO - Done attempting. 04:36:33 INFO - (blobuploader) - INFO - Iteration through files over. 04:36:33 INFO - Return code: 0 04:36:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:36:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:36:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a91fb60ba29a17654c8ad5e634c667b128051eb6514521d318398aa21ea2511a507b143764aecada0e879b1941f454571769024d1944b4c154d73b00f5fb5e59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e3bffbf96c538131472a6e8ec06259a51ecb5ef27ecb415c387ff767852ab909f381d158496d0a3e1c1448c13387c06aeadd4ac4e3f2208092409072fda20d7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3bb2c5e76212eb4ff8de00d01fc0bfee0df746a659bd39fcc61eb088674fe9b1134f556378c327559b6f376ad4043761860b02979ddb31317990c502ce843892"} 04:36:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:36:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:36:33 INFO - Contents: 04:36:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a91fb60ba29a17654c8ad5e634c667b128051eb6514521d318398aa21ea2511a507b143764aecada0e879b1941f454571769024d1944b4c154d73b00f5fb5e59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e3bffbf96c538131472a6e8ec06259a51ecb5ef27ecb415c387ff767852ab909f381d158496d0a3e1c1448c13387c06aeadd4ac4e3f2208092409072fda20d7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3bb2c5e76212eb4ff8de00d01fc0bfee0df746a659bd39fcc61eb088674fe9b1134f556378c327559b6f376ad4043761860b02979ddb31317990c502ce843892"} 04:36:33 INFO - Running post-run listener: copy_logs_to_upload_dir 04:36:33 INFO - Copying logs to upload dir... 04:36:33 INFO - mkdir: /builds/slave/test/build/upload/logs 04:36:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.551816 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-21 04:36:33.397706) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 04:36:33.400734) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a91fb60ba29a17654c8ad5e634c667b128051eb6514521d318398aa21ea2511a507b143764aecada0e879b1941f454571769024d1944b4c154d73b00f5fb5e59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e3bffbf96c538131472a6e8ec06259a51ecb5ef27ecb415c387ff767852ab909f381d158496d0a3e1c1448c13387c06aeadd4ac4e3f2208092409072fda20d7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3bb2c5e76212eb4ff8de00d01fc0bfee0df746a659bd39fcc61eb088674fe9b1134f556378c327559b6f376ad4043761860b02979ddb31317990c502ce843892"} build_url:https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011414 build_url: 'https://queue.taskcluster.net/v1/task/UTA_XSR8QZWITG-yR4Soag/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/Mozilla-Inbound/sha512/a91fb60ba29a17654c8ad5e634c667b128051eb6514521d318398aa21ea2511a507b143764aecada0e879b1941f454571769024d1944b4c154d73b00f5fb5e59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e3bffbf96c538131472a6e8ec06259a51ecb5ef27ecb415c387ff767852ab909f381d158496d0a3e1c1448c13387c06aeadd4ac4e3f2208092409072fda20d7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3bb2c5e76212eb4ff8de00d01fc0bfee0df746a659bd39fcc61eb088674fe9b1134f556378c327559b6f376ad4043761860b02979ddb31317990c502ce843892"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 04:36:33.425689) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 04:36:33.426007) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469099744.825073-1418551996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004400 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 04:36:33.606078) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 04:36:33.606405) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 04:36:33.606739) ========= ========= Total master_lag: 0.25 =========