builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1451600189.04 results: success (0) buildid: 20151231120338 builduid: d20c36d2241e4e2aa21f5b49f0233f31 revision: a602dbc1a620 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.040946) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.041329) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.041606) ========= 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=01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006370 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.067519) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.067805) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.102624) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.102952) ========= 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=01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004584 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.153280) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.153637) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.154000) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.154269) ========= 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=01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-31 14:16: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.4M=0s 2015-12-31 14:16:29 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159942 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.326284) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.326630) ========= 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=01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022219 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.377292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.377681) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a602dbc1a620 --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 a602dbc1a620 --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=01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-31 14:16:29,821 Setting DEBUG logging. 2015-12-31 14:16:29,821 attempt 1/10 2015-12-31 14:16:29,821 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a602dbc1a620?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-31 14:16:30,066 unpacking tar archive at: mozilla-beta-a602dbc1a620/testing/mozharness/ program finished with exit code 0 elapsedTime=0.520444 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.911949) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.912320) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.921728) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.922110) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-31 14:16:29.922713) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2015-12-31 14:16:29.923024) ========= /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=01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:16:30 INFO - MultiFileLogger online at 20151231 14:16:30 in /builds/slave/test-pgo 14:16: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 14:16:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 14:16:30 INFO - {'append_to_log': False, 14:16:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 14:16:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:16:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 14:16:30 INFO - 'branch': 'Mozilla-Beta', 14:16:30 INFO - 'buildbot_json_path': 'buildprops.json', 14:16:30 INFO - 'config_files': ('talos/linux_config.py',), 14:16:30 INFO - 'default_actions': ('clobber', 14:16:30 INFO - 'read-buildbot-config', 14:16:30 INFO - 'download-and-extract', 14:16:30 INFO - 'populate-webroot', 14:16:30 INFO - 'create-virtualenv', 14:16:30 INFO - 'install', 14:16:30 INFO - 'run-tests'), 14:16:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:16:30 INFO - 'download_minidump_stackwalk': True, 14:16:30 INFO - 'download_symbols': 'ondemand', 14:16:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:16:30 INFO - 'tooltool.py': '/tools/tooltool.py', 14:16:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:16:30 INFO - '/tools/misc-python/virtualenv.py')}, 14:16:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:16:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:16:30 INFO - 'installer_path': 'installer.exe', 14:16:30 INFO - 'log_level': 'info', 14:16:30 INFO - 'log_name': 'talos', 14:16:30 INFO - 'log_to_console': True, 14:16:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:16:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:16:30 INFO - 'opt_config_files': (), 14:16:30 INFO - 'pip_index': False, 14:16:30 INFO - 'sps_profile': False, 14:16:30 INFO - 'sps_profile_interval': 0, 14:16:30 INFO - 'suite': 'dromaeojs', 14:16:30 INFO - 'system_bits': '32', 14:16:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:16:30 INFO - 'title': 'talos-linux64-ix-014', 14:16:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:16:30 INFO - 'use_talos_json': True, 14:16:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 14:16:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:16:30 INFO - 'work_dir': 'build'} 14:16:30 INFO - ##### 14:16:30 INFO - ##### Running clobber step. 14:16:30 INFO - ##### 14:16:30 INFO - Running pre-action listener: _resource_record_pre_action 14:16:30 INFO - Running main action method: clobber 14:16:30 INFO - rmtree: /builds/slave/test-pgo/build 14:16:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 14:16:30 INFO - Running post-action listener: _resource_record_post_action 14:16:30 INFO - ##### 14:16:30 INFO - ##### Running read-buildbot-config step. 14:16:30 INFO - ##### 14:16:30 INFO - Running pre-action listener: _resource_record_pre_action 14:16:30 INFO - Running main action method: read_buildbot_config 14:16:30 INFO - Using buildbot properties: 14:16:30 INFO - { 14:16:30 INFO - "properties": { 14:16:30 INFO - "buildnumber": 57, 14:16:30 INFO - "product": "firefox", 14:16:30 INFO - "script_repo_revision": "production", 14:16:30 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:16:30 INFO - "repository": "", 14:16:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:16:30 INFO - "buildid": "20151231120338", 14:16:30 INFO - "slavename": "talos-linux64-ix-014", 14:16:30 INFO - "pgo_build": "True", 14:16:30 INFO - "basedir": "/builds/slave/test-pgo", 14:16:30 INFO - "project": "", 14:16:30 INFO - "platform": "ubuntu64_hw", 14:16:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:16:30 INFO - "slavebuilddir": "test", 14:16:30 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:16:30 INFO - "branch": "mozilla-beta", 14:16:30 INFO - "repo_path": "releases/mozilla-beta", 14:16:30 INFO - "stage_platform": "linux64-pgo", 14:16:30 INFO - "builduid": "d20c36d2241e4e2aa21f5b49f0233f31", 14:16:30 INFO - "revision": "a602dbc1a620" 14:16:30 INFO - }, 14:16:30 INFO - "sourcestamp": { 14:16:30 INFO - "repository": "", 14:16:30 INFO - "hasPatch": false, 14:16:30 INFO - "project": "", 14:16:30 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:16:30 INFO - "changes": [ 14:16:30 INFO - { 14:16:30 INFO - "category": null, 14:16:30 INFO - "files": [ 14:16:30 INFO - { 14:16:30 INFO - "url": null, 14:16:30 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.tar.bz2" 14:16:30 INFO - } 14:16:30 INFO - ], 14:16:30 INFO - "repository": "", 14:16:30 INFO - "rev": "a602dbc1a620", 14:16:30 INFO - "who": "sendchange", 14:16:30 INFO - "when": 1451600181, 14:16:30 INFO - "number": 7407308, 14:16:30 INFO - "comments": "Backed out changeset d130c7ff6c2f (bug 1235605) for build bustage a=backout", 14:16:30 INFO - "project": "", 14:16:30 INFO - "at": "Thu 31 Dec 2015 14:16:21", 14:16:30 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:16:30 INFO - "revlink": "", 14:16:30 INFO - "properties": [ 14:16:30 INFO - [ 14:16:30 INFO - "buildid", 14:16:30 INFO - "20151231120338", 14:16:30 INFO - "Change" 14:16:30 INFO - ], 14:16:30 INFO - [ 14:16:30 INFO - "builduid", 14:16:30 INFO - "d20c36d2241e4e2aa21f5b49f0233f31", 14:16:30 INFO - "Change" 14:16:30 INFO - ], 14:16:30 INFO - [ 14:16:30 INFO - "pgo_build", 14:16:30 INFO - "True", 14:16:30 INFO - "Change" 14:16:30 INFO - ] 14:16:30 INFO - ], 14:16:30 INFO - "revision": "a602dbc1a620" 14:16:30 INFO - } 14:16:30 INFO - ], 14:16:30 INFO - "revision": "a602dbc1a620" 14:16:30 INFO - } 14:16:30 INFO - } 14:16:30 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.tar.bz2. 14:16:30 INFO - Running post-action listener: _resource_record_post_action 14:16:30 INFO - ##### 14:16:30 INFO - ##### Running download-and-extract step. 14:16:30 INFO - ##### 14:16:30 INFO - Running pre-action listener: _resource_record_pre_action 14:16:30 INFO - Running main action method: download_and_extract 14:16:30 INFO - mkdir: /builds/slave/test-pgo/build/tests 14:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/test_packages.json 14:16:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 14:16:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 14:16:30 INFO - Downloaded 1270 bytes. 14:16:30 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 14:16:30 INFO - Using the following test package requirements: 14:16:30 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:16:30 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:16:30 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:16:30 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:16:30 INFO - u'jsshell-linux-x86_64.zip'], 14:16:30 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:16:30 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:16:30 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:16:30 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:16:30 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 14:16:30 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:16:30 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 14:16:30 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:16:30 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:16:30 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:16:30 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:16:30 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:16:30 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:16:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:16:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:16:31 INFO - Downloaded 22571852 bytes. 14:16:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:16:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:16:31 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:16:32 INFO - Return code: 0 14:16:32 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:16: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')]} 14:16:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:16:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:16:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:16:33 INFO - Downloaded 22571852 bytes. 14:16:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:16:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:16:33 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:16:34 INFO - Return code: 0 14:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:34 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:16:34 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:16:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:16:37 INFO - Downloaded 11131970 bytes. 14:16:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 14:16:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:16:37 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 14:16:37 INFO - Return code: 0 14:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:16:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:16:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:16:41 INFO - Downloaded 56628824 bytes. 14:16:41 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:16:41 INFO - mkdir: /builds/slave/test-pgo/properties 14:16:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 14:16:41 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 14:16:41 INFO - Contents: 14:16:41 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:16:41 INFO - Running post-action listener: _resource_record_post_action 14:16:41 INFO - Running post-action listener: set_extra_try_arguments 14:16:41 INFO - ##### 14:16:41 INFO - ##### Running populate-webroot step. 14:16:41 INFO - ##### 14:16:41 INFO - Running pre-action listener: _resource_record_pre_action 14:16:41 INFO - Running main action method: populate_webroot 14:16:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:16:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:16:41 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 14:16:41 INFO - '../fennec_ids.txt'], 14:16:41 INFO - 'tests': ['tcheck2']}, 14:16:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:16:41 INFO - '--tppagecycles', 14:16:41 INFO - '7'], 14:16:41 INFO - 'tests': ['tsvgm']}}, 14:16:41 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:16:41 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:16:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:16:41 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:16:41 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:16:41 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:16:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:16:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:41 INFO - 'talos_options': ['--e10s'], 14:16:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:16:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:41 INFO - 'tests': ['damp', 'tps']}, 14:16:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:41 INFO - 'talos_options': ['--e10s'], 14:16:41 INFO - 'tests': ['damp', 'tps']}, 14:16:41 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:16:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:16:41 INFO - 'other': {'tests': ['a11yr', 14:16:41 INFO - 'ts_paint', 14:16:41 INFO - 'tpaint', 14:16:41 INFO - 'sessionrestore', 14:16:41 INFO - 'sessionrestore_no_auto_restore']}, 14:16:41 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:16:41 INFO - 'tests': ['a11yr', 14:16:41 INFO - 'ts_paint', 14:16:41 INFO - 'tpaint', 14:16:41 INFO - 'sessionrestore', 14:16:41 INFO - 'sessionrestore_no_auto_restore']}, 14:16:41 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:16:41 INFO - 'tests': ['a11yr', 14:16:41 INFO - 'ts_paint', 14:16:41 INFO - 'tpaint', 14:16:41 INFO - 'sessionrestore', 14:16:41 INFO - 'sessionrestore_no_auto_restore']}, 14:16:41 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:16:41 INFO - 'tests': ['a11yr', 14:16:41 INFO - 'ts_paint', 14:16:41 INFO - 'tpaint', 14:16:41 INFO - 'sessionrestore', 14:16:41 INFO - 'sessionrestore_no_auto_restore']}, 14:16:41 INFO - 'other_l64': {'tests': ['a11yr', 14:16:41 INFO - 'ts_paint', 14:16:41 INFO - 'tpaint', 14:16:41 INFO - 'sessionrestore', 14:16:41 INFO - 'sessionrestore_no_auto_restore']}, 14:16:41 INFO - 'other_nol64': {'tests': ['a11yr', 14:16:41 INFO - 'ts_paint', 14:16:41 INFO - 'tpaint', 14:16:41 INFO - 'sessionrestore', 14:16:41 INFO - 'sessionrestore_no_auto_restore']}, 14:16:41 INFO - 'svgr': {'tests': ['tsvgx', 14:16:41 INFO - 'tsvgr_opacity', 14:16:41 INFO - 'tart', 14:16:41 INFO - 'tscrollx', 14:16:41 INFO - 'cart']}, 14:16:41 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:16:41 INFO - 'tests': ['tsvgx', 14:16:41 INFO - 'tsvgr_opacity', 14:16:41 INFO - 'tart', 14:16:41 INFO - 'tscrollx', 14:16:41 INFO - 'cart']}, 14:16:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:41 INFO - 'tests': ['tp5o']}, 14:16:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:41 INFO - 'talos_options': ['--e10s'], 14:16:41 INFO - 'tests': ['tp5o']}, 14:16:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:41 INFO - 'talos_options': ['--xperf_path', 14:16:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:16:41 INFO - 'tests': ['tp5n']}, 14:16:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:41 INFO - 'talos_options': ['--e10s', 14:16:41 INFO - '--xperf_path', 14:16:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:16:41 INFO - 'tests': ['tp5n']}}, 14:16:41 INFO - 'talos.zip': {'path': '', 14:16:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:16:41 INFO - Running post-action listener: _resource_record_post_action 14:16:41 INFO - ##### 14:16:41 INFO - ##### Running create-virtualenv step. 14:16:41 INFO - ##### 14:16:41 INFO - Running pre-action listener: _resource_record_pre_action 14:16:41 INFO - Running main action method: create_virtualenv 14:16:41 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 14:16:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 14:16:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 14:16:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:16:41 INFO - Using real prefix '/usr' 14:16:41 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 14:16:41 INFO - Installing distribute.............................................................................................................................................................................................done. 14:16:43 INFO - Installing pip.................done. 14:16:43 INFO - Return code: 0 14:16:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 14:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:43 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 0x7f5c2bdf0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1857600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e6350>, '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 0x18e7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e76e0>, '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': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:16:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 14:16:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:16:43 INFO - Using env: {'DISPLAY': ':0', 14:16:43 INFO - 'HOME': '/home/cltbld', 14:16:43 INFO - 'LANG': 'en_US.UTF-8', 14:16:43 INFO - 'LANGUAGE': 'en_US:en', 14:16:43 INFO - 'LOGNAME': 'cltbld', 14:16:43 INFO - 'MAIL': '/var/mail/cltbld', 14:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:43 INFO - 'MOZ_NO_REMOTE': '1', 14:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:16:43 INFO - 'NO_EM_RESTART': '1', 14:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:16:43 INFO - 'PWD': '/builds/slave/test-pgo', 14:16:43 INFO - 'SHELL': '/bin/bash', 14:16:43 INFO - 'SHLVL': '1', 14:16:43 INFO - 'TERM': 'linux', 14:16:43 INFO - 'TMOUT': '86400', 14:16:43 INFO - 'USER': 'cltbld', 14:16:43 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 14:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:43 INFO - '_': '/tools/buildbot/bin/python'} 14:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:16:43 INFO - Cleaning up... 14:16:43 INFO - Return code: 0 14:16:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 14:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:43 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 0x7f5c2bdf0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1857600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e6350>, '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 0x18e7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e76e0>, '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': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:16:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 14:16:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 14:16:43 INFO - Using env: {'DISPLAY': ':0', 14:16:43 INFO - 'HOME': '/home/cltbld', 14:16:43 INFO - 'LANG': 'en_US.UTF-8', 14:16:43 INFO - 'LANGUAGE': 'en_US:en', 14:16:43 INFO - 'LOGNAME': 'cltbld', 14:16:43 INFO - 'MAIL': '/var/mail/cltbld', 14:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:43 INFO - 'MOZ_NO_REMOTE': '1', 14:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:16:43 INFO - 'NO_EM_RESTART': '1', 14:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:16:43 INFO - 'PWD': '/builds/slave/test-pgo', 14:16:43 INFO - 'SHELL': '/bin/bash', 14:16:43 INFO - 'SHLVL': '1', 14:16:43 INFO - 'TERM': 'linux', 14:16:43 INFO - 'TMOUT': '86400', 14:16:43 INFO - 'USER': 'cltbld', 14:16:43 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 14:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:43 INFO - '_': '/tools/buildbot/bin/python'} 14:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:44 INFO - Downloading/unpacking psutil>=0.7.1 14:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:45 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 14:16:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 14:16:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 14:16:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:16:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:16:45 INFO - Installing collected packages: psutil 14:16:45 INFO - Running setup.py install for psutil 14:16:45 INFO - building 'psutil._psutil_linux' extension 14:16:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:16:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:16:46 INFO - building 'psutil._psutil_posix' extension 14:16:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:16:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:16:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:16:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:16:46 INFO - Successfully installed psutil 14:16:46 INFO - Cleaning up... 14:16:46 INFO - Return code: 0 14:16:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 14:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:46 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 0x7f5c2bdf0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1857600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e6350>, '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 0x18e7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e76e0>, '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': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:16:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 14:16:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 14:16:46 INFO - Using env: {'DISPLAY': ':0', 14:16:46 INFO - 'HOME': '/home/cltbld', 14:16:46 INFO - 'LANG': 'en_US.UTF-8', 14:16:46 INFO - 'LANGUAGE': 'en_US:en', 14:16:46 INFO - 'LOGNAME': 'cltbld', 14:16:46 INFO - 'MAIL': '/var/mail/cltbld', 14:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:46 INFO - 'MOZ_NO_REMOTE': '1', 14:16:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:16:46 INFO - 'NO_EM_RESTART': '1', 14:16:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:16:46 INFO - 'PWD': '/builds/slave/test-pgo', 14:16:46 INFO - 'SHELL': '/bin/bash', 14:16:46 INFO - 'SHLVL': '1', 14:16:46 INFO - 'TERM': 'linux', 14:16:46 INFO - 'TMOUT': '86400', 14:16:46 INFO - 'USER': 'cltbld', 14:16:46 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 14:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:46 INFO - '_': '/tools/buildbot/bin/python'} 14:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:46 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:16:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 14:16:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:16:48 INFO - Installing collected packages: mozsystemmonitor 14:16:48 INFO - Running setup.py install for mozsystemmonitor 14:16:48 INFO - Successfully installed mozsystemmonitor 14:16:48 INFO - Cleaning up... 14:16:48 INFO - Return code: 0 14:16:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 14:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16: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', '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 0x7f5c2bdf0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1857600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e6350>, '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 0x18e7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e76e0>, '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': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:16: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', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 14:16: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 blobuploader==1.2.4 14:16:48 INFO - Using env: {'DISPLAY': ':0', 14:16:48 INFO - 'HOME': '/home/cltbld', 14:16:48 INFO - 'LANG': 'en_US.UTF-8', 14:16:48 INFO - 'LANGUAGE': 'en_US:en', 14:16:48 INFO - 'LOGNAME': 'cltbld', 14:16:48 INFO - 'MAIL': '/var/mail/cltbld', 14:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:48 INFO - 'MOZ_NO_REMOTE': '1', 14:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:16:48 INFO - 'NO_EM_RESTART': '1', 14:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:16:48 INFO - 'PWD': '/builds/slave/test-pgo', 14:16:48 INFO - 'SHELL': '/bin/bash', 14:16:48 INFO - 'SHLVL': '1', 14:16:48 INFO - 'TERM': 'linux', 14:16:48 INFO - 'TMOUT': '86400', 14:16:48 INFO - 'USER': 'cltbld', 14:16:48 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 14:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:48 INFO - '_': '/tools/buildbot/bin/python'} 14:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:48 INFO - Downloading/unpacking blobuploader==1.2.4 14:16: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 14:16: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 14:16: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 14:16: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 14:16: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 14:16: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 14:16:50 INFO - Downloading blobuploader-1.2.4.tar.gz 14:16:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 14:16:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:16:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 14:16:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 14:16:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:51 INFO - Downloading docopt-0.6.1.tar.gz 14:16:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 14:16:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 14:16:51 INFO - Installing collected packages: blobuploader, requests, docopt 14:16:51 INFO - Running setup.py install for blobuploader 14:16:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:16:51 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 14:16:51 INFO - Running setup.py install for requests 14:16:51 INFO - Running setup.py install for docopt 14:16:51 INFO - Successfully installed blobuploader requests docopt 14:16:51 INFO - Cleaning up... 14:16:51 INFO - Return code: 0 14:16:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:51 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 0x7f5c2bdf0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1857600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e6350>, '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 0x18e7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e76e0>, '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': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:16:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 14:16:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:16:51 INFO - Using env: {'DISPLAY': ':0', 14:16:51 INFO - 'HOME': '/home/cltbld', 14:16:51 INFO - 'LANG': 'en_US.UTF-8', 14:16:51 INFO - 'LANGUAGE': 'en_US:en', 14:16:51 INFO - 'LOGNAME': 'cltbld', 14:16:51 INFO - 'MAIL': '/var/mail/cltbld', 14:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:51 INFO - 'MOZ_NO_REMOTE': '1', 14:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:16:51 INFO - 'NO_EM_RESTART': '1', 14:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:16:51 INFO - 'PWD': '/builds/slave/test-pgo', 14:16:51 INFO - 'SHELL': '/bin/bash', 14:16:51 INFO - 'SHLVL': '1', 14:16:51 INFO - 'TERM': 'linux', 14:16:51 INFO - 'TMOUT': '86400', 14:16:51 INFO - 'USER': 'cltbld', 14:16:51 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 14:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:51 INFO - '_': '/tools/buildbot/bin/python'} 14:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:16:51 INFO - Running setup.py (path:/tmp/pip-0HM1Pg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:16:52 INFO - Running setup.py (path:/tmp/pip-44GofM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:16:52 INFO - Running setup.py (path:/tmp/pip-tuKSMc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:16:52 INFO - Running setup.py (path:/tmp/pip-bv3H08-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:16:52 INFO - Running setup.py (path:/tmp/pip-YvXOxy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:16:52 INFO - Running setup.py (path:/tmp/pip-I0TXK_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:16:52 INFO - Running setup.py (path:/tmp/pip-D5wQ6l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:16:52 INFO - Running setup.py (path:/tmp/pip-lTbdE9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:16:52 INFO - Running setup.py (path:/tmp/pip-C2AkIH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:16:52 INFO - Running setup.py (path:/tmp/pip-Us_qPL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:16:52 INFO - Running setup.py (path:/tmp/pip-Kh7ytg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:16:52 INFO - Running setup.py (path:/tmp/pip-YdRDoz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:16:52 INFO - Running setup.py (path:/tmp/pip-Z_taDj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:16:52 INFO - Running setup.py (path:/tmp/pip-Hwubaq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:16:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:16:52 INFO - Running setup.py (path:/tmp/pip-4bD7fz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:16:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:16:53 INFO - Running setup.py (path:/tmp/pip-5rXeu5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:16:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:16:53 INFO - Running setup.py (path:/tmp/pip-4vButk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:16:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:16:53 INFO - Running setup.py install for manifestparser 14:16:53 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 14:16:53 INFO - Running setup.py install for mozcrash 14:16:53 INFO - Running setup.py install for mozdebug 14:16:53 INFO - Running setup.py install for mozdevice 14:16:53 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 14:16:53 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 14:16:53 INFO - Running setup.py install for mozfile 14:16:53 INFO - Running setup.py install for mozhttpd 14:16:53 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 14:16:53 INFO - Running setup.py install for mozinfo 14:16:53 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 14:16:53 INFO - Running setup.py install for mozInstall 14:16:53 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 14:16:53 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 14:16:53 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 14:16:53 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 14:16:53 INFO - Running setup.py install for mozleak 14:16:54 INFO - Running setup.py install for mozlog 14:16:54 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 14:16:54 INFO - Running setup.py install for moznetwork 14:16:54 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 14:16:54 INFO - Running setup.py install for mozprocess 14:16:54 INFO - Running setup.py install for mozprofile 14:16:54 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 14:16:54 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 14:16:54 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 14:16:54 INFO - Running setup.py install for mozrunner 14:16:54 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 14:16:54 INFO - Running setup.py install for mozscreenshot 14:16:54 INFO - Running setup.py install for moztest 14:16:54 INFO - Running setup.py install for mozversion 14:16:54 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 14:16:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:16:54 INFO - Cleaning up... 14:16:54 INFO - Return code: 0 14:16:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f5c2bdf0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1857600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e6350>, '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 0x18e7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e76e0>, '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': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:16:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 14:16:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:16:54 INFO - Using env: {'DISPLAY': ':0', 14:16:54 INFO - 'HOME': '/home/cltbld', 14:16:54 INFO - 'LANG': 'en_US.UTF-8', 14:16:54 INFO - 'LANGUAGE': 'en_US:en', 14:16:54 INFO - 'LOGNAME': 'cltbld', 14:16:54 INFO - 'MAIL': '/var/mail/cltbld', 14:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:54 INFO - 'MOZ_NO_REMOTE': '1', 14:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:16:54 INFO - 'NO_EM_RESTART': '1', 14:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:16:54 INFO - 'PWD': '/builds/slave/test-pgo', 14:16:54 INFO - 'SHELL': '/bin/bash', 14:16:54 INFO - 'SHLVL': '1', 14:16:54 INFO - 'TERM': 'linux', 14:16:54 INFO - 'TMOUT': '86400', 14:16:54 INFO - 'USER': 'cltbld', 14:16:54 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 14:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:54 INFO - '_': '/tools/buildbot/bin/python'} 14:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:16:55 INFO - Running setup.py (path:/tmp/pip-4X6i5P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:16:55 INFO - Running setup.py (path:/tmp/pip-_Gmxrl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:16:55 INFO - Running setup.py (path:/tmp/pip-IZdpwe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:16:55 INFO - Running setup.py (path:/tmp/pip-XtScBs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:16:55 INFO - Running setup.py (path:/tmp/pip-_0Au74-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:16:55 INFO - Running setup.py (path:/tmp/pip-GsKM31-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:16:55 INFO - Running setup.py (path:/tmp/pip-Fdc2F3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:16:55 INFO - Running setup.py (path:/tmp/pip-HdWbB8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:16:55 INFO - Running setup.py (path:/tmp/pip-qrA2Nj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:16:55 INFO - Running setup.py (path:/tmp/pip-Gji7YY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:16:55 INFO - Running setup.py (path:/tmp/pip-0tI6qM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 14:16:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:16:55 INFO - Running setup.py (path:/tmp/pip-rviXaE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 14:16:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:16:56 INFO - Running setup.py (path:/tmp/pip-3mUvwL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 14:16:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:16:56 INFO - Running setup.py (path:/tmp/pip-4Y9kAv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 14:16:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:16:56 INFO - Running setup.py (path:/tmp/pip-gUwYVx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 14:16:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:16:56 INFO - Running setup.py (path:/tmp/pip-BJzc8T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 14:16:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:16:56 INFO - Running setup.py (path:/tmp/pip-nOvkt0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:16:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:58 INFO - Downloading blessings-1.5.1.tar.gz 14:16:58 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 14:16:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 14:16:58 INFO - Installing collected packages: blessings 14:16:58 INFO - Running setup.py install for blessings 14:16:58 INFO - Successfully installed blessings 14:16:58 INFO - Cleaning up... 14:16:58 INFO - Return code: 0 14:16:58 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 14:16:58 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:16:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:16:58 INFO - Reading from file tmpfile_stdout 14:16:58 INFO - Current package versions: 14:16:58 INFO - argparse == 1.2.1 14:16:58 INFO - blessings == 1.5.1 14:16:58 INFO - blobuploader == 1.2.4 14:16:58 INFO - docopt == 0.6.1 14:16:58 INFO - manifestparser == 1.1 14:16:58 INFO - mozInstall == 1.12 14:16:58 INFO - mozcrash == 0.16 14:16:58 INFO - mozdebug == 0.1 14:16:58 INFO - mozdevice == 0.46 14:16:58 INFO - mozfile == 1.2 14:16:58 INFO - mozhttpd == 0.7 14:16:58 INFO - mozinfo == 0.8 14:16:58 INFO - mozleak == 0.1 14:16:58 INFO - mozlog == 3.0 14:16:58 INFO - moznetwork == 0.27 14:16:58 INFO - mozprocess == 0.22 14:16:58 INFO - mozprofile == 0.27 14:16:58 INFO - mozrunner == 6.11 14:16:58 INFO - mozscreenshot == 0.1 14:16:58 INFO - mozsystemmonitor == 0.0 14:16:58 INFO - moztest == 0.7 14:16:58 INFO - mozversion == 1.4 14:16:58 INFO - psutil == 3.1.1 14:16:58 INFO - requests == 1.2.3 14:16:58 INFO - wsgiref == 0.1.2 14:16:58 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f5c2bdf0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1857600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e6350>, '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 0x18e7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e76e0>, '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': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:16:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 14:16:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:16:58 INFO - Using env: {'DISPLAY': ':0', 14:16:58 INFO - 'HOME': '/home/cltbld', 14:16:58 INFO - 'LANG': 'en_US.UTF-8', 14:16:58 INFO - 'LANGUAGE': 'en_US:en', 14:16:58 INFO - 'LOGNAME': 'cltbld', 14:16:58 INFO - 'MAIL': '/var/mail/cltbld', 14:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:58 INFO - 'MOZ_NO_REMOTE': '1', 14:16:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:16:58 INFO - 'NO_EM_RESTART': '1', 14:16:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:16:58 INFO - 'PWD': '/builds/slave/test-pgo', 14:16:58 INFO - 'SHELL': '/bin/bash', 14:16:58 INFO - 'SHLVL': '1', 14:16:58 INFO - 'TERM': 'linux', 14:16:58 INFO - 'TMOUT': '86400', 14:16:58 INFO - 'USER': 'cltbld', 14:16:58 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 14:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:58 INFO - '_': '/tools/buildbot/bin/python'} 14:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:16:58 INFO - Cleaning up... 14:16:58 INFO - Return code: 0 14:16:58 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 14:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c2bdf0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1857600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e6350>, '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 0x18e7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e76e0>, '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': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:16:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 14:16:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:16:58 INFO - Using env: {'DISPLAY': ':0', 14:16:58 INFO - 'HOME': '/home/cltbld', 14:16:58 INFO - 'LANG': 'en_US.UTF-8', 14:16:58 INFO - 'LANGUAGE': 'en_US:en', 14:16:58 INFO - 'LOGNAME': 'cltbld', 14:16:58 INFO - 'MAIL': '/var/mail/cltbld', 14:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:58 INFO - 'MOZ_NO_REMOTE': '1', 14:16:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:16:58 INFO - 'NO_EM_RESTART': '1', 14:16:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:16:58 INFO - 'PWD': '/builds/slave/test-pgo', 14:16:58 INFO - 'SHELL': '/bin/bash', 14:16:58 INFO - 'SHLVL': '1', 14:16:58 INFO - 'TERM': 'linux', 14:16:58 INFO - 'TMOUT': '86400', 14:16:58 INFO - 'USER': 'cltbld', 14:16:58 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 14:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:58 INFO - '_': '/tools/buildbot/bin/python'} 14:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:58 INFO - Downloading/unpacking jsonschema 14:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:16:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:17:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 14:17:00 INFO - Downloading/unpacking functools32 (from jsonschema) 14:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:00 INFO - Downloading functools32-3.2.3-2.tar.gz 14:17:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 14:17:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 14:17:00 INFO - warning: no files found matching '*.txt' 14:17:00 INFO - no previously-included directories found matching 'build' 14:17:00 INFO - no previously-included directories found matching 'dist' 14:17:00 INFO - no previously-included directories found matching '.git*' 14:17:00 INFO - Installing collected packages: jsonschema, functools32 14:17:01 INFO - Running setup.py install for functools32 14:17:01 INFO - warning: no files found matching '*.txt' 14:17:01 INFO - no previously-included directories found matching 'build' 14:17:01 INFO - no previously-included directories found matching 'dist' 14:17:01 INFO - no previously-included directories found matching '.git*' 14:17:01 INFO - Successfully installed jsonschema functools32 14:17:01 INFO - Cleaning up... 14:17:01 INFO - Return code: 0 14:17:01 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 14:17: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')]} 14:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17: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')]} 14:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17: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', '--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 0x7f5c2bdf0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1857600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e6350>, '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 0x18e7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e76e0>, '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': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 14:17:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:17:01 INFO - Using env: {'DISPLAY': ':0', 14:17:01 INFO - 'HOME': '/home/cltbld', 14:17:01 INFO - 'LANG': 'en_US.UTF-8', 14:17:01 INFO - 'LANGUAGE': 'en_US:en', 14:17:01 INFO - 'LOGNAME': 'cltbld', 14:17:01 INFO - 'MAIL': '/var/mail/cltbld', 14:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:01 INFO - 'MOZ_NO_REMOTE': '1', 14:17:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:01 INFO - 'NO_EM_RESTART': '1', 14:17:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:17:01 INFO - 'PWD': '/builds/slave/test-pgo', 14:17:01 INFO - 'SHELL': '/bin/bash', 14:17:01 INFO - 'SHLVL': '1', 14:17:01 INFO - 'TERM': 'linux', 14:17:01 INFO - 'TMOUT': '86400', 14:17:01 INFO - 'USER': 'cltbld', 14:17:01 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 14:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:01 INFO - '_': '/tools/buildbot/bin/python'} 14:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:01 INFO - Downloading/unpacking flake8 14:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:17:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 14:17:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:17:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 14:17:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 14:17:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 14:17:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 14:17:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:17:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:17:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:17:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:17:03 INFO - no previously-included directories found matching 'docs/_build' 14:17:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:17:03 INFO - Running setup.py install for pep8 14:17:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:17:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:17:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:17:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:17:03 INFO - no previously-included directories found matching 'docs/_build' 14:17:03 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 14:17:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:17:03 INFO - Cleaning up... 14:17:03 INFO - Return code: 0 14:17:03 INFO - Running post-action listener: _resource_record_post_action 14:17:03 INFO - Running post-action listener: _start_resource_monitoring 14:17:03 INFO - Starting resource monitoring. 14:17:03 INFO - ##### 14:17:03 INFO - ##### Running install step. 14:17:03 INFO - ##### 14:17:03 INFO - Running pre-action listener: _resource_record_pre_action 14:17:03 INFO - Running main action method: install 14:17:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:17:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:17:04 INFO - Reading from file tmpfile_stdout 14:17:04 INFO - Detecting whether we're running mozinstall >=1.0... 14:17:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 14:17:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 14:17:04 INFO - Reading from file tmpfile_stdout 14:17:04 INFO - Output received: 14:17:04 INFO - Usage: mozinstall [options] installer 14:17:04 INFO - Options: 14:17:04 INFO - -h, --help show this help message and exit 14:17:04 INFO - -d DEST, --destination=DEST 14:17:04 INFO - Directory to install application into. [default: 14:17:04 INFO - "/builds/slave/test-pgo"] 14:17:04 INFO - --app=APP Application being installed. [default: firefox] 14:17:04 INFO - mkdir: /builds/slave/test-pgo/build/application 14:17:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 14:17:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 14:17:18 INFO - Reading from file tmpfile_stdout 14:17:18 INFO - Output received: 14:17:18 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 14:17:18 INFO - Running post-action listener: _resource_record_post_action 14:17:18 INFO - ##### 14:17:18 INFO - ##### Running run-tests step. 14:17:18 INFO - ##### 14:17:18 INFO - Running pre-action listener: _resource_record_pre_action 14:17:18 INFO - Running main action method: run_tests 14:17:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 14:17:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 14:17:18 INFO - Python 2.7.3 14:17:18 INFO - Return code: 0 14:17:18 INFO - grabbing minidump binary from tooltool 14:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:18 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 0x18e6350>, '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 0x18e7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e76e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 14:17:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 14:17:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:17:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:17:18 INFO - Return code: 0 14:17:18 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 14:17:18 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 14:17:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 14:17:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 14:17:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 14:17:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 14:17:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 14:17:19 INFO - Return code: 0 14:17:19 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/1451592218/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 14:17:19 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/1451592218/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --webServer localhost 14:17:19 INFO - Using env: {'DISPLAY': ':0', 14:17:19 INFO - 'HOME': '/home/cltbld', 14:17:19 INFO - 'LANG': 'en_US.UTF-8', 14:17:19 INFO - 'LANGUAGE': 'en_US:en', 14:17:19 INFO - 'LOGNAME': 'cltbld', 14:17:19 INFO - 'MAIL': '/var/mail/cltbld', 14:17:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:17:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 14:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:19 INFO - 'MOZ_NO_REMOTE': '1', 14:17:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:19 INFO - 'NO_EM_RESTART': '1', 14:17:19 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:17:19 INFO - 'PWD': '/builds/slave/test-pgo', 14:17:19 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 14:17:19 INFO - 'SHELL': '/bin/bash', 14:17:19 INFO - 'SHLVL': '1', 14:17:19 INFO - 'TERM': 'linux', 14:17:19 INFO - 'TMOUT': '86400', 14:17:19 INFO - 'USER': 'cltbld', 14:17:19 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844', 14:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:19 INFO - '_': '/tools/buildbot/bin/python'} 14:17:19 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/1451592218/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] with output_timeout 3600 14:17:19 INFO - mozversion INFO | application_buildid: 20151231120338 14:17:19 INFO - mozversion INFO | application_changeset: a602dbc1a620fa3cd96a093b13893376aed3106a 14:17:19 INFO - mozversion INFO | application_display_name: Nightly 14:17:19 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:17:19 INFO - mozversion INFO | application_name: Firefox 14:17:19 INFO - mozversion INFO | application_remotingname: firefox 14:17:19 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:17:19 INFO - mozversion INFO | application_vendor: Mozilla 14:17:19 INFO - mozversion INFO | application_version: 44.0 14:17:19 INFO - mozversion INFO | platform_buildid: 20151231120338 14:17:19 INFO - mozversion INFO | platform_changeset: a602dbc1a620fa3cd96a093b13893376aed3106a 14:17:19 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:17:19 INFO - mozversion INFO | platform_version: 44.0 14:17:19 INFO - 2015-12-31 14:17:19,660 DEBUG : using testdate: 1451600239 14:17:19 INFO - 2015-12-31 14:17:19,660 DEBUG : actual date: 1451600239 14:17:19 INFO - 2015-12-31 14:17:19,707 INFO : starting webserver on 'localhost:49260' 14:17:19 INFO - 2015-12-31 14:17:19,708 INFO : Starting test suite talos-linux64-ix-014 14:17:19 INFO - 2015-12-31 14:17:19,708 INFO : Starting test dromaeo_css 14:17:19 INFO - 2015-12-31 14:17:19,708 DEBUG : operating with platform_type : linux_ 14:17:19 INFO - 2015-12-31 14:17:19,709 INFO : Initialising browser for dromaeo_css test... 14:17:19 INFO - 2015-12-31 14:17:19,721 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzaI6Ab/profile http://localhost:49260/getInfo.html 14:17:23 INFO - 2015-12-31 14:17:23,529 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:17:23 INFO - 2015-12-31 14:17:23,529 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:17:23 INFO - 2015-12-31 14:17:23,529 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:17:23 INFO - 2015-12-31 14:17:23,529 DEBUG : BROWSER_OUTPUT: __metrics 14:17:23 INFO - 2015-12-31 14:17:23,646 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:17:25 INFO - 2015-12-31 14:17:25,146 INFO : Browser initialized. 14:17:25 INFO - 2015-12-31 14:17:25,147 INFO : Running cycle 1/1 for dromaeo_css test... 14:17:25 INFO - 2015-12-31 14:17:25,147 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzaI6Ab/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:17:36 INFO - 2015-12-31 14:17:36,724 DEBUG : BROWSER_OUTPUT: RSS: Main: 128532480 14:17:36 INFO - 2015-12-31 14:17:36,725 DEBUG : BROWSER_OUTPUT: 14:18:27 INFO - 2015-12-31 14:18:27,629 DEBUG : BROWSER_OUTPUT: 1451600307628 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:20:35 INFO - 2015-12-31 14:20:35,413 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49260/tests/dromaeo/cssquery-ext.html) 14:20:36 INFO - 2015-12-31 14:20:36,497 DEBUG : BROWSER_OUTPUT: RSS: Main: 237502464 14:20:36 INFO - 2015-12-31 14:20:36,498 DEBUG : BROWSER_OUTPUT: 14:23:38 INFO - 2015-12-31 14:23:38,536 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/dromaeo/cssquery-ext.html (next: http://localhost:49260/tests/dromaeo/cssquery-jquery.html) 14:23:39 INFO - 2015-12-31 14:23:39,089 DEBUG : BROWSER_OUTPUT: RSS: Main: 203231232 14:23:39 INFO - 2015-12-31 14:23:39,089 DEBUG : BROWSER_OUTPUT: 14:26:40 INFO - 2015-12-31 14:26:40,811 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49260/tests/dromaeo/cssquery-mootools.html) 14:26:41 INFO - 2015-12-31 14:26:41,377 DEBUG : BROWSER_OUTPUT: RSS: Main: 198647808 14:26:41 INFO - 2015-12-31 14:26:41,377 DEBUG : BROWSER_OUTPUT: 14:29:43 INFO - 2015-12-31 14:29:43,100 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49260/tests/dromaeo/cssquery-prototype.html) 14:29:43 INFO - 2015-12-31 14:29:43,671 DEBUG : BROWSER_OUTPUT: RSS: Main: 190656512 14:29:43 INFO - 2015-12-31 14:29:43,671 DEBUG : BROWSER_OUTPUT: 14:32:46 INFO - 2015-12-31 14:32:46,027 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49260/tests/dromaeo/cssquery-yui.html) 14:32:46 INFO - 2015-12-31 14:32:46,580 DEBUG : BROWSER_OUTPUT: RSS: Main: 192274432 14:32:46 INFO - 2015-12-31 14:32:46,581 DEBUG : BROWSER_OUTPUT: 14:35:48 INFO - 2015-12-31 14:35:48,721 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/dromaeo/cssquery-yui.html (next: http://localhost:49260/tests/dromaeo/cssquery-dojo.html) 14:35:48 INFO - 2015-12-31 14:35:48,972 DEBUG : BROWSER_OUTPUT: RSS: Main: 236150784 14:35:48 INFO - 2015-12-31 14:35:48,972 DEBUG : BROWSER_OUTPUT: 14:35:48 INFO - 2015-12-31 14:35:48,972 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:35:48 INFO - 2015-12-31 14:35:48,972 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:35:48 INFO - 2015-12-31 14:35:48,972 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:35:48 INFO - 2015-12-31 14:35:48,972 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:35:48 INFO - 2015-12-31 14:35:48,974 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3283.2120291130127;3358.3451227010714;3384.746144039196;3386.610539787925;3416.6241713411528;5490.890182196354;5492.313460817398;5509.752061157247;5522.475364840338;5527.972360138194;5511.669299842012;5516.834494965157;5519.254900588419;5533.861653458665;5536.916946245809;4921.852344429655;5011.974940125303;5031.899362012758;5114.539691427771;5116;4902.485239049902;4990.401151861782;5003.874903127433;5068.46781087986;5093.821716239932;5108.69347839129;5167.509086636764;5169.172932330828;5173.3533308336455;5196.974015129938;4841.951580484185;5074.822381216677;5088.669236499616;5137.768800403972;5154.278401023841;4886.560209581155;4951.207806750938;4965.975170124127;4981.178105612569;4998.300237966688;5384.5692344612335;5397.568194544427;5407.405185429599;5411.594130440233;5425.7287135643455;5714.371419143891;5805.622634528742;5965.224520812267;5966.492848107916;5982.670953097578;5853.76584936602;5918.2306300181135;5932.050871860525;5935.614185077956;5978.701064946736;5579.330480342344;5670.518005969498;5677.120046392815;5685.800996965087;5693.5445164387165;5933.732982015819;5953.315368732651;6088.238970128734;6143;6164.722587483574;5926.436988486087;5932.7330270137945;5937.109433584997;5938.970305148447;5969;5975.43233392827;6009.278886533644;6009.308929473079;6012.729427175787;6013.218281623361;5782.971085144632;5866.472017518444;5948.85127871798;5993.0710739835395;6081.6351018939;5948.583599148018;5974.910376344358;5976.432238937294;5979.79070732531;5985.401459854067;4574.4968053514085;4580.931286030712;4584.908301833944;4587.220172570671;4615.469221039556;3955.4462375267485;4005.078831868628;4033.6369726724147;4108.322126849096;4110.095778928631;5632.633878797865;5696.743646535916;5697.23087383198;5706.172604972256;5732.398098199712;5781.364049954502;6008.4592386684535;6022.819315420456;6026.698665066817;6031.7587296507645;5548.77804887808;5605.131204663284;5621.831345059655;5651.689157096399;5663.716814159276;5237.135872581058;5248.947510524922;5261.026710058652;5263.578913686896;5267.157254839208;5979.252593425822;6097.939020609826;6223.751049958041;6227.595206311666;6238.875222495525;5901.940980590225;5974.581779275582;6009.639421634716;6011.549133815069;6028.849278768066;6180.47465965398;6191.102290167811;6194.442500175006;6195.039768835657;6196.597221180609;5703.828885133453;5778.8266352009505;5804.564657650609;5980.730867110903;5985.760569577343;5971.850703732442;5996.190514280516;5997.910031349446;6004.459598636144;6021.367756385692;5844.853878653068;5848.122781582627;5855.8536036597725;5857.619254748598;5874.5887787854435;5700.657960522382;5782.913256301243;5792.884142317214;5858.2677165354335;5900.43945825146;5709.286339207599;5736.541076713769;5744.540436764965;5745.626534275259;5746.195532625519;5854.443827836519;5874.94125058744;5876.030454974882;5877.559183061202;5878.970605146947;5553.527950124285;5657.632253903483;5681.289838770153;5747.023006088892;5788.21859049023 14:35:48 INFO - 2015-12-31 14:35:48,975 DEBUG : BROWSER_OUTPUT: |1;ext.html;602.4457499100827;637.7034678874322;637.9521535884804;638.6104476269475;649.3182158733329;35375.11562210943;44302.55697443025;44466;44688.77655611718;44817.55182448179;47950.280745788754;48246;48368.75815620926;48949.75525122378;49009.75495122519;8248.422610417274;8379.874301885446;9261.398009129402;9454.952725236364;10786.244962852603;11058;11182.608608698698;11183.720406989809;11186.384748838811;11188.104951603853;11045.39250341231;11167.16246281528;11192.552297908074;11194.440277986108;11200.943995280013;10947.124230061576;11028.944855275753;11044.558217671283;11045.171612129123;11047.171462140332;10912;11026.889731102667;11035.062019728333;11035.558577656884;11040.889591104067;45304.77347613241;45621.771891140335;45710.77144614256;45731.54268457306;45754;3636.236390358028;3642.763220390666;3682.3371793077235;3704.073981504624;3742.5883152853166;33920.49119263213;34334.31331373384;34372.82813585916;34376.484352734726;34485.31029379423;3501.019714479924;3704.981475092607;3748.7188460865545;3751.3435148848844;3760.7555508891833;320671;322665;324303;324342;324678;60098.09852852211;61828.38171618316;70140;70291.29708702848;70546;71677;72025;72095;72153.6392318035;72185.63907180431;17426.91286543559;17557.64884702299;17707;17781.110944452725;17802.37691680811;13976.930115349358;14019.228942408265;14096;14113.647158821112;14134.08128471646;23556.411089722893;23629.527409451715;23685;23805;23939.80300984944;8858.557072146366;9189.218916392181;9193.402428842104;9224.631014759334;9230.307726920508;1018.7485656698778;1048.4548035021746;1085.0831047764627;1113.204059097749;1174.671092094215;30011.549826752616;30468.69531304703;30783.076507704805;30905.227369315497;31210.687893121234;1128.7178205448638;1147.3804145761362;1155.7457359380924;1188.7265928836243;1269;14416;16068.357265709474;16124.596885077732;16303.836961630232;16434.75347869759;356484;360171;361543;361807;362476;57980.420195798346;57999;58597.414025859194;58625.706871466224;58901.70549147227;1395.1907893421792;1471.161437980341;1484.7030593881268;1511.667433164702;1537.3235776258412;28681;28682.282942926595;28812.755527378256;29142.70857291485;29533.556996645497;50174;50481.49518504768;50550.494495054576;50582.74708626433;50605;278563;279882;280469;280654;281329;35916.64083359133;35962.82018589995;36265.81867090753;36312.27375452529;36766.44850327301;49093.75453122712;49543.25685114654;50040.49959500504;50052.24921626251;50396;46306;46647.30029049635;48014;48272.517274828206;48306.5169348302;42329.94175145646;42402.36396453994;42966;43174.78412607917;43192.13615727605;41901.161976759686;45378.54621453875;45593;45966;46440.767796160806;36506.452403214505;37196.25607487781;37318.62681373151;37394.813025934694;37993;28141.80342614965;28517.714822851507;28524.714752852207;28744.13767586976;28762.42475150527 14:35:48 INFO - 2015-12-31 14:35:48,976 DEBUG : BROWSER_OUTPUT: |2;jquery.html;382.72635065927875;383.1208468360272;384.82682792743236;386.9922601547969;387.32025295606223;12757.744845103092;15478.922605386957;18054;19973.400797976064;20006.799932000675;19054.33309834156;19262.325818596353;19314;19366.70949935752;19716.408507744756;5616.550675945935;5747.827565173052;5749.31008279005;5750.367459579464;5752.453516915907;4492.550744925497;4596.7931443085145;5866.296044474669;6385.936140638604;6583.07836902834;6189;6464.838379040514;6477.898757211286;6522.412982831568;6526.249481309663;6589.17635295588;6607.008948657692;6613.503987200931;6621.6689165541775;6629.33706629338;4404.1852257332175;4525.049739554697;5815.331236907768;5827.271591051119;5835.124731290298;15549.766753498707;15571.688566228611;15987.840121598867;16066;16086.517404477885;2111.5460176062065;2215.767344428844;2295.483516208856;2314.756950520205;2358.4811341505015;10864.674059778219;10976;10998.285111467809;11074.169437292236;11087.50106245221;1807.2861219818099;1839.5033340997857;1843.2258451450423;1865.8693891427606;1882.4352694191687;65695.3430465694;66133;66269.3373066268;67711;68695.65652171707;7439.479236453504;7453.776386708408;7591.430642701709;7637.236276372319;7658;9531.285153613368;9562.330636855493;9569.856452153223;9575.281853860988;9579;6189.535784816157;6256.74973001084;6303.873922521524;6309.558330916883;6312.305646378894;6666.166729158856;6737.292584278678;6783;6796.184457865088;6840.521163518605;3620.7465477416854;3657.104009517685;3658.6158453362546;3679.2089700714473;3680.1351682354625;1230.4032544216047;1236.9134160608671;1250.331072876007;1261.7602655495427;1273.5860845225154;515.816885005825;525.9184826351922;532.1246549426172;534.4548560468301;546.3171036204744;11397.715057123638;12424.422169164794;12727.23636581808;12868.420921058574;12949.417276222404;532.5952276269985;547.0126421808674;550.411060165627;554.5369616370295;559.7033572206736;7173.7130514779865;7316.524425912298;7337.853242935112;7367.5579465231185;7525.134609519866;623770;628569;632181;644509;664008.9474338263;84693.57653211818;85430.57284713662;86554;88470.55764721135;89378.55310723405;575.0339429758046;579.715939189794;580.2282963658324;581.7294957844623;583.9416058394212;12290.877091228973;12460.501579936701;12477.688057798265;12489.875101249234;12558.62324130241;39001.2199756009;39034;39125.2174956505;39651.2069758609;40696.79651601723;52908;54130;54295.72852135714;56204;56529.15206271827;7342.3758980487255;7503.324700776956;7601.619919003917;7682.193369696101;7729.536227826348;13549.86450135486;13646.931765341109;13896.444142234197;13910.234937078558;14054.578362649137;11857.288562686266;12092.334921579397;12140.75718485643;12208.267503949792;12277.631671049883;11659.242149260272;11767.941160294486;12040.879591203975;12223;12327.691807704521;9840.409575425498;10198.796024079626;12135.001896094074;12172.878271217529;12203.633890983285;7711.537307761552;7776.533407995538;7777.688892444239;7792.84414311722;7795.64919578609;12331.938340308241;12354.25874447536;12513.124081314585;12536.749265014467;12659.050571207381 14:35:48 INFO - 2015-12-31 14:35:48,976 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1540.630248740302;1552.9301181446833;1781.1717551338625;1818.3726614318061;1852.8419737663955;6913.792586222409;7097.645117744105;7802.2587854153835;8134.308583770379;8136.349092072634;7292.234315396887;7387.704491820335;7448.292412220831;7458.328750412462;7460.701571937129;2387.2480168746833;2429.5991161458337;2430.1373012580543;2430.6840110785574;2437.4150203951103;2084.81236688698;2128.4253251622054;2353.317537914003;2389.2115601851433;2402.0872068613903;2285.4514916420108;2305.4236440889777;2310.572544079342;2310.8266879983985;2314.4445333120016;2397.652340410639;2430.781229689319;2431.1977047574346;2431.878406079689;2433.3186707721957;2387.7373488916205;2437.512497500507;2440.316711320815;2442.4992876460283;2442.804575633945;7794.181610930827;7879.724209652634;7888.171741967126;7898.091719452279;7899;4948.529889660476;4980.551750342486;5005;5005.27423523587;5012.72430016349;3164.715175634204;3204.247001954539;3276.2792185719104;3292.703656670911;3307.3219989902013;1098.1105304703135;1113.582406597526;1143.7255058785915;1148.328227986629;1166.8424762657019;10711.892881071246;10712;10713.78572428547;10742.462876856125;10755.946220268926;4309.267424537773;4349.499807522112;4362.476502819682;4423.093265880487;4470.083632855257;9316.813663726687;9325.90674093264;9348.1119293667;9388.718338449857;9443.763905902408;4835.975820120877;4836.322914791932;4842.878928026827;4843.4430040545785;4853.684510506806;4426.778661066934;4427.579379958899;4438.090191510732;4449.866504004885;4453.955460445419;3609.8917032489067;3864.671502922227;3887.3002859485;3918.2555314490155;3941.3693808990424;1126.1159989408275;1246.3394400967502;1272.2366580051894;1281.4874050379922;1286.343964578072;1240.9689757755948;1397.9021468497108;1464.4361920660622;1491.7836913647461;1496.6034000989746;7572.848543029108;7651.387888968869;7680.577568233689;7718.189590092959;7737.148913619498;1196.4376742930808;1200.4237965776301;1214.9878501214876;1220.4294915541177;1226.5277868020698;5921.467067963904;5962.37395073511;5978.312494063239;5982.4316689914385;5986.55100867428;9120.224780893566;9471.715848524555;9484;9484.95257523708;9486.715398537917;9300.11648893354;9398.107179817905;9414.10565996243;9420;9422.434653920649;7194;7506.699732010768;7510.774676759706;7527.8118047048165;7528.284812942762;7263.418926485762;7298.525595836254;7335.339819416278;7345.045144131229;7357.889631655637;8278.503289802391;8423.831523369621;8439.831203375776;8441.620127094418;8456.788580285296;8466.153384661484;8585.712143178573;8606.741797746077;8614.18165274298;8614.396992210735;7190.856182876418;7205.747798827016;7219.278072192738;7236.638168091469;7248.891266630899;6832.795016149524;6855.177378714586;7462;7524.360429363565;7531.774046778605;6888.862222755417;7037.788866334018;7195.388391986739;7229.963850180716;7280.235575264521;6855.383015528626;6900.654967251718;6960.164780226405;6976.162860456778;7014.894776578458;6965.7213711453005;7141.678624461808;7187.101612298463;7214.927850721426;7217.963910180415;6746.257911629815;6786.59280443175;6858.965705171442;6895.138107736533;6897.172339319314;6691.799246022627;6706.832329191614;6745.730170793114;6747.4264687500145;6767.984802279698 14:35:48 INFO - 2015-12-31 14:35:48,978 DEBUG : BROWSER_OUTPUT: |4;prototype.html;355.9234764525626;362.1688225522426;367.56076488596136;370.57754160257304;371.9925601487969;5834.708264586775;5863.413658634135;5929.6738679372675;5981.312149102854;5989.131575921489;5718.885622287552;5818.243628328311;5820.650760954346;5824.708764561776;5835.795747148851;4606.700564463299;4625.421822272216;4629.953700462986;4630.027694184226;4631.490536041033;4464.933026004613;4490.595846373826;4522.3216517522305;4607.723536587799;4628.930566041512;4552.795124219401;4553.317002449626;4554.2713165893465;4559.498455169929;4567.931481027771;4524.886877828048;4546.681732278742;4554;4554.521775213588;4558.9772051139535;4052.0072582217545;4058.715889887709;4059.0867054912705;4060.553339132693;4069.1454794493193;5507.228987941691;5590.217369568264;5595.720213989284;5607.018771714934;5635.295588051494;5248.448912864171;5252.23842542833;5266.7629956652045;5269.789208431658;5271;5941.494972927307;6177.876442471171;6188.195534580521;6194.659293738842;6202.038684003942;4281.914361712779;4377.671674624416;4432.15789000089;4438.178936896652;4443.511213766483;6293.685315734195;6321.336259692758;6328.019157030713;6368.10846481493;6377.043443483468;4525.954740452553;4526.434195725535;4541.591256786905;4541.636669066466;4566.566176213254;4508.391367165456;4536.092781443725;4611.239145541015;4613.5847773700525;4613.95386046142;5270.49930256625;5273.44628813969;5274.498922602346;5276.445973172838;5281.735913204325;6313;6315.747370105236;6317.778877739257;6322.968385158045;6325.7785977491485;1653.3138747419687;1681.2854536822047;1683.0658984263607;1684.115839184438;1685.1995302231587;1112.1492058575197;1113.9888601114046;1115.7712668902855;1120.6694025262568;1123.2193625430248;6103.969480152571;6135.84660383494;6138.017917133327;6138.416850399205;6143.139960405547;6455.935440645628;6457.5479716419395;6459.903101453482;6469.676516174172;6475.579087359307;6026.397360263939;6118.785842495491;6127.816165515042;6131.049687298476;6139.785107521216;3524.629913859008;3536.2220311531423;3543.911402214914;3560.572731272264;3568.9643103568633;6639.1037209977;6641.701123449457;6644.8671026579195;6647.135872336566;6652.334766523309;6578.802635920834;6625.204975402982;6640.369164929324;6645.302243264484;6648.235452922879;6505.934940650627;6635.86728265432;6643.634600096944;6649.966750166218;6655.667216639149;6222.408871157233;6344.270408903035;6439.613623182624;6442.452391546583;6446.871062578816;6287.465565426807;6319;6344.397282258178;6372.776952806629;6383.9361606383345;6628.469722422291;6630.104935833793;6636.170478690164;6639.3360663935155;6639.800805975829;4401.207782599163;4523.095380923763;4525.479569849509;4530.796114174804;4532.206863798888;6396.264429590657;6397.424231819158;6401.327860574573;6402.391772781578;6404.807855764335;6222.439980401605;6222.937770622236;6223.751049957951;6225.470834978896;6227.097070924781;6184.505239580732;6195.16365290698;6196.628202307876;6204.627722336674;6207.689615519296;6113.786017489544;6152.907706384497;6169.691515424301;6170.413810687977;6171.6297022178815;6296.811095667137;6298.433141017331;6300.243970723543;6306.400891915261;6312.400321969406;6081.057436097412;6158.630482171084;6168.0439531872635;6176.135341052166;6179.227596550431 14:35:48 INFO - 2015-12-31 14:35:48,979 DEBUG : BROWSER_OUTPUT: |5;yui.html;846.2552953401006;886.0209468537266;928.3223247029669;929.2333824594707;938.8028514012057;13629;14398.928005359985;14407.495737649184;14410.639734006654;14414.92792536037;14150.50473233437;14194.432222711104;14226;14232.430702771902;14246.786298205536;763.8357753083086;771.4908160613996;913.5432283858071;915.9221466175383;929.6142101028065;918.9172974432299;920.5719340506663;927.8005228875791;928.6703220356777;929.3912487320815;955.8375076237022;961.0053594529663;963.2582911158439;963.9518024098767;964.0841200859177;791.2996997657086;800.7437619961614;951.5765484359497;967.3470407474927;968.1722127580888;716.3123401534533;717.5443593318229;720.5352547606817;720.6756959368305;720.8270015196315;14383.640408989755;14615.853841461556;14621.78067328991;14653.487127950471;14654.267286635626;13493.12294700851;14021.511801613304;14045;14054.92972535141;14162.787558186636;10278.049657431615;10775.515101820358;10872.782544349147;10899.618513352074;11035.558577656884;3907.687385009221;3927.8428862845453;3978.840846366171;4022.0749227677506;4034.9193016139807;54558.45441545574;54581.1812822808;54906;55390.44609553893;56628.867422651725;9979.850302245472;10020.431554293205;10107.595696172219;10239.436830974219;10605.84091238632;14026.929865350608;20267.695984560243;20338.898305508377;20385.694214586496;20419.38741837747;6930;7517.323440890346;7580.1661817200065;7679.6160191990175;7696.461247712718;4744.430668319824;4766.547178018082;4814.975925120352;4842.249451335048;4861.29511220871;3047.817130972149;3075.538669199593;3078.6459557150765;3079.337942342407;3080.6303243610464;477.6226780843165;479.0204032752959;481.7711586996219;487.5611949245636;488.4870885570137;579.147697589379;687.1547995964991;691.0912150522148;706.3713295167305;718.1167164387832;8456.534890580953;8571.271441927382;9145;9186.770330741785;9197.494137822485;564.5286186034625;647.4852492268658;656.980290591283;702.0627462337836;714.2821464428321;4273.743575385487;4427.690061695715;4427.800748966304;4534.478534968521;4597.172508948422;68571.97142042873;69175;69820;69880.6505967467;70269;59916.70041649851;60928;61448.38551614427;62304.06543901845;62570.37429625737;53763.73118134384;55320.17019744707;55413;56036.439635603936;56631.43368566344;7858.6070696466095;8170.918290817016;8173.346132309282;8804;8922.330825188266;39086.6091339083;39126.41310380289;39508.60491395049;40807.38788918228;41543.58456415397;53359;53487.73256133695;54186.72906635442;54407;54640.726796365765;8599.441036332619;8671.696490622799;8881.111888811067;8962.103789620985;9076.273898088006;12802.423890924745;12819.487220511075;13081.607551773463;13104.672383190496;13379.197248165141;11556.248843825124;11843.52625894989;12035.338056406632;12196;12242.816357754818;12131.332776697367;12273.693157670772;12535.686607834878;12639.93680031594;12757.681057973625;11626.983338959666;12032.338221397556;12078.27530348182;12104.818427723767;12435.626931192079;10338.844917326396;10884.61903833362;11102.2783519071;11129.443527823414;11259.943700281447;9768.853467197856;9783.608655653694;9876.901230987598;10382.377057376581;10430.478476076318 14:35:48 INFO - 2015-12-31 14:35:48,979 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:35:48 INFO - 2015-12-31 14:35:48,979 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:35:48 INFO - 2015-12-31 14:35:48,979 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,825 14:35:48 INFO - 2015-12-31 14:35:48,979 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:35:48 INFO - 2015-12-31 14:35:48,980 DEBUG : BROWSER_OUTPUT: __startTimestamp1451601348970__endTimestamp 14:35:48 INFO - 2015-12-31 14:35:48,980 DEBUG : BROWSER_OUTPUT: 14:35:48 INFO - 2015-12-31 14:35:48,980 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:35:48 INFO - 2015-12-31 14:35:48,980 DEBUG : BROWSER_OUTPUT: Number of tests: 6 14:35:48 INFO - 2015-12-31 14:35:48,980 DEBUG : BROWSER_OUTPUT: 14:35:48 INFO - 2015-12-31 14:35:48,980 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5537.09 Median:5736.54 stddev:616.50 (10.7%) stddev-sans-first:592.50 14:35:48 INFO - 2015-12-31 14:35:48,980 DEBUG : BROWSER_OUTPUT: Values: 3283.2 3358.3 3384.7 3386.6 3416.6 5490.9 5492.3 5509.8 5522.5 5528.0 5511.7 5516.8 5519.3 5533.9 5536.9 4921.9 5012.0 5031.9 5114.5 5116.0 4902.5 4990.4 5003.9 5068.5 5093.8 5108.7 5167.5 5169.2 5173.4 5197.0 4842.0 5074.8 5088.7 5137.8 5154.3 4886.6 4951.2 4966.0 4981.2 4998.3 5384.6 5397.6 5407.4 5411.6 5425.7 5714.4 5805.6 5965.2 5966.5 5982.7 5853.8 5918.2 5932.1 5935.6 5978.7 5579.3 5670.5 5677.1 5685.8 5693.5 5933.7 5953.3 6088.2 6143.0 6164.7 5926.4 5932.7 5937.1 5939.0 5969.0 5975.4 6009.3 6009.3 6012.7 6013.2 5783.0 5866.5 5948.9 5993.1 6081.6 5948.6 5974.9 5976.4 5979.8 5985.4 4574.5 4580.9 4584.9 4587.2 4615.5 3955.4 4005.1 4033.6 4108.3 4110.1 5632.6 5696.7 5697.2 5706.2 5732.4 5781.4 6008.5 6022.8 6026.7 6031.8 5548.8 5605.1 5621.8 5651.7 5663.7 5237.1 5248.9 5261.0 5263.6 5267.2 5979.3 6097.9 6223.8 6227.6 6238.9 5901.9 5974.6 6009.6 6011.5 6028.8 6180.5 6191.1 6194.4 6195.0 6196.6 5703.8 5778.8 5804.6 5980.7 5985.8 5971.9 5996.2 5997.9 6004.5 6021.4 5844.9 5848.1 5855.9 5857.6 5874.6 5700.7 5782.9 5792.9 5858.3 5900.4 5709.3 5736.5 5744.5 5745.6 5746.2 5854.4 5874.9 5876.0 5877.6 5879.0 5553.5 5657.6 5681.3 5747.0 5788.2 14:35:48 INFO - 2015-12-31 14:35:48,981 DEBUG : BROWSER_OUTPUT: 14:35:48 INFO - 2015-12-31 14:35:48,981 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:52093.74 Median:198066.91 stddev:84280.00 (42.6%) stddev-sans-first:84427.75 14:35:48 INFO - 2015-12-31 14:35:48,981 DEBUG : BROWSER_OUTPUT: Values: 602.4 637.7 638.0 638.6 649.3 35375.1 44302.6 44466.0 44688.8 44817.6 47950.3 48246.0 48368.8 48949.8 49009.8 8248.4 8379.9 9261.4 9455.0 10786.2 11058.0 11182.6 11183.7 11186.4 11188.1 11045.4 11167.2 11192.6 11194.4 11200.9 10947.1 11028.9 11044.6 11045.2 11047.2 10912.0 11026.9 11035.1 11035.6 11040.9 45304.8 45621.8 45710.8 45731.5 45754.0 3636.2 3642.8 3682.3 3704.1 3742.6 33920.5 34334.3 34372.8 34376.5 34485.3 3501.0 3705.0 3748.7 3751.3 3760.8 320671.0 322665.0 324303.0 324342.0 324678.0 60098.1 61828.4 70140.0 70291.3 70546.0 71677.0 72025.0 72095.0 72153.6 72185.6 17426.9 17557.6 17707.0 17781.1 17802.4 13976.9 14019.2 14096.0 14113.6 14134.1 23556.4 23629.5 23685.0 23805.0 23939.8 8858.6 9189.2 9193.4 9224.6 9230.3 1018.7 1048.5 1085.1 1113.2 1174.7 30011.5 30468.7 30783.1 30905.2 31210.7 1128.7 1147.4 1155.7 1188.7 1269.0 14416.0 16068.4 16124.6 16303.8 16434.8 356484.0 360171.0 361543.0 361807.0 362476.0 57980.4 57999.0 58597.4 58625.7 58901.7 1395.2 1471.2 1484.7 1511.7 1537.3 28681.0 28682.3 28812.8 29142.7 29533.6 50174.0 50481.5 50550.5 50582.7 50605.0 278563.0 279882.0 280469.0 280654.0 281329.0 35916.6 35962.8 36265.8 36312.3 36766.4 49093.8 49543.3 50040.5 50052.2 50396.0 46306.0 46647.3 48014.0 48272.5 48306.5 42329.9 42402.4 42966.0 43174.8 43192.1 41901.2 45378.5 45593.0 45966.0 46440.8 36506.5 37196.3 37318.6 37394.8 37993.0 28141.8 28517.7 28524.7 28744.1 28762.4 14:35:48 INFO - 2015-12-31 14:35:48,981 DEBUG : BROWSER_OUTPUT: 14:35:48 INFO - 2015-12-31 14:35:48,982 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31399.22 Median:26720.06 stddev:104623.96 (391.6%) stddev-sans-first:104891.53 14:35:48 INFO - 2015-12-31 14:35:48,982 DEBUG : BROWSER_OUTPUT: Values: 382.7 383.1 384.8 387.0 387.3 12757.7 15478.9 18054.0 19973.4 20006.8 19054.3 19262.3 19314.0 19366.7 19716.4 5616.6 5747.8 5749.3 5750.4 5752.5 4492.6 4596.8 5866.3 6385.9 6583.1 6189.0 6464.8 6477.9 6522.4 6526.2 6589.2 6607.0 6613.5 6621.7 6629.3 4404.2 4525.0 5815.3 5827.3 5835.1 15549.8 15571.7 15987.8 16066.0 16086.5 2111.5 2215.8 2295.5 2314.8 2358.5 10864.7 10976.0 10998.3 11074.2 11087.5 1807.3 1839.5 1843.2 1865.9 1882.4 65695.3 66133.0 66269.3 67711.0 68695.7 7439.5 7453.8 7591.4 7637.2 7658.0 9531.3 9562.3 9569.9 9575.3 9579.0 6189.5 6256.7 6303.9 6309.6 6312.3 6666.2 6737.3 6783.0 6796.2 6840.5 3620.7 3657.1 3658.6 3679.2 3680.1 1230.4 1236.9 1250.3 1261.8 1273.6 515.8 525.9 532.1 534.5 546.3 11397.7 12424.4 12727.2 12868.4 12949.4 532.6 547.0 550.4 554.5 559.7 7173.7 7316.5 7337.9 7367.6 7525.1 623770.0 628569.0 632181.0 644509.0 664008.9 84693.6 85430.6 86554.0 88470.6 89378.6 575.0 579.7 580.2 581.7 583.9 12290.9 12460.5 12477.7 12489.9 12558.6 39001.2 39034.0 39125.2 39651.2 40696.8 52908.0 54130.0 54295.7 56204.0 56529.2 7342.4 7503.3 7601.6 7682.2 7729.5 13549.9 13646.9 13896.4 13910.2 14054.6 11857.3 12092.3 12140.8 12208.3 12277.6 11659.2 11767.9 12040.9 12223.0 12327.7 9840.4 10198.8 12135.0 12172.9 12203.6 7711.5 7776.5 7777.7 7792.8 7795.6 12331.9 12354.3 12513.1 12536.7 12659.1 14:35:48 INFO - 2015-12-31 14:35:48,982 DEBUG : BROWSER_OUTPUT: 14:35:48 INFO - 2015-12-31 14:35:48,982 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5576.41 Median:6699.32 stddev:2796.37 (41.7%) stddev-sans-first:2787.76 14:35:48 INFO - 2015-12-31 14:35:48,984 DEBUG : BROWSER_OUTPUT: Values: 1540.6 1552.9 1781.2 1818.4 1852.8 6913.8 7097.6 7802.3 8134.3 8136.3 7292.2 7387.7 7448.3 7458.3 7460.7 2387.2 2429.6 2430.1 2430.7 2437.4 2084.8 2128.4 2353.3 2389.2 2402.1 2285.5 2305.4 2310.6 2310.8 2314.4 2397.7 2430.8 2431.2 2431.9 2433.3 2387.7 2437.5 2440.3 2442.5 2442.8 7794.2 7879.7 7888.2 7898.1 7899.0 4948.5 4980.6 5005.0 5005.3 5012.7 3164.7 3204.2 3276.3 3292.7 3307.3 1098.1 1113.6 1143.7 1148.3 1166.8 10711.9 10712.0 10713.8 10742.5 10755.9 4309.3 4349.5 4362.5 4423.1 4470.1 9316.8 9325.9 9348.1 9388.7 9443.8 4836.0 4836.3 4842.9 4843.4 4853.7 4426.8 4427.6 4438.1 4449.9 4454.0 3609.9 3864.7 3887.3 3918.3 3941.4 1126.1 1246.3 1272.2 1281.5 1286.3 1241.0 1397.9 1464.4 1491.8 1496.6 7572.8 7651.4 7680.6 7718.2 7737.1 1196.4 1200.4 1215.0 1220.4 1226.5 5921.5 5962.4 5978.3 5982.4 5986.6 9120.2 9471.7 9484.0 9485.0 9486.7 9300.1 9398.1 9414.1 9420.0 9422.4 7194.0 7506.7 7510.8 7527.8 7528.3 7263.4 7298.5 7335.3 7345.0 7357.9 8278.5 8423.8 8439.8 8441.6 8456.8 8466.2 8585.7 8606.7 8614.2 8614.4 7190.9 7205.7 7219.3 7236.6 7248.9 6832.8 6855.2 7462.0 7524.4 7531.8 6888.9 7037.8 7195.4 7230.0 7280.2 6855.4 6900.7 6960.2 6976.2 7014.9 6965.7 7141.7 7187.1 7214.9 7218.0 6746.3 6786.6 6859.0 6895.1 6897.2 6691.8 6706.8 6745.7 6747.4 6768.0 14:35:48 INFO - 2015-12-31 14:35:48,984 DEBUG : BROWSER_OUTPUT: 14:35:48 INFO - 2015-12-31 14:35:48,984 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5252.32 Median:6007.76 stddev:1542.40 (25.7%) stddev-sans-first:1502.31 14:35:48 INFO - 2015-12-31 14:35:48,986 DEBUG : BROWSER_OUTPUT: Values: 355.9 362.2 367.6 370.6 372.0 5834.7 5863.4 5929.7 5981.3 5989.1 5718.9 5818.2 5820.7 5824.7 5835.8 4606.7 4625.4 4630.0 4630.0 4631.5 4464.9 4490.6 4522.3 4607.7 4628.9 4552.8 4553.3 4554.3 4559.5 4567.9 4524.9 4546.7 4554.0 4554.5 4559.0 4052.0 4058.7 4059.1 4060.6 4069.1 5507.2 5590.2 5595.7 5607.0 5635.3 5248.4 5252.2 5266.8 5269.8 5271.0 5941.5 6177.9 6188.2 6194.7 6202.0 4281.9 4377.7 4432.2 4438.2 4443.5 6293.7 6321.3 6328.0 6368.1 6377.0 4526.0 4526.4 4541.6 4541.6 4566.6 4508.4 4536.1 4611.2 4613.6 4614.0 5270.5 5273.4 5274.5 5276.4 5281.7 6313.0 6315.7 6317.8 6323.0 6325.8 1653.3 1681.3 1683.1 1684.1 1685.2 1112.1 1114.0 1115.8 1120.7 1123.2 6104.0 6135.8 6138.0 6138.4 6143.1 6455.9 6457.5 6459.9 6469.7 6475.6 6026.4 6118.8 6127.8 6131.0 6139.8 3524.6 3536.2 3543.9 3560.6 3569.0 6639.1 6641.7 6644.9 6647.1 6652.3 6578.8 6625.2 6640.4 6645.3 6648.2 6505.9 6635.9 6643.6 6650.0 6655.7 6222.4 6344.3 6439.6 6442.5 6446.9 6287.5 6319.0 6344.4 6372.8 6383.9 6628.5 6630.1 6636.2 6639.3 6639.8 4401.2 4523.1 4525.5 4530.8 4532.2 6396.3 6397.4 6401.3 6402.4 6404.8 6222.4 6222.9 6223.8 6225.5 6227.1 6184.5 6195.2 6196.6 6204.6 6207.7 6113.8 6152.9 6169.7 6170.4 6171.6 6296.8 6298.4 6300.2 6306.4 6312.4 6081.1 6158.6 6168.0 6176.1 6179.2 14:35:48 INFO - 2015-12-31 14:35:48,986 DEBUG : BROWSER_OUTPUT: 14:35:48 INFO - 2015-12-31 14:35:48,986 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15562.17 Median:26923.74 stddev:19153.15 (71.1%) stddev-sans-first:19175.00 14:35:48 INFO - 2015-12-31 14:35:48,987 DEBUG : BROWSER_OUTPUT: Values: 846.3 886.0 928.3 929.2 938.8 13629.0 14398.9 14407.5 14410.6 14414.9 14150.5 14194.4 14226.0 14232.4 14246.8 763.8 771.5 913.5 915.9 929.6 918.9 920.6 927.8 928.7 929.4 955.8 961.0 963.3 964.0 964.1 791.3 800.7 951.6 967.3 968.2 716.3 717.5 720.5 720.7 720.8 14383.6 14615.9 14621.8 14653.5 14654.3 13493.1 14021.5 14045.0 14054.9 14162.8 10278.0 10775.5 10872.8 10899.6 11035.6 3907.7 3927.8 3978.8 4022.1 4034.9 54558.5 54581.2 54906.0 55390.4 56628.9 9979.9 10020.4 10107.6 10239.4 10605.8 14026.9 20267.7 20338.9 20385.7 20419.4 6930.0 7517.3 7580.2 7679.6 7696.5 4744.4 4766.5 4815.0 4842.2 4861.3 3047.8 3075.5 3078.6 3079.3 3080.6 477.6 479.0 481.8 487.6 488.5 579.1 687.2 691.1 706.4 718.1 8456.5 8571.3 9145.0 9186.8 9197.5 564.5 647.5 657.0 702.1 714.3 4273.7 4427.7 4427.8 4534.5 4597.2 68572.0 69175.0 69820.0 69880.7 70269.0 59916.7 60928.0 61448.4 62304.1 62570.4 53763.7 55320.2 55413.0 56036.4 56631.4 7858.6 8170.9 8173.3 8804.0 8922.3 39086.6 39126.4 39508.6 40807.4 41543.6 53359.0 53487.7 54186.7 54407.0 54640.7 8599.4 8671.7 8881.1 8962.1 9076.3 12802.4 12819.5 13081.6 13104.7 13379.2 11556.2 11843.5 12035.3 12196.0 12242.8 12131.3 12273.7 12535.7 12639.9 12757.7 11627.0 12032.3 12078.3 12104.8 12435.6 10338.8 10884.6 11102.3 11129.4 11259.9 9768.9 9783.6 9876.9 10382.4 10430.5 14:35:48 INFO - 2015-12-31 14:35:48,987 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:35:48 INFO - 2015-12-31 14:35:48,988 DEBUG : BROWSER_OUTPUT: 14:35:49 INFO - 2015-12-31 14:35:49,559 INFO : Browser exited with error code: 0 14:35:49 INFO - 2015-12-31 14:35:49,579 INFO : Completed test dromaeo_css (00:18:29) 14:35:49 INFO - 2015-12-31 14:35:49,579 INFO : Starting test kraken 14:35:49 INFO - 2015-12-31 14:35:49,580 DEBUG : operating with platform_type : linux_ 14:35:49 INFO - 2015-12-31 14:35:49,580 INFO : Initialising browser for kraken test... 14:35:49 INFO - 2015-12-31 14:35:49,586 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpz90FXM/profile http://localhost:49260/getInfo.html 14:35:53 INFO - 2015-12-31 14:35:53,246 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:35:53 INFO - 2015-12-31 14:35:53,246 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:35:53 INFO - 2015-12-31 14:35:53,247 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:35:53 INFO - 2015-12-31 14:35:53,247 DEBUG : BROWSER_OUTPUT: __metrics 14:35:53 INFO - 2015-12-31 14:35:53,375 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:35:54 INFO - 2015-12-31 14:35:54,766 INFO : Browser initialized. 14:35:54 INFO - 2015-12-31 14:35:54,767 INFO : Running cycle 1/1 for kraken test... 14:35:54 INFO - 2015-12-31 14:35:54,767 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpz90FXM/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:36:06 INFO - 2015-12-31 14:36:06,291 DEBUG : BROWSER_OUTPUT: RSS: Main: 128716800 14:36:06 INFO - 2015-12-31 14:36:06,291 DEBUG : BROWSER_OUTPUT: 14:36:08 INFO - 2015-12-31 14:36:08,740 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49260/tests/kraken/driver.html?testName=audio-beat-detection) 14:36:09 INFO - 2015-12-31 14:36:09,308 DEBUG : BROWSER_OUTPUT: RSS: Main: 163770368 14:36:09 INFO - 2015-12-31 14:36:09,308 DEBUG : BROWSER_OUTPUT: 14:36:11 INFO - 2015-12-31 14:36:11,845 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49260/tests/kraken/driver.html?testName=audio-dft) 14:36:12 INFO - 2015-12-31 14:36:12,407 DEBUG : BROWSER_OUTPUT: RSS: Main: 162390016 14:36:12 INFO - 2015-12-31 14:36:12,407 DEBUG : BROWSER_OUTPUT: 14:36:15 INFO - 2015-12-31 14:36:15,191 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49260/tests/kraken/driver.html?testName=audio-fft) 14:36:15 INFO - 2015-12-31 14:36:15,764 DEBUG : BROWSER_OUTPUT: RSS: Main: 159498240 14:36:15 INFO - 2015-12-31 14:36:15,765 DEBUG : BROWSER_OUTPUT: 14:36:17 INFO - 2015-12-31 14:36:17,702 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49260/tests/kraken/driver.html?testName=audio-oscillator) 14:36:18 INFO - 2015-12-31 14:36:18,271 DEBUG : BROWSER_OUTPUT: RSS: Main: 158355456 14:36:18 INFO - 2015-12-31 14:36:18,271 DEBUG : BROWSER_OUTPUT: 14:36:20 INFO - 2015-12-31 14:36:20,385 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49260/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:36:20 INFO - 2015-12-31 14:36:20,938 DEBUG : BROWSER_OUTPUT: RSS: Main: 156176384 14:36:20 INFO - 2015-12-31 14:36:20,938 DEBUG : BROWSER_OUTPUT: 14:36:23 INFO - 2015-12-31 14:36:23,782 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49260/tests/kraken/driver.html?testName=imaging-darkroom) 14:36:24 INFO - 2015-12-31 14:36:24,356 DEBUG : BROWSER_OUTPUT: RSS: Main: 157552640 14:36:24 INFO - 2015-12-31 14:36:24,357 DEBUG : BROWSER_OUTPUT: 14:36:27 INFO - 2015-12-31 14:36:27,647 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49260/tests/kraken/driver.html?testName=imaging-desaturate) 14:36:28 INFO - 2015-12-31 14:36:28,214 DEBUG : BROWSER_OUTPUT: RSS: Main: 157057024 14:36:28 INFO - 2015-12-31 14:36:28,214 DEBUG : BROWSER_OUTPUT: 14:36:30 INFO - 2015-12-31 14:36:30,876 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49260/tests/kraken/driver.html?testName=json-parse-financial) 14:36:31 INFO - 2015-12-31 14:36:31,443 DEBUG : BROWSER_OUTPUT: RSS: Main: 157687808 14:36:31 INFO - 2015-12-31 14:36:31,443 DEBUG : BROWSER_OUTPUT: 14:36:32 INFO - 2015-12-31 14:36:32,940 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49260/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:36:33 INFO - 2015-12-31 14:36:33,493 DEBUG : BROWSER_OUTPUT: RSS: Main: 163917824 14:36:33 INFO - 2015-12-31 14:36:33,494 DEBUG : BROWSER_OUTPUT: 14:36:35 INFO - 2015-12-31 14:36:35,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49260/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:36:35 INFO - 2015-12-31 14:36:35,811 DEBUG : BROWSER_OUTPUT: RSS: Main: 162750464 14:36:35 INFO - 2015-12-31 14:36:35,812 DEBUG : BROWSER_OUTPUT: 14:36:37 INFO - 2015-12-31 14:36:37,751 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49260/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:36:38 INFO - 2015-12-31 14:36:38,325 DEBUG : BROWSER_OUTPUT: RSS: Main: 168222720 14:36:38 INFO - 2015-12-31 14:36:38,326 DEBUG : BROWSER_OUTPUT: 14:36:40 INFO - 2015-12-31 14:36:40,624 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49260/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:36:41 INFO - 2015-12-31 14:36:41,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 171802624 14:36:41 INFO - 2015-12-31 14:36:41,206 DEBUG : BROWSER_OUTPUT: 14:36:43 INFO - 2015-12-31 14:36:43,641 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49260/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:36:44 INFO - 2015-12-31 14:36:44,204 DEBUG : BROWSER_OUTPUT: RSS: Main: 168140800 14:36:44 INFO - 2015-12-31 14:36:44,204 DEBUG : BROWSER_OUTPUT: 14:36:45 INFO - 2015-12-31 14:36:45,801 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49260/tests/kraken/driver.html?testName=ai-astar) 14:36:46 INFO - 2015-12-31 14:36:46,052 DEBUG : BROWSER_OUTPUT: RSS: Main: 228245504 14:36:46 INFO - 2015-12-31 14:36:46,052 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,053 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:36:46 INFO - 2015-12-31 14:36:46,054 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:36:46 INFO - 2015-12-31 14:36:46,055 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:36:46 INFO - 2015-12-31 14:36:46,055 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:36:46 INFO - 2015-12-31 14:36:46,055 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;105;109;110;110;100;111;108;110 14:36:46 INFO - 2015-12-31 14:36:46,055 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;121;145;166;122;151;144;122;158;125 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;158;159;159;162;158;158;161;159;161 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;70;71;139;72;71;71;71;148;70 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;90;136;89;89;90;133;88;90;137 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;110;109;110;109;110;109;109;109 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;144;145;145;145;145;145;145 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;96;95;95;95;95 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;51;54;56;55;56;56;56;53;56;53 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;62;63;62;63;63;62;63;63;63 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;83;77;84;78;76;77;77;76;77 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;106;113;106;122;112;125;123;109;106 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;128;145;139;145;142;136;135;137;137;136 14:36:46 INFO - 2015-12-31 14:36:46,057 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;61;60;62;62;60;61;60;61;61 14:36:46 INFO - 2015-12-31 14:36:46,058 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:36:46 INFO - 2015-12-31 14:36:46,058 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:36:46 INFO - 2015-12-31 14:36:46,058 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,887 14:36:46 INFO - 2015-12-31 14:36:46,058 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:36:46 INFO - 2015-12-31 14:36:46,058 DEBUG : BROWSER_OUTPUT: __startTimestamp1451601406053__endTimestamp 14:36:46 INFO - 2015-12-31 14:36:46,058 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,058 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:36:46 INFO - 2015-12-31 14:36:46,058 DEBUG : BROWSER_OUTPUT: Number of tests: 14 14:36:46 INFO - 2015-12-31 14:36:46,058 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.20 Median:110.00 stddev:3.33 (3.0%) stddev-sans-first:3.52 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 105.0 109.0 110.0 110.0 100.0 111.0 108.0 110.0 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.00 Median:145.50 stddev:16.44 (11.3%) stddev-sans-first:17.29 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: Values: 146.0 121.0 145.0 166.0 122.0 151.0 144.0 122.0 158.0 125.0 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.40 Median:159.00 stddev:1.43 (0.9%) stddev-sans-first:1.51 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: Values: 159.0 158.0 159.0 159.0 162.0 158.0 158.0 161.0 159.0 161.0 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:85.30 Median:71.00 stddev:30.75 (43.3%) stddev-sans-first:32.12 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 71.0 139.0 72.0 71.0 71.0 71.0 148.0 70.0 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.10 Median:89.00 stddev:22.27 (25.0%) stddev-sans-first:23.03 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: Values: 89.0 90.0 136.0 89.0 89.0 90.0 133.0 88.0 90.0 137.0 14:36:46 INFO - 2015-12-31 14:36:46,060 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 110.0 109.0 110.0 109.0 110.0 109.0 109.0 109.0 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:144.90 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 144.0 145.0 145.0 145.0 145.0 145.0 145.0 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.60 Median:56.00 stddev:1.78 (3.2%) stddev-sans-first:1.32 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: Values: 51.0 54.0 56.0 55.0 56.0 56.0 56.0 53.0 56.0 53.0 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:62.70 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 63.0 62.0 63.0 63.0 62.0 63.0 63.0 63.0 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,061 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:77.00 stddev:2.92 (3.8%) stddev-sans-first:3.00 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: Values: 76.0 83.0 77.0 84.0 78.0 76.0 77.0 77.0 76.0 77.0 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.60 Median:117.50 stddev:8.06 (6.9%) stddev-sans-first:7.80 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: Values: 124.0 106.0 113.0 106.0 122.0 112.0 125.0 123.0 109.0 106.0 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.00 Median:138.00 stddev:5.10 (3.7%) stddev-sans-first:3.92 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: Values: 128.0 145.0 139.0 145.0 142.0 136.0 135.0 137.0 137.0 136.0 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.23 (2.0%) stddev-sans-first:0.78 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: Values: 64.0 61.0 60.0 62.0 62.0 60.0 61.0 60.0 61.0 61.0 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:36:46 INFO - 2015-12-31 14:36:46,062 DEBUG : BROWSER_OUTPUT: 14:36:46 INFO - 2015-12-31 14:36:46,731 INFO : Browser exited with error code: 0 14:36:46 INFO - 2015-12-31 14:36:46,781 INFO : Completed test kraken (00:00:57) 14:36:46 INFO - 2015-12-31 14:36:46,781 INFO : Starting test v8_7 14:36:46 INFO - 2015-12-31 14:36:46,781 DEBUG : operating with platform_type : linux_ 14:36:46 INFO - 2015-12-31 14:36:46,781 INFO : Initialising browser for v8_7 test... 14:36:46 INFO - 2015-12-31 14:36:46,788 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdB9oft/profile http://localhost:49260/getInfo.html 14:36:50 INFO - 2015-12-31 14:36:50,707 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:36:50 INFO - 2015-12-31 14:36:50,707 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:36:50 INFO - 2015-12-31 14:36:50,707 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:36:50 INFO - 2015-12-31 14:36:50,707 DEBUG : BROWSER_OUTPUT: __metrics 14:36:50 INFO - 2015-12-31 14:36:50,810 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:36:52 INFO - 2015-12-31 14:36:52,190 INFO : Browser initialized. 14:36:52 INFO - 2015-12-31 14:36:52,191 INFO : Running cycle 1/1 for v8_7 test... 14:36:52 INFO - 2015-12-31 14:36:52,191 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdB9oft/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:37:03 INFO - 2015-12-31 14:37:03,710 DEBUG : BROWSER_OUTPUT: RSS: Main: 128503808 14:37:03 INFO - 2015-12-31 14:37:03,710 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,087 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49260/tests/v8_7/run.html (next: http://localhost:49260/tests/v8_7/run.html) 14:37:25 INFO - 2015-12-31 14:37:25,337 DEBUG : BROWSER_OUTPUT: RSS: Main: 422957056 14:37:25 INFO - 2015-12-31 14:37:25,337 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,338 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:37:25 INFO - 2015-12-31 14:37:25,338 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:37:25 INFO - 2015-12-31 14:37:25,338 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:37:25 INFO - 2015-12-31 14:37:25,338 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:37:25 INFO - 2015-12-31 14:37:25,338 DEBUG : BROWSER_OUTPUT: |0;Richards;161.73378618793467 14:37:25 INFO - 2015-12-31 14:37:25,338 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.44486108743823 14:37:25 INFO - 2015-12-31 14:37:25,339 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.87685418415896 14:37:25 INFO - 2015-12-31 14:37:25,339 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5010 14:37:25 INFO - 2015-12-31 14:37:25,339 DEBUG : BROWSER_OUTPUT: |4;RayTrace;946.969696969697 14:37:25 INFO - 2015-12-31 14:37:25,339 DEBUG : BROWSER_OUTPUT: |5;Earley;565.9309564233164 14:37:25 INFO - 2015-12-31 14:37:25,339 DEBUG : BROWSER_OUTPUT: |6;Boyer;10437.5 14:37:25 INFO - 2015-12-31 14:37:25,339 DEBUG : BROWSER_OUTPUT: |7;RegExp;24902.439024390245 14:37:25 INFO - 2015-12-31 14:37:25,339 DEBUG : BROWSER_OUTPUT: |8;Splay;522.1932114882507 14:37:25 INFO - 2015-12-31 14:37:25,340 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4812.5 14:37:25 INFO - 2015-12-31 14:37:25,340 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:37:25 INFO - 2015-12-31 14:37:25,340 DEBUG : BROWSER_OUTPUT: __startTimestamp1451601445336__endTimestamp 14:37:25 INFO - 2015-12-31 14:37:25,340 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,340 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:37:25 INFO - 2015-12-31 14:37:25,341 DEBUG : BROWSER_OUTPUT: Number of tests: 10 14:37:25 INFO - 2015-12-31 14:37:25,341 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,341 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:161.73 Median:161.73 stddev:0.00 (0.0%) 14:37:25 INFO - 2015-12-31 14:37:25,341 DEBUG : BROWSER_OUTPUT: Values: 161.7 14:37:25 INFO - 2015-12-31 14:37:25,341 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,343 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.44 Median:170.44 stddev:0.00 (0.0%) 14:37:25 INFO - 2015-12-31 14:37:25,343 DEBUG : BROWSER_OUTPUT: Values: 170.4 14:37:25 INFO - 2015-12-31 14:37:25,343 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,343 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.88 Median:279.88 stddev:0.00 (0.0%) 14:37:25 INFO - 2015-12-31 14:37:25,343 DEBUG : BROWSER_OUTPUT: Values: 279.9 14:37:25 INFO - 2015-12-31 14:37:25,343 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,343 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5010.00 Median:5010.00 stddev:0.00 (0.0%) 14:37:25 INFO - 2015-12-31 14:37:25,343 DEBUG : BROWSER_OUTPUT: Values: 5010.0 14:37:25 INFO - 2015-12-31 14:37:25,344 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,344 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:946.97 Median:946.97 stddev:0.00 (0.0%) 14:37:25 INFO - 2015-12-31 14:37:25,344 DEBUG : BROWSER_OUTPUT: Values: 947.0 14:37:25 INFO - 2015-12-31 14:37:25,344 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,344 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:565.93 Median:565.93 stddev:0.00 (0.0%) 14:37:25 INFO - 2015-12-31 14:37:25,344 DEBUG : BROWSER_OUTPUT: Values: 565.9 14:37:25 INFO - 2015-12-31 14:37:25,344 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,345 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10437.50 Median:10437.50 stddev:0.00 (0.0%) 14:37:25 INFO - 2015-12-31 14:37:25,345 DEBUG : BROWSER_OUTPUT: Values: 10437.5 14:37:25 INFO - 2015-12-31 14:37:25,345 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,345 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24902.44 Median:24902.44 stddev:0.00 (0.0%) 14:37:25 INFO - 2015-12-31 14:37:25,345 DEBUG : BROWSER_OUTPUT: Values: 24902.4 14:37:25 INFO - 2015-12-31 14:37:25,345 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,345 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:522.19 Median:522.19 stddev:0.00 (0.0%) 14:37:25 INFO - 2015-12-31 14:37:25,345 DEBUG : BROWSER_OUTPUT: Values: 522.2 14:37:25 INFO - 2015-12-31 14:37:25,346 DEBUG : BROWSER_OUTPUT: 14:37:25 INFO - 2015-12-31 14:37:25,346 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4812.50 Median:4812.50 stddev:0.00 (0.0%) 14:37:25 INFO - 2015-12-31 14:37:25,346 DEBUG : BROWSER_OUTPUT: Values: 4812.5 14:37:25 INFO - 2015-12-31 14:37:25,346 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:37:25 INFO - 2015-12-31 14:37:25,346 DEBUG : BROWSER_OUTPUT: 14:37:26 INFO - 2015-12-31 14:37:26,031 INFO : Browser exited with error code: 0 14:37:26 INFO - 2015-12-31 14:37:26,045 INFO : Completed test v8_7 (00:00:39) 14:37:26 INFO - 2015-12-31 14:37:26,356 INFO : Completed test suite (00:20:06) 14:37:26 INFO - 2015-12-31 14:37:26,356 DEBUG : Working with test: dromaeo_css 14:37:26 INFO - 2015-12-31 14:37:26,357 DEBUG : Generating results file: dromaeo_css 14:37:26 INFO - 2015-12-31 14:37:26,358 DEBUG : Working with test: kraken 14:37:26 INFO - 2015-12-31 14:37:26,358 DEBUG : Generating results file: kraken 14:37:26 INFO - 2015-12-31 14:37:26,358 INFO : javascript benchmark 14:37:26 INFO - 2015-12-31 14:37:26,358 DEBUG : Working with test: v8_7 14:37:26 INFO - 2015-12-31 14:37:26,358 DEBUG : Generating results file: v8_7 14:37:26 INFO - 2015-12-31 14:37:26,359 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:37:26 INFO - 2015-12-31 14:37:26,412 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 14:37:26 INFO - 2015-12-31 14:37:26,412 DEBUG : process_Request line: dromaeo_css 7189.61 graph.html#tests=[[72,53,35]] 14:37:26 INFO - 2015-12-31 14:37:26,412 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:37:26 INFO - 2015-12-31 14:37:26,442 DEBUG : process_Request line: kraken 1454.60 graph.html#tests=[[232,53,35]] 14:37:26 INFO - 2015-12-31 14:37:26,442 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:37:26 INFO - 2015-12-31 14:37:26,472 DEBUG : process_Request line: v8_7 23241.03 graph.html#tests=[[230,53,35]] 14:37:26 INFO - 2015-12-31 14:37:26,473 INFO : javascript benchmark 14:37:26 INFO - 2015-12-31 14:37:26,477 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3283.2120291130127, 3358.3451227010714, 3384.746144039196, 3386.610539787925, 3416.6241713411528, 5490.890182196354, 5492.313460817398, 5509.752061157247, 5522.475364840338, 5527.972360138194, 5511.669299842012, 5516.834494965157, 5519.254900588419, 5533.861653458665, 5536.916946245809, 4921.852344429655, 5011.974940125303, 5031.899362012758, 5114.539691427771, 5116.0, 4902.485239049902, 4990.401151861782, 5003.874903127433, 5068.46781087986, 5093.821716239932, 5108.69347839129, 5167.509086636764, 5169.172932330828, 5173.3533308336455, 5196.974015129938, 4841.951580484185, 5074.822381216677, 5088.669236499616, 5137.768800403972, 5154.278401023841, 4886.560209581155, 4951.207806750938, 4965.975170124127, 4981.178105612569, 4998.300237966688, 5384.5692344612335, 5397.568194544427, 5407.405185429599, 5411.594130440233, 5425.7287135643455, 5714.371419143891, 5805.622634528742, 5965.224520812267, 5966.492848107916, 5982.670953097578, 5853.76584936602, 5918.2306300181135, 5932.050871860525, 5935.614185077956, 5978.701064946736, 5579.330480342344, 5670.518005969498, 5677.120046392815, 5685.800996965087, 5693.5445164387165, 5933.732982015819, 5953.315368732651, 6088.238970128734, 6143.0, 6164.722587483574, 5926.436988486087, 5932.7330270137945, 5937.109433584997, 5938.970305148447, 5969.0, 5975.43233392827, 6009.278886533644, 6009.308929473079, 6012.729427175787, 6013.218281623361, 5782.971085144632, 5866.472017518444, 5948.85127871798, 5993.0710739835395, 6081.6351018939, 5948.583599148018, 5974.910376344358, 5976.432238937294, 5979.79070732531, 5985.401459854067, 4574.4968053514085, 4580.931286030712, 4584.908301833944, 4587.220172570671, 4615.469221039556, 3955.4462375267485, 4005.078831868628, 4033.6369726724147, 4108.322126849096, 4110.095778928631, 5632.633878797865, 5696.743646535916, 5697.23087383198, 5706.172604972256, 5732.398098199712, 5781.364049954502, 6008.4592386684535, 6022.819315420456, 6026.698665066817, 6031.7587296507645, 5548.77804887808, 5605.131204663284, 5621.831345059655, 5651.689157096399, 5663.716814159276, 5237.135872581058, 5248.947510524922, 5261.026710058652, 5263.578913686896, 5267.157254839208, 5979.252593425822, 6097.939020609826, 6223.751049958041, 6227.595206311666, 6238.875222495525, 5901.940980590225, 5974.581779275582, 6009.639421634716, 6011.549133815069, 6028.849278768066, 6180.47465965398, 6191.102290167811, 6194.442500175006, 6195.039768835657, 6196.597221180609, 5703.828885133453, 5778.8266352009505, 5804.564657650609, 5980.730867110903, 5985.760569577343, 5971.850703732442, 5996.190514280516, 5997.910031349446, 6004.459598636144, 6021.367756385692, 5844.853878653068, 5848.122781582627, 5855.8536036597725, 5857.619254748598, 5874.5887787854435, 5700.657960522382, 5782.913256301243, 5792.884142317214, 5858.2677165354335, 5900.43945825146, 5709.286339207599, 5736.541076713769, 5744.540436764965, 5745.626534275259, 5746.195532625519, 5854.443827836519, 5874.94125058744, 5876.030454974882, 5877.559183061202, 5878.970605146947, 5553.527950124285, 5657.632253903483, 5681.289838770153, 5747.023006088892, 5788.21859049023], "unit": "ms", "name": "dojo.html", "value": 5497.141028145155}, {"lowerIsBetter": false, "replicates": [602.4457499100827, 637.7034678874322, 637.9521535884804, 638.6104476269475, 649.3182158733329, 35375.11562210943, 44302.55697443025, 44466.0, 44688.77655611718, 44817.55182448179, 47950.280745788754, 48246.0, 48368.75815620926, 48949.75525122378, 49009.75495122519, 8248.422610417274, 8379.874301885446, 9261.398009129402, 9454.952725236364, 10786.244962852603, 11058.0, 11182.608608698698, 11183.720406989809, 11186.384748838811, 11188.104951603853, 11045.39250341231, 11167.16246281528, 11192.552297908074, 11194.440277986108, 11200.943995280013, 10947.124230061576, 11028.944855275753, 11044.558217671283, 11045.171612129123, 11047.171462140332, 10912.0, 11026.889731102667, 11035.062019728333, 11035.558577656884, 11040.889591104067, 45304.77347613241, 45621.771891140335, 45710.77144614256, 45731.54268457306, 45754.0, 3636.236390358028, 3642.763220390666, 3682.3371793077235, 3704.073981504624, 3742.5883152853166, 33920.49119263213, 34334.31331373384, 34372.82813585916, 34376.484352734726, 34485.31029379423, 3501.019714479924, 3704.981475092607, 3748.7188460865545, 3751.3435148848844, 3760.7555508891833, 320671.0, 322665.0, 324303.0, 324342.0, 324678.0, 60098.09852852211, 61828.38171618316, 70140.0, 70291.29708702848, 70546.0, 71677.0, 72025.0, 72095.0, 72153.6392318035, 72185.63907180431, 17426.91286543559, 17557.64884702299, 17707.0, 17781.110944452725, 17802.37691680811, 13976.930115349358, 14019.228942408265, 14096.0, 14113.647158821112, 14134.08128471646, 23556.411089722893, 23629.527409451715, 23685.0, 23805.0, 23939.80300984944, 8858.557072146366, 9189.218916392181, 9193.402428842104, 9224.631014759334, 9230.307726920508, 1018.7485656698778, 1048.4548035021746, 1085.0831047764627, 1113.204059097749, 1174.671092094215, 30011.549826752616, 30468.69531304703, 30783.076507704805, 30905.227369315497, 31210.687893121234, 1128.7178205448638, 1147.3804145761362, 1155.7457359380924, 1188.7265928836243, 1269.0, 14416.0, 16068.357265709474, 16124.596885077732, 16303.836961630232, 16434.75347869759, 356484.0, 360171.0, 361543.0, 361807.0, 362476.0, 57980.420195798346, 57999.0, 58597.414025859194, 58625.706871466224, 58901.70549147227, 1395.1907893421792, 1471.161437980341, 1484.7030593881268, 1511.667433164702, 1537.3235776258412, 28681.0, 28682.282942926595, 28812.755527378256, 29142.70857291485, 29533.556996645497, 50174.0, 50481.49518504768, 50550.494495054576, 50582.74708626433, 50605.0, 278563.0, 279882.0, 280469.0, 280654.0, 281329.0, 35916.64083359133, 35962.82018589995, 36265.81867090753, 36312.27375452529, 36766.44850327301, 49093.75453122712, 49543.25685114654, 50040.49959500504, 50052.24921626251, 50396.0, 46306.0, 46647.30029049635, 48014.0, 48272.517274828206, 48306.5169348302, 42329.94175145646, 42402.36396453994, 42966.0, 43174.78412607917, 43192.13615727605, 41901.161976759686, 45378.54621453875, 45593.0, 45966.0, 46440.767796160806, 36506.452403214505, 37196.25607487781, 37318.62681373151, 37394.813025934694, 37993.0, 28141.80342614965, 28517.714822851507, 28524.714752852207, 28744.13767586976, 28762.42475150527], "unit": "ms", "name": "ext.html", "value": 21082.137673563575}, {"lowerIsBetter": false, "replicates": [382.72635065927875, 383.1208468360272, 384.82682792743236, 386.9922601547969, 387.32025295606223, 12757.744845103092, 15478.922605386957, 18054.0, 19973.400797976064, 20006.799932000675, 19054.33309834156, 19262.325818596353, 19314.0, 19366.70949935752, 19716.408507744756, 5616.550675945935, 5747.827565173052, 5749.31008279005, 5750.367459579464, 5752.453516915907, 4492.550744925497, 4596.7931443085145, 5866.296044474669, 6385.936140638604, 6583.07836902834, 6189.0, 6464.838379040514, 6477.898757211286, 6522.412982831568, 6526.249481309663, 6589.17635295588, 6607.008948657692, 6613.503987200931, 6621.6689165541775, 6629.33706629338, 4404.1852257332175, 4525.049739554697, 5815.331236907768, 5827.271591051119, 5835.124731290298, 15549.766753498707, 15571.688566228611, 15987.840121598867, 16066.0, 16086.517404477885, 2111.5460176062065, 2215.767344428844, 2295.483516208856, 2314.756950520205, 2358.4811341505015, 10864.674059778219, 10976.0, 10998.285111467809, 11074.169437292236, 11087.50106245221, 1807.2861219818099, 1839.5033340997857, 1843.2258451450423, 1865.8693891427606, 1882.4352694191687, 65695.3430465694, 66133.0, 66269.3373066268, 67711.0, 68695.65652171707, 7439.479236453504, 7453.776386708408, 7591.430642701709, 7637.236276372319, 7658.0, 9531.285153613368, 9562.330636855493, 9569.856452153223, 9575.281853860988, 9579.0, 6189.535784816157, 6256.74973001084, 6303.873922521524, 6309.558330916883, 6312.305646378894, 6666.166729158856, 6737.292584278678, 6783.0, 6796.184457865088, 6840.521163518605, 3620.7465477416854, 3657.104009517685, 3658.6158453362546, 3679.2089700714473, 3680.1351682354625, 1230.4032544216047, 1236.9134160608671, 1250.331072876007, 1261.7602655495427, 1273.5860845225154, 515.816885005825, 525.9184826351922, 532.1246549426172, 534.4548560468301, 546.3171036204744, 11397.715057123638, 12424.422169164794, 12727.23636581808, 12868.420921058574, 12949.417276222404, 532.5952276269985, 547.0126421808674, 550.411060165627, 554.5369616370295, 559.7033572206736, 7173.7130514779865, 7316.524425912298, 7337.853242935112, 7367.5579465231185, 7525.134609519866, 623770.0, 628569.0, 632181.0, 644509.0, 664008.9474338263, 84693.57653211818, 85430.57284713662, 86554.0, 88470.55764721135, 89378.55310723405, 575.0339429758046, 579.715939189794, 580.2282963658324, 581.7294957844623, 583.9416058394212, 12290.877091228973, 12460.501579936701, 12477.688057798265, 12489.875101249234, 12558.62324130241, 39001.2199756009, 39034.0, 39125.2174956505, 39651.2069758609, 40696.79651601723, 52908.0, 54130.0, 54295.72852135714, 56204.0, 56529.15206271827, 7342.3758980487255, 7503.324700776956, 7601.619919003917, 7682.193369696101, 7729.536227826348, 13549.86450135486, 13646.931765341109, 13896.444142234197, 13910.234937078558, 14054.578362649137, 11857.288562686266, 12092.334921579397, 12140.75718485643, 12208.267503949792, 12277.631671049883, 11659.242149260272, 11767.941160294486, 12040.879591203975, 12223.0, 12327.691807704521, 9840.409575425498, 10198.796024079626, 12135.001896094074, 12172.878271217529, 12203.633890983285, 7711.537307761552, 7776.533407995538, 7777.688892444239, 7792.84414311722, 7795.64919578609, 12331.938340308241, 12354.25874447536, 12513.124081314585, 12536.749265014467, 12659.050571207381], "unit": "ms", "name": "jquery.html", "value": 7938.6349556336845}, {"lowerIsBetter": false, "replicates": [1540.630248740302, 1552.9301181446833, 1781.1717551338625, 1818.3726614318061, 1852.8419737663955, 6913.792586222409, 7097.645117744105, 7802.2587854153835, 8134.308583770379, 8136.349092072634, 7292.234315396887, 7387.704491820335, 7448.292412220831, 7458.328750412462, 7460.701571937129, 2387.2480168746833, 2429.5991161458337, 2430.1373012580543, 2430.6840110785574, 2437.4150203951103, 2084.81236688698, 2128.4253251622054, 2353.317537914003, 2389.2115601851433, 2402.0872068613903, 2285.4514916420108, 2305.4236440889777, 2310.572544079342, 2310.8266879983985, 2314.4445333120016, 2397.652340410639, 2430.781229689319, 2431.1977047574346, 2431.878406079689, 2433.3186707721957, 2387.7373488916205, 2437.512497500507, 2440.316711320815, 2442.4992876460283, 2442.804575633945, 7794.181610930827, 7879.724209652634, 7888.171741967126, 7898.091719452279, 7899.0, 4948.529889660476, 4980.551750342486, 5005.0, 5005.27423523587, 5012.72430016349, 3164.715175634204, 3204.247001954539, 3276.2792185719104, 3292.703656670911, 3307.3219989902013, 1098.1105304703135, 1113.582406597526, 1143.7255058785915, 1148.328227986629, 1166.8424762657019, 10711.892881071246, 10712.0, 10713.78572428547, 10742.462876856125, 10755.946220268926, 4309.267424537773, 4349.499807522112, 4362.476502819682, 4423.093265880487, 4470.083632855257, 9316.813663726687, 9325.90674093264, 9348.1119293667, 9388.718338449857, 9443.763905902408, 4835.975820120877, 4836.322914791932, 4842.878928026827, 4843.4430040545785, 4853.684510506806, 4426.778661066934, 4427.579379958899, 4438.090191510732, 4449.866504004885, 4453.955460445419, 3609.8917032489067, 3864.671502922227, 3887.3002859485, 3918.2555314490155, 3941.3693808990424, 1126.1159989408275, 1246.3394400967502, 1272.2366580051894, 1281.4874050379922, 1286.343964578072, 1240.9689757755948, 1397.9021468497108, 1464.4361920660622, 1491.7836913647461, 1496.6034000989746, 7572.848543029108, 7651.387888968869, 7680.577568233689, 7718.189590092959, 7737.148913619498, 1196.4376742930808, 1200.4237965776301, 1214.9878501214876, 1220.4294915541177, 1226.5277868020698, 5921.467067963904, 5962.37395073511, 5978.312494063239, 5982.4316689914385, 5986.55100867428, 9120.224780893566, 9471.715848524555, 9484.0, 9484.95257523708, 9486.715398537917, 9300.11648893354, 9398.107179817905, 9414.10565996243, 9420.0, 9422.434653920649, 7194.0, 7506.699732010768, 7510.774676759706, 7527.8118047048165, 7528.284812942762, 7263.418926485762, 7298.525595836254, 7335.339819416278, 7345.045144131229, 7357.889631655637, 8278.503289802391, 8423.831523369621, 8439.831203375776, 8441.620127094418, 8456.788580285296, 8466.153384661484, 8585.712143178573, 8606.741797746077, 8614.18165274298, 8614.396992210735, 7190.856182876418, 7205.747798827016, 7219.278072192738, 7236.638168091469, 7248.891266630899, 6832.795016149524, 6855.177378714586, 7462.0, 7524.360429363565, 7531.774046778605, 6888.862222755417, 7037.788866334018, 7195.388391986739, 7229.963850180716, 7280.235575264521, 6855.383015528626, 6900.654967251718, 6960.164780226405, 6976.162860456778, 7014.894776578458, 6965.7213711453005, 7141.678624461808, 7187.101612298463, 7214.927850721426, 7217.963910180415, 6746.257911629815, 6786.59280443175, 6858.965705171442, 6895.138107736533, 6897.172339319314, 6691.799246022627, 6706.832329191614, 6745.730170793114, 6747.4264687500145, 6767.984802279698], "unit": "ms", "name": "mootools.html", "value": 4653.974489167581}, {"lowerIsBetter": false, "replicates": [355.9234764525626, 362.1688225522426, 367.56076488596136, 370.57754160257304, 371.9925601487969, 5834.708264586775, 5863.413658634135, 5929.6738679372675, 5981.312149102854, 5989.131575921489, 5718.885622287552, 5818.243628328311, 5820.650760954346, 5824.708764561776, 5835.795747148851, 4606.700564463299, 4625.421822272216, 4629.953700462986, 4630.027694184226, 4631.490536041033, 4464.933026004613, 4490.595846373826, 4522.3216517522305, 4607.723536587799, 4628.930566041512, 4552.795124219401, 4553.317002449626, 4554.2713165893465, 4559.498455169929, 4567.931481027771, 4524.886877828048, 4546.681732278742, 4554.0, 4554.521775213588, 4558.9772051139535, 4052.0072582217545, 4058.715889887709, 4059.0867054912705, 4060.553339132693, 4069.1454794493193, 5507.228987941691, 5590.217369568264, 5595.720213989284, 5607.018771714934, 5635.295588051494, 5248.448912864171, 5252.23842542833, 5266.7629956652045, 5269.789208431658, 5271.0, 5941.494972927307, 6177.876442471171, 6188.195534580521, 6194.659293738842, 6202.038684003942, 4281.914361712779, 4377.671674624416, 4432.15789000089, 4438.178936896652, 4443.511213766483, 6293.685315734195, 6321.336259692758, 6328.019157030713, 6368.10846481493, 6377.043443483468, 4525.954740452553, 4526.434195725535, 4541.591256786905, 4541.636669066466, 4566.566176213254, 4508.391367165456, 4536.092781443725, 4611.239145541015, 4613.5847773700525, 4613.95386046142, 5270.49930256625, 5273.44628813969, 5274.498922602346, 5276.445973172838, 5281.735913204325, 6313.0, 6315.747370105236, 6317.778877739257, 6322.968385158045, 6325.7785977491485, 1653.3138747419687, 1681.2854536822047, 1683.0658984263607, 1684.115839184438, 1685.1995302231587, 1112.1492058575197, 1113.9888601114046, 1115.7712668902855, 1120.6694025262568, 1123.2193625430248, 6103.969480152571, 6135.84660383494, 6138.017917133327, 6138.416850399205, 6143.139960405547, 6455.935440645628, 6457.5479716419395, 6459.903101453482, 6469.676516174172, 6475.579087359307, 6026.397360263939, 6118.785842495491, 6127.816165515042, 6131.049687298476, 6139.785107521216, 3524.629913859008, 3536.2220311531423, 3543.911402214914, 3560.572731272264, 3568.9643103568633, 6639.1037209977, 6641.701123449457, 6644.8671026579195, 6647.135872336566, 6652.334766523309, 6578.802635920834, 6625.204975402982, 6640.369164929324, 6645.302243264484, 6648.235452922879, 6505.934940650627, 6635.86728265432, 6643.634600096944, 6649.966750166218, 6655.667216639149, 6222.408871157233, 6344.270408903035, 6439.613623182624, 6442.452391546583, 6446.871062578816, 6287.465565426807, 6319.0, 6344.397282258178, 6372.776952806629, 6383.9361606383345, 6628.469722422291, 6630.104935833793, 6636.170478690164, 6639.3360663935155, 6639.800805975829, 4401.207782599163, 4523.095380923763, 4525.479569849509, 4530.796114174804, 4532.206863798888, 6396.264429590657, 6397.424231819158, 6401.327860574573, 6402.391772781578, 6404.807855764335, 6222.439980401605, 6222.937770622236, 6223.751049957951, 6225.470834978896, 6227.097070924781, 6184.505239580732, 6195.16365290698, 6196.628202307876, 6204.627722336674, 6207.689615519296, 6113.786017489544, 6152.907706384497, 6169.691515424301, 6170.413810687977, 6171.6297022178815, 6296.811095667137, 6298.433141017331, 6300.243970723543, 6306.400891915261, 6312.400321969406, 6081.057436097412, 6158.630482171084, 6168.0439531872635, 6176.135341052166, 6179.227596550431], "unit": "ms", "name": "prototype.html", "value": 4765.987674573772}, {"lowerIsBetter": false, "replicates": [846.2552953401006, 886.0209468537266, 928.3223247029669, 929.2333824594707, 938.8028514012057, 13629.0, 14398.928005359985, 14407.495737649184, 14410.639734006654, 14414.92792536037, 14150.50473233437, 14194.432222711104, 14226.0, 14232.430702771902, 14246.786298205536, 763.8357753083086, 771.4908160613996, 913.5432283858071, 915.9221466175383, 929.6142101028065, 918.9172974432299, 920.5719340506663, 927.8005228875791, 928.6703220356777, 929.3912487320815, 955.8375076237022, 961.0053594529663, 963.2582911158439, 963.9518024098767, 964.0841200859177, 791.2996997657086, 800.7437619961614, 951.5765484359497, 967.3470407474927, 968.1722127580888, 716.3123401534533, 717.5443593318229, 720.5352547606817, 720.6756959368305, 720.8270015196315, 14383.640408989755, 14615.853841461556, 14621.78067328991, 14653.487127950471, 14654.267286635626, 13493.12294700851, 14021.511801613304, 14045.0, 14054.92972535141, 14162.787558186636, 10278.049657431615, 10775.515101820358, 10872.782544349147, 10899.618513352074, 11035.558577656884, 3907.687385009221, 3927.8428862845453, 3978.840846366171, 4022.0749227677506, 4034.9193016139807, 54558.45441545574, 54581.1812822808, 54906.0, 55390.44609553893, 56628.867422651725, 9979.850302245472, 10020.431554293205, 10107.595696172219, 10239.436830974219, 10605.84091238632, 14026.929865350608, 20267.695984560243, 20338.898305508377, 20385.694214586496, 20419.38741837747, 6930.0, 7517.323440890346, 7580.1661817200065, 7679.6160191990175, 7696.461247712718, 4744.430668319824, 4766.547178018082, 4814.975925120352, 4842.249451335048, 4861.29511220871, 3047.817130972149, 3075.538669199593, 3078.6459557150765, 3079.337942342407, 3080.6303243610464, 477.6226780843165, 479.0204032752959, 481.7711586996219, 487.5611949245636, 488.4870885570137, 579.147697589379, 687.1547995964991, 691.0912150522148, 706.3713295167305, 718.1167164387832, 8456.534890580953, 8571.271441927382, 9145.0, 9186.770330741785, 9197.494137822485, 564.5286186034625, 647.4852492268658, 656.980290591283, 702.0627462337836, 714.2821464428321, 4273.743575385487, 4427.690061695715, 4427.800748966304, 4534.478534968521, 4597.172508948422, 68571.97142042873, 69175.0, 69820.0, 69880.6505967467, 70269.0, 59916.70041649851, 60928.0, 61448.38551614427, 62304.06543901845, 62570.37429625737, 53763.73118134384, 55320.17019744707, 55413.0, 56036.439635603936, 56631.43368566344, 7858.6070696466095, 8170.918290817016, 8173.346132309282, 8804.0, 8922.330825188266, 39086.6091339083, 39126.41310380289, 39508.60491395049, 40807.38788918228, 41543.58456415397, 53359.0, 53487.73256133695, 54186.72906635442, 54407.0, 54640.726796365765, 8599.441036332619, 8671.696490622799, 8881.111888811067, 8962.103789620985, 9076.273898088006, 12802.423890924745, 12819.487220511075, 13081.607551773463, 13104.672383190496, 13379.197248165141, 11556.248843825124, 11843.52625894989, 12035.338056406632, 12196.0, 12242.816357754818, 12131.332776697367, 12273.693157670772, 12535.686607834878, 12639.93680031594, 12757.681057973625, 11626.983338959666, 12032.338221397556, 12078.27530348182, 12104.818427723767, 12435.626931192079, 10338.844917326396, 10884.61903833362, 11102.2783519071, 11129.443527823414, 11259.943700281447, 9768.853467197856, 9783.608655653694, 9876.901230987598, 10382.377057376581, 10430.478476076318], "unit": "ms", "name": "yui.html", "value": 6767.331705371326}], "name": "dromaeo_css", "value": 7189.6106010298}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 105.0, 109.0, 110.0, 110.0, 100.0, 111.0, 108.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 108.2}, {"lowerIsBetter": true, "replicates": [146.0, 121.0, 145.0, 166.0, 122.0, 151.0, 144.0, 122.0, 158.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.0}, {"lowerIsBetter": true, "replicates": [159.0, 158.0, 159.0, 159.0, 162.0, 158.0, 158.0, 161.0, 159.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 159.4}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 71.0, 139.0, 72.0, 71.0, 71.0, 71.0, 148.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 85.3}, {"lowerIsBetter": true, "replicates": [89.0, 90.0, 136.0, 89.0, 89.0, 90.0, 133.0, 88.0, 90.0, 137.0], "unit": "ms", "name": "audio-oscillator", "value": 103.1}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 110.0, 109.0, 110.0, 109.0, 110.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 144.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 144.9}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [51.0, 54.0, 56.0, 55.0, 56.0, 56.0, 56.0, 53.0, 56.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 54.6}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 63.0, 62.0, 63.0, 63.0, 62.0, 63.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 62.7}, {"lowerIsBetter": true, "replicates": [76.0, 83.0, 77.0, 84.0, 78.0, 76.0, 77.0, 77.0, 76.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.1}, {"lowerIsBetter": true, "replicates": [124.0, 106.0, 113.0, 106.0, 122.0, 112.0, 125.0, 123.0, 109.0, 106.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.6}, {"lowerIsBetter": true, "replicates": [128.0, 145.0, 139.0, 145.0, 142.0, 136.0, 135.0, 137.0, 137.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.0}, {"lowerIsBetter": true, "replicates": [64.0, 61.0, 60.0, 62.0, 62.0, 60.0, 61.0, 60.0, 61.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.2}], "name": "kraken", "value": 1454.6}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [161.73378618793467], "unit": "score", "name": "Richards", "value": 218.27226599999997}, {"lowerIsBetter": false, "replicates": [170.44486108743823], "unit": "score", "name": "DeltaBlue", "value": 387.91430599999984}, {"lowerIsBetter": false, "replicates": [279.87685418415896], "unit": "score", "name": "Encrypt", "value": 951.0647130000004}, {"lowerIsBetter": false, "replicates": [5010.0], "unit": "score", "name": "Decrypt", "value": 53.12994011976049}, {"lowerIsBetter": false, "replicates": [946.969696969697], "unit": "score", "name": "RayTrace", "value": 781.4283840000004}, {"lowerIsBetter": false, "replicates": [565.9309564233164], "unit": "score", "name": "Earley", "value": 1177.6401210000001}, {"lowerIsBetter": false, "replicates": [10437.5], "unit": "score", "name": "Boyer", "value": 63.85274251497008}, {"lowerIsBetter": false, "replicates": [24902.439024390245], "unit": "score", "name": "RegExp", "value": 36.58215964740448}, {"lowerIsBetter": false, "replicates": [522.1932114882507], "unit": "score", "name": "Splay", "value": 156.055265}, {"lowerIsBetter": false, "replicates": [4812.5], "unit": "score", "name": "NavierStokes", "value": 308.3636363636364}], "name": "v8_7", "value": 23241.025332657962}]} 14:37:26 INFO - RETURN: dromaeo_css: 7189.61 14:37:26 INFO - RETURN: kraken: 1454.60 14:37:26 INFO - RETURN: v8_7: 23241.03 14:37:26 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23241.03"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1454.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7189.61"}}} 14:37:26 INFO - Return code: 0 14:37:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 14:37:26 INFO - # TBPL SUCCESS # 14:37:26 INFO - Running post-action listener: _resource_record_post_action 14:37:26 INFO - Running post-run listener: _resource_record_post_run 14:37:27 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 315392; Write bytes: 511946752; Read time: 56; Write time: 948672 14:37:27 INFO - install - Wall time: 14s; CPU: 17.0%; Read bytes: 0; Write bytes: 220954624; Read time: 0; Write time: 601012 14:37:27 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 315392; Write bytes: 262057984; Read time: 56; Write time: 285184 14:37:27 INFO - Running post-run listener: _upload_blobber_files 14:37:27 INFO - Blob upload gear active. 14:37:27 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 14:37:27 INFO - Copying logs to upload dir... 14:37:27 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1257.420471 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2015-12-31 14:37:27.361664) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-31 14:37:27.364754) ========= 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=01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010085 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451592218/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-31 14:37:27.415281) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 14:37:27.415613) ========= 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=01deea79d00cc2c334834894000001d2-1451595625.127738-1904729844 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004619 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 14:37:27.465398) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-31 14:37:27.465707) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-31 14:37:27.466036) ========= ========= Total master_lag: 0.22 =========