builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-068 starttime: 1468593364.61 results: success (0) buildid: 20160713163005 builduid: 4e27467b6b114389b436cd7afdc62c4a revision: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:04.612564) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:04.613011) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:04.613420) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:04.634121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:04.634389) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-15 07:36:04-- 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% 84.6M=0s 2016-07-15 07:36:04 (84.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109508 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:04.757443) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:04.757796) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019335 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:04.808107) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:04.808497) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5fd14a66be3191e5628af3cfb0a456dd70f179b8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5fd14a66be3191e5628af3cfb0a456dd70f179b8 --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=97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-15 07:36:04,901 truncating revision to first 12 chars 2016-07-15 07:36:04,901 Setting DEBUG logging. 2016-07-15 07:36:04,901 attempt 1/10 2016-07-15 07:36:04,901 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5fd14a66be31?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 07:36:05,212 unpacking tar archive at: mozilla-central-5fd14a66be31/testing/mozharness/ program finished with exit code 0 elapsedTime=0.673087 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:05.666892) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:05.667163) ========= script_repo_revision: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:05.667527) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:05.667888) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 07:36:06.017828) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 48 secs) (at 2016-07-15 07:36:06.018089) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:36:06 INFO - MultiFileLogger online at 20160715 07:36:06 in /builds/slave/test 07:36:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:36:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:36:06 INFO - {'append_to_log': False, 07:36:06 INFO - 'base_work_dir': '/builds/slave/test', 07:36:06 INFO - 'blob_upload_branch': 'Firefox', 07:36:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:36:06 INFO - 'branch': 'Firefox', 07:36:06 INFO - 'buildbot_json_path': 'buildprops.json', 07:36:06 INFO - 'config_files': ('talos/linux_config.py',), 07:36:06 INFO - 'default_actions': ('clobber', 07:36:06 INFO - 'read-buildbot-config', 07:36:06 INFO - 'download-and-extract', 07:36:06 INFO - 'populate-webroot', 07:36:06 INFO - 'create-virtualenv', 07:36:06 INFO - 'install', 07:36:06 INFO - 'run-tests'), 07:36:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:36:06 INFO - 'download_minidump_stackwalk': True, 07:36:06 INFO - 'download_symbols': 'ondemand', 07:36:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:36:06 INFO - 'tooltool.py': '/tools/tooltool.py', 07:36:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:36:06 INFO - '/tools/misc-python/virtualenv.py')}, 07:36:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:36:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:36:06 INFO - 'installer_path': 'installer.exe', 07:36:06 INFO - 'log_level': 'info', 07:36:06 INFO - 'log_name': 'talos', 07:36:06 INFO - 'log_to_console': True, 07:36:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:36:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:36:06 INFO - 'opt_config_files': (), 07:36:06 INFO - 'pip_index': False, 07:36:06 INFO - 'sps_profile': False, 07:36:06 INFO - 'sps_profile_interval': 0, 07:36:06 INFO - 'suite': 'dromaeojs', 07:36:06 INFO - 'system_bits': '32', 07:36:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:36:06 INFO - 'title': 'talos-linux64-ix-068', 07:36:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:36:06 INFO - 'use_talos_json': True, 07:36:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:36:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:36:06 INFO - 'work_dir': 'build'} 07:36:06 INFO - ##### 07:36:06 INFO - ##### Running clobber step. 07:36:06 INFO - ##### 07:36:06 INFO - Running pre-action listener: _resource_record_pre_action 07:36:06 INFO - Running main action method: clobber 07:36:06 INFO - rmtree: /builds/slave/test/build 07:36:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:36:06 INFO - Running post-action listener: _resource_record_post_action 07:36:06 INFO - ##### 07:36:06 INFO - ##### Finished clobber step (success) 07:36:06 INFO - ##### 07:36:06 INFO - ##### 07:36:06 INFO - ##### Running read-buildbot-config step. 07:36:06 INFO - ##### 07:36:06 INFO - Running pre-action listener: _resource_record_pre_action 07:36:06 INFO - Running main action method: read_buildbot_config 07:36:06 INFO - Using buildbot properties: 07:36:06 INFO - { 07:36:06 INFO - "project": "", 07:36:06 INFO - "product": "firefox", 07:36:06 INFO - "script_repo_revision": "production", 07:36:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:36:06 INFO - "repository": "", 07:36:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:36:06 INFO - "buildid": "20160713163005", 07:36:06 INFO - "pgo_build": "True", 07:36:06 INFO - "basedir": "/builds/slave/test", 07:36:06 INFO - "buildnumber": 394, 07:36:06 INFO - "slavename": "talos-linux64-ix-068", 07:36:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:36:06 INFO - "platform": "ubuntu64_hw", 07:36:06 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 07:36:06 INFO - "branch": "mozilla-central", 07:36:06 INFO - "repo_path": "mozilla-central", 07:36:06 INFO - "revision": "5fd14a66be3191e5628af3cfb0a456dd70f179b8", 07:36:06 INFO - "stage_platform": "linux64-pgo", 07:36:06 INFO - "builduid": "4e27467b6b114389b436cd7afdc62c4a", 07:36:06 INFO - "slavebuilddir": "test" 07:36:06 INFO - } 07:36:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 07:36:06 INFO - Running post-action listener: _resource_record_post_action 07:36:06 INFO - ##### 07:36:06 INFO - ##### Finished read-buildbot-config step (success) 07:36:06 INFO - ##### 07:36:06 INFO - ##### 07:36:06 INFO - ##### Running download-and-extract step. 07:36:06 INFO - ##### 07:36:06 INFO - Running pre-action listener: _resource_record_pre_action 07:36:06 INFO - Running main action method: download_and_extract 07:36:06 INFO - mkdir: /builds/slave/test/build/tests 07:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:36:06 INFO - https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:36:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:36:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:36:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 07:36:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 07:36:19 INFO - Downloaded 1361 bytes. 07:36:19 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:36:19 INFO - Using the following test package requirements: 07:36:19 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:36:19 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:36:19 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:36:19 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:19 INFO - u'jsshell-linux-x86_64.zip'], 07:36:19 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:36:19 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:36:19 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:36:19 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:36:19 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:36:19 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:36:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:36:19 INFO - https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:36:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:36:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:36:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 07:36:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 07:36:21 INFO - Downloaded 24326108 bytes. 07:36:21 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'] 07:36:21 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 07:36:23 INFO - Return code: 0 07:36:23 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 07:36:23 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')]} 07:36:23 INFO - https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:36:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:36:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:36:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 07:36:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 07:36:53 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip: timed out 07:36:53 INFO - retry: attempt #1 caught exception: timed out 07:36:53 INFO - retry: Failed, sleeping 30 seconds before retrying 07:37:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 #2 07:37:24 INFO - Downloaded 24326108 bytes. 07:37:24 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'] 07:37:24 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 07:37:25 INFO - Return code: 0 07:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:37:25 INFO - https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:37:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:37:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:37:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 07:37:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 07:37:26 INFO - Downloaded 14403317 bytes. 07:37:26 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'] 07:37:26 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 07:37:26 INFO - Return code: 0 07:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:37:26 INFO - https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:37:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:37:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:37:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 07:37:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AeKnLAWLTt2AXK4wg48S7w/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 07:37:30 INFO - Downloaded 60811395 bytes. 07:37:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:37:30 INFO - mkdir: /builds/slave/test/properties 07:37:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:37:30 INFO - Writing to file /builds/slave/test/properties/build_url 07:37:30 INFO - Contents: 07:37:30 INFO - build_url:https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:37:31 INFO - Running post-action listener: _resource_record_post_action 07:37:31 INFO - Running post-action listener: set_extra_try_arguments 07:37:31 INFO - ##### 07:37:31 INFO - ##### Finished download-and-extract step (success) 07:37:31 INFO - ##### 07:37:31 INFO - ##### 07:37:31 INFO - ##### Running populate-webroot step. 07:37:31 INFO - ##### 07:37:31 INFO - Running pre-action listener: _resource_record_pre_action 07:37:31 INFO - Running main action method: populate_webroot 07:37:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:37:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:37:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:37:31 INFO - '--tppagecycles', 07:37:31 INFO - '7'], 07:37:31 INFO - 'tests': ['tsvgm']}}, 07:37:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:37:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:37:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:37:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:37:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:31 INFO - 'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:37:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:37:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:31 INFO - 'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['damp', 'tps']}, 07:37:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:31 INFO - 'tests': ['damp', 'tps']}, 07:37:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['dromaeo_dom']}, 07:37:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:37:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['basic_compositor_video']}, 07:37:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:37:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['a11yr', 07:37:31 INFO - 'ts_paint', 07:37:31 INFO - 'tpaint', 07:37:31 INFO - 'sessionrestore', 07:37:31 INFO - 'sessionrestore_no_auto_restore', 07:37:31 INFO - 'tabpaint']}, 07:37:31 INFO - 'other-e10s': {'tests': ['a11yr', 07:37:31 INFO - 'ts_paint', 07:37:31 INFO - 'tpaint', 07:37:31 INFO - 'sessionrestore', 07:37:31 INFO - 'sessionrestore_no_auto_restore', 07:37:31 INFO - 'tabpaint']}, 07:37:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:37:31 INFO - 'ts_paint', 07:37:31 INFO - 'tpaint', 07:37:31 INFO - 'sessionrestore', 07:37:31 INFO - 'sessionrestore_no_auto_restore', 07:37:31 INFO - 'tabpaint']}, 07:37:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:37:31 INFO - 'ts_paint', 07:37:31 INFO - 'tpaint', 07:37:31 INFO - 'sessionrestore', 07:37:31 INFO - 'sessionrestore_no_auto_restore', 07:37:31 INFO - 'tabpaint']}, 07:37:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['a11yr', 07:37:31 INFO - 'ts_paint', 07:37:31 INFO - 'tpaint', 07:37:31 INFO - 'sessionrestore', 07:37:31 INFO - 'sessionrestore_no_auto_restore', 07:37:31 INFO - 'tabpaint']}, 07:37:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['a11yr', 07:37:31 INFO - 'ts_paint', 07:37:31 INFO - 'tpaint', 07:37:31 INFO - 'sessionrestore', 07:37:31 INFO - 'sessionrestore_no_auto_restore', 07:37:31 INFO - 'tabpaint']}, 07:37:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['tsvgx', 07:37:31 INFO - 'tsvgr_opacity', 07:37:31 INFO - 'tart', 07:37:31 INFO - 'tscrollx', 07:37:31 INFO - 'cart']}, 07:37:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:37:31 INFO - 'tsvgr_opacity', 07:37:31 INFO - 'tart', 07:37:31 INFO - 'tscrollx', 07:37:31 INFO - 'cart']}, 07:37:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:31 INFO - 'talos_options': ['--disable-e10s'], 07:37:31 INFO - 'tests': ['tp5o']}, 07:37:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:31 INFO - 'tests': ['tp5o']}, 07:37:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:31 INFO - 'talos_options': ['--disable-e10s', 07:37:31 INFO - '--xperf_path', 07:37:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:37:31 INFO - 'tests': ['tp5n']}, 07:37:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:31 INFO - 'talos_options': ['--xperf_path', 07:37:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:37:31 INFO - 'tests': ['tp5n']}}, 07:37:31 INFO - 'talos.zip': {'path': '', 07:37:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:37:31 INFO - Running post-action listener: _resource_record_post_action 07:37:31 INFO - ##### 07:37:31 INFO - ##### Finished populate-webroot step (success) 07:37:31 INFO - ##### 07:37:31 INFO - ##### 07:37:31 INFO - ##### Running create-virtualenv step. 07:37:31 INFO - ##### 07:37:31 INFO - Running pre-action listener: _resource_record_pre_action 07:37:31 INFO - Running main action method: create_virtualenv 07:37:31 INFO - Creating virtualenv /builds/slave/test/build/venv 07:37:31 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 07:37:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:37:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:37:31 INFO - Using real prefix '/usr' 07:37:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:37:31 INFO - Installing distribute.............................................................................................................................................................................................done. 07:37:33 INFO - Installing pip.................done. 07:37:33 INFO - Return code: 0 07:37:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:37:33 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')]} 07:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:33 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')]} 07:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:33 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 0xcb2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf73180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf713b0>, '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 0xf722b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', '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 07:37:33 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 07:37:33 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 07:37:33 INFO - Using env: {'DISPLAY': ':0', 07:37:33 INFO - 'HOME': '/home/cltbld', 07:37:33 INFO - 'LANG': 'en_US.UTF-8', 07:37:33 INFO - 'LANGUAGE': 'en_US:en', 07:37:33 INFO - 'LOGNAME': 'cltbld', 07:37:33 INFO - 'MAIL': '/var/mail/cltbld', 07:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:33 INFO - 'MOZ_NO_REMOTE': '1', 07:37:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:33 INFO - 'NO_EM_RESTART': '1', 07:37:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:33 INFO - 'PWD': '/builds/slave/test', 07:37:33 INFO - 'SHELL': '/bin/bash', 07:37:33 INFO - 'SHLVL': '1', 07:37:33 INFO - 'TERM': 'linux', 07:37:33 INFO - 'TMOUT': '86400', 07:37:33 INFO - 'USER': 'cltbld', 07:37:33 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', 07:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:33 INFO - '_': '/tools/buildbot/bin/python'} 07:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:33 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 07:37:33 INFO - Cleaning up... 07:37:33 INFO - Return code: 0 07:37:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:37:33 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')]} 07:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:33 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')]} 07:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:33 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 0xcb2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf73180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf713b0>, '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 0xf722b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', '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 07:37:33 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 07:37:33 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 07:37:33 INFO - Using env: {'DISPLAY': ':0', 07:37:33 INFO - 'HOME': '/home/cltbld', 07:37:33 INFO - 'LANG': 'en_US.UTF-8', 07:37:33 INFO - 'LANGUAGE': 'en_US:en', 07:37:33 INFO - 'LOGNAME': 'cltbld', 07:37:33 INFO - 'MAIL': '/var/mail/cltbld', 07:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:33 INFO - 'MOZ_NO_REMOTE': '1', 07:37:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:33 INFO - 'NO_EM_RESTART': '1', 07:37:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:33 INFO - 'PWD': '/builds/slave/test', 07:37:33 INFO - 'SHELL': '/bin/bash', 07:37:33 INFO - 'SHLVL': '1', 07:37:33 INFO - 'TERM': 'linux', 07:37:33 INFO - 'TMOUT': '86400', 07:37:33 INFO - 'USER': 'cltbld', 07:37:33 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', 07:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:33 INFO - '_': '/tools/buildbot/bin/python'} 07:37:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:34 INFO - Downloading/unpacking psutil>=3.1.1 07:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:37:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:37:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:37:36 INFO - Installing collected packages: psutil 07:37:36 INFO - Running setup.py install for psutil 07:37:36 INFO - building 'psutil._psutil_linux' extension 07:37:36 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 07:37:36 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 07:37:36 INFO - building 'psutil._psutil_posix' extension 07:37:36 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 07:37:36 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 07:37:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:37:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:37:36 INFO - Successfully installed psutil 07:37:36 INFO - Cleaning up... 07:37:36 INFO - Return code: 0 07:37:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:37: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')]} 07:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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')]} 07:37:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcb2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf73180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf713b0>, '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 0xf722b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', '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 07:37:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:37:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:37:36 INFO - Using env: {'DISPLAY': ':0', 07:37:36 INFO - 'HOME': '/home/cltbld', 07:37:36 INFO - 'LANG': 'en_US.UTF-8', 07:37:36 INFO - 'LANGUAGE': 'en_US:en', 07:37:36 INFO - 'LOGNAME': 'cltbld', 07:37:36 INFO - 'MAIL': '/var/mail/cltbld', 07:37:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:36 INFO - 'MOZ_NO_REMOTE': '1', 07:37:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:36 INFO - 'NO_EM_RESTART': '1', 07:37:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:36 INFO - 'PWD': '/builds/slave/test', 07:37:36 INFO - 'SHELL': '/bin/bash', 07:37:36 INFO - 'SHLVL': '1', 07:37:36 INFO - 'TERM': 'linux', 07:37:36 INFO - 'TMOUT': '86400', 07:37:36 INFO - 'USER': 'cltbld', 07:37:36 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', 07:37:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:36 INFO - '_': '/tools/buildbot/bin/python'} 07:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:37:38 INFO - Installing collected packages: mozsystemmonitor 07:37:38 INFO - Running setup.py install for mozsystemmonitor 07:37:39 INFO - Successfully installed mozsystemmonitor 07:37:39 INFO - Cleaning up... 07:37:39 INFO - Return code: 0 07:37:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:37:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xcb2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf73180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf713b0>, '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 0xf722b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', '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 07:37:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:37:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:37:39 INFO - Using env: {'DISPLAY': ':0', 07:37:39 INFO - 'HOME': '/home/cltbld', 07:37:39 INFO - 'LANG': 'en_US.UTF-8', 07:37:39 INFO - 'LANGUAGE': 'en_US:en', 07:37:39 INFO - 'LOGNAME': 'cltbld', 07:37:39 INFO - 'MAIL': '/var/mail/cltbld', 07:37:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:39 INFO - 'MOZ_NO_REMOTE': '1', 07:37:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:39 INFO - 'NO_EM_RESTART': '1', 07:37:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:39 INFO - 'PWD': '/builds/slave/test', 07:37:39 INFO - 'SHELL': '/bin/bash', 07:37:39 INFO - 'SHLVL': '1', 07:37:39 INFO - 'TERM': 'linux', 07:37:39 INFO - 'TMOUT': '86400', 07:37:39 INFO - 'USER': 'cltbld', 07:37:39 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', 07:37:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:39 INFO - '_': '/tools/buildbot/bin/python'} 07:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:39 INFO - Downloading/unpacking jsonschema==2.5.1 07:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:37:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:41 INFO - Downloading functools32-3.2.3-2.tar.gz 07:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:37:41 INFO - warning: no files found matching '*.txt' 07:37:41 INFO - no previously-included directories found matching 'build' 07:37:41 INFO - no previously-included directories found matching 'dist' 07:37:41 INFO - no previously-included directories found matching '.git*' 07:37:41 INFO - Installing collected packages: jsonschema, functools32 07:37:41 INFO - Running setup.py install for functools32 07:37:41 INFO - warning: no files found matching '*.txt' 07:37:41 INFO - no previously-included directories found matching 'build' 07:37:41 INFO - no previously-included directories found matching 'dist' 07:37:41 INFO - no previously-included directories found matching '.git*' 07:37:41 INFO - Successfully installed jsonschema functools32 07:37:41 INFO - Cleaning up... 07:37:41 INFO - Return code: 0 07:37:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:37:41 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')]} 07:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:41 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')]} 07:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:41 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 0xcb2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf73180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf713b0>, '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 0xf722b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', '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 07:37:41 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 07:37:41 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 07:37:41 INFO - Using env: {'DISPLAY': ':0', 07:37:41 INFO - 'HOME': '/home/cltbld', 07:37:41 INFO - 'LANG': 'en_US.UTF-8', 07:37:41 INFO - 'LANGUAGE': 'en_US:en', 07:37:41 INFO - 'LOGNAME': 'cltbld', 07:37:41 INFO - 'MAIL': '/var/mail/cltbld', 07:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:41 INFO - 'MOZ_NO_REMOTE': '1', 07:37:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:41 INFO - 'NO_EM_RESTART': '1', 07:37:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:41 INFO - 'PWD': '/builds/slave/test', 07:37:41 INFO - 'SHELL': '/bin/bash', 07:37:41 INFO - 'SHLVL': '1', 07:37:41 INFO - 'TERM': 'linux', 07:37:41 INFO - 'TMOUT': '86400', 07:37:41 INFO - 'USER': 'cltbld', 07:37:41 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', 07:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:41 INFO - '_': '/tools/buildbot/bin/python'} 07:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:41 INFO - Downloading/unpacking blobuploader==1.2.4 07:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:43 INFO - Downloading blobuploader-1.2.4.tar.gz 07:37:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:37:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:37:43 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 07:37:43 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 07:37:43 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 07:37:43 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 07:37:43 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 07:37:43 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 07:37:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:37:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:37:44 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 07:37:44 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 07:37:44 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 07:37:44 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 07:37:44 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 07:37:44 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 07:37:44 INFO - Downloading docopt-0.6.1.tar.gz 07:37:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:37:44 INFO - Installing collected packages: blobuploader, requests, docopt 07:37:44 INFO - Running setup.py install for blobuploader 07:37:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:37:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:37:44 INFO - Running setup.py install for requests 07:37:44 INFO - Running setup.py install for docopt 07:37:45 INFO - Successfully installed blobuploader requests docopt 07:37:45 INFO - Cleaning up... 07:37:45 INFO - Return code: 0 07:37:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:45 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 0xcb2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf73180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf713b0>, '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 0xf722b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', '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 07:37:45 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 07:37:45 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 07:37:45 INFO - Using env: {'DISPLAY': ':0', 07:37:45 INFO - 'HOME': '/home/cltbld', 07:37:45 INFO - 'LANG': 'en_US.UTF-8', 07:37:45 INFO - 'LANGUAGE': 'en_US:en', 07:37:45 INFO - 'LOGNAME': 'cltbld', 07:37:45 INFO - 'MAIL': '/var/mail/cltbld', 07:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:45 INFO - 'MOZ_NO_REMOTE': '1', 07:37:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:45 INFO - 'NO_EM_RESTART': '1', 07:37:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:45 INFO - 'PWD': '/builds/slave/test', 07:37:45 INFO - 'SHELL': '/bin/bash', 07:37:45 INFO - 'SHLVL': '1', 07:37:45 INFO - 'TERM': 'linux', 07:37:45 INFO - 'TMOUT': '86400', 07:37:45 INFO - 'USER': 'cltbld', 07:37:45 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', 07:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:45 INFO - '_': '/tools/buildbot/bin/python'} 07:37:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:37:45 INFO - Running setup.py (path:/tmp/pip-FdB9Wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:37:45 INFO - Running setup.py (path:/tmp/pip-qndawQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:37:45 INFO - Running setup.py (path:/tmp/pip-XkMBf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:37:45 INFO - Running setup.py (path:/tmp/pip-2CI2HX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:37:45 INFO - Running setup.py (path:/tmp/pip-Im2Y8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:37:45 INFO - Running setup.py (path:/tmp/pip-a1JLFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:37:45 INFO - Running setup.py (path:/tmp/pip-eKFu70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:37:45 INFO - Running setup.py (path:/tmp/pip-Z2Wq9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:37:45 INFO - Running setup.py (path:/tmp/pip-a2GBdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:37:45 INFO - Running setup.py (path:/tmp/pip-OLKCCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:37:46 INFO - Running setup.py (path:/tmp/pip-V_x69h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:37:46 INFO - Running setup.py (path:/tmp/pip-18yB4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:37:46 INFO - Running setup.py (path:/tmp/pip-RdA8gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:37:46 INFO - Running setup.py (path:/tmp/pip-fy48re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:46 INFO - Running setup.py (path:/tmp/pip-TRpuRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:37:46 INFO - Running setup.py (path:/tmp/pip-DAgCa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:37:46 INFO - Running setup.py (path:/tmp/pip-8QaFbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:37:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:37:46 INFO - Running setup.py install for manifestparser 07:37:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:37:46 INFO - Running setup.py install for mozcrash 07:37:46 INFO - Running setup.py install for mozdebug 07:37:46 INFO - Running setup.py install for mozdevice 07:37:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Running setup.py install for mozfile 07:37:47 INFO - Running setup.py install for mozhttpd 07:37:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Running setup.py install for mozinfo 07:37:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Running setup.py install for mozInstall 07:37:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Running setup.py install for mozleak 07:37:47 INFO - Running setup.py install for mozlog 07:37:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Running setup.py install for moznetwork 07:37:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Running setup.py install for mozprocess 07:37:47 INFO - Running setup.py install for mozprofile 07:37:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:37:47 INFO - Running setup.py install for mozrunner 07:37:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:37:48 INFO - Running setup.py install for mozscreenshot 07:37:48 INFO - Running setup.py install for moztest 07:37:48 INFO - Running setup.py install for mozversion 07:37:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:37:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:37:48 INFO - Cleaning up... 07:37:48 INFO - Return code: 0 07:37:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37: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')]} 07:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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')]} 07:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:48 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 0xcb2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf73180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf713b0>, '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 0xf722b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', '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 07:37:48 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 07:37:48 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 07:37:48 INFO - Using env: {'DISPLAY': ':0', 07:37:48 INFO - 'HOME': '/home/cltbld', 07:37:48 INFO - 'LANG': 'en_US.UTF-8', 07:37:48 INFO - 'LANGUAGE': 'en_US:en', 07:37:48 INFO - 'LOGNAME': 'cltbld', 07:37:48 INFO - 'MAIL': '/var/mail/cltbld', 07:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:48 INFO - 'MOZ_NO_REMOTE': '1', 07:37:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:48 INFO - 'NO_EM_RESTART': '1', 07:37:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:48 INFO - 'PWD': '/builds/slave/test', 07:37:48 INFO - 'SHELL': '/bin/bash', 07:37:48 INFO - 'SHLVL': '1', 07:37:48 INFO - 'TERM': 'linux', 07:37:48 INFO - 'TMOUT': '86400', 07:37:48 INFO - 'USER': 'cltbld', 07:37:48 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', 07:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:48 INFO - '_': '/tools/buildbot/bin/python'} 07:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:37:48 INFO - Running setup.py (path:/tmp/pip-7kDOnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:37:48 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)) 07:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:37:48 INFO - Running setup.py (path:/tmp/pip-tAPZir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:37:48 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)) 07:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:37:48 INFO - Running setup.py (path:/tmp/pip-OdnpTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:37:48 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)) 07:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:37:48 INFO - Running setup.py (path:/tmp/pip-txIacl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:37:48 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)) 07:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:37:48 INFO - Running setup.py (path:/tmp/pip-twabPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:37:48 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)) 07:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:37:48 INFO - Running setup.py (path:/tmp/pip-a5kGO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:37:49 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)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:37:49 INFO - Running setup.py (path:/tmp/pip-Dy8X0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:37:49 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)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:37:49 INFO - Running setup.py (path:/tmp/pip-7Yw2GU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:37:49 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)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:37:49 INFO - Running setup.py (path:/tmp/pip-CR3hbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:37:49 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)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:37:49 INFO - Running setup.py (path:/tmp/pip-DVUmBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:37:49 INFO - Running setup.py (path:/tmp/pip-lk2blu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:37:49 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)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:37:49 INFO - Running setup.py (path:/tmp/pip-EZ_QCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:37:49 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)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:37:49 INFO - Running setup.py (path:/tmp/pip-MeRC8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:37:49 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)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:37:49 INFO - Running setup.py (path:/tmp/pip-ZzoU9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:37:49 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)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:49 INFO - Running setup.py (path:/tmp/pip-I3JMr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:49 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)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:37:49 INFO - Running setup.py (path:/tmp/pip-7zboiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:37:49 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)) 07:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:37:49 INFO - Running setup.py (path:/tmp/pip-FsBchK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:37:49 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)) 07:37:49 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)) 07:37:49 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)) 07:37:49 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)) 07:37:49 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)) 07:37:49 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)) 07:37:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:37:49 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 07:37:49 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 07:37:49 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 07:37:49 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 07:37:49 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 07:37:49 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 07:37:51 INFO - Downloading blessings-1.6.tar.gz 07:37:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:37:51 INFO - Installing collected packages: blessings 07:37:51 INFO - Running setup.py install for blessings 07:37:51 INFO - Successfully installed blessings 07:37:51 INFO - Cleaning up... 07:37:52 INFO - Return code: 0 07:37:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:37:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:37:52 INFO - Reading from file tmpfile_stdout 07:37:52 INFO - Current package versions: 07:37:52 INFO - argparse == 1.2.1 07:37:52 INFO - blessings == 1.6 07:37:52 INFO - blobuploader == 1.2.4 07:37:52 INFO - docopt == 0.6.1 07:37:52 INFO - functools32 == 3.2.3-2 07:37:52 INFO - jsonschema == 2.5.1 07:37:52 INFO - manifestparser == 1.1 07:37:52 INFO - mozInstall == 1.12 07:37:52 INFO - mozcrash == 0.17 07:37:52 INFO - mozdebug == 0.1 07:37:52 INFO - mozdevice == 0.48 07:37:52 INFO - mozfile == 1.2 07:37:52 INFO - mozhttpd == 0.7 07:37:52 INFO - mozinfo == 0.9 07:37:52 INFO - mozleak == 0.1 07:37:52 INFO - mozlog == 3.2 07:37:52 INFO - moznetwork == 0.27 07:37:52 INFO - mozprocess == 0.23 07:37:52 INFO - mozprofile == 0.28 07:37:52 INFO - mozrunner == 6.12 07:37:52 INFO - mozscreenshot == 0.1 07:37:52 INFO - mozsystemmonitor == 0.3 07:37:52 INFO - moztest == 0.7 07:37:52 INFO - mozversion == 1.4 07:37:52 INFO - psutil == 3.1.1 07:37:52 INFO - requests == 1.2.3 07:37:52 INFO - wsgiref == 0.1.2 07:37:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37:52 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')]} 07:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:52 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')]} 07:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:52 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 0xcb2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf73180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf713b0>, '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 0xf722b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', '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 07:37:52 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 07:37:52 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 07:37:52 INFO - Using env: {'DISPLAY': ':0', 07:37:52 INFO - 'HOME': '/home/cltbld', 07:37:52 INFO - 'LANG': 'en_US.UTF-8', 07:37:52 INFO - 'LANGUAGE': 'en_US:en', 07:37:52 INFO - 'LOGNAME': 'cltbld', 07:37:52 INFO - 'MAIL': '/var/mail/cltbld', 07:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:52 INFO - 'MOZ_NO_REMOTE': '1', 07:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:52 INFO - 'NO_EM_RESTART': '1', 07:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:52 INFO - 'PWD': '/builds/slave/test', 07:37:52 INFO - 'SHELL': '/bin/bash', 07:37:52 INFO - 'SHLVL': '1', 07:37:52 INFO - 'TERM': 'linux', 07:37:52 INFO - 'TMOUT': '86400', 07:37:52 INFO - 'USER': 'cltbld', 07:37:52 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', 07:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:52 INFO - '_': '/tools/buildbot/bin/python'} 07:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:52 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)) 07:37:52 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)) 07:37:52 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)) 07:37:52 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)) 07:37:52 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)) 07:37:52 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)) 07:37:52 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)) 07:37:52 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)) 07:37:52 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)) 07:37:52 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)) 07:37:52 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)) 07:37:52 INFO - Cleaning up... 07:37:52 INFO - Return code: 0 07:37:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:37:52 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')]} 07:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:52 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')]} 07:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:52 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 0xcb2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf73180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf713b0>, '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 0xf722b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', '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 07:37:52 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 07:37:52 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 07:37:52 INFO - Using env: {'DISPLAY': ':0', 07:37:52 INFO - 'HOME': '/home/cltbld', 07:37:52 INFO - 'LANG': 'en_US.UTF-8', 07:37:52 INFO - 'LANGUAGE': 'en_US:en', 07:37:52 INFO - 'LOGNAME': 'cltbld', 07:37:52 INFO - 'MAIL': '/var/mail/cltbld', 07:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:52 INFO - 'MOZ_NO_REMOTE': '1', 07:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:52 INFO - 'NO_EM_RESTART': '1', 07:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:52 INFO - 'PWD': '/builds/slave/test', 07:37:52 INFO - 'SHELL': '/bin/bash', 07:37:52 INFO - 'SHLVL': '1', 07:37:52 INFO - 'TERM': 'linux', 07:37:52 INFO - 'TMOUT': '86400', 07:37:52 INFO - 'USER': 'cltbld', 07:37:52 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', 07:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:52 INFO - '_': '/tools/buildbot/bin/python'} 07:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:37:52 INFO - Cleaning up... 07:37:52 INFO - Return code: 0 07:37:52 INFO - Running post-action listener: _resource_record_post_action 07:37:52 INFO - Running post-action listener: _start_resource_monitoring 07:37:52 INFO - Starting resource monitoring. 07:37:52 INFO - ##### 07:37:52 INFO - ##### Finished create-virtualenv step (success) 07:37:52 INFO - ##### 07:37:52 INFO - ##### 07:37:52 INFO - ##### Running install step. 07:37:52 INFO - ##### 07:37:52 INFO - Running pre-action listener: _resource_record_pre_action 07:37:52 INFO - Running main action method: install 07:37:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:37:52 INFO - Reading from file tmpfile_stdout 07:37:52 INFO - Detecting whether we're running mozinstall >=1.0... 07:37:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:37:53 INFO - Reading from file tmpfile_stdout 07:37:53 INFO - Output received: 07:37:53 INFO - Usage: mozinstall [options] installer 07:37:53 INFO - Options: 07:37:53 INFO - -h, --help show this help message and exit 07:37:53 INFO - -d DEST, --destination=DEST 07:37:53 INFO - Directory to install application into. [default: 07:37:53 INFO - "/builds/slave/test"] 07:37:53 INFO - --app=APP Application being installed. [default: firefox] 07:37:53 INFO - mkdir: /builds/slave/test/build/application 07:37:53 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'] 07:37:53 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 07:38:10 INFO - Reading from file tmpfile_stdout 07:38:10 INFO - Output received: 07:38:10 INFO - /builds/slave/test/build/application/firefox/firefox 07:38:10 INFO - Running post-action listener: _resource_record_post_action 07:38:10 INFO - ##### 07:38:10 INFO - ##### Finished install step (success) 07:38:10 INFO - ##### 07:38:10 INFO - ##### 07:38:10 INFO - ##### Running run-tests step. 07:38:10 INFO - ##### 07:38:10 INFO - Running pre-action listener: _resource_record_pre_action 07:38:10 INFO - Running main action method: run_tests 07:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:38:10 INFO - Python 2.7.3 07:38:10 INFO - Return code: 0 07:38:10 INFO - grabbing minidump binary from tooltool 07:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:10 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 0xf713b0>, '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 0xf722b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf72740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:38:10 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 07:38:10 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 07:38:10 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 07:38:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:38:10 INFO - Return code: 0 07:38:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:38:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:38:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:38:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:38:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 07:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 07:38:10 INFO - Using env: {'DISPLAY': ':0', 07:38:10 INFO - 'HOME': '/home/cltbld', 07:38:10 INFO - 'LANG': 'en_US.UTF-8', 07:38:10 INFO - 'LANGUAGE': 'en_US:en', 07:38:10 INFO - 'LOGNAME': 'cltbld', 07:38:10 INFO - 'MAIL': '/var/mail/cltbld', 07:38:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:38:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:10 INFO - 'MOZ_NO_REMOTE': '1', 07:38:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:10 INFO - 'NO_EM_RESTART': '1', 07:38:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:10 INFO - 'PWD': '/builds/slave/test', 07:38:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:38:10 INFO - 'SHELL': '/bin/bash', 07:38:10 INFO - 'SHLVL': '1', 07:38:10 INFO - 'TERM': 'linux', 07:38:10 INFO - 'TMOUT': '86400', 07:38:10 INFO - 'USER': 'cltbld', 07:38:10 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239', 07:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:10 INFO - '_': '/tools/buildbot/bin/python'} 07:38:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 07:38:10 INFO - mozversion application_buildid: 20160713163005 07:38:10 INFO - mozversion application_changeset: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 07:38:10 INFO - mozversion application_display_name: Nightly 07:38:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:38:10 INFO - mozversion application_name: Firefox 07:38:10 INFO - mozversion application_remotingname: firefox 07:38:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:38:10 INFO - mozversion application_vendor: Mozilla 07:38:10 INFO - mozversion application_version: 50.0a1 07:38:10 INFO - mozversion platform_buildid: 20160713163005 07:38:10 INFO - mozversion platform_changeset: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 07:38:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:38:10 INFO - mozversion platform_version: 50.0a1 07:38:10 INFO - using testdate: 1468593490 07:38:10 INFO - actual date: 1468593490 07:38:10 INFO - starting webserver on 'localhost:51564' 07:38:10 INFO - SUITE-START | Running 2 tests 07:38:10 INFO - TEST-START | dromaeo_css 07:38:10 INFO - operating with platform_type : linux_ 07:38:10 INFO - Initialising browser for dromaeo_css test... 07:38:10 INFO - TEST-INFO | started process 4230 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbN4U6U/profile http://localhost:51564/getInfo.html) 07:38:14 INFO - PROCESS | 4230 | __metrics Screen width/height:1600/1200 07:38:14 INFO - PROCESS | 4230 | colorDepth:24 07:38:14 INFO - PROCESS | 4230 | Browser inner width/height: 1024/697 07:38:14 INFO - PROCESS | 4230 | __metrics 07:38:15 INFO - PROCESS | 4230 | 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" 07:38:15 INFO - PROCESS | 4230 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:38:16 INFO - TEST-INFO | 4230: exit 0 07:38:16 INFO - Browser initialized. 07:38:16 INFO - Running cycle 1/1 for dromaeo_css test... 07:38:16 INFO - TEST-INFO | started process 4315 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbN4U6U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:28 INFO - PROCESS | 4315 | RSS: Main: 157577216 07:38:28 INFO - PROCESS | 4315 | 07:39:19 INFO - PROCESS | 4315 | 1468593559980 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] 07:41:27 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:51564/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51564/tests/dromaeo/cssquery-ext.html) 07:41:28 INFO - PROCESS | 4315 | RSS: Main: 255696896 07:41:28 INFO - PROCESS | 4315 | 07:44:31 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:51564/tests/dromaeo/cssquery-ext.html (next: http://localhost:51564/tests/dromaeo/cssquery-jquery.html) 07:44:31 INFO - PROCESS | 4315 | RSS: Main: 247554048 07:44:31 INFO - PROCESS | 4315 | 07:47:33 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:51564/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51564/tests/dromaeo/cssquery-mootools.html) 07:47:34 INFO - PROCESS | 4315 | RSS: Main: 251105280 07:47:34 INFO - PROCESS | 4315 | 07:50:36 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:51564/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51564/tests/dromaeo/cssquery-prototype.html) 07:50:37 INFO - PROCESS | 4315 | RSS: Main: 233963520 07:50:37 INFO - PROCESS | 4315 | 07:53:39 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:51564/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51564/tests/dromaeo/cssquery-yui.html) 07:53:40 INFO - PROCESS | 4315 | RSS: Main: 224010240 07:53:40 INFO - PROCESS | 4315 | 07:56:42 INFO - PROCESS | 4315 | Cycle 1(1): loaded http://localhost:51564/tests/dromaeo/cssquery-yui.html (next: http://localhost:51564/tests/dromaeo/cssquery-dojo.html) 07:56:43 INFO - PROCESS | 4315 | RSS: Main: 273395712 07:56:43 INFO - PROCESS | 4315 | 07:56:43 INFO - PROCESS | 4315 | __start_tp_report 07:56:43 INFO - PROCESS | 4315 | _x_x_mozilla_page_load 07:56:43 INFO - PROCESS | 4315 | _x_x_mozilla_page_load_details 07:56:43 INFO - PROCESS | 4315 | |i|pagename|runs| 07:56:43 INFO - PROCESS | 4315 | |0;dojo.html;2998.6701462839087;3069.5395690646387;3082.753379729622;3090.9845450772755;3109.8289594072317;5286.259923610688;5352.250684904112;5376.1129413646795;5448.42791506892;5450.100733378993;5314.388845282793;5335.573154147669;5434.483724046219;5463.453654634534;5499.697516636584;4767.547083027124;4785.449673287564;4830.468648448667;4859.101066302727;4956.281339205831;4917.50824917509;4930.309756634074;4933.753312334369;4967.403911530621;4969.527894849984;4787.952120478785;4845.636577256684;4849.709017458964;4872.1473742094995;4881.633877459205;4855.271709243606;4926.310316555685;4931.778069986859;4940.283658869444;4951.158303088484;4766.356541866839;4885.975570122162;4907.803687852482;4911.9754401227765;4947.431045429749;5410.053240682904;5473.616846820724;5495.8351249462185;5502.724863756796;5508.118701007859;5799.2750906136735;5813.186153938452;5828.708564571754;5829.650220986712;5851.385604511507;5566.053770858964;5716.8856622867725;5734.025215713339;5800.9709951450395;5821.62159459638;5428.321459817523;5431.701256430855;5440.863978400572;5443.374011988632;5443.863903402446;5903.527717782567;5912.704364781744;5914.290285165807;5915.349311575723;5938.910916336258;5512.614117011851;5610.607257492018;5654.604177707521;5667.1782591524;5719.628224165416;5762.337331206881;5783.884322313615;5812.3025236971835;5815.9709201453725;5816.88366232673;5689.800856969986;5689.9146512802345;5693.459121383462;5703.914441283384;5728.169415434739;5528.695921724344;5614.747336369792;5657.547396208293;5686.090225563973;5706.771729130871;4345.413369195181;4356.041670832412;4358.3244597087505;4360.4331436913;4394.670399720034;3890.5720370759195;3894.357431023849;3896.181801821649;3944.073142811437;3957.5448823385104;5371.650842695212;5407.567394608422;5427.6472029317965;5468.699221542854;5473.8357849264585;5753.51095156908;5784.566157538202;5787.797427090031;5793.710314484259;5810.535157187415;5419.187121931742;5419.458054194549;5433.864153396196;5436.864078398072;5438.5377242934;4842.83050093245;4919.77860996256;4953.356063711766;4956.405231372259;4977.726225057584;5921.496672782861;5923.200367950271;5923.970380148072;5932.881342373128;5933.762649493972;5638.633488823213;5762.9135562966585;5765.884682306414;5768.192453056659;5772.39389864075;5885.440883116097;5950.166976623363;5962.791302304398;5962.880742385215;5965.850853728518;5695.487406133468;5713.742881570256;5717.742701578356;5718.485336319751;5725.541956643542;5588.329400471969;5668.914966275592;5813.883722325614;5824.475797178275;5833.270841144857;5570.136628822539;5606.915896261477;5621.100623900156;5658.434156584473;5660;5572.275604171432;5574.303212098488;5576.693281869536;5580.051391263576;5583.888322233452;5410.783568657367;5512.228288039758;5524.558035357242;5532.1425179097305;5574.996500629925;5674.914876276942;5720.942790572041;5779.133130030529;5780.739866705924;5786.363500015079;5553.111502159635;5597;5599.0761524349655;5602.215689803512;5604.355499117653 07:56:43 INFO - PROCESS | 4315 | |1;ext.html;569.8148101866894;599.6791716431709;612.9570930034898;618.2858798088209;626.7524327890486;32676;43661.56338436615;44582.777086114525;44716.77641611787;44755.55244447559;47255.76372118135;47659.52340476594;47686.52313476864;47720.76139619297;47754.28368574474;8161.265486106249;8986.86519702205;9129.22401595865;9132.452052876815;10708.892911070907;10554.841677374845;10946;10947.890521094767;10949.890501094967;10951.945240273788;10949.781004379907;10991.450427478634;11039.282446640982;11045.668629941114;11049.613263535777;8234.094249632535;8395.664173433055;8414.957925210396;10567.207459440497;10914.399708016055;10313.948430257875;10658.893411065868;10672.466376681135;10682.626108086257;10684.626038088629;44469;44749.552504474865;44811.77594112041;44887.102257954655;44985.55014449846;3763.8870833875026;3797.525309336333;4114.444549985774;4189.748615083075;4210.2842516772225;34104.14739631504;34121.48817767711;34239.828800856085;34657.133571660655;35661.64338356635;4510.977445112753;4551.271796512542;4569.47451043131;4617.145828021827;4666.370040044619;378205;379186;381196;383265;384789;35214.11964700903;35967.82016089955;60770.69614651898;61673.07490387648;61728.38271617316;37667.62332376696;37687.4346884797;37801.62198377981;37820.676276330196;37864.24271514554;15487.690246195012;15760.527184184493;15767.684646307009;15768.369265229492;15786.289616967264;12870;13035.283059431633;13037.934810325887;13041.478340866259;13057.608271751862;8444;8444.57777111142;8481.27909127731;8497.107803680648;8520.61657225414;3242.627097883774;3249.5613092232247;3271.2966712157;3293.4640326948593;3293.571835661349;992.1417973453056;1036.6615713240114;1064.80301144287;1070.550368845087;1121.9551217951355;33852.49221261683;34234.65765342315;34327.65672343295;34512.48231276533;34682.30635387278;1085.8425528298353;1124.2579897267765;1143.765528066761;1162.6105254739757;1178.240035177323;17753.378631747826;18272.451826445227;18716.344927927457;18729;19594.216231350587;604199;611569;614898;614997;617833;62236.68881655653;62294.688526557984;62372;62424;62463.68768156221;1270.4727538071782;1375.8830499407663;1381.9447222111205;1411.0757453867734;1414.569668553753;33496.83251583727;34797;34902.30195396129;35046.82476587601;35217.1195720109;52436.47563524316;53086;53281.733591331795;53294.73352633212;53309;421013.2645357398;423071;423535;424319;426111;25130.497390052464;27518;28389.858050709616;28459.715402845708;29860.253493662833;48635.51364486451;48692.75653621709;49355;50318.49681503138;50429.74785126051;42155.57844421516;42285.36571951362;42533;43428.565714342454;43957.56042439535;42383;42535.361969569865;42679.57320426756;43174.78412607917;43356.566434335255;40252.79873600613;40692.59307406888;42071.7896410516;42082.78958605188;42146.78926605347;36475.81762091172;37189.81405092957;37564.81217593895;37956.24087518179;38165;28819.711802882543;29039;29136.7086329134;29183;29219.415611687222 07:56:43 INFO - PROCESS | 4315 | |2;jquery.html;388.1247786242024;392.6230818414322;398.5237641018983;400.14568895408314;425.13485057907167;18660.626787464244;20117.59764804703;20344.796552034455;20367.694484582724;21412.14351425941;19977;20391.694124588143;20704;20727.792722072776;20759;5471.972640136803;5738.368779434259;5745.798897038604;5821.592488525805;5834.212381328509;5568.415316391782;5643.830685079454;5718.771249150029;5734.369219385885;5752.74112664929;5690;5714.914276285859;5750.6262092963825;5752.683602401867;5756.971215143918;5498.120300751861;5718.7426565804435;5735.426457354273;5738.655680659173;5782.248307720011;5141.845744627667;5168.2764412982215;5172.6379153459275;5189.455107213726;5189.688618682853;22215.44461388478;23767.405814854767;24065.398365041015;24660.383490412703;24705.752942470524;2442.206282958046;2469.4814089041324;2526.7220605733355;2532.227670560478;2867.641544806899;13295;13395;14888.330025148894;15344.923275383551;15592.532224033297;1966.1349006437122;2030.5126769575195;2031.6647753120717;2036.5010572409706;2071.658176400892;57517.424825752045;61840.381596183914;77019.22980770176;83564;84419.57790211009;10646;11039.227254092137;11307.208495405408;11413.144014198968;11689;14169.291535423188;14637.12177269346;15907.920460397623;15913.68172636564;15938.123403212934;7552.433567482461;7751.806204844811;8182.549959752152;8369.944970085875;8392.496450213006;7905.339878625553;7930.286274235346;7931.087924888711;8105.392095592854;8116.959415203004;4781.713097214179;4889.779959901742;4962.429320628102;4967.3294105296045;4992.279207831741;1950.1029526417724;1973.1515448357345;1978.5053736565858;1985.5234743661417;2082.68063077173;954.4702690007113;960.351762560269;977.0620204603518;1059.8304271316551;1067.7063807453012;19972.600547988957;20514.487137821376;20685.27601533939;20865.165393384093;20876.269330573356;984.6799790068113;995.7162208770466;1005.0853723111933;1014.1227837920294;1020.4336593190702;10860.728481788019;11451.88548114525;11553.364564949008;11564.768704626029;11570.826437603442;375734;377449;379938;521499;523730;101985.49007254916;104868.47565762275;105701.47149264204;106740;110010.44994774974;1018.6332920148741;1025.5333823110616;1053.167997282154;1066.1790421375497;1067.508945884886;18454.815451845847;18595.535111622317;18867.15097820574;19016.904915475334;19174.233030679174;41914.1617167661;45049.32426013678;45218.32172517349;45463.31805022861;45825.77087114543;60846;61511.69244153929;61767;63212.36787632065;63277.36722632899;8621.224089831945;8982.86525702128;9158.313126515404;9170.312226583166;9256.861147082664;13343.332833358487;13748.175109493463;13782.862171378158;13966.720665586834;14171.220582868042;11677.591284305008;12036.939815300868;12092.39538023113;12203.511859525519;12433.751324973631;11960.10299227544;12017.879821202027;12036.63890083299;12057.15599907998;12080.879191207976;11626.186166968231;12159.939200303941;12168.695782605506;12447.502099915902;12530;8815.629787318621;8928.330375221754;9035.593398297224;9106.863397049183;9150.359474836698;9215.2627789778;11167.38579378142;11249.550017999516;11586.652400428;12262.325572093621 07:56:43 INFO - PROCESS | 4315 | |3;mootools.html;1305.3799445263508;1461;1519.5289460267315;1636.3945340224118;1782.777152855893;8025.9197408025975;8451.02813176485;8476.91523084769;8661.566921653908;8676.566171691422;8402.957985210065;8607.053224145338;8614.698485553006;8618.26744726699;8622.870656940151;2012.5874195789902;2062.731844860166;2064.9690254646193;2296.2881506732924;2333.4049817296564;2343.4610039690883;2419.4919066995953;2422.6729391532094;2429.465517586128;2433.841800282985;2350.6239001759723;2358.988205058972;2379.916702915398;2380.666706661069;2395.6526303685955;2024.5242368043498;2309.2379514760087;2366.148186652804;2371.288613415977;2371.5494056129364;2367.893444794988;2388.176079252655;2393.80849532037;2401.747816479262;2402.207271600385;8625.568721563897;8640.65437382504;8667.046624871258;8669.523176225304;8678.045415004344;5014.974925125351;5107.693538387709;5127.435982041972;5128.205128205134;5131.512506311894;3367.764256502046;3453.4129198036144;3569.125564236753;3577.123604716835;3588.4437912123662;1187.584345479084;1192.5349113845607;1217.2027322104036;1236.6475554467024;1238.0900038471716;11874.762504749942;11890.940545297304;11891.405429728566;11908.702282442922;11916.702082447922;4345.543717909637;4402.537733537996;4531.3882625845745;4556.270996640522;4611.930821037688;10131.240156988255;10169.9491502542;10172.847407288897;10177.287589868638;10188.388696678223;5174.09453345663;5188.688678679291;5192.688438693615;5202.895942081137;5208.374995000624;4114.259433302034;4598.0803839232285;4688.789004494806;4802.8799280018275;4803.1114243864995;3911.8239679214503;3926.70549708773;4026.798660066985;4035.1727895781296;4039.939400908989;1246.6696325473756;1249.4877100388796;1253.3607859991287;1254.3978890132787;1273.3251376770327;787.9290863822283;970.1898914406408;994.4431118573477;1005.6882366466273;1006.094514936563;5825.7378417971295;5973.726344262416;6052.334243233241;6065.727042283108;6143.232095988002;499.41568365012296;505.66373361714784;505.6940550966612;505.815377387251;602.9336772954971;3778;3927.3323534999117;4243.178286439484;4269.060806622538;4270.316749320094;10067.59729610822;10825.296355736851;10829.891701082888;10840.837387439195;10850.29473084247;10551.94724026375;10612.946935265429;10714.732131696614;10731.570737170581;10732.356058636507;8201.589920503951;8217.95891020553;8265.95867020661;8278.8344233115;8289.0467596226;5311.44229855875;5360.839174824761;5451.182322651634;5510.1459273812625;5518.365387980273;9612.51937403113;9778.266630002921;10037.648682296085;10055.648052318134;10131.341462804894;9993.800123997626;10177.898221017991;10187.7453063674;10268.07587317145;10327.690169294932;6730.663466826737;7322.670479828314;7510.962445187739;7524.360429363565;7577.924220757722;8270.627821747916;8307.709230176915;8310.792230194053;8337.95831020841;8342.374321925758;7201.207867134715;7293.0519032525435;7558.697652093854;7716.164991085064;7734.92265077342;7247.565146091251;7376.18861925177;7411.332980031823;7487;7556.546607203585;6934.8613027740175;6992.755253566101;7024.473164512784;7034.226235114235;7038.8944165838575;5108.386993560796;5188.9740551297;5193.298904647975;5282.366116066097;5293.7353132344;4945.975270123627;5113.616478764182;5389.380221274447;5391.379991300893;5489.737360407049 07:56:43 INFO - PROCESS | 4315 | |4;prototype.html;321.3572854291417;331.4795770640096;334.3079824762742;336.7322978232305;340.86706184588013;5153.948460515398;5292.285541451903;5293;5318.680879147254;5345.278387417698;5270.288511051007;5274.498922602356;5287.339082614673;5295.629305948585;5314.441983591725;4173.1445053764055;4179.519355274145;4183.477065366829;4188;4192.308269135589;4023.1752490739327;4174.4953028183;4180.414741936132;4185.476815398075;4225.556316586761;4121.649659778923;4137.503499580054;4173.457450531442;4176.640198287972;4178.498580170384;4131.338985762263;4144.544100148996;4146.91706165875;4147;4150.543440221573;3709.462127991453;3710.5361829771277;3713.610070942539;3714.4799728038097;3715.1826598148364;4937.407511098655;5145.76844042019;5161.793528258865;5177.922331165036;5179.2749015137915;4670.346151538787;4673.929891051637;4684.906301873977;4692.545310953616;4698.154332220199;5263.184206448007;5459.945400545984;5464.26232458621;5492.368377636543;5493.752781124859;3903.277893589695;3908.863189788344;3909.179072394772;3913.863014794497;3917.334053210961;5379.623426360156;5521.392646808888;5649.802256920988;5704.885902281972;5710.629448932268;4028.798560071985;4048;4053.5541090480024;4073.6944729145434;4084.244414783275;4120.690948178899;4121.567235440296;4125.29869922114;4126.154138401621;4135.58644135584;4458;4590.150822097922;4688.578027977498;4706.599939005155;4707.952920470751;5519.420460851633;5522.585806064561;5526;5533.170024496278;5538.144721518256;1382.5483242595435;1394.6443656867636;1395.6231817409446;1396.882494004792;1407.8873690104767;891.8840550728364;912.6577533424966;936.2229349639782;943.1558754914313;944.730751735752;4686.95313046872;4760.309755085563;4787.2101103318255;4791.257355109963;4797.424309082932;5004.824831130893;5010.423801262828;5021.715119620564;5029.949700503022;5041.294218809369;4677.76611169447;4681.882952926136;4733.928991065137;4736.881577960578;4977.552020318117;3019.230096325423;3031.590735250757;3031.787774855783;3041.574179614856;3044.254157731326;5820.6612479130035;5838.970805145947;5846.327672317738;5852.502537284378;5876.559258055578;5611.719414029364;5822.70886455684;5833.191710569737;5843.5325173985975;5847.152162936351;5773.5958482906635;5810.738516766755;5815.73829177688;5831.591788574759;5839.532837373;5422.370150477094;5657;5658.405867383865;5684.976704193285;5692.715364231854;5675.858103547444;5690.715464226855;5702.7474147712255;5709.658230315789;5725.198472213977;5593.657522194725;5759.712014399347;5888.263967004124;5909.438603332676;5951.685028354408;4168.7290326128705;4216.894577635461;4224.3663450481445;4224.788760562021;4239.42767726353;5594.300712410949;5656.151577263443;5688.54491640676;5706.4864162225595;5731.111677689964;5487.5609951203005;5504.733911200527;5514.972425137848;5526.723663816873;5530.198121272473;5505.256790333254;5516.834494965157;5527.143292789624;5527.364353099445;5531.7234138293725;5223.895522089613;5480.725963701879;5489.149181876815;5521.5306698931045;5532.280803495681;5567.137093750475;5581.6930068846605;5593.300837395325;5618.52242559387;5618.634788738719;5445.319335083114;5459.416769137065;5466.91799623014;5468.179773033918;5479.506844383865 07:56:43 INFO - PROCESS | 4315 | |5;yui.html;786.7482405630201;837.447284792037;839.118925128615;842.397685654757;865.852805023146;14176.716465670681;14931.178785166812;14953;15005.174715390649;15021.24893755312;11667.24162929411;13883.375248113834;13922.443102275922;13928.79106813396;13945.163290202596;780.3912947900633;824.6989848705209;824.9463784853996;825.686239229092;826.1490664615455;824.2828738997081;824.8597738384489;826.557791581504;831.3432388413146;832.7085520067958;699.545295557889;838.7651457591884;872.7469033980138;877.8814859993882;880.0803160697087;854.3378881366929;869.5956380283167;871.847426700325;871.8735783311384;873.0876234335135;631.0565704272143;631.8736252749469;635.4376376906425;636.0681601453846;636.3858876184504;15414.383424663;15450.227488625524;15455.072695638186;15468.613284667861;15489;14710.264486775619;15071.171085590287;15107.54677359681;15238.695226095528;15249.466268680542;13204.471821127143;13241.470341186341;13251;13335.133216341004;13366.06437549372;4469.7318160910445;4512.481064677571;4532.818687252506;4554.772261386951;4588.449386073659;82621.17378826255;83248;83282.16717832866;83423.16576834275;84405.57797211036;11658.70853228676;11857.762844743229;11885.702857428634;11966.940165299118;12129.393530323448;22698.092076317094;22731.009617765707;22830.885845570665;22863.771362286163;22912.427189320068;8904.688335908211;8926.910730892738;8977.730668079968;8985.46087234768;9163.541822908828;4495.190865644216;4525.977370113128;4546.727196368229;4552.499225085238;4612.077584483117;3382.458806590934;3465.6707612776745;3466.098814308298;3466.826658667107;3467.6705712957228;569.5614376929741;589.2899056636743;650.3236633900785;679.4734081087197;682.5290549520914;633.3286716080877;743.4869939741669;768.2893323675578;777.0908037596026;809.9838003239902;10309.278350515391;10714.892851071545;10760.89239107599;10882.61910833117;11036.392998385005;653.2813904704786;715.2382712411265;724.7970568240901;743.4201322968094;775.4998026273136;4984.77568509418;5121.774008937746;5197.212557369586;5239.502247286502;5243.318368612132;130622.34688826495;132799.33600331936;133697;134030.32984835014;135382;112802;114228.42885785518;114323.42838285756;115454;115565.4221728886;95459.52270238605;100406;100960.49519752355;102165;103563;9683;9811.901880981099;9837.360571562825;9846.359986600848;9936.900630993598;56123.71938140283;56136.719316403156;56248;56922.43077569171;57968.13047804202;84159.5792021036;86956.56521717351;87102.56448717717;89231.55384223038;89704.10295896957;13232;13367.182687148337;13463.259520726455;13573.796393053915;13729.656758581115;14310.49913253031;14371.281435928371;14688.853111469176;14720.116792992027;15098.169600672069;12895.806562901376;13246.403911824184;13303.06878518533;13464.596062118153;13832.239226842621;11578.652640420802;11622.418879056182;11686.24039437434;11773.941130294636;12246.448909798903;12568.622941311774;12743.241429700343;12859.742805144031;12883.227006379648;13178.670533236746;10458.424786636808;10970.232083754061;10992.230543862173;11196.66410007701;11316.7736645265;10748.408837514013;11030.22788404804;11255.380954047607;11305.15211359135;11330.206885517935 07:56:43 INFO - PROCESS | 4315 | __end_tp_report 07:56:43 INFO - PROCESS | 4315 | __start_cc_report 07:56:43 INFO - PROCESS | 4315 | _x_x_mozilla_cycle_collect,843 07:56:43 INFO - PROCESS | 4315 | __end_cc_report 07:56:43 INFO - PROCESS | 4315 | __startTimestamp1468594603024__endTimestamp 07:56:43 INFO - PROCESS | 4315 | 07:56:43 INFO - PROCESS | 4315 | ------- Summary: start ------- 07:56:43 INFO - PROCESS | 4315 | Number of tests: 6 07:56:43 INFO - PROCESS | 4315 | 07:56:43 INFO - PROCESS | 4315 | [#0] dojo.html Cycles:165 Average:5346.50 Median:5566.05 stddev:616.22 (11.1%) stddev-sans-first:589.94 07:56:43 INFO - PROCESS | 4315 | Values: 2998.7 3069.5 3082.8 3091.0 3109.8 5286.3 5352.3 5376.1 5448.4 5450.1 5314.4 5335.6 5434.5 5463.5 5499.7 4767.5 4785.4 4830.5 4859.1 4956.3 4917.5 4930.3 4933.8 4967.4 4969.5 4788.0 4845.6 4849.7 4872.1 4881.6 4855.3 4926.3 4931.8 4940.3 4951.2 4766.4 4886.0 4907.8 4912.0 4947.4 5410.1 5473.6 5495.8 5502.7 5508.1 5799.3 5813.2 5828.7 5829.7 5851.4 5566.1 5716.9 5734.0 5801.0 5821.6 5428.3 5431.7 5440.9 5443.4 5443.9 5903.5 5912.7 5914.3 5915.3 5938.9 5512.6 5610.6 5654.6 5667.2 5719.6 5762.3 5783.9 5812.3 5816.0 5816.9 5689.8 5689.9 5693.5 5703.9 5728.2 5528.7 5614.7 5657.5 5686.1 5706.8 4345.4 4356.0 4358.3 4360.4 4394.7 3890.6 3894.4 3896.2 3944.1 3957.5 5371.7 5407.6 5427.6 5468.7 5473.8 5753.5 5784.6 5787.8 5793.7 5810.5 5419.2 5419.5 5433.9 5436.9 5438.5 4842.8 4919.8 4953.4 4956.4 4977.7 5921.5 5923.2 5924.0 5932.9 5933.8 5638.6 5762.9 5765.9 5768.2 5772.4 5885.4 5950.2 5962.8 5962.9 5965.9 5695.5 5713.7 5717.7 5718.5 5725.5 5588.3 5668.9 5813.9 5824.5 5833.3 5570.1 5606.9 5621.1 5658.4 5660.0 5572.3 5574.3 5576.7 5580.1 5583.9 5410.8 5512.2 5524.6 5532.1 5575.0 5674.9 5720.9 5779.1 5780.7 5786.4 5553.1 5597.0 5599.1 5602.2 5604.4 07:56:43 INFO - PROCESS | 4315 | 07:56:43 INFO - PROCESS | 4315 | [#1] ext.html Cycles:180 Average:62607.98 Median:208571.12 stddev:128593.86 (61.7%) stddev-sans-first:128870.24 07:56:43 INFO - PROCESS | 4315 | Values: 569.8 599.7 613.0 618.3 626.8 32676.0 43661.6 44582.8 44716.8 44755.6 47255.8 47659.5 47686.5 47720.8 47754.3 8161.3 8986.9 9129.2 9132.5 10708.9 10554.8 10946.0 10947.9 10949.9 10951.9 10949.8 10991.5 11039.3 11045.7 11049.6 8234.1 8395.7 8415.0 10567.2 10914.4 10313.9 10658.9 10672.5 10682.6 10684.6 44469.0 44749.6 44811.8 44887.1 44985.6 3763.9 3797.5 4114.4 4189.7 4210.3 34104.1 34121.5 34239.8 34657.1 35661.6 4511.0 4551.3 4569.5 4617.1 4666.4 378205.0 379186.0 381196.0 383265.0 384789.0 35214.1 35967.8 60770.7 61673.1 61728.4 37667.6 37687.4 37801.6 37820.7 37864.2 15487.7 15760.5 15767.7 15768.4 15786.3 12870.0 13035.3 13037.9 13041.5 13057.6 8444.0 8444.6 8481.3 8497.1 8520.6 3242.6 3249.6 3271.3 3293.5 3293.6 992.1 1036.7 1064.8 1070.6 1122.0 33852.5 34234.7 34327.7 34512.5 34682.3 1085.8 1124.3 1143.8 1162.6 1178.2 17753.4 18272.5 18716.3 18729.0 19594.2 604199.0 611569.0 614898.0 614997.0 617833.0 62236.7 62294.7 62372.0 62424.0 62463.7 1270.5 1375.9 1381.9 1411.1 1414.6 33496.8 34797.0 34902.3 35046.8 35217.1 52436.5 53086.0 53281.7 53294.7 53309.0 421013.3 423071.0 423535.0 424319.0 426111.0 25130.5 27518.0 28389.9 28459.7 29860.3 48635.5 48692.8 49355.0 50318.5 50429.7 42155.6 42285.4 42533.0 43428.6 43957.6 42383.0 42535.4 42679.6 43174.8 43356.6 40252.8 40692.6 42071.8 42082.8 42146.8 36475.8 37189.8 37564.8 37956.2 38165.0 28819.7 29039.0 29136.7 29183.0 29219.4 07:56:43 INFO - PROCESS | 4315 | 07:56:43 INFO - PROCESS | 4315 | [#2] jquery.html Cycles:180 Average:28219.50 Median:23916.40 stddev:73388.81 (306.9%) stddev-sans-first:73564.93 07:56:43 INFO - PROCESS | 4315 | Values: 388.1 392.6 398.5 400.1 425.1 18660.6 20117.6 20344.8 20367.7 21412.1 19977.0 20391.7 20704.0 20727.8 20759.0 5472.0 5738.4 5745.8 5821.6 5834.2 5568.4 5643.8 5718.8 5734.4 5752.7 5690.0 5714.9 5750.6 5752.7 5757.0 5498.1 5718.7 5735.4 5738.7 5782.2 5141.8 5168.3 5172.6 5189.5 5189.7 22215.4 23767.4 24065.4 24660.4 24705.8 2442.2 2469.5 2526.7 2532.2 2867.6 13295.0 13395.0 14888.3 15344.9 15592.5 1966.1 2030.5 2031.7 2036.5 2071.7 57517.4 61840.4 77019.2 83564.0 84419.6 10646.0 11039.2 11307.2 11413.1 11689.0 14169.3 14637.1 15907.9 15913.7 15938.1 7552.4 7751.8 8182.5 8369.9 8392.5 7905.3 7930.3 7931.1 8105.4 8117.0 4781.7 4889.8 4962.4 4967.3 4992.3 1950.1 1973.2 1978.5 1985.5 2082.7 954.5 960.4 977.1 1059.8 1067.7 19972.6 20514.5 20685.3 20865.2 20876.3 984.7 995.7 1005.1 1014.1 1020.4 10860.7 11451.9 11553.4 11564.8 11570.8 375734.0 377449.0 379938.0 521499.0 523730.0 101985.5 104868.5 105701.5 106740.0 110010.4 1018.6 1025.5 1053.2 1066.2 1067.5 18454.8 18595.5 18867.2 19016.9 19174.2 41914.2 45049.3 45218.3 45463.3 45825.8 60846.0 61511.7 61767.0 63212.4 63277.4 8621.2 8982.9 9158.3 9170.3 9256.9 13343.3 13748.2 13782.9 13966.7 14171.2 11677.6 12036.9 12092.4 12203.5 12433.8 11960.1 12017.9 12036.6 12057.2 12080.9 11626.2 12159.9 12168.7 12447.5 12530.0 8815.6 8928.3 9035.6 9106.9 9150.4 9215.3 11167.4 11249.6 11586.7 12262.3 07:56:43 INFO - PROCESS | 4315 | 07:56:43 INFO - PROCESS | 4315 | [#3] mootools.html Cycles:180 Average:5695.73 Median:4650.36 stddev:3226.74 (69.4%) stddev-sans-first:3218.93 07:56:43 INFO - PROCESS | 4315 | Values: 1305.4 1461.0 1519.5 1636.4 1782.8 8025.9 8451.0 8476.9 8661.6 8676.6 8403.0 8607.1 8614.7 8618.3 8622.9 2012.6 2062.7 2065.0 2296.3 2333.4 2343.5 2419.5 2422.7 2429.5 2433.8 2350.6 2359.0 2379.9 2380.7 2395.7 2024.5 2309.2 2366.1 2371.3 2371.5 2367.9 2388.2 2393.8 2401.7 2402.2 8625.6 8640.7 8667.0 8669.5 8678.0 5015.0 5107.7 5127.4 5128.2 5131.5 3367.8 3453.4 3569.1 3577.1 3588.4 1187.6 1192.5 1217.2 1236.6 1238.1 11874.8 11890.9 11891.4 11908.7 11916.7 4345.5 4402.5 4531.4 4556.3 4611.9 10131.2 10169.9 10172.8 10177.3 10188.4 5174.1 5188.7 5192.7 5202.9 5208.4 4114.3 4598.1 4688.8 4802.9 4803.1 3911.8 3926.7 4026.8 4035.2 4039.9 1246.7 1249.5 1253.4 1254.4 1273.3 787.9 970.2 994.4 1005.7 1006.1 5825.7 5973.7 6052.3 6065.7 6143.2 499.4 505.7 505.7 505.8 602.9 3778.0 3927.3 4243.2 4269.1 4270.3 10067.6 10825.3 10829.9 10840.8 10850.3 10551.9 10612.9 10714.7 10731.6 10732.4 8201.6 8218.0 8266.0 8278.8 8289.0 5311.4 5360.8 5451.2 5510.1 5518.4 9612.5 9778.3 10037.6 10055.6 10131.3 9993.8 10177.9 10187.7 10268.1 10327.7 6730.7 7322.7 7511.0 7524.4 7577.9 8270.6 8307.7 8310.8 8338.0 8342.4 7201.2 7293.1 7558.7 7716.2 7734.9 7247.6 7376.2 7411.3 7487.0 7556.5 6934.9 6992.8 7024.5 7034.2 7038.9 5108.4 5189.0 5193.3 5282.4 5293.7 4946.0 5113.6 5389.4 5391.4 5489.7 07:56:43 INFO - PROCESS | 4315 | 07:56:43 INFO - PROCESS | 4315 | [#4] prototype.html Cycles:180 Average:4618.78 Median:5201.59 stddev:1344.64 (25.9%) stddev-sans-first:1309.15 07:56:43 INFO - PROCESS | 4315 | Values: 321.4 331.5 334.3 336.7 340.9 5153.9 5292.3 5293.0 5318.7 5345.3 5270.3 5274.5 5287.3 5295.6 5314.4 4173.1 4179.5 4183.5 4188.0 4192.3 4023.2 4174.5 4180.4 4185.5 4225.6 4121.6 4137.5 4173.5 4176.6 4178.5 4131.3 4144.5 4146.9 4147.0 4150.5 3709.5 3710.5 3713.6 3714.5 3715.2 4937.4 5145.8 5161.8 5177.9 5179.3 4670.3 4673.9 4684.9 4692.5 4698.2 5263.2 5459.9 5464.3 5492.4 5493.8 3903.3 3908.9 3909.2 3913.9 3917.3 5379.6 5521.4 5649.8 5704.9 5710.6 4028.8 4048.0 4053.6 4073.7 4084.2 4120.7 4121.6 4125.3 4126.2 4135.6 4458.0 4590.2 4688.6 4706.6 4708.0 5519.4 5522.6 5526.0 5533.2 5538.1 1382.5 1394.6 1395.6 1396.9 1407.9 891.9 912.7 936.2 943.2 944.7 4687.0 4760.3 4787.2 4791.3 4797.4 5004.8 5010.4 5021.7 5029.9 5041.3 4677.8 4681.9 4733.9 4736.9 4977.6 3019.2 3031.6 3031.8 3041.6 3044.3 5820.7 5839.0 5846.3 5852.5 5876.6 5611.7 5822.7 5833.2 5843.5 5847.2 5773.6 5810.7 5815.7 5831.6 5839.5 5422.4 5657.0 5658.4 5685.0 5692.7 5675.9 5690.7 5702.7 5709.7 5725.2 5593.7 5759.7 5888.3 5909.4 5951.7 4168.7 4216.9 4224.4 4224.8 4239.4 5594.3 5656.2 5688.5 5706.5 5731.1 5487.6 5504.7 5515.0 5526.7 5530.2 5505.3 5516.8 5527.1 5527.4 5531.7 5223.9 5480.7 5489.1 5521.5 5532.3 5567.1 5581.7 5593.3 5618.5 5618.6 5445.3 5459.4 5466.9 5468.2 5479.5 07:56:43 INFO - PROCESS | 4315 | 07:56:43 INFO - PROCESS | 4315 | [#5] yui.html Cycles:180 Average:22915.83 Median:3465.88 stddev:34700.24 (1001.2%) stddev-sans-first:34757.81 07:56:43 INFO - PROCESS | 4315 | Values: 786.7 837.4 839.1 842.4 865.9 14176.7 14931.2 14953.0 15005.2 15021.2 11667.2 13883.4 13922.4 13928.8 13945.2 780.4 824.7 824.9 825.7 826.1 824.3 824.9 826.6 831.3 832.7 699.5 838.8 872.7 877.9 880.1 854.3 869.6 871.8 871.9 873.1 631.1 631.9 635.4 636.1 636.4 15414.4 15450.2 15455.1 15468.6 15489.0 14710.3 15071.2 15107.5 15238.7 15249.5 13204.5 13241.5 13251.0 13335.1 13366.1 4469.7 4512.5 4532.8 4554.8 4588.4 82621.2 83248.0 83282.2 83423.2 84405.6 11658.7 11857.8 11885.7 11966.9 12129.4 22698.1 22731.0 22830.9 22863.8 22912.4 8904.7 8926.9 8977.7 8985.5 9163.5 4495.2 4526.0 4546.7 4552.5 4612.1 3382.5 3465.7 3466.1 3466.8 3467.7 569.6 589.3 650.3 679.5 682.5 633.3 743.5 768.3 777.1 810.0 10309.3 10714.9 10760.9 10882.6 11036.4 653.3 715.2 724.8 743.4 775.5 4984.8 5121.8 5197.2 5239.5 5243.3 130622.3 132799.3 133697.0 134030.3 135382.0 112802.0 114228.4 114323.4 115454.0 115565.4 95459.5 100406.0 100960.5 102165.0 103563.0 9683.0 9811.9 9837.4 9846.4 9936.9 56123.7 56136.7 56248.0 56922.4 57968.1 84159.6 86956.6 87102.6 89231.6 89704.1 13232.0 13367.2 13463.3 13573.8 13729.7 14310.5 14371.3 14688.9 14720.1 15098.2 12895.8 13246.4 13303.1 13464.6 13832.2 11578.7 11622.4 11686.2 11773.9 12246.4 12568.6 12743.2 12859.7 12883.2 13178.7 10458.4 10970.2 10992.2 11196.7 11316.8 10748.4 11030.2 11255.4 11305.2 11330.2 07:56:43 INFO - PROCESS | 4315 | -------- Summary: end -------- 07:56:43 INFO - PROCESS | 4315 | 07:56:43 INFO - TEST-INFO | 4315: exit 0 07:56:43 INFO - TEST-OK | dromaeo_css | took 1113296ms 07:56:43 INFO - TEST-START | kraken 07:56:43 INFO - operating with platform_type : linux_ 07:56:43 INFO - Initialising browser for kraken test... 07:56:43 INFO - TEST-INFO | started process 4501 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH2s6m8/profile http://localhost:51564/getInfo.html) 07:56:47 INFO - PROCESS | 4501 | __metrics Screen width/height:1600/1200 07:56:47 INFO - PROCESS | 4501 | colorDepth:24 07:56:47 INFO - PROCESS | 4501 | Browser inner width/height: 1024/697 07:56:47 INFO - PROCESS | 4501 | __metrics 07:56:48 INFO - PROCESS | 4501 | 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" 07:56:48 INFO - PROCESS | 4501 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:56:49 INFO - TEST-INFO | 4501: exit 0 07:56:49 INFO - Browser initialized. 07:56:49 INFO - Running cycle 1/1 for kraken test... 07:56:49 INFO - TEST-INFO | started process 4587 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH2s6m8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:57:01 INFO - PROCESS | 4587 | RSS: Main: 157585408 07:57:01 INFO - PROCESS | 4587 | 07:57:04 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51564/tests/kraken/driver.html?testName=audio-beat-detection) 07:57:04 INFO - PROCESS | 4587 | RSS: Main: 215719936 07:57:04 INFO - PROCESS | 4587 | 07:57:07 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51564/tests/kraken/driver.html?testName=audio-dft) 07:57:08 INFO - PROCESS | 4587 | RSS: Main: 203874304 07:57:08 INFO - PROCESS | 4587 | 07:57:11 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51564/tests/kraken/driver.html?testName=audio-fft) 07:57:11 INFO - PROCESS | 4587 | RSS: Main: 270245888 07:57:11 INFO - PROCESS | 4587 | 07:57:13 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51564/tests/kraken/driver.html?testName=audio-oscillator) 07:57:14 INFO - PROCESS | 4587 | RSS: Main: 180830208 07:57:14 INFO - PROCESS | 4587 | 07:57:16 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51564/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:57:17 INFO - PROCESS | 4587 | RSS: Main: 184635392 07:57:17 INFO - PROCESS | 4587 | 07:57:20 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51564/tests/kraken/driver.html?testName=imaging-darkroom) 07:57:20 INFO - PROCESS | 4587 | RSS: Main: 219144192 07:57:20 INFO - PROCESS | 4587 | 07:57:24 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51564/tests/kraken/driver.html?testName=imaging-desaturate) 07:57:24 INFO - PROCESS | 4587 | RSS: Main: 220864512 07:57:24 INFO - PROCESS | 4587 | 07:57:27 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51564/tests/kraken/driver.html?testName=json-parse-financial) 07:57:28 INFO - PROCESS | 4587 | RSS: Main: 221433856 07:57:28 INFO - PROCESS | 4587 | 07:57:29 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51564/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:57:30 INFO - PROCESS | 4587 | RSS: Main: 207990784 07:57:30 INFO - PROCESS | 4587 | 07:57:32 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51564/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:57:32 INFO - PROCESS | 4587 | RSS: Main: 212250624 07:57:32 INFO - PROCESS | 4587 | 07:57:34 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51564/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:57:35 INFO - PROCESS | 4587 | RSS: Main: 208773120 07:57:35 INFO - PROCESS | 4587 | 07:57:37 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51564/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:57:38 INFO - PROCESS | 4587 | RSS: Main: 220835840 07:57:38 INFO - PROCESS | 4587 | 07:57:41 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51564/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:57:41 INFO - PROCESS | 4587 | RSS: Main: 222355456 07:57:41 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | Cycle 1(1): loaded http://localhost:51564/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51564/tests/kraken/driver.html?testName=ai-astar) 07:57:43 INFO - PROCESS | 4587 | RSS: Main: 264216576 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | __start_tp_report 07:57:43 INFO - PROCESS | 4587 | _x_x_mozilla_page_load 07:57:43 INFO - PROCESS | 4587 | _x_x_mozilla_page_load_details 07:57:43 INFO - PROCESS | 4587 | |i|pagename|runs| 07:57:43 INFO - PROCESS | 4587 | |0;ai-astar;102;111;112;111;111;112;110;111;111;111 07:57:43 INFO - PROCESS | 4587 | |1;audio-beat-detection;140;118;123;163;117;119;149;122;148;123 07:57:43 INFO - PROCESS | 4587 | |2;audio-dft;162;162;160;160;160;159;158;160;160;161 07:57:43 INFO - PROCESS | 4587 | |3;audio-fft;75;75;75;129;75;77;74;75;99;75 07:57:43 INFO - PROCESS | 4587 | |4;audio-oscillator;85;84;116;83;84;85;123;84;84;128 07:57:43 INFO - PROCESS | 4587 | |5;imaging-gaussian-blur;117;117;118;116;117;117;117;116;118;117 07:57:43 INFO - PROCESS | 4587 | |6;imaging-darkroom;145;146;145;145;145;146;146;146;145;146 07:57:43 INFO - PROCESS | 4587 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;95 07:57:43 INFO - PROCESS | 4587 | |8;json-parse-financial;52;52;53;52;52;52;52;61;54;53 07:57:43 INFO - PROCESS | 4587 | |9;json-stringify-tinderbox;63;62;63;62;62;61;62;62;63;63 07:57:43 INFO - PROCESS | 4587 | |10;stanford-crypto-aes;76;76;76;75;75;80;74;75;75;79 07:57:43 INFO - PROCESS | 4587 | |11;stanford-crypto-ccm;118;117;117;119;117;125;121;122;149;127 07:57:43 INFO - PROCESS | 4587 | |12;stanford-crypto-pbkdf2;157;156;156;153;158;158;154;156;154;152 07:57:43 INFO - PROCESS | 4587 | |13;stanford-crypto-sha256-iterative;64;66;66;63;63;69;62;62;64;62 07:57:43 INFO - PROCESS | 4587 | __end_tp_report 07:57:43 INFO - PROCESS | 4587 | __start_cc_report 07:57:43 INFO - PROCESS | 4587 | _x_x_mozilla_cycle_collect,1607 07:57:43 INFO - PROCESS | 4587 | __end_cc_report 07:57:43 INFO - PROCESS | 4587 | __startTimestamp1468594663576__endTimestamp 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | ------- Summary: start ------- 07:57:43 INFO - PROCESS | 4587 | Number of tests: 14 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.00 stddev:2.94 (2.6%) stddev-sans-first:0.60 07:57:43 INFO - PROCESS | 4587 | Values: 102.0 111.0 112.0 111.0 111.0 112.0 110.0 111.0 111.0 111.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:131.50 stddev:16.40 (12.5%) stddev-sans-first:17.15 07:57:43 INFO - PROCESS | 4587 | Values: 140.0 118.0 123.0 163.0 117.0 119.0 149.0 122.0 148.0 123.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:1.23 (0.8%) stddev-sans-first:1.12 07:57:43 INFO - PROCESS | 4587 | Values: 162.0 162.0 160.0 160.0 160.0 159.0 158.0 160.0 160.0 161.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.00 stddev:17.87 (23.8%) stddev-sans-first:18.72 07:57:43 INFO - PROCESS | 4587 | Values: 75.0 75.0 75.0 129.0 75.0 77.0 74.0 75.0 99.0 75.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:84.00 stddev:18.67 (22.2%) stddev-sans-first:19.41 07:57:43 INFO - PROCESS | 4587 | Values: 85.0 84.0 116.0 83.0 84.0 85.0 123.0 84.0 84.0 128.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 07:57:43 INFO - PROCESS | 4587 | Values: 117.0 117.0 118.0 116.0 117.0 117.0 117.0 116.0 118.0 117.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 07:57:43 INFO - PROCESS | 4587 | Values: 145.0 146.0 145.0 145.0 145.0 146.0 146.0 146.0 145.0 146.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 07:57:43 INFO - PROCESS | 4587 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:52.50 stddev:2.79 (5.3%) stddev-sans-first:2.92 07:57:43 INFO - PROCESS | 4587 | Values: 52.0 52.0 53.0 52.0 52.0 52.0 52.0 61.0 54.0 53.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:62.50 stddev:0.67 (1.1%) stddev-sans-first:0.67 07:57:43 INFO - PROCESS | 4587 | Values: 63.0 62.0 63.0 62.0 62.0 61.0 62.0 62.0 63.0 63.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:76.00 stddev:1.91 (2.5%) stddev-sans-first:2.03 07:57:43 INFO - PROCESS | 4587 | Values: 76.0 76.0 76.0 75.0 75.0 80.0 74.0 75.0 75.0 79.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#11] stanford-crypto-ccm Cycles:10 Average:123.20 Median:121.50 stddev:9.72 (8.0%) stddev-sans-first:10.12 07:57:43 INFO - PROCESS | 4587 | Values: 118.0 117.0 117.0 119.0 117.0 125.0 121.0 122.0 149.0 127.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.40 Median:156.00 stddev:2.07 (1.3%) stddev-sans-first:2.11 07:57:43 INFO - PROCESS | 4587 | Values: 157.0 156.0 156.0 153.0 158.0 158.0 154.0 156.0 154.0 152.0 07:57:43 INFO - PROCESS | 4587 | 07:57:43 INFO - PROCESS | 4587 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:2.28 (3.6%) stddev-sans-first:2.42 07:57:43 INFO - PROCESS | 4587 | Values: 64.0 66.0 66.0 63.0 63.0 69.0 62.0 62.0 64.0 62.0 07:57:43 INFO - PROCESS | 4587 | -------- Summary: end -------- 07:57:43 INFO - PROCESS | 4587 | 07:57:44 INFO - TEST-INFO | 4587: exit 0 07:57:44 INFO - TEST-OK | kraken | took 60837ms 07:57:44 INFO - SUITE-END | took 1174s 07:57:44 INFO - Completed test suite (00:19:34) 07:57:44 INFO - javascript benchmark 07:57:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2998.6701462839087, 3069.5395690646387, 3082.753379729622, 3090.9845450772755, 3109.8289594072317, 5286.259923610688, 5352.250684904112, 5376.1129413646795, 5448.42791506892, 5450.100733378993, 5314.388845282793, 5335.573154147669, 5434.483724046219, 5463.453654634534, 5499.697516636584, 4767.547083027124, 4785.449673287564, 4830.468648448667, 4859.101066302727, 4956.281339205831, 4917.50824917509, 4930.309756634074, 4933.753312334369, 4967.403911530621, 4969.527894849984, 4787.952120478785, 4845.636577256684, 4849.709017458964, 4872.1473742094995, 4881.633877459205, 4855.271709243606, 4926.310316555685, 4931.778069986859, 4940.283658869444, 4951.158303088484, 4766.356541866839, 4885.975570122162, 4907.803687852482, 4911.9754401227765, 4947.431045429749, 5410.053240682904, 5473.616846820724, 5495.8351249462185, 5502.724863756796, 5508.118701007859, 5799.2750906136735, 5813.186153938452, 5828.708564571754, 5829.650220986712, 5851.385604511507, 5566.053770858964, 5716.8856622867725, 5734.025215713339, 5800.9709951450395, 5821.62159459638, 5428.321459817523, 5431.701256430855, 5440.863978400572, 5443.374011988632, 5443.863903402446, 5903.527717782567, 5912.704364781744, 5914.290285165807, 5915.349311575723, 5938.910916336258, 5512.614117011851, 5610.607257492018, 5654.604177707521, 5667.1782591524, 5719.628224165416, 5762.337331206881, 5783.884322313615, 5812.3025236971835, 5815.9709201453725, 5816.88366232673, 5689.800856969986, 5689.9146512802345, 5693.459121383462, 5703.914441283384, 5728.169415434739, 5528.695921724344, 5614.747336369792, 5657.547396208293, 5686.090225563973, 5706.771729130871, 4345.413369195181, 4356.041670832412, 4358.3244597087505, 4360.4331436913, 4394.670399720034, 3890.5720370759195, 3894.357431023849, 3896.181801821649, 3944.073142811437, 3957.5448823385104, 5371.650842695212, 5407.567394608422, 5427.6472029317965, 5468.699221542854, 5473.8357849264585, 5753.51095156908, 5784.566157538202, 5787.797427090031, 5793.710314484259, 5810.535157187415, 5419.187121931742, 5419.458054194549, 5433.864153396196, 5436.864078398072, 5438.5377242934, 4842.83050093245, 4919.77860996256, 4953.356063711766, 4956.405231372259, 4977.726225057584, 5921.496672782861, 5923.200367950271, 5923.970380148072, 5932.881342373128, 5933.762649493972, 5638.633488823213, 5762.9135562966585, 5765.884682306414, 5768.192453056659, 5772.39389864075, 5885.440883116097, 5950.166976623363, 5962.791302304398, 5962.880742385215, 5965.850853728518, 5695.487406133468, 5713.742881570256, 5717.742701578356, 5718.485336319751, 5725.541956643542, 5588.329400471969, 5668.914966275592, 5813.883722325614, 5824.475797178275, 5833.270841144857, 5570.136628822539, 5606.915896261477, 5621.100623900156, 5658.434156584473, 5660.0, 5572.275604171432, 5574.303212098488, 5576.693281869536, 5580.051391263576, 5583.888322233452, 5410.783568657367, 5512.228288039758, 5524.558035357242, 5532.1425179097305, 5574.996500629925, 5674.914876276942, 5720.942790572041, 5779.133130030529, 5780.739866705924, 5786.363500015079, 5553.111502159635, 5597.0, 5599.0761524349655, 5602.215689803512, 5604.355499117653], "value": 5303.907482996302, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.8148101866894, 599.6791716431709, 612.9570930034898, 618.2858798088209, 626.7524327890486, 32676.0, 43661.56338436615, 44582.777086114525, 44716.77641611787, 44755.55244447559, 47255.76372118135, 47659.52340476594, 47686.52313476864, 47720.76139619297, 47754.28368574474, 8161.265486106249, 8986.86519702205, 9129.22401595865, 9132.452052876815, 10708.892911070907, 10554.841677374845, 10946.0, 10947.890521094767, 10949.890501094967, 10951.945240273788, 10949.781004379907, 10991.450427478634, 11039.282446640982, 11045.668629941114, 11049.613263535777, 8234.094249632535, 8395.664173433055, 8414.957925210396, 10567.207459440497, 10914.399708016055, 10313.948430257875, 10658.893411065868, 10672.466376681135, 10682.626108086257, 10684.626038088629, 44469.0, 44749.552504474865, 44811.77594112041, 44887.102257954655, 44985.55014449846, 3763.8870833875026, 3797.525309336333, 4114.444549985774, 4189.748615083075, 4210.2842516772225, 34104.14739631504, 34121.48817767711, 34239.828800856085, 34657.133571660655, 35661.64338356635, 4510.977445112753, 4551.271796512542, 4569.47451043131, 4617.145828021827, 4666.370040044619, 378205.0, 379186.0, 381196.0, 383265.0, 384789.0, 35214.11964700903, 35967.82016089955, 60770.69614651898, 61673.07490387648, 61728.38271617316, 37667.62332376696, 37687.4346884797, 37801.62198377981, 37820.676276330196, 37864.24271514554, 15487.690246195012, 15760.527184184493, 15767.684646307009, 15768.369265229492, 15786.289616967264, 12870.0, 13035.283059431633, 13037.934810325887, 13041.478340866259, 13057.608271751862, 8444.0, 8444.57777111142, 8481.27909127731, 8497.107803680648, 8520.61657225414, 3242.627097883774, 3249.5613092232247, 3271.2966712157, 3293.4640326948593, 3293.571835661349, 992.1417973453056, 1036.6615713240114, 1064.80301144287, 1070.550368845087, 1121.9551217951355, 33852.49221261683, 34234.65765342315, 34327.65672343295, 34512.48231276533, 34682.30635387278, 1085.8425528298353, 1124.2579897267765, 1143.765528066761, 1162.6105254739757, 1178.240035177323, 17753.378631747826, 18272.451826445227, 18716.344927927457, 18729.0, 19594.216231350587, 604199.0, 611569.0, 614898.0, 614997.0, 617833.0, 62236.68881655653, 62294.688526557984, 62372.0, 62424.0, 62463.68768156221, 1270.4727538071782, 1375.8830499407663, 1381.9447222111205, 1411.0757453867734, 1414.569668553753, 33496.83251583727, 34797.0, 34902.30195396129, 35046.82476587601, 35217.1195720109, 52436.47563524316, 53086.0, 53281.733591331795, 53294.73352633212, 53309.0, 421013.2645357398, 423071.0, 423535.0, 424319.0, 426111.0, 25130.497390052464, 27518.0, 28389.858050709616, 28459.715402845708, 29860.253493662833, 48635.51364486451, 48692.75653621709, 49355.0, 50318.49681503138, 50429.74785126051, 42155.57844421516, 42285.36571951362, 42533.0, 43428.565714342454, 43957.56042439535, 42383.0, 42535.361969569865, 42679.57320426756, 43174.78412607917, 43356.566434335255, 40252.79873600613, 40692.59307406888, 42071.7896410516, 42082.78958605188, 42146.78926605347, 36475.81762091172, 37189.81405092957, 37564.81217593895, 37956.24087518179, 38165.0, 28819.711802882543, 29039.0, 29136.7086329134, 29183.0, 29219.415611687222], "value": 19892.93616064255, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.1247786242024, 392.6230818414322, 398.5237641018983, 400.14568895408314, 425.13485057907167, 18660.626787464244, 20117.59764804703, 20344.796552034455, 20367.694484582724, 21412.14351425941, 19977.0, 20391.694124588143, 20704.0, 20727.792722072776, 20759.0, 5471.972640136803, 5738.368779434259, 5745.798897038604, 5821.592488525805, 5834.212381328509, 5568.415316391782, 5643.830685079454, 5718.771249150029, 5734.369219385885, 5752.74112664929, 5690.0, 5714.914276285859, 5750.6262092963825, 5752.683602401867, 5756.971215143918, 5498.120300751861, 5718.7426565804435, 5735.426457354273, 5738.655680659173, 5782.248307720011, 5141.845744627667, 5168.2764412982215, 5172.6379153459275, 5189.455107213726, 5189.688618682853, 22215.44461388478, 23767.405814854767, 24065.398365041015, 24660.383490412703, 24705.752942470524, 2442.206282958046, 2469.4814089041324, 2526.7220605733355, 2532.227670560478, 2867.641544806899, 13295.0, 13395.0, 14888.330025148894, 15344.923275383551, 15592.532224033297, 1966.1349006437122, 2030.5126769575195, 2031.6647753120717, 2036.5010572409706, 2071.658176400892, 57517.424825752045, 61840.381596183914, 77019.22980770176, 83564.0, 84419.57790211009, 10646.0, 11039.227254092137, 11307.208495405408, 11413.144014198968, 11689.0, 14169.291535423188, 14637.12177269346, 15907.920460397623, 15913.68172636564, 15938.123403212934, 7552.433567482461, 7751.806204844811, 8182.549959752152, 8369.944970085875, 8392.496450213006, 7905.339878625553, 7930.286274235346, 7931.087924888711, 8105.392095592854, 8116.959415203004, 4781.713097214179, 4889.779959901742, 4962.429320628102, 4967.3294105296045, 4992.279207831741, 1950.1029526417724, 1973.1515448357345, 1978.5053736565858, 1985.5234743661417, 2082.68063077173, 954.4702690007113, 960.351762560269, 977.0620204603518, 1059.8304271316551, 1067.7063807453012, 19972.600547988957, 20514.487137821376, 20685.27601533939, 20865.165393384093, 20876.269330573356, 984.6799790068113, 995.7162208770466, 1005.0853723111933, 1014.1227837920294, 1020.4336593190702, 10860.728481788019, 11451.88548114525, 11553.364564949008, 11564.768704626029, 11570.826437603442, 375734.0, 377449.0, 379938.0, 521499.0, 523730.0, 101985.49007254916, 104868.47565762275, 105701.47149264204, 106740.0, 110010.44994774974, 1018.6332920148741, 1025.5333823110616, 1053.167997282154, 1066.1790421375497, 1067.508945884886, 18454.815451845847, 18595.535111622317, 18867.15097820574, 19016.904915475334, 19174.233030679174, 41914.1617167661, 45049.32426013678, 45218.32172517349, 45463.31805022861, 45825.77087114543, 60846.0, 61511.69244153929, 61767.0, 63212.36787632065, 63277.36722632899, 8621.224089831945, 8982.86525702128, 9158.313126515404, 9170.312226583166, 9256.861147082664, 13343.332833358487, 13748.175109493463, 13782.862171378158, 13966.720665586834, 14171.220582868042, 11677.591284305008, 12036.939815300868, 12092.39538023113, 12203.511859525519, 12433.751324973631, 11960.10299227544, 12017.879821202027, 12036.63890083299, 12057.15599907998, 12080.879191207976, 11626.186166968231, 12159.939200303941, 12168.695782605506, 12447.502099915902, 12530.0, 8815.629787318621, 8928.330375221754, 9035.593398297224, 9106.863397049183, 9150.359474836698, 9215.2627789778, 11167.38579378142, 11249.550017999516, 11586.652400428, 12262.325572093621], "value": 9523.059159038014, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.3799445263508, 1461.0, 1519.5289460267315, 1636.3945340224118, 1782.777152855893, 8025.9197408025975, 8451.02813176485, 8476.91523084769, 8661.566921653908, 8676.566171691422, 8402.957985210065, 8607.053224145338, 8614.698485553006, 8618.26744726699, 8622.870656940151, 2012.5874195789902, 2062.731844860166, 2064.9690254646193, 2296.2881506732924, 2333.4049817296564, 2343.4610039690883, 2419.4919066995953, 2422.6729391532094, 2429.465517586128, 2433.841800282985, 2350.6239001759723, 2358.988205058972, 2379.916702915398, 2380.666706661069, 2395.6526303685955, 2024.5242368043498, 2309.2379514760087, 2366.148186652804, 2371.288613415977, 2371.5494056129364, 2367.893444794988, 2388.176079252655, 2393.80849532037, 2401.747816479262, 2402.207271600385, 8625.568721563897, 8640.65437382504, 8667.046624871258, 8669.523176225304, 8678.045415004344, 5014.974925125351, 5107.693538387709, 5127.435982041972, 5128.205128205134, 5131.512506311894, 3367.764256502046, 3453.4129198036144, 3569.125564236753, 3577.123604716835, 3588.4437912123662, 1187.584345479084, 1192.5349113845607, 1217.2027322104036, 1236.6475554467024, 1238.0900038471716, 11874.762504749942, 11890.940545297304, 11891.405429728566, 11908.702282442922, 11916.702082447922, 4345.543717909637, 4402.537733537996, 4531.3882625845745, 4556.270996640522, 4611.930821037688, 10131.240156988255, 10169.9491502542, 10172.847407288897, 10177.287589868638, 10188.388696678223, 5174.09453345663, 5188.688678679291, 5192.688438693615, 5202.895942081137, 5208.374995000624, 4114.259433302034, 4598.0803839232285, 4688.789004494806, 4802.8799280018275, 4803.1114243864995, 3911.8239679214503, 3926.70549708773, 4026.798660066985, 4035.1727895781296, 4039.939400908989, 1246.6696325473756, 1249.4877100388796, 1253.3607859991287, 1254.3978890132787, 1273.3251376770327, 787.9290863822283, 970.1898914406408, 994.4431118573477, 1005.6882366466273, 1006.094514936563, 5825.7378417971295, 5973.726344262416, 6052.334243233241, 6065.727042283108, 6143.232095988002, 499.41568365012296, 505.66373361714784, 505.6940550966612, 505.815377387251, 602.9336772954971, 3778.0, 3927.3323534999117, 4243.178286439484, 4269.060806622538, 4270.316749320094, 10067.59729610822, 10825.296355736851, 10829.891701082888, 10840.837387439195, 10850.29473084247, 10551.94724026375, 10612.946935265429, 10714.732131696614, 10731.570737170581, 10732.356058636507, 8201.589920503951, 8217.95891020553, 8265.95867020661, 8278.8344233115, 8289.0467596226, 5311.44229855875, 5360.839174824761, 5451.182322651634, 5510.1459273812625, 5518.365387980273, 9612.51937403113, 9778.266630002921, 10037.648682296085, 10055.648052318134, 10131.341462804894, 9993.800123997626, 10177.898221017991, 10187.7453063674, 10268.07587317145, 10327.690169294932, 6730.663466826737, 7322.670479828314, 7510.962445187739, 7524.360429363565, 7577.924220757722, 8270.627821747916, 8307.709230176915, 8310.792230194053, 8337.95831020841, 8342.374321925758, 7201.207867134715, 7293.0519032525435, 7558.697652093854, 7716.164991085064, 7734.92265077342, 7247.565146091251, 7376.18861925177, 7411.332980031823, 7487.0, 7556.546607203585, 6934.8613027740175, 6992.755253566101, 7024.473164512784, 7034.226235114235, 7038.8944165838575, 5108.386993560796, 5188.9740551297, 5193.298904647975, 5282.366116066097, 5293.7353132344, 4945.975270123627, 5113.616478764182, 5389.380221274447, 5391.379991300893, 5489.737360407049], "value": 4519.493241992045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.3572854291417, 331.4795770640096, 334.3079824762742, 336.7322978232305, 340.86706184588013, 5153.948460515398, 5292.285541451903, 5293.0, 5318.680879147254, 5345.278387417698, 5270.288511051007, 5274.498922602356, 5287.339082614673, 5295.629305948585, 5314.441983591725, 4173.1445053764055, 4179.519355274145, 4183.477065366829, 4188.0, 4192.308269135589, 4023.1752490739327, 4174.4953028183, 4180.414741936132, 4185.476815398075, 4225.556316586761, 4121.649659778923, 4137.503499580054, 4173.457450531442, 4176.640198287972, 4178.498580170384, 4131.338985762263, 4144.544100148996, 4146.91706165875, 4147.0, 4150.543440221573, 3709.462127991453, 3710.5361829771277, 3713.610070942539, 3714.4799728038097, 3715.1826598148364, 4937.407511098655, 5145.76844042019, 5161.793528258865, 5177.922331165036, 5179.2749015137915, 4670.346151538787, 4673.929891051637, 4684.906301873977, 4692.545310953616, 4698.154332220199, 5263.184206448007, 5459.945400545984, 5464.26232458621, 5492.368377636543, 5493.752781124859, 3903.277893589695, 3908.863189788344, 3909.179072394772, 3913.863014794497, 3917.334053210961, 5379.623426360156, 5521.392646808888, 5649.802256920988, 5704.885902281972, 5710.629448932268, 4028.798560071985, 4048.0, 4053.5541090480024, 4073.6944729145434, 4084.244414783275, 4120.690948178899, 4121.567235440296, 4125.29869922114, 4126.154138401621, 4135.58644135584, 4458.0, 4590.150822097922, 4688.578027977498, 4706.599939005155, 4707.952920470751, 5519.420460851633, 5522.585806064561, 5526.0, 5533.170024496278, 5538.144721518256, 1382.5483242595435, 1394.6443656867636, 1395.6231817409446, 1396.882494004792, 1407.8873690104767, 891.8840550728364, 912.6577533424966, 936.2229349639782, 943.1558754914313, 944.730751735752, 4686.95313046872, 4760.309755085563, 4787.2101103318255, 4791.257355109963, 4797.424309082932, 5004.824831130893, 5010.423801262828, 5021.715119620564, 5029.949700503022, 5041.294218809369, 4677.76611169447, 4681.882952926136, 4733.928991065137, 4736.881577960578, 4977.552020318117, 3019.230096325423, 3031.590735250757, 3031.787774855783, 3041.574179614856, 3044.254157731326, 5820.6612479130035, 5838.970805145947, 5846.327672317738, 5852.502537284378, 5876.559258055578, 5611.719414029364, 5822.70886455684, 5833.191710569737, 5843.5325173985975, 5847.152162936351, 5773.5958482906635, 5810.738516766755, 5815.73829177688, 5831.591788574759, 5839.532837373, 5422.370150477094, 5657.0, 5658.405867383865, 5684.976704193285, 5692.715364231854, 5675.858103547444, 5690.715464226855, 5702.7474147712255, 5709.658230315789, 5725.198472213977, 5593.657522194725, 5759.712014399347, 5888.263967004124, 5909.438603332676, 5951.685028354408, 4168.7290326128705, 4216.894577635461, 4224.3663450481445, 4224.788760562021, 4239.42767726353, 5594.300712410949, 5656.151577263443, 5688.54491640676, 5706.4864162225595, 5731.111677689964, 5487.5609951203005, 5504.733911200527, 5514.972425137848, 5526.723663816873, 5530.198121272473, 5505.256790333254, 5516.834494965157, 5527.143292789624, 5527.364353099445, 5531.7234138293725, 5223.895522089613, 5480.725963701879, 5489.149181876815, 5521.5306698931045, 5532.280803495681, 5567.137093750475, 5581.6930068846605, 5593.300837395325, 5618.52242559387, 5618.634788738719, 5445.319335083114, 5459.416769137065, 5466.91799623014, 5468.179773033918, 5479.506844383865], "value": 4190.577291970245, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.7482405630201, 837.447284792037, 839.118925128615, 842.397685654757, 865.852805023146, 14176.716465670681, 14931.178785166812, 14953.0, 15005.174715390649, 15021.24893755312, 11667.24162929411, 13883.375248113834, 13922.443102275922, 13928.79106813396, 13945.163290202596, 780.3912947900633, 824.6989848705209, 824.9463784853996, 825.686239229092, 826.1490664615455, 824.2828738997081, 824.8597738384489, 826.557791581504, 831.3432388413146, 832.7085520067958, 699.545295557889, 838.7651457591884, 872.7469033980138, 877.8814859993882, 880.0803160697087, 854.3378881366929, 869.5956380283167, 871.847426700325, 871.8735783311384, 873.0876234335135, 631.0565704272143, 631.8736252749469, 635.4376376906425, 636.0681601453846, 636.3858876184504, 15414.383424663, 15450.227488625524, 15455.072695638186, 15468.613284667861, 15489.0, 14710.264486775619, 15071.171085590287, 15107.54677359681, 15238.695226095528, 15249.466268680542, 13204.471821127143, 13241.470341186341, 13251.0, 13335.133216341004, 13366.06437549372, 4469.7318160910445, 4512.481064677571, 4532.818687252506, 4554.772261386951, 4588.449386073659, 82621.17378826255, 83248.0, 83282.16717832866, 83423.16576834275, 84405.57797211036, 11658.70853228676, 11857.762844743229, 11885.702857428634, 11966.940165299118, 12129.393530323448, 22698.092076317094, 22731.009617765707, 22830.885845570665, 22863.771362286163, 22912.427189320068, 8904.688335908211, 8926.910730892738, 8977.730668079968, 8985.46087234768, 9163.541822908828, 4495.190865644216, 4525.977370113128, 4546.727196368229, 4552.499225085238, 4612.077584483117, 3382.458806590934, 3465.6707612776745, 3466.098814308298, 3466.826658667107, 3467.6705712957228, 569.5614376929741, 589.2899056636743, 650.3236633900785, 679.4734081087197, 682.5290549520914, 633.3286716080877, 743.4869939741669, 768.2893323675578, 777.0908037596026, 809.9838003239902, 10309.278350515391, 10714.892851071545, 10760.89239107599, 10882.61910833117, 11036.392998385005, 653.2813904704786, 715.2382712411265, 724.7970568240901, 743.4201322968094, 775.4998026273136, 4984.77568509418, 5121.774008937746, 5197.212557369586, 5239.502247286502, 5243.318368612132, 130622.34688826495, 132799.33600331936, 133697.0, 134030.32984835014, 135382.0, 112802.0, 114228.42885785518, 114323.42838285756, 115454.0, 115565.4221728886, 95459.52270238605, 100406.0, 100960.49519752355, 102165.0, 103563.0, 9683.0, 9811.901880981099, 9837.360571562825, 9846.359986600848, 9936.900630993598, 56123.71938140283, 56136.719316403156, 56248.0, 56922.43077569171, 57968.13047804202, 84159.5792021036, 86956.56521717351, 87102.56448717717, 89231.55384223038, 89704.10295896957, 13232.0, 13367.182687148337, 13463.259520726455, 13573.796393053915, 13729.656758581115, 14310.49913253031, 14371.281435928371, 14688.853111469176, 14720.116792992027, 15098.169600672069, 12895.806562901376, 13246.403911824184, 13303.06878518533, 13464.596062118153, 13832.239226842621, 11578.652640420802, 11622.418879056182, 11686.24039437434, 11773.941130294636, 12246.448909798903, 12568.622941311774, 12743.241429700343, 12859.742805144031, 12883.227006379648, 13178.670533236746, 10458.424786636808, 10970.232083754061, 10992.230543862173, 11196.66410007701, 11316.7736645265, 10748.408837514013, 11030.22788404804, 11255.380954047607, 11305.15211359135, 11330.206885517935], "value": 7845.69887934492, "unit": "score"}], "name": "dromaeo_css", "value": 7283.702462538047, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 112.0, 111.0, 111.0, 112.0, 110.0, 111.0, 111.0, 111.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 118.0, 123.0, 163.0, 117.0, 119.0, 149.0, 122.0, 148.0, 123.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 160.0, 160.0, 159.0, 158.0, 160.0, 160.0, 161.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 129.0, 75.0, 77.0, 74.0, 75.0, 99.0, 75.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 116.0, 83.0, 84.0, 85.0, 123.0, 84.0, 84.0, 128.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 116.0, 117.0, 117.0, 117.0, 116.0, 118.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 52.0, 52.0, 52.0, 52.0, 61.0, 54.0, 53.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 62.0, 62.0, 61.0, 62.0, 62.0, 63.0, 63.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 75.0, 75.0, 80.0, 74.0, 75.0, 75.0, 79.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 119.0, 117.0, 125.0, 121.0, 122.0, 149.0, 127.0], "value": 123.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 156.0, 153.0, 158.0, 158.0, 154.0, 156.0, 154.0, 152.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 63.0, 63.0, 69.0, 62.0, 62.0, 64.0, 62.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1472.8999999999999, "alertThreshold": 2.0}]} 07:57:44 INFO - Return code: 0 07:57:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:57:44 INFO - # TBPL SUCCESS # 07:57:44 INFO - Running post-action listener: _resource_record_post_action 07:57:44 INFO - ##### 07:57:44 INFO - ##### Finished run-tests step (success) 07:57:44 INFO - ##### 07:57:44 INFO - Running post-run listener: _resource_record_post_run 07:57:44 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 8192; Write bytes: 316289024; Read time: 44; Write time: 364284 07:57:44 INFO - TinderboxPrint: CPU usage
13.0% 07:57:44 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 44 07:57:44 INFO - TinderboxPrint: I/O write bytes / time
316,289,024 / 364,284 07:57:44 INFO - TinderboxPrint: CPU idle
8,277.8 (87.0%) 07:57:44 INFO - TinderboxPrint: CPU user
1,190.8 (12.5%) 07:57:44 INFO - TinderboxPrint: Swap in / out
0 / 0 07:57:44 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 24645632; Read time: 0; Write time: 16100 07:57:44 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 290611200; Read time: 0; Write time: 348028 07:57:45 INFO - Running post-run listener: _upload_blobber_files 07:57:45 INFO - Blob upload gear active. 07:57:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:57:45 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 07:57:45 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:57:45 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:57:45 INFO - (blobuploader) - INFO - Open directory for files ... 07:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:57:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:49 INFO - (blobuploader) - INFO - Done attempting. 07:57:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:57:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:57:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:57:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:50 INFO - (blobuploader) - INFO - Done attempting. 07:57:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:57:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:57:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:57:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:51 INFO - (blobuploader) - INFO - Done attempting. 07:57:51 INFO - (blobuploader) - INFO - Iteration through files over. 07:57:51 INFO - Return code: 0 07:57:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:57:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:57:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ba52b7a66f76d67ff9b951ebe7a8aced8cdf1e0f9521818cd3869029128e2276622fa09e8381d82cba6521ca241acdaf7bf200c14d0e37a4db5f1a904c8611b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f22e2c0173459d66542a645576a76b90ce5ac14460455f1a41103b91a7348513073369ff5d364df8c36c8c00d453e25129e2eac442d50d835c92ffa6463643a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c44e519b98db28ae7e342199129f103d185dec8c6a1cd23ebbeefeef3fbeead2751d311e69fb925acb191c5ec0cce4c632eddea95f5beefa3d3d21c9045fd9fb"} 07:57:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:57:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:57:51 INFO - Contents: 07:57:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ba52b7a66f76d67ff9b951ebe7a8aced8cdf1e0f9521818cd3869029128e2276622fa09e8381d82cba6521ca241acdaf7bf200c14d0e37a4db5f1a904c8611b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f22e2c0173459d66542a645576a76b90ce5ac14460455f1a41103b91a7348513073369ff5d364df8c36c8c00d453e25129e2eac442d50d835c92ffa6463643a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c44e519b98db28ae7e342199129f103d185dec8c6a1cd23ebbeefeef3fbeead2751d311e69fb925acb191c5ec0cce4c632eddea95f5beefa3d3d21c9045fd9fb"} 07:57:51 INFO - Running post-run listener: copy_logs_to_upload_dir 07:57:51 INFO - Copying logs to upload dir... 07:57:51 INFO - mkdir: /builds/slave/test/build/upload/logs 07:57:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1305.052394 ========= master_lag: 3.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 48 secs) (at 2016-07-15 07:57:54.170685) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 07:57:54.174646) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ba52b7a66f76d67ff9b951ebe7a8aced8cdf1e0f9521818cd3869029128e2276622fa09e8381d82cba6521ca241acdaf7bf200c14d0e37a4db5f1a904c8611b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f22e2c0173459d66542a645576a76b90ce5ac14460455f1a41103b91a7348513073369ff5d364df8c36c8c00d453e25129e2eac442d50d835c92ffa6463643a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c44e519b98db28ae7e342199129f103d185dec8c6a1cd23ebbeefeef3fbeead2751d311e69fb925acb191c5ec0cce4c632eddea95f5beefa3d3d21c9045fd9fb"} build_url:https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011176 build_url: 'https://queue.taskcluster.net/v1/task/AeKnLAWLTt2AXK4wg48S7w/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/Firefox/sha512/0ba52b7a66f76d67ff9b951ebe7a8aced8cdf1e0f9521818cd3869029128e2276622fa09e8381d82cba6521ca241acdaf7bf200c14d0e37a4db5f1a904c8611b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f22e2c0173459d66542a645576a76b90ce5ac14460455f1a41103b91a7348513073369ff5d364df8c36c8c00d453e25129e2eac442d50d835c92ffa6463643a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c44e519b98db28ae7e342199129f103d185dec8c6a1cd23ebbeefeef3fbeead2751d311e69fb925acb191c5ec0cce4c632eddea95f5beefa3d3d21c9045fd9fb"}' ========= master_lag: 0.14 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 07:57:54.325028) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:57:54.325405) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468592941.406932-1555890239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:57:54.342210) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 07:57:54.342516) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 07:57:54.342802) ========= ========= Total master_lag: 3.48 =========