builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-043 starttime: 1450736354.8 results: success (0) buildid: 20151221115629 builduid: 133ce1ffcfa54b83808bf4305d9b6708 revision: 432c275bd01c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:14.804092) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:14.804571) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:14.804881) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006306 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.09 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:14.899083) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2015-12-21 14:19:14.899581) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2015-12-21 14:19:19.100065) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:19.100456) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021771 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:19.158111) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:19.160508) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:19.160940) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:19.161228) ========= 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-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-21 14:19:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2015-12-21 14:19:19 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.172631 ========= master_lag: 0.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:19.777088) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:19.777388) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.180115 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:19.969695) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:19.970013) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 432c275bd01c --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 432c275bd01c --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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-21 14:19:20,176 Setting DEBUG logging. 2015-12-21 14:19:20,176 attempt 1/10 2015-12-21 14:19:20,176 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/432c275bd01c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-21 14:19:20,418 unpacking tar archive at: mozilla-beta-432c275bd01c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.524017 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:20.505986) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:20.506285) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:20.522156) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:20.522420) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 14:19:20.522814) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-12-21 14:19:20.523103) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:19:20 INFO - MultiFileLogger online at 20151221 14:19:20 in /builds/slave/test-pgo 14:19:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:19:20 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 14:19:20 INFO - {'append_to_log': False, 14:19:20 INFO - 'base_work_dir': '/builds/slave/test-pgo', 14:19:20 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:19:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 14:19:20 INFO - 'branch': 'Mozilla-Beta', 14:19:20 INFO - 'buildbot_json_path': 'buildprops.json', 14:19:20 INFO - 'config_files': ('talos/linux_config.py',), 14:19:20 INFO - 'default_actions': ('clobber', 14:19:20 INFO - 'read-buildbot-config', 14:19:20 INFO - 'download-and-extract', 14:19:20 INFO - 'populate-webroot', 14:19:20 INFO - 'create-virtualenv', 14:19:20 INFO - 'install', 14:19:20 INFO - 'run-tests'), 14:19:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:19:20 INFO - 'download_minidump_stackwalk': True, 14:19:20 INFO - 'download_symbols': 'ondemand', 14:19:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:19:20 INFO - 'tooltool.py': '/tools/tooltool.py', 14:19:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:19:20 INFO - '/tools/misc-python/virtualenv.py')}, 14:19:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:19:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:19:20 INFO - 'installer_path': 'installer.exe', 14:19:20 INFO - 'log_level': 'info', 14:19:20 INFO - 'log_name': 'talos', 14:19:20 INFO - 'log_to_console': True, 14:19:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:19:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:19:20 INFO - 'opt_config_files': (), 14:19:20 INFO - 'pip_index': False, 14:19:20 INFO - 'sps_profile': False, 14:19:20 INFO - 'sps_profile_interval': 0, 14:19:20 INFO - 'suite': 'dromaeojs', 14:19:20 INFO - 'system_bits': '64', 14:19:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:19:20 INFO - 'title': 'talos-linux64-ix-043', 14:19:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:19:20 INFO - 'use_talos_json': True, 14:19:20 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 14:19:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:19:20 INFO - 'work_dir': 'build'} 14:19:20 INFO - ##### 14:19:20 INFO - ##### Running clobber step. 14:19:20 INFO - ##### 14:19:20 INFO - Running pre-action listener: _resource_record_pre_action 14:19:20 INFO - Running main action method: clobber 14:19:20 INFO - rmtree: /builds/slave/test-pgo/build 14:19:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 14:19:24 INFO - Running post-action listener: _resource_record_post_action 14:19:24 INFO - ##### 14:19:24 INFO - ##### Running read-buildbot-config step. 14:19:24 INFO - ##### 14:19:24 INFO - Running pre-action listener: _resource_record_pre_action 14:19:24 INFO - Running main action method: read_buildbot_config 14:19:24 INFO - Using buildbot properties: 14:19:24 INFO - { 14:19:24 INFO - "properties": { 14:19:24 INFO - "buildnumber": 44, 14:19:24 INFO - "product": "firefox", 14:19:24 INFO - "script_repo_revision": "production", 14:19:24 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:19:24 INFO - "repository": "", 14:19:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:19:24 INFO - "buildid": "20151221115629", 14:19:24 INFO - "slavename": "talos-linux64-ix-043", 14:19:24 INFO - "pgo_build": "True", 14:19:24 INFO - "basedir": "/builds/slave/test-pgo", 14:19:24 INFO - "project": "", 14:19:24 INFO - "platform": "ubuntu64_hw", 14:19:24 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:19:24 INFO - "slavebuilddir": "test", 14:19:24 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:19:24 INFO - "branch": "mozilla-beta", 14:19:24 INFO - "repo_path": "releases/mozilla-beta", 14:19:24 INFO - "stage_platform": "linux64-pgo", 14:19:24 INFO - "builduid": "133ce1ffcfa54b83808bf4305d9b6708", 14:19:24 INFO - "revision": "432c275bd01c" 14:19:24 INFO - }, 14:19:24 INFO - "sourcestamp": { 14:19:24 INFO - "repository": "", 14:19:24 INFO - "hasPatch": false, 14:19:24 INFO - "project": "", 14:19:24 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:19:24 INFO - "changes": [ 14:19:24 INFO - { 14:19:24 INFO - "category": null, 14:19:24 INFO - "files": [ 14:19:24 INFO - { 14:19:24 INFO - "url": null, 14:19:24 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.tar.bz2" 14:19:24 INFO - } 14:19:24 INFO - ], 14:19:24 INFO - "repository": "", 14:19:24 INFO - "rev": "432c275bd01c", 14:19:24 INFO - "who": "sendchange", 14:19:24 INFO - "when": 1450736313, 14:19:24 INFO - "number": 7382852, 14:19:24 INFO - "comments": "Bug 1227023 - Include the Gtk+3 version in update URL if available on Gtk+2 builds. r=karlt a=ritu", 14:19:24 INFO - "project": "", 14:19:24 INFO - "at": "Mon 21 Dec 2015 14:18:33", 14:19:24 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:19:24 INFO - "revlink": "", 14:19:24 INFO - "properties": [ 14:19:24 INFO - [ 14:19:24 INFO - "buildid", 14:19:24 INFO - "20151221115629", 14:19:24 INFO - "Change" 14:19:24 INFO - ], 14:19:24 INFO - [ 14:19:24 INFO - "builduid", 14:19:24 INFO - "133ce1ffcfa54b83808bf4305d9b6708", 14:19:24 INFO - "Change" 14:19:24 INFO - ], 14:19:24 INFO - [ 14:19:24 INFO - "pgo_build", 14:19:24 INFO - "True", 14:19:24 INFO - "Change" 14:19:24 INFO - ] 14:19:24 INFO - ], 14:19:24 INFO - "revision": "432c275bd01c" 14:19:24 INFO - } 14:19:24 INFO - ], 14:19:24 INFO - "revision": "432c275bd01c" 14:19:24 INFO - } 14:19:24 INFO - } 14:19:24 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.tar.bz2. 14:19:24 INFO - Running post-action listener: _resource_record_post_action 14:19:24 INFO - ##### 14:19:24 INFO - ##### Running download-and-extract step. 14:19:24 INFO - ##### 14:19:24 INFO - Running pre-action listener: _resource_record_pre_action 14:19:24 INFO - Running main action method: download_and_extract 14:19:24 INFO - mkdir: /builds/slave/test-pgo/build/tests 14:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:24 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/test_packages.json 14:19:24 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 14:19:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 14:19:24 INFO - Downloaded 1270 bytes. 14:19:24 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 14:19:24 INFO - Using the following test package requirements: 14:19:24 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:19:24 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:19:24 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:19:24 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:19:24 INFO - u'jsshell-linux-x86_64.zip'], 14:19:24 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:19:24 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:19:24 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:19:24 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:19:24 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 14:19:24 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:19:24 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 14:19:24 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:19:24 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:19:24 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:19:24 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:19:24 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:19:24 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:24 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:19:24 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:19:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:19:25 INFO - Downloaded 22571965 bytes. 14:19:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:19:25 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:19:25 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:19:26 INFO - Return code: 0 14:19:26 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:19:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:19:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:19:26 INFO - Downloaded 22571965 bytes. 14:19:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:19:26 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:19:26 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:19:27 INFO - Return code: 0 14:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:19:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:19:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:19:28 INFO - Downloaded 11131970 bytes. 14:19:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 14:19:28 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:19:28 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 14:19:28 INFO - Return code: 0 14:19:28 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')]} 14:19:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:19:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:19:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:19:34 INFO - Downloaded 56617251 bytes. 14:19:34 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:19:34 INFO - mkdir: /builds/slave/test-pgo/properties 14:19:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 14:19:34 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 14:19:34 INFO - Contents: 14:19:34 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:19:34 INFO - Running post-action listener: _resource_record_post_action 14:19:34 INFO - Running post-action listener: set_extra_try_arguments 14:19:34 INFO - ##### 14:19:34 INFO - ##### Running populate-webroot step. 14:19:34 INFO - ##### 14:19:34 INFO - Running pre-action listener: _resource_record_pre_action 14:19:34 INFO - Running main action method: populate_webroot 14:19:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:19:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:19:34 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 14:19:34 INFO - '../fennec_ids.txt'], 14:19:34 INFO - 'tests': ['tcheck2']}, 14:19:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:19:34 INFO - '--tppagecycles', 14:19:34 INFO - '7'], 14:19:34 INFO - 'tests': ['tsvgm']}}, 14:19:34 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:19:34 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:19:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:19:34 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:19:34 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:19:34 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:19:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:19:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:34 INFO - 'talos_options': ['--e10s'], 14:19:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:19:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:34 INFO - 'tests': ['damp', 'tps']}, 14:19:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:34 INFO - 'talos_options': ['--e10s'], 14:19:34 INFO - 'tests': ['damp', 'tps']}, 14:19:34 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:19:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:19:34 INFO - 'other': {'tests': ['a11yr', 14:19:34 INFO - 'ts_paint', 14:19:34 INFO - 'tpaint', 14:19:34 INFO - 'sessionrestore', 14:19:34 INFO - 'sessionrestore_no_auto_restore']}, 14:19:34 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:19:34 INFO - 'tests': ['a11yr', 14:19:34 INFO - 'ts_paint', 14:19:34 INFO - 'tpaint', 14:19:34 INFO - 'sessionrestore', 14:19:34 INFO - 'sessionrestore_no_auto_restore']}, 14:19:34 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:19:34 INFO - 'tests': ['a11yr', 14:19:34 INFO - 'ts_paint', 14:19:34 INFO - 'tpaint', 14:19:34 INFO - 'sessionrestore', 14:19:34 INFO - 'sessionrestore_no_auto_restore']}, 14:19:34 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:19:34 INFO - 'tests': ['a11yr', 14:19:34 INFO - 'ts_paint', 14:19:34 INFO - 'tpaint', 14:19:34 INFO - 'sessionrestore', 14:19:34 INFO - 'sessionrestore_no_auto_restore']}, 14:19:34 INFO - 'other_l64': {'tests': ['a11yr', 14:19:34 INFO - 'ts_paint', 14:19:34 INFO - 'tpaint', 14:19:34 INFO - 'sessionrestore', 14:19:34 INFO - 'sessionrestore_no_auto_restore']}, 14:19:34 INFO - 'other_nol64': {'tests': ['a11yr', 14:19:34 INFO - 'ts_paint', 14:19:34 INFO - 'tpaint', 14:19:34 INFO - 'sessionrestore', 14:19:34 INFO - 'sessionrestore_no_auto_restore']}, 14:19:34 INFO - 'svgr': {'tests': ['tsvgx', 14:19:34 INFO - 'tsvgr_opacity', 14:19:34 INFO - 'tart', 14:19:34 INFO - 'tscrollx', 14:19:34 INFO - 'cart']}, 14:19:34 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:19:34 INFO - 'tests': ['tsvgx', 14:19:34 INFO - 'tsvgr_opacity', 14:19:34 INFO - 'tart', 14:19:34 INFO - 'tscrollx', 14:19:34 INFO - 'cart']}, 14:19:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:34 INFO - 'tests': ['tp5o']}, 14:19:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:34 INFO - 'talos_options': ['--e10s'], 14:19:34 INFO - 'tests': ['tp5o']}, 14:19:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:34 INFO - 'talos_options': ['--xperf_path', 14:19:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:19:34 INFO - 'tests': ['tp5n']}, 14:19:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:34 INFO - 'talos_options': ['--e10s', 14:19:34 INFO - '--xperf_path', 14:19:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:19:34 INFO - 'tests': ['tp5n']}}, 14:19:34 INFO - 'talos.zip': {'path': '', 14:19:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:19:34 INFO - Running post-action listener: _resource_record_post_action 14:19:34 INFO - ##### 14:19:34 INFO - ##### Running create-virtualenv step. 14:19:34 INFO - ##### 14:19:34 INFO - Running pre-action listener: _resource_record_pre_action 14:19:34 INFO - Running main action method: create_virtualenv 14:19:34 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 14:19:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 14:19:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 14:19:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:19:34 INFO - Using real prefix '/usr' 14:19:34 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 14:19:34 INFO - Installing distribute.............................................................................................................................................................................................done. 14:19:36 INFO - Installing pip.................done. 14:19:36 INFO - Return code: 0 14:19:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 14:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:36 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f2ca7f9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ccd350>, '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 0x2cce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cce6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 14:19:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 14:19:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 14:19:36 INFO - Using env: {'DISPLAY': ':0', 14:19:36 INFO - 'HOME': '/home/cltbld', 14:19:36 INFO - 'LANG': 'en_US.UTF-8', 14:19:36 INFO - 'LANGUAGE': 'en_US:en', 14:19:36 INFO - 'LOGNAME': 'cltbld', 14:19:36 INFO - 'MAIL': '/var/mail/cltbld', 14:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:36 INFO - 'MOZ_NO_REMOTE': '1', 14:19:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:36 INFO - 'NO_EM_RESTART': '1', 14:19:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:19:36 INFO - 'PWD': '/builds/slave/test-pgo', 14:19:36 INFO - 'SHELL': '/bin/bash', 14:19:36 INFO - 'SHLVL': '1', 14:19:36 INFO - 'TERM': 'linux', 14:19:36 INFO - 'TMOUT': '86400', 14:19:36 INFO - 'USER': 'cltbld', 14:19:36 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', 14:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:36 INFO - '_': '/tools/buildbot/bin/python'} 14:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:36 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 14:19:36 INFO - Cleaning up... 14:19:36 INFO - Return code: 0 14:19:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 14:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:36 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2ca7f9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ccd350>, '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 0x2cce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cce6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 14:19:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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>=0.7.1'] in /builds/slave/test-pgo/build 14:19:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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>=0.7.1 14:19:36 INFO - Using env: {'DISPLAY': ':0', 14:19:36 INFO - 'HOME': '/home/cltbld', 14:19:36 INFO - 'LANG': 'en_US.UTF-8', 14:19:36 INFO - 'LANGUAGE': 'en_US:en', 14:19:36 INFO - 'LOGNAME': 'cltbld', 14:19:36 INFO - 'MAIL': '/var/mail/cltbld', 14:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:36 INFO - 'MOZ_NO_REMOTE': '1', 14:19:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:36 INFO - 'NO_EM_RESTART': '1', 14:19:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:19:36 INFO - 'PWD': '/builds/slave/test-pgo', 14:19:36 INFO - 'SHELL': '/bin/bash', 14:19:36 INFO - 'SHLVL': '1', 14:19:36 INFO - 'TERM': 'linux', 14:19:36 INFO - 'TMOUT': '86400', 14:19:36 INFO - 'USER': 'cltbld', 14:19:36 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', 14:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:36 INFO - '_': '/tools/buildbot/bin/python'} 14:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:37 INFO - Downloading/unpacking psutil>=0.7.1 14:19:37 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 14:19:37 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 14:19:37 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 14:19:37 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 14:19:37 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 14:19:37 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 14:19:38 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 14:19:38 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 14:19:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 14:19:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:38 INFO - Installing collected packages: psutil 14:19:38 INFO - Running setup.py install for psutil 14:19:38 INFO - building 'psutil._psutil_linux' extension 14:19:38 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 14:19:39 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 14:19:39 INFO - building 'psutil._psutil_posix' extension 14:19:39 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 14:19:39 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 14:19:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:39 INFO - Successfully installed psutil 14:19:39 INFO - Cleaning up... 14:19:39 INFO - Return code: 0 14:19:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 14:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:39 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2ca7f9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ccd350>, '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 0x2cce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cce6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 14:19:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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.0.0'] in /builds/slave/test-pgo/build 14:19:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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.0.0 14:19:39 INFO - Using env: {'DISPLAY': ':0', 14:19:39 INFO - 'HOME': '/home/cltbld', 14:19:39 INFO - 'LANG': 'en_US.UTF-8', 14:19:39 INFO - 'LANGUAGE': 'en_US:en', 14:19:39 INFO - 'LOGNAME': 'cltbld', 14:19:39 INFO - 'MAIL': '/var/mail/cltbld', 14:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:39 INFO - 'MOZ_NO_REMOTE': '1', 14:19:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:39 INFO - 'NO_EM_RESTART': '1', 14:19:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:19:39 INFO - 'PWD': '/builds/slave/test-pgo', 14:19:39 INFO - 'SHELL': '/bin/bash', 14:19:39 INFO - 'SHLVL': '1', 14:19:39 INFO - 'TERM': 'linux', 14:19:39 INFO - 'TMOUT': '86400', 14:19:39 INFO - 'USER': 'cltbld', 14:19:39 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', 14:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:39 INFO - '_': '/tools/buildbot/bin/python'} 14:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:19:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 14:19:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:19:41 INFO - Installing collected packages: mozsystemmonitor 14:19:41 INFO - Running setup.py install for mozsystemmonitor 14:19:41 INFO - Successfully installed mozsystemmonitor 14:19:41 INFO - Cleaning up... 14:19:41 INFO - Return code: 0 14:19:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 14:19: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')]} 14:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19: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')]} 14:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:41 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f2ca7f9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ccd350>, '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 0x2cce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cce6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 14:19:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 14:19:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 14:19:41 INFO - Using env: {'DISPLAY': ':0', 14:19:41 INFO - 'HOME': '/home/cltbld', 14:19:41 INFO - 'LANG': 'en_US.UTF-8', 14:19:41 INFO - 'LANGUAGE': 'en_US:en', 14:19:41 INFO - 'LOGNAME': 'cltbld', 14:19:41 INFO - 'MAIL': '/var/mail/cltbld', 14:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:41 INFO - 'MOZ_NO_REMOTE': '1', 14:19:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:41 INFO - 'NO_EM_RESTART': '1', 14:19:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:19:41 INFO - 'PWD': '/builds/slave/test-pgo', 14:19:41 INFO - 'SHELL': '/bin/bash', 14:19:41 INFO - 'SHLVL': '1', 14:19:41 INFO - 'TERM': 'linux', 14:19:41 INFO - 'TMOUT': '86400', 14:19:41 INFO - 'USER': 'cltbld', 14:19:41 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', 14:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:41 INFO - '_': '/tools/buildbot/bin/python'} 14:19:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:41 INFO - Downloading/unpacking blobuploader==1.2.4 14:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:43 INFO - Downloading blobuploader-1.2.4.tar.gz 14:19:43 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 14:19:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:19:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:19: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 14:19: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 14:19: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 14:19: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 14:19: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 14:19: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 14:19:43 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 14:19:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 14:19:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:19: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 14:19: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 14:19: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 14:19: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 14:19: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 14:19: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 14:19:44 INFO - Downloading docopt-0.6.1.tar.gz 14:19:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 14:19:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 14:19:44 INFO - Installing collected packages: blobuploader, requests, docopt 14:19:44 INFO - Running setup.py install for blobuploader 14:19:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:19:44 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 14:19:44 INFO - Running setup.py install for requests 14:19:44 INFO - Running setup.py install for docopt 14:19:44 INFO - Successfully installed blobuploader requests docopt 14:19:44 INFO - Cleaning up... 14:19:44 INFO - Return code: 0 14:19:44 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:19:44 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')]} 14:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:44 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')]} 14:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f2ca7f9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ccd350>, '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 0x2cce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cce6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 14:19:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 14:19:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 14:19:44 INFO - Using env: {'DISPLAY': ':0', 14:19:44 INFO - 'HOME': '/home/cltbld', 14:19:44 INFO - 'LANG': 'en_US.UTF-8', 14:19:44 INFO - 'LANGUAGE': 'en_US:en', 14:19:44 INFO - 'LOGNAME': 'cltbld', 14:19:44 INFO - 'MAIL': '/var/mail/cltbld', 14:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:44 INFO - 'MOZ_NO_REMOTE': '1', 14:19:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:44 INFO - 'NO_EM_RESTART': '1', 14:19:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:19:44 INFO - 'PWD': '/builds/slave/test-pgo', 14:19:44 INFO - 'SHELL': '/bin/bash', 14:19:44 INFO - 'SHLVL': '1', 14:19:44 INFO - 'TERM': 'linux', 14:19:44 INFO - 'TMOUT': '86400', 14:19:44 INFO - 'USER': 'cltbld', 14:19:44 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', 14:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:44 INFO - '_': '/tools/buildbot/bin/python'} 14:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:19:45 INFO - Running setup.py (path:/tmp/pip-FQrbTm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:19:45 INFO - Running setup.py (path:/tmp/pip-NR0OTT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:19:45 INFO - Running setup.py (path:/tmp/pip-8GLRou-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:19:45 INFO - Running setup.py (path:/tmp/pip-mjnto6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:19:45 INFO - Running setup.py (path:/tmp/pip-27odzg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:19:45 INFO - Running setup.py (path:/tmp/pip-LVjvI1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:19:45 INFO - Running setup.py (path:/tmp/pip-jXdrOW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:19:45 INFO - Running setup.py (path:/tmp/pip-UjNxzP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:19:45 INFO - Running setup.py (path:/tmp/pip-HriNwz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:19:45 INFO - Running setup.py (path:/tmp/pip-BU_4p3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:19:45 INFO - Running setup.py (path:/tmp/pip-RSYAfH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:19:45 INFO - Running setup.py (path:/tmp/pip-cJWQWk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:19:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:19:45 INFO - Running setup.py (path:/tmp/pip-4hQnVr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:19:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:19:46 INFO - Running setup.py (path:/tmp/pip-XhzCYY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:19:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:19:46 INFO - Running setup.py (path:/tmp/pip-f00Y9x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:19:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:19:46 INFO - Running setup.py (path:/tmp/pip-Ly7mIb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:19:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:19:46 INFO - Running setup.py (path:/tmp/pip-kCvLYK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:19:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:19:46 INFO - Running setup.py install for manifestparser 14:19:46 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 14:19:46 INFO - Running setup.py install for mozcrash 14:19:46 INFO - Running setup.py install for mozdebug 14:19:46 INFO - Running setup.py install for mozdevice 14:19:46 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 14:19:46 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 14:19:46 INFO - Running setup.py install for mozfile 14:19:46 INFO - Running setup.py install for mozhttpd 14:19:46 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 14:19:46 INFO - Running setup.py install for mozinfo 14:19:47 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Running setup.py install for mozInstall 14:19:47 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Running setup.py install for mozleak 14:19:47 INFO - Running setup.py install for mozlog 14:19:47 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Running setup.py install for moznetwork 14:19:47 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Running setup.py install for mozprocess 14:19:47 INFO - Running setup.py install for mozprofile 14:19:47 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Running setup.py install for mozrunner 14:19:47 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 14:19:47 INFO - Running setup.py install for mozscreenshot 14:19:47 INFO - Running setup.py install for moztest 14:19:48 INFO - Running setup.py install for mozversion 14:19:48 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 14:19:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:19:48 INFO - Cleaning up... 14:19:48 INFO - Return code: 0 14:19:48 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:19: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')]} 14:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19: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')]} 14:19:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f2ca7f9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ccd350>, '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 0x2cce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cce6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 14:19:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 14:19:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 14:19:48 INFO - Using env: {'DISPLAY': ':0', 14:19:48 INFO - 'HOME': '/home/cltbld', 14:19:48 INFO - 'LANG': 'en_US.UTF-8', 14:19:48 INFO - 'LANGUAGE': 'en_US:en', 14:19:48 INFO - 'LOGNAME': 'cltbld', 14:19:48 INFO - 'MAIL': '/var/mail/cltbld', 14:19:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:48 INFO - 'MOZ_NO_REMOTE': '1', 14:19:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:48 INFO - 'NO_EM_RESTART': '1', 14:19:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:19:48 INFO - 'PWD': '/builds/slave/test-pgo', 14:19:48 INFO - 'SHELL': '/bin/bash', 14:19:48 INFO - 'SHLVL': '1', 14:19:48 INFO - 'TERM': 'linux', 14:19:48 INFO - 'TMOUT': '86400', 14:19:48 INFO - 'USER': 'cltbld', 14:19:48 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', 14:19:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:48 INFO - '_': '/tools/buildbot/bin/python'} 14:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:19:48 INFO - Running setup.py (path:/tmp/pip-aunrUs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 14:19:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:19:48 INFO - Running setup.py (path:/tmp/pip-uTINO7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:19:48 INFO - Running setup.py (path:/tmp/pip-251mU0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 14:19:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:19:48 INFO - Running setup.py (path:/tmp/pip-Ur9xSc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:19:48 INFO - Running setup.py (path:/tmp/pip-IJ6a3i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 14:19:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:19:48 INFO - Running setup.py (path:/tmp/pip-nNsKpI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 14:19:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:19:48 INFO - Running setup.py (path:/tmp/pip-OSGtRs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 14:19:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:19:48 INFO - Running setup.py (path:/tmp/pip-ZdpXdK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 14:19:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:19:48 INFO - Running setup.py (path:/tmp/pip-Xy9U7x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 14:19:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:19:49 INFO - Running setup.py (path:/tmp/pip-XWjSIv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:19:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:19:49 INFO - Running setup.py (path:/tmp/pip-MBh70X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 14:19:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:19:49 INFO - Running setup.py (path:/tmp/pip-awhNjx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 14:19:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:19:49 INFO - Running setup.py (path:/tmp/pip-yrPm3Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 14:19:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:19:49 INFO - Running setup.py (path:/tmp/pip-2d6TtN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 14:19:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:19:49 INFO - Running setup.py (path:/tmp/pip-S57J1K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 14:19:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:19:49 INFO - Running setup.py (path:/tmp/pip-wK3I4A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 14:19:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:19:49 INFO - Running setup.py (path:/tmp/pip-r_Np5g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:19: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 14:19: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 14:19: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 14:19: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 14:19: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 14:19: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 14:19:51 INFO - Downloading blessings-1.5.1.tar.gz 14:19:51 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 14:19:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 14:19:51 INFO - Installing collected packages: blessings 14:19:51 INFO - Running setup.py install for blessings 14:19:51 INFO - Successfully installed blessings 14:19:51 INFO - Cleaning up... 14:19:51 INFO - Return code: 0 14:19:51 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 14:19:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:19:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:19:51 INFO - Reading from file tmpfile_stdout 14:19:51 INFO - Current package versions: 14:19:51 INFO - argparse == 1.2.1 14:19:51 INFO - blessings == 1.5.1 14:19:51 INFO - blobuploader == 1.2.4 14:19:51 INFO - docopt == 0.6.1 14:19:51 INFO - manifestparser == 1.1 14:19:51 INFO - mozInstall == 1.12 14:19:51 INFO - mozcrash == 0.16 14:19:51 INFO - mozdebug == 0.1 14:19:51 INFO - mozdevice == 0.46 14:19:51 INFO - mozfile == 1.2 14:19:51 INFO - mozhttpd == 0.7 14:19:51 INFO - mozinfo == 0.8 14:19:51 INFO - mozleak == 0.1 14:19:51 INFO - mozlog == 3.0 14:19:51 INFO - moznetwork == 0.27 14:19:51 INFO - mozprocess == 0.22 14:19:51 INFO - mozprofile == 0.27 14:19:51 INFO - mozrunner == 6.11 14:19:51 INFO - mozscreenshot == 0.1 14:19:51 INFO - mozsystemmonitor == 0.0 14:19:51 INFO - moztest == 0.7 14:19:51 INFO - mozversion == 1.4 14:19:51 INFO - psutil == 3.1.1 14:19:51 INFO - requests == 1.2.3 14:19:51 INFO - wsgiref == 0.1.2 14:19:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f2ca7f9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ccd350>, '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 0x2cce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cce6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 14:19:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/talos 14:19:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 14:19:51 INFO - Using env: {'DISPLAY': ':0', 14:19:51 INFO - 'HOME': '/home/cltbld', 14:19:51 INFO - 'LANG': 'en_US.UTF-8', 14:19:51 INFO - 'LANGUAGE': 'en_US:en', 14:19:51 INFO - 'LOGNAME': 'cltbld', 14:19:51 INFO - 'MAIL': '/var/mail/cltbld', 14:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:51 INFO - 'MOZ_NO_REMOTE': '1', 14:19:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:51 INFO - 'NO_EM_RESTART': '1', 14:19:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:19:51 INFO - 'PWD': '/builds/slave/test-pgo', 14:19:51 INFO - 'SHELL': '/bin/bash', 14:19:51 INFO - 'SHLVL': '1', 14:19:51 INFO - 'TERM': 'linux', 14:19:51 INFO - 'TMOUT': '86400', 14:19:51 INFO - 'USER': 'cltbld', 14:19:51 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', 14:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:51 INFO - '_': '/tools/buildbot/bin/python'} 14:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 14:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:19:52 INFO - Cleaning up... 14:19:52 INFO - Return code: 0 14:19:52 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 14:19: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')]} 14:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19: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')]} 14:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f2ca7f9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ccd350>, '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 0x2cce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cce6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 14:19:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 14:19:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 14:19:52 INFO - Using env: {'DISPLAY': ':0', 14:19:52 INFO - 'HOME': '/home/cltbld', 14:19:52 INFO - 'LANG': 'en_US.UTF-8', 14:19:52 INFO - 'LANGUAGE': 'en_US:en', 14:19:52 INFO - 'LOGNAME': 'cltbld', 14:19:52 INFO - 'MAIL': '/var/mail/cltbld', 14:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:52 INFO - 'MOZ_NO_REMOTE': '1', 14:19:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:52 INFO - 'NO_EM_RESTART': '1', 14:19:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:19:52 INFO - 'PWD': '/builds/slave/test-pgo', 14:19:52 INFO - 'SHELL': '/bin/bash', 14:19:52 INFO - 'SHLVL': '1', 14:19:52 INFO - 'TERM': 'linux', 14:19:52 INFO - 'TMOUT': '86400', 14:19:52 INFO - 'USER': 'cltbld', 14:19:52 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', 14:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:52 INFO - '_': '/tools/buildbot/bin/python'} 14:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:52 INFO - Downloading/unpacking jsonschema 14:19:52 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 14:19:52 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 14:19:52 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 14:19:52 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 14:19:52 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 14:19:52 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 14:19:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:19:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 14:19:53 INFO - Downloading/unpacking functools32 (from jsonschema) 14:19:53 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 14:19:53 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 14:19:53 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 14:19:53 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 14:19:53 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 14:19:53 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 14:19:54 INFO - Downloading functools32-3.2.3-2.tar.gz 14:19:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 14:19:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 14:19:54 INFO - warning: no files found matching '*.txt' 14:19:54 INFO - no previously-included directories found matching 'build' 14:19:54 INFO - no previously-included directories found matching 'dist' 14:19:54 INFO - no previously-included directories found matching '.git*' 14:19:54 INFO - Installing collected packages: jsonschema, functools32 14:19:54 INFO - Running setup.py install for functools32 14:19:54 INFO - warning: no files found matching '*.txt' 14:19:54 INFO - no previously-included directories found matching 'build' 14:19:54 INFO - no previously-included directories found matching 'dist' 14:19:54 INFO - no previously-included directories found matching '.git*' 14:19:54 INFO - Successfully installed jsonschema functools32 14:19:54 INFO - Cleaning up... 14:19:54 INFO - Return code: 0 14:19:54 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 14:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2ca7f9ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ccd350>, '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 0x2cce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cce6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 14:19:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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', 'flake8'] in /builds/slave/test-pgo/build 14:19:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 flake8 14:19:54 INFO - Using env: {'DISPLAY': ':0', 14:19:54 INFO - 'HOME': '/home/cltbld', 14:19:54 INFO - 'LANG': 'en_US.UTF-8', 14:19:54 INFO - 'LANGUAGE': 'en_US:en', 14:19:54 INFO - 'LOGNAME': 'cltbld', 14:19:54 INFO - 'MAIL': '/var/mail/cltbld', 14:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:54 INFO - 'MOZ_NO_REMOTE': '1', 14:19:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:54 INFO - 'NO_EM_RESTART': '1', 14:19:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:19:54 INFO - 'PWD': '/builds/slave/test-pgo', 14:19:54 INFO - 'SHELL': '/bin/bash', 14:19:54 INFO - 'SHLVL': '1', 14:19:54 INFO - 'TERM': 'linux', 14:19:54 INFO - 'TMOUT': '86400', 14:19:54 INFO - 'USER': 'cltbld', 14:19:54 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', 14:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:54 INFO - '_': '/tools/buildbot/bin/python'} 14:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:54 INFO - Downloading/unpacking flake8 14:19:54 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 14:19:54 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 14:19:54 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 14:19:54 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 14:19:54 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 14:19:54 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 14:19:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:19:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 14:19:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:19:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 14:19:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 14:19:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 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 14:19:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 14:19:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 14:19:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:19:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:19:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:19:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:19:56 INFO - no previously-included directories found matching 'docs/_build' 14:19:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:19:56 INFO - Running setup.py install for pep8 14:19:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:19:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:19:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:19:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:19:57 INFO - no previously-included directories found matching 'docs/_build' 14:19:57 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 14:19:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:19:57 INFO - Cleaning up... 14:19:57 INFO - Return code: 0 14:19:57 INFO - Running post-action listener: _resource_record_post_action 14:19:57 INFO - Running post-action listener: _start_resource_monitoring 14:19:57 INFO - Starting resource monitoring. 14:19:57 INFO - ##### 14:19:57 INFO - ##### Running install step. 14:19:57 INFO - ##### 14:19:57 INFO - Running pre-action listener: _resource_record_pre_action 14:19:57 INFO - Running main action method: install 14:19:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:19:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:19:57 INFO - Reading from file tmpfile_stdout 14:19:57 INFO - Detecting whether we're running mozinstall >=1.0... 14:19:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 14:19:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 14:19:57 INFO - Reading from file tmpfile_stdout 14:19:57 INFO - Output received: 14:19:57 INFO - Usage: mozinstall [options] installer 14:19:57 INFO - Options: 14:19:57 INFO - -h, --help show this help message and exit 14:19:57 INFO - -d DEST, --destination=DEST 14:19:57 INFO - Directory to install application into. [default: 14:19:57 INFO - "/builds/slave/test-pgo"] 14:19:57 INFO - --app=APP Application being installed. [default: firefox] 14:19:57 INFO - mkdir: /builds/slave/test-pgo/build/application 14:19:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 14:19:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 14:20:11 INFO - Reading from file tmpfile_stdout 14:20:11 INFO - Output received: 14:20:11 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 14:20:11 INFO - Running post-action listener: _resource_record_post_action 14:20:11 INFO - ##### 14:20:11 INFO - ##### Running run-tests step. 14:20:11 INFO - ##### 14:20:11 INFO - Running pre-action listener: _resource_record_pre_action 14:20:11 INFO - Running main action method: run_tests 14:20:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 14:20:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 14:20:11 INFO - Python 2.7.3 14:20:11 INFO - Return code: 0 14:20:11 INFO - grabbing minidump binary from tooltool 14:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:11 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-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ccd350>, '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 0x2cce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cce6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 14:20:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 14:20:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:20:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:20:11 INFO - Return code: 0 14:20:11 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 14:20:11 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 14:20:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 14:20:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 14:20:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 14:20:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 14:20:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 14:20:12 INFO - Return code: 0 14:20:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 14:20:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --webServer localhost 14:20:12 INFO - Using env: {'DISPLAY': ':0', 14:20:12 INFO - 'HOME': '/home/cltbld', 14:20:12 INFO - 'LANG': 'en_US.UTF-8', 14:20:12 INFO - 'LANGUAGE': 'en_US:en', 14:20:12 INFO - 'LOGNAME': 'cltbld', 14:20:12 INFO - 'MAIL': '/var/mail/cltbld', 14:20:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:20:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 14:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:12 INFO - 'MOZ_NO_REMOTE': '1', 14:20:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:20:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:20:12 INFO - 'NO_EM_RESTART': '1', 14:20:12 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:20:12 INFO - 'PWD': '/builds/slave/test-pgo', 14:20:12 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 14:20:12 INFO - 'SHELL': '/bin/bash', 14:20:12 INFO - 'SHLVL': '1', 14:20:12 INFO - 'TERM': 'linux', 14:20:12 INFO - 'TMOUT': '86400', 14:20:12 INFO - 'USER': 'cltbld', 14:20:12 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668', 14:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:12 INFO - '_': '/tools/buildbot/bin/python'} 14:20:12 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost'] with output_timeout 3600 14:20:12 INFO - mozversion INFO | application_buildid: 20151221115629 14:20:12 INFO - mozversion INFO | application_changeset: 432c275bd01cd62cc072f81a780a871249a50d03 14:20:12 INFO - mozversion INFO | application_display_name: Nightly 14:20:12 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:20:12 INFO - mozversion INFO | application_name: Firefox 14:20:12 INFO - mozversion INFO | application_remotingname: firefox 14:20:12 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:20:12 INFO - mozversion INFO | application_vendor: Mozilla 14:20:12 INFO - mozversion INFO | application_version: 44.0 14:20:12 INFO - mozversion INFO | platform_buildid: 20151221115629 14:20:12 INFO - mozversion INFO | platform_changeset: 432c275bd01cd62cc072f81a780a871249a50d03 14:20:12 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:20:12 INFO - mozversion INFO | platform_version: 43.0 14:20:12 INFO - 2015-12-21 14:20:12,859 DEBUG : using testdate: 1450736412 14:20:12 INFO - 2015-12-21 14:20:12,859 DEBUG : actual date: 1450736412 14:20:12 INFO - 2015-12-21 14:20:12,906 INFO : starting webserver on 'localhost:48590' 14:20:12 INFO - 2015-12-21 14:20:12,907 INFO : Starting test suite talos-linux64-ix-043 14:20:12 INFO - 2015-12-21 14:20:12,907 INFO : Starting test dromaeo_css 14:20:12 INFO - 2015-12-21 14:20:12,907 DEBUG : operating with platform_type : linux_ 14:20:12 INFO - 2015-12-21 14:20:12,908 INFO : Initialising browser for dromaeo_css test... 14:20:12 INFO - 2015-12-21 14:20:12,922 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXsMbM4/profile http://localhost:48590/getInfo.html 14:20:16 INFO - 2015-12-21 14:20:16,502 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:20:16 INFO - 2015-12-21 14:20:16,502 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:20:16 INFO - 2015-12-21 14:20:16,502 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:20:16 INFO - 2015-12-21 14:20:16,503 DEBUG : BROWSER_OUTPUT: __metrics 14:20:16 INFO - 2015-12-21 14:20:16,599 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:20:17 INFO - 2015-12-21 14:20:17,630 INFO : Browser initialized. 14:20:17 INFO - 2015-12-21 14:20:17,630 INFO : Running cycle 1/1 for dromaeo_css test... 14:20:17 INFO - 2015-12-21 14:20:17,631 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXsMbM4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:20:29 INFO - 2015-12-21 14:20:29,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 128544768 14:20:29 INFO - 2015-12-21 14:20:29,154 DEBUG : BROWSER_OUTPUT: 14:21:20 INFO - 2015-12-21 14:21:20,158 DEBUG : BROWSER_OUTPUT: 1450736480155 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] 14:23:28 INFO - 2015-12-21 14:23:28,220 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48590/tests/dromaeo/cssquery-ext.html) 14:23:29 INFO - 2015-12-21 14:23:29,325 DEBUG : BROWSER_OUTPUT: RSS: Main: 241618944 14:23:29 INFO - 2015-12-21 14:23:29,325 DEBUG : BROWSER_OUTPUT: 14:26:31 INFO - 2015-12-21 14:26:31,317 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/dromaeo/cssquery-ext.html (next: http://localhost:48590/tests/dromaeo/cssquery-jquery.html) 14:26:31 INFO - 2015-12-21 14:26:31,879 DEBUG : BROWSER_OUTPUT: RSS: Main: 206680064 14:26:31 INFO - 2015-12-21 14:26:31,880 DEBUG : BROWSER_OUTPUT: 14:29:33 INFO - 2015-12-21 14:29:33,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48590/tests/dromaeo/cssquery-mootools.html) 14:29:34 INFO - 2015-12-21 14:29:34,191 DEBUG : BROWSER_OUTPUT: RSS: Main: 210145280 14:29:34 INFO - 2015-12-21 14:29:34,191 DEBUG : BROWSER_OUTPUT: 14:32:35 INFO - 2015-12-21 14:32:35,910 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48590/tests/dromaeo/cssquery-prototype.html) 14:32:36 INFO - 2015-12-21 14:32:36,480 DEBUG : BROWSER_OUTPUT: RSS: Main: 190636032 14:32:36 INFO - 2015-12-21 14:32:36,480 DEBUG : BROWSER_OUTPUT: 14:35:38 INFO - 2015-12-21 14:35:38,822 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48590/tests/dromaeo/cssquery-yui.html) 14:35:39 INFO - 2015-12-21 14:35:39,372 DEBUG : BROWSER_OUTPUT: RSS: Main: 191598592 14:35:39 INFO - 2015-12-21 14:35:39,372 DEBUG : BROWSER_OUTPUT: 14:38:41 INFO - 2015-12-21 14:38:41,258 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/dromaeo/cssquery-yui.html (next: http://localhost:48590/tests/dromaeo/cssquery-dojo.html) 14:38:41 INFO - 2015-12-21 14:38:41,508 DEBUG : BROWSER_OUTPUT: RSS: Main: 235511808 14:38:41 INFO - 2015-12-21 14:38:41,509 DEBUG : BROWSER_OUTPUT: 14:38:41 INFO - 2015-12-21 14:38:41,509 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:38:41 INFO - 2015-12-21 14:38:41,510 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:38:41 INFO - 2015-12-21 14:38:41,510 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:38:41 INFO - 2015-12-21 14:38:41,510 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:38:41 INFO - 2015-12-21 14:38:41,512 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3245.464498357771;3331.650176731445;3349.581302337208;3361.109306033903;3383.627800941896;5611.382747897733;5667.5749318801045;5671.06427439472;5675.6878371689545;5685.886282274352;5710.1434784782305;5711.857203569913;5720.1991721159075;5728.198052272685;5739.397363276848;4977.776000080005;5155.871103222421;5189.169732842746;5256.526912577867;5276.683398996053;5054.115529782292;5106.744662766865;5134.897302053957;5174.637775355726;5181.663191892534;5231.11071117909;5268.8155914542995;5285.86785330366;5292.841214763564;5312.495312945264;5002.849914502571;5194.35070616173;5197.8960420791755;5261.842144735664;5288.15389537674;5025.447200807908;5111.4121875984365;5130.9738052389675;5160.870978225536;5183.196604526304;5474.37044739856;5499.120140777496;5499.697516636584;5511.035568775448;5525.917111243294;5887.764489420418;6023.518118550505;6149.754009839601;6154;6156.84607884802;6063.878722425527;6117.2184870185665;6120.602160859575;6144.416280453394;6144.600600960924;5730.111832665943;5731.025725626653;5731.770729170787;5746.942530574683;5765.164051212592;6078.422549857756;6137.41694539018;6158.599691020069;6221.875562488815;6224.875502489925;6004.249468816398;6007.759689612367;6013.939860601426;6014.578979471483;6021.819345419644;6101.298350689639;6102.7253773579405;6105.938940610537;6106;6113.205283313141;5865.178874957509;5942.3166335871065;6006.249218847644;6075.635461872302;6117.156564497972;6073.696315184223;6073.817785466443;6079.300880398722;6084.269887613515;6089.69551522431;4635.976820115945;4647.767611619473;4649.907001859944;4650.4186976627925;4664.020555683277;4059.918801623951;4063.2482990646236;4120.526139493936;4122;4130.586941305845;5801.680907550125;5852.882942341129;5855.941440585625;5860.560457965669;5862.413758624189;6000.279966404059;6119.326874043852;6142.447179753844;6172.351903050115;6180.845478863064;5736.741846616912;5786.913196302059;5802.82591522255;5815.127730840408;5832.504237139806;5317.521423071942;5336.146216605324;5344.305240318811;5344.599155063386;5348.5186333230195;6049.969750151221;6117.482447592652;6352.078948552434;6359.713812878432;6366.427021568081;5980.641161530322;6081.756729730761;6081.756729730938;6082.148499210026;6098.0548015055965;6314.590121334428;6377.4579160771855;6382.90425643625;6384.3934826191435;6385.584936979081;5907.70461476933;6018.21763170785;6065.60573562717;6133.172021777182;6143.354947730602;6168.383161683975;6183.752649893954;6185.165002724574;6190.690465476618;6191.752329906753;6003.189569408074;6004.249468816398;6009.849753756016;6037.0642550404755;6046.546509011754;5864.882702346014;5914.497267732291;5957.940420595739;6010.3088144863905;6038.849028774316;5914.556408269311;5932.822015339547;5941.0197317442135;5943.346231914573;5946.583739138218;6013.669248191217;6019.367966363468;6045.214122164267;6046.274447066205;6050.909236361546;5648.039833228278;5752.07966725322;5789.102689083199;5891.911621325769;5909.763609455574 14:38:41 INFO - 2015-12-21 14:38:41,513 DEBUG : BROWSER_OUTPUT: |1;ext.html;588.838069530879;627.3381582430536;634.324444466643;669.4577392312227;672.3041651890288;44484.555154448484;45335;45390.0921981561;45460.09079818406;45466.863328416766;47977.76011119948;48493;48569.27146092812;48616.51383486164;48631.27053094206;8242.093369729326;8382.413231073828;9181.357304988636;10410.63562775303;10535.209859260547;10655;10683.572657093746;10755.83866242007;10775.299605525655;10778.784424311509;10861.782764344709;10928.125749940025;10993.945030274837;10999.780004399907;11001;10754;10775.137988960903;10822.72943176423;10847.945760271148;10849.457527123612;10661;10745.83881241782;10783.514741836558;10803.297785643908;10807.243492955497;44858;45082.77458612719;45245;45294.32058519125;45298;3603.72972027099;3756.924861502782;3797.9810100949594;3827.8468861245797;4002.5397079335958;34525.48211776825;34809.825950870334;34856.12859678503;35064.82467587671;35117.64882351195;3804.258169656918;3831.7126215533945;3865.091703449687;3915.7846318452475;3930.3318435865685;307908;315877;316892;317196;317219.4139029313;65638.67180664066;66692.33307666861;73390.26609733941;74180;80732.17437389781;79012.20987790049;79866.6006669963;80585;80787.19212807914;80810;17959.551011224823;18217.18022688982;18232.08839558017;18236.45290641283;18241.543961400806;13462.192268463923;13572.117812342167;13576.660583485493;13582.660433489242;13582.932085339708;24698.259052228463;24775.876120619643;24886.626700599507;25087.62368564473;25096.4980700385;9487.525623718786;9608.231341492663;9683.806323873483;9717.6598819041;9736.464494452874;1025.7230547752215;1100.1638754546602;1133.7619099988929;1152.256794367645;1215.4469716279073;29975.25061873427;30092.849535752182;30361.392772144874;30880.69119308823;31114.68885311163;1111.1721767283425;1122.0462606784167;1170.0581032269106;1201.1351826684772;1285.2866659004342;14098.718025639635;15241.542753717406;15524.611884702746;15923.442679506394;16340.428085017205;430231;435974;436308;436454;436959;57920.68317267347;58379;58480.70759646175;58569.70715146482;58624.41375586275;1329.71411146602;1334.6796768775423;1450.2023886862185;1462.8829693624698;1487.2340744516582;30362.848185759813;30433.847830760704;30521.389572207987;31401.37197256088;31792.36415271728;49338.506614933394;49751;49884;49934.75032624813;49949.500504995936;282229;282665;285361;285629;286035;36240.63759362478;36960.815195923846;37619.435708464945;37703.434448483844;37707.2458550822;46491.76754116208;46728.76635616936;47304;48448.757756210995;48647.27029094637;38891.80554097211;38960.610393895695;39702.404463932486;39923.20153596854;40055;40398.19203615852;40488.79755601203;40732.38901416422;40829.183416332104;46292.53707463017;40980.38529422002;41567.376489353286;41715.79142104391;41858.790706046275;42236.155276893674;35819.82090089533;36620.81689591535;39568;41387.1722565553;41773.164536708486;27776.861115694293;27805.860970695016;28088.438231234853;28221.858890705415;28283 14:38:41 INFO - 2015-12-21 14:38:41,514 DEBUG : BROWSER_OUTPUT: |2;jquery.html;362.8222171136142;365.4116871836343;366.3570433888525;366.85142517280497;368.50068157646405;14817.18505482198;17158.22787974541;18447.446576602706;18818.623627527442;19359.225630974743;19064;19514.31699890504;19552.706709399336;19555.315563955264;19755.802441975575;5780.797672081468;5922.733476993524;5936.821895343147;5943.88112237755;5951.315598706139;4436.112777444508;4564.566366195206;5851.473367396933;6402.199725034371;6648;6378.681065946707;6469.223693156804;6493.93506064938;6573;6576.046473261375;6422.550421470499;6434.324395938406;6450.4839612830865;6456.0961465394885;6465.935340646581;5532.197831314477;5610.747516361774;5618;5619.522340601013;5649.209110724501;23202.65196022061;23278.41803954898;23550.64674029891;23626.291211263517;23724.525509489715;2240.630296001174;2342.461233916209;2352.4942137440535;2410.349205714447;3066.620020990541;10466.1627069835;10473.63342283024;10684.732881678021;14356;15239.923800380928;1801.6576850398383;1821.9908900455414;1825.589242420458;1848.5563464768493;1886.7735871695484;68881.9999999995;69351.65324173398;69604.30395696028;70741.29258707399;71844;7377.926220737724;7419.517731347445;7494.28804263605;7496.850062998818;7517.924820751832;8626.913730862736;8637.136286371438;8658.437201581877;8674.739757807276;8681.175288347597;6119.84700382494;6123.601965872204;6159.414855588801;6187.978983467768;6209.9379006210265;6482.902756458657;6508.837279068061;6576.309487503839;6589.9341006590275;6592.604443733391;3377.341418423433;3420.794752314819;3476.165720227127;3498.317828023562;3502.8949131526097;1198.6284251881943;1207.1127721124967;1208.7582483503334;1232.3714905398133;1257.2330878164312;499.38824939448887;516.1535082464761;526.3026489900897;542.6011881267266;559.158466507903;11643.53425862973;12205.20666156697;12831.10182287231;12862.742745145044;13009;506.27349753104414;508.453412581469;515.1397166731567;523.4059342646124;541.2125357604676;6320.209973753281;6421.4541763949655;6543.5419520633095;6561.442277406474;6633.2703402625675;580444;593859;595116;602629;606864;82772.58613706892;84980.57509712412;87079.56460217659;87478.56260718784;88953;548.569373042168;560.7476635514035;561.0238185557101;562.3111688181945;562.6820846221872;12113.454894529592;12115.454804533994;12285.815712764494;12488.937555312528;12491.75016499683;37879;38250;38596.6140338593;39521.20957580775;39553.802230989815;52271.47728522666;52598.737006314725;53773.193402098215;53862;54313;7159.140903091662;7218.27817218274;7224.674889630084;7233.276672332725;7256.2743725627015;11316.6039188628;12315.568955086528;13413.396397161956;13486.190828550318;13547.119437236548;11591.362475063952;11776.941115294369;11991.22057066288;11993.700157496132;12000.339981300764;11516.94241528787;11585.710357241136;11596.710082248012;11697.590584329508;11758.470868811095;11357.4889129991;11370.658880233606;11440.82838757436;11519;12054.276743395425;7134.643267836691;7243.492955493065;7316.524425912298;7338.816529586803;7343.412526997934;12122.090843186972;12269.75460490803;12473.126881118234;12486.12597118193;12545.184563003375 14:38:41 INFO - 2015-12-21 14:38:41,516 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1553.7358649029663;1559.7660350947363;1807.6836553603116;1865.4776662534491;1868.411450393126;7169.175544812349;7306.926930730691;7651.617419129049;7814.92185078149;7830.725924592641;7487.85024299515;7705.499142555744;7806.102298235704;7813.3749300056;7815.609219539028;2312.109837712476;2363.621820508719;2365.44412063165;2365.8935347909387;2368.336865677613;2067.9483012924647;2103.652897271956;2266.7279926408855;2314.351981445198;2363.5745565798147;2302.7582103879104;2305.204704376996;2307.526956973816;2311.722593288799;2313.826463015272;2334.2297041976108;2365.042157926043;2365.3258821235863;2366.219147681261;2374.41826752445;2322.442613772699;2364.7635236476385;2365.1721897335965;2374.7625237476286;2378.595638741418;7849.254320839511;7905.881411778828;7910.841783164362;7925;7930.048394192734;4840.370751802243;4918.262260660894;4920.778564964549;4924.359833221658;4927.6550641455115;3242.8702851885896;3362.3107263011025;3367.7305815534696;3433;3434.4848272759036;1138.9772204555945;1147.294413935432;1151.83874257604;1158.571328608412;1169.0589075793982;10676.572937082507;10677.572897084106;10686.893131068668;10698.625548105856;10705.411202383788;4403.383526306319;4407.140607581491;4450.955490445119;4486.147631949919;4486.237339652267;9188.90811091894;9215.44707317563;9216.17054465088;9219.170274675178;9227.492487913096;4935.3090567320605;4941.48114447985;4945.282933974626;4945.9752701236985;4951.70289782607;4549.6360291176625;4556.11155824611;4558.361829343895;4559.247724125548;4560.996580752229;3726.5341832270965;3794.79128647927;3808.352580061396;3813.9237215256094;3863.285292220948;1105.6848798092506;1183.9940800295944;1212.7695737809788;1221.6762557922157;1234.8394708687813;1212.7150119721857;1230.477047254913;1238.3993763024926;1482.4959513765157;1503.0155248312374;7562.243775622504;7579.204183560647;7657.540547567164;7658.119316278588;7659.348955338859;1200.7538454616783;1205.421397729095;1209.625016244967;1219.609724888045;1226.1662069792626;5861.3552509223955;5903.291605007426;5913.822585322448;5914.8817023659285;5918;9082.772930676785;9522.90477095234;9535.23718102564;9537.761555961157;9538.236941044699;9389.29580281468;9406.247500199968;9427.717168484956;9428.198603118812;9442.197413219954;7031.296870312928;7380.926190738024;7394.186639469654;7397.482176247719;7403.148637906602;7165.426765858823;7218.855622887408;7227.060482137288;7227.457940654577;7228.819279518054;8412.579371031547;8532.829343412974;8575.31397488212;8588.484690918565;8588.785280368042;8553.101924298107;8607.913920860712;8628.39601227908;8643.740687779376;8649.308055355683;7109.431245500244;7164.605946672983;7177.031100801325;7193.748218812317;7195.71217151329;6807.5575087619145;7454.813629659302;7493.40052795786;7501.924980750123;7505.512141710771;6807;6960.547564408297;7402.666879990521;7448.441366897396;7459.440541959266;7043.647817609201;7078.964605177147;7120.430365570845;7178.892316615359;7364.41084713211;7146.821329466805;7240.674169662272;7277.090363704537;7359.227281135618;7374.33630973215;6633.535652504472;6886.552374095668;6916.896246556205;6918.515703900679;6926.792196234121;6947.652617369213;6949.895751563629;6951.339622735832;7039.6832142552685;7071 14:38:41 INFO - 2015-12-21 14:38:41,516 DEBUG : BROWSER_OUTPUT: |4;prototype.html;354.81726910641015;359.1183644153603;359.5487662982957;365.2548800447088;366.73961487344;5980.760769569218;5991.880162396755;5993.910091348634;6004.099385092239;6062.757489700418;5740.684262365568;5832.56255780816;5835.5915085944;5917.349091599931;5930.822075337746;4636.930446043312;4657.88355291117;4666.346711460398;4671.369365135714;4687.882802929928;4635.559621835921;4640.721556706609;4651.999999999983;4665.393498845146;4665.743384113873;4486.349479325512;4569.268916973285;4605.1710692075585;4644.883877903046;4661.580457758818;4633.768311584441;4634.814607415699;4650.348951146842;4651.279051746985;4663.720176789404;4150.128473020669;4150.83396664133;4156.56356082613;4161.812718427678;4163.75017498951;5609.837353501299;5673.432656734335;5674.063779476382;5687.800926967578;5695.259616249861;5473.726313684339;5487.533559647395;5492.945070549324;5494.011078005958;5496.395396506381;6356.364363563653;6389.201349831271;6393.328700486475;6396.360363963613;6399.97600383941;4638.628909687217;4638.909856183795;4641.257398816173;4641.698289611165;4643.558861908147;6482.67586620667;6605.867882642368;6606.966965165191;6618.503612229054;6622.072909792633;4710.29345598163;4710.646701497401;4712.293156026555;4713.2458806590785;4729.456112547033;4763.356946812205;4777.9010827509865;4778.187708089633;4781.450133234653;4781.904361912742;5399.163129714901;5402.594805389612;5404.54061404785;5404.594655400861;5472.890542189134;6272.3100458949475;6349.9682501587195;6351.333110023474;6363.777267795697;6418.26189988148;1680.3362671744592;1698.0575780441727;1700.7108791505473;1702.719051356537;1702.8467437930647;1136.1422126294594;1136.783641503583;1137.766757814646;1140;1161.3670549550516;6228.626282423069;6230.532710046765;6238.688065596702;6239.719212635442;6240.968795155995;6618.900716489256;6625.105610742585;6632.071509988606;6634.668266586747;6635.071090047397;6103.725332360054;6181.876362472815;6191.84520386994;6298.811035668846;6299.118123462719;3611.747177697536;3616.5117709108936;3635.2729454109285;3638.526991491089;3640.3083414151756;6771.8984215236815;6774.220964589135;6777.220619628707;6782.525223234326;6782.525223234326;6564.704588293538;6803.387695107464;6803.7618683345845;6811.284815094443;6812.48906332027;6829.214640316427;6837.35045170708;6839;6842.315768423217;6856.417204537571;6639.7012134455035;6647.567908085959;6650;6650.600963942179;6655.534112612071;6394.200724909387;6506.479481641362;6507.219133703986;6513.446357059703;6519.674016299261;6707.228668703162;6712.261651218264;6762.019507171334;6766.627835469097;6772.796816095525;4506.954930450654;4564.589186973056;4564.817407303671;4569.13186494565;4569.885752856205;6516.315786842313;6583.30875258091;6602.933970660424;6611.66941652925;6613.867722645424;6424.614523128627;6444.967775161094;6446.09754634361;6448.322926092693;6456.54804163704;6425.293217746138;6434.806955791334;6440.420362167428;6440.452561532322;6443.420092191725;6238.656873871799;6311.715972781144;6323.7786677466065;6332.398422149889;6334.303226645157;6455.644939528183;6459.61242325462;6460.612363258219;6460.903086453613;6462.483001359974;6196.721147548281;6290.811275661737;6305.5901366411035;6307.05394190875;6308.274548426806 14:38:41 INFO - 2015-12-21 14:38:41,517 DEBUG : BROWSER_OUTPUT: |5;yui.html;932.7108596335132;938.2540880000399;951.8143961927427;958.0993865766181;963.8795150606176;14894.404223831047;15025.549233522997;15176.62058448538;15856.207189640501;15868.444604438848;15093.698126037472;15165.469208577702;15187.46843860465;15187.620309492268;15187.84812151881;781.2304879693497;785.6896525872277;935.3265648732936;940.6378544260458;956.3592393096642;945.6973768394082;955.8518429643382;957.602594923106;964;966.3718582921123;978.3933960944224;999.5701848205268;999.9900001000007;1000;1000.2698030437784;806.6813608624619;810.9310708589777;970.7039352997368;985.935914165577;986.921046316293;725.662566906391;731.4331393170282;733.1056111543961;733.1751779248345;734.717133903451;14900.478483253142;15043.398264069425;15051.849481505264;15054.623634409118;15060.924695376563;13191.472341106344;13333.733325333438;13359.86640133596;13524.864751352558;13981.650458738512;11398;11540.538378464935;11621.941890290578;11689;11755.647330580096;3728.5898551159353;3814.1608846053823;3844.0774214188395;3889.946392082202;3918.784466854322;54015.18977215345;54193;54522.18216726752;54760;55000;9725.319227653996;9997.900020999843;10632.521536530874;10732.141428685687;10876.945615271872;18644.161012754186;18721.438356849318;18727;18730.625387492448;18765.343212987755;7635.923640763522;8025.558594277254;8063.314618257396;8081.030276366756;8096.51420914747;4786.521347865186;4835.3714017178245;4886.291487734258;4910.459849416561;4938.185199442124;3252.154439845657;3283.9835800820842;3284.064041748138;3290.44062509374;3294.275259442919;512.8307658472695;527.1802347349831;527.7150338816996;529.1031701266394;539.2989114151587;635.2472320300459;661.0795638669672;735.2243383230743;742.8997085393511;758.2076729817354;9037.68368107113;9610.231181505464;9669.564869580885;9766.560504777302;9972.551235194434;668.3383450384084;683.111954459201;685.0751485494642;729.8686236477484;765.6937225109889;3857.2478366718783;4120.381942708617;4147.419361289422;4206.621404073648;4243.172581346607;69416;70511.64744176246;71377.2862271381;71420;71500.2849971504;60277.39722602806;60738;61027.38972610306;61027.38972610306;62037.37962620406;54094.729526352115;54240;54586;54855.177172342446;54921.45078549243;8366.24703776663;8396.244338009608;8697.217250447236;9336.673216437392;9538.093881081286;40004.1999160021;40559.18881622292;41551.7922410386;42307.3653895198;42377.152456951306;55436;55614.16578751241;55703.721481392335;55914.72042639761;56589;8483.872741908754;8604.913950860662;8765.561721914006;8801.867971980297;8831.735047948572;13867.167969921837;14210.889317593534;14253.144811311355;14406.927965360106;14600.561983140522;12512.124151309685;12864.421101050288;12927.224366538037;13386.183127520588;13707.657308567366;12922.741545169232;13028.218306901616;13208.27354495512;13307.534236301683;13446.193228406328;12719.55481558141;12773.808392873929;13204.867951320364;13233.404496797475;13345.733085338434;10444;11371.260868043883;11401.885981140415;11624.128190385924;11832.940835295769;10655.680329590125;11004.339739615649;11077.335359878432;11095.944520277348;11151.10791136691 14:38:41 INFO - 2015-12-21 14:38:41,517 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:38:41 INFO - 2015-12-21 14:38:41,517 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:38:41 INFO - 2015-12-21 14:38:41,517 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,848 14:38:41 INFO - 2015-12-21 14:38:41,517 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:38:41 INFO - 2015-12-21 14:38:41,518 DEBUG : BROWSER_OUTPUT: __startTimestamp1450737521508__endTimestamp 14:38:41 INFO - 2015-12-21 14:38:41,518 DEBUG : BROWSER_OUTPUT: 14:38:41 INFO - 2015-12-21 14:38:41,518 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:38:41 INFO - 2015-12-21 14:38:41,518 DEBUG : BROWSER_OUTPUT: Number of tests: 6 14:38:41 INFO - 2015-12-21 14:38:41,518 DEBUG : BROWSER_OUTPUT: 14:38:41 INFO - 2015-12-21 14:38:41,518 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5664.48 Median:5907.70 stddev:646.28 (10.9%) stddev-sans-first:619.78 14:38:41 INFO - 2015-12-21 14:38:41,518 DEBUG : BROWSER_OUTPUT: Values: 3245.5 3331.7 3349.6 3361.1 3383.6 5611.4 5667.6 5671.1 5675.7 5685.9 5710.1 5711.9 5720.2 5728.2 5739.4 4977.8 5155.9 5189.2 5256.5 5276.7 5054.1 5106.7 5134.9 5174.6 5181.7 5231.1 5268.8 5285.9 5292.8 5312.5 5002.8 5194.4 5197.9 5261.8 5288.2 5025.4 5111.4 5131.0 5160.9 5183.2 5474.4 5499.1 5499.7 5511.0 5525.9 5887.8 6023.5 6149.8 6154.0 6156.8 6063.9 6117.2 6120.6 6144.4 6144.6 5730.1 5731.0 5731.8 5746.9 5765.2 6078.4 6137.4 6158.6 6221.9 6224.9 6004.2 6007.8 6013.9 6014.6 6021.8 6101.3 6102.7 6105.9 6106.0 6113.2 5865.2 5942.3 6006.2 6075.6 6117.2 6073.7 6073.8 6079.3 6084.3 6089.7 4636.0 4647.8 4649.9 4650.4 4664.0 4059.9 4063.2 4120.5 4122.0 4130.6 5801.7 5852.9 5855.9 5860.6 5862.4 6000.3 6119.3 6142.4 6172.4 6180.8 5736.7 5786.9 5802.8 5815.1 5832.5 5317.5 5336.1 5344.3 5344.6 5348.5 6050.0 6117.5 6352.1 6359.7 6366.4 5980.6 6081.8 6081.8 6082.1 6098.1 6314.6 6377.5 6382.9 6384.4 6385.6 5907.7 6018.2 6065.6 6133.2 6143.4 6168.4 6183.8 6185.2 6190.7 6191.8 6003.2 6004.2 6009.8 6037.1 6046.5 5864.9 5914.5 5957.9 6010.3 6038.8 5914.6 5932.8 5941.0 5943.3 5946.6 6013.7 6019.4 6045.2 6046.3 6050.9 5648.0 5752.1 5789.1 5891.9 5909.8 14:38:41 INFO - 2015-12-21 14:38:41,518 DEBUG : BROWSER_OUTPUT: 14:38:41 INFO - 2015-12-21 14:38:41,519 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54202.91 Median:36790.82 stddev:92135.71 (250.4%) stddev-sans-first:92306.24 14:38:41 INFO - 2015-12-21 14:38:41,520 DEBUG : BROWSER_OUTPUT: Values: 588.8 627.3 634.3 669.5 672.3 44484.6 45335.0 45390.1 45460.1 45466.9 47977.8 48493.0 48569.3 48616.5 48631.3 8242.1 8382.4 9181.4 10410.6 10535.2 10655.0 10683.6 10755.8 10775.3 10778.8 10861.8 10928.1 10993.9 10999.8 11001.0 10754.0 10775.1 10822.7 10847.9 10849.5 10661.0 10745.8 10783.5 10803.3 10807.2 44858.0 45082.8 45245.0 45294.3 45298.0 3603.7 3756.9 3798.0 3827.8 4002.5 34525.5 34809.8 34856.1 35064.8 35117.6 3804.3 3831.7 3865.1 3915.8 3930.3 307908.0 315877.0 316892.0 317196.0 317219.4 65638.7 66692.3 73390.3 74180.0 80732.2 79012.2 79866.6 80585.0 80787.2 80810.0 17959.6 18217.2 18232.1 18236.5 18241.5 13462.2 13572.1 13576.7 13582.7 13582.9 24698.3 24775.9 24886.6 25087.6 25096.5 9487.5 9608.2 9683.8 9717.7 9736.5 1025.7 1100.2 1133.8 1152.3 1215.4 29975.3 30092.8 30361.4 30880.7 31114.7 1111.2 1122.0 1170.1 1201.1 1285.3 14098.7 15241.5 15524.6 15923.4 16340.4 430231.0 435974.0 436308.0 436454.0 436959.0 57920.7 58379.0 58480.7 58569.7 58624.4 1329.7 1334.7 1450.2 1462.9 1487.2 30362.8 30433.8 30521.4 31401.4 31792.4 49338.5 49751.0 49884.0 49934.8 49949.5 282229.0 282665.0 285361.0 285629.0 286035.0 36240.6 36960.8 37619.4 37703.4 37707.2 46491.8 46728.8 47304.0 48448.8 48647.3 38891.8 38960.6 39702.4 39923.2 40055.0 40398.2 40488.8 40732.4 40829.2 46292.5 40980.4 41567.4 41715.8 41858.8 42236.2 35819.8 36620.8 39568.0 41387.2 41773.2 27776.9 27805.9 28088.4 28221.9 28283.0 14:38:41 INFO - 2015-12-21 14:38:41,520 DEBUG : BROWSER_OUTPUT: 14:38:41 INFO - 2015-12-21 14:38:41,520 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30362.50 Median:515.65 stddev:97677.21 (18942.7%) stddev-sans-first:97925.24 14:38:41 INFO - 2015-12-21 14:38:41,521 DEBUG : BROWSER_OUTPUT: Values: 362.8 365.4 366.4 366.9 368.5 14817.2 17158.2 18447.4 18818.6 19359.2 19064.0 19514.3 19552.7 19555.3 19755.8 5780.8 5922.7 5936.8 5943.9 5951.3 4436.1 4564.6 5851.5 6402.2 6648.0 6378.7 6469.2 6493.9 6573.0 6576.0 6422.6 6434.3 6450.5 6456.1 6465.9 5532.2 5610.7 5618.0 5619.5 5649.2 23202.7 23278.4 23550.6 23626.3 23724.5 2240.6 2342.5 2352.5 2410.3 3066.6 10466.2 10473.6 10684.7 14356.0 15239.9 1801.7 1822.0 1825.6 1848.6 1886.8 68882.0 69351.7 69604.3 70741.3 71844.0 7377.9 7419.5 7494.3 7496.9 7517.9 8626.9 8637.1 8658.4 8674.7 8681.2 6119.8 6123.6 6159.4 6188.0 6209.9 6482.9 6508.8 6576.3 6589.9 6592.6 3377.3 3420.8 3476.2 3498.3 3502.9 1198.6 1207.1 1208.8 1232.4 1257.2 499.4 516.2 526.3 542.6 559.2 11643.5 12205.2 12831.1 12862.7 13009.0 506.3 508.5 515.1 523.4 541.2 6320.2 6421.5 6543.5 6561.4 6633.3 580444.0 593859.0 595116.0 602629.0 606864.0 82772.6 84980.6 87079.6 87478.6 88953.0 548.6 560.7 561.0 562.3 562.7 12113.5 12115.5 12285.8 12488.9 12491.8 37879.0 38250.0 38596.6 39521.2 39553.8 52271.5 52598.7 53773.2 53862.0 54313.0 7159.1 7218.3 7224.7 7233.3 7256.3 11316.6 12315.6 13413.4 13486.2 13547.1 11591.4 11776.9 11991.2 11993.7 12000.3 11516.9 11585.7 11596.7 11697.6 11758.5 11357.5 11370.7 11440.8 11519.0 12054.3 7134.6 7243.5 7316.5 7338.8 7343.4 12122.1 12269.8 12473.1 12486.1 12545.2 14:38:41 INFO - 2015-12-21 14:38:41,521 DEBUG : BROWSER_OUTPUT: 14:38:41 INFO - 2015-12-21 14:38:41,522 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5595.59 Median:6720.27 stddev:2814.97 (41.9%) stddev-sans-first:2806.48 14:38:41 INFO - 2015-12-21 14:38:41,522 DEBUG : BROWSER_OUTPUT: Values: 1553.7 1559.8 1807.7 1865.5 1868.4 7169.2 7306.9 7651.6 7814.9 7830.7 7487.9 7705.5 7806.1 7813.4 7815.6 2312.1 2363.6 2365.4 2365.9 2368.3 2067.9 2103.7 2266.7 2314.4 2363.6 2302.8 2305.2 2307.5 2311.7 2313.8 2334.2 2365.0 2365.3 2366.2 2374.4 2322.4 2364.8 2365.2 2374.8 2378.6 7849.3 7905.9 7910.8 7925.0 7930.0 4840.4 4918.3 4920.8 4924.4 4927.7 3242.9 3362.3 3367.7 3433.0 3434.5 1139.0 1147.3 1151.8 1158.6 1169.1 10676.6 10677.6 10686.9 10698.6 10705.4 4403.4 4407.1 4451.0 4486.1 4486.2 9188.9 9215.4 9216.2 9219.2 9227.5 4935.3 4941.5 4945.3 4946.0 4951.7 4549.6 4556.1 4558.4 4559.2 4561.0 3726.5 3794.8 3808.4 3813.9 3863.3 1105.7 1184.0 1212.8 1221.7 1234.8 1212.7 1230.5 1238.4 1482.5 1503.0 7562.2 7579.2 7657.5 7658.1 7659.3 1200.8 1205.4 1209.6 1219.6 1226.2 5861.4 5903.3 5913.8 5914.9 5918.0 9082.8 9522.9 9535.2 9537.8 9538.2 9389.3 9406.2 9427.7 9428.2 9442.2 7031.3 7380.9 7394.2 7397.5 7403.1 7165.4 7218.9 7227.1 7227.5 7228.8 8412.6 8532.8 8575.3 8588.5 8588.8 8553.1 8607.9 8628.4 8643.7 8649.3 7109.4 7164.6 7177.0 7193.7 7195.7 6807.6 7454.8 7493.4 7501.9 7505.5 6807.0 6960.5 7402.7 7448.4 7459.4 7043.6 7079.0 7120.4 7178.9 7364.4 7146.8 7240.7 7277.1 7359.2 7374.3 6633.5 6886.6 6916.9 6918.5 6926.8 6947.7 6949.9 6951.3 7039.7 7071.0 14:38:41 INFO - 2015-12-21 14:38:41,522 DEBUG : BROWSER_OUTPUT: 14:38:41 INFO - 2015-12-21 14:38:41,522 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5384.49 Median:6142.80 stddev:1584.63 (25.8%) stddev-sans-first:1543.45 14:38:41 INFO - 2015-12-21 14:38:41,522 DEBUG : BROWSER_OUTPUT: Values: 354.8 359.1 359.5 365.3 366.7 5980.8 5991.9 5993.9 6004.1 6062.8 5740.7 5832.6 5835.6 5917.3 5930.8 4636.9 4657.9 4666.3 4671.4 4687.9 4635.6 4640.7 4652.0 4665.4 4665.7 4486.3 4569.3 4605.2 4644.9 4661.6 4633.8 4634.8 4650.3 4651.3 4663.7 4150.1 4150.8 4156.6 4161.8 4163.8 5609.8 5673.4 5674.1 5687.8 5695.3 5473.7 5487.5 5492.9 5494.0 5496.4 6356.4 6389.2 6393.3 6396.4 6400.0 4638.6 4638.9 4641.3 4641.7 4643.6 6482.7 6605.9 6607.0 6618.5 6622.1 4710.3 4710.6 4712.3 4713.2 4729.5 4763.4 4777.9 4778.2 4781.5 4781.9 5399.2 5402.6 5404.5 5404.6 5472.9 6272.3 6350.0 6351.3 6363.8 6418.3 1680.3 1698.1 1700.7 1702.7 1702.8 1136.1 1136.8 1137.8 1140.0 1161.4 6228.6 6230.5 6238.7 6239.7 6241.0 6618.9 6625.1 6632.1 6634.7 6635.1 6103.7 6181.9 6191.8 6298.8 6299.1 3611.7 3616.5 3635.3 3638.5 3640.3 6771.9 6774.2 6777.2 6782.5 6782.5 6564.7 6803.4 6803.8 6811.3 6812.5 6829.2 6837.4 6839.0 6842.3 6856.4 6639.7 6647.6 6650.0 6650.6 6655.5 6394.2 6506.5 6507.2 6513.4 6519.7 6707.2 6712.3 6762.0 6766.6 6772.8 4507.0 4564.6 4564.8 4569.1 4569.9 6516.3 6583.3 6602.9 6611.7 6613.9 6424.6 6445.0 6446.1 6448.3 6456.5 6425.3 6434.8 6440.4 6440.5 6443.4 6238.7 6311.7 6323.8 6332.4 6334.3 6455.6 6459.6 6460.6 6460.9 6462.5 6196.7 6290.8 6305.6 6307.1 6308.3 14:38:41 INFO - 2015-12-21 14:38:41,522 DEBUG : BROWSER_OUTPUT: 14:38:41 INFO - 2015-12-21 14:38:41,522 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15900.07 Median:520.01 stddev:19228.91 (3697.8%) stddev-sans-first:19250.01 14:38:41 INFO - 2015-12-21 14:38:41,524 DEBUG : BROWSER_OUTPUT: Values: 932.7 938.3 951.8 958.1 963.9 14894.4 15025.5 15176.6 15856.2 15868.4 15093.7 15165.5 15187.5 15187.6 15187.8 781.2 785.7 935.3 940.6 956.4 945.7 955.9 957.6 964.0 966.4 978.4 999.6 1000.0 1000.0 1000.3 806.7 810.9 970.7 985.9 986.9 725.7 731.4 733.1 733.2 734.7 14900.5 15043.4 15051.8 15054.6 15060.9 13191.5 13333.7 13359.9 13524.9 13981.7 11398.0 11540.5 11621.9 11689.0 11755.6 3728.6 3814.2 3844.1 3889.9 3918.8 54015.2 54193.0 54522.2 54760.0 55000.0 9725.3 9997.9 10632.5 10732.1 10876.9 18644.2 18721.4 18727.0 18730.6 18765.3 7635.9 8025.6 8063.3 8081.0 8096.5 4786.5 4835.4 4886.3 4910.5 4938.2 3252.2 3284.0 3284.1 3290.4 3294.3 512.8 527.2 527.7 529.1 539.3 635.2 661.1 735.2 742.9 758.2 9037.7 9610.2 9669.6 9766.6 9972.6 668.3 683.1 685.1 729.9 765.7 3857.2 4120.4 4147.4 4206.6 4243.2 69416.0 70511.6 71377.3 71420.0 71500.3 60277.4 60738.0 61027.4 61027.4 62037.4 54094.7 54240.0 54586.0 54855.2 54921.5 8366.2 8396.2 8697.2 9336.7 9538.1 40004.2 40559.2 41551.8 42307.4 42377.2 55436.0 55614.2 55703.7 55914.7 56589.0 8483.9 8604.9 8765.6 8801.9 8831.7 13867.2 14210.9 14253.1 14406.9 14600.6 12512.1 12864.4 12927.2 13386.2 13707.7 12922.7 13028.2 13208.3 13307.5 13446.2 12719.6 12773.8 13204.9 13233.4 13345.7 10444.0 11371.3 11401.9 11624.1 11832.9 10655.7 11004.3 11077.3 11095.9 11151.1 14:38:41 INFO - 2015-12-21 14:38:41,524 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:38:41 INFO - 2015-12-21 14:38:41,524 DEBUG : BROWSER_OUTPUT: 14:38:42 INFO - 2015-12-21 14:38:42,037 INFO : Browser exited with error code: 0 14:38:42 INFO - 2015-12-21 14:38:42,058 INFO : Completed test dromaeo_css (00:18:29) 14:38:42 INFO - 2015-12-21 14:38:42,058 INFO : Starting test kraken 14:38:42 INFO - 2015-12-21 14:38:42,058 DEBUG : operating with platform_type : linux_ 14:38:42 INFO - 2015-12-21 14:38:42,059 INFO : Initialising browser for kraken test... 14:38:42 INFO - 2015-12-21 14:38:42,065 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp9qlpz5/profile http://localhost:48590/getInfo.html 14:38:45 INFO - 2015-12-21 14:38:45,478 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:38:45 INFO - 2015-12-21 14:38:45,479 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:38:45 INFO - 2015-12-21 14:38:45,479 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:38:45 INFO - 2015-12-21 14:38:45,479 DEBUG : BROWSER_OUTPUT: __metrics 14:38:45 INFO - 2015-12-21 14:38:45,583 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:38:46 INFO - 2015-12-21 14:38:46,729 INFO : Browser initialized. 14:38:46 INFO - 2015-12-21 14:38:46,730 INFO : Running cycle 1/1 for kraken test... 14:38:46 INFO - 2015-12-21 14:38:46,730 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp9qlpz5/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:38:58 INFO - 2015-12-21 14:38:58,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 128774144 14:38:58 INFO - 2015-12-21 14:38:58,233 DEBUG : BROWSER_OUTPUT: 14:39:00 INFO - 2015-12-21 14:39:00,668 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48590/tests/kraken/driver.html?testName=audio-beat-detection) 14:39:01 INFO - 2015-12-21 14:39:01,235 DEBUG : BROWSER_OUTPUT: RSS: Main: 162271232 14:39:01 INFO - 2015-12-21 14:39:01,235 DEBUG : BROWSER_OUTPUT: 14:39:03 INFO - 2015-12-21 14:39:03,797 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48590/tests/kraken/driver.html?testName=audio-dft) 14:39:04 INFO - 2015-12-21 14:39:04,363 DEBUG : BROWSER_OUTPUT: RSS: Main: 161447936 14:39:04 INFO - 2015-12-21 14:39:04,363 DEBUG : BROWSER_OUTPUT: 14:39:07 INFO - 2015-12-21 14:39:07,162 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48590/tests/kraken/driver.html?testName=audio-fft) 14:39:07 INFO - 2015-12-21 14:39:07,728 DEBUG : BROWSER_OUTPUT: RSS: Main: 156782592 14:39:07 INFO - 2015-12-21 14:39:07,728 DEBUG : BROWSER_OUTPUT: 14:39:09 INFO - 2015-12-21 14:39:09,691 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48590/tests/kraken/driver.html?testName=audio-oscillator) 14:39:10 INFO - 2015-12-21 14:39:10,257 DEBUG : BROWSER_OUTPUT: RSS: Main: 158203904 14:39:10 INFO - 2015-12-21 14:39:10,257 DEBUG : BROWSER_OUTPUT: 14:39:12 INFO - 2015-12-21 14:39:12,379 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48590/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:39:12 INFO - 2015-12-21 14:39:12,941 DEBUG : BROWSER_OUTPUT: RSS: Main: 156135424 14:39:12 INFO - 2015-12-21 14:39:12,941 DEBUG : BROWSER_OUTPUT: 14:39:15 INFO - 2015-12-21 14:39:15,862 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48590/tests/kraken/driver.html?testName=imaging-darkroom) 14:39:16 INFO - 2015-12-21 14:39:16,430 DEBUG : BROWSER_OUTPUT: RSS: Main: 154591232 14:39:16 INFO - 2015-12-21 14:39:16,430 DEBUG : BROWSER_OUTPUT: 14:39:19 INFO - 2015-12-21 14:39:19,764 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48590/tests/kraken/driver.html?testName=imaging-desaturate) 14:39:20 INFO - 2015-12-21 14:39:20,332 DEBUG : BROWSER_OUTPUT: RSS: Main: 154947584 14:39:20 INFO - 2015-12-21 14:39:20,332 DEBUG : BROWSER_OUTPUT: 14:39:23 INFO - 2015-12-21 14:39:23,073 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48590/tests/kraken/driver.html?testName=json-parse-financial) 14:39:23 INFO - 2015-12-21 14:39:23,639 DEBUG : BROWSER_OUTPUT: RSS: Main: 155086848 14:39:23 INFO - 2015-12-21 14:39:23,639 DEBUG : BROWSER_OUTPUT: 14:39:25 INFO - 2015-12-21 14:39:25,130 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48590/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:39:25 INFO - 2015-12-21 14:39:25,691 DEBUG : BROWSER_OUTPUT: RSS: Main: 159997952 14:39:25 INFO - 2015-12-21 14:39:25,692 DEBUG : BROWSER_OUTPUT: 14:39:27 INFO - 2015-12-21 14:39:27,414 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48590/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:39:28 INFO - 2015-12-21 14:39:28,016 DEBUG : BROWSER_OUTPUT: RSS: Main: 158896128 14:39:28 INFO - 2015-12-21 14:39:28,016 DEBUG : BROWSER_OUTPUT: 14:39:29 INFO - 2015-12-21 14:39:29,968 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48590/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:39:30 INFO - 2015-12-21 14:39:30,534 DEBUG : BROWSER_OUTPUT: RSS: Main: 163360768 14:39:30 INFO - 2015-12-21 14:39:30,535 DEBUG : BROWSER_OUTPUT: 14:39:32 INFO - 2015-12-21 14:39:32,834 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48590/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:39:33 INFO - 2015-12-21 14:39:33,415 DEBUG : BROWSER_OUTPUT: RSS: Main: 167845888 14:39:33 INFO - 2015-12-21 14:39:33,416 DEBUG : BROWSER_OUTPUT: 14:39:35 INFO - 2015-12-21 14:39:35,803 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48590/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:39:36 INFO - 2015-12-21 14:39:36,369 DEBUG : BROWSER_OUTPUT: RSS: Main: 166850560 14:39:36 INFO - 2015-12-21 14:39:36,369 DEBUG : BROWSER_OUTPUT: 14:39:37 INFO - 2015-12-21 14:39:37,990 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48590/tests/kraken/driver.html?testName=ai-astar) 14:39:38 INFO - 2015-12-21 14:39:38,241 DEBUG : BROWSER_OUTPUT: RSS: Main: 224845824 14:39:38 INFO - 2015-12-21 14:39:38,241 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,241 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:39:38 INFO - 2015-12-21 14:39:38,242 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:39:38 INFO - 2015-12-21 14:39:38,242 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:39:38 INFO - 2015-12-21 14:39:38,243 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:39:38 INFO - 2015-12-21 14:39:38,243 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;109;105;109;111;111;100;110;110;106 14:39:38 INFO - 2015-12-21 14:39:38,244 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;122;150;159;130;168;153;127;157;124 14:39:38 INFO - 2015-12-21 14:39:38,244 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;160;160;159;159;163;158;163;161;162 14:39:38 INFO - 2015-12-21 14:39:38,244 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;70;146;70;72;70;70;147;71 14:39:38 INFO - 2015-12-21 14:39:38,244 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;90;141;89;89;90;131;88;89;135 14:39:38 INFO - 2015-12-21 14:39:38,244 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;110;109;110;110 14:39:38 INFO - 2015-12-21 14:39:38,244 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;145;146;146;145;145;146;146;145 14:39:38 INFO - 2015-12-21 14:39:38,244 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;96;96;96;96;95 14:39:38 INFO - 2015-12-21 14:39:38,245 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;54;56;55;55;55;57;55;52;53 14:39:38 INFO - 2015-12-21 14:39:38,245 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;64;63;64;63;64;63;64;64 14:39:38 INFO - 2015-12-21 14:39:38,245 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;77;77;79;79;77;78;78;78;77 14:39:38 INFO - 2015-12-21 14:39:38,245 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;115;111;111;116;111;111;112;114;113 14:39:38 INFO - 2015-12-21 14:39:38,245 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;135;133;136;134;134;135;128;135;140 14:39:38 INFO - 2015-12-21 14:39:38,245 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;60;62;62;62;62;62;63;61;61;62 14:39:38 INFO - 2015-12-21 14:39:38,245 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:39:38 INFO - 2015-12-21 14:39:38,246 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:39:38 INFO - 2015-12-21 14:39:38,246 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,889 14:39:38 INFO - 2015-12-21 14:39:38,246 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:39:38 INFO - 2015-12-21 14:39:38,246 DEBUG : BROWSER_OUTPUT: __startTimestamp1450737578241__endTimestamp 14:39:38 INFO - 2015-12-21 14:39:38,246 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,250 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:39:38 INFO - 2015-12-21 14:39:38,250 DEBUG : BROWSER_OUTPUT: Number of tests: 14 14:39:38 INFO - 2015-12-21 14:39:38,250 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,250 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.00 Median:109.50 stddev:3.43 (3.1%) stddev-sans-first:3.62 14:39:38 INFO - 2015-12-21 14:39:38,250 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 105.0 109.0 111.0 111.0 100.0 110.0 110.0 106.0 14:39:38 INFO - 2015-12-21 14:39:38,251 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,251 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.90 Median:151.50 stddev:16.60 (11.0%) stddev-sans-first:17.51 14:39:38 INFO - 2015-12-21 14:39:38,251 DEBUG : BROWSER_OUTPUT: Values: 149.0 122.0 150.0 159.0 130.0 168.0 153.0 127.0 157.0 124.0 14:39:38 INFO - 2015-12-21 14:39:38,251 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,251 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.78 (1.1%) stddev-sans-first:1.81 14:39:38 INFO - 2015-12-21 14:39:38,252 DEBUG : BROWSER_OUTPUT: Values: 159.0 160.0 160.0 159.0 159.0 163.0 158.0 163.0 161.0 162.0 14:39:38 INFO - 2015-12-21 14:39:38,252 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,252 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:85.80 Median:70.50 stddev:32.00 (45.4%) stddev-sans-first:33.49 14:39:38 INFO - 2015-12-21 14:39:38,252 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 70.0 146.0 70.0 72.0 70.0 70.0 147.0 71.0 14:39:38 INFO - 2015-12-21 14:39:38,252 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,253 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.10 Median:89.00 stddev:22.61 (25.4%) stddev-sans-first:23.39 14:39:38 INFO - 2015-12-21 14:39:38,253 DEBUG : BROWSER_OUTPUT: Values: 89.0 90.0 141.0 89.0 89.0 90.0 131.0 88.0 89.0 135.0 14:39:38 INFO - 2015-12-21 14:39:38,253 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,253 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 14:39:38 INFO - 2015-12-21 14:39:38,253 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 110.0 110.0 14:39:38 INFO - 2015-12-21 14:39:38,253 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,253 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 14:39:38 INFO - 2015-12-21 14:39:38,253 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 145.0 146.0 146.0 145.0 145.0 146.0 146.0 145.0 14:39:38 INFO - 2015-12-21 14:39:38,253 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,253 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.43 (2.6%) stddev-sans-first:1.50 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: Values: 54.0 54.0 56.0 55.0 55.0 55.0 57.0 55.0 52.0 53.0 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:64.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 64.0 63.0 64.0 63.0 64.0 63.0 64.0 64.0 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.80 Median:78.00 stddev:0.79 (1.0%) stddev-sans-first:0.83 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: Values: 78.0 77.0 77.0 79.0 79.0 77.0 78.0 78.0 78.0 77.0 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.20 Median:113.50 stddev:2.49 (2.2%) stddev-sans-first:1.94 14:39:38 INFO - 2015-12-21 14:39:38,254 DEBUG : BROWSER_OUTPUT: Values: 118.0 115.0 111.0 111.0 116.0 111.0 111.0 112.0 114.0 113.0 14:39:38 INFO - 2015-12-21 14:39:38,255 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,255 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.70 Median:135.00 stddev:3.06 (2.3%) stddev-sans-first:3.13 14:39:38 INFO - 2015-12-21 14:39:38,255 DEBUG : BROWSER_OUTPUT: Values: 137.0 135.0 133.0 136.0 134.0 134.0 135.0 128.0 135.0 140.0 14:39:38 INFO - 2015-12-21 14:39:38,255 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,255 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:0.82 (1.3%) stddev-sans-first:0.60 14:39:38 INFO - 2015-12-21 14:39:38,255 DEBUG : BROWSER_OUTPUT: Values: 60.0 62.0 62.0 62.0 62.0 62.0 63.0 61.0 61.0 62.0 14:39:38 INFO - 2015-12-21 14:39:38,255 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:39:38 INFO - 2015-12-21 14:39:38,255 DEBUG : BROWSER_OUTPUT: 14:39:38 INFO - 2015-12-21 14:39:38,899 INFO : Browser exited with error code: 0 14:39:38 INFO - 2015-12-21 14:39:38,951 INFO : Completed test kraken (00:00:56) 14:39:38 INFO - 2015-12-21 14:39:38,951 INFO : Starting test v8_7 14:39:38 INFO - 2015-12-21 14:39:38,951 DEBUG : operating with platform_type : linux_ 14:39:38 INFO - 2015-12-21 14:39:38,951 INFO : Initialising browser for v8_7 test... 14:39:38 INFO - 2015-12-21 14:39:38,958 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFADZRX/profile http://localhost:48590/getInfo.html 14:39:42 INFO - 2015-12-21 14:39:42,413 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:39:42 INFO - 2015-12-21 14:39:42,413 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:39:42 INFO - 2015-12-21 14:39:42,413 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:39:42 INFO - 2015-12-21 14:39:42,413 DEBUG : BROWSER_OUTPUT: __metrics 14:39:42 INFO - 2015-12-21 14:39:42,534 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:39:43 INFO - 2015-12-21 14:39:43,653 INFO : Browser initialized. 14:39:43 INFO - 2015-12-21 14:39:43,654 INFO : Running cycle 1/1 for v8_7 test... 14:39:43 INFO - 2015-12-21 14:39:43,654 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFADZRX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:39:55 INFO - 2015-12-21 14:39:55,251 DEBUG : BROWSER_OUTPUT: RSS: Main: 128417792 14:39:55 INFO - 2015-12-21 14:39:55,251 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,652 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48590/tests/v8_7/run.html (next: http://localhost:48590/tests/v8_7/run.html) 14:40:16 INFO - 2015-12-21 14:40:16,911 DEBUG : BROWSER_OUTPUT: RSS: Main: 426594304 14:40:16 INFO - 2015-12-21 14:40:16,911 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,911 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:40:16 INFO - 2015-12-21 14:40:16,911 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:40:16 INFO - 2015-12-21 14:40:16,912 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:40:16 INFO - 2015-12-21 14:40:16,912 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:40:16 INFO - 2015-12-21 14:40:16,912 DEBUG : BROWSER_OUTPUT: |0;Richards;163.98819285011479 14:40:16 INFO - 2015-12-21 14:40:16,912 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.50298380221653 14:40:16 INFO - 2015-12-21 14:40:16,912 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.2516056967328 14:40:16 INFO - 2015-12-21 14:40:16,913 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5035.175879396985 14:40:16 INFO - 2015-12-21 14:40:16,913 DEBUG : BROWSER_OUTPUT: |4;RayTrace;946.969696969697 14:40:16 INFO - 2015-12-21 14:40:16,913 DEBUG : BROWSER_OUTPUT: |5;Earley;553.4034311012729 14:40:16 INFO - 2015-12-21 14:40:16,913 DEBUG : BROWSER_OUTPUT: |6;Boyer;10902.173913043478 14:40:16 INFO - 2015-12-21 14:40:16,913 DEBUG : BROWSER_OUTPUT: |7;RegExp;23674.418604651164 14:40:16 INFO - 2015-12-21 14:40:16,913 DEBUG : BROWSER_OUTPUT: |8;Splay;546.7468562055768 14:40:16 INFO - 2015-12-21 14:40:16,913 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: __startTimestamp1450737616904__endTimestamp 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: Number of tests: 10 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.99 Median:163.99 stddev:0.00 (0.0%) 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: Values: 164.0 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.50 Median:170.50 stddev:0.00 (0.0%) 14:40:16 INFO - 2015-12-21 14:40:16,914 DEBUG : BROWSER_OUTPUT: Values: 170.5 14:40:16 INFO - 2015-12-21 14:40:16,915 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,915 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.25 Median:279.25 stddev:0.00 (0.0%) 14:40:16 INFO - 2015-12-21 14:40:16,915 DEBUG : BROWSER_OUTPUT: Values: 279.3 14:40:16 INFO - 2015-12-21 14:40:16,915 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,915 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5035.18 Median:5035.18 stddev:0.00 (0.0%) 14:40:16 INFO - 2015-12-21 14:40:16,915 DEBUG : BROWSER_OUTPUT: Values: 5035.2 14:40:16 INFO - 2015-12-21 14:40:16,915 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,915 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:946.97 Median:946.97 stddev:0.00 (0.0%) 14:40:16 INFO - 2015-12-21 14:40:16,915 DEBUG : BROWSER_OUTPUT: Values: 947.0 14:40:16 INFO - 2015-12-21 14:40:16,915 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:553.40 Median:553.40 stddev:0.00 (0.0%) 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: Values: 553.4 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10902.17 Median:10902.17 stddev:0.00 (0.0%) 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: Values: 10902.2 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23674.42 Median:23674.42 stddev:0.00 (0.0%) 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: Values: 23674.4 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:546.75 Median:546.75 stddev:0.00 (0.0%) 14:40:16 INFO - 2015-12-21 14:40:16,916 DEBUG : BROWSER_OUTPUT: Values: 546.7 14:40:16 INFO - 2015-12-21 14:40:16,917 DEBUG : BROWSER_OUTPUT: 14:40:16 INFO - 2015-12-21 14:40:16,917 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 14:40:16 INFO - 2015-12-21 14:40:16,917 DEBUG : BROWSER_OUTPUT: Values: 4822.1 14:40:16 INFO - 2015-12-21 14:40:16,917 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:40:16 INFO - 2015-12-21 14:40:16,917 DEBUG : BROWSER_OUTPUT: 14:40:17 INFO - 2015-12-21 14:40:17,593 INFO : Browser exited with error code: 0 14:40:17 INFO - 2015-12-21 14:40:17,610 INFO : Completed test v8_7 (00:00:38) 14:40:17 INFO - 2015-12-21 14:40:17,924 INFO : Completed test suite (00:20:05) 14:40:17 INFO - 2015-12-21 14:40:17,924 DEBUG : Working with test: dromaeo_css 14:40:17 INFO - 2015-12-21 14:40:17,924 DEBUG : Generating results file: dromaeo_css 14:40:17 INFO - 2015-12-21 14:40:17,925 DEBUG : Working with test: kraken 14:40:17 INFO - 2015-12-21 14:40:17,925 DEBUG : Generating results file: kraken 14:40:17 INFO - 2015-12-21 14:40:17,925 INFO : javascript benchmark 14:40:17 INFO - 2015-12-21 14:40:17,926 DEBUG : Working with test: v8_7 14:40:17 INFO - 2015-12-21 14:40:17,926 DEBUG : Generating results file: v8_7 14:40:17 INFO - 2015-12-21 14:40:17,926 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:40:17 INFO - 2015-12-21 14:40:17,981 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 14:40:17 INFO - 2015-12-21 14:40:17,981 DEBUG : process_Request line: dromaeo_css 7289.51 graph.html#tests=[[72,53,35]] 14:40:17 INFO - 2015-12-21 14:40:17,981 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:40:18 INFO - 2015-12-21 14:40:18,014 DEBUG : process_Request line: kraken 1457.00 graph.html#tests=[[232,53,35]] 14:40:18 INFO - 2015-12-21 14:40:18,014 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:40:18 INFO - 2015-12-21 14:40:18,121 DEBUG : process_Request line: v8_7 23157.87 graph.html#tests=[[230,53,35]] 14:40:18 INFO - 2015-12-21 14:40:18,122 INFO : javascript benchmark 14:40:18 INFO - 2015-12-21 14:40:18,125 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3245.464498357771, 3331.650176731445, 3349.581302337208, 3361.109306033903, 3383.627800941896, 5611.382747897733, 5667.5749318801045, 5671.06427439472, 5675.6878371689545, 5685.886282274352, 5710.1434784782305, 5711.857203569913, 5720.1991721159075, 5728.198052272685, 5739.397363276848, 4977.776000080005, 5155.871103222421, 5189.169732842746, 5256.526912577867, 5276.683398996053, 5054.115529782292, 5106.744662766865, 5134.897302053957, 5174.637775355726, 5181.663191892534, 5231.11071117909, 5268.8155914542995, 5285.86785330366, 5292.841214763564, 5312.495312945264, 5002.849914502571, 5194.35070616173, 5197.8960420791755, 5261.842144735664, 5288.15389537674, 5025.447200807908, 5111.4121875984365, 5130.9738052389675, 5160.870978225536, 5183.196604526304, 5474.37044739856, 5499.120140777496, 5499.697516636584, 5511.035568775448, 5525.917111243294, 5887.764489420418, 6023.518118550505, 6149.754009839601, 6154.0, 6156.84607884802, 6063.878722425527, 6117.2184870185665, 6120.602160859575, 6144.416280453394, 6144.600600960924, 5730.111832665943, 5731.025725626653, 5731.770729170787, 5746.942530574683, 5765.164051212592, 6078.422549857756, 6137.41694539018, 6158.599691020069, 6221.875562488815, 6224.875502489925, 6004.249468816398, 6007.759689612367, 6013.939860601426, 6014.578979471483, 6021.819345419644, 6101.298350689639, 6102.7253773579405, 6105.938940610537, 6106.0, 6113.205283313141, 5865.178874957509, 5942.3166335871065, 6006.249218847644, 6075.635461872302, 6117.156564497972, 6073.696315184223, 6073.817785466443, 6079.300880398722, 6084.269887613515, 6089.69551522431, 4635.976820115945, 4647.767611619473, 4649.907001859944, 4650.4186976627925, 4664.020555683277, 4059.918801623951, 4063.2482990646236, 4120.526139493936, 4122.0, 4130.586941305845, 5801.680907550125, 5852.882942341129, 5855.941440585625, 5860.560457965669, 5862.413758624189, 6000.279966404059, 6119.326874043852, 6142.447179753844, 6172.351903050115, 6180.845478863064, 5736.741846616912, 5786.913196302059, 5802.82591522255, 5815.127730840408, 5832.504237139806, 5317.521423071942, 5336.146216605324, 5344.305240318811, 5344.599155063386, 5348.5186333230195, 6049.969750151221, 6117.482447592652, 6352.078948552434, 6359.713812878432, 6366.427021568081, 5980.641161530322, 6081.756729730761, 6081.756729730938, 6082.148499210026, 6098.0548015055965, 6314.590121334428, 6377.4579160771855, 6382.90425643625, 6384.3934826191435, 6385.584936979081, 5907.70461476933, 6018.21763170785, 6065.60573562717, 6133.172021777182, 6143.354947730602, 6168.383161683975, 6183.752649893954, 6185.165002724574, 6190.690465476618, 6191.752329906753, 6003.189569408074, 6004.249468816398, 6009.849753756016, 6037.0642550404755, 6046.546509011754, 5864.882702346014, 5914.497267732291, 5957.940420595739, 6010.3088144863905, 6038.849028774316, 5914.556408269311, 5932.822015339547, 5941.0197317442135, 5943.346231914573, 5946.583739138218, 6013.669248191217, 6019.367966363468, 6045.214122164267, 6046.274447066205, 6050.909236361546, 5648.039833228278, 5752.07966725322, 5789.102689083199, 5891.911621325769, 5909.763609455574], "unit": "ms", "name": "dojo.html", "value": 5620.807581353373}, {"lowerIsBetter": false, "replicates": [588.838069530879, 627.3381582430536, 634.324444466643, 669.4577392312227, 672.3041651890288, 44484.555154448484, 45335.0, 45390.0921981561, 45460.09079818406, 45466.863328416766, 47977.76011119948, 48493.0, 48569.27146092812, 48616.51383486164, 48631.27053094206, 8242.093369729326, 8382.413231073828, 9181.357304988636, 10410.63562775303, 10535.209859260547, 10655.0, 10683.572657093746, 10755.83866242007, 10775.299605525655, 10778.784424311509, 10861.782764344709, 10928.125749940025, 10993.945030274837, 10999.780004399907, 11001.0, 10754.0, 10775.137988960903, 10822.72943176423, 10847.945760271148, 10849.457527123612, 10661.0, 10745.83881241782, 10783.514741836558, 10803.297785643908, 10807.243492955497, 44858.0, 45082.77458612719, 45245.0, 45294.32058519125, 45298.0, 3603.72972027099, 3756.924861502782, 3797.9810100949594, 3827.8468861245797, 4002.5397079335958, 34525.48211776825, 34809.825950870334, 34856.12859678503, 35064.82467587671, 35117.64882351195, 3804.258169656918, 3831.7126215533945, 3865.091703449687, 3915.7846318452475, 3930.3318435865685, 307908.0, 315877.0, 316892.0, 317196.0, 317219.4139029313, 65638.67180664066, 66692.33307666861, 73390.26609733941, 74180.0, 80732.17437389781, 79012.20987790049, 79866.6006669963, 80585.0, 80787.19212807914, 80810.0, 17959.551011224823, 18217.18022688982, 18232.08839558017, 18236.45290641283, 18241.543961400806, 13462.192268463923, 13572.117812342167, 13576.660583485493, 13582.660433489242, 13582.932085339708, 24698.259052228463, 24775.876120619643, 24886.626700599507, 25087.62368564473, 25096.4980700385, 9487.525623718786, 9608.231341492663, 9683.806323873483, 9717.6598819041, 9736.464494452874, 1025.7230547752215, 1100.1638754546602, 1133.7619099988929, 1152.256794367645, 1215.4469716279073, 29975.25061873427, 30092.849535752182, 30361.392772144874, 30880.69119308823, 31114.68885311163, 1111.1721767283425, 1122.0462606784167, 1170.0581032269106, 1201.1351826684772, 1285.2866659004342, 14098.718025639635, 15241.542753717406, 15524.611884702746, 15923.442679506394, 16340.428085017205, 430231.0, 435974.0, 436308.0, 436454.0, 436959.0, 57920.68317267347, 58379.0, 58480.70759646175, 58569.70715146482, 58624.41375586275, 1329.71411146602, 1334.6796768775423, 1450.2023886862185, 1462.8829693624698, 1487.2340744516582, 30362.848185759813, 30433.847830760704, 30521.389572207987, 31401.37197256088, 31792.36415271728, 49338.506614933394, 49751.0, 49884.0, 49934.75032624813, 49949.500504995936, 282229.0, 282665.0, 285361.0, 285629.0, 286035.0, 36240.63759362478, 36960.815195923846, 37619.435708464945, 37703.434448483844, 37707.2458550822, 46491.76754116208, 46728.76635616936, 47304.0, 48448.757756210995, 48647.27029094637, 38891.80554097211, 38960.610393895695, 39702.404463932486, 39923.20153596854, 40055.0, 40398.19203615852, 40488.79755601203, 40732.38901416422, 40829.183416332104, 46292.53707463017, 40980.38529422002, 41567.376489353286, 41715.79142104391, 41858.790706046275, 42236.155276893674, 35819.82090089533, 36620.81689591535, 39568.0, 41387.1722565553, 41773.164536708486, 27776.861115694293, 27805.860970695016, 28088.438231234853, 28221.858890705415, 28283.0], "unit": "ms", "name": "ext.html", "value": 21225.631987823363}, {"lowerIsBetter": false, "replicates": [362.8222171136142, 365.4116871836343, 366.3570433888525, 366.85142517280497, 368.50068157646405, 14817.18505482198, 17158.22787974541, 18447.446576602706, 18818.623627527442, 19359.225630974743, 19064.0, 19514.31699890504, 19552.706709399336, 19555.315563955264, 19755.802441975575, 5780.797672081468, 5922.733476993524, 5936.821895343147, 5943.88112237755, 5951.315598706139, 4436.112777444508, 4564.566366195206, 5851.473367396933, 6402.199725034371, 6648.0, 6378.681065946707, 6469.223693156804, 6493.93506064938, 6573.0, 6576.046473261375, 6422.550421470499, 6434.324395938406, 6450.4839612830865, 6456.0961465394885, 6465.935340646581, 5532.197831314477, 5610.747516361774, 5618.0, 5619.522340601013, 5649.209110724501, 23202.65196022061, 23278.41803954898, 23550.64674029891, 23626.291211263517, 23724.525509489715, 2240.630296001174, 2342.461233916209, 2352.4942137440535, 2410.349205714447, 3066.620020990541, 10466.1627069835, 10473.63342283024, 10684.732881678021, 14356.0, 15239.923800380928, 1801.6576850398383, 1821.9908900455414, 1825.589242420458, 1848.5563464768493, 1886.7735871695484, 68881.9999999995, 69351.65324173398, 69604.30395696028, 70741.29258707399, 71844.0, 7377.926220737724, 7419.517731347445, 7494.28804263605, 7496.850062998818, 7517.924820751832, 8626.913730862736, 8637.136286371438, 8658.437201581877, 8674.739757807276, 8681.175288347597, 6119.84700382494, 6123.601965872204, 6159.414855588801, 6187.978983467768, 6209.9379006210265, 6482.902756458657, 6508.837279068061, 6576.309487503839, 6589.9341006590275, 6592.604443733391, 3377.341418423433, 3420.794752314819, 3476.165720227127, 3498.317828023562, 3502.8949131526097, 1198.6284251881943, 1207.1127721124967, 1208.7582483503334, 1232.3714905398133, 1257.2330878164312, 499.38824939448887, 516.1535082464761, 526.3026489900897, 542.6011881267266, 559.158466507903, 11643.53425862973, 12205.20666156697, 12831.10182287231, 12862.742745145044, 13009.0, 506.27349753104414, 508.453412581469, 515.1397166731567, 523.4059342646124, 541.2125357604676, 6320.209973753281, 6421.4541763949655, 6543.5419520633095, 6561.442277406474, 6633.2703402625675, 580444.0, 593859.0, 595116.0, 602629.0, 606864.0, 82772.58613706892, 84980.57509712412, 87079.56460217659, 87478.56260718784, 88953.0, 548.569373042168, 560.7476635514035, 561.0238185557101, 562.3111688181945, 562.6820846221872, 12113.454894529592, 12115.454804533994, 12285.815712764494, 12488.937555312528, 12491.75016499683, 37879.0, 38250.0, 38596.6140338593, 39521.20957580775, 39553.802230989815, 52271.47728522666, 52598.737006314725, 53773.193402098215, 53862.0, 54313.0, 7159.140903091662, 7218.27817218274, 7224.674889630084, 7233.276672332725, 7256.2743725627015, 11316.6039188628, 12315.568955086528, 13413.396397161956, 13486.190828550318, 13547.119437236548, 11591.362475063952, 11776.941115294369, 11991.22057066288, 11993.700157496132, 12000.339981300764, 11516.94241528787, 11585.710357241136, 11596.710082248012, 11697.590584329508, 11758.470868811095, 11357.4889129991, 11370.658880233606, 11440.82838757436, 11519.0, 12054.276743395425, 7134.643267836691, 7243.492955493065, 7316.524425912298, 7338.816529586803, 7343.412526997934, 12122.090843186972, 12269.75460490803, 12473.126881118234, 12486.12597118193, 12545.184563003375], "unit": "ms", "name": "jquery.html", "value": 7909.732539828428}, {"lowerIsBetter": false, "replicates": [1553.7358649029663, 1559.7660350947363, 1807.6836553603116, 1865.4776662534491, 1868.411450393126, 7169.175544812349, 7306.926930730691, 7651.617419129049, 7814.92185078149, 7830.725924592641, 7487.85024299515, 7705.499142555744, 7806.102298235704, 7813.3749300056, 7815.609219539028, 2312.109837712476, 2363.621820508719, 2365.44412063165, 2365.8935347909387, 2368.336865677613, 2067.9483012924647, 2103.652897271956, 2266.7279926408855, 2314.351981445198, 2363.5745565798147, 2302.7582103879104, 2305.204704376996, 2307.526956973816, 2311.722593288799, 2313.826463015272, 2334.2297041976108, 2365.042157926043, 2365.3258821235863, 2366.219147681261, 2374.41826752445, 2322.442613772699, 2364.7635236476385, 2365.1721897335965, 2374.7625237476286, 2378.595638741418, 7849.254320839511, 7905.881411778828, 7910.841783164362, 7925.0, 7930.048394192734, 4840.370751802243, 4918.262260660894, 4920.778564964549, 4924.359833221658, 4927.6550641455115, 3242.8702851885896, 3362.3107263011025, 3367.7305815534696, 3433.0, 3434.4848272759036, 1138.9772204555945, 1147.294413935432, 1151.83874257604, 1158.571328608412, 1169.0589075793982, 10676.572937082507, 10677.572897084106, 10686.893131068668, 10698.625548105856, 10705.411202383788, 4403.383526306319, 4407.140607581491, 4450.955490445119, 4486.147631949919, 4486.237339652267, 9188.90811091894, 9215.44707317563, 9216.17054465088, 9219.170274675178, 9227.492487913096, 4935.3090567320605, 4941.48114447985, 4945.282933974626, 4945.9752701236985, 4951.70289782607, 4549.6360291176625, 4556.11155824611, 4558.361829343895, 4559.247724125548, 4560.996580752229, 3726.5341832270965, 3794.79128647927, 3808.352580061396, 3813.9237215256094, 3863.285292220948, 1105.6848798092506, 1183.9940800295944, 1212.7695737809788, 1221.6762557922157, 1234.8394708687813, 1212.7150119721857, 1230.477047254913, 1238.3993763024926, 1482.4959513765157, 1503.0155248312374, 7562.243775622504, 7579.204183560647, 7657.540547567164, 7658.119316278588, 7659.348955338859, 1200.7538454616783, 1205.421397729095, 1209.625016244967, 1219.609724888045, 1226.1662069792626, 5861.3552509223955, 5903.291605007426, 5913.822585322448, 5914.8817023659285, 5918.0, 9082.772930676785, 9522.90477095234, 9535.23718102564, 9537.761555961157, 9538.236941044699, 9389.29580281468, 9406.247500199968, 9427.717168484956, 9428.198603118812, 9442.197413219954, 7031.296870312928, 7380.926190738024, 7394.186639469654, 7397.482176247719, 7403.148637906602, 7165.426765858823, 7218.855622887408, 7227.060482137288, 7227.457940654577, 7228.819279518054, 8412.579371031547, 8532.829343412974, 8575.31397488212, 8588.484690918565, 8588.785280368042, 8553.101924298107, 8607.913920860712, 8628.39601227908, 8643.740687779376, 8649.308055355683, 7109.431245500244, 7164.605946672983, 7177.031100801325, 7193.748218812317, 7195.71217151329, 6807.5575087619145, 7454.813629659302, 7493.40052795786, 7501.924980750123, 7505.512141710771, 6807.0, 6960.547564408297, 7402.666879990521, 7448.441366897396, 7459.440541959266, 7043.647817609201, 7078.964605177147, 7120.430365570845, 7178.892316615359, 7364.41084713211, 7146.821329466805, 7240.674169662272, 7277.090363704537, 7359.227281135618, 7374.33630973215, 6633.535652504472, 6886.552374095668, 6916.896246556205, 6918.515703900679, 6926.792196234121, 6947.652617369213, 6949.895751563629, 6951.339622735832, 7039.6832142552685, 7071.0], "unit": "ms", "name": "mootools.html", "value": 4653.524931970393}, {"lowerIsBetter": false, "replicates": [354.81726910641015, 359.1183644153603, 359.5487662982957, 365.2548800447088, 366.73961487344, 5980.760769569218, 5991.880162396755, 5993.910091348634, 6004.099385092239, 6062.757489700418, 5740.684262365568, 5832.56255780816, 5835.5915085944, 5917.349091599931, 5930.822075337746, 4636.930446043312, 4657.88355291117, 4666.346711460398, 4671.369365135714, 4687.882802929928, 4635.559621835921, 4640.721556706609, 4651.999999999983, 4665.393498845146, 4665.743384113873, 4486.349479325512, 4569.268916973285, 4605.1710692075585, 4644.883877903046, 4661.580457758818, 4633.768311584441, 4634.814607415699, 4650.348951146842, 4651.279051746985, 4663.720176789404, 4150.128473020669, 4150.83396664133, 4156.56356082613, 4161.812718427678, 4163.75017498951, 5609.837353501299, 5673.432656734335, 5674.063779476382, 5687.800926967578, 5695.259616249861, 5473.726313684339, 5487.533559647395, 5492.945070549324, 5494.011078005958, 5496.395396506381, 6356.364363563653, 6389.201349831271, 6393.328700486475, 6396.360363963613, 6399.97600383941, 4638.628909687217, 4638.909856183795, 4641.257398816173, 4641.698289611165, 4643.558861908147, 6482.67586620667, 6605.867882642368, 6606.966965165191, 6618.503612229054, 6622.072909792633, 4710.29345598163, 4710.646701497401, 4712.293156026555, 4713.2458806590785, 4729.456112547033, 4763.356946812205, 4777.9010827509865, 4778.187708089633, 4781.450133234653, 4781.904361912742, 5399.163129714901, 5402.594805389612, 5404.54061404785, 5404.594655400861, 5472.890542189134, 6272.3100458949475, 6349.9682501587195, 6351.333110023474, 6363.777267795697, 6418.26189988148, 1680.3362671744592, 1698.0575780441727, 1700.7108791505473, 1702.719051356537, 1702.8467437930647, 1136.1422126294594, 1136.783641503583, 1137.766757814646, 1140.0, 1161.3670549550516, 6228.626282423069, 6230.532710046765, 6238.688065596702, 6239.719212635442, 6240.968795155995, 6618.900716489256, 6625.105610742585, 6632.071509988606, 6634.668266586747, 6635.071090047397, 6103.725332360054, 6181.876362472815, 6191.84520386994, 6298.811035668846, 6299.118123462719, 3611.747177697536, 3616.5117709108936, 3635.2729454109285, 3638.526991491089, 3640.3083414151756, 6771.8984215236815, 6774.220964589135, 6777.220619628707, 6782.525223234326, 6782.525223234326, 6564.704588293538, 6803.387695107464, 6803.7618683345845, 6811.284815094443, 6812.48906332027, 6829.214640316427, 6837.35045170708, 6839.0, 6842.315768423217, 6856.417204537571, 6639.7012134455035, 6647.567908085959, 6650.0, 6650.600963942179, 6655.534112612071, 6394.200724909387, 6506.479481641362, 6507.219133703986, 6513.446357059703, 6519.674016299261, 6707.228668703162, 6712.261651218264, 6762.019507171334, 6766.627835469097, 6772.796816095525, 4506.954930450654, 4564.589186973056, 4564.817407303671, 4569.13186494565, 4569.885752856205, 6516.315786842313, 6583.30875258091, 6602.933970660424, 6611.66941652925, 6613.867722645424, 6424.614523128627, 6444.967775161094, 6446.09754634361, 6448.322926092693, 6456.54804163704, 6425.293217746138, 6434.806955791334, 6440.420362167428, 6440.452561532322, 6443.420092191725, 6238.656873871799, 6311.715972781144, 6323.7786677466065, 6332.398422149889, 6334.303226645157, 6455.644939528183, 6459.61242325462, 6460.612363258219, 6460.903086453613, 6462.483001359974, 6196.721147548281, 6290.811275661737, 6305.5901366411035, 6307.05394190875, 6308.274548426806], "unit": "ms", "name": "prototype.html", "value": 4879.609418651545}, {"lowerIsBetter": false, "replicates": [932.7108596335132, 938.2540880000399, 951.8143961927427, 958.0993865766181, 963.8795150606176, 14894.404223831047, 15025.549233522997, 15176.62058448538, 15856.207189640501, 15868.444604438848, 15093.698126037472, 15165.469208577702, 15187.46843860465, 15187.620309492268, 15187.84812151881, 781.2304879693497, 785.6896525872277, 935.3265648732936, 940.6378544260458, 956.3592393096642, 945.6973768394082, 955.8518429643382, 957.602594923106, 964.0, 966.3718582921123, 978.3933960944224, 999.5701848205268, 999.9900001000007, 1000.0, 1000.2698030437784, 806.6813608624619, 810.9310708589777, 970.7039352997368, 985.935914165577, 986.921046316293, 725.662566906391, 731.4331393170282, 733.1056111543961, 733.1751779248345, 734.717133903451, 14900.478483253142, 15043.398264069425, 15051.849481505264, 15054.623634409118, 15060.924695376563, 13191.472341106344, 13333.733325333438, 13359.86640133596, 13524.864751352558, 13981.650458738512, 11398.0, 11540.538378464935, 11621.941890290578, 11689.0, 11755.647330580096, 3728.5898551159353, 3814.1608846053823, 3844.0774214188395, 3889.946392082202, 3918.784466854322, 54015.18977215345, 54193.0, 54522.18216726752, 54760.0, 55000.0, 9725.319227653996, 9997.900020999843, 10632.521536530874, 10732.141428685687, 10876.945615271872, 18644.161012754186, 18721.438356849318, 18727.0, 18730.625387492448, 18765.343212987755, 7635.923640763522, 8025.558594277254, 8063.314618257396, 8081.030276366756, 8096.51420914747, 4786.521347865186, 4835.3714017178245, 4886.291487734258, 4910.459849416561, 4938.185199442124, 3252.154439845657, 3283.9835800820842, 3284.064041748138, 3290.44062509374, 3294.275259442919, 512.8307658472695, 527.1802347349831, 527.7150338816996, 529.1031701266394, 539.2989114151587, 635.2472320300459, 661.0795638669672, 735.2243383230743, 742.8997085393511, 758.2076729817354, 9037.68368107113, 9610.231181505464, 9669.564869580885, 9766.560504777302, 9972.551235194434, 668.3383450384084, 683.111954459201, 685.0751485494642, 729.8686236477484, 765.6937225109889, 3857.2478366718783, 4120.381942708617, 4147.419361289422, 4206.621404073648, 4243.172581346607, 69416.0, 70511.64744176246, 71377.2862271381, 71420.0, 71500.2849971504, 60277.39722602806, 60738.0, 61027.38972610306, 61027.38972610306, 62037.37962620406, 54094.729526352115, 54240.0, 54586.0, 54855.177172342446, 54921.45078549243, 8366.24703776663, 8396.244338009608, 8697.217250447236, 9336.673216437392, 9538.093881081286, 40004.1999160021, 40559.18881622292, 41551.7922410386, 42307.3653895198, 42377.152456951306, 55436.0, 55614.16578751241, 55703.721481392335, 55914.72042639761, 56589.0, 8483.872741908754, 8604.913950860662, 8765.561721914006, 8801.867971980297, 8831.735047948572, 13867.167969921837, 14210.889317593534, 14253.144811311355, 14406.927965360106, 14600.561983140522, 12512.124151309685, 12864.421101050288, 12927.224366538037, 13386.183127520588, 13707.657308567366, 12922.741545169232, 13028.218306901616, 13208.27354495512, 13307.534236301683, 13446.193228406328, 12719.55481558141, 12773.808392873929, 13204.867951320364, 13233.404496797475, 13345.733085338434, 10444.0, 11371.260868043883, 11401.885981140415, 11624.128190385924, 11832.940835295769, 10655.680329590125, 11004.339739615649, 11077.335359878432, 11095.944520277348, 11151.10791136691], "unit": "ms", "name": "yui.html", "value": 7001.042126526703}], "name": "dromaeo_css", "value": 7289.511238427206}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 109.0, 105.0, 109.0, 111.0, 111.0, 100.0, 110.0, 110.0, 106.0], "unit": "ms", "name": "ai-astar", "value": 108.0}, {"lowerIsBetter": true, "replicates": [149.0, 122.0, 150.0, 159.0, 130.0, 168.0, 153.0, 127.0, 157.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.9}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 160.0, 159.0, 159.0, 163.0, 158.0, 163.0, 161.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 70.0, 146.0, 70.0, 72.0, 70.0, 70.0, 147.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 85.8}, {"lowerIsBetter": true, "replicates": [89.0, 90.0, 141.0, 89.0, 89.0, 90.0, 131.0, 88.0, 89.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 103.1}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [54.0, 54.0, 56.0, 55.0, 55.0, 55.0, 57.0, 55.0, 52.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 54.6}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 64.0, 63.0, 64.0, 63.0, 64.0, 63.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.6}, {"lowerIsBetter": true, "replicates": [78.0, 77.0, 77.0, 79.0, 79.0, 77.0, 78.0, 78.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.8}, {"lowerIsBetter": true, "replicates": [118.0, 115.0, 111.0, 111.0, 116.0, 111.0, 111.0, 112.0, 114.0, 113.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.2}, {"lowerIsBetter": true, "replicates": [137.0, 135.0, 133.0, 136.0, 134.0, 134.0, 135.0, 128.0, 135.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 134.7}, {"lowerIsBetter": true, "replicates": [60.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 61.0, 61.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.7}], "name": "kraken", "value": 1457.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.98819285011479], "unit": "score", "name": "Richards", "value": 215.27159600000007}, {"lowerIsBetter": false, "replicates": [170.50298380221653], "unit": "score", "name": "DeltaBlue", "value": 387.78207000000015}, {"lowerIsBetter": false, "replicates": [279.2516056967328], "unit": "score", "name": "Encrypt", "value": 953.1941609999996}, {"lowerIsBetter": false, "replicates": [5035.175879396985], "unit": "score", "name": "Decrypt", "value": 52.864290419161705}, {"lowerIsBetter": false, "replicates": [946.969696969697], "unit": "score", "name": "RayTrace", "value": 781.4283840000004}, {"lowerIsBetter": false, "replicates": [553.4034311012729], "unit": "score", "name": "Earley", "value": 1204.2986409999994}, {"lowerIsBetter": false, "replicates": [10902.173913043478], "unit": "score", "name": "Boyer", "value": 61.131202392821535}, {"lowerIsBetter": false, "replicates": [23674.418604651164], "unit": "score", "name": "RegExp", "value": 38.47972003929271}, {"lowerIsBetter": false, "replicates": [546.7468562055768], "unit": "score", "name": "Splay", "value": 149.047039}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23157.86867673815}]} 14:40:18 INFO - RETURN: dromaeo_css: 7289.51 14:40:18 INFO - RETURN: kraken: 1457.00 14:40:18 INFO - RETURN: v8_7: 23157.87 14:40:18 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23157.87"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1457.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7289.51"}}} 14:40:18 INFO - Return code: 0 14:40:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 14:40:18 INFO - # TBPL SUCCESS # 14:40:18 INFO - Running post-action listener: _resource_record_post_action 14:40:18 INFO - Running post-run listener: _resource_record_post_run 14:40:19 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 339968; Write bytes: 498376704; Read time: 228; Write time: 425344 14:40:19 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 239493120; Read time: 0; Write time: 287448 14:40:19 INFO - run-tests - Wall time: 1207s; CPU: 12.0%; Read bytes: 339968; Write bytes: 258605056; Read time: 228; Write time: 137872 14:40:19 INFO - Running post-run listener: _upload_blobber_files 14:40:19 INFO - Blob upload gear active. 14:40:19 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 14:40:19 INFO - Copying logs to upload dir... 14:40:19 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.685928 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-12-21 14:40:19.224090) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-21 14:40:19.228003) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/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-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009920 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450727789/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-21 14:40:19.279702) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:40:19.280044) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1450736307.796949-423459668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:40:19.329296) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 14:40:19.329624) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 14:40:19.329941) ========= ========= Total master_lag: 0.69 =========