builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1451952809.16 results: success (0) buildid: 20160104135432 builduid: ef3d1971966946d396d2a56f2b3c2cc1 revision: 57f85790732e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.158757) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.159448) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.159747) ========= 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=1861a34ebfea852630dec091000001fa-1451952329.353571-624575631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006422 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.207487) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.207862) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.259695) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.260329) ========= 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=1861a34ebfea852630dec091000001fa-1451952329.353571-624575631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024120 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.314542) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.315025) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.315640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.316132) ========= 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=1861a34ebfea852630dec091000001fa-1451952329.353571-624575631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-04 16:13:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 63.6M=0s 2016-01-04 16:13:29 (63.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161870 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.492389) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.492687) ========= 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=1861a34ebfea852630dec091000001fa-1451952329.353571-624575631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.052292 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.556344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:29.556693) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 57f85790732e --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 57f85790732e --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=1861a34ebfea852630dec091000001fa-1451952329.353571-624575631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-04 16:13:29,603 Setting DEBUG logging. 2016-01-04 16:13:29,603 attempt 1/10 2016-01-04 16:13:29,603 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/57f85790732e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-04 16:13:29,865 unpacking tar archive at: mozilla-beta-57f85790732e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.555864 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:30.124393) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:30.124729) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:30.135572) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:30.135975) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 16:13:30.136515) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-01-04 16:13:30.136862) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --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', '--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=1861a34ebfea852630dec091000001fa-1451952329.353571-624575631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:13:30 INFO - MultiFileLogger online at 20160104 16:13:30 in /builds/slave/test-pgo 16:13:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:13:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:13:30 INFO - {'append_to_log': False, 16:13:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:13:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:13:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:13:30 INFO - 'branch': 'Mozilla-Beta', 16:13:30 INFO - 'buildbot_json_path': 'buildprops.json', 16:13:30 INFO - 'config_files': ('talos/linux_config.py',), 16:13:30 INFO - 'default_actions': ('clobber', 16:13:30 INFO - 'read-buildbot-config', 16:13:30 INFO - 'download-and-extract', 16:13:30 INFO - 'populate-webroot', 16:13:30 INFO - 'create-virtualenv', 16:13:30 INFO - 'install', 16:13:30 INFO - 'run-tests'), 16:13:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:13:30 INFO - 'download_minidump_stackwalk': True, 16:13:30 INFO - 'download_symbols': 'ondemand', 16:13:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:13:30 INFO - 'tooltool.py': '/tools/tooltool.py', 16:13:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:13:30 INFO - '/tools/misc-python/virtualenv.py')}, 16:13:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:13:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:13:30 INFO - 'installer_path': 'installer.exe', 16:13:30 INFO - 'log_level': 'info', 16:13:30 INFO - 'log_name': 'talos', 16:13:30 INFO - 'log_to_console': True, 16:13:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:13:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:13:30 INFO - 'opt_config_files': (), 16:13:30 INFO - 'pip_index': False, 16:13:30 INFO - 'sps_profile': False, 16:13:30 INFO - 'sps_profile_interval': 0, 16:13:30 INFO - 'suite': 'dromaeojs', 16:13:30 INFO - 'system_bits': '32', 16:13:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:13:30 INFO - 'title': 'talos-linux64-ix-069', 16:13:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:13:30 INFO - 'use_talos_json': True, 16:13:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:13:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:13:30 INFO - 'work_dir': 'build'} 16:13:30 INFO - ##### 16:13:30 INFO - ##### Running clobber step. 16:13:30 INFO - ##### 16:13:30 INFO - Running pre-action listener: _resource_record_pre_action 16:13:30 INFO - Running main action method: clobber 16:13:30 INFO - rmtree: /builds/slave/test-pgo/build 16:13:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:13:35 INFO - Running post-action listener: _resource_record_post_action 16:13:35 INFO - ##### 16:13:35 INFO - ##### Running read-buildbot-config step. 16:13:35 INFO - ##### 16:13:35 INFO - Running pre-action listener: _resource_record_pre_action 16:13:35 INFO - Running main action method: read_buildbot_config 16:13:35 INFO - Using buildbot properties: 16:13:35 INFO - { 16:13:35 INFO - "properties": { 16:13:35 INFO - "buildnumber": 63, 16:13:35 INFO - "product": "firefox", 16:13:35 INFO - "script_repo_revision": "production", 16:13:35 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:13:35 INFO - "repository": "", 16:13:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:13:35 INFO - "buildid": "20160104135432", 16:13:35 INFO - "slavename": "talos-linux64-ix-069", 16:13:35 INFO - "pgo_build": "True", 16:13:35 INFO - "basedir": "/builds/slave/test-pgo", 16:13:35 INFO - "project": "", 16:13:35 INFO - "platform": "ubuntu64_hw", 16:13:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:13:35 INFO - "slavebuilddir": "test", 16:13:35 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:13:35 INFO - "branch": "mozilla-beta", 16:13:35 INFO - "repo_path": "releases/mozilla-beta", 16:13:35 INFO - "stage_platform": "linux64-pgo", 16:13:35 INFO - "builduid": "ef3d1971966946d396d2a56f2b3c2cc1", 16:13:35 INFO - "revision": "57f85790732e" 16:13:35 INFO - }, 16:13:35 INFO - "sourcestamp": { 16:13:35 INFO - "repository": "", 16:13:35 INFO - "hasPatch": false, 16:13:35 INFO - "project": "", 16:13:35 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:13:35 INFO - "changes": [ 16:13:35 INFO - { 16:13:35 INFO - "category": null, 16:13:35 INFO - "files": [ 16:13:35 INFO - { 16:13:35 INFO - "url": null, 16:13:35 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/firefox-44.0.en-US.linux-x86_64.tar.bz2" 16:13:35 INFO - } 16:13:35 INFO - ], 16:13:35 INFO - "repository": "", 16:13:35 INFO - "rev": "57f85790732e", 16:13:35 INFO - "who": "sendchange", 16:13:35 INFO - "when": 1451952795, 16:13:35 INFO - "number": 7415180, 16:13:35 INFO - "comments": "Backed out changeset 4c66ae0b0b91 (bug 1218266) for xpcshell bustage in test_api.js a=backout", 16:13:35 INFO - "project": "", 16:13:35 INFO - "at": "Mon 04 Jan 2016 16:13:15", 16:13:35 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:13:35 INFO - "revlink": "", 16:13:35 INFO - "properties": [ 16:13:35 INFO - [ 16:13:35 INFO - "buildid", 16:13:35 INFO - "20160104135432", 16:13:35 INFO - "Change" 16:13:35 INFO - ], 16:13:35 INFO - [ 16:13:35 INFO - "builduid", 16:13:35 INFO - "ef3d1971966946d396d2a56f2b3c2cc1", 16:13:35 INFO - "Change" 16:13:35 INFO - ], 16:13:35 INFO - [ 16:13:35 INFO - "pgo_build", 16:13:35 INFO - "True", 16:13:35 INFO - "Change" 16:13:35 INFO - ] 16:13:35 INFO - ], 16:13:35 INFO - "revision": "57f85790732e" 16:13:35 INFO - } 16:13:35 INFO - ], 16:13:35 INFO - "revision": "57f85790732e" 16:13:35 INFO - } 16:13:35 INFO - } 16:13:35 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/firefox-44.0.en-US.linux-x86_64.tar.bz2. 16:13:35 INFO - Running post-action listener: _resource_record_post_action 16:13:35 INFO - ##### 16:13:35 INFO - ##### Running download-and-extract step. 16:13:35 INFO - ##### 16:13:35 INFO - Running pre-action listener: _resource_record_pre_action 16:13:35 INFO - Running main action method: download_and_extract 16:13:35 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/test_packages.json 16:13:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:13:35 INFO - Downloaded 1270 bytes. 16:13:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:13:35 INFO - Using the following test package requirements: 16:13:35 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 16:13:35 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:13:35 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:13:35 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:13:35 INFO - u'jsshell-linux-x86_64.zip'], 16:13:35 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:13:35 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:13:35 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 16:13:35 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:13:35 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 16:13:35 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:13:35 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 16:13:35 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:13:35 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:13:35 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 16:13:35 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:13:35 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:13:35 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:13:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/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 16:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/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 16:13:37 INFO - Downloaded 22571859 bytes. 16:13:37 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 16:13:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:13:37 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 16:13:39 INFO - Return code: 0 16:13:39 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 16:13: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')]} 16:13:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:13:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/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 16:13:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/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 16:13:39 INFO - Downloaded 22571859 bytes. 16:13:39 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 16:13:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:13:39 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 16:13:40 INFO - Return code: 0 16:13:40 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')]} 16:13:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 16:13:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/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 16:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/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 16:13:43 INFO - Downloaded 11131970 bytes. 16:13:43 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 16:13:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 16:13:43 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 16:13:44 INFO - Return code: 0 16:13: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')]} 16:13:44 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/firefox-44.0.en-US.linux-x86_64.tar.bz2 16:13:44 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/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 16:13:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/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 16:13:53 INFO - Downloaded 56629322 bytes. 16:13:53 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/firefox-44.0.en-US.linux-x86_64.tar.bz2 16:13:53 INFO - mkdir: /builds/slave/test-pgo/properties 16:13:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:13:53 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:13:53 INFO - Contents: 16:13:53 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/firefox-44.0.en-US.linux-x86_64.tar.bz2 16:13:53 INFO - Running post-action listener: _resource_record_post_action 16:13:53 INFO - Running post-action listener: set_extra_try_arguments 16:13:53 INFO - ##### 16:13:53 INFO - ##### Running populate-webroot step. 16:13:53 INFO - ##### 16:13:53 INFO - Running pre-action listener: _resource_record_pre_action 16:13:53 INFO - Running main action method: populate_webroot 16:13:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:13:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:13:53 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 16:13:53 INFO - '../fennec_ids.txt'], 16:13:53 INFO - 'tests': ['tcheck2']}, 16:13:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:13:53 INFO - '--tppagecycles', 16:13:53 INFO - '7'], 16:13:53 INFO - 'tests': ['tsvgm']}}, 16:13:53 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:13:53 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:13:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:13:53 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:13:53 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:13:53 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:13:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:13:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:53 INFO - 'talos_options': ['--e10s'], 16:13:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:13:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:53 INFO - 'tests': ['damp', 'tps']}, 16:13:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:53 INFO - 'talos_options': ['--e10s'], 16:13:53 INFO - 'tests': ['damp', 'tps']}, 16:13:53 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:13:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:13:53 INFO - 'other': {'tests': ['a11yr', 16:13:53 INFO - 'ts_paint', 16:13:53 INFO - 'tpaint', 16:13:53 INFO - 'sessionrestore', 16:13:53 INFO - 'sessionrestore_no_auto_restore']}, 16:13:53 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:13:53 INFO - 'tests': ['a11yr', 16:13:53 INFO - 'ts_paint', 16:13:53 INFO - 'tpaint', 16:13:53 INFO - 'sessionrestore', 16:13:53 INFO - 'sessionrestore_no_auto_restore']}, 16:13:53 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:13:53 INFO - 'tests': ['a11yr', 16:13:53 INFO - 'ts_paint', 16:13:53 INFO - 'tpaint', 16:13:53 INFO - 'sessionrestore', 16:13:53 INFO - 'sessionrestore_no_auto_restore']}, 16:13:53 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:13:53 INFO - 'tests': ['a11yr', 16:13:53 INFO - 'ts_paint', 16:13:53 INFO - 'tpaint', 16:13:53 INFO - 'sessionrestore', 16:13:53 INFO - 'sessionrestore_no_auto_restore']}, 16:13:53 INFO - 'other_l64': {'tests': ['a11yr', 16:13:53 INFO - 'ts_paint', 16:13:53 INFO - 'tpaint', 16:13:53 INFO - 'sessionrestore', 16:13:53 INFO - 'sessionrestore_no_auto_restore']}, 16:13:53 INFO - 'other_nol64': {'tests': ['a11yr', 16:13:53 INFO - 'ts_paint', 16:13:53 INFO - 'tpaint', 16:13:53 INFO - 'sessionrestore', 16:13:53 INFO - 'sessionrestore_no_auto_restore']}, 16:13:53 INFO - 'svgr': {'tests': ['tsvgx', 16:13:53 INFO - 'tsvgr_opacity', 16:13:53 INFO - 'tart', 16:13:53 INFO - 'tscrollx', 16:13:53 INFO - 'cart']}, 16:13:53 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:13:53 INFO - 'tests': ['tsvgx', 16:13:53 INFO - 'tsvgr_opacity', 16:13:53 INFO - 'tart', 16:13:53 INFO - 'tscrollx', 16:13:53 INFO - 'cart']}, 16:13:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:53 INFO - 'tests': ['tp5o']}, 16:13:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:53 INFO - 'talos_options': ['--e10s'], 16:13:53 INFO - 'tests': ['tp5o']}, 16:13:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:53 INFO - 'talos_options': ['--xperf_path', 16:13:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:13:53 INFO - 'tests': ['tp5n']}, 16:13:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:53 INFO - 'talos_options': ['--e10s', 16:13:53 INFO - '--xperf_path', 16:13:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:13:53 INFO - 'tests': ['tp5n']}}, 16:13:53 INFO - 'talos.zip': {'path': '', 16:13:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:13:53 INFO - Running post-action listener: _resource_record_post_action 16:13:53 INFO - ##### 16:13:53 INFO - ##### Running create-virtualenv step. 16:13:53 INFO - ##### 16:13:53 INFO - Running pre-action listener: _resource_record_pre_action 16:13:53 INFO - Running main action method: create_virtualenv 16:13:53 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:13:53 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 16:13:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:13:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:13:53 INFO - Using real prefix '/usr' 16:13:53 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:13:53 INFO - Installing distribute.............................................................................................................................................................................................done. 16:13:55 INFO - Installing pip.................done. 16:13:55 INFO - Return code: 0 16:13:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:13:55 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')]} 16:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:55 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')]} 16:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:55 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 0x7f4a39816f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2070600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ff350>, '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 0x2100250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21006e0>, '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': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', '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 16:13:55 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 16:13:55 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 16:13:55 INFO - Using env: {'DISPLAY': ':0', 16:13:55 INFO - 'HOME': '/home/cltbld', 16:13:55 INFO - 'LANG': 'en_US.UTF-8', 16:13:55 INFO - 'LANGUAGE': 'en_US:en', 16:13:55 INFO - 'LOGNAME': 'cltbld', 16:13:55 INFO - 'MAIL': '/var/mail/cltbld', 16:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:55 INFO - 'MOZ_NO_REMOTE': '1', 16:13:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:55 INFO - 'NO_EM_RESTART': '1', 16:13:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:13:55 INFO - 'PWD': '/builds/slave/test-pgo', 16:13:55 INFO - 'SHELL': '/bin/bash', 16:13:55 INFO - 'SHLVL': '1', 16:13:55 INFO - 'TERM': 'linux', 16:13:55 INFO - 'TMOUT': '86400', 16:13:55 INFO - 'USER': 'cltbld', 16:13:55 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', 16:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:55 INFO - '_': '/tools/buildbot/bin/python'} 16:13:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:55 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 16:13:55 INFO - Cleaning up... 16:13:55 INFO - Return code: 0 16:13:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:13:55 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')]} 16:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:55 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')]} 16:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:55 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 0x7f4a39816f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2070600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ff350>, '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 0x2100250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21006e0>, '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': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', '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 16:13:55 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 16:13:55 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 16:13:55 INFO - Using env: {'DISPLAY': ':0', 16:13:55 INFO - 'HOME': '/home/cltbld', 16:13:55 INFO - 'LANG': 'en_US.UTF-8', 16:13:55 INFO - 'LANGUAGE': 'en_US:en', 16:13:55 INFO - 'LOGNAME': 'cltbld', 16:13:55 INFO - 'MAIL': '/var/mail/cltbld', 16:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:55 INFO - 'MOZ_NO_REMOTE': '1', 16:13:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:55 INFO - 'NO_EM_RESTART': '1', 16:13:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:13:55 INFO - 'PWD': '/builds/slave/test-pgo', 16:13:55 INFO - 'SHELL': '/bin/bash', 16:13:56 INFO - 'SHLVL': '1', 16:13:56 INFO - 'TERM': 'linux', 16:13:56 INFO - 'TMOUT': '86400', 16:13:56 INFO - 'USER': 'cltbld', 16:13:56 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', 16:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:56 INFO - '_': '/tools/buildbot/bin/python'} 16:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:56 INFO - Downloading/unpacking psutil>=0.7.1 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13:57 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 16:13:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 16:13:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:13:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:13:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:13:58 INFO - Installing collected packages: psutil 16:13:58 INFO - Running setup.py install for psutil 16:13:58 INFO - building 'psutil._psutil_linux' extension 16:13:58 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 16:13:58 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 16:13:58 INFO - building 'psutil._psutil_posix' extension 16:13:58 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 16:13:58 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 16:13:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:13:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:13:58 INFO - Successfully installed psutil 16:13:58 INFO - Cleaning up... 16:13:58 INFO - Return code: 0 16:13:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:13:58 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')]} 16:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:58 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')]} 16:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:58 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 0x7f4a39816f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2070600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ff350>, '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 0x2100250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21006e0>, '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': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', '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 16:13:58 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 16:13:58 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 16:13:58 INFO - Using env: {'DISPLAY': ':0', 16:13:58 INFO - 'HOME': '/home/cltbld', 16:13:58 INFO - 'LANG': 'en_US.UTF-8', 16:13:58 INFO - 'LANGUAGE': 'en_US:en', 16:13:58 INFO - 'LOGNAME': 'cltbld', 16:13:58 INFO - 'MAIL': '/var/mail/cltbld', 16:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:58 INFO - 'MOZ_NO_REMOTE': '1', 16:13:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:58 INFO - 'NO_EM_RESTART': '1', 16:13:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:13:58 INFO - 'PWD': '/builds/slave/test-pgo', 16:13:58 INFO - 'SHELL': '/bin/bash', 16:13:58 INFO - 'SHLVL': '1', 16:13:58 INFO - 'TERM': 'linux', 16:13:58 INFO - 'TMOUT': '86400', 16:13:58 INFO - 'USER': 'cltbld', 16:13:58 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', 16:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:58 INFO - '_': '/tools/buildbot/bin/python'} 16:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:13:58 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 16:13:58 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 16:13:58 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 16:13:58 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 16:13:58 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 16:13:58 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 16:14:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:14:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:14:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:14:00 INFO - Installing collected packages: mozsystemmonitor 16:14:00 INFO - Running setup.py install for mozsystemmonitor 16:14:00 INFO - Successfully installed mozsystemmonitor 16:14:00 INFO - Cleaning up... 16:14:00 INFO - Return code: 0 16:14:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:00 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 0x7f4a39816f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2070600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ff350>, '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 0x2100250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21006e0>, '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': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', '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 16:14:00 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 16:14:00 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 16:14:00 INFO - Using env: {'DISPLAY': ':0', 16:14:00 INFO - 'HOME': '/home/cltbld', 16:14:00 INFO - 'LANG': 'en_US.UTF-8', 16:14:00 INFO - 'LANGUAGE': 'en_US:en', 16:14:00 INFO - 'LOGNAME': 'cltbld', 16:14:00 INFO - 'MAIL': '/var/mail/cltbld', 16:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:00 INFO - 'MOZ_NO_REMOTE': '1', 16:14:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:00 INFO - 'NO_EM_RESTART': '1', 16:14:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:00 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:00 INFO - 'SHELL': '/bin/bash', 16:14:00 INFO - 'SHLVL': '1', 16:14:00 INFO - 'TERM': 'linux', 16:14:00 INFO - 'TMOUT': '86400', 16:14:00 INFO - 'USER': 'cltbld', 16:14:00 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', 16:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:00 INFO - '_': '/tools/buildbot/bin/python'} 16:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:00 INFO - Downloading/unpacking blobuploader==1.2.4 16:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:02 INFO - Downloading blobuploader-1.2.4.tar.gz 16:14:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 16:14:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:14:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 16:14:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:14:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:03 INFO - Downloading docopt-0.6.1.tar.gz 16:14:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 16:14:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:14:03 INFO - Installing collected packages: blobuploader, requests, docopt 16:14:03 INFO - Running setup.py install for blobuploader 16:14:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:14:03 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:14:03 INFO - Running setup.py install for requests 16:14:03 INFO - Running setup.py install for docopt 16:14:03 INFO - Successfully installed blobuploader requests docopt 16:14:03 INFO - Cleaning up... 16:14:03 INFO - Return code: 0 16:14:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:03 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 0x7f4a39816f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2070600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ff350>, '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 0x2100250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21006e0>, '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': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', '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 16:14:03 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 16:14:03 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 16:14:03 INFO - Using env: {'DISPLAY': ':0', 16:14:03 INFO - 'HOME': '/home/cltbld', 16:14:03 INFO - 'LANG': 'en_US.UTF-8', 16:14:03 INFO - 'LANGUAGE': 'en_US:en', 16:14:03 INFO - 'LOGNAME': 'cltbld', 16:14:03 INFO - 'MAIL': '/var/mail/cltbld', 16:14:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:03 INFO - 'MOZ_NO_REMOTE': '1', 16:14:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:03 INFO - 'NO_EM_RESTART': '1', 16:14:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:03 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:03 INFO - 'SHELL': '/bin/bash', 16:14:03 INFO - 'SHLVL': '1', 16:14:03 INFO - 'TERM': 'linux', 16:14:03 INFO - 'TMOUT': '86400', 16:14:03 INFO - 'USER': 'cltbld', 16:14:03 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', 16:14:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:03 INFO - '_': '/tools/buildbot/bin/python'} 16:14:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:14:04 INFO - Running setup.py (path:/tmp/pip-aAAirO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:14:04 INFO - Running setup.py (path:/tmp/pip-vmnZLL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:14:04 INFO - Running setup.py (path:/tmp/pip-tbz758-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:14:04 INFO - Running setup.py (path:/tmp/pip-QKp4nA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:14:04 INFO - Running setup.py (path:/tmp/pip-0ObBwV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:14:04 INFO - Running setup.py (path:/tmp/pip-kiRR8b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:14:04 INFO - Running setup.py (path:/tmp/pip-VaTnib-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:14:04 INFO - Running setup.py (path:/tmp/pip-aPZFkh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:14:04 INFO - Running setup.py (path:/tmp/pip-IcQlZe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:14:04 INFO - Running setup.py (path:/tmp/pip-w8pRnP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:14:04 INFO - Running setup.py (path:/tmp/pip-2AqZWV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:14:04 INFO - Running setup.py (path:/tmp/pip-Uai4il-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:14:04 INFO - Running setup.py (path:/tmp/pip-Le_yzh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:14:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:14:04 INFO - Running setup.py (path:/tmp/pip-Og7Qxu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:14:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:14:05 INFO - Running setup.py (path:/tmp/pip-Ng4e59-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:14:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:14:05 INFO - Running setup.py (path:/tmp/pip-QzGgHu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:14:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:14:05 INFO - Running setup.py (path:/tmp/pip-j0yxX_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:14:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:14:05 INFO - Running setup.py install for manifestparser 16:14:05 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:14:05 INFO - Running setup.py install for mozcrash 16:14:05 INFO - Running setup.py install for mozdebug 16:14:05 INFO - Running setup.py install for mozdevice 16:14:05 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:14:05 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:14:05 INFO - Running setup.py install for mozfile 16:14:05 INFO - Running setup.py install for mozhttpd 16:14:05 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:14:05 INFO - Running setup.py install for mozinfo 16:14:05 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:14:05 INFO - Running setup.py install for mozInstall 16:14:06 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Running setup.py install for mozleak 16:14:06 INFO - Running setup.py install for mozlog 16:14:06 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Running setup.py install for moznetwork 16:14:06 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Running setup.py install for mozprocess 16:14:06 INFO - Running setup.py install for mozprofile 16:14:06 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Running setup.py install for mozrunner 16:14:06 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Running setup.py install for mozscreenshot 16:14:06 INFO - Running setup.py install for moztest 16:14:06 INFO - Running setup.py install for mozversion 16:14:06 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:14:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:14:06 INFO - Cleaning up... 16:14:07 INFO - Return code: 0 16:14:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:07 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 0x7f4a39816f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2070600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ff350>, '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 0x2100250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21006e0>, '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': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', '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 16:14:07 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 16:14:07 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 16:14:07 INFO - Using env: {'DISPLAY': ':0', 16:14:07 INFO - 'HOME': '/home/cltbld', 16:14:07 INFO - 'LANG': 'en_US.UTF-8', 16:14:07 INFO - 'LANGUAGE': 'en_US:en', 16:14:07 INFO - 'LOGNAME': 'cltbld', 16:14:07 INFO - 'MAIL': '/var/mail/cltbld', 16:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:07 INFO - 'MOZ_NO_REMOTE': '1', 16:14:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:07 INFO - 'NO_EM_RESTART': '1', 16:14:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:07 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:07 INFO - 'SHELL': '/bin/bash', 16:14:07 INFO - 'SHLVL': '1', 16:14:07 INFO - 'TERM': 'linux', 16:14:07 INFO - 'TMOUT': '86400', 16:14:07 INFO - 'USER': 'cltbld', 16:14:07 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', 16:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:07 INFO - '_': '/tools/buildbot/bin/python'} 16:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:14:07 INFO - Running setup.py (path:/tmp/pip-sqUtYj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:14:07 INFO - Running setup.py (path:/tmp/pip-Hn3LtX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:14:07 INFO - Running setup.py (path:/tmp/pip-b0SGD8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:14:07 INFO - Running setup.py (path:/tmp/pip-8lbs_K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:14:07 INFO - Running setup.py (path:/tmp/pip-5lNvyc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:14:07 INFO - Running setup.py (path:/tmp/pip-_tyTdi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:14:07 INFO - Running setup.py (path:/tmp/pip-3TI6m8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:14:07 INFO - Running setup.py (path:/tmp/pip-cty3pa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:14:07 INFO - Running setup.py (path:/tmp/pip-oXCA1o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:14:07 INFO - Running setup.py (path:/tmp/pip-ouBxOB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:14:07 INFO - Running setup.py (path:/tmp/pip-kJsDK9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:14:08 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)) 16:14:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:14:08 INFO - Running setup.py (path:/tmp/pip-nVnkak-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:14:08 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)) 16:14:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:14:08 INFO - Running setup.py (path:/tmp/pip-avw3j2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:14:08 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)) 16:14:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:14:08 INFO - Running setup.py (path:/tmp/pip-7HRfjB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:14:08 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)) 16:14:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:14:08 INFO - Running setup.py (path:/tmp/pip-Dem6f6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:14:08 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)) 16:14:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:14:08 INFO - Running setup.py (path:/tmp/pip-ztczwj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:14:08 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)) 16:14:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:14:08 INFO - Running setup.py (path:/tmp/pip-rCcta_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:14:08 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)) 16:14:08 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)) 16:14:08 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)) 16:14:08 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)) 16:14:08 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)) 16:14:08 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)) 16:14:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:10 INFO - Downloading blessings-1.5.1.tar.gz 16:14:10 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 16:14:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:14:10 INFO - Installing collected packages: blessings 16:14:10 INFO - Running setup.py install for blessings 16:14:10 INFO - Successfully installed blessings 16:14:10 INFO - Cleaning up... 16:14:10 INFO - Return code: 0 16:14:10 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:14:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:14:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:14:10 INFO - Reading from file tmpfile_stdout 16:14:10 INFO - Current package versions: 16:14:10 INFO - argparse == 1.2.1 16:14:10 INFO - blessings == 1.5.1 16:14:10 INFO - blobuploader == 1.2.4 16:14:10 INFO - docopt == 0.6.1 16:14:10 INFO - manifestparser == 1.1 16:14:10 INFO - mozInstall == 1.12 16:14:10 INFO - mozcrash == 0.16 16:14:10 INFO - mozdebug == 0.1 16:14:10 INFO - mozdevice == 0.46 16:14:10 INFO - mozfile == 1.2 16:14:10 INFO - mozhttpd == 0.7 16:14:10 INFO - mozinfo == 0.8 16:14:10 INFO - mozleak == 0.1 16:14:10 INFO - mozlog == 3.0 16:14:10 INFO - moznetwork == 0.27 16:14:10 INFO - mozprocess == 0.22 16:14:10 INFO - mozprofile == 0.27 16:14:10 INFO - mozrunner == 6.11 16:14:10 INFO - mozscreenshot == 0.1 16:14:10 INFO - mozsystemmonitor == 0.0 16:14:10 INFO - moztest == 0.7 16:14:10 INFO - mozversion == 1.4 16:14:10 INFO - psutil == 3.1.1 16:14:10 INFO - requests == 1.2.3 16:14:10 INFO - wsgiref == 0.1.2 16:14:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:10 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 0x7f4a39816f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2070600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ff350>, '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 0x2100250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21006e0>, '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': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', '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 16:14:10 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 16:14:10 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 16:14:10 INFO - Using env: {'DISPLAY': ':0', 16:14:10 INFO - 'HOME': '/home/cltbld', 16:14:10 INFO - 'LANG': 'en_US.UTF-8', 16:14:10 INFO - 'LANGUAGE': 'en_US:en', 16:14:10 INFO - 'LOGNAME': 'cltbld', 16:14:10 INFO - 'MAIL': '/var/mail/cltbld', 16:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:10 INFO - 'MOZ_NO_REMOTE': '1', 16:14:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:10 INFO - 'NO_EM_RESTART': '1', 16:14:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:10 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:10 INFO - 'SHELL': '/bin/bash', 16:14:10 INFO - 'SHLVL': '1', 16:14:10 INFO - 'TERM': 'linux', 16:14:10 INFO - 'TMOUT': '86400', 16:14:10 INFO - 'USER': 'cltbld', 16:14:10 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', 16:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:10 INFO - '_': '/tools/buildbot/bin/python'} 16:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 INFO - Cleaning up... 16:14:10 INFO - Return code: 0 16:14:10 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:10 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 0x7f4a39816f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2070600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ff350>, '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 0x2100250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21006e0>, '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': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', '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 16:14:10 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 16:14:10 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 16:14:10 INFO - Using env: {'DISPLAY': ':0', 16:14:10 INFO - 'HOME': '/home/cltbld', 16:14:10 INFO - 'LANG': 'en_US.UTF-8', 16:14:10 INFO - 'LANGUAGE': 'en_US:en', 16:14:10 INFO - 'LOGNAME': 'cltbld', 16:14:10 INFO - 'MAIL': '/var/mail/cltbld', 16:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:10 INFO - 'MOZ_NO_REMOTE': '1', 16:14:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:10 INFO - 'NO_EM_RESTART': '1', 16:14:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:10 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:10 INFO - 'SHELL': '/bin/bash', 16:14:10 INFO - 'SHLVL': '1', 16:14:10 INFO - 'TERM': 'linux', 16:14:10 INFO - 'TMOUT': '86400', 16:14:10 INFO - 'USER': 'cltbld', 16:14:10 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', 16:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:10 INFO - '_': '/tools/buildbot/bin/python'} 16:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:11 INFO - Downloading/unpacking jsonschema 16:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:14:12 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 16:14:12 INFO - Downloading/unpacking functools32 (from jsonschema) 16:14:12 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 16:14:12 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 16:14:12 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 16:14:12 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 16:14:12 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 16:14:12 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 16:14:12 INFO - Downloading functools32-3.2.3-2.tar.gz 16:14:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 16:14:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:14:12 INFO - warning: no files found matching '*.txt' 16:14:12 INFO - no previously-included directories found matching 'build' 16:14:12 INFO - no previously-included directories found matching 'dist' 16:14:12 INFO - no previously-included directories found matching '.git*' 16:14:13 INFO - Installing collected packages: jsonschema, functools32 16:14:13 INFO - Running setup.py install for functools32 16:14:13 INFO - warning: no files found matching '*.txt' 16:14:13 INFO - no previously-included directories found matching 'build' 16:14:13 INFO - no previously-included directories found matching 'dist' 16:14:13 INFO - no previously-included directories found matching '.git*' 16:14:13 INFO - Successfully installed jsonschema functools32 16:14:13 INFO - Cleaning up... 16:14:13 INFO - Return code: 0 16:14:13 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 16:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:13 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 0x7f4a39816f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2070600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ff350>, '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 0x2100250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21006e0>, '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': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', '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 16:14:13 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 16:14:13 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 16:14:13 INFO - Using env: {'DISPLAY': ':0', 16:14:13 INFO - 'HOME': '/home/cltbld', 16:14:13 INFO - 'LANG': 'en_US.UTF-8', 16:14:13 INFO - 'LANGUAGE': 'en_US:en', 16:14:13 INFO - 'LOGNAME': 'cltbld', 16:14:13 INFO - 'MAIL': '/var/mail/cltbld', 16:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:13 INFO - 'MOZ_NO_REMOTE': '1', 16:14:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:13 INFO - 'NO_EM_RESTART': '1', 16:14:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:13 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:13 INFO - 'SHELL': '/bin/bash', 16:14:13 INFO - 'SHLVL': '1', 16:14:13 INFO - 'TERM': 'linux', 16:14:13 INFO - 'TMOUT': '86400', 16:14:13 INFO - 'USER': 'cltbld', 16:14:13 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', 16:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:13 INFO - '_': '/tools/buildbot/bin/python'} 16:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:13 INFO - Downloading/unpacking flake8 16:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:14:15 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 16:14:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:14:15 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 16:14:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:15 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 16:14:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 16:14:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 16:14:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:14:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:14:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:14:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:14:15 INFO - no previously-included directories found matching 'docs/_build' 16:14:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:14:16 INFO - Running setup.py install for pep8 16:14:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:14:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:14:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:14:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:14:18 INFO - no previously-included directories found matching 'docs/_build' 16:14:18 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 16:14:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:14:18 INFO - Cleaning up... 16:14:18 INFO - Return code: 0 16:14:18 INFO - Running post-action listener: _resource_record_post_action 16:14:18 INFO - Running post-action listener: _start_resource_monitoring 16:14:18 INFO - Starting resource monitoring. 16:14:18 INFO - ##### 16:14:18 INFO - ##### Running install step. 16:14:18 INFO - ##### 16:14:18 INFO - Running pre-action listener: _resource_record_pre_action 16:14:18 INFO - Running main action method: install 16:14:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:14:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:14:18 INFO - Reading from file tmpfile_stdout 16:14:18 INFO - Detecting whether we're running mozinstall >=1.0... 16:14:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:14:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:14:18 INFO - Reading from file tmpfile_stdout 16:14:18 INFO - Output received: 16:14:18 INFO - Usage: mozinstall [options] installer 16:14:18 INFO - Options: 16:14:18 INFO - -h, --help show this help message and exit 16:14:18 INFO - -d DEST, --destination=DEST 16:14:18 INFO - Directory to install application into. [default: 16:14:18 INFO - "/builds/slave/test-pgo"] 16:14:18 INFO - --app=APP Application being installed. [default: firefox] 16:14:18 INFO - mkdir: /builds/slave/test-pgo/build/application 16:14:18 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'] 16:14:18 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 16:14:33 INFO - Reading from file tmpfile_stdout 16:14:33 INFO - Output received: 16:14:33 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:14:33 INFO - Running post-action listener: _resource_record_post_action 16:14:33 INFO - ##### 16:14:33 INFO - ##### Running run-tests step. 16:14:33 INFO - ##### 16:14:33 INFO - Running pre-action listener: _resource_record_pre_action 16:14:33 INFO - Running main action method: run_tests 16:14:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:14:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:14:33 INFO - Python 2.7.3 16:14:33 INFO - Return code: 0 16:14:33 INFO - grabbing minidump binary from tooltool 16:14:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:33 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 0x20ff350>, '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 0x2100250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21006e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:14:33 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 16:14:33 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 16:14:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:14:33 INFO - Return code: 0 16:14:33 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:14:33 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:14:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:14:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:14:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:14:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 16:14:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 16:14:34 INFO - Return code: 0 16:14:34 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/1451944472/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:14:34 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/1451944472/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --webServer localhost 16:14:34 INFO - Using env: {'DISPLAY': ':0', 16:14:34 INFO - 'HOME': '/home/cltbld', 16:14:34 INFO - 'LANG': 'en_US.UTF-8', 16:14:34 INFO - 'LANGUAGE': 'en_US:en', 16:14:34 INFO - 'LOGNAME': 'cltbld', 16:14:34 INFO - 'MAIL': '/var/mail/cltbld', 16:14:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:14:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:34 INFO - 'MOZ_NO_REMOTE': '1', 16:14:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:34 INFO - 'NO_EM_RESTART': '1', 16:14:34 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:34 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:34 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:14:34 INFO - 'SHELL': '/bin/bash', 16:14:34 INFO - 'SHLVL': '1', 16:14:34 INFO - 'TERM': 'linux', 16:14:34 INFO - 'TMOUT': '86400', 16:14:34 INFO - 'USER': 'cltbld', 16:14:34 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1451952329.353571-624575631', 16:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:34 INFO - '_': '/tools/buildbot/bin/python'} 16:14:34 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/1451944472/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost'] with output_timeout 3600 16:14:34 INFO - mozversion INFO | application_buildid: 20160104135432 16:14:34 INFO - mozversion INFO | application_changeset: 57f85790732edeff1890c3c56a02b3ae049885ba 16:14:34 INFO - mozversion INFO | application_display_name: Nightly 16:14:34 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:14:34 INFO - mozversion INFO | application_name: Firefox 16:14:34 INFO - mozversion INFO | application_remotingname: firefox 16:14:34 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:14:34 INFO - mozversion INFO | application_vendor: Mozilla 16:14:34 INFO - mozversion INFO | application_version: 44.0 16:14:34 INFO - mozversion INFO | platform_buildid: 20160104135432 16:14:34 INFO - mozversion INFO | platform_changeset: 57f85790732edeff1890c3c56a02b3ae049885ba 16:14:34 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:14:34 INFO - mozversion INFO | platform_version: 44.0 16:14:34 INFO - 2016-01-04 16:14:34,416 DEBUG : using testdate: 1451952874 16:14:34 INFO - 2016-01-04 16:14:34,416 DEBUG : actual date: 1451952874 16:14:34 INFO - 2016-01-04 16:14:34,425 INFO : starting webserver on 'localhost:49714' 16:14:34 INFO - 2016-01-04 16:14:34,426 INFO : Starting test suite talos-linux64-ix-069 16:14:34 INFO - 2016-01-04 16:14:34,426 INFO : Starting test dromaeo_css 16:14:34 INFO - 2016-01-04 16:14:34,426 DEBUG : operating with platform_type : linux_ 16:14:34 INFO - 2016-01-04 16:14:34,427 INFO : Initialising browser for dromaeo_css test... 16:14:34 INFO - 2016-01-04 16:14:34,444 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpz4BzGy/profile http://localhost:49714/getInfo.html 16:14:38 INFO - 2016-01-04 16:14:38,747 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:14:38 INFO - 2016-01-04 16:14:38,747 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:14:38 INFO - 2016-01-04 16:14:38,748 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:14:38 INFO - 2016-01-04 16:14:38,748 DEBUG : BROWSER_OUTPUT: __metrics 16:14:38 INFO - 2016-01-04 16:14:38,864 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] 16:14:40 INFO - 2016-01-04 16:14:40,197 INFO : Browser initialized. 16:14:40 INFO - 2016-01-04 16:14:40,198 INFO : Running cycle 1/1 for dromaeo_css test... 16:14:40 INFO - 2016-01-04 16:14:40,198 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpz4BzGy/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:14:51 INFO - 2016-01-04 16:14:51,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 128548864 16:14:51 INFO - 2016-01-04 16:14:51,741 DEBUG : BROWSER_OUTPUT: 16:15:42 INFO - 2016-01-04 16:15:42,743 DEBUG : BROWSER_OUTPUT: 1451952942742 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] 16:17:51 INFO - 2016-01-04 16:17:51,101 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49714/tests/dromaeo/cssquery-ext.html) 16:17:52 INFO - 2016-01-04 16:17:52,057 DEBUG : BROWSER_OUTPUT: RSS: Main: 217174016 16:17:52 INFO - 2016-01-04 16:17:52,057 DEBUG : BROWSER_OUTPUT: 16:20:54 INFO - 2016-01-04 16:20:54,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-ext.html (next: http://localhost:49714/tests/dromaeo/cssquery-jquery.html) 16:20:54 INFO - 2016-01-04 16:20:54,595 DEBUG : BROWSER_OUTPUT: RSS: Main: 216629248 16:20:54 INFO - 2016-01-04 16:20:54,596 DEBUG : BROWSER_OUTPUT: 16:23:56 INFO - 2016-01-04 16:23:56,278 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49714/tests/dromaeo/cssquery-mootools.html) 16:23:56 INFO - 2016-01-04 16:23:56,839 DEBUG : BROWSER_OUTPUT: RSS: Main: 203800576 16:23:56 INFO - 2016-01-04 16:23:56,839 DEBUG : BROWSER_OUTPUT: 16:26:58 INFO - 2016-01-04 16:26:58,485 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49714/tests/dromaeo/cssquery-prototype.html) 16:26:59 INFO - 2016-01-04 16:26:59,052 DEBUG : BROWSER_OUTPUT: RSS: Main: 190763008 16:26:59 INFO - 2016-01-04 16:26:59,053 DEBUG : BROWSER_OUTPUT: 16:30:01 INFO - 2016-01-04 16:30:01,344 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49714/tests/dromaeo/cssquery-yui.html) 16:30:01 INFO - 2016-01-04 16:30:01,918 DEBUG : BROWSER_OUTPUT: RSS: Main: 193040384 16:30:01 INFO - 2016-01-04 16:30:01,918 DEBUG : BROWSER_OUTPUT: 16:33:04 INFO - 2016-01-04 16:33:04,048 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-yui.html (next: http://localhost:49714/tests/dromaeo/cssquery-dojo.html) 16:33:04 INFO - 2016-01-04 16:33:04,298 DEBUG : BROWSER_OUTPUT: RSS: Main: 236916736 16:33:04 INFO - 2016-01-04 16:33:04,298 DEBUG : BROWSER_OUTPUT: 16:33:04 INFO - 2016-01-04 16:33:04,299 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:33:04 INFO - 2016-01-04 16:33:04,299 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:33:04 INFO - 2016-01-04 16:33:04,300 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:33:04 INFO - 2016-01-04 16:33:04,301 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:33:04 INFO - 2016-01-04 16:33:04,302 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3346.7155291800195;3445.5348527948718;3468.2023134679025;3471.2883858808946;3509.947350789738;5660.264165658464;5703.973284808738;5711.800521890397;5730.226419433386;5749.166370876221;5744.741486633101;5767.336756273019;5788.797392091277;5799.971000144993;5812.50593699535;5094.133997220463;5231.163013917774;5262.842114736545;5323.60072994525;5359.624826262163;5237.764300606482;5257.342832145981;5261.237120617528;5261.500157485033;5264.8157314494;5064.822731204408;5146.253793199984;5200.92198617021;5226.738663066851;5237.397699264595;5172.715500647463;5280.2079688046715;5288.524032837063;5294.1529355303155;5302.337207849019;5239.0045891280915;5251.159814429673;5258.526732594085;5282.996230716151;5308.867278318035;5368.543673787733;5386.084365657847;5462.754176062087;5523.1163013917985;5562.833115006556;6187.195664563623;6188.040853667689;6193.87612247757;6194.690265486753;6202.13170156174;6197.03945888388;6201.255849298113;6207.906881396782;6209.5032397408095;6210.254769427653;5525.834224973217;5645.717714114319;5666.405027472098;5690.231818704422;5713.628614140109;6281.371862813682;6289.96855015722;6294.653794041372;6300.558960872787;6324.051392291101;5817.7963771267805;6070.969645151834;6112.908306375408;6121.693915304217;6122.908156377658;6028.367021462771;6101.816945491642;6117.668168587243;6138.202033735675;6138.235132557318;5978.3124940631515;6144.881340737587;6162.691865406712;6172.135900973867;6182.752689892354;5835.912461313083;5941.435563621449;6140.6929653517145;6178.62928224299;6184.721687524072;4578.427696537933;4598.655100867449;4599.310103484504;4599.448066232074;4620.792064357112;4121;4126.938095928564;4130.194612050681;4131.648809851136;4160.313548264563;5511.145772405283;5576.247206627135;5600.915986260209;5699.515541178963;5702.315722113373;6095.207623008981;6120.632762034292;6140.754369825247;6142.232220972379;6150.969245153746;5750.453706897838;5761.596688231867;5768.653880767168;5770.5095066919785;5779.682117483579;5144.871378215575;5210.97394513025;5239.947600524022;5283.656562323436;5338.6529875558745;6331.176946996861;6388.7125079371535;6398.328175541501;6413.647249401234;6414.518911081687;6220.875582488416;6233.283172435228;6234.812955611339;6237.937620623918;6238.407351301619;6050.485708714808;6125.409382524018;6138.2044803098925;6259.96870015647;6269.843253918506;6045.667488288011;6107.083937409424;6108.847278818065;6114.0828875669;6117.204763380732;6020.939790602038;6192.783252586138;6209.59637623553;6209.689515524296;6213.565050446607;5951.732172052355;6026.879462410815;6097.054956481575;6097.4817140543555;6115.877682446415;5997.640141591519;6009.098635204754;6009.2188015557695;6009.729562169626;6036.215292012011;5777.277840269966;5878.353381128158;5891.882162356815;5919.733611987384;5920.585559010828;5964.612300200473;6071.563763871364;6077.756889724362;6101.725422355916;6102.816915492543;5887.0580707086665;5890.410958904246;5897.852553686193;5922.200502932222;5938.287405511366 16:33:04 INFO - 2016-01-04 16:33:04,304 DEBUG : BROWSER_OUTPUT: |1;ext.html;563.8131732702661;598.3298705449897;600.4745847383542;600.6966481926627;609.1532769450463;34618.30763384734;43942.56057439424;44891.55108448915;44906.77546612271;44994.875128121814;46556.76721616387;47226.527734722644;47246.52753472464;47277.29084063733;47316.05367892649;8134.715284965027;8353.457025293337;9247.075292470767;9440.905590944105;10705.732356691105;10893.183011274146;11028.393438360887;11030.28303160296;11039.944800275987;11042.723931901686;11020.944895275512;11109.055730262937;11117.332960022424;11122.777544449105;11128.944355278212;10909.18181136414;10993.780124397546;10994.945025274863;11016.449177541172;11020.614278500254;10830.241883068189;10958.506867190996;10970.506327215295;10990.505427255792;10992.890071099268;43634.781826090984;44213;44293.55706442927;44354.77822610899;44356.112877742264;3510.473428985647;3946.6645335146545;3978.622030907059;4016.2369149861433;4204;33156.6684333156;33684.652613895414;33909.6609033909;34448.82775586106;34886.651133488594;3222.3716375306826;3252.894016034536;3264.2981758921946;3312.966870331314;3363.8486268117995;318503;318663;319253;319798;319969;59147.40852591419;60389.69805151034;70307.2969270311;75368;77577.22422775812;70521.2947870525;70728;70744.29255707377;70835.64582177057;70851.64574177096;17946.282148713904;18103.366382176562;18104.456866293767;18109.275628974956;18111;14400.207988560729;14401.351939162763;14414;14510.637234069021;14610.926945365205;22252.332430027123;22310.88844555767;22378.55242895133;22382.10471581151;22563;8347.791305217419;8485.95757021211;8514.106018868053;8536.957315213383;8662.354152710965;1041.2815157541272;1063.122923588043;1087.0596933652491;1123.5618108937522;1139.720768411728;30563.69436305653;30927.0721878344;31241.375172496424;31270.687293127234;31981.52027719586;1071.0842229893485;1091.7488977535052;1115.130198445214;1226.4297101847683;1278.878506541877;14118;14938.178400188093;15031.173285469404;15552.455664051704;15989.440369587008;393532;395635;396127;397390;399122;57184.71407642935;57602.71198644064;57624.71187644035;57671.71164144152;57690.711546442;1372.7323171981368;1399.979000314996;1495.6933828565118;1501.8648321651103;1528.2435194578532;29257.414851703274;29272.56091158592;29327;31018.844905775328;31564.526532102496;49885.25172122494;50212.24681629852;50342.49657503378;50404;50454.49545504498;248278;255427;256296;256629;257995;34320.82839585786;34784.65215347814;35188.64811351854;36118.63881361153;36190.27619447544;42860.35709464423;43276.13447730965;43618;44157.33763993607;44178.337324940796;38368.80815595904;39022.609773901895;40052.1989560213;40166.397504036875;40620.79689601533;39985.2002959945;40486.392704108875;40638;40816.591834082465;41132.383014254214;39996.20007599774;40294.39558406568;40527.59472405238;40597;40719.59280407158;33940.83029584836;35216.6719821405;35695.46456803202;35819.28361432705;35932.28135437329;23842.88078559596;26844.463110738066;26990.595141073292;27116.864415678585;27137.59293610558 16:33:04 INFO - 2016-01-04 16:33:04,307 DEBUG : BROWSER_OUTPUT: |2;jquery.html;365.2876890064374;370.5812431951894;380.4122630535822;383.8809968909637;389.7213492352967;12701.745965080692;15102.848971510282;17756.467305980826;19584.99999999998;19608.70586941195;17060.82939170608;17193.226304816282;17370.47888563342;18495.537611559717;18568.628627427443;5858.1505681676235;5998.610090344114;6005.459508644221;6005.459508644221;6009;4540.046590216042;4662.067586482717;5940.138679891413;6514.41370276675;6740.797776066702;6162.815115546518;6610.173728283965;6634.767783127568;6648.235452922903;6666.533342666015;6626.337366263359;6649.567778094409;6690.431313338372;6701.664916754167;6702.966485167592;5858.47273745365;5953.077273022688;5953.821385358403;5996.9205543002245;6000.8499787505225;14352.138871667734;24431.511369772506;24746.133885313928;25017.49965000708;25031.49937001268;2137.722096127509;2172.6197915364905;2184.028107492167;2191.6712493126;2237.3288013595857;10481.58073677052;10561.947190264;10567.577296908114;10823;10955.726106847313;1820.9362672306406;1852.6757817381904;1855.1929910488846;1867.3650958674116;1895.0998275819013;57409;58111;58238.4176158233;65478.34521654818;67207.66396168037;7648.158702542716;7819.765407037798;7957.6021198939825;7988.320992715568;8012.919870801334;9470;9514.571844267024;9522.95238523803;9523.857142142873;9529;6454.32229615893;6461.483081353481;6479.481641468671;6485.4487368573255;6495.8700825983215;6682.298358672367;6715.764948226789;6755.256921738604;6815.829604259933;6914.654267286615;3492.8078955657174;3676.28312479064;3696.3716168251462;3707.5921648618632;3742.4947632069866;1265.1397050006085;1269.136986848933;1274.254561081759;1297.0531511996296;1303.9478420863059;503.65247978894445;531.8351311093573;532.1459058211592;545.5826292885945;553.3802141601352;11303.434828258718;12789.744205116032;12983.221006739437;12985.675358115934;13151.21092734439;532.9600279979018;545.961782675209;557.2004174010308;558.8351436326293;577.6851615869361;6916.827079322957;6998.125234345707;7061.293870612898;7071.929280707127;7188.28117188277;631659;634093;652279;658222;659196;69875.65062174656;74239.25760742431;74377.25622743812;76016.23983760092;86158;591.9319278282967;603.7554790309931;604.4710877981765;606.0182504342905;606.084811933974;11887.702807429883;12139.150259481752;12393.380330983235;12408.193467424588;12409.62771116868;37679.43480847844;37766.62233377631;37890.2421951565;38657.2268554633;39138;49874;50490.747546262035;50560.494395055575;51281.48718512767;51402;7506.662200201114;7610.200928902607;7622.961885190539;7627.694892204249;7634.50375725576;10772.084372828398;12525.93737031309;13306.268155251553;13454.663633409244;13725.107867988549;11626.360550169822;11656.88343116558;11855.762884742084;11876.346800925687;11901.642950711492;11126.443677815914;11237.876212378696;11442.084633229155;11528.884711152781;11552.711182220512;11094.500747466222;11136.164787640797;11313.094952403624;11792.646220613395;11845.289282643069;7705.1909549496495;7709.922900770921;7806.531608103532;7822.13956464777;7830.33442157423;12031.879681203425;12065.215760975509;12169.513219471122;12354.938225308815;12357.196782209128 16:33:04 INFO - 2016-01-04 16:33:04,309 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1533.2103966457285;1772.1847949943028;1807.493901707522;1847.8244566766161;1870.7568016157898;6449.645269510175;6602.43879270262;6613.404793568584;6962.547434416772;7120.608366539838;7535.472516923817;7678.270564296383;7760.689572417096;7764.495307804988;7767.805804854882;2383.2254517281867;2424.078850036992;2426.817988650849;2427;2427.174760581402;2132.818710409609;2178.7494438139656;2339.1929784224417;2385.9642105368434;2418.830681852271;2359.056377449017;2369.5379401016808;2369.7156341239074;2370.7392186859433;2382.988085059581;2400.291913885408;2419.830611857153;2420.1529464687396;2420.8668523231217;2421.69728783902;2347.835651504395;2392.1627430399394;2395.317334559642;2396.892139853711;2399.3881560202212;7788.415868809862;7855.371570274365;7859.2533709298095;7862.449628526006;7867.881981770278;4826.420829500447;4903.681260718077;4906.1414252505665;4909.484504127051;4911.214205727067;3082.9845850770826;3220.66183050781;3297.8186199759;3309.25541753106;3324.9501257481156;1086.940218287994;1095.2333366643381;1104.5195340027115;1107.0811226681835;1124.8087825069676;10513.106385957202;10525.736856578647;10546.894531054668;10564.577416903392;10565.313254638424;4512.548745125527;4561;4571.1086338164405;4575.885602859955;4581.221192397234;8926.330525210635;8958.596863141174;8969.551522423853;8982.775430614287;9021.052789457144;5009.398872135294;5016.6237532184505;5019.29729837823;5031.371078615173;5041.6722913010535;4615.492295847454;4621.168189725881;4621.607163391082;4636.281376386665;4636.6058884995155;3622.7645203062243;3997.180577981565;4049.1294371710223;4060.2488539620263;4095.262852686545;1232.7842627540144;1254.084518301645;1323.305264736002;1329.0563699773077;1337.585348541955;1346.9259190744604;1478.6303424143964;1484.9851501485064;1488.3079368093888;1509.6603264265452;7666.616669166519;7675.808104797313;7791.883121753178;7797.9220207798335;7808.68765249395;1206.390772659801;1221.639616313172;1222.2116734706242;1237.5297386992886;1238.7150955280335;6034.969825150846;6062.666553339609;6132.202813634199;6133.3866613338305;6145.4161854623735;9200.723978280663;9509.096635819586;9578.808423831624;9585.185259252901;9587.760305992406;9375.203107735782;9479.336446448682;9479.33644644882;9492.857607135898;9515.096065873731;6987.685554150075;7147.106611673541;7149.571025738473;7198.172210195789;7201.3518783309755;7562.168161502121;7606.543607383574;7659.272369124924;7674.155842857393;7676.040494938133;8520.914790852012;8659.783505412415;8675.34934879899;8676.479411235347;8677.26243269304;8670.696525621574;8856.202941735275;8859.645614175619;8860.512671803113;9008.32437567172;7213.1704853942465;7286.526375785557;7293.598852063187;7301.634918254173;7301.817454563466;7674.347680447225;7723.961380193063;7744.419168562267;7752.961235194013;7786;7056.1179852518435;7121.537100088478;7293.343599076109;7353.8161545961775;7404.740834070782;6656.7337306509135;7213.422926165999;7289.234630363948;7391.852162956819;7392.4086073115095;7292.0520332356455;7326.9267307326245;7332.0834895638045;7332.486725929134;7339.889901651372;6657.800265992028;6769.627670477974;6776.085228494288;6786.151731033621;6789.796306110825;6764.2221144569;6795.082663840319;6820.113385260083;6824.692888820115;6838 16:33:04 INFO - 2016-01-04 16:33:04,310 DEBUG : BROWSER_OUTPUT: |4;prototype.html;363.63454727998356;365.46459436924897;368.50068157646393;370.5608853508591;374.08722716571566;5900.350961394243;5991.281046274453;6022.457978781908;6045.667488288148;6052.182955301038;5837.7373018214175;5936.851578710523;5952.940470595293;5955.702214889249;5967.671778052206;4700;4700.059988002413;4700.670953033289;4700.976495117519;4705.552972467619;4699.553542413482;4701.882452938669;4710.199266124767;4710.929336059962;4715.269133284346;4629.722216667011;4660.766961651921;4699.694519856215;4716.599089077416;4717.716936983775;4684.250519916831;4689.9765501172615;4703.318018887259;4706.647001474886;4710.811567537295;4022.6178513041214;4077.368007958771;4228.027553662644;4230.8519201827785;4234.386014027979;5674.177244299594;5718.942810571924;5722.227499287585;5751.741171647286;5752.5110365618975;5470.097433923398;5472.50747432729;5475.315585551806;5475.561955043587;5480.835574932758;6265.328763102515;6297.811065668037;6299.68501574924;6302.212223472066;6331.366863313678;4727.5272472752795;4732.432108147044;4738.1708201064675;4738.573528382462;4740.928886066712;6566.474682025474;6631.767888123893;6631.801045968628;6638.900416493757;6644.169478815148;4840;4846.29728689338;4849.878753031202;4853.538913803183;4855.830045948375;4838.588719958772;4921.704697718148;4931.753412329369;4931.950680493149;4941.555260026615;5551.72241387929;5552.472515111058;5553.666779993213;5553.972230138905;5557.888842223133;6454.386833250834;6458.838529036811;6461.547691661539;6461.870762584722;6479.481641468671;1767.734839774044;1791.3730194432105;1791.3819732192374;1791.8566514678794;1792.3905872003581;1225.1179151011258;1228.9815652765217;1232.9013678905667;1251.7871961766339;1263;6088.6042407243385;6100.054491553817;6100.572959892854;6102.4202700744245;6103.450689437972;6638.236602790645;6641.269460359356;6650.567713098633;6651.135352404156;6653.234877989094;5964.194833697482;6086.147939288503;6092.238470191226;6092.939070609325;6100.877982440326;3660.9816950915074;3679.742418030712;3682.208325210093;3683.5211422514903;3687.1888184599347;6617.636030018299;6667.56660817055;6713.362230588086;6773.729050837911;6788.185417749902;6806.183258008971;6838.076859623887;6839.350261725128;6841.17905851301;6851.040854280404;6751.189857217068;6876.209235937831;6876.484263680144;6883.174019117738;6884.0362349271145;6704.094947182263;6707.061011458497;6712.194536655633;6712.362325579063;6718.630475323904;6580.703868325841;6594.505412094208;6600.042993765973;6616.867662646817;6616.90074648871;6710.698018589078;6867.725290988305;6882.242953275236;6888.104546408935;6899.551529150589;4659.254519276899;4690.601298889633;4696.107739529479;4698.412698412699;4701.0362875609735;6603.636799976048;6610.933890661032;6618.305077966744;6621.1392518972225;6628.5691430056895;6349.9682501587195;6405.583637063576;6457.515686323451;6469.223693156851;6472.967635161794;6434.935650643433;6445.0332450132855;6452.22573291208;6523.934760652332;6532.248791388861;6415.615063096229;6422.197225346832;6426.357364263723;6428.357164283534;6429.839254018687;6463.57986730861;6513.772017979348;6539.346065393423;6564.048213009182;6566;6407.615543067431;6408.327125651739;6414.647194404352;6424.871502570016;6427.58220715652 16:33:04 INFO - 2016-01-04 16:33:04,311 DEBUG : BROWSER_OUTPUT: |5;yui.html;803.9236272554107;808.3937047214589;821.1665159862738;857.425524898318;865.5974971638187;13545;13692.383842727077;13697.246651434167;13780.104293220946;13805.51680691176;13057.216567005962;13133;13150.473981040746;13158.144720593154;13177.802332965013;756.1417790807445;763.3778470546487;903.4398672822842;903.530164314556;913.0185051070093;912.1699253679153;914.9771255718593;916.4043371808344;919.9770005749842;920.7974245665977;784.90973538043;939.9953000234989;956.6556039825657;958.5015791788265;958.9232861371073;782.22950393862;935.223764275653;945.6784693204344;947.5593848860314;948.340903072365;708.0688894104236;709.1419382547124;709.1419382547124;709.2871663977669;709.6097146569418;13310.667233319147;13426.194428334331;13459.932700336436;13478.05653604248;13487.66280842977;11512.251703639236;11766.647000589996;11939.462724177432;12094.395280236042;12099.697507562294;9904.158146557551;10144.289899707024;10168.593256269814;10185.541650625666;10247.743806404826;3436.5876094868777;3478.0783092480515;3489.4068008438626;3490.825458727079;3492.633273506296;46197.53802461966;46681.29978050332;46743.76628116905;52326.7383663083;52421;6812.250652428229;6816.250212476623;6847.281035491302;6967.325679528917;7072.398846098139;13115.344232788322;13135;13147.934260328637;13152.736945261042;13162.60512184636;6885.277045910208;6908.3091690830515;6946.930530694628;7020.227774944752;7051.964740176266;4285.871423857227;4308.246056940023;4336.4145840311785;4338.783060846945;4345.565443455693;2970;2989.0136255036223;2994.2664047308112;3008.157715839525;3021.9093427197217;436.36726746218113;453.4037740371401;460.617687319524;463.5179413410016;470.8752180672125;639.4436839949274;663.9435647969976;665.2349797732646;674.039493721445;683.5351960666695;7702.293811601878;7933.920660793434;8077;8120.147384524658;8279.417961875064;513.922911563261;576.6511260687307;603.2158194347331;635.6027482823235;686.5777546808677;4366.19225443294;4399.670024748157;4462.44219472566;4495.303227999665;4561.452625684873;63312.36687633065;63948.68025659842;64314.035289470696;64351.67824160943;64391.356086439475;54391.728041359536;54940.17589736157;55041;55296.72351638216;57152.71423642938;50927;51782.48217517776;52222.47777522252;52357;53212.201816972774;8694.260987815853;8790.208881200468;9071.138241867013;9251.352405331561;9319.394239374418;38815.6118438812;38991.2201755969;39706.205875881744;40421.797891010356;40572.3914141294;52241.477585225184;53473.46526534685;53623.46376536185;53861.73069134629;54168.72915635554;8735.781605459915;8867.246284065926;8902.643894244417;8939.418937769024;9013.774655633662;13021.609351719464;13037.739245215233;13130.934345328213;13172.407241673956;13289.20264784116;12003.819942700691;12090.758184836079;12272.81590776157;12390.81413778776;12544.435500402686;12131.514739410324;12175.939120304341;12180.451879665614;12244.75510489803;12801.679958000752;10668.62659806903;11077.612283570037;11335.308921418437;11825.113116516124;12218.45016974256;9697.563609637442;9814.55834487464;10042.297039207186;10359.482025898524;10605.89394106049;10245.180385569285;10664.573417063231;10726.73183170427;10732.356058636507;10782 16:33:04 INFO - 2016-01-04 16:33:04,311 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:33:04 INFO - 2016-01-04 16:33:04,311 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:33:04 INFO - 2016-01-04 16:33:04,311 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,709 16:33:04 INFO - 2016-01-04 16:33:04,311 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:33:04 INFO - 2016-01-04 16:33:04,311 DEBUG : BROWSER_OUTPUT: __startTimestamp1451953984298__endTimestamp 16:33:04 INFO - 2016-01-04 16:33:04,312 DEBUG : BROWSER_OUTPUT: 16:33:04 INFO - 2016-01-04 16:33:04,312 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:33:04 INFO - 2016-01-04 16:33:04,312 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:33:04 INFO - 2016-01-04 16:33:04,312 DEBUG : BROWSER_OUTPUT: 16:33:04 INFO - 2016-01-04 16:33:04,312 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5690.20 Median:5897.85 stddev:643.80 (10.9%) stddev-sans-first:618.97 16:33:04 INFO - 2016-01-04 16:33:04,312 DEBUG : BROWSER_OUTPUT: Values: 3346.7 3445.5 3468.2 3471.3 3509.9 5660.3 5704.0 5711.8 5730.2 5749.2 5744.7 5767.3 5788.8 5800.0 5812.5 5094.1 5231.2 5262.8 5323.6 5359.6 5237.8 5257.3 5261.2 5261.5 5264.8 5064.8 5146.3 5200.9 5226.7 5237.4 5172.7 5280.2 5288.5 5294.2 5302.3 5239.0 5251.2 5258.5 5283.0 5308.9 5368.5 5386.1 5462.8 5523.1 5562.8 6187.2 6188.0 6193.9 6194.7 6202.1 6197.0 6201.3 6207.9 6209.5 6210.3 5525.8 5645.7 5666.4 5690.2 5713.6 6281.4 6290.0 6294.7 6300.6 6324.1 5817.8 6071.0 6112.9 6121.7 6122.9 6028.4 6101.8 6117.7 6138.2 6138.2 5978.3 6144.9 6162.7 6172.1 6182.8 5835.9 5941.4 6140.7 6178.6 6184.7 4578.4 4598.7 4599.3 4599.4 4620.8 4121.0 4126.9 4130.2 4131.6 4160.3 5511.1 5576.2 5600.9 5699.5 5702.3 6095.2 6120.6 6140.8 6142.2 6151.0 5750.5 5761.6 5768.7 5770.5 5779.7 5144.9 5211.0 5239.9 5283.7 5338.7 6331.2 6388.7 6398.3 6413.6 6414.5 6220.9 6233.3 6234.8 6237.9 6238.4 6050.5 6125.4 6138.2 6260.0 6269.8 6045.7 6107.1 6108.8 6114.1 6117.2 6020.9 6192.8 6209.6 6209.7 6213.6 5951.7 6026.9 6097.1 6097.5 6115.9 5997.6 6009.1 6009.2 6009.7 6036.2 5777.3 5878.4 5891.9 5919.7 5920.6 5964.6 6071.6 6077.8 6101.7 6102.8 5887.1 5890.4 5897.9 5922.2 5938.3 16:33:04 INFO - 2016-01-04 16:33:04,312 DEBUG : BROWSER_OUTPUT: 16:33:04 INFO - 2016-01-04 16:33:04,313 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51431.14 Median:34384.83 stddev:86089.80 (250.4%) stddev-sans-first:86246.58 16:33:04 INFO - 2016-01-04 16:33:04,313 DEBUG : BROWSER_OUTPUT: Values: 563.8 598.3 600.5 600.7 609.2 34618.3 43942.6 44891.6 44906.8 44994.9 46556.8 47226.5 47246.5 47277.3 47316.1 8134.7 8353.5 9247.1 9440.9 10705.7 10893.2 11028.4 11030.3 11039.9 11042.7 11020.9 11109.1 11117.3 11122.8 11128.9 10909.2 10993.8 10994.9 11016.4 11020.6 10830.2 10958.5 10970.5 10990.5 10992.9 43634.8 44213.0 44293.6 44354.8 44356.1 3510.5 3946.7 3978.6 4016.2 4204.0 33156.7 33684.7 33909.7 34448.8 34886.7 3222.4 3252.9 3264.3 3313.0 3363.8 318503.0 318663.0 319253.0 319798.0 319969.0 59147.4 60389.7 70307.3 75368.0 77577.2 70521.3 70728.0 70744.3 70835.6 70851.6 17946.3 18103.4 18104.5 18109.3 18111.0 14400.2 14401.4 14414.0 14510.6 14610.9 22252.3 22310.9 22378.6 22382.1 22563.0 8347.8 8486.0 8514.1 8537.0 8662.4 1041.3 1063.1 1087.1 1123.6 1139.7 30563.7 30927.1 31241.4 31270.7 31981.5 1071.1 1091.7 1115.1 1226.4 1278.9 14118.0 14938.2 15031.2 15552.5 15989.4 393532.0 395635.0 396127.0 397390.0 399122.0 57184.7 57602.7 57624.7 57671.7 57690.7 1372.7 1400.0 1495.7 1501.9 1528.2 29257.4 29272.6 29327.0 31018.8 31564.5 49885.3 50212.2 50342.5 50404.0 50454.5 248278.0 255427.0 256296.0 256629.0 257995.0 34320.8 34784.7 35188.6 36118.6 36190.3 42860.4 43276.1 43618.0 44157.3 44178.3 38368.8 39022.6 40052.2 40166.4 40620.8 39985.2 40486.4 40638.0 40816.6 41132.4 39996.2 40294.4 40527.6 40597.0 40719.6 33940.8 35216.7 35695.5 35819.3 35932.3 23842.9 26844.5 26990.6 27116.9 27137.6 16:33:04 INFO - 2016-01-04 16:33:04,313 DEBUG : BROWSER_OUTPUT: 16:33:04 INFO - 2016-01-04 16:33:04,313 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31137.14 Median:50920.99 stddev:105769.47 (207.7%) stddev-sans-first:106040.94 16:33:04 INFO - 2016-01-04 16:33:04,313 DEBUG : BROWSER_OUTPUT: Values: 365.3 370.6 380.4 383.9 389.7 12701.7 15102.8 17756.5 19585.0 19608.7 17060.8 17193.2 17370.5 18495.5 18568.6 5858.2 5998.6 6005.5 6005.5 6009.0 4540.0 4662.1 5940.1 6514.4 6740.8 6162.8 6610.2 6634.8 6648.2 6666.5 6626.3 6649.6 6690.4 6701.7 6703.0 5858.5 5953.1 5953.8 5996.9 6000.8 14352.1 24431.5 24746.1 25017.5 25031.5 2137.7 2172.6 2184.0 2191.7 2237.3 10481.6 10561.9 10567.6 10823.0 10955.7 1820.9 1852.7 1855.2 1867.4 1895.1 57409.0 58111.0 58238.4 65478.3 67207.7 7648.2 7819.8 7957.6 7988.3 8012.9 9470.0 9514.6 9523.0 9523.9 9529.0 6454.3 6461.5 6479.5 6485.4 6495.9 6682.3 6715.8 6755.3 6815.8 6914.7 3492.8 3676.3 3696.4 3707.6 3742.5 1265.1 1269.1 1274.3 1297.1 1303.9 503.7 531.8 532.1 545.6 553.4 11303.4 12789.7 12983.2 12985.7 13151.2 533.0 546.0 557.2 558.8 577.7 6916.8 6998.1 7061.3 7071.9 7188.3 631659.0 634093.0 652279.0 658222.0 659196.0 69875.7 74239.3 74377.3 76016.2 86158.0 591.9 603.8 604.5 606.0 606.1 11887.7 12139.2 12393.4 12408.2 12409.6 37679.4 37766.6 37890.2 38657.2 39138.0 49874.0 50490.7 50560.5 51281.5 51402.0 7506.7 7610.2 7623.0 7627.7 7634.5 10772.1 12525.9 13306.3 13454.7 13725.1 11626.4 11656.9 11855.8 11876.3 11901.6 11126.4 11237.9 11442.1 11528.9 11552.7 11094.5 11136.2 11313.1 11792.6 11845.3 7705.2 7709.9 7806.5 7822.1 7830.3 12031.9 12065.2 12169.5 12354.9 12357.2 16:33:04 INFO - 2016-01-04 16:33:04,313 DEBUG : BROWSER_OUTPUT: 16:33:04 INFO - 2016-01-04 16:33:04,313 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5618.78 Median:6526.04 stddev:2793.25 (42.8%) stddev-sans-first:2784.21 16:33:04 INFO - 2016-01-04 16:33:04,314 DEBUG : BROWSER_OUTPUT: Values: 1533.2 1772.2 1807.5 1847.8 1870.8 6449.6 6602.4 6613.4 6962.5 7120.6 7535.5 7678.3 7760.7 7764.5 7767.8 2383.2 2424.1 2426.8 2427.0 2427.2 2132.8 2178.7 2339.2 2386.0 2418.8 2359.1 2369.5 2369.7 2370.7 2383.0 2400.3 2419.8 2420.2 2420.9 2421.7 2347.8 2392.2 2395.3 2396.9 2399.4 7788.4 7855.4 7859.3 7862.4 7867.9 4826.4 4903.7 4906.1 4909.5 4911.2 3083.0 3220.7 3297.8 3309.3 3325.0 1086.9 1095.2 1104.5 1107.1 1124.8 10513.1 10525.7 10546.9 10564.6 10565.3 4512.5 4561.0 4571.1 4575.9 4581.2 8926.3 8958.6 8969.6 8982.8 9021.1 5009.4 5016.6 5019.3 5031.4 5041.7 4615.5 4621.2 4621.6 4636.3 4636.6 3622.8 3997.2 4049.1 4060.2 4095.3 1232.8 1254.1 1323.3 1329.1 1337.6 1346.9 1478.6 1485.0 1488.3 1509.7 7666.6 7675.8 7791.9 7797.9 7808.7 1206.4 1221.6 1222.2 1237.5 1238.7 6035.0 6062.7 6132.2 6133.4 6145.4 9200.7 9509.1 9578.8 9585.2 9587.8 9375.2 9479.3 9479.3 9492.9 9515.1 6987.7 7147.1 7149.6 7198.2 7201.4 7562.2 7606.5 7659.3 7674.2 7676.0 8520.9 8659.8 8675.3 8676.5 8677.3 8670.7 8856.2 8859.6 8860.5 9008.3 7213.2 7286.5 7293.6 7301.6 7301.8 7674.3 7724.0 7744.4 7753.0 7786.0 7056.1 7121.5 7293.3 7353.8 7404.7 6656.7 7213.4 7289.2 7391.9 7392.4 7292.1 7326.9 7332.1 7332.5 7339.9 6657.8 6769.6 6776.1 6786.2 6789.8 6764.2 6795.1 6820.1 6824.7 6838.0 16:33:04 INFO - 2016-01-04 16:33:04,314 DEBUG : BROWSER_OUTPUT: 16:33:04 INFO - 2016-01-04 16:33:04,315 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5432.11 Median:6069.17 stddev:1572.82 (25.9%) stddev-sans-first:1530.54 16:33:04 INFO - 2016-01-04 16:33:04,315 DEBUG : BROWSER_OUTPUT: Values: 363.6 365.5 368.5 370.6 374.1 5900.4 5991.3 6022.5 6045.7 6052.2 5837.7 5936.9 5952.9 5955.7 5967.7 4700.0 4700.1 4700.7 4701.0 4705.6 4699.6 4701.9 4710.2 4710.9 4715.3 4629.7 4660.8 4699.7 4716.6 4717.7 4684.3 4690.0 4703.3 4706.6 4710.8 4022.6 4077.4 4228.0 4230.9 4234.4 5674.2 5718.9 5722.2 5751.7 5752.5 5470.1 5472.5 5475.3 5475.6 5480.8 6265.3 6297.8 6299.7 6302.2 6331.4 4727.5 4732.4 4738.2 4738.6 4740.9 6566.5 6631.8 6631.8 6638.9 6644.2 4840.0 4846.3 4849.9 4853.5 4855.8 4838.6 4921.7 4931.8 4932.0 4941.6 5551.7 5552.5 5553.7 5554.0 5557.9 6454.4 6458.8 6461.5 6461.9 6479.5 1767.7 1791.4 1791.4 1791.9 1792.4 1225.1 1229.0 1232.9 1251.8 1263.0 6088.6 6100.1 6100.6 6102.4 6103.5 6638.2 6641.3 6650.6 6651.1 6653.2 5964.2 6086.1 6092.2 6092.9 6100.9 3661.0 3679.7 3682.2 3683.5 3687.2 6617.6 6667.6 6713.4 6773.7 6788.2 6806.2 6838.1 6839.4 6841.2 6851.0 6751.2 6876.2 6876.5 6883.2 6884.0 6704.1 6707.1 6712.2 6712.4 6718.6 6580.7 6594.5 6600.0 6616.9 6616.9 6710.7 6867.7 6882.2 6888.1 6899.6 4659.3 4690.6 4696.1 4698.4 4701.0 6603.6 6610.9 6618.3 6621.1 6628.6 6350.0 6405.6 6457.5 6469.2 6473.0 6434.9 6445.0 6452.2 6523.9 6532.2 6415.6 6422.2 6426.4 6428.4 6429.8 6463.6 6513.8 6539.3 6564.0 6566.0 6407.6 6408.3 6414.6 6424.9 6427.6 16:33:04 INFO - 2016-01-04 16:33:04,315 DEBUG : BROWSER_OUTPUT: 16:33:04 INFO - 2016-01-04 16:33:04,315 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14466.20 Median:25720.46 stddev:17804.91 (69.2%) stddev-sans-first:17825.30 16:33:04 INFO - 2016-01-04 16:33:04,316 DEBUG : BROWSER_OUTPUT: Values: 803.9 808.4 821.2 857.4 865.6 13545.0 13692.4 13697.2 13780.1 13805.5 13057.2 13133.0 13150.5 13158.1 13177.8 756.1 763.4 903.4 903.5 913.0 912.2 915.0 916.4 920.0 920.8 784.9 940.0 956.7 958.5 958.9 782.2 935.2 945.7 947.6 948.3 708.1 709.1 709.1 709.3 709.6 13310.7 13426.2 13459.9 13478.1 13487.7 11512.3 11766.6 11939.5 12094.4 12099.7 9904.2 10144.3 10168.6 10185.5 10247.7 3436.6 3478.1 3489.4 3490.8 3492.6 46197.5 46681.3 46743.8 52326.7 52421.0 6812.3 6816.3 6847.3 6967.3 7072.4 13115.3 13135.0 13147.9 13152.7 13162.6 6885.3 6908.3 6946.9 7020.2 7052.0 4285.9 4308.2 4336.4 4338.8 4345.6 2970.0 2989.0 2994.3 3008.2 3021.9 436.4 453.4 460.6 463.5 470.9 639.4 663.9 665.2 674.0 683.5 7702.3 7933.9 8077.0 8120.1 8279.4 513.9 576.7 603.2 635.6 686.6 4366.2 4399.7 4462.4 4495.3 4561.5 63312.4 63948.7 64314.0 64351.7 64391.4 54391.7 54940.2 55041.0 55296.7 57152.7 50927.0 51782.5 52222.5 52357.0 53212.2 8694.3 8790.2 9071.1 9251.4 9319.4 38815.6 38991.2 39706.2 40421.8 40572.4 52241.5 53473.5 53623.5 53861.7 54168.7 8735.8 8867.2 8902.6 8939.4 9013.8 13021.6 13037.7 13130.9 13172.4 13289.2 12003.8 12090.8 12272.8 12390.8 12544.4 12131.5 12175.9 12180.5 12244.8 12801.7 10668.6 11077.6 11335.3 11825.1 12218.5 9697.6 9814.6 10042.3 10359.5 10605.9 10245.2 10664.6 10726.7 10732.4 10782.0 16:33:04 INFO - 2016-01-04 16:33:04,316 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:33:04 INFO - 2016-01-04 16:33:04,316 DEBUG : BROWSER_OUTPUT: 16:33:04 INFO - 2016-01-04 16:33:04,878 INFO : Browser exited with error code: 0 16:33:04 INFO - 2016-01-04 16:33:04,894 INFO : Completed test dromaeo_css (00:18:30) 16:33:04 INFO - 2016-01-04 16:33:04,894 INFO : Starting test kraken 16:33:04 INFO - 2016-01-04 16:33:04,894 DEBUG : operating with platform_type : linux_ 16:33:04 INFO - 2016-01-04 16:33:04,894 INFO : Initialising browser for kraken test... 16:33:04 INFO - 2016-01-04 16:33:04,900 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0dFn0m/profile http://localhost:49714/getInfo.html 16:33:08 INFO - 2016-01-04 16:33:08,700 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:33:08 INFO - 2016-01-04 16:33:08,700 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:33:08 INFO - 2016-01-04 16:33:08,700 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:33:08 INFO - 2016-01-04 16:33:08,700 DEBUG : BROWSER_OUTPUT: __metrics 16:33:08 INFO - 2016-01-04 16:33:08,869 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] 16:33:10 INFO - 2016-01-04 16:33:10,154 INFO : Browser initialized. 16:33:10 INFO - 2016-01-04 16:33:10,154 INFO : Running cycle 1/1 for kraken test... 16:33:10 INFO - 2016-01-04 16:33:10,155 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0dFn0m/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:33:21 INFO - 2016-01-04 16:33:21,731 DEBUG : BROWSER_OUTPUT: RSS: Main: 130101248 16:33:21 INFO - 2016-01-04 16:33:21,732 DEBUG : BROWSER_OUTPUT: 16:33:24 INFO - 2016-01-04 16:33:24,158 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49714/tests/kraken/driver.html?testName=audio-beat-detection) 16:33:24 INFO - 2016-01-04 16:33:24,728 DEBUG : BROWSER_OUTPUT: RSS: Main: 163901440 16:33:24 INFO - 2016-01-04 16:33:24,728 DEBUG : BROWSER_OUTPUT: 16:33:27 INFO - 2016-01-04 16:33:27,265 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49714/tests/kraken/driver.html?testName=audio-dft) 16:33:27 INFO - 2016-01-04 16:33:27,826 DEBUG : BROWSER_OUTPUT: RSS: Main: 159285248 16:33:27 INFO - 2016-01-04 16:33:27,827 DEBUG : BROWSER_OUTPUT: 16:33:30 INFO - 2016-01-04 16:33:30,630 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49714/tests/kraken/driver.html?testName=audio-fft) 16:33:31 INFO - 2016-01-04 16:33:31,196 DEBUG : BROWSER_OUTPUT: RSS: Main: 159297536 16:33:31 INFO - 2016-01-04 16:33:31,196 DEBUG : BROWSER_OUTPUT: 16:33:33 INFO - 2016-01-04 16:33:33,132 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49714/tests/kraken/driver.html?testName=audio-oscillator) 16:33:33 INFO - 2016-01-04 16:33:33,698 DEBUG : BROWSER_OUTPUT: RSS: Main: 158535680 16:33:33 INFO - 2016-01-04 16:33:33,698 DEBUG : BROWSER_OUTPUT: 16:33:35 INFO - 2016-01-04 16:33:35,812 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49714/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:33:36 INFO - 2016-01-04 16:33:36,365 DEBUG : BROWSER_OUTPUT: RSS: Main: 157212672 16:33:36 INFO - 2016-01-04 16:33:36,366 DEBUG : BROWSER_OUTPUT: 16:33:39 INFO - 2016-01-04 16:33:39,216 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49714/tests/kraken/driver.html?testName=imaging-darkroom) 16:33:39 INFO - 2016-01-04 16:33:39,789 DEBUG : BROWSER_OUTPUT: RSS: Main: 156413952 16:33:39 INFO - 2016-01-04 16:33:39,790 DEBUG : BROWSER_OUTPUT: 16:33:43 INFO - 2016-01-04 16:33:43,132 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49714/tests/kraken/driver.html?testName=imaging-desaturate) 16:33:43 INFO - 2016-01-04 16:33:43,699 DEBUG : BROWSER_OUTPUT: RSS: Main: 157696000 16:33:43 INFO - 2016-01-04 16:33:43,699 DEBUG : BROWSER_OUTPUT: 16:33:46 INFO - 2016-01-04 16:33:46,449 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49714/tests/kraken/driver.html?testName=json-parse-financial) 16:33:47 INFO - 2016-01-04 16:33:47,015 DEBUG : BROWSER_OUTPUT: RSS: Main: 157683712 16:33:47 INFO - 2016-01-04 16:33:47,015 DEBUG : BROWSER_OUTPUT: 16:33:48 INFO - 2016-01-04 16:33:48,503 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49714/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:33:49 INFO - 2016-01-04 16:33:49,052 DEBUG : BROWSER_OUTPUT: RSS: Main: 160661504 16:33:49 INFO - 2016-01-04 16:33:49,052 DEBUG : BROWSER_OUTPUT: 16:33:50 INFO - 2016-01-04 16:33:50,803 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:33:51 INFO - 2016-01-04 16:33:51,393 DEBUG : BROWSER_OUTPUT: RSS: Main: 159031296 16:33:51 INFO - 2016-01-04 16:33:51,393 DEBUG : BROWSER_OUTPUT: 16:33:53 INFO - 2016-01-04 16:33:53,345 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:33:53 INFO - 2016-01-04 16:33:53,906 DEBUG : BROWSER_OUTPUT: RSS: Main: 163983360 16:33:53 INFO - 2016-01-04 16:33:53,906 DEBUG : BROWSER_OUTPUT: 16:33:56 INFO - 2016-01-04 16:33:56,221 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:33:56 INFO - 2016-01-04 16:33:56,787 DEBUG : BROWSER_OUTPUT: RSS: Main: 167997440 16:33:56 INFO - 2016-01-04 16:33:56,787 DEBUG : BROWSER_OUTPUT: 16:33:59 INFO - 2016-01-04 16:33:59,173 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:33:59 INFO - 2016-01-04 16:33:59,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 167256064 16:33:59 INFO - 2016-01-04 16:33:59,741 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,361 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49714/tests/kraken/driver.html?testName=ai-astar) 16:34:01 INFO - 2016-01-04 16:34:01,611 DEBUG : BROWSER_OUTPUT: RSS: Main: 226131968 16:34:01 INFO - 2016-01-04 16:34:01,612 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,612 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:34:01 INFO - 2016-01-04 16:34:01,612 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:34:01 INFO - 2016-01-04 16:34:01,612 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:34:01 INFO - 2016-01-04 16:34:01,613 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:34:01 INFO - 2016-01-04 16:34:01,613 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;103;110;111;109;100;109;101;109 16:34:01 INFO - 2016-01-04 16:34:01,613 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;119;143;161;125;148;145;122;160;121 16:34:01 INFO - 2016-01-04 16:34:01,613 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;168;159;158;162;160;158;160;158;162 16:34:01 INFO - 2016-01-04 16:34:01,614 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;70;145;72;71;71;71;148;71 16:34:01 INFO - 2016-01-04 16:34:01,614 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;89;132;89;89;89;132;89;89;139 16:34:01 INFO - 2016-01-04 16:34:01,614 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;110;110;109 16:34:01 INFO - 2016-01-04 16:34:01,614 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;144;146;147;145;146;145;146;145 16:34:01 INFO - 2016-01-04 16:34:01,614 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;97;96;95;95;95;95;95 16:34:01 INFO - 2016-01-04 16:34:01,615 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;53;55;54;57;55;54;54;62;52;54 16:34:01 INFO - 2016-01-04 16:34:01,615 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;62;63;63;63;62;62;62;64;62 16:34:01 INFO - 2016-01-04 16:34:01,615 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;76;85;78;79;78;78;79;78;78 16:34:01 INFO - 2016-01-04 16:34:01,615 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;111;111;105;116;116;111;117;108;116 16:34:01 INFO - 2016-01-04 16:34:01,616 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;134;134;134;133;138;133;134;132;132;144 16:34:01 INFO - 2016-01-04 16:34:01,616 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;60;62;62;61;60;62;61;60;62;63 16:34:01 INFO - 2016-01-04 16:34:01,616 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:34:01 INFO - 2016-01-04 16:34:01,616 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:34:01 INFO - 2016-01-04 16:34:01,616 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,864 16:34:01 INFO - 2016-01-04 16:34:01,617 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:34:01 INFO - 2016-01-04 16:34:01,617 DEBUG : BROWSER_OUTPUT: __startTimestamp1451954041611__endTimestamp 16:34:01 INFO - 2016-01-04 16:34:01,617 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,619 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:34:01 INFO - 2016-01-04 16:34:01,619 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:34:01 INFO - 2016-01-04 16:34:01,619 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,620 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.20 Median:109.00 stddev:4.18 (3.8%) stddev-sans-first:4.39 16:34:01 INFO - 2016-01-04 16:34:01,620 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 103.0 110.0 111.0 109.0 100.0 109.0 101.0 109.0 16:34:01 INFO - 2016-01-04 16:34:01,620 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,621 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.10 Median:146.50 stddev:16.93 (11.6%) stddev-sans-first:16.81 16:34:01 INFO - 2016-01-04 16:34:01,621 DEBUG : BROWSER_OUTPUT: Values: 157.0 119.0 143.0 161.0 125.0 148.0 145.0 122.0 160.0 121.0 16:34:01 INFO - 2016-01-04 16:34:01,621 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,621 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.50 Median:160.00 stddev:3.03 (1.9%) stddev-sans-first:3.21 16:34:01 INFO - 2016-01-04 16:34:01,621 DEBUG : BROWSER_OUTPUT: Values: 160.0 168.0 159.0 158.0 162.0 160.0 158.0 160.0 158.0 162.0 16:34:01 INFO - 2016-01-04 16:34:01,622 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,622 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.10 Median:71.00 stddev:31.84 (44.9%) stddev-sans-first:33.30 16:34:01 INFO - 2016-01-04 16:34:01,622 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 70.0 145.0 72.0 71.0 71.0 71.0 148.0 71.0 16:34:01 INFO - 2016-01-04 16:34:01,622 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,622 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.70 Median:89.00 stddev:21.91 (24.6%) stddev-sans-first:22.76 16:34:01 INFO - 2016-01-04 16:34:01,622 DEBUG : BROWSER_OUTPUT: Values: 90.0 89.0 132.0 89.0 89.0 89.0 132.0 89.0 89.0 139.0 16:34:01 INFO - 2016-01-04 16:34:01,622 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,623 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:34:01 INFO - 2016-01-04 16:34:01,623 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 110.0 109.0 16:34:01 INFO - 2016-01-04 16:34:01,623 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,623 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.84 (0.6%) stddev-sans-first:0.88 16:34:01 INFO - 2016-01-04 16:34:01,623 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 144.0 146.0 147.0 145.0 146.0 145.0 146.0 145.0 16:34:01 INFO - 2016-01-04 16:34:01,623 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,623 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 16:34:01 INFO - 2016-01-04 16:34:01,623 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 97.0 96.0 95.0 95.0 95.0 95.0 95.0 16:34:01 INFO - 2016-01-04 16:34:01,624 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,624 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.00 Median:54.50 stddev:2.79 (5.1%) stddev-sans-first:2.86 16:34:01 INFO - 2016-01-04 16:34:01,624 DEBUG : BROWSER_OUTPUT: Values: 53.0 55.0 54.0 57.0 55.0 54.0 54.0 62.0 52.0 54.0 16:34:01 INFO - 2016-01-04 16:34:01,624 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,624 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:62.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 16:34:01 INFO - 2016-01-04 16:34:01,624 DEBUG : BROWSER_OUTPUT: Values: 62.0 62.0 63.0 63.0 63.0 62.0 62.0 62.0 64.0 62.0 16:34:01 INFO - 2016-01-04 16:34:01,624 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,624 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:78.50 stddev:2.35 (3.0%) stddev-sans-first:2.49 16:34:01 INFO - 2016-01-04 16:34:01,624 DEBUG : BROWSER_OUTPUT: Values: 79.0 76.0 85.0 78.0 79.0 78.0 78.0 79.0 78.0 78.0 16:34:01 INFO - 2016-01-04 16:34:01,625 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,625 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.70 Median:116.00 stddev:4.11 (3.5%) stddev-sans-first:4.18 16:34:01 INFO - 2016-01-04 16:34:01,625 DEBUG : BROWSER_OUTPUT: Values: 116.0 111.0 111.0 105.0 116.0 116.0 111.0 117.0 108.0 116.0 16:34:01 INFO - 2016-01-04 16:34:01,625 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,625 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.80 Median:134.00 stddev:3.65 (2.7%) stddev-sans-first:3.86 16:34:01 INFO - 2016-01-04 16:34:01,625 DEBUG : BROWSER_OUTPUT: Values: 134.0 134.0 134.0 133.0 138.0 133.0 134.0 132.0 132.0 144.0 16:34:01 INFO - 2016-01-04 16:34:01,625 DEBUG : BROWSER_OUTPUT: 16:34:01 INFO - 2016-01-04 16:34:01,625 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:62.00 stddev:1.06 (1.7%) stddev-sans-first:1.01 16:34:01 INFO - 2016-01-04 16:34:01,625 DEBUG : BROWSER_OUTPUT: Values: 60.0 62.0 62.0 61.0 60.0 62.0 61.0 60.0 62.0 63.0 16:34:01 INFO - 2016-01-04 16:34:01,626 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:34:01 INFO - 2016-01-04 16:34:01,626 DEBUG : BROWSER_OUTPUT: 16:34:02 INFO - 2016-01-04 16:34:02,305 INFO : Browser exited with error code: 0 16:34:02 INFO - 2016-01-04 16:34:02,358 INFO : Completed test kraken (00:00:57) 16:34:02 INFO - 2016-01-04 16:34:02,358 INFO : Starting test v8_7 16:34:02 INFO - 2016-01-04 16:34:02,358 DEBUG : operating with platform_type : linux_ 16:34:02 INFO - 2016-01-04 16:34:02,358 INFO : Initialising browser for v8_7 test... 16:34:02 INFO - 2016-01-04 16:34:02,365 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpxACuBQ/profile http://localhost:49714/getInfo.html 16:34:06 INFO - 2016-01-04 16:34:06,231 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:34:06 INFO - 2016-01-04 16:34:06,231 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:34:06 INFO - 2016-01-04 16:34:06,232 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:34:06 INFO - 2016-01-04 16:34:06,232 DEBUG : BROWSER_OUTPUT: __metrics 16:34:06 INFO - 2016-01-04 16:34:06,340 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] 16:34:07 INFO - 2016-01-04 16:34:07,925 INFO : Browser initialized. 16:34:07 INFO - 2016-01-04 16:34:07,926 INFO : Running cycle 1/1 for v8_7 test... 16:34:07 INFO - 2016-01-04 16:34:07,926 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpxACuBQ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:34:19 INFO - 2016-01-04 16:34:19,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 129138688 16:34:19 INFO - 2016-01-04 16:34:19,538 DEBUG : BROWSER_OUTPUT: 16:34:40 INFO - 2016-01-04 16:34:40,865 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49714/tests/v8_7/run.html (next: http://localhost:49714/tests/v8_7/run.html) 16:34:41 INFO - 2016-01-04 16:34:41,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 415350784 16:34:41 INFO - 2016-01-04 16:34:41,117 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,117 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:34:41 INFO - 2016-01-04 16:34:41,118 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:34:41 INFO - 2016-01-04 16:34:41,118 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:34:41 INFO - 2016-01-04 16:34:41,118 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:34:41 INFO - 2016-01-04 16:34:41,118 DEBUG : BROWSER_OUTPUT: |0;Richards;161.39444803098775 16:34:41 INFO - 2016-01-04 16:34:41,118 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.446637066272 16:34:41 INFO - 2016-01-04 16:34:41,119 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.72027972027973 16:34:41 INFO - 2016-01-04 16:34:41,119 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4995.024875621891 16:34:41 INFO - 2016-01-04 16:34:41,119 DEBUG : BROWSER_OUTPUT: |4;RayTrace;947.8672985781991 16:34:41 INFO - 2016-01-04 16:34:41,119 DEBUG : BROWSER_OUTPUT: |5;Earley;547.645125958379 16:34:41 INFO - 2016-01-04 16:34:41,119 DEBUG : BROWSER_OUTPUT: |6;Boyer;10526.315789473685 16:34:41 INFO - 2016-01-04 16:34:41,119 DEBUG : BROWSER_OUTPUT: |7;RegExp;24390.243902439026 16:34:41 INFO - 2016-01-04 16:34:41,120 DEBUG : BROWSER_OUTPUT: |8;Splay;535.8672376873661 16:34:41 INFO - 2016-01-04 16:34:41,120 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 16:34:41 INFO - 2016-01-04 16:34:41,120 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:34:41 INFO - 2016-01-04 16:34:41,120 DEBUG : BROWSER_OUTPUT: __startTimestamp1451954081114__endTimestamp 16:34:41 INFO - 2016-01-04 16:34:41,120 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,120 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:34:41 INFO - 2016-01-04 16:34:41,121 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:34:41 INFO - 2016-01-04 16:34:41,121 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,121 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:161.39 Median:161.39 stddev:0.00 (0.0%) 16:34:41 INFO - 2016-01-04 16:34:41,121 DEBUG : BROWSER_OUTPUT: Values: 161.4 16:34:41 INFO - 2016-01-04 16:34:41,121 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,122 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.45 Median:164.45 stddev:0.00 (0.0%) 16:34:41 INFO - 2016-01-04 16:34:41,122 DEBUG : BROWSER_OUTPUT: Values: 164.4 16:34:41 INFO - 2016-01-04 16:34:41,122 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,122 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.72 Median:279.72 stddev:0.00 (0.0%) 16:34:41 INFO - 2016-01-04 16:34:41,122 DEBUG : BROWSER_OUTPUT: Values: 279.7 16:34:41 INFO - 2016-01-04 16:34:41,122 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,123 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4995.02 Median:4995.02 stddev:0.00 (0.0%) 16:34:41 INFO - 2016-01-04 16:34:41,123 DEBUG : BROWSER_OUTPUT: Values: 4995.0 16:34:41 INFO - 2016-01-04 16:34:41,123 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,123 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:947.87 Median:947.87 stddev:0.00 (0.0%) 16:34:41 INFO - 2016-01-04 16:34:41,123 DEBUG : BROWSER_OUTPUT: Values: 947.9 16:34:41 INFO - 2016-01-04 16:34:41,124 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,124 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:547.65 Median:547.65 stddev:0.00 (0.0%) 16:34:41 INFO - 2016-01-04 16:34:41,124 DEBUG : BROWSER_OUTPUT: Values: 547.6 16:34:41 INFO - 2016-01-04 16:34:41,124 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,124 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10526.32 Median:10526.32 stddev:0.00 (0.0%) 16:34:41 INFO - 2016-01-04 16:34:41,124 DEBUG : BROWSER_OUTPUT: Values: 10526.3 16:34:41 INFO - 2016-01-04 16:34:41,124 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,125 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24390.24 Median:24390.24 stddev:0.00 (0.0%) 16:34:41 INFO - 2016-01-04 16:34:41,125 DEBUG : BROWSER_OUTPUT: Values: 24390.2 16:34:41 INFO - 2016-01-04 16:34:41,125 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,125 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:535.87 Median:535.87 stddev:0.00 (0.0%) 16:34:41 INFO - 2016-01-04 16:34:41,125 DEBUG : BROWSER_OUTPUT: Values: 535.9 16:34:41 INFO - 2016-01-04 16:34:41,125 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,125 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 16:34:41 INFO - 2016-01-04 16:34:41,126 DEBUG : BROWSER_OUTPUT: Values: 4835.7 16:34:41 INFO - 2016-01-04 16:34:41,126 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:34:41 INFO - 2016-01-04 16:34:41,126 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-01-04 16:34:41,870 INFO : Browser exited with error code: 0 16:34:41 INFO - 2016-01-04 16:34:41,884 INFO : Completed test v8_7 (00:00:39) 16:34:42 INFO - 2016-01-04 16:34:42,199 INFO : Completed test suite (00:20:07) 16:34:42 INFO - 2016-01-04 16:34:42,199 DEBUG : Working with test: dromaeo_css 16:34:42 INFO - 2016-01-04 16:34:42,199 DEBUG : Generating results file: dromaeo_css 16:34:42 INFO - 2016-01-04 16:34:42,201 DEBUG : Working with test: kraken 16:34:42 INFO - 2016-01-04 16:34:42,201 DEBUG : Generating results file: kraken 16:34:42 INFO - 2016-01-04 16:34:42,202 INFO : javascript benchmark 16:34:42 INFO - 2016-01-04 16:34:42,202 DEBUG : Working with test: v8_7 16:34:42 INFO - 2016-01-04 16:34:42,202 DEBUG : Generating results file: v8_7 16:34:42 INFO - 2016-01-04 16:34:42,203 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:34:42 INFO - 2016-01-04 16:34:42,254 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 16:34:42 INFO - 2016-01-04 16:34:42,254 DEBUG : process_Request line: dromaeo_css 7167.40 graph.html#tests=[[72,53,35]] 16:34:42 INFO - 2016-01-04 16:34:42,255 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:34:42 INFO - 2016-01-04 16:34:42,284 DEBUG : process_Request line: kraken 1451.90 graph.html#tests=[[232,53,35]] 16:34:42 INFO - 2016-01-04 16:34:42,284 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:34:42 INFO - 2016-01-04 16:34:42,313 DEBUG : process_Request line: v8_7 23368.82 graph.html#tests=[[230,53,35]] 16:34:42 INFO - 2016-01-04 16:34:42,315 INFO : javascript benchmark 16:34:42 INFO - 2016-01-04 16:34:42,318 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3346.7155291800195, 3445.5348527948718, 3468.2023134679025, 3471.2883858808946, 3509.947350789738, 5660.264165658464, 5703.973284808738, 5711.800521890397, 5730.226419433386, 5749.166370876221, 5744.741486633101, 5767.336756273019, 5788.797392091277, 5799.971000144993, 5812.50593699535, 5094.133997220463, 5231.163013917774, 5262.842114736545, 5323.60072994525, 5359.624826262163, 5237.764300606482, 5257.342832145981, 5261.237120617528, 5261.500157485033, 5264.8157314494, 5064.822731204408, 5146.253793199984, 5200.92198617021, 5226.738663066851, 5237.397699264595, 5172.715500647463, 5280.2079688046715, 5288.524032837063, 5294.1529355303155, 5302.337207849019, 5239.0045891280915, 5251.159814429673, 5258.526732594085, 5282.996230716151, 5308.867278318035, 5368.543673787733, 5386.084365657847, 5462.754176062087, 5523.1163013917985, 5562.833115006556, 6187.195664563623, 6188.040853667689, 6193.87612247757, 6194.690265486753, 6202.13170156174, 6197.03945888388, 6201.255849298113, 6207.906881396782, 6209.5032397408095, 6210.254769427653, 5525.834224973217, 5645.717714114319, 5666.405027472098, 5690.231818704422, 5713.628614140109, 6281.371862813682, 6289.96855015722, 6294.653794041372, 6300.558960872787, 6324.051392291101, 5817.7963771267805, 6070.969645151834, 6112.908306375408, 6121.693915304217, 6122.908156377658, 6028.367021462771, 6101.816945491642, 6117.668168587243, 6138.202033735675, 6138.235132557318, 5978.3124940631515, 6144.881340737587, 6162.691865406712, 6172.135900973867, 6182.752689892354, 5835.912461313083, 5941.435563621449, 6140.6929653517145, 6178.62928224299, 6184.721687524072, 4578.427696537933, 4598.655100867449, 4599.310103484504, 4599.448066232074, 4620.792064357112, 4121.0, 4126.938095928564, 4130.194612050681, 4131.648809851136, 4160.313548264563, 5511.145772405283, 5576.247206627135, 5600.915986260209, 5699.515541178963, 5702.315722113373, 6095.207623008981, 6120.632762034292, 6140.754369825247, 6142.232220972379, 6150.969245153746, 5750.453706897838, 5761.596688231867, 5768.653880767168, 5770.5095066919785, 5779.682117483579, 5144.871378215575, 5210.97394513025, 5239.947600524022, 5283.656562323436, 5338.6529875558745, 6331.176946996861, 6388.7125079371535, 6398.328175541501, 6413.647249401234, 6414.518911081687, 6220.875582488416, 6233.283172435228, 6234.812955611339, 6237.937620623918, 6238.407351301619, 6050.485708714808, 6125.409382524018, 6138.2044803098925, 6259.96870015647, 6269.843253918506, 6045.667488288011, 6107.083937409424, 6108.847278818065, 6114.0828875669, 6117.204763380732, 6020.939790602038, 6192.783252586138, 6209.59637623553, 6209.689515524296, 6213.565050446607, 5951.732172052355, 6026.879462410815, 6097.054956481575, 6097.4817140543555, 6115.877682446415, 5997.640141591519, 6009.098635204754, 6009.2188015557695, 6009.729562169626, 6036.215292012011, 5777.277840269966, 5878.353381128158, 5891.882162356815, 5919.733611987384, 5920.585559010828, 5964.612300200473, 6071.563763871364, 6077.756889724362, 6101.725422355916, 6102.816915492543, 5887.0580707086665, 5890.410958904246, 5897.852553686193, 5922.200502932222, 5938.287405511366], "unit": "ms", "name": "dojo.html", "value": 5647.781765125308}, {"lowerIsBetter": false, "replicates": [563.8131732702661, 598.3298705449897, 600.4745847383542, 600.6966481926627, 609.1532769450463, 34618.30763384734, 43942.56057439424, 44891.55108448915, 44906.77546612271, 44994.875128121814, 46556.76721616387, 47226.527734722644, 47246.52753472464, 47277.29084063733, 47316.05367892649, 8134.715284965027, 8353.457025293337, 9247.075292470767, 9440.905590944105, 10705.732356691105, 10893.183011274146, 11028.393438360887, 11030.28303160296, 11039.944800275987, 11042.723931901686, 11020.944895275512, 11109.055730262937, 11117.332960022424, 11122.777544449105, 11128.944355278212, 10909.18181136414, 10993.780124397546, 10994.945025274863, 11016.449177541172, 11020.614278500254, 10830.241883068189, 10958.506867190996, 10970.506327215295, 10990.505427255792, 10992.890071099268, 43634.781826090984, 44213.0, 44293.55706442927, 44354.77822610899, 44356.112877742264, 3510.473428985647, 3946.6645335146545, 3978.622030907059, 4016.2369149861433, 4204.0, 33156.6684333156, 33684.652613895414, 33909.6609033909, 34448.82775586106, 34886.651133488594, 3222.3716375306826, 3252.894016034536, 3264.2981758921946, 3312.966870331314, 3363.8486268117995, 318503.0, 318663.0, 319253.0, 319798.0, 319969.0, 59147.40852591419, 60389.69805151034, 70307.2969270311, 75368.0, 77577.22422775812, 70521.2947870525, 70728.0, 70744.29255707377, 70835.64582177057, 70851.64574177096, 17946.282148713904, 18103.366382176562, 18104.456866293767, 18109.275628974956, 18111.0, 14400.207988560729, 14401.351939162763, 14414.0, 14510.637234069021, 14610.926945365205, 22252.332430027123, 22310.88844555767, 22378.55242895133, 22382.10471581151, 22563.0, 8347.791305217419, 8485.95757021211, 8514.106018868053, 8536.957315213383, 8662.354152710965, 1041.2815157541272, 1063.122923588043, 1087.0596933652491, 1123.5618108937522, 1139.720768411728, 30563.69436305653, 30927.0721878344, 31241.375172496424, 31270.687293127234, 31981.52027719586, 1071.0842229893485, 1091.7488977535052, 1115.130198445214, 1226.4297101847683, 1278.878506541877, 14118.0, 14938.178400188093, 15031.173285469404, 15552.455664051704, 15989.440369587008, 393532.0, 395635.0, 396127.0, 397390.0, 399122.0, 57184.71407642935, 57602.71198644064, 57624.71187644035, 57671.71164144152, 57690.711546442, 1372.7323171981368, 1399.979000314996, 1495.6933828565118, 1501.8648321651103, 1528.2435194578532, 29257.414851703274, 29272.56091158592, 29327.0, 31018.844905775328, 31564.526532102496, 49885.25172122494, 50212.24681629852, 50342.49657503378, 50404.0, 50454.49545504498, 248278.0, 255427.0, 256296.0, 256629.0, 257995.0, 34320.82839585786, 34784.65215347814, 35188.64811351854, 36118.63881361153, 36190.27619447544, 42860.35709464423, 43276.13447730965, 43618.0, 44157.33763993607, 44178.337324940796, 38368.80815595904, 39022.609773901895, 40052.1989560213, 40166.397504036875, 40620.79689601533, 39985.2002959945, 40486.392704108875, 40638.0, 40816.591834082465, 41132.383014254214, 39996.20007599774, 40294.39558406568, 40527.59472405238, 40597.0, 40719.59280407158, 33940.83029584836, 35216.6719821405, 35695.46456803202, 35819.28361432705, 35932.28135437329, 23842.88078559596, 26844.463110738066, 26990.595141073292, 27116.864415678585, 27137.59293610558], "unit": "ms", "name": "ext.html", "value": 20519.26741050761}, {"lowerIsBetter": false, "replicates": [365.2876890064374, 370.5812431951894, 380.4122630535822, 383.8809968909637, 389.7213492352967, 12701.745965080692, 15102.848971510282, 17756.467305980826, 19584.99999999998, 19608.70586941195, 17060.82939170608, 17193.226304816282, 17370.47888563342, 18495.537611559717, 18568.628627427443, 5858.1505681676235, 5998.610090344114, 6005.459508644221, 6005.459508644221, 6009.0, 4540.046590216042, 4662.067586482717, 5940.138679891413, 6514.41370276675, 6740.797776066702, 6162.815115546518, 6610.173728283965, 6634.767783127568, 6648.235452922903, 6666.533342666015, 6626.337366263359, 6649.567778094409, 6690.431313338372, 6701.664916754167, 6702.966485167592, 5858.47273745365, 5953.077273022688, 5953.821385358403, 5996.9205543002245, 6000.8499787505225, 14352.138871667734, 24431.511369772506, 24746.133885313928, 25017.49965000708, 25031.49937001268, 2137.722096127509, 2172.6197915364905, 2184.028107492167, 2191.6712493126, 2237.3288013595857, 10481.58073677052, 10561.947190264, 10567.577296908114, 10823.0, 10955.726106847313, 1820.9362672306406, 1852.6757817381904, 1855.1929910488846, 1867.3650958674116, 1895.0998275819013, 57409.0, 58111.0, 58238.4176158233, 65478.34521654818, 67207.66396168037, 7648.158702542716, 7819.765407037798, 7957.6021198939825, 7988.320992715568, 8012.919870801334, 9470.0, 9514.571844267024, 9522.95238523803, 9523.857142142873, 9529.0, 6454.32229615893, 6461.483081353481, 6479.481641468671, 6485.4487368573255, 6495.8700825983215, 6682.298358672367, 6715.764948226789, 6755.256921738604, 6815.829604259933, 6914.654267286615, 3492.8078955657174, 3676.28312479064, 3696.3716168251462, 3707.5921648618632, 3742.4947632069866, 1265.1397050006085, 1269.136986848933, 1274.254561081759, 1297.0531511996296, 1303.9478420863059, 503.65247978894445, 531.8351311093573, 532.1459058211592, 545.5826292885945, 553.3802141601352, 11303.434828258718, 12789.744205116032, 12983.221006739437, 12985.675358115934, 13151.21092734439, 532.9600279979018, 545.961782675209, 557.2004174010308, 558.8351436326293, 577.6851615869361, 6916.827079322957, 6998.125234345707, 7061.293870612898, 7071.929280707127, 7188.28117188277, 631659.0, 634093.0, 652279.0, 658222.0, 659196.0, 69875.65062174656, 74239.25760742431, 74377.25622743812, 76016.23983760092, 86158.0, 591.9319278282967, 603.7554790309931, 604.4710877981765, 606.0182504342905, 606.084811933974, 11887.702807429883, 12139.150259481752, 12393.380330983235, 12408.193467424588, 12409.62771116868, 37679.43480847844, 37766.62233377631, 37890.2421951565, 38657.2268554633, 39138.0, 49874.0, 50490.747546262035, 50560.494395055575, 51281.48718512767, 51402.0, 7506.662200201114, 7610.200928902607, 7622.961885190539, 7627.694892204249, 7634.50375725576, 10772.084372828398, 12525.93737031309, 13306.268155251553, 13454.663633409244, 13725.107867988549, 11626.360550169822, 11656.88343116558, 11855.762884742084, 11876.346800925687, 11901.642950711492, 11126.443677815914, 11237.876212378696, 11442.084633229155, 11528.884711152781, 11552.711182220512, 11094.500747466222, 11136.164787640797, 11313.094952403624, 11792.646220613395, 11845.289282643069, 7705.1909549496495, 7709.922900770921, 7806.531608103532, 7822.13956464777, 7830.33442157423, 12031.879681203425, 12065.215760975509, 12169.513219471122, 12354.938225308815, 12357.196782209128], "unit": "ms", "name": "jquery.html", "value": 7939.450883315944}, {"lowerIsBetter": false, "replicates": [1533.2103966457285, 1772.1847949943028, 1807.493901707522, 1847.8244566766161, 1870.7568016157898, 6449.645269510175, 6602.43879270262, 6613.404793568584, 6962.547434416772, 7120.608366539838, 7535.472516923817, 7678.270564296383, 7760.689572417096, 7764.495307804988, 7767.805804854882, 2383.2254517281867, 2424.078850036992, 2426.817988650849, 2427.0, 2427.174760581402, 2132.818710409609, 2178.7494438139656, 2339.1929784224417, 2385.9642105368434, 2418.830681852271, 2359.056377449017, 2369.5379401016808, 2369.7156341239074, 2370.7392186859433, 2382.988085059581, 2400.291913885408, 2419.830611857153, 2420.1529464687396, 2420.8668523231217, 2421.69728783902, 2347.835651504395, 2392.1627430399394, 2395.317334559642, 2396.892139853711, 2399.3881560202212, 7788.415868809862, 7855.371570274365, 7859.2533709298095, 7862.449628526006, 7867.881981770278, 4826.420829500447, 4903.681260718077, 4906.1414252505665, 4909.484504127051, 4911.214205727067, 3082.9845850770826, 3220.66183050781, 3297.8186199759, 3309.25541753106, 3324.9501257481156, 1086.940218287994, 1095.2333366643381, 1104.5195340027115, 1107.0811226681835, 1124.8087825069676, 10513.106385957202, 10525.736856578647, 10546.894531054668, 10564.577416903392, 10565.313254638424, 4512.548745125527, 4561.0, 4571.1086338164405, 4575.885602859955, 4581.221192397234, 8926.330525210635, 8958.596863141174, 8969.551522423853, 8982.775430614287, 9021.052789457144, 5009.398872135294, 5016.6237532184505, 5019.29729837823, 5031.371078615173, 5041.6722913010535, 4615.492295847454, 4621.168189725881, 4621.607163391082, 4636.281376386665, 4636.6058884995155, 3622.7645203062243, 3997.180577981565, 4049.1294371710223, 4060.2488539620263, 4095.262852686545, 1232.7842627540144, 1254.084518301645, 1323.305264736002, 1329.0563699773077, 1337.585348541955, 1346.9259190744604, 1478.6303424143964, 1484.9851501485064, 1488.3079368093888, 1509.6603264265452, 7666.616669166519, 7675.808104797313, 7791.883121753178, 7797.9220207798335, 7808.68765249395, 1206.390772659801, 1221.639616313172, 1222.2116734706242, 1237.5297386992886, 1238.7150955280335, 6034.969825150846, 6062.666553339609, 6132.202813634199, 6133.3866613338305, 6145.4161854623735, 9200.723978280663, 9509.096635819586, 9578.808423831624, 9585.185259252901, 9587.760305992406, 9375.203107735782, 9479.336446448682, 9479.33644644882, 9492.857607135898, 9515.096065873731, 6987.685554150075, 7147.106611673541, 7149.571025738473, 7198.172210195789, 7201.3518783309755, 7562.168161502121, 7606.543607383574, 7659.272369124924, 7674.155842857393, 7676.040494938133, 8520.914790852012, 8659.783505412415, 8675.34934879899, 8676.479411235347, 8677.26243269304, 8670.696525621574, 8856.202941735275, 8859.645614175619, 8860.512671803113, 9008.32437567172, 7213.1704853942465, 7286.526375785557, 7293.598852063187, 7301.634918254173, 7301.817454563466, 7674.347680447225, 7723.961380193063, 7744.419168562267, 7752.961235194013, 7786.0, 7056.1179852518435, 7121.537100088478, 7293.343599076109, 7353.8161545961775, 7404.740834070782, 6656.7337306509135, 7213.422926165999, 7289.234630363948, 7391.852162956819, 7392.4086073115095, 7292.0520332356455, 7326.9267307326245, 7332.0834895638045, 7332.486725929134, 7339.889901651372, 6657.800265992028, 6769.627670477974, 6776.085228494288, 6786.151731033621, 6789.796306110825, 6764.2221144569, 6795.082663840319, 6820.113385260083, 6824.692888820115, 6838.0], "unit": "ms", "name": "mootools.html", "value": 4698.186917925176}, {"lowerIsBetter": false, "replicates": [363.63454727998356, 365.46459436924897, 368.50068157646393, 370.5608853508591, 374.08722716571566, 5900.350961394243, 5991.281046274453, 6022.457978781908, 6045.667488288148, 6052.182955301038, 5837.7373018214175, 5936.851578710523, 5952.940470595293, 5955.702214889249, 5967.671778052206, 4700.0, 4700.059988002413, 4700.670953033289, 4700.976495117519, 4705.552972467619, 4699.553542413482, 4701.882452938669, 4710.199266124767, 4710.929336059962, 4715.269133284346, 4629.722216667011, 4660.766961651921, 4699.694519856215, 4716.599089077416, 4717.716936983775, 4684.250519916831, 4689.9765501172615, 4703.318018887259, 4706.647001474886, 4710.811567537295, 4022.6178513041214, 4077.368007958771, 4228.027553662644, 4230.8519201827785, 4234.386014027979, 5674.177244299594, 5718.942810571924, 5722.227499287585, 5751.741171647286, 5752.5110365618975, 5470.097433923398, 5472.50747432729, 5475.315585551806, 5475.561955043587, 5480.835574932758, 6265.328763102515, 6297.811065668037, 6299.68501574924, 6302.212223472066, 6331.366863313678, 4727.5272472752795, 4732.432108147044, 4738.1708201064675, 4738.573528382462, 4740.928886066712, 6566.474682025474, 6631.767888123893, 6631.801045968628, 6638.900416493757, 6644.169478815148, 4840.0, 4846.29728689338, 4849.878753031202, 4853.538913803183, 4855.830045948375, 4838.588719958772, 4921.704697718148, 4931.753412329369, 4931.950680493149, 4941.555260026615, 5551.72241387929, 5552.472515111058, 5553.666779993213, 5553.972230138905, 5557.888842223133, 6454.386833250834, 6458.838529036811, 6461.547691661539, 6461.870762584722, 6479.481641468671, 1767.734839774044, 1791.3730194432105, 1791.3819732192374, 1791.8566514678794, 1792.3905872003581, 1225.1179151011258, 1228.9815652765217, 1232.9013678905667, 1251.7871961766339, 1263.0, 6088.6042407243385, 6100.054491553817, 6100.572959892854, 6102.4202700744245, 6103.450689437972, 6638.236602790645, 6641.269460359356, 6650.567713098633, 6651.135352404156, 6653.234877989094, 5964.194833697482, 6086.147939288503, 6092.238470191226, 6092.939070609325, 6100.877982440326, 3660.9816950915074, 3679.742418030712, 3682.208325210093, 3683.5211422514903, 3687.1888184599347, 6617.636030018299, 6667.56660817055, 6713.362230588086, 6773.729050837911, 6788.185417749902, 6806.183258008971, 6838.076859623887, 6839.350261725128, 6841.17905851301, 6851.040854280404, 6751.189857217068, 6876.209235937831, 6876.484263680144, 6883.174019117738, 6884.0362349271145, 6704.094947182263, 6707.061011458497, 6712.194536655633, 6712.362325579063, 6718.630475323904, 6580.703868325841, 6594.505412094208, 6600.042993765973, 6616.867662646817, 6616.90074648871, 6710.698018589078, 6867.725290988305, 6882.242953275236, 6888.104546408935, 6899.551529150589, 4659.254519276899, 4690.601298889633, 4696.107739529479, 4698.412698412699, 4701.0362875609735, 6603.636799976048, 6610.933890661032, 6618.305077966744, 6621.1392518972225, 6628.5691430056895, 6349.9682501587195, 6405.583637063576, 6457.515686323451, 6469.223693156851, 6472.967635161794, 6434.935650643433, 6445.0332450132855, 6452.22573291208, 6523.934760652332, 6532.248791388861, 6415.615063096229, 6422.197225346832, 6426.357364263723, 6428.357164283534, 6429.839254018687, 6463.57986730861, 6513.772017979348, 6539.346065393423, 6564.048213009182, 6566.0, 6407.615543067431, 6408.327125651739, 6414.647194404352, 6424.871502570016, 6427.58220715652], "unit": "ms", "name": "prototype.html", "value": 4939.5526675846995}, {"lowerIsBetter": false, "replicates": [803.9236272554107, 808.3937047214589, 821.1665159862738, 857.425524898318, 865.5974971638187, 13545.0, 13692.383842727077, 13697.246651434167, 13780.104293220946, 13805.51680691176, 13057.216567005962, 13133.0, 13150.473981040746, 13158.144720593154, 13177.802332965013, 756.1417790807445, 763.3778470546487, 903.4398672822842, 903.530164314556, 913.0185051070093, 912.1699253679153, 914.9771255718593, 916.4043371808344, 919.9770005749842, 920.7974245665977, 784.90973538043, 939.9953000234989, 956.6556039825657, 958.5015791788265, 958.9232861371073, 782.22950393862, 935.223764275653, 945.6784693204344, 947.5593848860314, 948.340903072365, 708.0688894104236, 709.1419382547124, 709.1419382547124, 709.2871663977669, 709.6097146569418, 13310.667233319147, 13426.194428334331, 13459.932700336436, 13478.05653604248, 13487.66280842977, 11512.251703639236, 11766.647000589996, 11939.462724177432, 12094.395280236042, 12099.697507562294, 9904.158146557551, 10144.289899707024, 10168.593256269814, 10185.541650625666, 10247.743806404826, 3436.5876094868777, 3478.0783092480515, 3489.4068008438626, 3490.825458727079, 3492.633273506296, 46197.53802461966, 46681.29978050332, 46743.76628116905, 52326.7383663083, 52421.0, 6812.250652428229, 6816.250212476623, 6847.281035491302, 6967.325679528917, 7072.398846098139, 13115.344232788322, 13135.0, 13147.934260328637, 13152.736945261042, 13162.60512184636, 6885.277045910208, 6908.3091690830515, 6946.930530694628, 7020.227774944752, 7051.964740176266, 4285.871423857227, 4308.246056940023, 4336.4145840311785, 4338.783060846945, 4345.565443455693, 2970.0, 2989.0136255036223, 2994.2664047308112, 3008.157715839525, 3021.9093427197217, 436.36726746218113, 453.4037740371401, 460.617687319524, 463.5179413410016, 470.8752180672125, 639.4436839949274, 663.9435647969976, 665.2349797732646, 674.039493721445, 683.5351960666695, 7702.293811601878, 7933.920660793434, 8077.0, 8120.147384524658, 8279.417961875064, 513.922911563261, 576.6511260687307, 603.2158194347331, 635.6027482823235, 686.5777546808677, 4366.19225443294, 4399.670024748157, 4462.44219472566, 4495.303227999665, 4561.452625684873, 63312.36687633065, 63948.68025659842, 64314.035289470696, 64351.67824160943, 64391.356086439475, 54391.728041359536, 54940.17589736157, 55041.0, 55296.72351638216, 57152.71423642938, 50927.0, 51782.48217517776, 52222.47777522252, 52357.0, 53212.201816972774, 8694.260987815853, 8790.208881200468, 9071.138241867013, 9251.352405331561, 9319.394239374418, 38815.6118438812, 38991.2201755969, 39706.205875881744, 40421.797891010356, 40572.3914141294, 52241.477585225184, 53473.46526534685, 53623.46376536185, 53861.73069134629, 54168.72915635554, 8735.781605459915, 8867.246284065926, 8902.643894244417, 8939.418937769024, 9013.774655633662, 13021.609351719464, 13037.739245215233, 13130.934345328213, 13172.407241673956, 13289.20264784116, 12003.819942700691, 12090.758184836079, 12272.81590776157, 12390.81413778776, 12544.435500402686, 12131.514739410324, 12175.939120304341, 12180.451879665614, 12244.75510489803, 12801.679958000752, 10668.62659806903, 11077.612283570037, 11335.308921418437, 11825.113116516124, 12218.45016974256, 9697.563609637442, 9814.55834487464, 10042.297039207186, 10359.482025898524, 10605.89394106049, 10245.180385569285, 10664.573417063231, 10726.73183170427, 10732.356058636507, 10782.0], "unit": "ms", "name": "yui.html", "value": 6348.693239047593}], "name": "dromaeo_css", "value": 7167.401615679433}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 103.0, 110.0, 111.0, 109.0, 100.0, 109.0, 101.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.2}, {"lowerIsBetter": true, "replicates": [157.0, 119.0, 143.0, 161.0, 125.0, 148.0, 145.0, 122.0, 160.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.1}, {"lowerIsBetter": true, "replicates": [160.0, 168.0, 159.0, 158.0, 162.0, 160.0, 158.0, 160.0, 158.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 160.5}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 70.0, 145.0, 72.0, 71.0, 71.0, 71.0, 148.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 86.1}, {"lowerIsBetter": true, "replicates": [90.0, 89.0, 132.0, 89.0, 89.0, 89.0, 132.0, 89.0, 89.0, 139.0], "unit": "ms", "name": "audio-oscillator", "value": 102.7}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 144.0, 146.0, 147.0, 145.0, 146.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 97.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [53.0, 55.0, 54.0, 57.0, 55.0, 54.0, 54.0, 62.0, 52.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.0}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 63.0, 63.0, 63.0, 62.0, 62.0, 62.0, 64.0, 62.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 62.5}, {"lowerIsBetter": true, "replicates": [79.0, 76.0, 85.0, 78.0, 79.0, 78.0, 78.0, 79.0, 78.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.8}, {"lowerIsBetter": true, "replicates": [116.0, 111.0, 111.0, 105.0, 116.0, 116.0, 111.0, 117.0, 108.0, 116.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.7}, {"lowerIsBetter": true, "replicates": [134.0, 134.0, 134.0, 133.0, 138.0, 133.0, 134.0, 132.0, 132.0, 144.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 134.8}, {"lowerIsBetter": true, "replicates": [60.0, 62.0, 62.0, 61.0, 60.0, 62.0, 61.0, 60.0, 62.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.3}], "name": "kraken", "value": 1451.9}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [161.39444803098775], "unit": "score", "name": "Richards", "value": 218.731192}, {"lowerIsBetter": false, "replicates": [164.446637066272], "unit": "score", "name": "DeltaBlue", "value": 402.06355800000017}, {"lowerIsBetter": false, "replicates": [279.72027972027973], "unit": "score", "name": "Encrypt", "value": 951.5970750000004}, {"lowerIsBetter": false, "replicates": [4995.024875621891], "unit": "score", "name": "Decrypt", "value": 53.289224103585696}, {"lowerIsBetter": false, "replicates": [947.8672985781991], "unit": "score", "name": "RayTrace", "value": 780.6883950000001}, {"lowerIsBetter": false, "replicates": [547.645125958379], "unit": "score", "name": "Earley", "value": 1216.961438}, {"lowerIsBetter": false, "replicates": [10526.315789473685], "unit": "score", "name": "Boyer", "value": 63.313984999999995}, {"lowerIsBetter": false, "replicates": [24390.243902439026], "unit": "score", "name": "RegExp", "value": 37.350384999999974}, {"lowerIsBetter": false, "replicates": [535.8672376873661], "unit": "score", "name": "Splay", "value": 152.07311488511496}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 23368.81951780779}]} 16:34:42 INFO - RETURN: dromaeo_css: 7167.40 16:34:42 INFO - RETURN: kraken: 1451.90 16:34:42 INFO - RETURN: v8_7: 23368.82 16:34:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23368.82"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1451.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7167.40"}}} 16:34:42 INFO - Return code: 0 16:34:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:34:42 INFO - # TBPL SUCCESS # 16:34:42 INFO - Running post-action listener: _resource_record_post_action 16:34:42 INFO - Running post-run listener: _resource_record_post_run 16:34:43 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 360448; Write bytes: 275832832; Read time: 224; Write time: 249820 16:34:43 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 14127104; Read time: 0; Write time: 1592 16:34:43 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 360448; Write bytes: 261705728; Read time: 224; Write time: 248228 16:34:43 INFO - Running post-run listener: _upload_blobber_files 16:34:43 INFO - Blob upload gear active. 16:34:43 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:34:43 INFO - Copying logs to upload dir... 16:34:43 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1273.408206 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-01-04 16:34:43.540017) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-04 16:34:43.543785) ========= 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=1861a34ebfea852630dec091000001fa-1451952329.353571-624575631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009922 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451944472/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 2016-01-04 16:34:43.595445) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 16:34:43.595782) ========= 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=1861a34ebfea852630dec091000001fa-1451952329.353571-624575631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 16:34:43.647383) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 16:34:43.647706) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 16:34:43.648000) ========= ========= Total master_lag: 0.19 =========