builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1452771149.02 results: success (0) buildid: 20160114020735 builduid: b57ce9f3916f48de9ba69ed924c70748 revision: 8b07fd4f3ca5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.020575) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.021047) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.021374) ========= 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=52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006293 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.047555) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.048042) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.074920) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.075406) ========= 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=52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.038923 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.126043) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.126366) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.126762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.127082) ========= 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=52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-14 03:32:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-01-14 03:32:29 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.173554 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.313546) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.313904) ========= 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=52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.057248 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:29.380960) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-14 03:32:29.381354) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8b07fd4f3ca5 --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 8b07fd4f3ca5 --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=52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-14 03:32:29,492 Setting DEBUG logging. 2016-01-14 03:32:29,492 attempt 1/10 2016-01-14 03:32:29,492 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/8b07fd4f3ca5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-14 03:32:30,227 unpacking tar archive at: mozilla-beta-8b07fd4f3ca5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.044935 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-14 03:32:30.439733) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:30.440095) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:30.450948) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:30.451384) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 03:32:30.452106) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-01-14 03:32:30.452588) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:32:30 INFO - MultiFileLogger online at 20160114 03:32:30 in /builds/slave/test-pgo 03:32:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 03:32:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 03:32:30 INFO - {'append_to_log': False, 03:32:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 03:32:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:32:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 03:32:30 INFO - 'branch': 'Mozilla-Beta', 03:32:30 INFO - 'buildbot_json_path': 'buildprops.json', 03:32:30 INFO - 'config_files': ('talos/linux_config.py',), 03:32:30 INFO - 'default_actions': ('clobber', 03:32:30 INFO - 'read-buildbot-config', 03:32:30 INFO - 'download-and-extract', 03:32:30 INFO - 'populate-webroot', 03:32:30 INFO - 'create-virtualenv', 03:32:30 INFO - 'install', 03:32:30 INFO - 'run-tests'), 03:32:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:32:30 INFO - 'download_minidump_stackwalk': True, 03:32:30 INFO - 'download_symbols': 'ondemand', 03:32:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:32:30 INFO - 'tooltool.py': '/tools/tooltool.py', 03:32:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:32:30 INFO - '/tools/misc-python/virtualenv.py')}, 03:32:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:32:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:32:30 INFO - 'installer_path': 'installer.exe', 03:32:30 INFO - 'log_level': 'info', 03:32:30 INFO - 'log_name': 'talos', 03:32:30 INFO - 'log_to_console': True, 03:32:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:32:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:32:30 INFO - 'opt_config_files': (), 03:32:30 INFO - 'pip_index': False, 03:32:30 INFO - 'sps_profile': False, 03:32:30 INFO - 'sps_profile_interval': 0, 03:32:30 INFO - 'suite': 'dromaeojs', 03:32:30 INFO - 'system_bits': '32', 03:32:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:32:30 INFO - 'title': 'talos-linux64-ix-056', 03:32:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:32:30 INFO - 'use_talos_json': True, 03:32:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 03:32:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:32:30 INFO - 'work_dir': 'build'} 03:32:30 INFO - ##### 03:32:30 INFO - ##### Running clobber step. 03:32:30 INFO - ##### 03:32:30 INFO - Running pre-action listener: _resource_record_pre_action 03:32:30 INFO - Running main action method: clobber 03:32:30 INFO - rmtree: /builds/slave/test-pgo/build 03:32:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 03:32:31 INFO - Running post-action listener: _resource_record_post_action 03:32:31 INFO - ##### 03:32:31 INFO - ##### Running read-buildbot-config step. 03:32:31 INFO - ##### 03:32:31 INFO - Running pre-action listener: _resource_record_pre_action 03:32:31 INFO - Running main action method: read_buildbot_config 03:32:31 INFO - Using buildbot properties: 03:32:31 INFO - { 03:32:31 INFO - "properties": { 03:32:31 INFO - "buildnumber": 76, 03:32:31 INFO - "product": "firefox", 03:32:31 INFO - "script_repo_revision": "production", 03:32:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 03:32:31 INFO - "repository": "", 03:32:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 03:32:31 INFO - "buildid": "20160114020735", 03:32:31 INFO - "slavename": "talos-linux64-ix-056", 03:32:31 INFO - "pgo_build": "True", 03:32:31 INFO - "basedir": "/builds/slave/test-pgo", 03:32:31 INFO - "project": "", 03:32:31 INFO - "platform": "ubuntu64_hw", 03:32:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:32:31 INFO - "slavebuilddir": "test", 03:32:31 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 03:32:31 INFO - "branch": "mozilla-beta", 03:32:31 INFO - "repo_path": "releases/mozilla-beta", 03:32:31 INFO - "stage_platform": "linux64-pgo", 03:32:31 INFO - "builduid": "b57ce9f3916f48de9ba69ed924c70748", 03:32:31 INFO - "revision": "8b07fd4f3ca5" 03:32:31 INFO - }, 03:32:31 INFO - "sourcestamp": { 03:32:31 INFO - "repository": "", 03:32:31 INFO - "hasPatch": false, 03:32:31 INFO - "project": "", 03:32:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 03:32:31 INFO - "changes": [ 03:32:31 INFO - { 03:32:31 INFO - "category": null, 03:32:31 INFO - "files": [ 03:32:31 INFO - { 03:32:31 INFO - "url": null, 03:32:31 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/firefox-44.0.en-US.linux-x86_64.tar.bz2" 03:32:31 INFO - } 03:32:31 INFO - ], 03:32:31 INFO - "repository": "", 03:32:31 INFO - "rev": "8b07fd4f3ca5", 03:32:31 INFO - "who": "sendchange", 03:32:31 INFO - "when": 1452771100, 03:32:31 INFO - "number": 7459502, 03:32:31 INFO - "comments": "Bug 1238010 - Turn off ClosingService. r=mcmanus, a=ritu", 03:32:31 INFO - "project": "", 03:32:31 INFO - "at": "Thu 14 Jan 2016 03:31:40", 03:32:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 03:32:31 INFO - "revlink": "", 03:32:31 INFO - "properties": [ 03:32:31 INFO - [ 03:32:31 INFO - "buildid", 03:32:31 INFO - "20160114020735", 03:32:31 INFO - "Change" 03:32:31 INFO - ], 03:32:31 INFO - [ 03:32:31 INFO - "builduid", 03:32:31 INFO - "b57ce9f3916f48de9ba69ed924c70748", 03:32:31 INFO - "Change" 03:32:31 INFO - ], 03:32:31 INFO - [ 03:32:31 INFO - "pgo_build", 03:32:31 INFO - "True", 03:32:31 INFO - "Change" 03:32:31 INFO - ] 03:32:31 INFO - ], 03:32:31 INFO - "revision": "8b07fd4f3ca5" 03:32:31 INFO - } 03:32:31 INFO - ], 03:32:31 INFO - "revision": "8b07fd4f3ca5" 03:32:31 INFO - } 03:32:31 INFO - } 03:32:31 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/firefox-44.0.en-US.linux-x86_64.tar.bz2. 03:32:31 INFO - Running post-action listener: _resource_record_post_action 03:32:31 INFO - ##### 03:32:31 INFO - ##### Running download-and-extract step. 03:32:31 INFO - ##### 03:32:31 INFO - Running pre-action listener: _resource_record_pre_action 03:32:31 INFO - Running main action method: download_and_extract 03:32:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 03:32:31 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')]} 03:32:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/test_packages.json 03:32:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 03:32:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 03:32:32 INFO - Downloaded 1270 bytes. 03:32:32 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 03:32:32 INFO - Using the following test package requirements: 03:32:32 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 03:32:32 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:32:32 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 03:32:32 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:32:32 INFO - u'jsshell-linux-x86_64.zip'], 03:32:32 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:32:32 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 03:32:32 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 03:32:32 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:32:32 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 03:32:32 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:32:32 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 03:32:32 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:32:32 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 03:32:32 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 03:32:32 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:32:32 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 03:32:32 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:32:32 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')]} 03:32:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/firefox-44.0.en-US.linux-x86_64.common.tests.zip 03:32:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/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 03:32:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/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 03:32:35 INFO - Downloaded 22572726 bytes. 03:32:35 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 03:32:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 03:32:35 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 03:32:36 INFO - Return code: 0 03:32:36 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 03:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:36 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/firefox-44.0.en-US.linux-x86_64.common.tests.zip 03:32:36 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/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 03:32:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/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 03:32:37 INFO - Downloaded 22572726 bytes. 03:32:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 03:32:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 03:32:37 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:32:39 INFO - Return code: 0 03:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 03:32:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/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 03:32:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/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 03:32:42 INFO - Downloaded 11131970 bytes. 03:32:42 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 03:32:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 03:32:42 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 03:32:42 INFO - Return code: 0 03:32:42 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')]} 03:32:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/firefox-44.0.en-US.linux-x86_64.tar.bz2 03:32:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/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 03:32:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/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 03:32:45 INFO - Downloaded 56632794 bytes. 03:32:45 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/firefox-44.0.en-US.linux-x86_64.tar.bz2 03:32:45 INFO - mkdir: /builds/slave/test-pgo/properties 03:32:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 03:32:45 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 03:32:45 INFO - Contents: 03:32:45 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/firefox-44.0.en-US.linux-x86_64.tar.bz2 03:32:45 INFO - Running post-action listener: _resource_record_post_action 03:32:45 INFO - Running post-action listener: set_extra_try_arguments 03:32:45 INFO - ##### 03:32:45 INFO - ##### Running populate-webroot step. 03:32:45 INFO - ##### 03:32:45 INFO - Running pre-action listener: _resource_record_pre_action 03:32:45 INFO - Running main action method: populate_webroot 03:32:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:32:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:32:45 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 03:32:45 INFO - '../fennec_ids.txt'], 03:32:45 INFO - 'tests': ['tcheck2']}, 03:32:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:32:45 INFO - '--tppagecycles', 03:32:45 INFO - '7'], 03:32:45 INFO - 'tests': ['tsvgm']}}, 03:32:45 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:32:45 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:32:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:32:45 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:32:45 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:32:46 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:32:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:32:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:46 INFO - 'talos_options': ['--e10s'], 03:32:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:32:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:46 INFO - 'tests': ['damp', 'tps']}, 03:32:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:46 INFO - 'talos_options': ['--e10s'], 03:32:46 INFO - 'tests': ['damp', 'tps']}, 03:32:46 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:32:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:32:46 INFO - 'other': {'tests': ['a11yr', 03:32:46 INFO - 'ts_paint', 03:32:46 INFO - 'tpaint', 03:32:46 INFO - 'sessionrestore', 03:32:46 INFO - 'sessionrestore_no_auto_restore']}, 03:32:46 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:32:46 INFO - 'tests': ['a11yr', 03:32:46 INFO - 'ts_paint', 03:32:46 INFO - 'tpaint', 03:32:46 INFO - 'sessionrestore', 03:32:46 INFO - 'sessionrestore_no_auto_restore']}, 03:32:46 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:32:46 INFO - 'tests': ['a11yr', 03:32:46 INFO - 'ts_paint', 03:32:46 INFO - 'tpaint', 03:32:46 INFO - 'sessionrestore', 03:32:46 INFO - 'sessionrestore_no_auto_restore']}, 03:32:46 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:32:46 INFO - 'tests': ['a11yr', 03:32:46 INFO - 'ts_paint', 03:32:46 INFO - 'tpaint', 03:32:46 INFO - 'sessionrestore', 03:32:46 INFO - 'sessionrestore_no_auto_restore']}, 03:32:46 INFO - 'other_l64': {'tests': ['a11yr', 03:32:46 INFO - 'ts_paint', 03:32:46 INFO - 'tpaint', 03:32:46 INFO - 'sessionrestore', 03:32:46 INFO - 'sessionrestore_no_auto_restore']}, 03:32:46 INFO - 'other_nol64': {'tests': ['a11yr', 03:32:46 INFO - 'ts_paint', 03:32:46 INFO - 'tpaint', 03:32:46 INFO - 'sessionrestore', 03:32:46 INFO - 'sessionrestore_no_auto_restore']}, 03:32:46 INFO - 'svgr': {'tests': ['tsvgx', 03:32:46 INFO - 'tsvgr_opacity', 03:32:46 INFO - 'tart', 03:32:46 INFO - 'tscrollx', 03:32:46 INFO - 'cart']}, 03:32:46 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:32:46 INFO - 'tests': ['tsvgx', 03:32:46 INFO - 'tsvgr_opacity', 03:32:46 INFO - 'tart', 03:32:46 INFO - 'tscrollx', 03:32:46 INFO - 'cart']}, 03:32:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:46 INFO - 'tests': ['tp5o']}, 03:32:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:46 INFO - 'talos_options': ['--e10s'], 03:32:46 INFO - 'tests': ['tp5o']}, 03:32:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:46 INFO - 'talos_options': ['--xperf_path', 03:32:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:32:46 INFO - 'tests': ['tp5n']}, 03:32:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:46 INFO - 'talos_options': ['--e10s', 03:32:46 INFO - '--xperf_path', 03:32:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:32:46 INFO - 'tests': ['tp5n']}}, 03:32:46 INFO - 'talos.zip': {'path': '', 03:32:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:32:46 INFO - Running post-action listener: _resource_record_post_action 03:32:46 INFO - ##### 03:32:46 INFO - ##### Running create-virtualenv step. 03:32:46 INFO - ##### 03:32:46 INFO - Running pre-action listener: _resource_record_pre_action 03:32:46 INFO - Running main action method: create_virtualenv 03:32:46 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 03:32:46 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 03:32:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 03:32:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:32:46 INFO - Using real prefix '/usr' 03:32:46 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 03:32:46 INFO - Installing distribute.............................................................................................................................................................................................done. 03:32:48 INFO - Installing pip.................done. 03:32:48 INFO - Return code: 0 03:32:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 03:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7ffe0bc11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe51600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee0350>, '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 0xee1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee16e0>, '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': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', '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 03:32:48 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 03:32:48 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 03:32:48 INFO - Using env: {'DISPLAY': ':0', 03:32:48 INFO - 'HOME': '/home/cltbld', 03:32:48 INFO - 'LANG': 'en_US.UTF-8', 03:32:48 INFO - 'LANGUAGE': 'en_US:en', 03:32:48 INFO - 'LOGNAME': 'cltbld', 03:32:48 INFO - 'MAIL': '/var/mail/cltbld', 03:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:48 INFO - 'MOZ_NO_REMOTE': '1', 03:32:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:48 INFO - 'NO_EM_RESTART': '1', 03:32:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:32:48 INFO - 'PWD': '/builds/slave/test-pgo', 03:32:48 INFO - 'SHELL': '/bin/bash', 03:32:48 INFO - 'SHLVL': '1', 03:32:48 INFO - 'TERM': 'linux', 03:32:48 INFO - 'TMOUT': '86400', 03:32:48 INFO - 'USER': 'cltbld', 03:32:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', 03:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:48 INFO - '_': '/tools/buildbot/bin/python'} 03:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:48 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 03:32:48 INFO - Cleaning up... 03:32:48 INFO - Return code: 0 03:32:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 03:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7ffe0bc11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe51600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee0350>, '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 0xee1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee16e0>, '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': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', '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 03:32:48 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 03:32:48 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 03:32:48 INFO - Using env: {'DISPLAY': ':0', 03:32:48 INFO - 'HOME': '/home/cltbld', 03:32:48 INFO - 'LANG': 'en_US.UTF-8', 03:32:48 INFO - 'LANGUAGE': 'en_US:en', 03:32:48 INFO - 'LOGNAME': 'cltbld', 03:32:48 INFO - 'MAIL': '/var/mail/cltbld', 03:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:48 INFO - 'MOZ_NO_REMOTE': '1', 03:32:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:48 INFO - 'NO_EM_RESTART': '1', 03:32:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:32:48 INFO - 'PWD': '/builds/slave/test-pgo', 03:32:48 INFO - 'SHELL': '/bin/bash', 03:32:48 INFO - 'SHLVL': '1', 03:32:48 INFO - 'TERM': 'linux', 03:32:48 INFO - 'TMOUT': '86400', 03:32:48 INFO - 'USER': 'cltbld', 03:32:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', 03:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:48 INFO - '_': '/tools/buildbot/bin/python'} 03:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:48 INFO - Downloading/unpacking psutil>=0.7.1 03:32:48 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 03:32:48 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 03:32:48 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 03:32:48 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 03:32:48 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 03:32:48 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 03:32:50 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 03:32:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 03:32:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 03:32:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:32:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:32:51 INFO - Installing collected packages: psutil 03:32:51 INFO - Running setup.py install for psutil 03:32:51 INFO - building 'psutil._psutil_linux' extension 03:32:51 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 03:32:51 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 03:32:51 INFO - building 'psutil._psutil_posix' extension 03:32:51 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 03:32:51 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 03:32:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:32:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:32:51 INFO - Successfully installed psutil 03:32:51 INFO - Cleaning up... 03:32:51 INFO - Return code: 0 03:32:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 03:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7ffe0bc11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe51600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee0350>, '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 0xee1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee16e0>, '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': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', '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 03:32:51 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 03:32:51 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 03:32:51 INFO - Using env: {'DISPLAY': ':0', 03:32:51 INFO - 'HOME': '/home/cltbld', 03:32:51 INFO - 'LANG': 'en_US.UTF-8', 03:32:51 INFO - 'LANGUAGE': 'en_US:en', 03:32:51 INFO - 'LOGNAME': 'cltbld', 03:32:51 INFO - 'MAIL': '/var/mail/cltbld', 03:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:51 INFO - 'MOZ_NO_REMOTE': '1', 03:32:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:51 INFO - 'NO_EM_RESTART': '1', 03:32:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:32:51 INFO - 'PWD': '/builds/slave/test-pgo', 03:32:51 INFO - 'SHELL': '/bin/bash', 03:32:51 INFO - 'SHLVL': '1', 03:32:51 INFO - 'TERM': 'linux', 03:32:51 INFO - 'TMOUT': '86400', 03:32:51 INFO - 'USER': 'cltbld', 03:32:51 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', 03:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:51 INFO - '_': '/tools/buildbot/bin/python'} 03:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:32:51 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 03:32:51 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 03:32:51 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 03:32:51 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 03:32:51 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 03:32:51 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 03:32:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:32:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 03:32:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:32:53 INFO - Installing collected packages: mozsystemmonitor 03:32:53 INFO - Running setup.py install for mozsystemmonitor 03:32:53 INFO - Successfully installed mozsystemmonitor 03:32:53 INFO - Cleaning up... 03:32:53 INFO - Return code: 0 03:32:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 03:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:53 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 0x7ffe0bc11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe51600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee0350>, '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 0xee1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee16e0>, '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': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', '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 03:32:53 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 03:32:53 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 03:32:53 INFO - Using env: {'DISPLAY': ':0', 03:32:53 INFO - 'HOME': '/home/cltbld', 03:32:53 INFO - 'LANG': 'en_US.UTF-8', 03:32:53 INFO - 'LANGUAGE': 'en_US:en', 03:32:53 INFO - 'LOGNAME': 'cltbld', 03:32:53 INFO - 'MAIL': '/var/mail/cltbld', 03:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:53 INFO - 'MOZ_NO_REMOTE': '1', 03:32:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:53 INFO - 'NO_EM_RESTART': '1', 03:32:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:32:53 INFO - 'PWD': '/builds/slave/test-pgo', 03:32:53 INFO - 'SHELL': '/bin/bash', 03:32:53 INFO - 'SHLVL': '1', 03:32:53 INFO - 'TERM': 'linux', 03:32:53 INFO - 'TMOUT': '86400', 03:32:53 INFO - 'USER': 'cltbld', 03:32:53 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', 03:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:53 INFO - '_': '/tools/buildbot/bin/python'} 03:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:53 INFO - Downloading/unpacking blobuploader==1.2.4 03:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:55 INFO - Downloading blobuploader-1.2.4.tar.gz 03:32:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 03:32:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:32:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:32:55 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 03:32:55 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 03:32:55 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 03:32:55 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 03:32:55 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 03:32:55 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 03:32:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 03:32:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 03:32:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:56 INFO - Downloading docopt-0.6.1.tar.gz 03:32:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 03:32:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 03:32:56 INFO - Installing collected packages: blobuploader, requests, docopt 03:32:56 INFO - Running setup.py install for blobuploader 03:32:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:32:56 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 03:32:56 INFO - Running setup.py install for requests 03:32:57 INFO - Running setup.py install for docopt 03:32:57 INFO - Successfully installed blobuploader requests docopt 03:32:57 INFO - Cleaning up... 03:32:57 INFO - Return code: 0 03:32:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:32: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')]} 03:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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')]} 03:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:57 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 0x7ffe0bc11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe51600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee0350>, '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 0xee1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee16e0>, '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': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', '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 03:32:57 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 03:32:57 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 03:32:57 INFO - Using env: {'DISPLAY': ':0', 03:32:57 INFO - 'HOME': '/home/cltbld', 03:32:57 INFO - 'LANG': 'en_US.UTF-8', 03:32:57 INFO - 'LANGUAGE': 'en_US:en', 03:32:57 INFO - 'LOGNAME': 'cltbld', 03:32:57 INFO - 'MAIL': '/var/mail/cltbld', 03:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:57 INFO - 'MOZ_NO_REMOTE': '1', 03:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:57 INFO - 'NO_EM_RESTART': '1', 03:32:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:32:57 INFO - 'PWD': '/builds/slave/test-pgo', 03:32:57 INFO - 'SHELL': '/bin/bash', 03:32:57 INFO - 'SHLVL': '1', 03:32:57 INFO - 'TERM': 'linux', 03:32:57 INFO - 'TMOUT': '86400', 03:32:57 INFO - 'USER': 'cltbld', 03:32:57 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', 03:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:57 INFO - '_': '/tools/buildbot/bin/python'} 03:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:32:58 INFO - Running setup.py (path:/tmp/pip-Oq5_27-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:32:58 INFO - Running setup.py (path:/tmp/pip-rfdIEo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:32:58 INFO - Running setup.py (path:/tmp/pip-yl2_dm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:32:58 INFO - Running setup.py (path:/tmp/pip-uWezTR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:32:58 INFO - Running setup.py (path:/tmp/pip-ArsInj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:32:58 INFO - Running setup.py (path:/tmp/pip-3AUsfC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:32:58 INFO - Running setup.py (path:/tmp/pip-bVgsJR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:32:58 INFO - Running setup.py (path:/tmp/pip-wBIvvq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:32:58 INFO - Running setup.py (path:/tmp/pip-p1nm4G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:32:58 INFO - Running setup.py (path:/tmp/pip-LItMzi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:32:58 INFO - Running setup.py (path:/tmp/pip-4RLLTV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:32:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:32:58 INFO - Running setup.py (path:/tmp/pip-svCHId-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:32:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:32:59 INFO - Running setup.py (path:/tmp/pip-A7DF7O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:32:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:32:59 INFO - Running setup.py (path:/tmp/pip-nU19ey-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:32:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:32:59 INFO - Running setup.py (path:/tmp/pip-VlPuMc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:32:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:32:59 INFO - Running setup.py (path:/tmp/pip-NkhE0F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:32:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:32:59 INFO - Running setup.py (path:/tmp/pip-GEt3uM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:32:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:32:59 INFO - Running setup.py install for manifestparser 03:32:59 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 03:32:59 INFO - Running setup.py install for mozcrash 03:32:59 INFO - Running setup.py install for mozdebug 03:32:59 INFO - Running setup.py install for mozdevice 03:32:59 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 03:32:59 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 03:32:59 INFO - Running setup.py install for mozfile 03:32:59 INFO - Running setup.py install for mozhttpd 03:32:59 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 03:32:59 INFO - Running setup.py install for mozinfo 03:33:00 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Running setup.py install for mozInstall 03:33:00 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Running setup.py install for mozleak 03:33:00 INFO - Running setup.py install for mozlog 03:33:00 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Running setup.py install for moznetwork 03:33:00 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Running setup.py install for mozprocess 03:33:00 INFO - Running setup.py install for mozprofile 03:33:00 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Running setup.py install for mozrunner 03:33:00 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 03:33:00 INFO - Running setup.py install for mozscreenshot 03:33:00 INFO - Running setup.py install for moztest 03:33:01 INFO - Running setup.py install for mozversion 03:33:01 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 03:33:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:33:01 INFO - Cleaning up... 03:33:01 INFO - Return code: 0 03:33:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:33:01 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')]} 03:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:01 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')]} 03:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:01 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 0x7ffe0bc11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe51600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee0350>, '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 0xee1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee16e0>, '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': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', '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 03:33:01 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 03:33:01 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 03:33:01 INFO - Using env: {'DISPLAY': ':0', 03:33:01 INFO - 'HOME': '/home/cltbld', 03:33:01 INFO - 'LANG': 'en_US.UTF-8', 03:33:01 INFO - 'LANGUAGE': 'en_US:en', 03:33:01 INFO - 'LOGNAME': 'cltbld', 03:33:01 INFO - 'MAIL': '/var/mail/cltbld', 03:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:01 INFO - 'MOZ_NO_REMOTE': '1', 03:33:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:01 INFO - 'NO_EM_RESTART': '1', 03:33:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:33:01 INFO - 'PWD': '/builds/slave/test-pgo', 03:33:01 INFO - 'SHELL': '/bin/bash', 03:33:01 INFO - 'SHLVL': '1', 03:33:01 INFO - 'TERM': 'linux', 03:33:01 INFO - 'TMOUT': '86400', 03:33:01 INFO - 'USER': 'cltbld', 03:33:01 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', 03:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:01 INFO - '_': '/tools/buildbot/bin/python'} 03:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:33:01 INFO - Running setup.py (path:/tmp/pip-uVPE0Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:33:01 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)) 03:33:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:33:01 INFO - Running setup.py (path:/tmp/pip-V7vpj1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:33:01 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)) 03:33:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:33:01 INFO - Running setup.py (path:/tmp/pip-rJ37ua-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:33:01 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)) 03:33:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:33:01 INFO - Running setup.py (path:/tmp/pip-mNBfGk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:33:01 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)) 03:33:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:33:01 INFO - Running setup.py (path:/tmp/pip-NdJyIE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:33:01 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)) 03:33:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:33:01 INFO - Running setup.py (path:/tmp/pip-wP7c4C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:33:01 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)) 03:33:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:33:01 INFO - Running setup.py (path:/tmp/pip-am5fqR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:33:01 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)) 03:33:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:33:01 INFO - Running setup.py (path:/tmp/pip-neGInk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:33:01 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)) 03:33:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:33:01 INFO - Running setup.py (path:/tmp/pip-OtMsOl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:33:02 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)) 03:33:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:33:02 INFO - Running setup.py (path:/tmp/pip-Oz5qgX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:33:02 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)) 03:33:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:33:02 INFO - Running setup.py (path:/tmp/pip-6aj_7H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:33:02 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)) 03:33:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:33:02 INFO - Running setup.py (path:/tmp/pip-OgX94_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:33:02 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)) 03:33:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:33:02 INFO - Running setup.py (path:/tmp/pip-_Bv_63-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:33:02 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)) 03:33:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:33:02 INFO - Running setup.py (path:/tmp/pip-bm6Vf7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:33:02 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)) 03:33:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:33:02 INFO - Running setup.py (path:/tmp/pip-bfpZds-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:33:02 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)) 03:33:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:33:02 INFO - Running setup.py (path:/tmp/pip-RdsbdV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:33:02 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)) 03:33:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:33:02 INFO - Running setup.py (path:/tmp/pip-9q02GH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:33:02 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)) 03:33:02 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)) 03:33:02 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)) 03:33:02 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)) 03:33:02 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)) 03:33:02 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)) 03:33:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 03:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:04 INFO - Downloading blessings-1.5.1.tar.gz 03:33:04 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 03:33:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 03:33:04 INFO - Installing collected packages: blessings 03:33:04 INFO - Running setup.py install for blessings 03:33:04 INFO - Successfully installed blessings 03:33:04 INFO - Cleaning up... 03:33:04 INFO - Return code: 0 03:33:04 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 03:33:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:33:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:33:04 INFO - Reading from file tmpfile_stdout 03:33:04 INFO - Current package versions: 03:33:04 INFO - argparse == 1.2.1 03:33:04 INFO - blessings == 1.5.1 03:33:04 INFO - blobuploader == 1.2.4 03:33:04 INFO - docopt == 0.6.1 03:33:04 INFO - manifestparser == 1.1 03:33:04 INFO - mozInstall == 1.12 03:33:04 INFO - mozcrash == 0.16 03:33:04 INFO - mozdebug == 0.1 03:33:04 INFO - mozdevice == 0.46 03:33:04 INFO - mozfile == 1.2 03:33:04 INFO - mozhttpd == 0.7 03:33:04 INFO - mozinfo == 0.8 03:33:04 INFO - mozleak == 0.1 03:33:04 INFO - mozlog == 3.0 03:33:04 INFO - moznetwork == 0.27 03:33:04 INFO - mozprocess == 0.22 03:33:04 INFO - mozprofile == 0.27 03:33:04 INFO - mozrunner == 6.11 03:33:04 INFO - mozscreenshot == 0.1 03:33:04 INFO - mozsystemmonitor == 0.0 03:33:04 INFO - moztest == 0.7 03:33:04 INFO - mozversion == 1.4 03:33:04 INFO - psutil == 3.1.1 03:33:04 INFO - requests == 1.2.3 03:33:04 INFO - wsgiref == 0.1.2 03:33:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:33: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')]} 03:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33: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')]} 03:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33: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', '-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 0x7ffe0bc11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe51600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee0350>, '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 0xee1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee16e0>, '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': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', '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 03:33:04 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 03:33:04 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 03:33:04 INFO - Using env: {'DISPLAY': ':0', 03:33:04 INFO - 'HOME': '/home/cltbld', 03:33:04 INFO - 'LANG': 'en_US.UTF-8', 03:33:04 INFO - 'LANGUAGE': 'en_US:en', 03:33:04 INFO - 'LOGNAME': 'cltbld', 03:33:04 INFO - 'MAIL': '/var/mail/cltbld', 03:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:04 INFO - 'MOZ_NO_REMOTE': '1', 03:33:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:04 INFO - 'NO_EM_RESTART': '1', 03:33:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:33:04 INFO - 'PWD': '/builds/slave/test-pgo', 03:33:04 INFO - 'SHELL': '/bin/bash', 03:33:04 INFO - 'SHLVL': '1', 03:33:04 INFO - 'TERM': 'linux', 03:33:04 INFO - 'TMOUT': '86400', 03:33:04 INFO - 'USER': 'cltbld', 03:33:04 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', 03:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:04 INFO - '_': '/tools/buildbot/bin/python'} 03:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:05 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)) 03:33:05 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)) 03:33:05 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)) 03:33:05 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)) 03:33:05 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)) 03:33:05 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)) 03:33:05 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)) 03:33:05 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)) 03:33:05 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)) 03:33:05 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)) 03:33:05 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)) 03:33:05 INFO - Cleaning up... 03:33:05 INFO - Return code: 0 03:33:05 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 03:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:05 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 0x7ffe0bc11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe51600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee0350>, '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 0xee1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee16e0>, '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': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', '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 03:33:05 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 03:33:05 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 03:33:05 INFO - Using env: {'DISPLAY': ':0', 03:33:05 INFO - 'HOME': '/home/cltbld', 03:33:05 INFO - 'LANG': 'en_US.UTF-8', 03:33:05 INFO - 'LANGUAGE': 'en_US:en', 03:33:05 INFO - 'LOGNAME': 'cltbld', 03:33:05 INFO - 'MAIL': '/var/mail/cltbld', 03:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:05 INFO - 'MOZ_NO_REMOTE': '1', 03:33:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:05 INFO - 'NO_EM_RESTART': '1', 03:33:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:33:05 INFO - 'PWD': '/builds/slave/test-pgo', 03:33:05 INFO - 'SHELL': '/bin/bash', 03:33:05 INFO - 'SHLVL': '1', 03:33:05 INFO - 'TERM': 'linux', 03:33:05 INFO - 'TMOUT': '86400', 03:33:05 INFO - 'USER': 'cltbld', 03:33:05 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', 03:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:05 INFO - '_': '/tools/buildbot/bin/python'} 03:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:05 INFO - Downloading/unpacking jsonschema 03:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:33:06 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 03:33:06 INFO - Downloading/unpacking functools32 (from jsonschema) 03:33: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 03:33: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 03:33: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 03:33: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 03:33: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 03:33: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 03:33:07 INFO - Downloading functools32-3.2.3-2.tar.gz 03:33:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 03:33:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 03:33:07 INFO - warning: no files found matching '*.txt' 03:33:07 INFO - no previously-included directories found matching 'build' 03:33:07 INFO - no previously-included directories found matching 'dist' 03:33:07 INFO - no previously-included directories found matching '.git*' 03:33:07 INFO - Installing collected packages: jsonschema, functools32 03:33:07 INFO - Running setup.py install for functools32 03:33:07 INFO - warning: no files found matching '*.txt' 03:33:07 INFO - no previously-included directories found matching 'build' 03:33:07 INFO - no previously-included directories found matching 'dist' 03:33:07 INFO - no previously-included directories found matching '.git*' 03:33:07 INFO - Successfully installed jsonschema functools32 03:33:07 INFO - Cleaning up... 03:33:07 INFO - Return code: 0 03:33:07 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 03:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7ffe0bc11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe51600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee0350>, '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 0xee1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee16e0>, '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': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', '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 03:33:07 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 03:33:07 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 03:33:07 INFO - Using env: {'DISPLAY': ':0', 03:33:07 INFO - 'HOME': '/home/cltbld', 03:33:07 INFO - 'LANG': 'en_US.UTF-8', 03:33:07 INFO - 'LANGUAGE': 'en_US:en', 03:33:07 INFO - 'LOGNAME': 'cltbld', 03:33:07 INFO - 'MAIL': '/var/mail/cltbld', 03:33:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:07 INFO - 'MOZ_NO_REMOTE': '1', 03:33:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:07 INFO - 'NO_EM_RESTART': '1', 03:33:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:33:07 INFO - 'PWD': '/builds/slave/test-pgo', 03:33:07 INFO - 'SHELL': '/bin/bash', 03:33:07 INFO - 'SHLVL': '1', 03:33:07 INFO - 'TERM': 'linux', 03:33:07 INFO - 'TMOUT': '86400', 03:33:07 INFO - 'USER': 'cltbld', 03:33:07 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', 03:33:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:07 INFO - '_': '/tools/buildbot/bin/python'} 03:33:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:07 INFO - Downloading/unpacking flake8 03:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:33:09 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 03:33:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:33:09 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 03:33:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:09 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 03:33:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 03:33:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 03:33:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:33:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:33:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:33:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:33:09 INFO - no previously-included directories found matching 'docs/_build' 03:33:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:33:10 INFO - Running setup.py install for pep8 03:33:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:33:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:33:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:33:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:33:10 INFO - no previously-included directories found matching 'docs/_build' 03:33:10 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 03:33:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:33:10 INFO - Cleaning up... 03:33:10 INFO - Return code: 0 03:33:10 INFO - Running post-action listener: _resource_record_post_action 03:33:10 INFO - Running post-action listener: _start_resource_monitoring 03:33:10 INFO - Starting resource monitoring. 03:33:10 INFO - ##### 03:33:10 INFO - ##### Running install step. 03:33:10 INFO - ##### 03:33:10 INFO - Running pre-action listener: _resource_record_pre_action 03:33:10 INFO - Running main action method: install 03:33:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:33:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:33:10 INFO - Reading from file tmpfile_stdout 03:33:10 INFO - Detecting whether we're running mozinstall >=1.0... 03:33:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 03:33:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 03:33:10 INFO - Reading from file tmpfile_stdout 03:33:10 INFO - Output received: 03:33:10 INFO - Usage: mozinstall [options] installer 03:33:10 INFO - Options: 03:33:10 INFO - -h, --help show this help message and exit 03:33:10 INFO - -d DEST, --destination=DEST 03:33:10 INFO - Directory to install application into. [default: 03:33:10 INFO - "/builds/slave/test-pgo"] 03:33:10 INFO - --app=APP Application being installed. [default: firefox] 03:33:10 INFO - mkdir: /builds/slave/test-pgo/build/application 03:33:10 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'] 03:33:10 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 03:33:24 INFO - Reading from file tmpfile_stdout 03:33:24 INFO - Output received: 03:33:24 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 03:33:24 INFO - Running post-action listener: _resource_record_post_action 03:33:24 INFO - ##### 03:33:24 INFO - ##### Running run-tests step. 03:33:24 INFO - ##### 03:33:24 INFO - Running pre-action listener: _resource_record_pre_action 03:33:24 INFO - Running main action method: run_tests 03:33:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 03:33:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 03:33:24 INFO - Python 2.7.3 03:33:24 INFO - Return code: 0 03:33:24 INFO - grabbing minidump binary from tooltool 03:33:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:24 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 0xee0350>, '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 0xee1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee16e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 03:33:24 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 03:33:24 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 03:33:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:33:24 INFO - Return code: 0 03:33:24 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 03:33:24 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 03:33:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 03:33:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 03:33:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 03:33:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 03:33:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 03:33:25 INFO - Return code: 0 03:33:25 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/1452766055/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 03:33:25 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/1452766055/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost 03:33:25 INFO - Using env: {'DISPLAY': ':0', 03:33:25 INFO - 'HOME': '/home/cltbld', 03:33:25 INFO - 'LANG': 'en_US.UTF-8', 03:33:25 INFO - 'LANGUAGE': 'en_US:en', 03:33:25 INFO - 'LOGNAME': 'cltbld', 03:33:25 INFO - 'MAIL': '/var/mail/cltbld', 03:33:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:33:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 03:33:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:25 INFO - 'MOZ_NO_REMOTE': '1', 03:33:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:33:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:25 INFO - 'NO_EM_RESTART': '1', 03:33:25 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:33:25 INFO - 'PWD': '/builds/slave/test-pgo', 03:33:25 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 03:33:25 INFO - 'SHELL': '/bin/bash', 03:33:25 INFO - 'SHLVL': '1', 03:33:25 INFO - 'TERM': 'linux', 03:33:25 INFO - 'TMOUT': '86400', 03:33:25 INFO - 'USER': 'cltbld', 03:33:25 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916', 03:33:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:25 INFO - '_': '/tools/buildbot/bin/python'} 03:33:25 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/1452766055/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] with output_timeout 3600 03:33:25 INFO - mozversion INFO | application_buildid: 20160114020735 03:33:25 INFO - mozversion INFO | application_changeset: 8b07fd4f3ca5812ceeadd1a6d428dffd5f5b917f 03:33:25 INFO - mozversion INFO | application_display_name: Nightly 03:33:25 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:33:25 INFO - mozversion INFO | application_name: Firefox 03:33:25 INFO - mozversion INFO | application_remotingname: firefox 03:33:25 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:33:25 INFO - mozversion INFO | application_vendor: Mozilla 03:33:25 INFO - mozversion INFO | application_version: 44.0 03:33:25 INFO - mozversion INFO | platform_buildid: 20160114020735 03:33:25 INFO - mozversion INFO | platform_changeset: 8b07fd4f3ca5812ceeadd1a6d428dffd5f5b917f 03:33:25 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:33:25 INFO - mozversion INFO | platform_version: 44.0 03:33:25 INFO - 2016-01-14 03:33:25,957 DEBUG : using testdate: 1452771205 03:33:25 INFO - 2016-01-14 03:33:25,957 DEBUG : actual date: 1452771205 03:33:25 INFO - 2016-01-14 03:33:25,970 INFO : starting webserver on 'localhost:37033' 03:33:25 INFO - 2016-01-14 03:33:25,971 INFO : Starting test suite talos-linux64-ix-056 03:33:25 INFO - 2016-01-14 03:33:25,972 INFO : Starting test dromaeo_css 03:33:25 INFO - 2016-01-14 03:33:25,972 DEBUG : operating with platform_type : linux_ 03:33:25 INFO - 2016-01-14 03:33:25,972 INFO : Initialising browser for dromaeo_css test... 03:33:25 INFO - 2016-01-14 03:33:25,986 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQFDQSK/profile http://localhost:37033/getInfo.html 03:33:30 INFO - 2016-01-14 03:33:30,085 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:33:30 INFO - 2016-01-14 03:33:30,086 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:33:30 INFO - 2016-01-14 03:33:30,086 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:33:30 INFO - 2016-01-14 03:33:30,086 DEBUG : BROWSER_OUTPUT: __metrics 03:33:30 INFO - 2016-01-14 03:33:30,183 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] 03:33:31 INFO - 2016-01-14 03:33:31,684 INFO : Browser initialized. 03:33:31 INFO - 2016-01-14 03:33:31,684 INFO : Running cycle 1/1 for dromaeo_css test... 03:33:31 INFO - 2016-01-14 03:33:31,685 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQFDQSK/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:33:43 INFO - 2016-01-14 03:33:43,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 129327104 03:33:43 INFO - 2016-01-14 03:33:43,260 DEBUG : BROWSER_OUTPUT: 03:34:34 INFO - 2016-01-14 03:34:34,072 DEBUG : BROWSER_OUTPUT: 1452771274071 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] 03:36:42 INFO - 2016-01-14 03:36:42,150 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37033/tests/dromaeo/cssquery-ext.html) 03:36:43 INFO - 2016-01-14 03:36:43,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 229146624 03:36:43 INFO - 2016-01-14 03:36:43,155 DEBUG : BROWSER_OUTPUT: 03:39:45 INFO - 2016-01-14 03:39:45,180 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/dromaeo/cssquery-ext.html (next: http://localhost:37033/tests/dromaeo/cssquery-jquery.html) 03:39:45 INFO - 2016-01-14 03:39:45,746 DEBUG : BROWSER_OUTPUT: RSS: Main: 205561856 03:39:45 INFO - 2016-01-14 03:39:45,746 DEBUG : BROWSER_OUTPUT: 03:42:47 INFO - 2016-01-14 03:42:47,442 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37033/tests/dromaeo/cssquery-mootools.html) 03:42:48 INFO - 2016-01-14 03:42:48,011 DEBUG : BROWSER_OUTPUT: RSS: Main: 202350592 03:42:48 INFO - 2016-01-14 03:42:48,011 DEBUG : BROWSER_OUTPUT: 03:45:49 INFO - 2016-01-14 03:45:49,753 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37033/tests/dromaeo/cssquery-prototype.html) 03:45:50 INFO - 2016-01-14 03:45:50,335 DEBUG : BROWSER_OUTPUT: RSS: Main: 193163264 03:45:50 INFO - 2016-01-14 03:45:50,336 DEBUG : BROWSER_OUTPUT: 03:48:52 INFO - 2016-01-14 03:48:52,810 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37033/tests/dromaeo/cssquery-yui.html) 03:48:53 INFO - 2016-01-14 03:48:53,371 DEBUG : BROWSER_OUTPUT: RSS: Main: 194195456 03:48:53 INFO - 2016-01-14 03:48:53,372 DEBUG : BROWSER_OUTPUT: 03:51:55 INFO - 2016-01-14 03:51:55,431 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/dromaeo/cssquery-yui.html (next: http://localhost:37033/tests/dromaeo/cssquery-dojo.html) 03:51:55 INFO - 2016-01-14 03:51:55,689 DEBUG : BROWSER_OUTPUT: RSS: Main: 263684096 03:51:55 INFO - 2016-01-14 03:51:55,689 DEBUG : BROWSER_OUTPUT: 03:51:55 INFO - 2016-01-14 03:51:55,689 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:51:55 INFO - 2016-01-14 03:51:55,689 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:51:55 INFO - 2016-01-14 03:51:55,689 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:51:55 INFO - 2016-01-14 03:51:55,690 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:51:55 INFO - 2016-01-14 03:51:55,690 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3293.785903916246;3362.1090411041064;3386.136535183528;3389.322135572886;3413.146713321669;5554.50009499145;5554.750036248369;5563.109902415615;5564.8330550083465;5573.5819813513945;5576.888462230753;5586.050371436855;5599.216109744639;5612.35457922338;5620.269364982557;4993.375828021497;5089.236614507817;5113.872153196163;5155.123628983063;5222.712750798704;4990.05189014088;5141.485851414866;5179.559737422323;5186.870328241786;5188.818391356303;4998.200287953928;5111.897762044757;5114.488551144893;5116.258142569344;5126.102931986888;5154.793808247666;5183.714895680736;5185.403678576955;5198.5841132709675;5200.1939699346285;4880.560749532559;4934.08719386911;4952.331435256267;5098.4136824265315;5119.411267704188;5406.702631355274;5442.374126975369;5442.6462279952075;5465;5471.452854714496;5971.462568368825;5972.283326000865;5980.491658209014;6064.514838812885;6079;5812.796552120655;5874.295084589877;5947.553933454965;5993;6023.548233882477;5587.944120558783;5590.8043218487155;5602.747876345574;5604.6917419541505;5621.128725047624;6041.486473649701;6049.243844519435;6051.485623721945;6054.424829641177;6064.605800622945;5713.571482138773;5823.79616713413;5834.212381328552;5914.526837852962;5930.406959304035;5982.132590774313;6075.665838378844;6124.5100391968535;6131.724072416752;6135.631862088289;5725.169850371756;6008.489278411296;6028.668423236676;6083.057126145454;6112.235970503687;5759.6832174229985;5877.853053673607;6003.879922401527;6013.6993150342305;6013.969930150409;4624.514425985223;4627.421572303462;4628.930566041512;4636.6058884995155;4647.512011238839;4036.354183330653;4126.339785634284;4159.729617574848;4166.979165104155;4189.455370801777;5607.102863541813;5646.20953066571;5665.2635157430095;5670.971645141831;5694.373618901917;6039.728212230372;6075.3924607539775;6095.116208149882;6096;6099.085137229363;5501.587380946445;5558.416366281563;5616.803411880564;5705.144228365695;5719.656820590778;5303.15149576066;5311.097113490716;5312.017276803804;5313.282706834605;5315.362156541162;6264.686765661699;6267.2792628847355;6269.373062693694;6276.686165691697;6285.937140628626;5793.739281732248;5818.359980402237;5924.733386997509;6020.668863212565;6133.202683651097;6059.72731227087;6068.362821903636;6070.423309785563;6076.605020673642;6170.50635949114;5966.522678185822;5973.372795856546;5976.073708575178;5978.133170690138;5981.401859813984;5823.301203855564;5901.262342207224;5934.109883517334;6020.066889632114;6022.45797878193;5827.6794776286015;5828.825135245949;5829.970850145722;5836.854078648068;5877.911831322448;5637.238972738628;5818.505427038749;5825.650460972355;5830.358660547252;5837.503812176013;5610.831675049755;5668.2064510967675;5670.886582268414;5687.886242275049;5713.8285851424525;5921.733521991607;5929.703514824328;5938.465538101592;5946.643201407929;5953;5567.944320556905;5691.7438715257085;5809.593328466966;5814.7964821232745;5824.271966004249 03:51:55 INFO - 2016-01-14 03:51:55,690 DEBUG : BROWSER_OUTPUT: |1;ext.html;581.8021872563328;626.3830127324585;633.4932054356515;634.1502386801687;636.6148480169501;34345.82827085864;40043.39934900975;40516.18967620646;40553.39169912446;40553.79723101388;43786;44820.1035979281;44868.775656121674;44905.77547112268;44948.55051449485;8084.797880052987;8201.056878458963;9335.85996210059;9488.810223795519;9513.048695130467;11056.889431105667;11263.887361126366;11382.943085284562;11392.088632909348;11397.259178153436;10986.285891417072;11066.889331106666;11081.72295692606;11082.778344433107;11085.057770089512;11048.613298534552;11145.832812507779;11148.554057837677;11150;11165.609203677874;10838.945805270923;10960.28758130719;10976.012158905656;10977.176711746652;10987.450627468595;42913.35629965522;43582.128357432994;43609.78195109036;43795.34306985366;43944.121117577786;3527.2416430467315;3560.626134255918;3569.982150089233;3605.152789094561;3693.833777479993;32770.18074548132;33097.83451082753;33157.3368532628;33207.33585328304;33480.330393392236;3663.981680091609;3667.266546690646;3716.108134047809;3718.535183102112;3759.285735710206;303224;304094;304343.9999999978;304876;305251;64892;66819.33180668228;67800.6609966947;68222.65888670525;68246;77182;77325;77474;78111.60944195243;78149.60925195452;16633.417830375878;16774.83225167757;16782.91608541974;16845.663086738197;16940.915295423445;14331.85668143326;14402.495912643008;14411.279436028157;14414.927925360305;14429.278536073154;21565.137394504043;21774.346769596938;21818.672719909213;21824.672629910565;21837.78162218389;8507.489550626982;8620.310375169971;8716.259117974916;8803.559822008874;8883.955580222058;1054.446415631784;1074.3178081918038;1077.5851297250615;1127.9548818047185;1155.526234243956;33499.665003349655;33805.32389352249;33867.15332116717;34009.829950850086;34018.82990585031;1144.6508814811561;1195.838561794164;1195.9581414650445;1197.5209916033657;1219.3415555599877;14230.417692626557;16627.916860415862;17153.228104735317;17433.56416089608;17652.20565074549;378423;379562;381094;382002;382041;59530.40469595335;59575.702121489114;59651.40348596458;59672.10491842626;59832.40167598355;1485.7994170786806;1490.5304828978838;1494.6562290673205;1512;1539.638185026518;28205;30957.535636965913;31245.687543124277;31772.364552709278;31790.205244869063;55542.16686749621;55580.44419555915;55632.721836390556;55633.165502518306;55816.44183558112;251400;252547;253680;253860;254104;31157.376852463276;31311.84344078265;34811.65188348084;34973.65026349704;35052.474212886314;41629;41756.3736543946;41776;42609.5739042618;42621.57378426176;35886;36306;36792.26415471622;36813.44779828358;37039;37927;38595.421068684554;40004.599954000085;40096.1980760389;40596;36261.81869090637;36445.27109457849;36652.63347366599;37973.240535189696;38342.42486362651;32113;32276.83861580771;32654.836725817167;32693.346133076728;33266.83366583152;25515.6172657414;26081.217563473125;26364.340891477867;27271;27538.311542211606 03:51:55 INFO - 2016-01-14 03:51:55,693 DEBUG : BROWSER_OUTPUT: |2;jquery.html;365.38432740831684;375.24762850484774;376.9849206031758;383.0614993266496;387.5388287937353;16817.579560510978;19099.236030558797;19252.807471925276;19298.131584078714;20822.375328740163;18714.812851871477;18737;18910.62178756424;19570.510737231576;19580.21679132833;4332.24185767491;4434.844780432685;5695.971520142404;5775.740091695863;5829.621074630157;4443.688941774077;6127.754889804425;6316.30520642729;6340.144080549136;6362.809115726513;4982.128127577677;5046.21783623539;5109.054824857413;6259.186305780242;6273.3726627337355;5081.237814327862;5081.669691470042;5081.720505372203;5085.516875896802;5135.820246291362;4582.679212455129;4615.076984603093;4618.307253911907;4618.792154353062;4623.606993405565;13807.378667959865;14031.43874245029;14648.340824662915;15059.397624095023;15071.924640376837;2195;2203.0857194264363;2212.358416059341;2212.8782916939563;2298.28753086542;10800.837987430195;10984.505697243563;11229.887701122965;11321.094312454983;11400.543978240861;1789.0249813851483;1811.1216060210918;1823.7264410338423;1843.797182309945;1892.7255547945608;65417.672911635134;65576.34423655798;65589;65618.67190664064;66198.66900665466;7910.367170626336;7937.960310198528;7964.123946365895;7987.9615649965135;8043.638036288381;9630.036996300314;9645.08371704687;9668.70993870185;9670.806583868283;9676.951615241878;6324.494040476751;6333.1766870306565;6369.267534233622;6503.739850406026;6508.446782023487;6779.084823548857;6828.2147553031045;6829.248782633907;6866.622335771481;6875.931240687629;3221.6778322168066;3302.53764472974;3557.8221088945966;3638.3450978824067;3682.889513314551;1272.4973635414144;1276.183242724657;1290.1871820753054;1298.8181654568368;1335.8129861819352;522.9424763276037;524.9527542521191;525.8921921006184;532.4063669009028;550.5430492691137;12965.35173241334;13319.600411987656;13425.462981480827;13478.39347229377;13513.324333783272;547.250267134021;553.4189101443468;559.1082223852948;561.50026476436;564.0889962710194;7263.0921134858145;7271.490995630255;7305.707771689073;7414.14737305206;7442.702291908263;620284;627757;627835;632482;635646;78701;83617.58191209006;83756;84528;84779;565.3667891960948;566.3769853161572;567.611186337352;568.4486048532999;569.7265312649952;11945.462454189408;12060.155789094679;12118.636440906786;12130.393480326125;12134;36846.26307473889;37086.44370334501;37218;38411.2317753649;38456;49707.50292497173;49956.50043499664;51038.234426482886;51232.74383628207;52074.73962630163;7235.674394652148;7247;7252.4560657949805;7258.600776957318;7282.6722797473185;10713.089387401846;10997.670069897917;11049.226554141454;11445.25605835618;12052.578159764365;10889;10960.616378426717;11063.557457701601;11173;11189.720256993314;10703.304285221435;10729.517171727135;10767.3539587625;10808.297460665031;10984.121270298518;9332.626694932398;10828.296160749525;11039;11129.054030407182;11187.216894817284;16227.756583651018;16461.34154633801;16501.83498165051;16696.66606667884;16708.74936875972;17121;17214.569635758704;17236.655266894842;17405.99831019449;17558.648827023644 03:51:55 INFO - 2016-01-14 03:51:55,694 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1546.3659899441225;1554.261725680302;1804.548862784304;1870.625874825035;1879.2295158984814;7006.964965175167;7113.217546069928;7727.690892364306;7869.370450363965;7898.763037108882;7717.421193410502;7899.0126234220725;7943.602819858998;7960.402969777261;8010.639521221545;2339.6841426407473;2372.54921564903;2383.4518060846017;2386.486905315357;2391.7129944406697;2088.6866969954476;2129.2547608337113;2308.3305841306;2362.1850461590725;2378.52429514097;2303.285981345788;2320.431494283894;2328.278233747535;2331.743508214095;2331.7668233176714;2346.049849810821;2367.2543148908126;2369.277370402027;2371.8932648030795;2373.679553260305;2330.4290448839974;2365.5150694107665;2369.5260947810507;2369.822263330257;2371.8221133415063;8003.559804210766;8049.637766300472;8050.879236811453;8056.073551541589;8058.959705201495;4741.193997020515;4811.109944660249;4811.4707382187935;4813.614910807127;4818.662693611449;2998.670146283907;3066.9233269168226;3069.907902762921;3078.7998780079224;3082.614673165854;966.9371490853142;980.6273616025865;996.3374356053289;998.5007496251874;1002.2082554781714;10579.947100264526;10587;10590.470476476145;10599.099076578577;10605.469726513644;4413.44831896013;4439.445069366329;4474.709143905636;4479.664025198123;4489.551044895549;9106.772330691652;9115.954420227856;9190.988991210961;9210.401323913924;9218.44689318643;4860.8298709545;4876.341693871282;4879.536444037811;4886.169351210303;4913.48408417111;4416.447944006999;4455.888602784957;4473.485549161888;4501.774911254424;4514.142312960527;3585.8386372613295;3843.5195600549932;3873.0511024799102;3914.4911161549385;3937.665298449607;1140.5836869542723;1225.081189108169;1253.9310337931502;1255.706622179157;1263.437770192274;1122.854028976228;1188.8276199950958;1428.748727877475;1460.3063544816127;1461.7222727681706;7559.773206803805;7657.425693073042;7681.308682218627;7683.615819209017;7687.192844751333;1215.7082300247878;1281.1864466064117;1317.3742472325569;1333.893288536934;1346.7845144776788;5978.163057172;6022.397760223943;6025.487833534111;6030.608010479393;6030.909536356959;9070.138336857988;9458.810823783486;9463.05369463062;9473.28950328728;9481.336306458481;9277.58250878712;9354.953225233967;9363.625454981724;9370.812583748288;9388.2020028297;6856.41720453767;6938.098047253926;6956.513044086966;6970.233274339818;7257.419406447577;7463.589502577411;7543.245675432632;7568.5458872467825;7577.242275772379;7585.924140758521;8385.538795366314;8492.017503546647;8492.363072769691;8547.888774459281;8586.441881277697;8507.361947853811;8621.612027458905;8622.310215182644;8622.482651040958;8645.697400590949;7294.088238970129;7340.779776606711;7365.373943214887;7370.226126256666;7373.8525229494035;6879.174499060145;7170.605616691132;7901.407394445325;7973.282404583615;7983.1617680142745;7399.963000184965;7414.592197429193;7454.291842274975;7466.589337586485;7489.325960663567;7225.602591857288;7312.0128782613665;7315.63421828917;7358.595277259802;7408.703651854081;7480.775576732707;7485.47601667878;7513.887291690519;7528.021357223526;7539;6841.692123854339;6878.105846239957;6890.10428644273;6902.585844849325;6907.620080895599;7091.893621595577;7102.680379383043;7104.289571043061;7112.786616401516;7146.464015198777 03:51:55 INFO - 2016-01-14 03:51:55,695 DEBUG : BROWSER_OUTPUT: |4;prototype.html;363.26076434455877;364.74650118167887;367.2123295531086;367.6984872404628;373.90278527582836;6022.728977196026;6074.483668888144;6100.298465676454;6112.144299798032;6121;5876.353601103886;5973.910391344122;6021.337652858182;6033.69831508425;6057.151998720172;4743.786529606167;4748.0978614063215;4748.240281554952;4753.691010084333;4755.714657120584;4574.519675434081;4667.113248482794;4696.64775141864;4759.000609871931;4761.262004389325;4724.858254252378;4727.787249573777;4731.408573928259;4761.976175122348;4765.404324459442;4709.693869898446;4729.952700472985;4730.645201609858;4731.621470282386;4733.905321893543;4203.47456567929;4211.178820130076;4211.97894010531;4212.641925436342;4216.894577635553;5706.229658996024;5729.026065568822;5732.5127364174095;5812.476877081083;5820.330661973842;5425.37608175061;5430.56555475561;5434.673919564839;5434.728263586844;5444.183372494118;6203.131561581382;6445.4199122079235;6459.612423254573;6459.61242325462;6465.83835404114;4425.491068527096;4503.774811259456;4572.51988541205;4577.56513131252;4580.679352445331;6538.051982462516;6567.080608714784;6623.635700036496;6627.469802415795;6635.867282654368;4683.039976804746;4684.95315046852;4685.1800934836265;4691.296305554126;4691.788869500812;4619.284010978304;4690.296455531698;4701.741404222732;4737;4749.311349854252;5388.973055134699;5391.110466773017;5391.541718953933;5392.433794451605;5395.05586522357;6475.676216189266;6476.158099447041;6477.222733271943;6482.967585162044;6488.578242414228;1707.692615329228;1732.7487514310355;1732.9133543322782;1733.1507561295127;1735.3665911942303;1138.1861968692424;1155.907527397823;1157.9594714184964;1158.8667303260065;1185.8221266810046;6172.703732216285;6184.938150618436;6187.566870319034;6191.752329906753;6201.255849298113;6552.770653027216;6557.803265901935;6614.239362473281;6617.9669101655145;6619.900701489481;6141.048137538689;6181.567290289637;6182.103594978703;6190;6215.192025036717;3704.148045949447;3709.3694072007825;3737.7570457920147;3738.3084129435597;3757.9812100939866;6864.519483636197;6874.621895795779;6877.518573699893;6884.759033433806;6887.724491020404;6812.216595091628;6819.24988251292;6886;6891.483138764613;6893.551919125241;6879;6879.793606191823;6886.827829304308;6893.517453778188;6907.171139463196;6547.247066587403;6627.900581491378;6631;6735.696893639896;6742.69657865407;6596.604203747598;6606.174228221473;6621.503387246072;6637.4690024798865;6650.068990341259;6835.829104272234;6837.965810170917;6840.726370945106;6841.281665425058;6842.281560436082;4612.192866248461;4661.743604101807;4757.714537127784;4848.418189817104;4863.465018847854;6617.636030018395;6652.600843949379;6655.567388119756;6660.56706314088;6661.233958094881;6459.967700161469;6485.967570162307;6517.804465866032;6518.087467754423;6521.184851893513;6407.568244377804;6431.389018043278;6497.4477169441125;6500.447461965786;6506.41442270198;6330.113784070326;6374.649394283359;6379.74481020754;6397.232332120176;6431.807045788634;6575.934240657532;6590.670466476561;6607.900881486686;6620.404163625104;6624.072629831924;6294.15028971083;6404.775832845828;6451.2903580607035;6452.903206451813;6466.644334561456 03:51:55 INFO - 2016-01-14 03:51:55,696 DEBUG : BROWSER_OUTPUT: |5;yui.html;812.3298278919889;822.2312138150829;887.3522328700049;898.4654130792177;961.3462845265226;15152.62118447038;15181.92409037956;15257.694846103072;15287.69424611507;15298;12870.742585148291;12926.09517333787;12931.870681293185;12955.09314347996;12959.092863499558;785.4344871692373;794.3684770607352;920.6409500294891;923.5336155241628;942.1661829281102;934.8410770169088;939.6523286384047;946;946.0114180681205;946.8153710026513;815.0993152566388;981.1660088924428;984.6553706202808;990.3859607043607;990.7968866382411;819.6229734322189;964.3972517176765;966.5505539923969;967.569431602937;983.5573991703726;714.4641518860855;715.8460930899829;721.76542623647;724.807925899637;726.1358982810439;14924.403023879031;15086.924565377212;15105.244737763069;15107.32017059235;15112.244387780567;13368;13368.665783355495;13632.318384080756;13687.24720140392;13775.311234438239;11217.551297948072;11390.943045284721;11599.594014209462;11617.94191029048;11766.176367654267;3829.061879839457;3908.1402091539817;3936.9212615747524;3941.7043721720697;3950;52817;52970.999999999614;53090;53178;53519.73240133814;10791.568337266579;10883.074938630305;11005.724856878642;11150.944245278723;11189.216754827246;20133.49666258326;20159.798402015793;20163.798362016485;20174;20205.292814751414;8133.471324363897;8212.17878212174;8476.703315383931;8611.39720219591;8652.178043085896;4681.789319480631;4704.717716936994;4716.858494245178;4781.569658730731;4825.638077144228;3178.475551534017;3230.78999865008;3233.11089450403;3233.66046565112;3239.6436391996863;486.7858142417325;489.82611173033774;492.25423483422645;492.7610109097085;498.50896866586385;573.8708790522182;641.2913730328014;664.4983037806522;680.7379159023712;720.3624792058961;8032.196780321921;8170.346372290202;8214.671413143407;8298.419110662317;8368.3305335573;527.4540850220004;584.9151872978395;687.656171914043;716.8924661300742;724.5326764237037;4219.6202341789385;4251.553586873334;4265.1469706058915;4266.512846778464;4271.487421509377;67552.32447675559;67744;70366.296337037;70510;70874.2912570878;57842;58503.70748146232;58894;58909.705451472466;59455.40544594585;54027.729861351225;54225.1866222007;54412.455875441534;54808.17787733187;55211.44788552143;8824.117588241124;8854.335924805795;8973.01296857332;9076.152513514411;9103.317251206054;37863.43204851874;40407.1918561633;40744.388834166915;41180.1763964725;41585;51627.2255916154;51817.48182518127;52101;53086.73456632692;53823.46176538184;8578.571071446528;8585.742427727178;8667.609957551798;8828.735137945616;8954.641814327353;13332.466701332227;13519;13832.930835345758;13983.370748316554;14078.788818167532;12275.386230688608;12288.938555307166;12530.812037819622;12612.810807838074;12644.557440489538;12312.630621081382;12335.136540442083;12524.624261271812;12618.684532886751;13124.934375328445;10470.581176753149;11869.827031395924;11923.76152476963;12010.39948002579;12358.382080896099;10519.26365154432;10637.725356534182;10778.245522813642;10939.945300273448;10954.068904143238;8528.403011789364;10515.631952881613;10942.45287735626;10966.396848173426;10983.890161098287 03:51:55 INFO - 2016-01-14 03:51:55,696 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:51:55 INFO - 2016-01-14 03:51:55,696 DEBUG : BROWSER_OUTPUT: __start_cc_report 03:51:55 INFO - 2016-01-14 03:51:55,696 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,770 03:51:55 INFO - 2016-01-14 03:51:55,697 DEBUG : BROWSER_OUTPUT: __end_cc_report 03:51:55 INFO - 2016-01-14 03:51:55,697 DEBUG : BROWSER_OUTPUT: __startTimestamp1452772315682__endTimestamp 03:51:55 INFO - 2016-01-14 03:51:55,697 DEBUG : BROWSER_OUTPUT: 03:51:55 INFO - 2016-01-14 03:51:55,697 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:51:55 INFO - 2016-01-14 03:51:55,697 DEBUG : BROWSER_OUTPUT: Number of tests: 6 03:51:55 INFO - 2016-01-14 03:51:55,697 DEBUG : BROWSER_OUTPUT: 03:51:55 INFO - 2016-01-14 03:51:55,697 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5563.40 Median:5713.83 stddev:606.57 (10.6%) stddev-sans-first:581.71 03:51:55 INFO - 2016-01-14 03:51:55,697 DEBUG : BROWSER_OUTPUT: Values: 3293.8 3362.1 3386.1 3389.3 3413.1 5554.5 5554.8 5563.1 5564.8 5573.6 5576.9 5586.1 5599.2 5612.4 5620.3 4993.4 5089.2 5113.9 5155.1 5222.7 4990.1 5141.5 5179.6 5186.9 5188.8 4998.2 5111.9 5114.5 5116.3 5126.1 5154.8 5183.7 5185.4 5198.6 5200.2 4880.6 4934.1 4952.3 5098.4 5119.4 5406.7 5442.4 5442.6 5465.0 5471.5 5971.5 5972.3 5980.5 6064.5 6079.0 5812.8 5874.3 5947.6 5993.0 6023.5 5587.9 5590.8 5602.7 5604.7 5621.1 6041.5 6049.2 6051.5 6054.4 6064.6 5713.6 5823.8 5834.2 5914.5 5930.4 5982.1 6075.7 6124.5 6131.7 6135.6 5725.2 6008.5 6028.7 6083.1 6112.2 5759.7 5877.9 6003.9 6013.7 6014.0 4624.5 4627.4 4628.9 4636.6 4647.5 4036.4 4126.3 4159.7 4167.0 4189.5 5607.1 5646.2 5665.3 5671.0 5694.4 6039.7 6075.4 6095.1 6096.0 6099.1 5501.6 5558.4 5616.8 5705.1 5719.7 5303.2 5311.1 5312.0 5313.3 5315.4 6264.7 6267.3 6269.4 6276.7 6285.9 5793.7 5818.4 5924.7 6020.7 6133.2 6059.7 6068.4 6070.4 6076.6 6170.5 5966.5 5973.4 5976.1 5978.1 5981.4 5823.3 5901.3 5934.1 6020.1 6022.5 5827.7 5828.8 5830.0 5836.9 5877.9 5637.2 5818.5 5825.7 5830.4 5837.5 5610.8 5668.2 5670.9 5687.9 5713.8 5921.7 5929.7 5938.5 5946.6 5953.0 5567.9 5691.7 5809.6 5814.8 5824.3 03:51:55 INFO - 2016-01-14 03:51:55,697 DEBUG : BROWSER_OUTPUT: 03:51:55 INFO - 2016-01-14 03:51:55,697 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:50407.02 Median:34014.33 stddev:83029.12 (244.1%) stddev-sans-first:83177.76 03:51:55 INFO - 2016-01-14 03:51:55,698 DEBUG : BROWSER_OUTPUT: Values: 581.8 626.4 633.5 634.2 636.6 34345.8 40043.4 40516.2 40553.4 40553.8 43786.0 44820.1 44868.8 44905.8 44948.6 8084.8 8201.1 9335.9 9488.8 9513.0 11056.9 11263.9 11382.9 11392.1 11397.3 10986.3 11066.9 11081.7 11082.8 11085.1 11048.6 11145.8 11148.6 11150.0 11165.6 10838.9 10960.3 10976.0 10977.2 10987.5 42913.4 43582.1 43609.8 43795.3 43944.1 3527.2 3560.6 3570.0 3605.2 3693.8 32770.2 33097.8 33157.3 33207.3 33480.3 3664.0 3667.3 3716.1 3718.5 3759.3 303224.0 304094.0 304344.0 304876.0 305251.0 64892.0 66819.3 67800.7 68222.7 68246.0 77182.0 77325.0 77474.0 78111.6 78149.6 16633.4 16774.8 16782.9 16845.7 16940.9 14331.9 14402.5 14411.3 14414.9 14429.3 21565.1 21774.3 21818.7 21824.7 21837.8 8507.5 8620.3 8716.3 8803.6 8884.0 1054.4 1074.3 1077.6 1128.0 1155.5 33499.7 33805.3 33867.2 34009.8 34018.8 1144.7 1195.8 1196.0 1197.5 1219.3 14230.4 16627.9 17153.2 17433.6 17652.2 378423.0 379562.0 381094.0 382002.0 382041.0 59530.4 59575.7 59651.4 59672.1 59832.4 1485.8 1490.5 1494.7 1512.0 1539.6 28205.0 30957.5 31245.7 31772.4 31790.2 55542.2 55580.4 55632.7 55633.2 55816.4 251400.0 252547.0 253680.0 253860.0 254104.0 31157.4 31311.8 34811.7 34973.7 35052.5 41629.0 41756.4 41776.0 42609.6 42621.6 35886.0 36306.0 36792.3 36813.4 37039.0 37927.0 38595.4 40004.6 40096.2 40596.0 36261.8 36445.3 36652.6 37973.2 38342.4 32113.0 32276.8 32654.8 32693.3 33266.8 25515.6 26081.2 26364.3 27271.0 27538.3 03:51:55 INFO - 2016-01-14 03:51:55,699 DEBUG : BROWSER_OUTPUT: 03:51:55 INFO - 2016-01-14 03:51:55,699 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30987.48 Median:4439.27 stddev:102894.46 (2317.8%) stddev-sans-first:103157.41 03:51:55 INFO - 2016-01-14 03:51:55,699 DEBUG : BROWSER_OUTPUT: Values: 365.4 375.2 377.0 383.1 387.5 16817.6 19099.2 19252.8 19298.1 20822.4 18714.8 18737.0 18910.6 19570.5 19580.2 4332.2 4434.8 5696.0 5775.7 5829.6 4443.7 6127.8 6316.3 6340.1 6362.8 4982.1 5046.2 5109.1 6259.2 6273.4 5081.2 5081.7 5081.7 5085.5 5135.8 4582.7 4615.1 4618.3 4618.8 4623.6 13807.4 14031.4 14648.3 15059.4 15071.9 2195.0 2203.1 2212.4 2212.9 2298.3 10800.8 10984.5 11229.9 11321.1 11400.5 1789.0 1811.1 1823.7 1843.8 1892.7 65417.7 65576.3 65589.0 65618.7 66198.7 7910.4 7938.0 7964.1 7988.0 8043.6 9630.0 9645.1 9668.7 9670.8 9677.0 6324.5 6333.2 6369.3 6503.7 6508.4 6779.1 6828.2 6829.2 6866.6 6875.9 3221.7 3302.5 3557.8 3638.3 3682.9 1272.5 1276.2 1290.2 1298.8 1335.8 522.9 525.0 525.9 532.4 550.5 12965.4 13319.6 13425.5 13478.4 13513.3 547.3 553.4 559.1 561.5 564.1 7263.1 7271.5 7305.7 7414.1 7442.7 620284.0 627757.0 627835.0 632482.0 635646.0 78701.0 83617.6 83756.0 84528.0 84779.0 565.4 566.4 567.6 568.4 569.7 11945.5 12060.2 12118.6 12130.4 12134.0 36846.3 37086.4 37218.0 38411.2 38456.0 49707.5 49956.5 51038.2 51232.7 52074.7 7235.7 7247.0 7252.5 7258.6 7282.7 10713.1 10997.7 11049.2 11445.3 12052.6 10889.0 10960.6 11063.6 11173.0 11189.7 10703.3 10729.5 10767.4 10808.3 10984.1 9332.6 10828.3 11039.0 11129.1 11187.2 16227.8 16461.3 16501.8 16696.7 16708.7 17121.0 17214.6 17236.7 17406.0 17558.6 03:51:55 INFO - 2016-01-14 03:51:55,699 DEBUG : BROWSER_OUTPUT: 03:51:55 INFO - 2016-01-14 03:51:55,699 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5618.35 Median:6896.35 stddev:2840.07 (41.2%) stddev-sans-first:2831.54 03:51:55 INFO - 2016-01-14 03:51:55,700 DEBUG : BROWSER_OUTPUT: Values: 1546.4 1554.3 1804.5 1870.6 1879.2 7007.0 7113.2 7727.7 7869.4 7898.8 7717.4 7899.0 7943.6 7960.4 8010.6 2339.7 2372.5 2383.5 2386.5 2391.7 2088.7 2129.3 2308.3 2362.2 2378.5 2303.3 2320.4 2328.3 2331.7 2331.8 2346.0 2367.3 2369.3 2371.9 2373.7 2330.4 2365.5 2369.5 2369.8 2371.8 8003.6 8049.6 8050.9 8056.1 8059.0 4741.2 4811.1 4811.5 4813.6 4818.7 2998.7 3066.9 3069.9 3078.8 3082.6 966.9 980.6 996.3 998.5 1002.2 10579.9 10587.0 10590.5 10599.1 10605.5 4413.4 4439.4 4474.7 4479.7 4489.6 9106.8 9116.0 9191.0 9210.4 9218.4 4860.8 4876.3 4879.5 4886.2 4913.5 4416.4 4455.9 4473.5 4501.8 4514.1 3585.8 3843.5 3873.1 3914.5 3937.7 1140.6 1225.1 1253.9 1255.7 1263.4 1122.9 1188.8 1428.7 1460.3 1461.7 7559.8 7657.4 7681.3 7683.6 7687.2 1215.7 1281.2 1317.4 1333.9 1346.8 5978.2 6022.4 6025.5 6030.6 6030.9 9070.1 9458.8 9463.1 9473.3 9481.3 9277.6 9355.0 9363.6 9370.8 9388.2 6856.4 6938.1 6956.5 6970.2 7257.4 7463.6 7543.2 7568.5 7577.2 7585.9 8385.5 8492.0 8492.4 8547.9 8586.4 8507.4 8621.6 8622.3 8622.5 8645.7 7294.1 7340.8 7365.4 7370.2 7373.9 6879.2 7170.6 7901.4 7973.3 7983.2 7400.0 7414.6 7454.3 7466.6 7489.3 7225.6 7312.0 7315.6 7358.6 7408.7 7480.8 7485.5 7513.9 7528.0 7539.0 6841.7 6878.1 6890.1 6902.6 6907.6 7091.9 7102.7 7104.3 7112.8 7146.5 03:51:55 INFO - 2016-01-14 03:51:55,700 DEBUG : BROWSER_OUTPUT: 03:51:55 INFO - 2016-01-14 03:51:55,700 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5434.78 Median:6156.88 stddev:1596.59 (25.9%) stddev-sans-first:1555.03 03:51:55 INFO - 2016-01-14 03:51:55,702 DEBUG : BROWSER_OUTPUT: Values: 363.3 364.7 367.2 367.7 373.9 6022.7 6074.5 6100.3 6112.1 6121.0 5876.4 5973.9 6021.3 6033.7 6057.2 4743.8 4748.1 4748.2 4753.7 4755.7 4574.5 4667.1 4696.6 4759.0 4761.3 4724.9 4727.8 4731.4 4762.0 4765.4 4709.7 4730.0 4730.6 4731.6 4733.9 4203.5 4211.2 4212.0 4212.6 4216.9 5706.2 5729.0 5732.5 5812.5 5820.3 5425.4 5430.6 5434.7 5434.7 5444.2 6203.1 6445.4 6459.6 6459.6 6465.8 4425.5 4503.8 4572.5 4577.6 4580.7 6538.1 6567.1 6623.6 6627.5 6635.9 4683.0 4685.0 4685.2 4691.3 4691.8 4619.3 4690.3 4701.7 4737.0 4749.3 5389.0 5391.1 5391.5 5392.4 5395.1 6475.7 6476.2 6477.2 6483.0 6488.6 1707.7 1732.7 1732.9 1733.2 1735.4 1138.2 1155.9 1158.0 1158.9 1185.8 6172.7 6184.9 6187.6 6191.8 6201.3 6552.8 6557.8 6614.2 6618.0 6619.9 6141.0 6181.6 6182.1 6190.0 6215.2 3704.1 3709.4 3737.8 3738.3 3758.0 6864.5 6874.6 6877.5 6884.8 6887.7 6812.2 6819.2 6886.0 6891.5 6893.6 6879.0 6879.8 6886.8 6893.5 6907.2 6547.2 6627.9 6631.0 6735.7 6742.7 6596.6 6606.2 6621.5 6637.5 6650.1 6835.8 6838.0 6840.7 6841.3 6842.3 4612.2 4661.7 4757.7 4848.4 4863.5 6617.6 6652.6 6655.6 6660.6 6661.2 6460.0 6486.0 6517.8 6518.1 6521.2 6407.6 6431.4 6497.4 6500.4 6506.4 6330.1 6374.6 6379.7 6397.2 6431.8 6575.9 6590.7 6607.9 6620.4 6624.1 6294.2 6404.8 6451.3 6452.9 6466.6 03:51:55 INFO - 2016-01-14 03:51:55,702 DEBUG : BROWSER_OUTPUT: 03:51:55 INFO - 2016-01-14 03:51:55,702 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15482.18 Median:491.04 stddev:18704.48 (3809.2%) stddev-sans-first:18724.51 03:51:55 INFO - 2016-01-14 03:51:55,702 DEBUG : BROWSER_OUTPUT: Values: 812.3 822.2 887.4 898.5 961.3 15152.6 15181.9 15257.7 15287.7 15298.0 12870.7 12926.1 12931.9 12955.1 12959.1 785.4 794.4 920.6 923.5 942.2 934.8 939.7 946.0 946.0 946.8 815.1 981.2 984.7 990.4 990.8 819.6 964.4 966.6 967.6 983.6 714.5 715.8 721.8 724.8 726.1 14924.4 15086.9 15105.2 15107.3 15112.2 13368.0 13368.7 13632.3 13687.2 13775.3 11217.6 11390.9 11599.6 11617.9 11766.2 3829.1 3908.1 3936.9 3941.7 3950.0 52817.0 52971.0 53090.0 53178.0 53519.7 10791.6 10883.1 11005.7 11150.9 11189.2 20133.5 20159.8 20163.8 20174.0 20205.3 8133.5 8212.2 8476.7 8611.4 8652.2 4681.8 4704.7 4716.9 4781.6 4825.6 3178.5 3230.8 3233.1 3233.7 3239.6 486.8 489.8 492.3 492.8 498.5 573.9 641.3 664.5 680.7 720.4 8032.2 8170.3 8214.7 8298.4 8368.3 527.5 584.9 687.7 716.9 724.5 4219.6 4251.6 4265.1 4266.5 4271.5 67552.3 67744.0 70366.3 70510.0 70874.3 57842.0 58503.7 58894.0 58909.7 59455.4 54027.7 54225.2 54412.5 54808.2 55211.4 8824.1 8854.3 8973.0 9076.2 9103.3 37863.4 40407.2 40744.4 41180.2 41585.0 51627.2 51817.5 52101.0 53086.7 53823.5 8578.6 8585.7 8667.6 8828.7 8954.6 13332.5 13519.0 13832.9 13983.4 14078.8 12275.4 12288.9 12530.8 12612.8 12644.6 12312.6 12335.1 12524.6 12618.7 13124.9 10470.6 11869.8 11923.8 12010.4 12358.4 10519.3 10637.7 10778.2 10939.9 10954.1 8528.4 10515.6 10942.5 10966.4 10983.9 03:51:55 INFO - 2016-01-14 03:51:55,702 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:51:55 INFO - 2016-01-14 03:51:55,702 DEBUG : BROWSER_OUTPUT: 03:51:56 INFO - 2016-01-14 03:51:56,276 INFO : Browser exited with error code: 0 03:51:56 INFO - 2016-01-14 03:51:56,297 INFO : Completed test dromaeo_css (00:18:30) 03:51:56 INFO - 2016-01-14 03:51:56,297 INFO : Starting test kraken 03:51:56 INFO - 2016-01-14 03:51:56,297 DEBUG : operating with platform_type : linux_ 03:51:56 INFO - 2016-01-14 03:51:56,297 INFO : Initialising browser for kraken test... 03:51:56 INFO - 2016-01-14 03:51:56,303 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphu31np/profile http://localhost:37033/getInfo.html 03:52:00 INFO - 2016-01-14 03:52:00,131 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:52:00 INFO - 2016-01-14 03:52:00,131 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:52:00 INFO - 2016-01-14 03:52:00,131 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:52:00 INFO - 2016-01-14 03:52:00,131 DEBUG : BROWSER_OUTPUT: __metrics 03:52:00 INFO - 2016-01-14 03:52:00,349 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] 03:52:01 INFO - 2016-01-14 03:52:01,664 INFO : Browser initialized. 03:52:01 INFO - 2016-01-14 03:52:01,664 INFO : Running cycle 1/1 for kraken test... 03:52:01 INFO - 2016-01-14 03:52:01,665 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphu31np/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:52:13 INFO - 2016-01-14 03:52:13,170 DEBUG : BROWSER_OUTPUT: RSS: Main: 128212992 03:52:13 INFO - 2016-01-14 03:52:13,171 DEBUG : BROWSER_OUTPUT: 03:52:15 INFO - 2016-01-14 03:52:15,627 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37033/tests/kraken/driver.html?testName=audio-beat-detection) 03:52:16 INFO - 2016-01-14 03:52:16,194 DEBUG : BROWSER_OUTPUT: RSS: Main: 162910208 03:52:16 INFO - 2016-01-14 03:52:16,194 DEBUG : BROWSER_OUTPUT: 03:52:18 INFO - 2016-01-14 03:52:18,731 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37033/tests/kraken/driver.html?testName=audio-dft) 03:52:19 INFO - 2016-01-14 03:52:19,297 DEBUG : BROWSER_OUTPUT: RSS: Main: 160415744 03:52:19 INFO - 2016-01-14 03:52:19,297 DEBUG : BROWSER_OUTPUT: 03:52:22 INFO - 2016-01-14 03:52:22,069 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37033/tests/kraken/driver.html?testName=audio-fft) 03:52:22 INFO - 2016-01-14 03:52:22,635 DEBUG : BROWSER_OUTPUT: RSS: Main: 157466624 03:52:22 INFO - 2016-01-14 03:52:22,636 DEBUG : BROWSER_OUTPUT: 03:52:24 INFO - 2016-01-14 03:52:24,613 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37033/tests/kraken/driver.html?testName=audio-oscillator) 03:52:25 INFO - 2016-01-14 03:52:25,179 DEBUG : BROWSER_OUTPUT: RSS: Main: 157745152 03:52:25 INFO - 2016-01-14 03:52:25,179 DEBUG : BROWSER_OUTPUT: 03:52:27 INFO - 2016-01-14 03:52:27,322 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37033/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:52:27 INFO - 2016-01-14 03:52:27,874 DEBUG : BROWSER_OUTPUT: RSS: Main: 156069888 03:52:27 INFO - 2016-01-14 03:52:27,875 DEBUG : BROWSER_OUTPUT: 03:52:30 INFO - 2016-01-14 03:52:30,795 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37033/tests/kraken/driver.html?testName=imaging-darkroom) 03:52:31 INFO - 2016-01-14 03:52:31,362 DEBUG : BROWSER_OUTPUT: RSS: Main: 156139520 03:52:31 INFO - 2016-01-14 03:52:31,363 DEBUG : BROWSER_OUTPUT: 03:52:34 INFO - 2016-01-14 03:52:34,715 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37033/tests/kraken/driver.html?testName=imaging-desaturate) 03:52:35 INFO - 2016-01-14 03:52:35,276 DEBUG : BROWSER_OUTPUT: RSS: Main: 156344320 03:52:35 INFO - 2016-01-14 03:52:35,276 DEBUG : BROWSER_OUTPUT: 03:52:38 INFO - 2016-01-14 03:52:38,002 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37033/tests/kraken/driver.html?testName=json-parse-financial) 03:52:38 INFO - 2016-01-14 03:52:38,566 DEBUG : BROWSER_OUTPUT: RSS: Main: 156983296 03:52:38 INFO - 2016-01-14 03:52:38,567 DEBUG : BROWSER_OUTPUT: 03:52:40 INFO - 2016-01-14 03:52:40,087 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37033/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:52:40 INFO - 2016-01-14 03:52:40,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 162775040 03:52:40 INFO - 2016-01-14 03:52:40,640 DEBUG : BROWSER_OUTPUT: 03:52:42 INFO - 2016-01-14 03:52:42,362 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37033/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:52:42 INFO - 2016-01-14 03:52:42,955 DEBUG : BROWSER_OUTPUT: RSS: Main: 160075776 03:52:42 INFO - 2016-01-14 03:52:42,956 DEBUG : BROWSER_OUTPUT: 03:52:44 INFO - 2016-01-14 03:52:44,928 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37033/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:52:45 INFO - 2016-01-14 03:52:45,489 DEBUG : BROWSER_OUTPUT: RSS: Main: 164319232 03:52:45 INFO - 2016-01-14 03:52:45,489 DEBUG : BROWSER_OUTPUT: 03:52:47 INFO - 2016-01-14 03:52:47,789 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37033/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:52:48 INFO - 2016-01-14 03:52:48,362 DEBUG : BROWSER_OUTPUT: RSS: Main: 169357312 03:52:48 INFO - 2016-01-14 03:52:48,362 DEBUG : BROWSER_OUTPUT: 03:52:50 INFO - 2016-01-14 03:52:50,782 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37033/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:52:51 INFO - 2016-01-14 03:52:51,347 DEBUG : BROWSER_OUTPUT: RSS: Main: 165208064 03:52:51 INFO - 2016-01-14 03:52:51,348 DEBUG : BROWSER_OUTPUT: 03:52:52 INFO - 2016-01-14 03:52:52,948 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37033/tests/kraken/driver.html?testName=ai-astar) 03:52:53 INFO - 2016-01-14 03:52:53,198 DEBUG : BROWSER_OUTPUT: RSS: Main: 225169408 03:52:53 INFO - 2016-01-14 03:52:53,198 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,199 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:52:53 INFO - 2016-01-14 03:52:53,199 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:52:53 INFO - 2016-01-14 03:52:53,199 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:52:53 INFO - 2016-01-14 03:52:53,199 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:52:53 INFO - 2016-01-14 03:52:53,199 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;111;105;109;110;111;102;111;109;111 03:52:53 INFO - 2016-01-14 03:52:53,199 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;122;145;154;125;149;151;123;156;125 03:52:53 INFO - 2016-01-14 03:52:53,200 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;162;160;159;160;161;165;161;161 03:52:53 INFO - 2016-01-14 03:52:53,200 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;71;140;72;73;72;72;159;70 03:52:53 INFO - 2016-01-14 03:52:53,200 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;136;88;90;90;130;90;89;134 03:52:53 INFO - 2016-01-14 03:52:53,201 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;109;110;110;110;111;109;110 03:52:53 INFO - 2016-01-14 03:52:53,201 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;147;148;147;146;147;146;147 03:52:53 INFO - 2016-01-14 03:52:53,201 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;95;96;96;96;96;95;96 03:52:53 INFO - 2016-01-14 03:52:53,201 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;53;55;58;55;56;57;56;56;54;55 03:52:53 INFO - 2016-01-14 03:52:53,201 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;64;63;63;64;64;62;63;63 03:52:53 INFO - 2016-01-14 03:52:53,201 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;78;79;79;78;79;80;78;79;78 03:52:53 INFO - 2016-01-14 03:52:53,201 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;110;119;109;116;109;118;108;108;115 03:52:53 INFO - 2016-01-14 03:52:53,202 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;142;142;142;140;138;139;138;138;129;139 03:52:53 INFO - 2016-01-14 03:52:53,202 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;65;65;62;62;62;61;63;62;62 03:52:53 INFO - 2016-01-14 03:52:53,202 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:52:53 INFO - 2016-01-14 03:52:53,202 DEBUG : BROWSER_OUTPUT: __start_cc_report 03:52:53 INFO - 2016-01-14 03:52:53,202 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,851 03:52:53 INFO - 2016-01-14 03:52:53,202 DEBUG : BROWSER_OUTPUT: __end_cc_report 03:52:53 INFO - 2016-01-14 03:52:53,202 DEBUG : BROWSER_OUTPUT: __startTimestamp1452772373198__endTimestamp 03:52:53 INFO - 2016-01-14 03:52:53,202 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,206 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:52:53 INFO - 2016-01-14 03:52:53,206 DEBUG : BROWSER_OUTPUT: Number of tests: 14 03:52:53 INFO - 2016-01-14 03:52:53,206 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,206 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.70 Median:110.50 stddev:3.02 (2.7%) stddev-sans-first:3.19 03:52:53 INFO - 2016-01-14 03:52:53,206 DEBUG : BROWSER_OUTPUT: Values: 108.0 111.0 105.0 109.0 110.0 111.0 102.0 111.0 109.0 111.0 03:52:53 INFO - 2016-01-14 03:52:53,207 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,207 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.60 Median:147.50 stddev:14.05 (9.5%) stddev-sans-first:14.71 03:52:53 INFO - 2016-01-14 03:52:53,207 DEBUG : BROWSER_OUTPUT: Values: 146.0 122.0 145.0 154.0 125.0 149.0 151.0 123.0 156.0 125.0 03:52:53 INFO - 2016-01-14 03:52:53,207 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,207 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.75 (1.1%) stddev-sans-first:1.83 03:52:53 INFO - 2016-01-14 03:52:53,207 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 162.0 160.0 159.0 160.0 161.0 165.0 161.0 161.0 03:52:53 INFO - 2016-01-14 03:52:53,207 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,207 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.20 Median:72.00 stddev:33.15 (46.0%) stddev-sans-first:34.70 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 71.0 140.0 72.0 73.0 72.0 72.0 159.0 70.0 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.50 Median:89.00 stddev:21.33 (24.0%) stddev-sans-first:22.06 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 136.0 88.0 90.0 90.0 130.0 90.0 89.0 134.0 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 109.0 110.0 110.0 110.0 111.0 109.0 110.0 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 147.0 148.0 147.0 146.0 147.0 146.0 147.0 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 95.0 96.0 96.0 96.0 96.0 95.0 96.0 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.00 stddev:1.43 (2.6%) stddev-sans-first:1.20 03:52:53 INFO - 2016-01-14 03:52:53,208 DEBUG : BROWSER_OUTPUT: Values: 53.0 55.0 58.0 55.0 56.0 57.0 56.0 56.0 54.0 55.0 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 64.0 63.0 63.0 64.0 64.0 62.0 63.0 63.0 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.60 Median:79.00 stddev:0.70 (0.9%) stddev-sans-first:0.71 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: Values: 78.0 78.0 79.0 79.0 78.0 79.0 80.0 78.0 79.0 78.0 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.90 Median:115.50 stddev:4.48 (3.9%) stddev-sans-first:4.50 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: Values: 117.0 110.0 119.0 109.0 116.0 109.0 118.0 108.0 108.0 115.0 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.70 Median:139.50 stddev:3.80 (2.7%) stddev-sans-first:3.84 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: Values: 142.0 142.0 142.0 140.0 138.0 139.0 138.0 138.0 129.0 139.0 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:62.50 stddev:1.34 (2.1%) stddev-sans-first:1.41 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: Values: 63.0 65.0 65.0 62.0 62.0 62.0 61.0 63.0 62.0 62.0 03:52:53 INFO - 2016-01-14 03:52:53,209 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:52:53 INFO - 2016-01-14 03:52:53,210 DEBUG : BROWSER_OUTPUT: 03:52:53 INFO - 2016-01-14 03:52:53,876 INFO : Browser exited with error code: 0 03:52:53 INFO - 2016-01-14 03:52:53,929 INFO : Completed test kraken (00:00:57) 03:52:53 INFO - 2016-01-14 03:52:53,929 INFO : Starting test v8_7 03:52:53 INFO - 2016-01-14 03:52:53,929 DEBUG : operating with platform_type : linux_ 03:52:53 INFO - 2016-01-14 03:52:53,929 INFO : Initialising browser for v8_7 test... 03:52:53 INFO - 2016-01-14 03:52:53,936 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkYAGdF/profile http://localhost:37033/getInfo.html 03:52:57 INFO - 2016-01-14 03:52:57,584 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:52:57 INFO - 2016-01-14 03:52:57,584 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:52:57 INFO - 2016-01-14 03:52:57,584 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:52:57 INFO - 2016-01-14 03:52:57,584 DEBUG : BROWSER_OUTPUT: __metrics 03:52:57 INFO - 2016-01-14 03:52:57,747 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] 03:52:58 INFO - 2016-01-14 03:52:58,940 INFO : Browser initialized. 03:52:58 INFO - 2016-01-14 03:52:58,940 INFO : Running cycle 1/1 for v8_7 test... 03:52:58 INFO - 2016-01-14 03:52:58,941 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkYAGdF/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:53:10 INFO - 2016-01-14 03:53:10,492 DEBUG : BROWSER_OUTPUT: RSS: Main: 128471040 03:53:10 INFO - 2016-01-14 03:53:10,492 DEBUG : BROWSER_OUTPUT: 03:53:31 INFO - 2016-01-14 03:53:31,917 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37033/tests/v8_7/run.html (next: http://localhost:37033/tests/v8_7/run.html) 03:53:32 INFO - 2016-01-14 03:53:32,164 DEBUG : BROWSER_OUTPUT: RSS: Main: 424669184 03:53:32 INFO - 2016-01-14 03:53:32,164 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,164 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:53:32 INFO - 2016-01-14 03:53:32,164 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:53:32 INFO - 2016-01-14 03:53:32,164 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |0;Richards;166.72224074691565 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.44486108743823 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.4076557697681 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5010 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |4;RayTrace;955.1098376313277 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |5;Earley;556.1735261401557 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |6;Boyer;10458.333333333334 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |7;RegExp;23744.18604651163 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |8;Splay;518.2707153885743 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 03:53:32 INFO - 2016-01-14 03:53:32,165 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: __startTimestamp1452772412163__endTimestamp 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: Number of tests: 10 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.72 Median:166.72 stddev:0.00 (0.0%) 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: Values: 166.7 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.44 Median:170.44 stddev:0.00 (0.0%) 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: Values: 170.4 03:53:32 INFO - 2016-01-14 03:53:32,166 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,167 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.41 Median:279.41 stddev:0.00 (0.0%) 03:53:32 INFO - 2016-01-14 03:53:32,167 DEBUG : BROWSER_OUTPUT: Values: 279.4 03:53:32 INFO - 2016-01-14 03:53:32,167 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,167 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5010.00 Median:5010.00 stddev:0.00 (0.0%) 03:53:32 INFO - 2016-01-14 03:53:32,167 DEBUG : BROWSER_OUTPUT: Values: 5010.0 03:53:32 INFO - 2016-01-14 03:53:32,167 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,167 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:955.11 Median:955.11 stddev:0.00 (0.0%) 03:53:32 INFO - 2016-01-14 03:53:32,167 DEBUG : BROWSER_OUTPUT: Values: 955.1 03:53:32 INFO - 2016-01-14 03:53:32,167 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,167 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:556.17 Median:556.17 stddev:0.00 (0.0%) 03:53:32 INFO - 2016-01-14 03:53:32,168 DEBUG : BROWSER_OUTPUT: Values: 556.2 03:53:32 INFO - 2016-01-14 03:53:32,168 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,168 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10458.33 Median:10458.33 stddev:0.00 (0.0%) 03:53:32 INFO - 2016-01-14 03:53:32,168 DEBUG : BROWSER_OUTPUT: Values: 10458.3 03:53:32 INFO - 2016-01-14 03:53:32,168 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,169 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23744.19 Median:23744.19 stddev:0.00 (0.0%) 03:53:32 INFO - 2016-01-14 03:53:32,169 DEBUG : BROWSER_OUTPUT: Values: 23744.2 03:53:32 INFO - 2016-01-14 03:53:32,169 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,169 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:518.27 Median:518.27 stddev:0.00 (0.0%) 03:53:32 INFO - 2016-01-14 03:53:32,169 DEBUG : BROWSER_OUTPUT: Values: 518.3 03:53:32 INFO - 2016-01-14 03:53:32,169 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,169 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 03:53:32 INFO - 2016-01-14 03:53:32,170 DEBUG : BROWSER_OUTPUT: Values: 4817.3 03:53:32 INFO - 2016-01-14 03:53:32,170 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:53:32 INFO - 2016-01-14 03:53:32,170 DEBUG : BROWSER_OUTPUT: 03:53:32 INFO - 2016-01-14 03:53:32,924 INFO : Browser exited with error code: 0 03:53:32 INFO - 2016-01-14 03:53:32,940 INFO : Completed test v8_7 (00:00:39) 03:53:33 INFO - 2016-01-14 03:53:33,168 INFO : Completed test suite (00:20:07) 03:53:33 INFO - 2016-01-14 03:53:33,169 DEBUG : Working with test: dromaeo_css 03:53:33 INFO - 2016-01-14 03:53:33,169 DEBUG : Generating results file: dromaeo_css 03:53:33 INFO - 2016-01-14 03:53:33,170 DEBUG : Working with test: kraken 03:53:33 INFO - 2016-01-14 03:53:33,170 DEBUG : Generating results file: kraken 03:53:33 INFO - 2016-01-14 03:53:33,171 INFO : javascript benchmark 03:53:33 INFO - 2016-01-14 03:53:33,171 DEBUG : Working with test: v8_7 03:53:33 INFO - 2016-01-14 03:53:33,171 DEBUG : Generating results file: v8_7 03:53:33 INFO - 2016-01-14 03:53:33,172 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:53:33 INFO - 2016-01-14 03:53:33,223 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 03:53:33 INFO - 2016-01-14 03:53:33,223 DEBUG : process_Request line: dromaeo_css 7202.16 graph.html#tests=[[72,53,35]] 03:53:33 INFO - 2016-01-14 03:53:33,223 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:53:33 INFO - 2016-01-14 03:53:33,253 DEBUG : process_Request line: kraken 1462.30 graph.html#tests=[[232,53,35]] 03:53:33 INFO - 2016-01-14 03:53:33,253 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:53:33 INFO - 2016-01-14 03:53:33,282 DEBUG : process_Request line: v8_7 23313.94 graph.html#tests=[[230,53,35]] 03:53:33 INFO - 2016-01-14 03:53:33,283 INFO : javascript benchmark 03:53:33 INFO - 2016-01-14 03:53:33,287 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3293.785903916246, 3362.1090411041064, 3386.136535183528, 3389.322135572886, 3413.146713321669, 5554.50009499145, 5554.750036248369, 5563.109902415615, 5564.8330550083465, 5573.5819813513945, 5576.888462230753, 5586.050371436855, 5599.216109744639, 5612.35457922338, 5620.269364982557, 4993.375828021497, 5089.236614507817, 5113.872153196163, 5155.123628983063, 5222.712750798704, 4990.05189014088, 5141.485851414866, 5179.559737422323, 5186.870328241786, 5188.818391356303, 4998.200287953928, 5111.897762044757, 5114.488551144893, 5116.258142569344, 5126.102931986888, 5154.793808247666, 5183.714895680736, 5185.403678576955, 5198.5841132709675, 5200.1939699346285, 4880.560749532559, 4934.08719386911, 4952.331435256267, 5098.4136824265315, 5119.411267704188, 5406.702631355274, 5442.374126975369, 5442.6462279952075, 5465.0, 5471.452854714496, 5971.462568368825, 5972.283326000865, 5980.491658209014, 6064.514838812885, 6079.0, 5812.796552120655, 5874.295084589877, 5947.553933454965, 5993.0, 6023.548233882477, 5587.944120558783, 5590.8043218487155, 5602.747876345574, 5604.6917419541505, 5621.128725047624, 6041.486473649701, 6049.243844519435, 6051.485623721945, 6054.424829641177, 6064.605800622945, 5713.571482138773, 5823.79616713413, 5834.212381328552, 5914.526837852962, 5930.406959304035, 5982.132590774313, 6075.665838378844, 6124.5100391968535, 6131.724072416752, 6135.631862088289, 5725.169850371756, 6008.489278411296, 6028.668423236676, 6083.057126145454, 6112.235970503687, 5759.6832174229985, 5877.853053673607, 6003.879922401527, 6013.6993150342305, 6013.969930150409, 4624.514425985223, 4627.421572303462, 4628.930566041512, 4636.6058884995155, 4647.512011238839, 4036.354183330653, 4126.339785634284, 4159.729617574848, 4166.979165104155, 4189.455370801777, 5607.102863541813, 5646.20953066571, 5665.2635157430095, 5670.971645141831, 5694.373618901917, 6039.728212230372, 6075.3924607539775, 6095.116208149882, 6096.0, 6099.085137229363, 5501.587380946445, 5558.416366281563, 5616.803411880564, 5705.144228365695, 5719.656820590778, 5303.15149576066, 5311.097113490716, 5312.017276803804, 5313.282706834605, 5315.362156541162, 6264.686765661699, 6267.2792628847355, 6269.373062693694, 6276.686165691697, 6285.937140628626, 5793.739281732248, 5818.359980402237, 5924.733386997509, 6020.668863212565, 6133.202683651097, 6059.72731227087, 6068.362821903636, 6070.423309785563, 6076.605020673642, 6170.50635949114, 5966.522678185822, 5973.372795856546, 5976.073708575178, 5978.133170690138, 5981.401859813984, 5823.301203855564, 5901.262342207224, 5934.109883517334, 6020.066889632114, 6022.45797878193, 5827.6794776286015, 5828.825135245949, 5829.970850145722, 5836.854078648068, 5877.911831322448, 5637.238972738628, 5818.505427038749, 5825.650460972355, 5830.358660547252, 5837.503812176013, 5610.831675049755, 5668.2064510967675, 5670.886582268414, 5687.886242275049, 5713.8285851424525, 5921.733521991607, 5929.703514824328, 5938.465538101592, 5946.643201407929, 5953.0, 5567.944320556905, 5691.7438715257085, 5809.593328466966, 5814.7964821232745, 5824.271966004249], "unit": "ms", "name": "dojo.html", "value": 5524.846556411255}, {"lowerIsBetter": false, "replicates": [581.8021872563328, 626.3830127324585, 633.4932054356515, 634.1502386801687, 636.6148480169501, 34345.82827085864, 40043.39934900975, 40516.18967620646, 40553.39169912446, 40553.79723101388, 43786.0, 44820.1035979281, 44868.775656121674, 44905.77547112268, 44948.55051449485, 8084.797880052987, 8201.056878458963, 9335.85996210059, 9488.810223795519, 9513.048695130467, 11056.889431105667, 11263.887361126366, 11382.943085284562, 11392.088632909348, 11397.259178153436, 10986.285891417072, 11066.889331106666, 11081.72295692606, 11082.778344433107, 11085.057770089512, 11048.613298534552, 11145.832812507779, 11148.554057837677, 11150.0, 11165.609203677874, 10838.945805270923, 10960.28758130719, 10976.012158905656, 10977.176711746652, 10987.450627468595, 42913.35629965522, 43582.128357432994, 43609.78195109036, 43795.34306985366, 43944.121117577786, 3527.2416430467315, 3560.626134255918, 3569.982150089233, 3605.152789094561, 3693.833777479993, 32770.18074548132, 33097.83451082753, 33157.3368532628, 33207.33585328304, 33480.330393392236, 3663.981680091609, 3667.266546690646, 3716.108134047809, 3718.535183102112, 3759.285735710206, 303224.0, 304094.0, 304343.9999999978, 304876.0, 305251.0, 64892.0, 66819.33180668228, 67800.6609966947, 68222.65888670525, 68246.0, 77182.0, 77325.0, 77474.0, 78111.60944195243, 78149.60925195452, 16633.417830375878, 16774.83225167757, 16782.91608541974, 16845.663086738197, 16940.915295423445, 14331.85668143326, 14402.495912643008, 14411.279436028157, 14414.927925360305, 14429.278536073154, 21565.137394504043, 21774.346769596938, 21818.672719909213, 21824.672629910565, 21837.78162218389, 8507.489550626982, 8620.310375169971, 8716.259117974916, 8803.559822008874, 8883.955580222058, 1054.446415631784, 1074.3178081918038, 1077.5851297250615, 1127.9548818047185, 1155.526234243956, 33499.665003349655, 33805.32389352249, 33867.15332116717, 34009.829950850086, 34018.82990585031, 1144.6508814811561, 1195.838561794164, 1195.9581414650445, 1197.5209916033657, 1219.3415555599877, 14230.417692626557, 16627.916860415862, 17153.228104735317, 17433.56416089608, 17652.20565074549, 378423.0, 379562.0, 381094.0, 382002.0, 382041.0, 59530.40469595335, 59575.702121489114, 59651.40348596458, 59672.10491842626, 59832.40167598355, 1485.7994170786806, 1490.5304828978838, 1494.6562290673205, 1512.0, 1539.638185026518, 28205.0, 30957.535636965913, 31245.687543124277, 31772.364552709278, 31790.205244869063, 55542.16686749621, 55580.44419555915, 55632.721836390556, 55633.165502518306, 55816.44183558112, 251400.0, 252547.0, 253680.0, 253860.0, 254104.0, 31157.376852463276, 31311.84344078265, 34811.65188348084, 34973.65026349704, 35052.474212886314, 41629.0, 41756.3736543946, 41776.0, 42609.5739042618, 42621.57378426176, 35886.0, 36306.0, 36792.26415471622, 36813.44779828358, 37039.0, 37927.0, 38595.421068684554, 40004.599954000085, 40096.1980760389, 40596.0, 36261.81869090637, 36445.27109457849, 36652.63347366599, 37973.240535189696, 38342.42486362651, 32113.0, 32276.83861580771, 32654.836725817167, 32693.346133076728, 33266.83366583152, 25515.6172657414, 26081.217563473125, 26364.340891477867, 27271.0, 27538.311542211606], "unit": "ms", "name": "ext.html", "value": 20430.426567178085}, {"lowerIsBetter": false, "replicates": [365.38432740831684, 375.24762850484774, 376.9849206031758, 383.0614993266496, 387.5388287937353, 16817.579560510978, 19099.236030558797, 19252.807471925276, 19298.131584078714, 20822.375328740163, 18714.812851871477, 18737.0, 18910.62178756424, 19570.510737231576, 19580.21679132833, 4332.24185767491, 4434.844780432685, 5695.971520142404, 5775.740091695863, 5829.621074630157, 4443.688941774077, 6127.754889804425, 6316.30520642729, 6340.144080549136, 6362.809115726513, 4982.128127577677, 5046.21783623539, 5109.054824857413, 6259.186305780242, 6273.3726627337355, 5081.237814327862, 5081.669691470042, 5081.720505372203, 5085.516875896802, 5135.820246291362, 4582.679212455129, 4615.076984603093, 4618.307253911907, 4618.792154353062, 4623.606993405565, 13807.378667959865, 14031.43874245029, 14648.340824662915, 15059.397624095023, 15071.924640376837, 2195.0, 2203.0857194264363, 2212.358416059341, 2212.8782916939563, 2298.28753086542, 10800.837987430195, 10984.505697243563, 11229.887701122965, 11321.094312454983, 11400.543978240861, 1789.0249813851483, 1811.1216060210918, 1823.7264410338423, 1843.797182309945, 1892.7255547945608, 65417.672911635134, 65576.34423655798, 65589.0, 65618.67190664064, 66198.66900665466, 7910.367170626336, 7937.960310198528, 7964.123946365895, 7987.9615649965135, 8043.638036288381, 9630.036996300314, 9645.08371704687, 9668.70993870185, 9670.806583868283, 9676.951615241878, 6324.494040476751, 6333.1766870306565, 6369.267534233622, 6503.739850406026, 6508.446782023487, 6779.084823548857, 6828.2147553031045, 6829.248782633907, 6866.622335771481, 6875.931240687629, 3221.6778322168066, 3302.53764472974, 3557.8221088945966, 3638.3450978824067, 3682.889513314551, 1272.4973635414144, 1276.183242724657, 1290.1871820753054, 1298.8181654568368, 1335.8129861819352, 522.9424763276037, 524.9527542521191, 525.8921921006184, 532.4063669009028, 550.5430492691137, 12965.35173241334, 13319.600411987656, 13425.462981480827, 13478.39347229377, 13513.324333783272, 547.250267134021, 553.4189101443468, 559.1082223852948, 561.50026476436, 564.0889962710194, 7263.0921134858145, 7271.490995630255, 7305.707771689073, 7414.14737305206, 7442.702291908263, 620284.0, 627757.0, 627835.0, 632482.0, 635646.0, 78701.0, 83617.58191209006, 83756.0, 84528.0, 84779.0, 565.3667891960948, 566.3769853161572, 567.611186337352, 568.4486048532999, 569.7265312649952, 11945.462454189408, 12060.155789094679, 12118.636440906786, 12130.393480326125, 12134.0, 36846.26307473889, 37086.44370334501, 37218.0, 38411.2317753649, 38456.0, 49707.50292497173, 49956.50043499664, 51038.234426482886, 51232.74383628207, 52074.73962630163, 7235.674394652148, 7247.0, 7252.4560657949805, 7258.600776957318, 7282.6722797473185, 10713.089387401846, 10997.670069897917, 11049.226554141454, 11445.25605835618, 12052.578159764365, 10889.0, 10960.616378426717, 11063.557457701601, 11173.0, 11189.720256993314, 10703.304285221435, 10729.517171727135, 10767.3539587625, 10808.297460665031, 10984.121270298518, 9332.626694932398, 10828.296160749525, 11039.0, 11129.054030407182, 11187.216894817284, 16227.756583651018, 16461.34154633801, 16501.83498165051, 16696.66606667884, 16708.74936875972, 17121.0, 17214.569635758704, 17236.655266894842, 17405.99831019449, 17558.648827023644], "unit": "ms", "name": "jquery.html", "value": 7910.454031878812}, {"lowerIsBetter": false, "replicates": [1546.3659899441225, 1554.261725680302, 1804.548862784304, 1870.625874825035, 1879.2295158984814, 7006.964965175167, 7113.217546069928, 7727.690892364306, 7869.370450363965, 7898.763037108882, 7717.421193410502, 7899.0126234220725, 7943.602819858998, 7960.402969777261, 8010.639521221545, 2339.6841426407473, 2372.54921564903, 2383.4518060846017, 2386.486905315357, 2391.7129944406697, 2088.6866969954476, 2129.2547608337113, 2308.3305841306, 2362.1850461590725, 2378.52429514097, 2303.285981345788, 2320.431494283894, 2328.278233747535, 2331.743508214095, 2331.7668233176714, 2346.049849810821, 2367.2543148908126, 2369.277370402027, 2371.8932648030795, 2373.679553260305, 2330.4290448839974, 2365.5150694107665, 2369.5260947810507, 2369.822263330257, 2371.8221133415063, 8003.559804210766, 8049.637766300472, 8050.879236811453, 8056.073551541589, 8058.959705201495, 4741.193997020515, 4811.109944660249, 4811.4707382187935, 4813.614910807127, 4818.662693611449, 2998.670146283907, 3066.9233269168226, 3069.907902762921, 3078.7998780079224, 3082.614673165854, 966.9371490853142, 980.6273616025865, 996.3374356053289, 998.5007496251874, 1002.2082554781714, 10579.947100264526, 10587.0, 10590.470476476145, 10599.099076578577, 10605.469726513644, 4413.44831896013, 4439.445069366329, 4474.709143905636, 4479.664025198123, 4489.551044895549, 9106.772330691652, 9115.954420227856, 9190.988991210961, 9210.401323913924, 9218.44689318643, 4860.8298709545, 4876.341693871282, 4879.536444037811, 4886.169351210303, 4913.48408417111, 4416.447944006999, 4455.888602784957, 4473.485549161888, 4501.774911254424, 4514.142312960527, 3585.8386372613295, 3843.5195600549932, 3873.0511024799102, 3914.4911161549385, 3937.665298449607, 1140.5836869542723, 1225.081189108169, 1253.9310337931502, 1255.706622179157, 1263.437770192274, 1122.854028976228, 1188.8276199950958, 1428.748727877475, 1460.3063544816127, 1461.7222727681706, 7559.773206803805, 7657.425693073042, 7681.308682218627, 7683.615819209017, 7687.192844751333, 1215.7082300247878, 1281.1864466064117, 1317.3742472325569, 1333.893288536934, 1346.7845144776788, 5978.163057172, 6022.397760223943, 6025.487833534111, 6030.608010479393, 6030.909536356959, 9070.138336857988, 9458.810823783486, 9463.05369463062, 9473.28950328728, 9481.336306458481, 9277.58250878712, 9354.953225233967, 9363.625454981724, 9370.812583748288, 9388.2020028297, 6856.41720453767, 6938.098047253926, 6956.513044086966, 6970.233274339818, 7257.419406447577, 7463.589502577411, 7543.245675432632, 7568.5458872467825, 7577.242275772379, 7585.924140758521, 8385.538795366314, 8492.017503546647, 8492.363072769691, 8547.888774459281, 8586.441881277697, 8507.361947853811, 8621.612027458905, 8622.310215182644, 8622.482651040958, 8645.697400590949, 7294.088238970129, 7340.779776606711, 7365.373943214887, 7370.226126256666, 7373.8525229494035, 6879.174499060145, 7170.605616691132, 7901.407394445325, 7973.282404583615, 7983.1617680142745, 7399.963000184965, 7414.592197429193, 7454.291842274975, 7466.589337586485, 7489.325960663567, 7225.602591857288, 7312.0128782613665, 7315.63421828917, 7358.595277259802, 7408.703651854081, 7480.775576732707, 7485.47601667878, 7513.887291690519, 7528.021357223526, 7539.0, 6841.692123854339, 6878.105846239957, 6890.10428644273, 6902.585844849325, 6907.620080895599, 7091.893621595577, 7102.680379383043, 7104.289571043061, 7112.786616401516, 7146.464015198777], "unit": "ms", "name": "mootools.html", "value": 4656.715771035997}, {"lowerIsBetter": false, "replicates": [363.26076434455877, 364.74650118167887, 367.2123295531086, 367.6984872404628, 373.90278527582836, 6022.728977196026, 6074.483668888144, 6100.298465676454, 6112.144299798032, 6121.0, 5876.353601103886, 5973.910391344122, 6021.337652858182, 6033.69831508425, 6057.151998720172, 4743.786529606167, 4748.0978614063215, 4748.240281554952, 4753.691010084333, 4755.714657120584, 4574.519675434081, 4667.113248482794, 4696.64775141864, 4759.000609871931, 4761.262004389325, 4724.858254252378, 4727.787249573777, 4731.408573928259, 4761.976175122348, 4765.404324459442, 4709.693869898446, 4729.952700472985, 4730.645201609858, 4731.621470282386, 4733.905321893543, 4203.47456567929, 4211.178820130076, 4211.97894010531, 4212.641925436342, 4216.894577635553, 5706.229658996024, 5729.026065568822, 5732.5127364174095, 5812.476877081083, 5820.330661973842, 5425.37608175061, 5430.56555475561, 5434.673919564839, 5434.728263586844, 5444.183372494118, 6203.131561581382, 6445.4199122079235, 6459.612423254573, 6459.61242325462, 6465.83835404114, 4425.491068527096, 4503.774811259456, 4572.51988541205, 4577.56513131252, 4580.679352445331, 6538.051982462516, 6567.080608714784, 6623.635700036496, 6627.469802415795, 6635.867282654368, 4683.039976804746, 4684.95315046852, 4685.1800934836265, 4691.296305554126, 4691.788869500812, 4619.284010978304, 4690.296455531698, 4701.741404222732, 4737.0, 4749.311349854252, 5388.973055134699, 5391.110466773017, 5391.541718953933, 5392.433794451605, 5395.05586522357, 6475.676216189266, 6476.158099447041, 6477.222733271943, 6482.967585162044, 6488.578242414228, 1707.692615329228, 1732.7487514310355, 1732.9133543322782, 1733.1507561295127, 1735.3665911942303, 1138.1861968692424, 1155.907527397823, 1157.9594714184964, 1158.8667303260065, 1185.8221266810046, 6172.703732216285, 6184.938150618436, 6187.566870319034, 6191.752329906753, 6201.255849298113, 6552.770653027216, 6557.803265901935, 6614.239362473281, 6617.9669101655145, 6619.900701489481, 6141.048137538689, 6181.567290289637, 6182.103594978703, 6190.0, 6215.192025036717, 3704.148045949447, 3709.3694072007825, 3737.7570457920147, 3738.3084129435597, 3757.9812100939866, 6864.519483636197, 6874.621895795779, 6877.518573699893, 6884.759033433806, 6887.724491020404, 6812.216595091628, 6819.24988251292, 6886.0, 6891.483138764613, 6893.551919125241, 6879.0, 6879.793606191823, 6886.827829304308, 6893.517453778188, 6907.171139463196, 6547.247066587403, 6627.900581491378, 6631.0, 6735.696893639896, 6742.69657865407, 6596.604203747598, 6606.174228221473, 6621.503387246072, 6637.4690024798865, 6650.068990341259, 6835.829104272234, 6837.965810170917, 6840.726370945106, 6841.281665425058, 6842.281560436082, 4612.192866248461, 4661.743604101807, 4757.714537127784, 4848.418189817104, 4863.465018847854, 6617.636030018395, 6652.600843949379, 6655.567388119756, 6660.56706314088, 6661.233958094881, 6459.967700161469, 6485.967570162307, 6517.804465866032, 6518.087467754423, 6521.184851893513, 6407.568244377804, 6431.389018043278, 6497.4477169441125, 6500.447461965786, 6506.41442270198, 6330.113784070326, 6374.649394283359, 6379.74481020754, 6397.232332120176, 6431.807045788634, 6575.934240657532, 6590.670466476561, 6607.900881486686, 6620.404163625104, 6624.072629831924, 6294.15028971083, 6404.775832845828, 6451.2903580607035, 6452.903206451813, 6466.644334561456], "unit": "ms", "name": "prototype.html", "value": 4928.265985964172}, {"lowerIsBetter": false, "replicates": [812.3298278919889, 822.2312138150829, 887.3522328700049, 898.4654130792177, 961.3462845265226, 15152.62118447038, 15181.92409037956, 15257.694846103072, 15287.69424611507, 15298.0, 12870.742585148291, 12926.09517333787, 12931.870681293185, 12955.09314347996, 12959.092863499558, 785.4344871692373, 794.3684770607352, 920.6409500294891, 923.5336155241628, 942.1661829281102, 934.8410770169088, 939.6523286384047, 946.0, 946.0114180681205, 946.8153710026513, 815.0993152566388, 981.1660088924428, 984.6553706202808, 990.3859607043607, 990.7968866382411, 819.6229734322189, 964.3972517176765, 966.5505539923969, 967.569431602937, 983.5573991703726, 714.4641518860855, 715.8460930899829, 721.76542623647, 724.807925899637, 726.1358982810439, 14924.403023879031, 15086.924565377212, 15105.244737763069, 15107.32017059235, 15112.244387780567, 13368.0, 13368.665783355495, 13632.318384080756, 13687.24720140392, 13775.311234438239, 11217.551297948072, 11390.943045284721, 11599.594014209462, 11617.94191029048, 11766.176367654267, 3829.061879839457, 3908.1402091539817, 3936.9212615747524, 3941.7043721720697, 3950.0, 52817.0, 52970.999999999614, 53090.0, 53178.0, 53519.73240133814, 10791.568337266579, 10883.074938630305, 11005.724856878642, 11150.944245278723, 11189.216754827246, 20133.49666258326, 20159.798402015793, 20163.798362016485, 20174.0, 20205.292814751414, 8133.471324363897, 8212.17878212174, 8476.703315383931, 8611.39720219591, 8652.178043085896, 4681.789319480631, 4704.717716936994, 4716.858494245178, 4781.569658730731, 4825.638077144228, 3178.475551534017, 3230.78999865008, 3233.11089450403, 3233.66046565112, 3239.6436391996863, 486.7858142417325, 489.82611173033774, 492.25423483422645, 492.7610109097085, 498.50896866586385, 573.8708790522182, 641.2913730328014, 664.4983037806522, 680.7379159023712, 720.3624792058961, 8032.196780321921, 8170.346372290202, 8214.671413143407, 8298.419110662317, 8368.3305335573, 527.4540850220004, 584.9151872978395, 687.656171914043, 716.8924661300742, 724.5326764237037, 4219.6202341789385, 4251.553586873334, 4265.1469706058915, 4266.512846778464, 4271.487421509377, 67552.32447675559, 67744.0, 70366.296337037, 70510.0, 70874.2912570878, 57842.0, 58503.70748146232, 58894.0, 58909.705451472466, 59455.40544594585, 54027.729861351225, 54225.1866222007, 54412.455875441534, 54808.17787733187, 55211.44788552143, 8824.117588241124, 8854.335924805795, 8973.01296857332, 9076.152513514411, 9103.317251206054, 37863.43204851874, 40407.1918561633, 40744.388834166915, 41180.1763964725, 41585.0, 51627.2255916154, 51817.48182518127, 52101.0, 53086.73456632692, 53823.46176538184, 8578.571071446528, 8585.742427727178, 8667.609957551798, 8828.735137945616, 8954.641814327353, 13332.466701332227, 13519.0, 13832.930835345758, 13983.370748316554, 14078.788818167532, 12275.386230688608, 12288.938555307166, 12530.812037819622, 12612.810807838074, 12644.557440489538, 12312.630621081382, 12335.136540442083, 12524.624261271812, 12618.684532886751, 13124.934375328445, 10470.581176753149, 11869.827031395924, 11923.76152476963, 12010.39948002579, 12358.382080896099, 10519.26365154432, 10637.725356534182, 10778.245522813642, 10939.945300273448, 10954.068904143238, 8528.403011789364, 10515.631952881613, 10942.45287735626, 10966.396848173426, 10983.890161098287], "unit": "ms", "name": "yui.html", "value": 6810.247155974062}], "name": "dromaeo_css", "value": 7202.152612321952}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 111.0, 105.0, 109.0, 110.0, 111.0, 102.0, 111.0, 109.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 108.7}, {"lowerIsBetter": true, "replicates": [146.0, 122.0, 145.0, 154.0, 125.0, 149.0, 151.0, 123.0, 156.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.6}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 162.0, 160.0, 159.0, 160.0, 161.0, 165.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.8}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 71.0, 140.0, 72.0, 73.0, 72.0, 72.0, 159.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 87.2}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 136.0, 88.0, 90.0, 90.0, 130.0, 90.0, 89.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 102.5}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0, 111.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 147.0, 148.0, 147.0, 146.0, 147.0, 146.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.6}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [53.0, 55.0, 58.0, 55.0, 56.0, 57.0, 56.0, 56.0, 54.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 55.5}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 64.0, 63.0, 63.0, 64.0, 64.0, 62.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.2}, {"lowerIsBetter": true, "replicates": [78.0, 78.0, 79.0, 79.0, 78.0, 79.0, 80.0, 78.0, 79.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.6}, {"lowerIsBetter": true, "replicates": [117.0, 110.0, 119.0, 109.0, 116.0, 109.0, 118.0, 108.0, 108.0, 115.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.9}, {"lowerIsBetter": true, "replicates": [142.0, 142.0, 142.0, 140.0, 138.0, 139.0, 138.0, 138.0, 129.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.7}, {"lowerIsBetter": true, "replicates": [63.0, 65.0, 65.0, 62.0, 62.0, 62.0, 61.0, 63.0, 62.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.7}], "name": "kraken", "value": 1462.3000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.72224074691565], "unit": "score", "name": "Richards", "value": 211.74139599999998}, {"lowerIsBetter": false, "replicates": [170.44486108743823], "unit": "score", "name": "DeltaBlue", "value": 387.91430599999984}, {"lowerIsBetter": false, "replicates": [279.4076557697681], "unit": "score", "name": "Encrypt", "value": 952.6617989999997}, {"lowerIsBetter": false, "replicates": [5010.0], "unit": "score", "name": "Decrypt", "value": 53.12994011976049}, {"lowerIsBetter": false, "replicates": [955.1098376313277], "unit": "score", "name": "RayTrace", "value": 774.7684829999998}, {"lowerIsBetter": false, "replicates": [556.1735261401557], "unit": "score", "name": "Earley", "value": 1198.3004740000003}, {"lowerIsBetter": false, "replicates": [10458.333333333334], "unit": "score", "name": "Boyer", "value": 63.725545816733074}, {"lowerIsBetter": false, "replicates": [23744.18604651163], "unit": "score", "name": "RegExp", "value": 38.36665523996084}, {"lowerIsBetter": false, "replicates": [518.2707153885743], "unit": "score", "name": "Splay", "value": 157.236358490566}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23313.942818875326}]} 03:53:33 INFO - RETURN: dromaeo_css: 7202.16 03:53:33 INFO - RETURN: kraken: 1462.30 03:53:33 INFO - RETURN: v8_7: 23313.94 03:53:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23313.94"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1462.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7202.16"}}} 03:53:33 INFO - Return code: 0 03:53:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 03:53:33 INFO - # TBPL SUCCESS # 03:53:33 INFO - Running post-action listener: _resource_record_post_action 03:53:33 INFO - Running post-run listener: _resource_record_post_run 03:53:34 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 598016; Write bytes: 257769472; Read time: 164; Write time: 146748 03:53:34 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 1458176; Read time: 0; Write time: 88 03:53:34 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 598016; Write bytes: 256311296; Read time: 164; Write time: 146660 03:53:34 INFO - Running post-run listener: _upload_blobber_files 03:53:34 INFO - Blob upload gear active. 03:53:34 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 03:53:34 INFO - Copying logs to upload dir... 03:53:34 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1264.020090 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-01-14 03:53:34.483910) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-14 03:53:34.491052) ========= 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=52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009940 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452766055/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-14 03:53:34.545657) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 03:53:34.546062) ========= 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=52faae308a556c440401f8e5000001e8-1452771085.546594-1155521916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004469 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 03:53:34.595418) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 03:53:34.595737) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 03:53:34.596040) ========= ========= Total master_lag: 0.17 =========