builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1451317552.92 results: success (0) buildid: 20151226032348 builduid: 19e8e25020d64c83993df7ebeb9453e6 revision: 336a31419e00 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:52.917747) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:52.918232) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:52.918853) ========= 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=d333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006339 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:52.952171) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:52.952501) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:52.984028) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:52.984368) ========= 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=d333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019830 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.032384) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.032705) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.033466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.033753) ========= 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=d333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-28 07:45:53-- 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% 224M=0s 2015-12-28 07:45:53 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.170666 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.217418) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.217755) ========= 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=d333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.069134 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.298764) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.299126) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 336a31419e00 --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 336a31419e00 --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=d333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-28 07:45:53,891 Setting DEBUG logging. 2015-12-28 07:45:53,891 attempt 1/10 2015-12-28 07:45:53,891 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/336a31419e00?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-28 07:45:54,136 unpacking tar archive at: mozilla-beta-336a31419e00/testing/mozharness/ program finished with exit code 0 elapsedTime=0.547530 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.859595) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.859904) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.869628) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.869885) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-28 07:45:53.870265) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2015-12-28 07:45:53.870570) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:45:54 INFO - MultiFileLogger online at 20151228 07:45:54 in /builds/slave/test-pgo 07:45:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 07:45:54 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 07:45:54 INFO - {'append_to_log': False, 07:45:54 INFO - 'base_work_dir': '/builds/slave/test-pgo', 07:45:54 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:45:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 07:45:54 INFO - 'branch': 'Mozilla-Beta', 07:45:54 INFO - 'buildbot_json_path': 'buildprops.json', 07:45:54 INFO - 'config_files': ('talos/linux_config.py',), 07:45:54 INFO - 'default_actions': ('clobber', 07:45:54 INFO - 'read-buildbot-config', 07:45:54 INFO - 'download-and-extract', 07:45:54 INFO - 'populate-webroot', 07:45:54 INFO - 'create-virtualenv', 07:45:54 INFO - 'install', 07:45:54 INFO - 'run-tests'), 07:45:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:45:54 INFO - 'download_minidump_stackwalk': True, 07:45:54 INFO - 'download_symbols': 'ondemand', 07:45:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:45:54 INFO - 'tooltool.py': '/tools/tooltool.py', 07:45:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:45:54 INFO - '/tools/misc-python/virtualenv.py')}, 07:45:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:45:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:45:54 INFO - 'installer_path': 'installer.exe', 07:45:54 INFO - 'log_level': 'info', 07:45:54 INFO - 'log_name': 'talos', 07:45:54 INFO - 'log_to_console': True, 07:45:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:45:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:45:54 INFO - 'opt_config_files': (), 07:45:54 INFO - 'pip_index': False, 07:45:54 INFO - 'sps_profile': False, 07:45:54 INFO - 'sps_profile_interval': 0, 07:45:54 INFO - 'suite': 'dromaeojs', 07:45:54 INFO - 'system_bits': '64', 07:45:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:45:54 INFO - 'title': 'talos-linux64-ix-051', 07:45:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:45:54 INFO - 'use_talos_json': True, 07:45:54 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 07:45:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:45:54 INFO - 'work_dir': 'build'} 07:45:54 INFO - ##### 07:45:54 INFO - ##### Running clobber step. 07:45:54 INFO - ##### 07:45:54 INFO - Running pre-action listener: _resource_record_pre_action 07:45:54 INFO - Running main action method: clobber 07:45:54 INFO - rmtree: /builds/slave/test-pgo/build 07:45:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 07:45:55 INFO - Running post-action listener: _resource_record_post_action 07:45:55 INFO - ##### 07:45:55 INFO - ##### Running read-buildbot-config step. 07:45:55 INFO - ##### 07:45:55 INFO - Running pre-action listener: _resource_record_pre_action 07:45:55 INFO - Running main action method: read_buildbot_config 07:45:55 INFO - Using buildbot properties: 07:45:55 INFO - { 07:45:55 INFO - "properties": { 07:45:55 INFO - "buildnumber": 47, 07:45:55 INFO - "product": "firefox", 07:45:55 INFO - "script_repo_revision": "production", 07:45:55 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 07:45:55 INFO - "repository": "", 07:45:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 07:45:55 INFO - "buildid": "20151226032348", 07:45:55 INFO - "slavename": "talos-linux64-ix-051", 07:45:55 INFO - "pgo_build": "True", 07:45:55 INFO - "basedir": "/builds/slave/test-pgo", 07:45:55 INFO - "project": "", 07:45:55 INFO - "platform": "ubuntu64_hw", 07:45:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:45:55 INFO - "slavebuilddir": "test", 07:45:55 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 07:45:55 INFO - "branch": "mozilla-beta", 07:45:55 INFO - "repo_path": "releases/mozilla-beta", 07:45:55 INFO - "stage_platform": "linux64-pgo", 07:45:55 INFO - "builduid": "19e8e25020d64c83993df7ebeb9453e6", 07:45:55 INFO - "revision": "336a31419e00" 07:45:55 INFO - }, 07:45:55 INFO - "sourcestamp": { 07:45:55 INFO - "repository": "", 07:45:55 INFO - "hasPatch": false, 07:45:55 INFO - "project": "", 07:45:55 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 07:45:55 INFO - "changes": [ 07:45:55 INFO - { 07:45:55 INFO - "category": null, 07:45:55 INFO - "files": [ 07:45:55 INFO - { 07:45:55 INFO - "url": null, 07:45:55 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/firefox-44.0.en-US.linux-x86_64.tar.bz2" 07:45:55 INFO - } 07:45:55 INFO - ], 07:45:55 INFO - "repository": "", 07:45:55 INFO - "rev": "336a31419e00", 07:45:55 INFO - "who": "sendchange", 07:45:55 INFO - "when": 1451138676, 07:45:55 INFO - "number": 7395242, 07:45:55 INFO - "comments": "No bug, Automated blocklist update from host bld-linux64-spot-307 - a=blocklist-update", 07:45:55 INFO - "project": "", 07:45:55 INFO - "at": "Sat 26 Dec 2015 06:04:36", 07:45:55 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 07:45:55 INFO - "revlink": "", 07:45:55 INFO - "properties": [ 07:45:55 INFO - [ 07:45:55 INFO - "buildid", 07:45:55 INFO - "20151226032348", 07:45:55 INFO - "Change" 07:45:55 INFO - ], 07:45:55 INFO - [ 07:45:55 INFO - "builduid", 07:45:55 INFO - "19e8e25020d64c83993df7ebeb9453e6", 07:45:55 INFO - "Change" 07:45:55 INFO - ], 07:45:55 INFO - [ 07:45:55 INFO - "pgo_build", 07:45:55 INFO - "True", 07:45:55 INFO - "Change" 07:45:55 INFO - ] 07:45:55 INFO - ], 07:45:55 INFO - "revision": "336a31419e00" 07:45:55 INFO - } 07:45:55 INFO - ], 07:45:55 INFO - "revision": "336a31419e00" 07:45:55 INFO - } 07:45:55 INFO - } 07:45:55 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/firefox-44.0.en-US.linux-x86_64.tar.bz2. 07:45:55 INFO - Running post-action listener: _resource_record_post_action 07:45:55 INFO - ##### 07:45:55 INFO - ##### Running download-and-extract step. 07:45:55 INFO - ##### 07:45:55 INFO - Running pre-action listener: _resource_record_pre_action 07:45:55 INFO - Running main action method: download_and_extract 07:45:55 INFO - mkdir: /builds/slave/test-pgo/build/tests 07:45: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')]} 07:45:55 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/test_packages.json 07:45:55 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 07:45:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 07:45:55 INFO - Downloaded 1270 bytes. 07:45:55 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 07:45:55 INFO - Using the following test package requirements: 07:45:55 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 07:45:55 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 07:45:55 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 07:45:55 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 07:45:55 INFO - u'jsshell-linux-x86_64.zip'], 07:45:55 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 07:45:55 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 07:45:55 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 07:45:55 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 07:45:55 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 07:45:55 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 07:45:55 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 07:45:55 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 07:45:55 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 07:45:55 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 07:45:55 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 07:45:55 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 07:45:55 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:45: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')]} 07:45:55 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/firefox-44.0.en-US.linux-x86_64.common.tests.zip 07:45:55 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/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 07:45:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/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 07:45:56 INFO - Downloaded 22571517 bytes. 07:45:56 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 07:45:56 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 07:45:56 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 07:45:57 INFO - Return code: 0 07:45:57 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 07:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:45:57 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/firefox-44.0.en-US.linux-x86_64.common.tests.zip 07:45:57 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/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 07:45:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/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 07:45:58 INFO - Downloaded 22571517 bytes. 07:45:58 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 07:45:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 07:45:58 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 07:46:00 INFO - Return code: 0 07:46: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')]} 07:46:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 07:46:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/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 07:46:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/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 07:46:00 INFO - Downloaded 11131970 bytes. 07:46:00 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 07:46:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 07:46:00 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 07:46:00 INFO - Return code: 0 07:46: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')]} 07:46:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/firefox-44.0.en-US.linux-x86_64.tar.bz2 07:46:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/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 07:46:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/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 07:46:01 INFO - Downloaded 56617008 bytes. 07:46:01 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/firefox-44.0.en-US.linux-x86_64.tar.bz2 07:46:01 INFO - mkdir: /builds/slave/test-pgo/properties 07:46:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 07:46:01 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 07:46:01 INFO - Contents: 07:46:01 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/firefox-44.0.en-US.linux-x86_64.tar.bz2 07:46:01 INFO - Running post-action listener: _resource_record_post_action 07:46:01 INFO - Running post-action listener: set_extra_try_arguments 07:46:01 INFO - ##### 07:46:01 INFO - ##### Running populate-webroot step. 07:46:01 INFO - ##### 07:46:01 INFO - Running pre-action listener: _resource_record_pre_action 07:46:01 INFO - Running main action method: populate_webroot 07:46:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:46:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:46:01 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 07:46:01 INFO - '../fennec_ids.txt'], 07:46:01 INFO - 'tests': ['tcheck2']}, 07:46:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:46:01 INFO - '--tppagecycles', 07:46:01 INFO - '7'], 07:46:01 INFO - 'tests': ['tsvgm']}}, 07:46:01 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:46:01 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:46:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:46:01 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:46:01 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:46:01 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:46:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:46:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:01 INFO - 'talos_options': ['--e10s'], 07:46:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:46:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:01 INFO - 'tests': ['damp', 'tps']}, 07:46:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:01 INFO - 'talos_options': ['--e10s'], 07:46:01 INFO - 'tests': ['damp', 'tps']}, 07:46:01 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:46:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:46:01 INFO - 'other': {'tests': ['a11yr', 07:46:01 INFO - 'ts_paint', 07:46:01 INFO - 'tpaint', 07:46:01 INFO - 'sessionrestore', 07:46:01 INFO - 'sessionrestore_no_auto_restore']}, 07:46:01 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:46:01 INFO - 'tests': ['a11yr', 07:46:01 INFO - 'ts_paint', 07:46:01 INFO - 'tpaint', 07:46:01 INFO - 'sessionrestore', 07:46:01 INFO - 'sessionrestore_no_auto_restore']}, 07:46:01 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:46:01 INFO - 'tests': ['a11yr', 07:46:01 INFO - 'ts_paint', 07:46:01 INFO - 'tpaint', 07:46:01 INFO - 'sessionrestore', 07:46:01 INFO - 'sessionrestore_no_auto_restore']}, 07:46:01 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:46:01 INFO - 'tests': ['a11yr', 07:46:01 INFO - 'ts_paint', 07:46:01 INFO - 'tpaint', 07:46:01 INFO - 'sessionrestore', 07:46:01 INFO - 'sessionrestore_no_auto_restore']}, 07:46:01 INFO - 'other_l64': {'tests': ['a11yr', 07:46:01 INFO - 'ts_paint', 07:46:01 INFO - 'tpaint', 07:46:01 INFO - 'sessionrestore', 07:46:01 INFO - 'sessionrestore_no_auto_restore']}, 07:46:01 INFO - 'other_nol64': {'tests': ['a11yr', 07:46:01 INFO - 'ts_paint', 07:46:01 INFO - 'tpaint', 07:46:01 INFO - 'sessionrestore', 07:46:01 INFO - 'sessionrestore_no_auto_restore']}, 07:46:01 INFO - 'svgr': {'tests': ['tsvgx', 07:46:01 INFO - 'tsvgr_opacity', 07:46:01 INFO - 'tart', 07:46:01 INFO - 'tscrollx', 07:46:01 INFO - 'cart']}, 07:46:01 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:46:01 INFO - 'tests': ['tsvgx', 07:46:01 INFO - 'tsvgr_opacity', 07:46:01 INFO - 'tart', 07:46:01 INFO - 'tscrollx', 07:46:01 INFO - 'cart']}, 07:46:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:01 INFO - 'tests': ['tp5o']}, 07:46:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:01 INFO - 'talos_options': ['--e10s'], 07:46:01 INFO - 'tests': ['tp5o']}, 07:46:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:01 INFO - 'talos_options': ['--xperf_path', 07:46:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:46:01 INFO - 'tests': ['tp5n']}, 07:46:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:01 INFO - 'talos_options': ['--e10s', 07:46:01 INFO - '--xperf_path', 07:46:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:46:01 INFO - 'tests': ['tp5n']}}, 07:46:01 INFO - 'talos.zip': {'path': '', 07:46:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:46:01 INFO - Running post-action listener: _resource_record_post_action 07:46:01 INFO - ##### 07:46:01 INFO - ##### Running create-virtualenv step. 07:46:01 INFO - ##### 07:46:01 INFO - Running pre-action listener: _resource_record_pre_action 07:46:01 INFO - Running main action method: create_virtualenv 07:46:01 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 07:46:01 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 07:46:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 07:46:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:46:01 INFO - Using real prefix '/usr' 07:46:01 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 07:46:01 INFO - Installing distribute.............................................................................................................................................................................................done. 07:46:03 INFO - Installing pip.................done. 07:46:03 INFO - Return code: 0 07:46:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 07:46: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')]} 07:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46: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')]} 07:46:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:03 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 0x7f9894b0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d9350>, '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 0x12da250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12da6e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', '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 07:46:03 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 07:46:03 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 07:46:03 INFO - Using env: {'DISPLAY': ':0', 07:46:03 INFO - 'HOME': '/home/cltbld', 07:46:03 INFO - 'LANG': 'en_US.UTF-8', 07:46:03 INFO - 'LANGUAGE': 'en_US:en', 07:46:03 INFO - 'LOGNAME': 'cltbld', 07:46:03 INFO - 'MAIL': '/var/mail/cltbld', 07:46:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:03 INFO - 'MOZ_NO_REMOTE': '1', 07:46:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:03 INFO - 'NO_EM_RESTART': '1', 07:46:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:03 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:03 INFO - 'SHELL': '/bin/bash', 07:46:03 INFO - 'SHLVL': '1', 07:46:03 INFO - 'TERM': 'linux', 07:46:03 INFO - 'TMOUT': '86400', 07:46:03 INFO - 'USER': 'cltbld', 07:46:03 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', 07:46:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:03 INFO - '_': '/tools/buildbot/bin/python'} 07:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:46:04 INFO - Cleaning up... 07:46:04 INFO - Return code: 0 07:46:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 07:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:04 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 0x7f9894b0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d9350>, '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 0x12da250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12da6e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', '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 07:46:04 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 07:46:04 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 07:46:04 INFO - Using env: {'DISPLAY': ':0', 07:46:04 INFO - 'HOME': '/home/cltbld', 07:46:04 INFO - 'LANG': 'en_US.UTF-8', 07:46:04 INFO - 'LANGUAGE': 'en_US:en', 07:46:04 INFO - 'LOGNAME': 'cltbld', 07:46:04 INFO - 'MAIL': '/var/mail/cltbld', 07:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:04 INFO - 'MOZ_NO_REMOTE': '1', 07:46:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:04 INFO - 'NO_EM_RESTART': '1', 07:46:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:04 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:04 INFO - 'SHELL': '/bin/bash', 07:46:04 INFO - 'SHLVL': '1', 07:46:04 INFO - 'TERM': 'linux', 07:46:04 INFO - 'TMOUT': '86400', 07:46:04 INFO - 'USER': 'cltbld', 07:46:04 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', 07:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:04 INFO - '_': '/tools/buildbot/bin/python'} 07:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:04 INFO - Downloading/unpacking psutil>=0.7.1 07:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:06 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 07:46:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 07:46:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 07:46:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:46:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:46:06 INFO - Installing collected packages: psutil 07:46:06 INFO - Running setup.py install for psutil 07:46:06 INFO - building 'psutil._psutil_linux' extension 07:46:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:46:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:46:06 INFO - building 'psutil._psutil_posix' extension 07:46:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:46:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:46:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:46:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:46:06 INFO - Successfully installed psutil 07:46:06 INFO - Cleaning up... 07:46:06 INFO - Return code: 0 07:46:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 07:46:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:06 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 0x7f9894b0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d9350>, '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 0x12da250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12da6e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', '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 07:46:06 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 07:46:06 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 07:46:06 INFO - Using env: {'DISPLAY': ':0', 07:46:06 INFO - 'HOME': '/home/cltbld', 07:46:06 INFO - 'LANG': 'en_US.UTF-8', 07:46:06 INFO - 'LANGUAGE': 'en_US:en', 07:46:06 INFO - 'LOGNAME': 'cltbld', 07:46:06 INFO - 'MAIL': '/var/mail/cltbld', 07:46:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:06 INFO - 'MOZ_NO_REMOTE': '1', 07:46:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:06 INFO - 'NO_EM_RESTART': '1', 07:46:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:06 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:06 INFO - 'SHELL': '/bin/bash', 07:46:06 INFO - 'SHLVL': '1', 07:46:06 INFO - 'TERM': 'linux', 07:46:06 INFO - 'TMOUT': '86400', 07:46:06 INFO - 'USER': 'cltbld', 07:46:06 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', 07:46:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:06 INFO - '_': '/tools/buildbot/bin/python'} 07:46:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:46:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 07:46:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:46:08 INFO - Installing collected packages: mozsystemmonitor 07:46:08 INFO - Running setup.py install for mozsystemmonitor 07:46:08 INFO - Successfully installed mozsystemmonitor 07:46:08 INFO - Cleaning up... 07:46:08 INFO - Return code: 0 07:46:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 07:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:08 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 0x7f9894b0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d9350>, '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 0x12da250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12da6e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', '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 07:46:08 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 07:46:08 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 07:46:08 INFO - Using env: {'DISPLAY': ':0', 07:46:08 INFO - 'HOME': '/home/cltbld', 07:46:08 INFO - 'LANG': 'en_US.UTF-8', 07:46:08 INFO - 'LANGUAGE': 'en_US:en', 07:46:08 INFO - 'LOGNAME': 'cltbld', 07:46:08 INFO - 'MAIL': '/var/mail/cltbld', 07:46:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:08 INFO - 'MOZ_NO_REMOTE': '1', 07:46:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:08 INFO - 'NO_EM_RESTART': '1', 07:46:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:08 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:08 INFO - 'SHELL': '/bin/bash', 07:46:08 INFO - 'SHLVL': '1', 07:46:08 INFO - 'TERM': 'linux', 07:46:08 INFO - 'TMOUT': '86400', 07:46:08 INFO - 'USER': 'cltbld', 07:46:08 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', 07:46:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:08 INFO - '_': '/tools/buildbot/bin/python'} 07:46:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:08 INFO - Downloading/unpacking blobuploader==1.2.4 07:46: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 07:46: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 07:46: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 07:46: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 07:46: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 07:46: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 07:46:10 INFO - Downloading blobuploader-1.2.4.tar.gz 07:46:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 07:46:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:46:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 07:46:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 07:46:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:46: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 07:46: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 07:46: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 07:46: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 07:46: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 07:46: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 07:46:11 INFO - Downloading docopt-0.6.1.tar.gz 07:46:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 07:46:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 07:46:11 INFO - Installing collected packages: blobuploader, requests, docopt 07:46:11 INFO - Running setup.py install for blobuploader 07:46:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:46:11 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 07:46:11 INFO - Running setup.py install for requests 07:46:11 INFO - Running setup.py install for docopt 07:46:11 INFO - Successfully installed blobuploader requests docopt 07:46:11 INFO - Cleaning up... 07:46:11 INFO - Return code: 0 07:46:11 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:11 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 0x7f9894b0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d9350>, '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 0x12da250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12da6e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', '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 07:46:11 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 07:46:11 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 07:46:11 INFO - Using env: {'DISPLAY': ':0', 07:46:11 INFO - 'HOME': '/home/cltbld', 07:46:11 INFO - 'LANG': 'en_US.UTF-8', 07:46:11 INFO - 'LANGUAGE': 'en_US:en', 07:46:11 INFO - 'LOGNAME': 'cltbld', 07:46:11 INFO - 'MAIL': '/var/mail/cltbld', 07:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:11 INFO - 'MOZ_NO_REMOTE': '1', 07:46:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:11 INFO - 'NO_EM_RESTART': '1', 07:46:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:11 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:11 INFO - 'SHELL': '/bin/bash', 07:46:11 INFO - 'SHLVL': '1', 07:46:11 INFO - 'TERM': 'linux', 07:46:11 INFO - 'TMOUT': '86400', 07:46:11 INFO - 'USER': 'cltbld', 07:46:11 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', 07:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:11 INFO - '_': '/tools/buildbot/bin/python'} 07:46:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:46:12 INFO - Running setup.py (path:/tmp/pip-SrYq8_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:46:12 INFO - Running setup.py (path:/tmp/pip-nxKnja-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:46:12 INFO - Running setup.py (path:/tmp/pip-hSASjL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:46:12 INFO - Running setup.py (path:/tmp/pip-a9hZXz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:46:12 INFO - Running setup.py (path:/tmp/pip-2CwN8S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:46:12 INFO - Running setup.py (path:/tmp/pip-Zu4xv5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:46:12 INFO - Running setup.py (path:/tmp/pip-coZ_FW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:46:12 INFO - Running setup.py (path:/tmp/pip-aUgeQx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:46:12 INFO - Running setup.py (path:/tmp/pip-tgC7Kd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:46:12 INFO - Running setup.py (path:/tmp/pip-I07fPR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:46:12 INFO - Running setup.py (path:/tmp/pip-0K4wno-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:46:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:46:12 INFO - Running setup.py (path:/tmp/pip-9_rDnL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:46:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:46:13 INFO - Running setup.py (path:/tmp/pip-n3s7kj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:46:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:46:13 INFO - Running setup.py (path:/tmp/pip-Xued69-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:46:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:46:13 INFO - Running setup.py (path:/tmp/pip-txKIbz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:46:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:46:13 INFO - Running setup.py (path:/tmp/pip-Pkzq4M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:46:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:46:13 INFO - Running setup.py (path:/tmp/pip-CG2fp7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:46:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:46:13 INFO - Running setup.py install for manifestparser 07:46:13 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 07:46:13 INFO - Running setup.py install for mozcrash 07:46:13 INFO - Running setup.py install for mozdebug 07:46:13 INFO - Running setup.py install for mozdevice 07:46:13 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 07:46:13 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 07:46:13 INFO - Running setup.py install for mozfile 07:46:13 INFO - Running setup.py install for mozhttpd 07:46:13 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Running setup.py install for mozinfo 07:46:14 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Running setup.py install for mozInstall 07:46:14 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Running setup.py install for mozleak 07:46:14 INFO - Running setup.py install for mozlog 07:46:14 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Running setup.py install for moznetwork 07:46:14 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Running setup.py install for mozprocess 07:46:14 INFO - Running setup.py install for mozprofile 07:46:14 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Running setup.py install for mozrunner 07:46:14 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 07:46:14 INFO - Running setup.py install for mozscreenshot 07:46:14 INFO - Running setup.py install for moztest 07:46:15 INFO - Running setup.py install for mozversion 07:46:15 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 07:46:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:46:15 INFO - Cleaning up... 07:46:15 INFO - Return code: 0 07:46:15 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:15 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 0x7f9894b0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d9350>, '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 0x12da250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12da6e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', '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 07:46:15 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 07:46:15 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 07:46:15 INFO - Using env: {'DISPLAY': ':0', 07:46:15 INFO - 'HOME': '/home/cltbld', 07:46:15 INFO - 'LANG': 'en_US.UTF-8', 07:46:15 INFO - 'LANGUAGE': 'en_US:en', 07:46:15 INFO - 'LOGNAME': 'cltbld', 07:46:15 INFO - 'MAIL': '/var/mail/cltbld', 07:46:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:15 INFO - 'MOZ_NO_REMOTE': '1', 07:46:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:15 INFO - 'NO_EM_RESTART': '1', 07:46:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:15 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:15 INFO - 'SHELL': '/bin/bash', 07:46:15 INFO - 'SHLVL': '1', 07:46:15 INFO - 'TERM': 'linux', 07:46:15 INFO - 'TMOUT': '86400', 07:46:15 INFO - 'USER': 'cltbld', 07:46:15 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', 07:46:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:15 INFO - '_': '/tools/buildbot/bin/python'} 07:46:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:46:15 INFO - Running setup.py (path:/tmp/pip-Rw1mXS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:46:15 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)) 07:46:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:46:15 INFO - Running setup.py (path:/tmp/pip-0J7ccv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:46:15 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)) 07:46:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:46:15 INFO - Running setup.py (path:/tmp/pip-ArCIZh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:46:15 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)) 07:46:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:46:15 INFO - Running setup.py (path:/tmp/pip-7BsRgx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:46:15 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)) 07:46:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:46:15 INFO - Running setup.py (path:/tmp/pip-O0agHJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:46:15 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)) 07:46:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:46:15 INFO - Running setup.py (path:/tmp/pip-SsX3mc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:46:15 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)) 07:46:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:46:15 INFO - Running setup.py (path:/tmp/pip-ZRSHyN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:46:15 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)) 07:46:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:46:15 INFO - Running setup.py (path:/tmp/pip-DfAofT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:46:15 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)) 07:46:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:46:15 INFO - Running setup.py (path:/tmp/pip-FIJXlt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:46:16 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)) 07:46:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:46:16 INFO - Running setup.py (path:/tmp/pip-q4KSer-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:46:16 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)) 07:46:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:46:16 INFO - Running setup.py (path:/tmp/pip-qVtWuu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:46:16 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)) 07:46:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:46:16 INFO - Running setup.py (path:/tmp/pip-3pjcCR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:46:16 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)) 07:46:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:46:16 INFO - Running setup.py (path:/tmp/pip-uC45mX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:46:16 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)) 07:46:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:46:16 INFO - Running setup.py (path:/tmp/pip-micr72-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:46:16 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)) 07:46:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:46:16 INFO - Running setup.py (path:/tmp/pip-dBEVWo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:46:16 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)) 07:46:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:46:16 INFO - Running setup.py (path:/tmp/pip-WM7KsT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:46:16 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)) 07:46:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:46:16 INFO - Running setup.py (path:/tmp/pip-pXwrnj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:46:16 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)) 07:46:16 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)) 07:46:16 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)) 07:46:16 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)) 07:46:16 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)) 07:46:16 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)) 07:46:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 07:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:18 INFO - Downloading blessings-1.5.1.tar.gz 07:46:18 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 07:46:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 07:46:18 INFO - Installing collected packages: blessings 07:46:18 INFO - Running setup.py install for blessings 07:46:18 INFO - Successfully installed blessings 07:46:18 INFO - Cleaning up... 07:46:18 INFO - Return code: 0 07:46:18 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 07:46:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:46:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:46:18 INFO - Reading from file tmpfile_stdout 07:46:18 INFO - Current package versions: 07:46:18 INFO - argparse == 1.2.1 07:46:18 INFO - blessings == 1.5.1 07:46:18 INFO - blobuploader == 1.2.4 07:46:18 INFO - docopt == 0.6.1 07:46:18 INFO - manifestparser == 1.1 07:46:18 INFO - mozInstall == 1.12 07:46:18 INFO - mozcrash == 0.16 07:46:18 INFO - mozdebug == 0.1 07:46:18 INFO - mozdevice == 0.46 07:46:18 INFO - mozfile == 1.2 07:46:18 INFO - mozhttpd == 0.7 07:46:18 INFO - mozinfo == 0.8 07:46:18 INFO - mozleak == 0.1 07:46:18 INFO - mozlog == 3.0 07:46:18 INFO - moznetwork == 0.27 07:46:18 INFO - mozprocess == 0.22 07:46:18 INFO - mozprofile == 0.27 07:46:18 INFO - mozrunner == 6.11 07:46:18 INFO - mozscreenshot == 0.1 07:46:18 INFO - mozsystemmonitor == 0.0 07:46:18 INFO - moztest == 0.7 07:46:18 INFO - mozversion == 1.4 07:46:18 INFO - psutil == 3.1.1 07:46:18 INFO - requests == 1.2.3 07:46:18 INFO - wsgiref == 0.1.2 07:46:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:18 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 0x7f9894b0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d9350>, '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 0x12da250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12da6e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', '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 07:46:18 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 07:46:18 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 07:46:18 INFO - Using env: {'DISPLAY': ':0', 07:46:18 INFO - 'HOME': '/home/cltbld', 07:46:18 INFO - 'LANG': 'en_US.UTF-8', 07:46:18 INFO - 'LANGUAGE': 'en_US:en', 07:46:18 INFO - 'LOGNAME': 'cltbld', 07:46:18 INFO - 'MAIL': '/var/mail/cltbld', 07:46:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:18 INFO - 'MOZ_NO_REMOTE': '1', 07:46:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:18 INFO - 'NO_EM_RESTART': '1', 07:46:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:18 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:18 INFO - 'SHELL': '/bin/bash', 07:46:18 INFO - 'SHLVL': '1', 07:46:18 INFO - 'TERM': 'linux', 07:46:18 INFO - 'TMOUT': '86400', 07:46:18 INFO - 'USER': 'cltbld', 07:46:18 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', 07:46:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:18 INFO - '_': '/tools/buildbot/bin/python'} 07:46:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:18 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)) 07:46:18 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)) 07:46:18 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)) 07:46:18 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)) 07:46:18 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)) 07:46:18 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)) 07:46:18 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)) 07:46:18 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)) 07:46:18 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)) 07:46:18 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)) 07:46:18 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)) 07:46:18 INFO - Cleaning up... 07:46:18 INFO - Return code: 0 07:46:18 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 07:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:18 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 0x7f9894b0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d9350>, '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 0x12da250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12da6e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', '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 07:46:18 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 07:46:18 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 07:46:18 INFO - Using env: {'DISPLAY': ':0', 07:46:18 INFO - 'HOME': '/home/cltbld', 07:46:18 INFO - 'LANG': 'en_US.UTF-8', 07:46:18 INFO - 'LANGUAGE': 'en_US:en', 07:46:18 INFO - 'LOGNAME': 'cltbld', 07:46:18 INFO - 'MAIL': '/var/mail/cltbld', 07:46:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:18 INFO - 'MOZ_NO_REMOTE': '1', 07:46:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:18 INFO - 'NO_EM_RESTART': '1', 07:46:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:18 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:18 INFO - 'SHELL': '/bin/bash', 07:46:18 INFO - 'SHLVL': '1', 07:46:18 INFO - 'TERM': 'linux', 07:46:18 INFO - 'TMOUT': '86400', 07:46:18 INFO - 'USER': 'cltbld', 07:46:18 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', 07:46:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:18 INFO - '_': '/tools/buildbot/bin/python'} 07:46:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:19 INFO - Downloading/unpacking jsonschema 07:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:46:20 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 07:46:20 INFO - Downloading/unpacking functools32 (from jsonschema) 07:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:21 INFO - Downloading functools32-3.2.3-2.tar.gz 07:46:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 07:46:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 07:46:21 INFO - warning: no files found matching '*.txt' 07:46:21 INFO - no previously-included directories found matching 'build' 07:46:21 INFO - no previously-included directories found matching 'dist' 07:46:21 INFO - no previously-included directories found matching '.git*' 07:46:21 INFO - Installing collected packages: jsonschema, functools32 07:46:21 INFO - Running setup.py install for functools32 07:46:21 INFO - warning: no files found matching '*.txt' 07:46:21 INFO - no previously-included directories found matching 'build' 07:46:21 INFO - no previously-included directories found matching 'dist' 07:46:21 INFO - no previously-included directories found matching '.git*' 07:46:21 INFO - Successfully installed jsonschema functools32 07:46:21 INFO - Cleaning up... 07:46:21 INFO - Return code: 0 07:46:21 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 07:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:21 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 0x7f9894b0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d9350>, '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 0x12da250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12da6e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', '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 07:46:21 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 07:46:21 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 07:46:21 INFO - Using env: {'DISPLAY': ':0', 07:46:21 INFO - 'HOME': '/home/cltbld', 07:46:21 INFO - 'LANG': 'en_US.UTF-8', 07:46:21 INFO - 'LANGUAGE': 'en_US:en', 07:46:21 INFO - 'LOGNAME': 'cltbld', 07:46:21 INFO - 'MAIL': '/var/mail/cltbld', 07:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:21 INFO - 'MOZ_NO_REMOTE': '1', 07:46:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:21 INFO - 'NO_EM_RESTART': '1', 07:46:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:21 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:21 INFO - 'SHELL': '/bin/bash', 07:46:21 INFO - 'SHLVL': '1', 07:46:21 INFO - 'TERM': 'linux', 07:46:21 INFO - 'TMOUT': '86400', 07:46:21 INFO - 'USER': 'cltbld', 07:46:21 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', 07:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:21 INFO - '_': '/tools/buildbot/bin/python'} 07:46:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:21 INFO - Downloading/unpacking flake8 07:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:46:23 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 07:46:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:46:23 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 07:46:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 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 07:46:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 07:46:23 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 07:46:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:46:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:46:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:46:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:46:23 INFO - no previously-included directories found matching 'docs/_build' 07:46:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:46:23 INFO - Running setup.py install for pep8 07:46:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:46:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:46:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:46:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:46:24 INFO - no previously-included directories found matching 'docs/_build' 07:46:24 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 07:46:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:46:24 INFO - Cleaning up... 07:46:24 INFO - Return code: 0 07:46:24 INFO - Running post-action listener: _resource_record_post_action 07:46:24 INFO - Running post-action listener: _start_resource_monitoring 07:46:24 INFO - Starting resource monitoring. 07:46:24 INFO - ##### 07:46:24 INFO - ##### Running install step. 07:46:24 INFO - ##### 07:46:24 INFO - Running pre-action listener: _resource_record_pre_action 07:46:24 INFO - Running main action method: install 07:46:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:46:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:46:24 INFO - Reading from file tmpfile_stdout 07:46:24 INFO - Detecting whether we're running mozinstall >=1.0... 07:46:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 07:46:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 07:46:24 INFO - Reading from file tmpfile_stdout 07:46:24 INFO - Output received: 07:46:24 INFO - Usage: mozinstall [options] installer 07:46:24 INFO - Options: 07:46:24 INFO - -h, --help show this help message and exit 07:46:24 INFO - -d DEST, --destination=DEST 07:46:24 INFO - Directory to install application into. [default: 07:46:24 INFO - "/builds/slave/test-pgo"] 07:46:24 INFO - --app=APP Application being installed. [default: firefox] 07:46:24 INFO - mkdir: /builds/slave/test-pgo/build/application 07:46:24 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'] 07:46:24 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 07:46:38 INFO - Reading from file tmpfile_stdout 07:46:38 INFO - Output received: 07:46:38 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 07:46:38 INFO - Running post-action listener: _resource_record_post_action 07:46:38 INFO - ##### 07:46:38 INFO - ##### Running run-tests step. 07:46:38 INFO - ##### 07:46:38 INFO - Running pre-action listener: _resource_record_pre_action 07:46:38 INFO - Running main action method: run_tests 07:46:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 07:46:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 07:46:38 INFO - Python 2.7.3 07:46:38 INFO - Return code: 0 07:46:38 INFO - grabbing minidump binary from tooltool 07:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:38 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 0x12d9350>, '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 0x12da250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12da6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 07:46:38 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 07:46:38 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 07:46:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:46:38 INFO - Return code: 0 07:46:38 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 07:46:38 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 07:46:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 07:46:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 07:46:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 07:46:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 07:46:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 07:46:39 INFO - Return code: 0 07:46:39 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/1451129028/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 07:46:39 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/1451129028/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --webServer localhost 07:46:39 INFO - Using env: {'DISPLAY': ':0', 07:46:39 INFO - 'HOME': '/home/cltbld', 07:46:39 INFO - 'LANG': 'en_US.UTF-8', 07:46:39 INFO - 'LANGUAGE': 'en_US:en', 07:46:39 INFO - 'LOGNAME': 'cltbld', 07:46:39 INFO - 'MAIL': '/var/mail/cltbld', 07:46:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:46:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 07:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:39 INFO - 'MOZ_NO_REMOTE': '1', 07:46:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:46:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:39 INFO - 'NO_EM_RESTART': '1', 07:46:39 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:39 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:39 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 07:46:39 INFO - 'SHELL': '/bin/bash', 07:46:39 INFO - 'SHLVL': '1', 07:46:39 INFO - 'TERM': 'linux', 07:46:39 INFO - 'TMOUT': '86400', 07:46:39 INFO - 'USER': 'cltbld', 07:46:39 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325', 07:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:39 INFO - '_': '/tools/buildbot/bin/python'} 07:46:39 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/1451129028/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost'] with output_timeout 3600 07:46:39 INFO - mozversion INFO | application_buildid: 20151226032348 07:46:39 INFO - mozversion INFO | application_changeset: 336a31419e0085f3c63c294d8824ca283d9cafcb 07:46:39 INFO - mozversion INFO | application_display_name: Nightly 07:46:39 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:46:39 INFO - mozversion INFO | application_name: Firefox 07:46:39 INFO - mozversion INFO | application_remotingname: firefox 07:46:39 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:46:39 INFO - mozversion INFO | application_vendor: Mozilla 07:46:39 INFO - mozversion INFO | application_version: 44.0 07:46:39 INFO - mozversion INFO | platform_buildid: 20151226032348 07:46:39 INFO - mozversion INFO | platform_changeset: 336a31419e0085f3c63c294d8824ca283d9cafcb 07:46:39 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:46:39 INFO - mozversion INFO | platform_version: 44.0 07:46:39 INFO - 2015-12-28 07:46:39,906 DEBUG : using testdate: 1451317599 07:46:39 INFO - 2015-12-28 07:46:39,906 DEBUG : actual date: 1451317599 07:46:39 INFO - 2015-12-28 07:46:39,957 INFO : starting webserver on 'localhost:35906' 07:46:39 INFO - 2015-12-28 07:46:39,958 INFO : Starting test suite talos-linux64-ix-051 07:46:39 INFO - 2015-12-28 07:46:39,958 INFO : Starting test dromaeo_css 07:46:39 INFO - 2015-12-28 07:46:39,959 DEBUG : operating with platform_type : linux_ 07:46:39 INFO - 2015-12-28 07:46:39,959 INFO : Initialising browser for dromaeo_css test... 07:46:39 INFO - 2015-12-28 07:46:39,976 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDZGwKc/profile http://localhost:35906/getInfo.html 07:46:43 INFO - 2015-12-28 07:46:43,619 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:46:43 INFO - 2015-12-28 07:46:43,620 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:46:43 INFO - 2015-12-28 07:46:43,620 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:46:43 INFO - 2015-12-28 07:46:43,620 DEBUG : BROWSER_OUTPUT: __metrics 07:46:43 INFO - 2015-12-28 07:46:43,716 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] 07:46:44 INFO - 2015-12-28 07:46:44,869 INFO : Browser initialized. 07:46:44 INFO - 2015-12-28 07:46:44,870 INFO : Running cycle 1/1 for dromaeo_css test... 07:46:44 INFO - 2015-12-28 07:46:44,871 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDZGwKc/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:46:56 INFO - 2015-12-28 07:46:56,413 DEBUG : BROWSER_OUTPUT: RSS: Main: 128430080 07:46:56 INFO - 2015-12-28 07:46:56,413 DEBUG : BROWSER_OUTPUT: 07:47:47 INFO - 2015-12-28 07:47:47,237 DEBUG : BROWSER_OUTPUT: 1451317667237 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:49:55 INFO - 2015-12-28 07:49:55,326 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35906/tests/dromaeo/cssquery-ext.html) 07:49:56 INFO - 2015-12-28 07:49:56,379 DEBUG : BROWSER_OUTPUT: RSS: Main: 235048960 07:49:56 INFO - 2015-12-28 07:49:56,379 DEBUG : BROWSER_OUTPUT: 07:52:58 INFO - 2015-12-28 07:52:58,446 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/dromaeo/cssquery-ext.html (next: http://localhost:35906/tests/dromaeo/cssquery-jquery.html) 07:52:59 INFO - 2015-12-28 07:52:59,009 DEBUG : BROWSER_OUTPUT: RSS: Main: 200867840 07:52:59 INFO - 2015-12-28 07:52:59,009 DEBUG : BROWSER_OUTPUT: 07:56:00 INFO - 2015-12-28 07:56:00,739 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35906/tests/dromaeo/cssquery-mootools.html) 07:56:01 INFO - 2015-12-28 07:56:01,302 DEBUG : BROWSER_OUTPUT: RSS: Main: 202113024 07:56:01 INFO - 2015-12-28 07:56:01,302 DEBUG : BROWSER_OUTPUT: 07:59:03 INFO - 2015-12-28 07:59:03,025 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35906/tests/dromaeo/cssquery-prototype.html) 07:59:03 INFO - 2015-12-28 07:59:03,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 192258048 07:59:03 INFO - 2015-12-28 07:59:03,598 DEBUG : BROWSER_OUTPUT: 08:02:05 INFO - 2015-12-28 08:02:05,851 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35906/tests/dromaeo/cssquery-yui.html) 08:02:06 INFO - 2015-12-28 08:02:06,417 DEBUG : BROWSER_OUTPUT: RSS: Main: 193216512 08:02:06 INFO - 2015-12-28 08:02:06,418 DEBUG : BROWSER_OUTPUT: 08:05:08 INFO - 2015-12-28 08:05:08,509 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/dromaeo/cssquery-yui.html (next: http://localhost:35906/tests/dromaeo/cssquery-dojo.html) 08:05:08 INFO - 2015-12-28 08:05:08,759 DEBUG : BROWSER_OUTPUT: RSS: Main: 204189696 08:05:08 INFO - 2015-12-28 08:05:08,759 DEBUG : BROWSER_OUTPUT: 08:05:08 INFO - 2015-12-28 08:05:08,760 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:05:08 INFO - 2015-12-28 08:05:08,760 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:05:08 INFO - 2015-12-28 08:05:08,760 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:05:08 INFO - 2015-12-28 08:05:08,760 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:05:08 INFO - 2015-12-28 08:05:08,761 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3325.9168520786984;3395.6095049069336;3424.5376874122003;3431.4166591679414;3449.36186805441;5670.149477578365;5687.402822703608;5692;5693.8576535586635;5701.230333904927;5719.857003574903;5743.396943320954;5747.59766816323;5748.05157149071;5750.654960702361;5051.6716413433005;5211.817586384468;5228.47715228478;5294.708791016512;5308.522232999029;5119.488051194888;5142.948570514303;5168.664036837612;5173.534381905628;5233.188855727369;5098.872528186788;5195.220716892459;5196.402413722432;5211.765470553815;5213.661112027725;5216.035033518773;5273.050850846846;5289.920651190235;5296.311479507678;5308.601854860882;4958.603311735053;5036.093503169428;5036.11867923112;5124.359455068116;5220.190870415092;5450.291462109901;5457.290552228185;5464.754086066137;5465.426130256305;5477.67133971963;6127.172831667757;6130.96934515329;6137.9386206137815;6138.355472675349;6140.416660417253;6023.849403764897;6037.879242415127;6125.601835880653;6130.673316708229;6141.578063794106;5796.710164491801;5814.854628634275;5822.883542329129;5824.504917082096;5838.12428135783;6000.939990600125;6304.432601065834;6318.304986451487;6341.778037768656;6346.397092276226;5938.732757025944;5956.255468066492;6118.268583817243;6122.967606272468;6126.692094832117;6114.694265286718;6117.449429551273;6122.26532816056;6133.44798968095;6152.3540028297275;5980.5514586405325;6086.178365920633;6095.359987201368;6099.725512351866;6110.908336374958;5972.820815375546;6078.848028799315;6079.878402431927;6080.483158931452;6101.786437474667;4674.5325467452985;4682.5551572601225;4694.2958556216845;4700.882477938079;4721.716696998123;4086.244044851712;4092.570280120604;4164.68764842638;4185.22573323936;4195.056112374691;5765.711714414347;5765.971170144123;5768.855778605484;5771.88456230873;5813.244278243801;6184.567080304426;6193;6194.256689197235;6197.287311959182;6229.408206220402;5669.376368599451;5679.971600141973;5782.537397008229;5796.449337312949;5842.065269556935;5323.441038690959;5348.786048558092;5349.893002139936;5350.4649535046965;5355.036093503207;6270.372962703785;6285.9057114143325;6287.1198032275515;6289.245290565162;6289.276733175743;5967;6018.458338749533;6081.939180608138;6113.35025128125;6116.67207508553;6156;6174.845628859314;6177.135201071764;6201.844953876189;6202.193714817045;5854.560907931837;5906.763729450774;6021.729022193924;6030.577859549965;6048.546359023003;5966.70166491665;6119.541034422347;6239.78160764371;6242.843928901632;6243.8126856194385;6053.364396738455;6062.757489700362;6063.969680151571;6064.363241859542;6068.6965651716355;5821.446962538552;5948.405159484017;6041.848953776191;6044;6067.393260673897;5824.970875145597;5913.704314784158;5916.615419997686;5918.437748413894;5933.110033495011;5971.761129554943;6006.219191505076;6048.274207095177;6056.424639659225;6058.757649694139;5700.771969121189;5760.423957604206;5797.101449275369;5914.290285165807;5925.792597259076 08:05:08 INFO - 2015-12-28 08:05:08,763 DEBUG : BROWSER_OUTPUT: |1;ext.html;603.4900509069835;630.1555915073801;638.2373064188293;651.9185101862267;654.6792071884778;34558.82720586397;43356;43436.3484547732;43449.78275108628;43466.56533434665;47027.764861175645;47745.52254477454;47778.52221477784;47782;47977;8202;8296;9152.405093668907;9347.345685801996;10657.573697052147;10831.350118992885;10966.451677416137;10971.890281097207;10975.50610222542;10984.890151098507;10972.670819875377;11052.834207486894;11085.667429977113;11097.056750176245;11116.388598627072;10922.890771092307;11003.61487347939;11004.284721493077;11013.28413653114;11019.393933333744;10657.360558366523;10782.137429005661;10804.13566914645;10805.459727013618;10806.135509159249;44318.556814431766;44743.32885006727;44746;44766.55233447656;44837.775811121064;3436.226848958989;3468.2543253200683;3541.94687079694;3609.151849315409;4027.1744292419985;32502.34995300081;32839.835800821085;32938.17654558655;32957.6704232957;33043.83478082618;3026.8637911293827;3072.247299411636;3082.7379672727843;3160.8893688720786;3200.487921932503;303000;305776;306369;306679;308235;62435.37564624296;64446.67776661181;65207.34792652108;65501;72195.27804721991;68526.314736852;68631;68927.65536172286;68937.65531172312;68971.31028689649;16264.18679066042;16394.590135246475;16405.91797041031;16433.506994790176;16452.506424807274;11446.313221206588;11487.483063262172;11491.540338386538;11493.425328733698;11496.310221386744;20995.370138895858;21519.46201344979;21577.676334854677;21605.35183944484;21708.674369884466;8608.784780380416;8724.738257852274;8866.468011919176;8915.55422228886;8932.955335223282;947.1239103828931;1042.671558459083;1051.6897515233024;1095.315427857589;1181.0846593889805;29154.416911662072;29693.85153074221;30594.69405305963;30644.38711225763;30818.53772193419;955.6989548292265;1042.4839704346246;1047.3244757131608;1150.0512077536066;1206.9861316494187;12937.417816198295;14606.196659183634;15621.453249136226;15715.284017170627;16298.266578004019;363778;370668;370840;371263;371678;59114.40885591089;59273.110903336485;59302.40697593055;59532.404675953556;59630.40369596335;1425.836028856674;1453.978190327146;1459.4672944375227;1479.0607963942973;1482.1773915477013;31791.364172715952;31854.203644909063;31862.995872591797;32265.35469290648;32518.83740581282;50951.235731464796;51347.22979155241;51459.74270128625;51479;51526.48473515217;334645;337386;338718;338953;339017;33154.834225828716;33300.333993320484;35173;37352.62647373491;37933;47447.76276118597;59251.111233330674;63071;63938;64972.67513662401;41702.582974171084;44631.776841115585;45318;49592.504074960234;51681;45462;45684.77157614191;45709.77145114386;45735.313970291136;45916.311255331864;44116;44193.33709994417;44211.33682994694;44715;44907.77546112249;38826.22347552977;39581.60418395779;40089.3941893776;40478;41994.160116798106;26765.19704408871;28602.42795144127;29133.85433072821;29460.55809162907;30438.69561304447 08:05:08 INFO - 2015-12-28 08:05:08,763 DEBUG : BROWSER_OUTPUT: |2;jquery.html;386.4358037265592;387.8991462219822;389.04877574330754;391.3562190197125;394.52065740125744;14242.786358204608;18583.349582764607;20565.691514627273;20598.588028239446;21249.36251912445;20154.798452015475;20285.188592456285;20607.690884636744;20653.793462065376;20678.586428271425;5788.82633520995;5922.230110085683;5930.940690593104;5933.436323549277;5935.406459354073;5842.561807864406;5927.822165335026;6005.159277701126;6007.309159446654;6008.939910600881;4391.407160033403;4502.414686090804;5961.374055724152;5967.791127310545;5973.701314934257;6336.081268216106;6481.546291759579;6530.44491218247;6537.705803238866;6539.836504087412;5776.653400795965;5888.617239879437;5899.115132730125;5905.232319798399;5907.793227237026;23436.17973370924;23752;24013;24620.753792462026;24620.87689561541;2159.7948194921455;2240.1487434774845;2272.9204477843195;2336.661184128309;2346.906123755065;14741.705165896728;14815.555533333909;14943.356230814601;15028.699426011528;15263.313150908236;1791.3730194431844;1836.1737218251706;1853.5736780540417;1882.6611209982334;1899.6580615489206;63336;70756.29243707549;71840.28159718389;72868.6356568219;73389.26610733877;7705.768826935201;7767.067951845815;7840.607969601611;7924.049114106344;7975.641096150687;9423.151916327564;9426.293028022927;9446.716598502057;9447.291453141042;9448.338616296793;6184.907226391608;6310.652914089769;6343.016832390987;6343.207099112611;6349.9682501587195;6460.515461340418;6462.515311351667;6478.773242936474;6483.740650373932;6508.284088750233;3596.946045809315;3647.744657873923;3647.98852721051;3662.926741465209;3673.081729567608;1208.4501551793912;1247.077162899466;1266.0631132961541;1285.0233822295122;1291.3156027305542;467.4437419470819;475.8239451403003;480.14773776546906;488.8044782087122;501.2731539268076;12058.879411205775;12085.516579336903;12419.068569857296;12528.686782830502;12720.173188742701;490.8699194713404;499.6627276588288;521.3613323678463;522.4175044824992;522.8170140450811;5885.14665373527;6857.522641827638;6904.723811047602;6961.129858767654;7055.506114572034;669842;673936;674232;714115;721182;84093.57953210195;86774;88009.14235525497;88376.55811721028;88421;617.3702823120392;624.0763669768768;624.5659266809524;626.6177631644601;627.4948666323653;11201.60794372193;11376.146788991024;11380.089592832719;11833.526658933546;12009;38333.80833095816;38586;39041.804790977;40175;40210.79894600508;54582.7270863659;54689.44518606278;54998.45001550093;56213.43786562082;56550.43449565452;7506.28690274423;7557.35762460197;7658.4639075264195;7660.195679453577;7661.15727270011;13458.798118028433;13479.663008424868;13560.660983475493;13567.389467474184;13695.726085478434;11351.37567433799;11627.18609697347;11786.823197651871;11957.940210298893;11981.161318707607;11234.494447749707;11455.656330310105;11696;11847.992920601846;11872.46573904159;9293.814123717622;11503.53985840557;11549.191556590958;11626.302421854716;11925.880741192477;7376.373008294355;7384.96307518459;7446.962765186139;7487.513311634726;7492.325690687646;10639.14886809069;10651.041406273473;10725.41010244444;10846.511906964364;11425.257358271685 08:05:08 INFO - 2015-12-28 08:05:08,764 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1559.5477311579643;1560.8439156084387;1760.8327208915148;1825.0692147005027;1852.397970659535;6705.329467053292;6820.52256342055;7615.961920190398;7827.569483678402;7847.372210223176;7066.152061752597;7764.611769411535;7894.684212631488;7908.485948413349;7912.6439310231035;2318.130700987134;2379.119725701493;2379.4408314046186;2380.690510233676;2383.4279772854475;2014.5769388428375;2055.835533157344;2357.7524359942217;2369.763023697634;2379.1553998330605;2334.451403920086;2366.763323667628;2366.810655147584;2370.2533701883935;2372.027468737818;2313.6066868632374;2345.8240631952585;2346.4603141277516;2347.7534858839917;2347.9060837566476;2382.4401265702545;2400.84394514356;2414.625733011386;2416.649585810065;2417.16607770319;8065.072516660601;8065.879011814827;8101.230383113654;8110.188981101902;8110.837783244302;4970.229614409773;5041.571466425358;5042.42012168598;5053.242013697938;5053.74731263439;3296.5055241713694;3340.28183940454;3352.5641666583433;3354.1614596350914;3357.7649564530493;977.5258999385293;979.1347483044811;998.9150922171624;1017.4454922067491;1033.0237925160727;11053.447327633667;11173.944130279458;11195.720106997309;11199.776004479865;11211.943940280247;4563.133004729091;4584.381108550303;4611.746353950565;4618.030213655103;4645.349651048856;9811.803763924681;9822.26333025026;9825.263105266991;9829.115379615869;9835.45904975219;5011.674241174312;5016.924746128811;5017.67385119966;5019.14674505335;5029.874253143701;4555.863324100216;4558.680892337505;4570.268756998864;4572.291294849238;4581.65637577183;3809.9047523811573;3856.382978723391;3881.4177873318663;3883.96116038836;3889.4943657324366;1144.324848339467;1221.6823625857164;1263.848338199422;1320.7886738121854;1322.5371120108077;1114.4817659788237;1135.7217481717032;1140.1278022312772;1188.916775825684;1219.3903048475763;7681.231876812386;7748.845023099507;7817.491863028885;7826.373890088779;7842.294193522496;1096.413418820927;1152.4180288954024;1186.768580126867;1189.393409361232;1207.402335843763;6091.0558863376245;6112.908306375319;6131.14164017038;6133.84665383369;6141.508679305645;9608.087231712976;9940.055694708992;9944.900550994542;9976.15202707778;10012.04885535873;9836.213102951746;9872.654457093968;9945.353552019096;9973.750656233653;9976.700698979042;7487.66305516253;7698.191689872483;7700.730474433368;7712.691492340244;7719.884201736979;7692;7756.263155000266;7778.299953004031;7785.455018148902;7837.843243135219;8693.434926729742;8849.60176792033;8854.380193386658;8878.822423551623;8960.1935825776;8959.238464730572;9037.954810225907;9049.185573298435;9085.36402451822;9213.447193168431;7482.289182527651;7583.5070720403;7594.468387212842;7594.772156835304;7603.809904752426;6847.452203823782;7715.652795624323;7721.9227807723455;7765.495242809199;8049.356051515982;6594.90107648376;7164.104486939133;7357.0803649543805;7436.888446673413;7463.925360746323;7103.644817758988;7116.857662846817;7222.422206223385;7246.492745507764;7346.5592064476305;7312.378447831994;7350.044494215717;7353.7058517660835;7355.264473552602;7361.852762944604;6551.082848401133;6607.2071351438135;6620.106285651568;6629.900551491828;6645.933540664531;6640.169978752656;6651.833704157435;6664.966675166787;6668.866622667617;6674.699638516181 08:05:08 INFO - 2015-12-28 08:05:08,765 DEBUG : BROWSER_OUTPUT: |4;prototype.html;354.1129470675957;355.2823296940181;355.9857605695772;359.7841295222865;364.5279363224622;5885.08781138923;6010.8196754097335;6016.428439298271;6037;6040.57715959883;5805.506531944779;5825.737841797119;5920.644761314314;5924;5929.051351783715;4659.324397962285;4663.510331415204;4668.11305851887;4674.158651442739;4679.181143299926;4662.0675864827;4665.160271151191;4670.953290467086;4676.064787042588;4677.88305292367;4543.204939135655;4618.861434156981;4625.930611040837;4626.86119416418;4677.368555245032;4661.836835710734;4662.97668511657;4663.556962088614;4668.159731248374;4682.204025315705;3945.349017412152;4128.649064829493;4130.463039804837;4131.586841315874;4134.586541345871;5520.723963801794;5556.499915007628;5656.066748986413;5656.632318899258;5672.064109421982;5329.706866122362;5360.061989151883;5362.410134885159;5366.302380690524;5368.006918720011;6211.9068213976825;6222.937770622281;6297.086922396227;6299.275583307933;6303.653299068549;4421.137878113769;4488.236999710057;4528.5245049270015;4539.364488971513;4580.816767329303;6539.2479864815205;6565.277819439858;6578.144841170665;6579.374959378852;6589.34106589342;4687.296905464208;4700.2244629635725;4704.29435584665;4707.152712511713;4713.151632706145;4770.809167633325;4771.5228477152705;4774.188387974053;4774.427068751772;4777.163996300634;5207.843764687065;5301.0723123453245;5304.098303288451;5304.522592966651;5305.549028332558;6354.459870911024;6358.77744278948;6359.9364006360265;6361.681915904186;6362.045693145972;1653.6609994951007;1676.857467115309;1678.6223099802692;1679.1268540358944;1680.2942764039133;1081.3944191252924;1111.8832522585174;1120.2326406411635;1127.5433449452985;1143.0398465289197;6218.160548325918;6219.564630475823;6221.626702397871;6223.315435302113;6226.813195604139;6631.071649969008;6637.369449902251;6637.7676781313385;6641.169853768279;6642.4021838034805;6024.421170339182;6206.317305096436;6210.31686514483;6216.564840461215;6222.813315600449;3619.3847046002243;3649.379605467024;3652.634736526379;3655;3656.341858465502;6458.418742313214;6521.934780652227;6571.83570410734;6681.365270299314;6698.095757072831;6664.866702666017;6684.398404143651;6684.699188536528;6698.765543205965;6699.899501507482;6632.2372927114;6647.135872336662;6661.500387470959;6662.267150613428;6663.5335526513645;6437;6535.215774106948;6562.868742625025;6567.376099270562;6570.080188773671;6583.703733331916;6593.472522198309;6594.70323835419;6598.208215014229;6601.768938087144;6744.291849355944;6750;6751.93248067513;6756;6757.526973111835;4565.908681826411;4670.883227919329;4673.691366417409;4681.204195286741;4685.484596694293;6657;6661.367170119025;6663.200415950117;6665.133532640726;6667.066610674607;6448.806535803933;6470.579412338183;6498.3826536478955;6498.707558159799;6506.902396463962;6342.778002769881;6441.838954026;6482.708278127401;6487.22153341602;6491.156149700698;6384.840378990562;6385.776497822554;6389.648569328731;6391.265004524539;6393.328700486382;6512.869742605216;6515;6515.2833188348295;6521.152250207443;6521.282658907421;6244.438000579969;6359;6360.045993101072;6372.330905254881;6374.968125159345 08:05:08 INFO - 2015-12-28 08:05:08,765 DEBUG : BROWSER_OUTPUT: |5;yui.html;719.1083057009307;781.0627247303238;785.4501848705908;803.6865622407258;813.9593020348983;13543.458261669533;14257.572272831805;14262.144271343726;14285.78571321431;14299.92850035751;13302.93348533256;13369.866301336984;13377.464901403931;13386.59840204793;13397.464101435955;764.632976171438;779.5439667794348;913.6710784117712;933.7385532051037;933.9533023348839;924.0205382294755;925.3615005646117;932.6502561539422;932.9860052099224;933.0576118120679;816.8897198878135;826.099551488877;971.9368241064308;980.308882238024;982.0719420147963;938.1462868789403;952.7475219066953;952.7951490429556;954.3796532254021;954.9092836180552;706.2161001288565;707.7098389660266;708.0264636125328;708.1396103733937;708.5642329967085;13059.281739504324;13184.60446186616;13192.934035329858;13205.405756740969;13212.339383030907;10883.67348979532;10995.560177592886;11159.832602510969;11249.550017999352;11371.772564548746;9726.367786093952;9870.45712485813;9993.150582200522;10143.18854491639;10178.18574514037;3389.694927456541;3431.3480438716565;3432.63957284484;3445.7243420526297;3476.4437689969736;51090.23364649496;52369.73815130938;52712;52725;52836.73581632067;9494.05059494045;9595.712128636153;10053.648122315684;10073.244506661884;10131.442770647685;21085.57828843445;21139.26012589552;21302.147914083263;21309.893450532647;21357.572848543252;7869.331106855867;7895.60521973899;7934.2859142677435;8049;8124.7562573122905;4090.570490098556;4205.053862880888;4210.5578914214175;4222.071144348239;4224.345226489899;3485.5294535237927;3511.9815253576176;3514.7188224941942;3516.40221162403;3521.0669172669263;493.5064935064935;509.4905094905095;512.5643203277259;512.718005097195;513.2634669249564;580.8896309701144;595.8480587450173;621.9937800622026;636.5257882877286;651.6155468273741;8586.95706521476;8887.644494220158;9123.543822808833;9224.40041397307;9365.203957663538;507.9492050794891;517.7126694684487;540.1654443884208;613.2579578709812;663.9609011896367;4030.5163380393956;4259.701820872509;4284.485861696617;4302.827886884552;4505.774711264489;69389.65305173442;70873;71275.50535731224;71423.64288178629;71577.64211178912;59468;59792.40207597955;60721;61288.38711612916;61306.38693613007;53555.19667204995;54875;54896;57172.14241786377;57914.420855791745;8244;8358.331333493428;8433.704820331259;8496.32029437656;8525.872111918203;39641.801790992016;39653.206935860544;40761.79619101885;40927;40961.590384095776;50822.23766643577;53324.73337633287;53482.732586338374;53813.46186538084;53973.73013134909;8010.879836802569;8110.513369197867;8198.057223419382;8358.45670031446;8414.326853851555;11067.944660276647;11120.833187502356;11187.384693841917;11220.438978051228;11278.774424511628;10044.196464282986;10054.29619926598;10358.792824143324;10559.894401055892;10873.782524349626;10718.73203169927;10822.675319740105;10828.24202305831;10861.945690271497;11169.162312826733;9904.158146557624;10136;10323;10764.192685548458;10987.560497580007;9500;9793.65722199748;9805.75485612837;9929.702108936743;9948.950255248677;8933.687320943736;8934.195922367018;9088.591013404544;9431;9476.336656433983 08:05:08 INFO - 2015-12-28 08:05:08,765 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:05:08 INFO - 2015-12-28 08:05:08,765 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:05:08 INFO - 2015-12-28 08:05:08,765 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,799 08:05:08 INFO - 2015-12-28 08:05:08,765 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:05:08 INFO - 2015-12-28 08:05:08,765 DEBUG : BROWSER_OUTPUT: __startTimestamp1451318708757__endTimestamp 08:05:08 INFO - 2015-12-28 08:05:08,766 DEBUG : BROWSER_OUTPUT: 08:05:08 INFO - 2015-12-28 08:05:08,769 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:05:08 INFO - 2015-12-28 08:05:08,769 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:05:08 INFO - 2015-12-28 08:05:08,769 DEBUG : BROWSER_OUTPUT: 08:05:08 INFO - 2015-12-28 08:05:08,769 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5673.37 Median:5913.70 stddev:634.08 (10.7%) stddev-sans-first:608.69 08:05:08 INFO - 2015-12-28 08:05:08,769 DEBUG : BROWSER_OUTPUT: Values: 3325.9 3395.6 3424.5 3431.4 3449.4 5670.1 5687.4 5692.0 5693.9 5701.2 5719.9 5743.4 5747.6 5748.1 5750.7 5051.7 5211.8 5228.5 5294.7 5308.5 5119.5 5142.9 5168.7 5173.5 5233.2 5098.9 5195.2 5196.4 5211.8 5213.7 5216.0 5273.1 5289.9 5296.3 5308.6 4958.6 5036.1 5036.1 5124.4 5220.2 5450.3 5457.3 5464.8 5465.4 5477.7 6127.2 6131.0 6137.9 6138.4 6140.4 6023.8 6037.9 6125.6 6130.7 6141.6 5796.7 5814.9 5822.9 5824.5 5838.1 6000.9 6304.4 6318.3 6341.8 6346.4 5938.7 5956.3 6118.3 6123.0 6126.7 6114.7 6117.4 6122.3 6133.4 6152.4 5980.6 6086.2 6095.4 6099.7 6110.9 5972.8 6078.8 6079.9 6080.5 6101.8 4674.5 4682.6 4694.3 4700.9 4721.7 4086.2 4092.6 4164.7 4185.2 4195.1 5765.7 5766.0 5768.9 5771.9 5813.2 6184.6 6193.0 6194.3 6197.3 6229.4 5669.4 5680.0 5782.5 5796.4 5842.1 5323.4 5348.8 5349.9 5350.5 5355.0 6270.4 6285.9 6287.1 6289.2 6289.3 5967.0 6018.5 6081.9 6113.4 6116.7 6156.0 6174.8 6177.1 6201.8 6202.2 5854.6 5906.8 6021.7 6030.6 6048.5 5966.7 6119.5 6239.8 6242.8 6243.8 6053.4 6062.8 6064.0 6064.4 6068.7 5821.4 5948.4 6041.8 6044.0 6067.4 5825.0 5913.7 5916.6 5918.4 5933.1 5971.8 6006.2 6048.3 6056.4 6058.8 5700.8 5760.4 5797.1 5914.3 5925.8 08:05:08 INFO - 2015-12-28 08:05:08,769 DEBUG : BROWSER_OUTPUT: 08:05:08 INFO - 2015-12-28 08:05:08,769 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:53498.71 Median:171226.61 stddev:88586.53 (51.7%) stddev-sans-first:88746.01 08:05:08 INFO - 2015-12-28 08:05:08,769 DEBUG : BROWSER_OUTPUT: Values: 603.5 630.2 638.2 651.9 654.7 34558.8 43356.0 43436.3 43449.8 43466.6 47027.8 47745.5 47778.5 47782.0 47977.0 8202.0 8296.0 9152.4 9347.3 10657.6 10831.4 10966.5 10971.9 10975.5 10984.9 10972.7 11052.8 11085.7 11097.1 11116.4 10922.9 11003.6 11004.3 11013.3 11019.4 10657.4 10782.1 10804.1 10805.5 10806.1 44318.6 44743.3 44746.0 44766.6 44837.8 3436.2 3468.3 3541.9 3609.2 4027.2 32502.3 32839.8 32938.2 32957.7 33043.8 3026.9 3072.2 3082.7 3160.9 3200.5 303000.0 305776.0 306369.0 306679.0 308235.0 62435.4 64446.7 65207.3 65501.0 72195.3 68526.3 68631.0 68927.7 68937.7 68971.3 16264.2 16394.6 16405.9 16433.5 16452.5 11446.3 11487.5 11491.5 11493.4 11496.3 20995.4 21519.5 21577.7 21605.4 21708.7 8608.8 8724.7 8866.5 8915.6 8933.0 947.1 1042.7 1051.7 1095.3 1181.1 29154.4 29693.9 30594.7 30644.4 30818.5 955.7 1042.5 1047.3 1150.1 1207.0 12937.4 14606.2 15621.5 15715.3 16298.3 363778.0 370668.0 370840.0 371263.0 371678.0 59114.4 59273.1 59302.4 59532.4 59630.4 1425.8 1454.0 1459.5 1479.1 1482.2 31791.4 31854.2 31863.0 32265.4 32518.8 50951.2 51347.2 51459.7 51479.0 51526.5 334645.0 337386.0 338718.0 338953.0 339017.0 33154.8 33300.3 35173.0 37352.6 37933.0 47447.8 59251.1 63071.0 63938.0 64972.7 41702.6 44631.8 45318.0 49592.5 51681.0 45462.0 45684.8 45709.8 45735.3 45916.3 44116.0 44193.3 44211.3 44715.0 44907.8 38826.2 39581.6 40089.4 40478.0 41994.2 26765.2 28602.4 29133.9 29460.6 30438.7 08:05:08 INFO - 2015-12-28 08:05:08,769 DEBUG : BROWSER_OUTPUT: 08:05:08 INFO - 2015-12-28 08:05:08,770 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33244.98 Median:495.27 stddev:113116.50 (22839.5%) stddev-sans-first:113406.91 08:05:08 INFO - 2015-12-28 08:05:08,770 DEBUG : BROWSER_OUTPUT: Values: 386.4 387.9 389.0 391.4 394.5 14242.8 18583.3 20565.7 20598.6 21249.4 20154.8 20285.2 20607.7 20653.8 20678.6 5788.8 5922.2 5930.9 5933.4 5935.4 5842.6 5927.8 6005.2 6007.3 6008.9 4391.4 4502.4 5961.4 5967.8 5973.7 6336.1 6481.5 6530.4 6537.7 6539.8 5776.7 5888.6 5899.1 5905.2 5907.8 23436.2 23752.0 24013.0 24620.8 24620.9 2159.8 2240.1 2272.9 2336.7 2346.9 14741.7 14815.6 14943.4 15028.7 15263.3 1791.4 1836.2 1853.6 1882.7 1899.7 63336.0 70756.3 71840.3 72868.6 73389.3 7705.8 7767.1 7840.6 7924.0 7975.6 9423.2 9426.3 9446.7 9447.3 9448.3 6184.9 6310.7 6343.0 6343.2 6350.0 6460.5 6462.5 6478.8 6483.7 6508.3 3596.9 3647.7 3648.0 3662.9 3673.1 1208.5 1247.1 1266.1 1285.0 1291.3 467.4 475.8 480.1 488.8 501.3 12058.9 12085.5 12419.1 12528.7 12720.2 490.9 499.7 521.4 522.4 522.8 5885.1 6857.5 6904.7 6961.1 7055.5 669842.0 673936.0 674232.0 714115.0 721182.0 84093.6 86774.0 88009.1 88376.6 88421.0 617.4 624.1 624.6 626.6 627.5 11201.6 11376.1 11380.1 11833.5 12009.0 38333.8 38586.0 39041.8 40175.0 40210.8 54582.7 54689.4 54998.5 56213.4 56550.4 7506.3 7557.4 7658.5 7660.2 7661.2 13458.8 13479.7 13560.7 13567.4 13695.7 11351.4 11627.2 11786.8 11957.9 11981.2 11234.5 11455.7 11696.0 11848.0 11872.5 9293.8 11503.5 11549.2 11626.3 11925.9 7376.4 7385.0 7447.0 7487.5 7492.3 10639.1 10651.0 10725.4 10846.5 11425.3 08:05:08 INFO - 2015-12-28 08:05:08,770 DEBUG : BROWSER_OUTPUT: 08:05:08 INFO - 2015-12-28 08:05:08,770 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5718.94 Median:6613.66 stddev:2958.30 (44.7%) stddev-sans-first:2950.08 08:05:08 INFO - 2015-12-28 08:05:08,770 DEBUG : BROWSER_OUTPUT: Values: 1559.5 1560.8 1760.8 1825.1 1852.4 6705.3 6820.5 7616.0 7827.6 7847.4 7066.2 7764.6 7894.7 7908.5 7912.6 2318.1 2379.1 2379.4 2380.7 2383.4 2014.6 2055.8 2357.8 2369.8 2379.2 2334.5 2366.8 2366.8 2370.3 2372.0 2313.6 2345.8 2346.5 2347.8 2347.9 2382.4 2400.8 2414.6 2416.6 2417.2 8065.1 8065.9 8101.2 8110.2 8110.8 4970.2 5041.6 5042.4 5053.2 5053.7 3296.5 3340.3 3352.6 3354.2 3357.8 977.5 979.1 998.9 1017.4 1033.0 11053.4 11173.9 11195.7 11199.8 11211.9 4563.1 4584.4 4611.7 4618.0 4645.3 9811.8 9822.3 9825.3 9829.1 9835.5 5011.7 5016.9 5017.7 5019.1 5029.9 4555.9 4558.7 4570.3 4572.3 4581.7 3809.9 3856.4 3881.4 3884.0 3889.5 1144.3 1221.7 1263.8 1320.8 1322.5 1114.5 1135.7 1140.1 1188.9 1219.4 7681.2 7748.8 7817.5 7826.4 7842.3 1096.4 1152.4 1186.8 1189.4 1207.4 6091.1 6112.9 6131.1 6133.8 6141.5 9608.1 9940.1 9944.9 9976.2 10012.0 9836.2 9872.7 9945.4 9973.8 9976.7 7487.7 7698.2 7700.7 7712.7 7719.9 7692.0 7756.3 7778.3 7785.5 7837.8 8693.4 8849.6 8854.4 8878.8 8960.2 8959.2 9038.0 9049.2 9085.4 9213.4 7482.3 7583.5 7594.5 7594.8 7603.8 6847.5 7715.7 7721.9 7765.5 8049.4 6594.9 7164.1 7357.1 7436.9 7463.9 7103.6 7116.9 7222.4 7246.5 7346.6 7312.4 7350.0 7353.7 7355.3 7361.9 6551.1 6607.2 6620.1 6629.9 6645.9 6640.2 6651.8 6665.0 6668.9 6674.7 08:05:08 INFO - 2015-12-28 08:05:08,770 DEBUG : BROWSER_OUTPUT: 08:05:08 INFO - 2015-12-28 08:05:08,771 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5365.98 Median:6123.45 stddev:1581.49 (25.8%) stddev-sans-first:1540.53 08:05:08 INFO - 2015-12-28 08:05:08,771 DEBUG : BROWSER_OUTPUT: Values: 354.1 355.3 356.0 359.8 364.5 5885.1 6010.8 6016.4 6037.0 6040.6 5805.5 5825.7 5920.6 5924.0 5929.1 4659.3 4663.5 4668.1 4674.2 4679.2 4662.1 4665.2 4671.0 4676.1 4677.9 4543.2 4618.9 4625.9 4626.9 4677.4 4661.8 4663.0 4663.6 4668.2 4682.2 3945.3 4128.6 4130.5 4131.6 4134.6 5520.7 5556.5 5656.1 5656.6 5672.1 5329.7 5360.1 5362.4 5366.3 5368.0 6211.9 6222.9 6297.1 6299.3 6303.7 4421.1 4488.2 4528.5 4539.4 4580.8 6539.2 6565.3 6578.1 6579.4 6589.3 4687.3 4700.2 4704.3 4707.2 4713.2 4770.8 4771.5 4774.2 4774.4 4777.2 5207.8 5301.1 5304.1 5304.5 5305.5 6354.5 6358.8 6359.9 6361.7 6362.0 1653.7 1676.9 1678.6 1679.1 1680.3 1081.4 1111.9 1120.2 1127.5 1143.0 6218.2 6219.6 6221.6 6223.3 6226.8 6631.1 6637.4 6637.8 6641.2 6642.4 6024.4 6206.3 6210.3 6216.6 6222.8 3619.4 3649.4 3652.6 3655.0 3656.3 6458.4 6521.9 6571.8 6681.4 6698.1 6664.9 6684.4 6684.7 6698.8 6699.9 6632.2 6647.1 6661.5 6662.3 6663.5 6437.0 6535.2 6562.9 6567.4 6570.1 6583.7 6593.5 6594.7 6598.2 6601.8 6744.3 6750.0 6751.9 6756.0 6757.5 4565.9 4670.9 4673.7 4681.2 4685.5 6657.0 6661.4 6663.2 6665.1 6667.1 6448.8 6470.6 6498.4 6498.7 6506.9 6342.8 6441.8 6482.7 6487.2 6491.2 6384.8 6385.8 6389.6 6391.3 6393.3 6512.9 6515.0 6515.3 6521.2 6521.3 6244.4 6359.0 6360.0 6372.3 6375.0 08:05:08 INFO - 2015-12-28 08:05:08,771 DEBUG : BROWSER_OUTPUT: 08:05:08 INFO - 2015-12-28 08:05:08,771 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15098.28 Median:53893.60 stddev:19123.68 (35.5%) stddev-sans-first:19146.85 08:05:08 INFO - 2015-12-28 08:05:08,771 DEBUG : BROWSER_OUTPUT: Values: 719.1 781.1 785.5 803.7 814.0 13543.5 14257.6 14262.1 14285.8 14299.9 13302.9 13369.9 13377.5 13386.6 13397.5 764.6 779.5 913.7 933.7 934.0 924.0 925.4 932.7 933.0 933.1 816.9 826.1 971.9 980.3 982.1 938.1 952.7 952.8 954.4 954.9 706.2 707.7 708.0 708.1 708.6 13059.3 13184.6 13192.9 13205.4 13212.3 10883.7 10995.6 11159.8 11249.6 11371.8 9726.4 9870.5 9993.2 10143.2 10178.2 3389.7 3431.3 3432.6 3445.7 3476.4 51090.2 52369.7 52712.0 52725.0 52836.7 9494.1 9595.7 10053.6 10073.2 10131.4 21085.6 21139.3 21302.1 21309.9 21357.6 7869.3 7895.6 7934.3 8049.0 8124.8 4090.6 4205.1 4210.6 4222.1 4224.3 3485.5 3512.0 3514.7 3516.4 3521.1 493.5 509.5 512.6 512.7 513.3 580.9 595.8 622.0 636.5 651.6 8587.0 8887.6 9123.5 9224.4 9365.2 507.9 517.7 540.2 613.3 664.0 4030.5 4259.7 4284.5 4302.8 4505.8 69389.7 70873.0 71275.5 71423.6 71577.6 59468.0 59792.4 60721.0 61288.4 61306.4 53555.2 54875.0 54896.0 57172.1 57914.4 8244.0 8358.3 8433.7 8496.3 8525.9 39641.8 39653.2 40761.8 40927.0 40961.6 50822.2 53324.7 53482.7 53813.5 53973.7 8010.9 8110.5 8198.1 8358.5 8414.3 11067.9 11120.8 11187.4 11220.4 11278.8 10044.2 10054.3 10358.8 10559.9 10873.8 10718.7 10822.7 10828.2 10861.9 11169.2 9904.2 10136.0 10323.0 10764.2 10987.6 9500.0 9793.7 9805.8 9929.7 9949.0 8933.7 8934.2 9088.6 9431.0 9476.3 08:05:08 INFO - 2015-12-28 08:05:08,772 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:05:08 INFO - 2015-12-28 08:05:08,772 DEBUG : BROWSER_OUTPUT: 08:05:09 INFO - 2015-12-28 08:05:09,357 INFO : Browser exited with error code: 0 08:05:09 INFO - 2015-12-28 08:05:09,378 INFO : Completed test dromaeo_css (00:18:29) 08:05:09 INFO - 2015-12-28 08:05:09,378 INFO : Starting test kraken 08:05:09 INFO - 2015-12-28 08:05:09,378 DEBUG : operating with platform_type : linux_ 08:05:09 INFO - 2015-12-28 08:05:09,379 INFO : Initialising browser for kraken test... 08:05:09 INFO - 2015-12-28 08:05:09,385 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp7_2mny/profile http://localhost:35906/getInfo.html 08:05:12 INFO - 2015-12-28 08:05:12,798 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:05:12 INFO - 2015-12-28 08:05:12,798 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:05:12 INFO - 2015-12-28 08:05:12,798 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:05:12 INFO - 2015-12-28 08:05:12,798 DEBUG : BROWSER_OUTPUT: __metrics 08:05:12 INFO - 2015-12-28 08:05:12,866 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] 08:05:14 INFO - 2015-12-28 08:05:14,253 INFO : Browser initialized. 08:05:14 INFO - 2015-12-28 08:05:14,254 INFO : Running cycle 1/1 for kraken test... 08:05:14 INFO - 2015-12-28 08:05:14,254 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp7_2mny/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:05:25 INFO - 2015-12-28 08:05:25,774 DEBUG : BROWSER_OUTPUT: RSS: Main: 129052672 08:05:25 INFO - 2015-12-28 08:05:25,774 DEBUG : BROWSER_OUTPUT: 08:05:28 INFO - 2015-12-28 08:05:28,194 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35906/tests/kraken/driver.html?testName=audio-beat-detection) 08:05:28 INFO - 2015-12-28 08:05:28,762 DEBUG : BROWSER_OUTPUT: RSS: Main: 162598912 08:05:28 INFO - 2015-12-28 08:05:28,762 DEBUG : BROWSER_OUTPUT: 08:05:31 INFO - 2015-12-28 08:05:31,301 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35906/tests/kraken/driver.html?testName=audio-dft) 08:05:31 INFO - 2015-12-28 08:05:31,854 DEBUG : BROWSER_OUTPUT: RSS: Main: 160145408 08:05:31 INFO - 2015-12-28 08:05:31,854 DEBUG : BROWSER_OUTPUT: 08:05:34 INFO - 2015-12-28 08:05:34,637 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35906/tests/kraken/driver.html?testName=audio-fft) 08:05:35 INFO - 2015-12-28 08:05:35,200 DEBUG : BROWSER_OUTPUT: RSS: Main: 157446144 08:05:35 INFO - 2015-12-28 08:05:35,200 DEBUG : BROWSER_OUTPUT: 08:05:37 INFO - 2015-12-28 08:05:37,154 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35906/tests/kraken/driver.html?testName=audio-oscillator) 08:05:37 INFO - 2015-12-28 08:05:37,715 DEBUG : BROWSER_OUTPUT: RSS: Main: 155701248 08:05:37 INFO - 2015-12-28 08:05:37,715 DEBUG : BROWSER_OUTPUT: 08:05:39 INFO - 2015-12-28 08:05:39,813 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35906/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:05:40 INFO - 2015-12-28 08:05:40,366 DEBUG : BROWSER_OUTPUT: RSS: Main: 156422144 08:05:40 INFO - 2015-12-28 08:05:40,366 DEBUG : BROWSER_OUTPUT: 08:05:43 INFO - 2015-12-28 08:05:43,248 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35906/tests/kraken/driver.html?testName=imaging-darkroom) 08:05:43 INFO - 2015-12-28 08:05:43,814 DEBUG : BROWSER_OUTPUT: RSS: Main: 155037696 08:05:43 INFO - 2015-12-28 08:05:43,814 DEBUG : BROWSER_OUTPUT: 08:05:47 INFO - 2015-12-28 08:05:47,148 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35906/tests/kraken/driver.html?testName=imaging-desaturate) 08:05:47 INFO - 2015-12-28 08:05:47,710 DEBUG : BROWSER_OUTPUT: RSS: Main: 157315072 08:05:47 INFO - 2015-12-28 08:05:47,710 DEBUG : BROWSER_OUTPUT: 08:05:50 INFO - 2015-12-28 08:05:50,427 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35906/tests/kraken/driver.html?testName=json-parse-financial) 08:05:50 INFO - 2015-12-28 08:05:50,997 DEBUG : BROWSER_OUTPUT: RSS: Main: 156344320 08:05:50 INFO - 2015-12-28 08:05:50,997 DEBUG : BROWSER_OUTPUT: 08:05:52 INFO - 2015-12-28 08:05:52,471 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35906/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:05:53 INFO - 2015-12-28 08:05:53,034 DEBUG : BROWSER_OUTPUT: RSS: Main: 159244288 08:05:53 INFO - 2015-12-28 08:05:53,034 DEBUG : BROWSER_OUTPUT: 08:05:54 INFO - 2015-12-28 08:05:54,806 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35906/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:05:55 INFO - 2015-12-28 08:05:55,392 DEBUG : BROWSER_OUTPUT: RSS: Main: 160219136 08:05:55 INFO - 2015-12-28 08:05:55,392 DEBUG : BROWSER_OUTPUT: 08:05:57 INFO - 2015-12-28 08:05:57,345 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35906/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:05:57 INFO - 2015-12-28 08:05:57,911 DEBUG : BROWSER_OUTPUT: RSS: Main: 165507072 08:05:57 INFO - 2015-12-28 08:05:57,911 DEBUG : BROWSER_OUTPUT: 08:06:00 INFO - 2015-12-28 08:06:00,231 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35906/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:06:00 INFO - 2015-12-28 08:06:00,813 DEBUG : BROWSER_OUTPUT: RSS: Main: 171704320 08:06:00 INFO - 2015-12-28 08:06:00,813 DEBUG : BROWSER_OUTPUT: 08:06:03 INFO - 2015-12-28 08:06:03,240 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35906/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:06:03 INFO - 2015-12-28 08:06:03,802 DEBUG : BROWSER_OUTPUT: RSS: Main: 170180608 08:06:03 INFO - 2015-12-28 08:06:03,802 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,412 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35906/tests/kraken/driver.html?testName=ai-astar) 08:06:05 INFO - 2015-12-28 08:06:05,658 DEBUG : BROWSER_OUTPUT: RSS: Main: 228347904 08:06:05 INFO - 2015-12-28 08:06:05,658 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,659 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:06:05 INFO - 2015-12-28 08:06:05,660 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:06:05 INFO - 2015-12-28 08:06:05,660 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:06:05 INFO - 2015-12-28 08:06:05,660 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:06:05 INFO - 2015-12-28 08:06:05,660 DEBUG : BROWSER_OUTPUT: |0;ai-astar;106;109;106;110;109;110;100;109;111;109 08:06:05 INFO - 2015-12-28 08:06:05,660 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;147;122;145;158;127;151;152;132;158;121 08:06:05 INFO - 2015-12-28 08:06:05,660 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;159;159;158;161;159;164;159;161 08:06:05 INFO - 2015-12-28 08:06:05,660 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;70;138;78;72;72;71;147;71 08:06:05 INFO - 2015-12-28 08:06:05,661 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;133;90;90;90;129;88;89;136 08:06:05 INFO - 2015-12-28 08:06:05,661 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;110;110;110;109;109;109;110 08:06:05 INFO - 2015-12-28 08:06:05,661 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;144;145;146;145;145;145;145;145;145;145 08:06:05 INFO - 2015-12-28 08:06:05,661 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;96;95;95;95;95;96;96 08:06:05 INFO - 2015-12-28 08:06:05,661 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;53;54;54;54;55;55;65;52;53 08:06:05 INFO - 2015-12-28 08:06:05,661 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;65;64;64;63;63;64;64;63 08:06:05 INFO - 2015-12-28 08:06:05,661 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;76;87;80;77;80;79;80;78;76 08:06:05 INFO - 2015-12-28 08:06:05,662 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;106;115;111;116;116;120;111;106;111 08:06:05 INFO - 2015-12-28 08:06:05,662 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;128;136;135;147;148;137;137;136;135;133 08:06:05 INFO - 2015-12-28 08:06:05,662 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;62;61;61;60;60;63;62;61;63 08:06:05 INFO - 2015-12-28 08:06:05,662 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:06:05 INFO - 2015-12-28 08:06:05,662 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:06:05 INFO - 2015-12-28 08:06:05,662 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,855 08:06:05 INFO - 2015-12-28 08:06:05,662 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:06:05 INFO - 2015-12-28 08:06:05,663 DEBUG : BROWSER_OUTPUT: __startTimestamp1451318765658__endTimestamp 08:06:05 INFO - 2015-12-28 08:06:05,663 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,666 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:06:05 INFO - 2015-12-28 08:06:05,666 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:06:05 INFO - 2015-12-28 08:06:05,666 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,666 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.90 Median:109.00 stddev:3.21 (2.9%) stddev-sans-first:3.33 08:06:05 INFO - 2015-12-28 08:06:05,666 DEBUG : BROWSER_OUTPUT: Values: 106.0 109.0 106.0 110.0 109.0 110.0 100.0 109.0 111.0 109.0 08:06:05 INFO - 2015-12-28 08:06:05,666 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,666 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.30 Median:149.00 stddev:14.48 (9.7%) stddev-sans-first:15.22 08:06:05 INFO - 2015-12-28 08:06:05,666 DEBUG : BROWSER_OUTPUT: Values: 147.0 122.0 145.0 158.0 127.0 151.0 152.0 132.0 158.0 121.0 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.10 Median:160.50 stddev:1.73 (1.1%) stddev-sans-first:1.83 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 159.0 159.0 158.0 161.0 159.0 164.0 159.0 161.0 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.20 Median:71.50 stddev:29.83 (41.7%) stddev-sans-first:31.13 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 70.0 138.0 78.0 72.0 72.0 71.0 147.0 71.0 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.30 Median:89.00 stddev:21.03 (23.6%) stddev-sans-first:21.75 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 133.0 90.0 90.0 90.0 129.0 88.0 89.0 136.0 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 110.0 110.0 110.0 109.0 109.0 109.0 110.0 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.00 Median:145.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: Values: 144.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 08:06:05 INFO - 2015-12-28 08:06:05,667 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.00 Median:54.50 stddev:3.65 (6.7%) stddev-sans-first:3.87 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: Values: 55.0 53.0 54.0 54.0 54.0 55.0 55.0 65.0 52.0 53.0 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 65.0 64.0 64.0 63.0 63.0 64.0 64.0 63.0 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:79.50 stddev:3.23 (4.1%) stddev-sans-first:3.35 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: Values: 77.0 76.0 87.0 80.0 77.0 80.0 79.0 80.0 78.0 76.0 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.90 Median:115.50 stddev:4.68 (4.0%) stddev-sans-first:4.72 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: Values: 117.0 106.0 115.0 111.0 116.0 116.0 120.0 111.0 106.0 111.0 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,668 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.20 Median:136.50 stddev:6.03 (4.4%) stddev-sans-first:5.40 08:06:05 INFO - 2015-12-28 08:06:05,669 DEBUG : BROWSER_OUTPUT: Values: 128.0 136.0 135.0 147.0 148.0 137.0 137.0 136.0 135.0 133.0 08:06:05 INFO - 2015-12-28 08:06:05,669 DEBUG : BROWSER_OUTPUT: 08:06:05 INFO - 2015-12-28 08:06:05,669 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:1.17 (1.9%) stddev-sans-first:1.13 08:06:05 INFO - 2015-12-28 08:06:05,669 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 61.0 61.0 60.0 60.0 63.0 62.0 61.0 63.0 08:06:05 INFO - 2015-12-28 08:06:05,669 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:06:05 INFO - 2015-12-28 08:06:05,669 DEBUG : BROWSER_OUTPUT: 08:06:06 INFO - 2015-12-28 08:06:06,301 INFO : Browser exited with error code: 0 08:06:06 INFO - 2015-12-28 08:06:06,351 INFO : Completed test kraken (00:00:56) 08:06:06 INFO - 2015-12-28 08:06:06,352 INFO : Starting test v8_7 08:06:06 INFO - 2015-12-28 08:06:06,352 DEBUG : operating with platform_type : linux_ 08:06:06 INFO - 2015-12-28 08:06:06,352 INFO : Initialising browser for v8_7 test... 08:06:06 INFO - 2015-12-28 08:06:06,358 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4wVYoc/profile http://localhost:35906/getInfo.html 08:06:09 INFO - 2015-12-28 08:06:09,856 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:06:09 INFO - 2015-12-28 08:06:09,856 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:06:09 INFO - 2015-12-28 08:06:09,857 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:06:09 INFO - 2015-12-28 08:06:09,857 DEBUG : BROWSER_OUTPUT: __metrics 08:06:09 INFO - 2015-12-28 08:06:09,973 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] 08:06:11 INFO - 2015-12-28 08:06:11,041 INFO : Browser initialized. 08:06:11 INFO - 2015-12-28 08:06:11,042 INFO : Running cycle 1/1 for v8_7 test... 08:06:11 INFO - 2015-12-28 08:06:11,042 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4wVYoc/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:06:22 INFO - 2015-12-28 08:06:22,570 DEBUG : BROWSER_OUTPUT: RSS: Main: 129339392 08:06:22 INFO - 2015-12-28 08:06:22,570 DEBUG : BROWSER_OUTPUT: 08:06:43 INFO - 2015-12-28 08:06:43,919 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35906/tests/v8_7/run.html (next: http://localhost:35906/tests/v8_7/run.html) 08:06:44 INFO - 2015-12-28 08:06:44,177 DEBUG : BROWSER_OUTPUT: RSS: Main: 411824128 08:06:44 INFO - 2015-12-28 08:06:44,178 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,178 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:06:44 INFO - 2015-12-28 08:06:44,178 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:06:44 INFO - 2015-12-28 08:06:44,178 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:06:44 INFO - 2015-12-28 08:06:44,179 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:06:44 INFO - 2015-12-28 08:06:44,179 DEBUG : BROWSER_OUTPUT: |0;Richards;166.58337497917708 08:06:44 INFO - 2015-12-28 08:06:44,179 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.590242238144 08:06:44 INFO - 2015-12-28 08:06:44,179 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.4076557697681 08:06:44 INFO - 2015-12-28 08:06:44,179 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5040.201005025126 08:06:44 INFO - 2015-12-28 08:06:44,180 DEBUG : BROWSER_OUTPUT: |4;RayTrace;956.9377990430622 08:06:44 INFO - 2015-12-28 08:06:44,180 DEBUG : BROWSER_OUTPUT: |5;Earley;554.016620498615 08:06:44 INFO - 2015-12-28 08:06:44,180 DEBUG : BROWSER_OUTPUT: |6;Boyer;10934.782608695652 08:06:44 INFO - 2015-12-28 08:06:44,180 DEBUG : BROWSER_OUTPUT: |7;RegExp;25871.79487179487 08:06:44 INFO - 2015-12-28 08:06:44,181 DEBUG : BROWSER_OUTPUT: |8;Splay;517.3305742369374 08:06:44 INFO - 2015-12-28 08:06:44,181 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4882.926829268293 08:06:44 INFO - 2015-12-28 08:06:44,181 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:06:44 INFO - 2015-12-28 08:06:44,181 DEBUG : BROWSER_OUTPUT: __startTimestamp1451318804170__endTimestamp 08:06:44 INFO - 2015-12-28 08:06:44,182 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,182 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:06:44 INFO - 2015-12-28 08:06:44,182 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:06:44 INFO - 2015-12-28 08:06:44,182 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,182 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.58 Median:166.58 stddev:0.00 (0.0%) 08:06:44 INFO - 2015-12-28 08:06:44,183 DEBUG : BROWSER_OUTPUT: Values: 166.6 08:06:44 INFO - 2015-12-28 08:06:44,183 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,183 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.59 Median:170.59 stddev:0.00 (0.0%) 08:06:44 INFO - 2015-12-28 08:06:44,183 DEBUG : BROWSER_OUTPUT: Values: 170.6 08:06:44 INFO - 2015-12-28 08:06:44,184 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,184 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.41 Median:279.41 stddev:0.00 (0.0%) 08:06:44 INFO - 2015-12-28 08:06:44,184 DEBUG : BROWSER_OUTPUT: Values: 279.4 08:06:44 INFO - 2015-12-28 08:06:44,184 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,185 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5040.20 Median:5040.20 stddev:0.00 (0.0%) 08:06:44 INFO - 2015-12-28 08:06:44,185 DEBUG : BROWSER_OUTPUT: Values: 5040.2 08:06:44 INFO - 2015-12-28 08:06:44,185 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,185 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:956.94 Median:956.94 stddev:0.00 (0.0%) 08:06:44 INFO - 2015-12-28 08:06:44,185 DEBUG : BROWSER_OUTPUT: Values: 956.9 08:06:44 INFO - 2015-12-28 08:06:44,185 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,185 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:554.02 Median:554.02 stddev:0.00 (0.0%) 08:06:44 INFO - 2015-12-28 08:06:44,186 DEBUG : BROWSER_OUTPUT: Values: 554.0 08:06:44 INFO - 2015-12-28 08:06:44,186 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,186 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10934.78 Median:10934.78 stddev:0.00 (0.0%) 08:06:44 INFO - 2015-12-28 08:06:44,186 DEBUG : BROWSER_OUTPUT: Values: 10934.8 08:06:44 INFO - 2015-12-28 08:06:44,186 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,186 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25871.79 Median:25871.79 stddev:0.00 (0.0%) 08:06:44 INFO - 2015-12-28 08:06:44,186 DEBUG : BROWSER_OUTPUT: Values: 25871.8 08:06:44 INFO - 2015-12-28 08:06:44,186 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,186 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:517.33 Median:517.33 stddev:0.00 (0.0%) 08:06:44 INFO - 2015-12-28 08:06:44,187 DEBUG : BROWSER_OUTPUT: Values: 517.3 08:06:44 INFO - 2015-12-28 08:06:44,187 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,187 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4882.93 Median:4882.93 stddev:0.00 (0.0%) 08:06:44 INFO - 2015-12-28 08:06:44,187 DEBUG : BROWSER_OUTPUT: Values: 4882.9 08:06:44 INFO - 2015-12-28 08:06:44,187 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:06:44 INFO - 2015-12-28 08:06:44,187 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2015-12-28 08:06:44,826 INFO : Browser exited with error code: 0 08:06:44 INFO - 2015-12-28 08:06:44,837 INFO : Completed test v8_7 (00:00:38) 08:06:45 INFO - 2015-12-28 08:06:45,230 INFO : Completed test suite (00:20:05) 08:06:45 INFO - 2015-12-28 08:06:45,230 DEBUG : Working with test: dromaeo_css 08:06:45 INFO - 2015-12-28 08:06:45,231 DEBUG : Generating results file: dromaeo_css 08:06:45 INFO - 2015-12-28 08:06:45,232 DEBUG : Working with test: kraken 08:06:45 INFO - 2015-12-28 08:06:45,232 DEBUG : Generating results file: kraken 08:06:45 INFO - 2015-12-28 08:06:45,232 INFO : javascript benchmark 08:06:45 INFO - 2015-12-28 08:06:45,232 DEBUG : Working with test: v8_7 08:06:45 INFO - 2015-12-28 08:06:45,232 DEBUG : Generating results file: v8_7 08:06:45 INFO - 2015-12-28 08:06:45,233 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:06:45 INFO - 2015-12-28 08:06:45,290 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 08:06:45 INFO - 2015-12-28 08:06:45,291 DEBUG : process_Request line: dromaeo_css 7196.47 graph.html#tests=[[72,53,35]] 08:06:45 INFO - 2015-12-28 08:06:45,291 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:06:45 INFO - 2015-12-28 08:06:45,355 DEBUG : process_Request line: kraken 1457.20 graph.html#tests=[[232,53,35]] 08:06:45 INFO - 2015-12-28 08:06:45,355 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:06:45 INFO - 2015-12-28 08:06:45,403 DEBUG : process_Request line: v8_7 22981.47 graph.html#tests=[[230,53,35]] 08:06:45 INFO - 2015-12-28 08:06:45,404 INFO : javascript benchmark 08:06:45 INFO - 2015-12-28 08:06:45,408 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3325.9168520786984, 3395.6095049069336, 3424.5376874122003, 3431.4166591679414, 3449.36186805441, 5670.149477578365, 5687.402822703608, 5692.0, 5693.8576535586635, 5701.230333904927, 5719.857003574903, 5743.396943320954, 5747.59766816323, 5748.05157149071, 5750.654960702361, 5051.6716413433005, 5211.817586384468, 5228.47715228478, 5294.708791016512, 5308.522232999029, 5119.488051194888, 5142.948570514303, 5168.664036837612, 5173.534381905628, 5233.188855727369, 5098.872528186788, 5195.220716892459, 5196.402413722432, 5211.765470553815, 5213.661112027725, 5216.035033518773, 5273.050850846846, 5289.920651190235, 5296.311479507678, 5308.601854860882, 4958.603311735053, 5036.093503169428, 5036.11867923112, 5124.359455068116, 5220.190870415092, 5450.291462109901, 5457.290552228185, 5464.754086066137, 5465.426130256305, 5477.67133971963, 6127.172831667757, 6130.96934515329, 6137.9386206137815, 6138.355472675349, 6140.416660417253, 6023.849403764897, 6037.879242415127, 6125.601835880653, 6130.673316708229, 6141.578063794106, 5796.710164491801, 5814.854628634275, 5822.883542329129, 5824.504917082096, 5838.12428135783, 6000.939990600125, 6304.432601065834, 6318.304986451487, 6341.778037768656, 6346.397092276226, 5938.732757025944, 5956.255468066492, 6118.268583817243, 6122.967606272468, 6126.692094832117, 6114.694265286718, 6117.449429551273, 6122.26532816056, 6133.44798968095, 6152.3540028297275, 5980.5514586405325, 6086.178365920633, 6095.359987201368, 6099.725512351866, 6110.908336374958, 5972.820815375546, 6078.848028799315, 6079.878402431927, 6080.483158931452, 6101.786437474667, 4674.5325467452985, 4682.5551572601225, 4694.2958556216845, 4700.882477938079, 4721.716696998123, 4086.244044851712, 4092.570280120604, 4164.68764842638, 4185.22573323936, 4195.056112374691, 5765.711714414347, 5765.971170144123, 5768.855778605484, 5771.88456230873, 5813.244278243801, 6184.567080304426, 6193.0, 6194.256689197235, 6197.287311959182, 6229.408206220402, 5669.376368599451, 5679.971600141973, 5782.537397008229, 5796.449337312949, 5842.065269556935, 5323.441038690959, 5348.786048558092, 5349.893002139936, 5350.4649535046965, 5355.036093503207, 6270.372962703785, 6285.9057114143325, 6287.1198032275515, 6289.245290565162, 6289.276733175743, 5967.0, 6018.458338749533, 6081.939180608138, 6113.35025128125, 6116.67207508553, 6156.0, 6174.845628859314, 6177.135201071764, 6201.844953876189, 6202.193714817045, 5854.560907931837, 5906.763729450774, 6021.729022193924, 6030.577859549965, 6048.546359023003, 5966.70166491665, 6119.541034422347, 6239.78160764371, 6242.843928901632, 6243.8126856194385, 6053.364396738455, 6062.757489700362, 6063.969680151571, 6064.363241859542, 6068.6965651716355, 5821.446962538552, 5948.405159484017, 6041.848953776191, 6044.0, 6067.393260673897, 5824.970875145597, 5913.704314784158, 5916.615419997686, 5918.437748413894, 5933.110033495011, 5971.761129554943, 6006.219191505076, 6048.274207095177, 6056.424639659225, 6058.757649694139, 5700.771969121189, 5760.423957604206, 5797.101449275369, 5914.290285165807, 5925.792597259076], "unit": "ms", "name": "dojo.html", "value": 5631.778356328063}, {"lowerIsBetter": false, "replicates": [603.4900509069835, 630.1555915073801, 638.2373064188293, 651.9185101862267, 654.6792071884778, 34558.82720586397, 43356.0, 43436.3484547732, 43449.78275108628, 43466.56533434665, 47027.764861175645, 47745.52254477454, 47778.52221477784, 47782.0, 47977.0, 8202.0, 8296.0, 9152.405093668907, 9347.345685801996, 10657.573697052147, 10831.350118992885, 10966.451677416137, 10971.890281097207, 10975.50610222542, 10984.890151098507, 10972.670819875377, 11052.834207486894, 11085.667429977113, 11097.056750176245, 11116.388598627072, 10922.890771092307, 11003.61487347939, 11004.284721493077, 11013.28413653114, 11019.393933333744, 10657.360558366523, 10782.137429005661, 10804.13566914645, 10805.459727013618, 10806.135509159249, 44318.556814431766, 44743.32885006727, 44746.0, 44766.55233447656, 44837.775811121064, 3436.226848958989, 3468.2543253200683, 3541.94687079694, 3609.151849315409, 4027.1744292419985, 32502.34995300081, 32839.835800821085, 32938.17654558655, 32957.6704232957, 33043.83478082618, 3026.8637911293827, 3072.247299411636, 3082.7379672727843, 3160.8893688720786, 3200.487921932503, 303000.0, 305776.0, 306369.0, 306679.0, 308235.0, 62435.37564624296, 64446.67776661181, 65207.34792652108, 65501.0, 72195.27804721991, 68526.314736852, 68631.0, 68927.65536172286, 68937.65531172312, 68971.31028689649, 16264.18679066042, 16394.590135246475, 16405.91797041031, 16433.506994790176, 16452.506424807274, 11446.313221206588, 11487.483063262172, 11491.540338386538, 11493.425328733698, 11496.310221386744, 20995.370138895858, 21519.46201344979, 21577.676334854677, 21605.35183944484, 21708.674369884466, 8608.784780380416, 8724.738257852274, 8866.468011919176, 8915.55422228886, 8932.955335223282, 947.1239103828931, 1042.671558459083, 1051.6897515233024, 1095.315427857589, 1181.0846593889805, 29154.416911662072, 29693.85153074221, 30594.69405305963, 30644.38711225763, 30818.53772193419, 955.6989548292265, 1042.4839704346246, 1047.3244757131608, 1150.0512077536066, 1206.9861316494187, 12937.417816198295, 14606.196659183634, 15621.453249136226, 15715.284017170627, 16298.266578004019, 363778.0, 370668.0, 370840.0, 371263.0, 371678.0, 59114.40885591089, 59273.110903336485, 59302.40697593055, 59532.404675953556, 59630.40369596335, 1425.836028856674, 1453.978190327146, 1459.4672944375227, 1479.0607963942973, 1482.1773915477013, 31791.364172715952, 31854.203644909063, 31862.995872591797, 32265.35469290648, 32518.83740581282, 50951.235731464796, 51347.22979155241, 51459.74270128625, 51479.0, 51526.48473515217, 334645.0, 337386.0, 338718.0, 338953.0, 339017.0, 33154.834225828716, 33300.333993320484, 35173.0, 37352.62647373491, 37933.0, 47447.76276118597, 59251.111233330674, 63071.0, 63938.0, 64972.67513662401, 41702.582974171084, 44631.776841115585, 45318.0, 49592.504074960234, 51681.0, 45462.0, 45684.77157614191, 45709.77145114386, 45735.313970291136, 45916.311255331864, 44116.0, 44193.33709994417, 44211.33682994694, 44715.0, 44907.77546112249, 38826.22347552977, 39581.60418395779, 40089.3941893776, 40478.0, 41994.160116798106, 26765.19704408871, 28602.42795144127, 29133.85433072821, 29460.55809162907, 30438.69561304447], "unit": "ms", "name": "ext.html", "value": 20820.384326550662}, {"lowerIsBetter": false, "replicates": [386.4358037265592, 387.8991462219822, 389.04877574330754, 391.3562190197125, 394.52065740125744, 14242.786358204608, 18583.349582764607, 20565.691514627273, 20598.588028239446, 21249.36251912445, 20154.798452015475, 20285.188592456285, 20607.690884636744, 20653.793462065376, 20678.586428271425, 5788.82633520995, 5922.230110085683, 5930.940690593104, 5933.436323549277, 5935.406459354073, 5842.561807864406, 5927.822165335026, 6005.159277701126, 6007.309159446654, 6008.939910600881, 4391.407160033403, 4502.414686090804, 5961.374055724152, 5967.791127310545, 5973.701314934257, 6336.081268216106, 6481.546291759579, 6530.44491218247, 6537.705803238866, 6539.836504087412, 5776.653400795965, 5888.617239879437, 5899.115132730125, 5905.232319798399, 5907.793227237026, 23436.17973370924, 23752.0, 24013.0, 24620.753792462026, 24620.87689561541, 2159.7948194921455, 2240.1487434774845, 2272.9204477843195, 2336.661184128309, 2346.906123755065, 14741.705165896728, 14815.555533333909, 14943.356230814601, 15028.699426011528, 15263.313150908236, 1791.3730194431844, 1836.1737218251706, 1853.5736780540417, 1882.6611209982334, 1899.6580615489206, 63336.0, 70756.29243707549, 71840.28159718389, 72868.6356568219, 73389.26610733877, 7705.768826935201, 7767.067951845815, 7840.607969601611, 7924.049114106344, 7975.641096150687, 9423.151916327564, 9426.293028022927, 9446.716598502057, 9447.291453141042, 9448.338616296793, 6184.907226391608, 6310.652914089769, 6343.016832390987, 6343.207099112611, 6349.9682501587195, 6460.515461340418, 6462.515311351667, 6478.773242936474, 6483.740650373932, 6508.284088750233, 3596.946045809315, 3647.744657873923, 3647.98852721051, 3662.926741465209, 3673.081729567608, 1208.4501551793912, 1247.077162899466, 1266.0631132961541, 1285.0233822295122, 1291.3156027305542, 467.4437419470819, 475.8239451403003, 480.14773776546906, 488.8044782087122, 501.2731539268076, 12058.879411205775, 12085.516579336903, 12419.068569857296, 12528.686782830502, 12720.173188742701, 490.8699194713404, 499.6627276588288, 521.3613323678463, 522.4175044824992, 522.8170140450811, 5885.14665373527, 6857.522641827638, 6904.723811047602, 6961.129858767654, 7055.506114572034, 669842.0, 673936.0, 674232.0, 714115.0, 721182.0, 84093.57953210195, 86774.0, 88009.14235525497, 88376.55811721028, 88421.0, 617.3702823120392, 624.0763669768768, 624.5659266809524, 626.6177631644601, 627.4948666323653, 11201.60794372193, 11376.146788991024, 11380.089592832719, 11833.526658933546, 12009.0, 38333.80833095816, 38586.0, 39041.804790977, 40175.0, 40210.79894600508, 54582.7270863659, 54689.44518606278, 54998.45001550093, 56213.43786562082, 56550.43449565452, 7506.28690274423, 7557.35762460197, 7658.4639075264195, 7660.195679453577, 7661.15727270011, 13458.798118028433, 13479.663008424868, 13560.660983475493, 13567.389467474184, 13695.726085478434, 11351.37567433799, 11627.18609697347, 11786.823197651871, 11957.940210298893, 11981.161318707607, 11234.494447749707, 11455.656330310105, 11696.0, 11847.992920601846, 11872.46573904159, 9293.814123717622, 11503.53985840557, 11549.191556590958, 11626.302421854716, 11925.880741192477, 7376.373008294355, 7384.96307518459, 7446.962765186139, 7487.513311634726, 7492.325690687646, 10639.14886809069, 10651.041406273473, 10725.41010244444, 10846.511906964364, 11425.257358271685], "unit": "ms", "name": "jquery.html", "value": 8048.106171067422}, {"lowerIsBetter": false, "replicates": [1559.5477311579643, 1560.8439156084387, 1760.8327208915148, 1825.0692147005027, 1852.397970659535, 6705.329467053292, 6820.52256342055, 7615.961920190398, 7827.569483678402, 7847.372210223176, 7066.152061752597, 7764.611769411535, 7894.684212631488, 7908.485948413349, 7912.6439310231035, 2318.130700987134, 2379.119725701493, 2379.4408314046186, 2380.690510233676, 2383.4279772854475, 2014.5769388428375, 2055.835533157344, 2357.7524359942217, 2369.763023697634, 2379.1553998330605, 2334.451403920086, 2366.763323667628, 2366.810655147584, 2370.2533701883935, 2372.027468737818, 2313.6066868632374, 2345.8240631952585, 2346.4603141277516, 2347.7534858839917, 2347.9060837566476, 2382.4401265702545, 2400.84394514356, 2414.625733011386, 2416.649585810065, 2417.16607770319, 8065.072516660601, 8065.879011814827, 8101.230383113654, 8110.188981101902, 8110.837783244302, 4970.229614409773, 5041.571466425358, 5042.42012168598, 5053.242013697938, 5053.74731263439, 3296.5055241713694, 3340.28183940454, 3352.5641666583433, 3354.1614596350914, 3357.7649564530493, 977.5258999385293, 979.1347483044811, 998.9150922171624, 1017.4454922067491, 1033.0237925160727, 11053.447327633667, 11173.944130279458, 11195.720106997309, 11199.776004479865, 11211.943940280247, 4563.133004729091, 4584.381108550303, 4611.746353950565, 4618.030213655103, 4645.349651048856, 9811.803763924681, 9822.26333025026, 9825.263105266991, 9829.115379615869, 9835.45904975219, 5011.674241174312, 5016.924746128811, 5017.67385119966, 5019.14674505335, 5029.874253143701, 4555.863324100216, 4558.680892337505, 4570.268756998864, 4572.291294849238, 4581.65637577183, 3809.9047523811573, 3856.382978723391, 3881.4177873318663, 3883.96116038836, 3889.4943657324366, 1144.324848339467, 1221.6823625857164, 1263.848338199422, 1320.7886738121854, 1322.5371120108077, 1114.4817659788237, 1135.7217481717032, 1140.1278022312772, 1188.916775825684, 1219.3903048475763, 7681.231876812386, 7748.845023099507, 7817.491863028885, 7826.373890088779, 7842.294193522496, 1096.413418820927, 1152.4180288954024, 1186.768580126867, 1189.393409361232, 1207.402335843763, 6091.0558863376245, 6112.908306375319, 6131.14164017038, 6133.84665383369, 6141.508679305645, 9608.087231712976, 9940.055694708992, 9944.900550994542, 9976.15202707778, 10012.04885535873, 9836.213102951746, 9872.654457093968, 9945.353552019096, 9973.750656233653, 9976.700698979042, 7487.66305516253, 7698.191689872483, 7700.730474433368, 7712.691492340244, 7719.884201736979, 7692.0, 7756.263155000266, 7778.299953004031, 7785.455018148902, 7837.843243135219, 8693.434926729742, 8849.60176792033, 8854.380193386658, 8878.822423551623, 8960.1935825776, 8959.238464730572, 9037.954810225907, 9049.185573298435, 9085.36402451822, 9213.447193168431, 7482.289182527651, 7583.5070720403, 7594.468387212842, 7594.772156835304, 7603.809904752426, 6847.452203823782, 7715.652795624323, 7721.9227807723455, 7765.495242809199, 8049.356051515982, 6594.90107648376, 7164.104486939133, 7357.0803649543805, 7436.888446673413, 7463.925360746323, 7103.644817758988, 7116.857662846817, 7222.422206223385, 7246.492745507764, 7346.5592064476305, 7312.378447831994, 7350.044494215717, 7353.7058517660835, 7355.264473552602, 7361.852762944604, 6551.082848401133, 6607.2071351438135, 6620.106285651568, 6629.900551491828, 6645.933540664531, 6640.169978752656, 6651.833704157435, 6664.966675166787, 6668.866622667617, 6674.699638516181], "unit": "ms", "name": "mootools.html", "value": 4697.299881835658}, {"lowerIsBetter": false, "replicates": [354.1129470675957, 355.2823296940181, 355.9857605695772, 359.7841295222865, 364.5279363224622, 5885.08781138923, 6010.8196754097335, 6016.428439298271, 6037.0, 6040.57715959883, 5805.506531944779, 5825.737841797119, 5920.644761314314, 5924.0, 5929.051351783715, 4659.324397962285, 4663.510331415204, 4668.11305851887, 4674.158651442739, 4679.181143299926, 4662.0675864827, 4665.160271151191, 4670.953290467086, 4676.064787042588, 4677.88305292367, 4543.204939135655, 4618.861434156981, 4625.930611040837, 4626.86119416418, 4677.368555245032, 4661.836835710734, 4662.97668511657, 4663.556962088614, 4668.159731248374, 4682.204025315705, 3945.349017412152, 4128.649064829493, 4130.463039804837, 4131.586841315874, 4134.586541345871, 5520.723963801794, 5556.499915007628, 5656.066748986413, 5656.632318899258, 5672.064109421982, 5329.706866122362, 5360.061989151883, 5362.410134885159, 5366.302380690524, 5368.006918720011, 6211.9068213976825, 6222.937770622281, 6297.086922396227, 6299.275583307933, 6303.653299068549, 4421.137878113769, 4488.236999710057, 4528.5245049270015, 4539.364488971513, 4580.816767329303, 6539.2479864815205, 6565.277819439858, 6578.144841170665, 6579.374959378852, 6589.34106589342, 4687.296905464208, 4700.2244629635725, 4704.29435584665, 4707.152712511713, 4713.151632706145, 4770.809167633325, 4771.5228477152705, 4774.188387974053, 4774.427068751772, 4777.163996300634, 5207.843764687065, 5301.0723123453245, 5304.098303288451, 5304.522592966651, 5305.549028332558, 6354.459870911024, 6358.77744278948, 6359.9364006360265, 6361.681915904186, 6362.045693145972, 1653.6609994951007, 1676.857467115309, 1678.6223099802692, 1679.1268540358944, 1680.2942764039133, 1081.3944191252924, 1111.8832522585174, 1120.2326406411635, 1127.5433449452985, 1143.0398465289197, 6218.160548325918, 6219.564630475823, 6221.626702397871, 6223.315435302113, 6226.813195604139, 6631.071649969008, 6637.369449902251, 6637.7676781313385, 6641.169853768279, 6642.4021838034805, 6024.421170339182, 6206.317305096436, 6210.31686514483, 6216.564840461215, 6222.813315600449, 3619.3847046002243, 3649.379605467024, 3652.634736526379, 3655.0, 3656.341858465502, 6458.418742313214, 6521.934780652227, 6571.83570410734, 6681.365270299314, 6698.095757072831, 6664.866702666017, 6684.398404143651, 6684.699188536528, 6698.765543205965, 6699.899501507482, 6632.2372927114, 6647.135872336662, 6661.500387470959, 6662.267150613428, 6663.5335526513645, 6437.0, 6535.215774106948, 6562.868742625025, 6567.376099270562, 6570.080188773671, 6583.703733331916, 6593.472522198309, 6594.70323835419, 6598.208215014229, 6601.768938087144, 6744.291849355944, 6750.0, 6751.93248067513, 6756.0, 6757.526973111835, 4565.908681826411, 4670.883227919329, 4673.691366417409, 4681.204195286741, 4685.484596694293, 6657.0, 6661.367170119025, 6663.200415950117, 6665.133532640726, 6667.066610674607, 6448.806535803933, 6470.579412338183, 6498.3826536478955, 6498.707558159799, 6506.902396463962, 6342.778002769881, 6441.838954026, 6482.708278127401, 6487.22153341602, 6491.156149700698, 6384.840378990562, 6385.776497822554, 6389.648569328731, 6391.265004524539, 6393.328700486382, 6512.869742605216, 6515.0, 6515.2833188348295, 6521.152250207443, 6521.282658907421, 6244.438000579969, 6359.0, 6360.045993101072, 6372.330905254881, 6374.968125159345], "unit": "ms", "name": "prototype.html", "value": 4859.441567029818}, {"lowerIsBetter": false, "replicates": [719.1083057009307, 781.0627247303238, 785.4501848705908, 803.6865622407258, 813.9593020348983, 13543.458261669533, 14257.572272831805, 14262.144271343726, 14285.78571321431, 14299.92850035751, 13302.93348533256, 13369.866301336984, 13377.464901403931, 13386.59840204793, 13397.464101435955, 764.632976171438, 779.5439667794348, 913.6710784117712, 933.7385532051037, 933.9533023348839, 924.0205382294755, 925.3615005646117, 932.6502561539422, 932.9860052099224, 933.0576118120679, 816.8897198878135, 826.099551488877, 971.9368241064308, 980.308882238024, 982.0719420147963, 938.1462868789403, 952.7475219066953, 952.7951490429556, 954.3796532254021, 954.9092836180552, 706.2161001288565, 707.7098389660266, 708.0264636125328, 708.1396103733937, 708.5642329967085, 13059.281739504324, 13184.60446186616, 13192.934035329858, 13205.405756740969, 13212.339383030907, 10883.67348979532, 10995.560177592886, 11159.832602510969, 11249.550017999352, 11371.772564548746, 9726.367786093952, 9870.45712485813, 9993.150582200522, 10143.18854491639, 10178.18574514037, 3389.694927456541, 3431.3480438716565, 3432.63957284484, 3445.7243420526297, 3476.4437689969736, 51090.23364649496, 52369.73815130938, 52712.0, 52725.0, 52836.73581632067, 9494.05059494045, 9595.712128636153, 10053.648122315684, 10073.244506661884, 10131.442770647685, 21085.57828843445, 21139.26012589552, 21302.147914083263, 21309.893450532647, 21357.572848543252, 7869.331106855867, 7895.60521973899, 7934.2859142677435, 8049.0, 8124.7562573122905, 4090.570490098556, 4205.053862880888, 4210.5578914214175, 4222.071144348239, 4224.345226489899, 3485.5294535237927, 3511.9815253576176, 3514.7188224941942, 3516.40221162403, 3521.0669172669263, 493.5064935064935, 509.4905094905095, 512.5643203277259, 512.718005097195, 513.2634669249564, 580.8896309701144, 595.8480587450173, 621.9937800622026, 636.5257882877286, 651.6155468273741, 8586.95706521476, 8887.644494220158, 9123.543822808833, 9224.40041397307, 9365.203957663538, 507.9492050794891, 517.7126694684487, 540.1654443884208, 613.2579578709812, 663.9609011896367, 4030.5163380393956, 4259.701820872509, 4284.485861696617, 4302.827886884552, 4505.774711264489, 69389.65305173442, 70873.0, 71275.50535731224, 71423.64288178629, 71577.64211178912, 59468.0, 59792.40207597955, 60721.0, 61288.38711612916, 61306.38693613007, 53555.19667204995, 54875.0, 54896.0, 57172.14241786377, 57914.420855791745, 8244.0, 8358.331333493428, 8433.704820331259, 8496.32029437656, 8525.872111918203, 39641.801790992016, 39653.206935860544, 40761.79619101885, 40927.0, 40961.590384095776, 50822.23766643577, 53324.73337633287, 53482.732586338374, 53813.46186538084, 53973.73013134909, 8010.879836802569, 8110.513369197867, 8198.057223419382, 8358.45670031446, 8414.326853851555, 11067.944660276647, 11120.833187502356, 11187.384693841917, 11220.438978051228, 11278.774424511628, 10044.196464282986, 10054.29619926598, 10358.792824143324, 10559.894401055892, 10873.782524349626, 10718.73203169927, 10822.675319740105, 10828.24202305831, 10861.945690271497, 11169.162312826733, 9904.158146557624, 10136.0, 10323.0, 10764.192685548458, 10987.560497580007, 9500.0, 9793.65722199748, 9805.75485612837, 9929.702108936743, 9948.950255248677, 8933.687320943736, 8934.195922367018, 9088.591013404544, 9431.0, 9476.336656433983], "unit": "ms", "name": "yui.html", "value": 6447.870940740472}], "name": "dromaeo_css", "value": 7196.472442407694}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [106.0, 109.0, 106.0, 110.0, 109.0, 110.0, 100.0, 109.0, 111.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.9}, {"lowerIsBetter": true, "replicates": [147.0, 122.0, 145.0, 158.0, 127.0, 151.0, 152.0, 132.0, 158.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.3}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 159.0, 159.0, 158.0, 161.0, 159.0, 164.0, 159.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.1}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 70.0, 138.0, 78.0, 72.0, 72.0, 71.0, 147.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 86.2}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 133.0, 90.0, 90.0, 90.0, 129.0, 88.0, 89.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 102.3}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 110.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [144.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.0}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [55.0, 53.0, 54.0, 54.0, 54.0, 55.0, 55.0, 65.0, 52.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.0}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 65.0, 64.0, 64.0, 63.0, 63.0, 64.0, 64.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.8}, {"lowerIsBetter": true, "replicates": [77.0, 76.0, 87.0, 80.0, 77.0, 80.0, 79.0, 80.0, 78.0, 76.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.0}, {"lowerIsBetter": true, "replicates": [117.0, 106.0, 115.0, 111.0, 116.0, 116.0, 120.0, 111.0, 106.0, 111.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.9}, {"lowerIsBetter": true, "replicates": [128.0, 136.0, 135.0, 147.0, 148.0, 137.0, 137.0, 136.0, 135.0, 133.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.2}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 61.0, 61.0, 60.0, 60.0, 63.0, 62.0, 61.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.6}], "name": "kraken", "value": 1457.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.58337497917708], "unit": "score", "name": "Richards", "value": 211.91790600000007}, {"lowerIsBetter": false, "replicates": [170.590242238144], "unit": "score", "name": "DeltaBlue", "value": 387.583716}, {"lowerIsBetter": false, "replicates": [279.4076557697681], "unit": "score", "name": "Encrypt", "value": 952.6617989999997}, {"lowerIsBetter": false, "replicates": [5040.201005025126], "unit": "score", "name": "Decrypt", "value": 52.81158424725827}, {"lowerIsBetter": false, "replicates": [956.9377990430622], "unit": "score", "name": "RayTrace", "value": 773.2885049999996}, {"lowerIsBetter": false, "replicates": [554.016620498615], "unit": "score", "name": "Earley", "value": 1202.9657150000005}, {"lowerIsBetter": false, "replicates": [10934.782608695652], "unit": "score", "name": "Boyer", "value": 60.94890258449304}, {"lowerIsBetter": false, "replicates": [25871.79487179487], "unit": "score", "name": "RegExp", "value": 35.21151139742318}, {"lowerIsBetter": false, "replicates": [517.3305742369374], "unit": "score", "name": "Splay", "value": 157.52210300000002}, {"lowerIsBetter": false, "replicates": [4882.926829268293], "unit": "score", "name": "NavierStokes", "value": 303.91608391608383}], "name": "v8_7", "value": 22981.468206563248}]} 08:06:45 INFO - RETURN: dromaeo_css: 7196.47 08:06:45 INFO - RETURN: kraken: 1457.20 08:06:45 INFO - RETURN: v8_7: 22981.47 08:06:45 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "22981.47"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1457.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7196.47"}}} 08:06:45 INFO - Return code: 0 08:06:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:06:45 INFO - # TBPL SUCCESS # 08:06:45 INFO - Running post-action listener: _resource_record_post_action 08:06:45 INFO - Running post-run listener: _resource_record_post_run 08:06:46 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 348160; Write bytes: 508309504; Read time: 488; Write time: 546760 08:06:46 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 4096; Write bytes: 245497856; Read time: 156; Write time: 376512 08:06:46 INFO - run-tests - Wall time: 1207s; CPU: 12.0%; Read bytes: 344064; Write bytes: 262811648; Read time: 332; Write time: 170248 08:06:46 INFO - Running post-run listener: _upload_blobber_files 08:06:46 INFO - Blob upload gear active. 08:06:46 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:06:46 INFO - Copying logs to upload dir... 08:06:46 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1252.252873 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2015-12-28 08:06:46.130428) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-28 08:06:46.134080) ========= 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=d333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010138 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451129028/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-28 08:06:46.185658) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 08:06:46.186048) ========= 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=d333f129428bd91e5d9d687c000001bd-1451316468.127538-1177731325 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004526 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 08:06:46.235334) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-28 08:06:46.235698) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-28 08:06:46.236041) ========= ========= Total master_lag: 0.19 =========