builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1451337989.02 results: success (0) buildid: 20151228111030 builduid: 45114fb5d66b44afb37216ecf901787c revision: b86b3deb9b01 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.024568) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.025057) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.025380) ========= 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-1451337677.96287-1089953967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006438 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.054656) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.054979) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.103483) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.103770) ========= 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-1451337677.96287-1089953967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004629 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.158149) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.158483) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.158875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.159190) ========= 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-1451337677.96287-1089953967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-28 13:26: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% 225M=0s 2015-12-28 13:26:29 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.178689 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.353781) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.354411) ========= 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-1451337677.96287-1089953967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019426 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.413665) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.414281) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b86b3deb9b01 --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 b86b3deb9b01 --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-1451337677.96287-1089953967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-28 13:26:29,639 Setting DEBUG logging. 2015-12-28 13:26:29,639 attempt 1/10 2015-12-28 13:26:29,639 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b86b3deb9b01?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-28 13:26:29,891 unpacking tar archive at: mozilla-beta-b86b3deb9b01/testing/mozharness/ program finished with exit code 0 elapsedTime=0.534532 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.971565) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.972123) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.985400) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.985871) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-28 13:26:29.986542) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2015-12-28 13:26:29.987035) ========= /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-1451337677.96287-1089953967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:26:30 INFO - MultiFileLogger online at 20151228 13:26:30 in /builds/slave/test-pgo 13:26: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 13:26:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 13:26:30 INFO - {'append_to_log': False, 13:26:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 13:26:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:26:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 13:26:30 INFO - 'branch': 'Mozilla-Beta', 13:26:30 INFO - 'buildbot_json_path': 'buildprops.json', 13:26:30 INFO - 'config_files': ('talos/linux_config.py',), 13:26:30 INFO - 'default_actions': ('clobber', 13:26:30 INFO - 'read-buildbot-config', 13:26:30 INFO - 'download-and-extract', 13:26:30 INFO - 'populate-webroot', 13:26:30 INFO - 'create-virtualenv', 13:26:30 INFO - 'install', 13:26:30 INFO - 'run-tests'), 13:26:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:26:30 INFO - 'download_minidump_stackwalk': True, 13:26:30 INFO - 'download_symbols': 'ondemand', 13:26:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:26:30 INFO - 'tooltool.py': '/tools/tooltool.py', 13:26:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:26:30 INFO - '/tools/misc-python/virtualenv.py')}, 13:26:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:26:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:26:30 INFO - 'installer_path': 'installer.exe', 13:26:30 INFO - 'log_level': 'info', 13:26:30 INFO - 'log_name': 'talos', 13:26:30 INFO - 'log_to_console': True, 13:26:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:26:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:26:30 INFO - 'opt_config_files': (), 13:26:30 INFO - 'pip_index': False, 13:26:30 INFO - 'sps_profile': False, 13:26:30 INFO - 'sps_profile_interval': 0, 13:26:30 INFO - 'suite': 'dromaeojs', 13:26:30 INFO - 'system_bits': '32', 13:26:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:26:30 INFO - 'title': 'talos-linux64-ix-014', 13:26:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:26:30 INFO - 'use_talos_json': True, 13:26:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 13:26:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:26:30 INFO - 'work_dir': 'build'} 13:26:30 INFO - ##### 13:26:30 INFO - ##### Running clobber step. 13:26:30 INFO - ##### 13:26:30 INFO - Running pre-action listener: _resource_record_pre_action 13:26:30 INFO - Running main action method: clobber 13:26:30 INFO - rmtree: /builds/slave/test-pgo/build 13:26:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 13:26:31 INFO - Running post-action listener: _resource_record_post_action 13:26:31 INFO - ##### 13:26:31 INFO - ##### Running read-buildbot-config step. 13:26:31 INFO - ##### 13:26:31 INFO - Running pre-action listener: _resource_record_pre_action 13:26:31 INFO - Running main action method: read_buildbot_config 13:26:31 INFO - Using buildbot properties: 13:26:31 INFO - { 13:26:31 INFO - "properties": { 13:26:31 INFO - "buildnumber": 49, 13:26:31 INFO - "product": "firefox", 13:26:31 INFO - "script_repo_revision": "production", 13:26:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:26:31 INFO - "repository": "", 13:26:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 13:26:31 INFO - "buildid": "20151228111030", 13:26:31 INFO - "slavename": "talos-linux64-ix-014", 13:26:31 INFO - "pgo_build": "True", 13:26:31 INFO - "basedir": "/builds/slave/test-pgo", 13:26:31 INFO - "project": "", 13:26:31 INFO - "platform": "ubuntu64_hw", 13:26:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:26:31 INFO - "slavebuilddir": "test", 13:26:31 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 13:26:31 INFO - "branch": "mozilla-beta", 13:26:31 INFO - "repo_path": "releases/mozilla-beta", 13:26:31 INFO - "stage_platform": "linux64-pgo", 13:26:31 INFO - "builduid": "45114fb5d66b44afb37216ecf901787c", 13:26:31 INFO - "revision": "b86b3deb9b01" 13:26:31 INFO - }, 13:26:31 INFO - "sourcestamp": { 13:26:31 INFO - "repository": "", 13:26:31 INFO - "hasPatch": false, 13:26:31 INFO - "project": "", 13:26:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 13:26:31 INFO - "changes": [ 13:26:31 INFO - { 13:26:31 INFO - "category": null, 13:26:31 INFO - "files": [ 13:26:31 INFO - { 13:26:31 INFO - "url": null, 13:26:31 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/firefox-44.0.en-US.linux-x86_64.tar.bz2" 13:26:31 INFO - } 13:26:31 INFO - ], 13:26:31 INFO - "repository": "", 13:26:31 INFO - "rev": "b86b3deb9b01", 13:26:31 INFO - "who": "sendchange", 13:26:31 INFO - "when": 1451337956, 13:26:31 INFO - "number": 7397573, 13:26:31 INFO - "comments": "Bug 1234310 - Enable navigation-redirect.https.html for e10s. r=ehsan a=sylvestre", 13:26:31 INFO - "project": "", 13:26:31 INFO - "at": "Mon 28 Dec 2015 13:25:56", 13:26:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 13:26:31 INFO - "revlink": "", 13:26:31 INFO - "properties": [ 13:26:31 INFO - [ 13:26:31 INFO - "buildid", 13:26:31 INFO - "20151228111030", 13:26:31 INFO - "Change" 13:26:31 INFO - ], 13:26:31 INFO - [ 13:26:31 INFO - "builduid", 13:26:31 INFO - "45114fb5d66b44afb37216ecf901787c", 13:26:31 INFO - "Change" 13:26:31 INFO - ], 13:26:31 INFO - [ 13:26:31 INFO - "pgo_build", 13:26:31 INFO - "True", 13:26:31 INFO - "Change" 13:26:31 INFO - ] 13:26:31 INFO - ], 13:26:31 INFO - "revision": "b86b3deb9b01" 13:26:31 INFO - } 13:26:31 INFO - ], 13:26:31 INFO - "revision": "b86b3deb9b01" 13:26:31 INFO - } 13:26:31 INFO - } 13:26:31 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/firefox-44.0.en-US.linux-x86_64.tar.bz2. 13:26:31 INFO - Running post-action listener: _resource_record_post_action 13:26:31 INFO - ##### 13:26:31 INFO - ##### Running download-and-extract step. 13:26:31 INFO - ##### 13:26:31 INFO - Running pre-action listener: _resource_record_pre_action 13:26:31 INFO - Running main action method: download_and_extract 13:26:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 13:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/test_packages.json 13:26:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 13:26:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 13:26:31 INFO - Downloaded 1270 bytes. 13:26:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 13:26:31 INFO - Using the following test package requirements: 13:26:31 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 13:26:31 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:26:31 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 13:26:31 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:26:31 INFO - u'jsshell-linux-x86_64.zip'], 13:26:31 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:26:31 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 13:26:31 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 13:26:31 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:26:31 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 13:26:31 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:26:31 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 13:26:31 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:26:31 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 13:26:31 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 13:26:31 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:26:31 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 13:26:31 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:26:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/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 13:26:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/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 13:26:32 INFO - Downloaded 22571697 bytes. 13:26:32 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 13:26:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:26:32 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 13:26:33 INFO - Return code: 0 13:26:33 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 13:26:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:33 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:26:33 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/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 13:26:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/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 13:26:33 INFO - Downloaded 22571697 bytes. 13:26: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 13:26:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:26: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 13:26:35 INFO - Return code: 0 13:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 13:26:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/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 13:26:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/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 13:26:36 INFO - Downloaded 11131970 bytes. 13:26:36 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 13:26:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 13:26:36 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 13:26:36 INFO - Return code: 0 13:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:36 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/firefox-44.0.en-US.linux-x86_64.tar.bz2 13:26:36 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/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 13:26:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/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 13:26:40 INFO - Downloaded 56619121 bytes. 13:26:40 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/firefox-44.0.en-US.linux-x86_64.tar.bz2 13:26:40 INFO - mkdir: /builds/slave/test-pgo/properties 13:26:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 13:26:40 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 13:26:40 INFO - Contents: 13:26:40 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/firefox-44.0.en-US.linux-x86_64.tar.bz2 13:26:40 INFO - Running post-action listener: _resource_record_post_action 13:26:40 INFO - Running post-action listener: set_extra_try_arguments 13:26:40 INFO - ##### 13:26:40 INFO - ##### Running populate-webroot step. 13:26:40 INFO - ##### 13:26:40 INFO - Running pre-action listener: _resource_record_pre_action 13:26:40 INFO - Running main action method: populate_webroot 13:26:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:26:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:26:40 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 13:26:40 INFO - '../fennec_ids.txt'], 13:26:40 INFO - 'tests': ['tcheck2']}, 13:26:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:26:40 INFO - '--tppagecycles', 13:26:40 INFO - '7'], 13:26:40 INFO - 'tests': ['tsvgm']}}, 13:26:40 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 13:26:40 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 13:26:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:26:40 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 13:26:40 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 13:26:40 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 13:26:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:26:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:40 INFO - 'talos_options': ['--e10s'], 13:26:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:26:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:40 INFO - 'tests': ['damp', 'tps']}, 13:26:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:40 INFO - 'talos_options': ['--e10s'], 13:26:40 INFO - 'tests': ['damp', 'tps']}, 13:26:40 INFO - 'g3': {'tests': ['dromaeo_dom']}, 13:26:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:26:40 INFO - 'other': {'tests': ['a11yr', 13:26:40 INFO - 'ts_paint', 13:26:40 INFO - 'tpaint', 13:26:40 INFO - 'sessionrestore', 13:26:40 INFO - 'sessionrestore_no_auto_restore']}, 13:26:40 INFO - 'other-e10s': {'talos_options': ['--e10s'], 13:26:40 INFO - 'tests': ['a11yr', 13:26:40 INFO - 'ts_paint', 13:26:40 INFO - 'tpaint', 13:26:40 INFO - 'sessionrestore', 13:26:40 INFO - 'sessionrestore_no_auto_restore']}, 13:26:40 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 13:26:40 INFO - 'tests': ['a11yr', 13:26:40 INFO - 'ts_paint', 13:26:40 INFO - 'tpaint', 13:26:40 INFO - 'sessionrestore', 13:26:40 INFO - 'sessionrestore_no_auto_restore']}, 13:26:40 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 13:26:40 INFO - 'tests': ['a11yr', 13:26:40 INFO - 'ts_paint', 13:26:40 INFO - 'tpaint', 13:26:40 INFO - 'sessionrestore', 13:26:40 INFO - 'sessionrestore_no_auto_restore']}, 13:26:40 INFO - 'other_l64': {'tests': ['a11yr', 13:26:40 INFO - 'ts_paint', 13:26:40 INFO - 'tpaint', 13:26:40 INFO - 'sessionrestore', 13:26:40 INFO - 'sessionrestore_no_auto_restore']}, 13:26:40 INFO - 'other_nol64': {'tests': ['a11yr', 13:26:40 INFO - 'ts_paint', 13:26:40 INFO - 'tpaint', 13:26:40 INFO - 'sessionrestore', 13:26:40 INFO - 'sessionrestore_no_auto_restore']}, 13:26:40 INFO - 'svgr': {'tests': ['tsvgx', 13:26:40 INFO - 'tsvgr_opacity', 13:26:40 INFO - 'tart', 13:26:40 INFO - 'tscrollx', 13:26:40 INFO - 'cart']}, 13:26:40 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 13:26:40 INFO - 'tests': ['tsvgx', 13:26:40 INFO - 'tsvgr_opacity', 13:26:40 INFO - 'tart', 13:26:40 INFO - 'tscrollx', 13:26:40 INFO - 'cart']}, 13:26:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:40 INFO - 'tests': ['tp5o']}, 13:26:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:40 INFO - 'talos_options': ['--e10s'], 13:26:40 INFO - 'tests': ['tp5o']}, 13:26:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:40 INFO - 'talos_options': ['--xperf_path', 13:26:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:26:40 INFO - 'tests': ['tp5n']}, 13:26:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:40 INFO - 'talos_options': ['--e10s', 13:26:40 INFO - '--xperf_path', 13:26:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:26:40 INFO - 'tests': ['tp5n']}}, 13:26:40 INFO - 'talos.zip': {'path': '', 13:26:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:26:40 INFO - Running post-action listener: _resource_record_post_action 13:26:40 INFO - ##### 13:26:40 INFO - ##### Running create-virtualenv step. 13:26:40 INFO - ##### 13:26:40 INFO - Running pre-action listener: _resource_record_pre_action 13:26:40 INFO - Running main action method: create_virtualenv 13:26:40 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 13:26:40 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 13:26:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 13:26:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:26:40 INFO - Using real prefix '/usr' 13:26:40 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 13:26:41 INFO - Installing distribute.............................................................................................................................................................................................done. 13:26:43 INFO - Installing pip.................done. 13:26:43 INFO - Return code: 0 13:26:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 13:26: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')]} 13:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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 0x7f17a33a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f87350>, '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 0x1f88250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f886e0>, '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-1451337677.96287-1089953967', '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 13:26: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 13:26: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 13:26:43 INFO - Using env: {'DISPLAY': ':0', 13:26:43 INFO - 'HOME': '/home/cltbld', 13:26:43 INFO - 'LANG': 'en_US.UTF-8', 13:26:43 INFO - 'LANGUAGE': 'en_US:en', 13:26:43 INFO - 'LOGNAME': 'cltbld', 13:26:43 INFO - 'MAIL': '/var/mail/cltbld', 13:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:43 INFO - 'MOZ_NO_REMOTE': '1', 13:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:43 INFO - 'NO_EM_RESTART': '1', 13:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:26:43 INFO - 'PWD': '/builds/slave/test-pgo', 13:26:43 INFO - 'SHELL': '/bin/bash', 13:26:43 INFO - 'SHLVL': '1', 13:26:43 INFO - 'TERM': 'linux', 13:26:43 INFO - 'TMOUT': '86400', 13:26:43 INFO - 'USER': 'cltbld', 13:26:43 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451337677.96287-1089953967', 13:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:43 INFO - '_': '/tools/buildbot/bin/python'} 13:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26: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 13:26:43 INFO - Cleaning up... 13:26:43 INFO - Return code: 0 13:26:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 13:26: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')]} 13:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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 0x7f17a33a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f87350>, '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 0x1f88250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f886e0>, '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-1451337677.96287-1089953967', '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 13:26: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 13:26: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 13:26:43 INFO - Using env: {'DISPLAY': ':0', 13:26:43 INFO - 'HOME': '/home/cltbld', 13:26:43 INFO - 'LANG': 'en_US.UTF-8', 13:26:43 INFO - 'LANGUAGE': 'en_US:en', 13:26:43 INFO - 'LOGNAME': 'cltbld', 13:26:43 INFO - 'MAIL': '/var/mail/cltbld', 13:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:43 INFO - 'MOZ_NO_REMOTE': '1', 13:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:43 INFO - 'NO_EM_RESTART': '1', 13:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:26:43 INFO - 'PWD': '/builds/slave/test-pgo', 13:26:43 INFO - 'SHELL': '/bin/bash', 13:26:43 INFO - 'SHLVL': '1', 13:26:43 INFO - 'TERM': 'linux', 13:26:43 INFO - 'TMOUT': '86400', 13:26:43 INFO - 'USER': 'cltbld', 13:26:43 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451337677.96287-1089953967', 13:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:43 INFO - '_': '/tools/buildbot/bin/python'} 13:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:43 INFO - Downloading/unpacking psutil>=0.7.1 13:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:45 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 13:26:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 13:26:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 13:26:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:26:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:26:45 INFO - Installing collected packages: psutil 13:26:45 INFO - Running setup.py install for psutil 13:26:45 INFO - building 'psutil._psutil_linux' extension 13:26: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 13:26:45 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 13:26:45 INFO - building 'psutil._psutil_posix' extension 13:26:45 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 13:26:45 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 13:26:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:26:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:26:45 INFO - Successfully installed psutil 13:26:45 INFO - Cleaning up... 13:26:46 INFO - Return code: 0 13:26:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 13:26: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')]} 13:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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 0x7f17a33a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f87350>, '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 0x1f88250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f886e0>, '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-1451337677.96287-1089953967', '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 13:26: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 13:26: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 13:26:46 INFO - Using env: {'DISPLAY': ':0', 13:26:46 INFO - 'HOME': '/home/cltbld', 13:26:46 INFO - 'LANG': 'en_US.UTF-8', 13:26:46 INFO - 'LANGUAGE': 'en_US:en', 13:26:46 INFO - 'LOGNAME': 'cltbld', 13:26:46 INFO - 'MAIL': '/var/mail/cltbld', 13:26:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:46 INFO - 'MOZ_NO_REMOTE': '1', 13:26:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:46 INFO - 'NO_EM_RESTART': '1', 13:26:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:26:46 INFO - 'PWD': '/builds/slave/test-pgo', 13:26:46 INFO - 'SHELL': '/bin/bash', 13:26:46 INFO - 'SHLVL': '1', 13:26:46 INFO - 'TERM': 'linux', 13:26:46 INFO - 'TMOUT': '86400', 13:26:46 INFO - 'USER': 'cltbld', 13:26:46 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451337677.96287-1089953967', 13:26:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:46 INFO - '_': '/tools/buildbot/bin/python'} 13:26:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:46 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:26:47 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 13:26:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:26:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:26:47 INFO - Installing collected packages: mozsystemmonitor 13:26:47 INFO - Running setup.py install for mozsystemmonitor 13:26:48 INFO - Successfully installed mozsystemmonitor 13:26:48 INFO - Cleaning up... 13:26:48 INFO - Return code: 0 13:26:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 13:26: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')]} 13:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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 0x7f17a33a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f87350>, '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 0x1f88250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f886e0>, '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-1451337677.96287-1089953967', '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 13:26: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 13:26: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 13:26:48 INFO - Using env: {'DISPLAY': ':0', 13:26:48 INFO - 'HOME': '/home/cltbld', 13:26:48 INFO - 'LANG': 'en_US.UTF-8', 13:26:48 INFO - 'LANGUAGE': 'en_US:en', 13:26:48 INFO - 'LOGNAME': 'cltbld', 13:26:48 INFO - 'MAIL': '/var/mail/cltbld', 13:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:48 INFO - 'MOZ_NO_REMOTE': '1', 13:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:48 INFO - 'NO_EM_RESTART': '1', 13:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:26:48 INFO - 'PWD': '/builds/slave/test-pgo', 13:26:48 INFO - 'SHELL': '/bin/bash', 13:26:48 INFO - 'SHLVL': '1', 13:26:48 INFO - 'TERM': 'linux', 13:26:48 INFO - 'TMOUT': '86400', 13:26:48 INFO - 'USER': 'cltbld', 13:26:48 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451337677.96287-1089953967', 13:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:48 INFO - '_': '/tools/buildbot/bin/python'} 13:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:48 INFO - Downloading/unpacking blobuploader==1.2.4 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26:49 INFO - Downloading blobuploader-1.2.4.tar.gz 13:26:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 13:26:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:26:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 13:26:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 13:26:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26:50 INFO - Downloading docopt-0.6.1.tar.gz 13:26:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 13:26:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 13:26:50 INFO - Installing collected packages: blobuploader, requests, docopt 13:26:50 INFO - Running setup.py install for blobuploader 13:26:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:26:50 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 13:26:50 INFO - Running setup.py install for requests 13:26:51 INFO - Running setup.py install for docopt 13:26:51 INFO - Successfully installed blobuploader requests docopt 13:26:51 INFO - Cleaning up... 13:26:51 INFO - Return code: 0 13:26:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:26: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')]} 13:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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 0x7f17a33a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f87350>, '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 0x1f88250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f886e0>, '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-1451337677.96287-1089953967', '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 13:26: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 13:26: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 13:26:51 INFO - Using env: {'DISPLAY': ':0', 13:26:51 INFO - 'HOME': '/home/cltbld', 13:26:51 INFO - 'LANG': 'en_US.UTF-8', 13:26:51 INFO - 'LANGUAGE': 'en_US:en', 13:26:51 INFO - 'LOGNAME': 'cltbld', 13:26:51 INFO - 'MAIL': '/var/mail/cltbld', 13:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:51 INFO - 'MOZ_NO_REMOTE': '1', 13:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:51 INFO - 'NO_EM_RESTART': '1', 13:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:26:51 INFO - 'PWD': '/builds/slave/test-pgo', 13:26:51 INFO - 'SHELL': '/bin/bash', 13:26:51 INFO - 'SHLVL': '1', 13:26:51 INFO - 'TERM': 'linux', 13:26:51 INFO - 'TMOUT': '86400', 13:26:51 INFO - 'USER': 'cltbld', 13:26:51 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451337677.96287-1089953967', 13:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:51 INFO - '_': '/tools/buildbot/bin/python'} 13:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:26:51 INFO - Running setup.py (path:/tmp/pip-3z1TW5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:26:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:26:51 INFO - Running setup.py (path:/tmp/pip-0T0R2Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:26:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:26:51 INFO - Running setup.py (path:/tmp/pip-Mu_DdQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:26:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:26:51 INFO - Running setup.py (path:/tmp/pip-Jt79tS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:26:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 13:26:51 INFO - Running setup.py (path:/tmp/pip-RxeWUR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:26:52 INFO - Running setup.py (path:/tmp/pip-80iEhA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:26:52 INFO - Running setup.py (path:/tmp/pip-kl83rv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:26:52 INFO - Running setup.py (path:/tmp/pip-yPKBRx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 13:26:52 INFO - Running setup.py (path:/tmp/pip-fmYJz2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 13:26:52 INFO - Running setup.py (path:/tmp/pip-gQNPAa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:26:52 INFO - Running setup.py (path:/tmp/pip-hZCXbA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:26:52 INFO - Running setup.py (path:/tmp/pip-K44WKO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:26:52 INFO - Running setup.py (path:/tmp/pip-fjATQF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:26:52 INFO - Running setup.py (path:/tmp/pip-osx7LK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:26:52 INFO - Running setup.py (path:/tmp/pip-ADn1i0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 13:26:52 INFO - Running setup.py (path:/tmp/pip-8IkFwt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 13:26:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 13:26:52 INFO - Running setup.py (path:/tmp/pip-hapHhw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 13:26:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:26:52 INFO - Running setup.py install for manifestparser 13:26:52 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 13:26:52 INFO - Running setup.py install for mozcrash 13:26:53 INFO - Running setup.py install for mozdebug 13:26:53 INFO - Running setup.py install for mozdevice 13:26:53 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 13:26:53 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 13:26:53 INFO - Running setup.py install for mozfile 13:26:53 INFO - Running setup.py install for mozhttpd 13:26:53 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 13:26:53 INFO - Running setup.py install for mozinfo 13:26:53 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 13:26:53 INFO - Running setup.py install for mozInstall 13:26:53 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 13:26:53 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 13:26:53 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 13:26:53 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 13:26:53 INFO - Running setup.py install for mozleak 13:26:53 INFO - Running setup.py install for mozlog 13:26:53 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 13:26:53 INFO - Running setup.py install for moznetwork 13:26:54 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 13:26:54 INFO - Running setup.py install for mozprocess 13:26:54 INFO - Running setup.py install for mozprofile 13:26:54 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 13:26:54 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 13:26:54 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 13:26:54 INFO - Running setup.py install for mozrunner 13:26:54 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 13:26:54 INFO - Running setup.py install for mozscreenshot 13:26:54 INFO - Running setup.py install for moztest 13:26:54 INFO - Running setup.py install for mozversion 13:26:54 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 13:26:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:26:54 INFO - Cleaning up... 13:26:54 INFO - Return code: 0 13:26:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:26: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')]} 13:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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 0x7f17a33a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f87350>, '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 0x1f88250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f886e0>, '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-1451337677.96287-1089953967', '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 13:26: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 13:26: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 13:26:54 INFO - Using env: {'DISPLAY': ':0', 13:26:54 INFO - 'HOME': '/home/cltbld', 13:26:54 INFO - 'LANG': 'en_US.UTF-8', 13:26:54 INFO - 'LANGUAGE': 'en_US:en', 13:26:54 INFO - 'LOGNAME': 'cltbld', 13:26:54 INFO - 'MAIL': '/var/mail/cltbld', 13:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:54 INFO - 'MOZ_NO_REMOTE': '1', 13:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:54 INFO - 'NO_EM_RESTART': '1', 13:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:26:54 INFO - 'PWD': '/builds/slave/test-pgo', 13:26:54 INFO - 'SHELL': '/bin/bash', 13:26:54 INFO - 'SHLVL': '1', 13:26:54 INFO - 'TERM': 'linux', 13:26:54 INFO - 'TMOUT': '86400', 13:26:54 INFO - 'USER': 'cltbld', 13:26:54 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451337677.96287-1089953967', 13:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:54 INFO - '_': '/tools/buildbot/bin/python'} 13:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:26:54 INFO - Running setup.py (path:/tmp/pip-6qgfGB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:26:54 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)) 13:26:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:26:54 INFO - Running setup.py (path:/tmp/pip-KYy9Ni-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:26: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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:26:55 INFO - Running setup.py (path:/tmp/pip-XL6cdC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:26: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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:26:55 INFO - Running setup.py (path:/tmp/pip-AT67N5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:26: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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 13:26:55 INFO - Running setup.py (path:/tmp/pip-GyZruk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 13:26: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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:26:55 INFO - Running setup.py (path:/tmp/pip-oQqGQZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:26: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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:26:55 INFO - Running setup.py (path:/tmp/pip-blJ4EA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:26: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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:26:55 INFO - Running setup.py (path:/tmp/pip-9IpGN9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:26: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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 13:26:55 INFO - Running setup.py (path:/tmp/pip-lYkzH3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 13:26: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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 13:26:55 INFO - Running setup.py (path:/tmp/pip-EUHG4r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 13:26: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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:26:55 INFO - Running setup.py (path:/tmp/pip-tpeagY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:26: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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:26:55 INFO - Running setup.py (path:/tmp/pip-aDaKXy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:26:55 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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:26:55 INFO - Running setup.py (path:/tmp/pip-v9nJyU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:26:55 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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:26:55 INFO - Running setup.py (path:/tmp/pip-BTKpNn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:26:55 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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:26:55 INFO - Running setup.py (path:/tmp/pip-mPJ8GD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:26:55 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)) 13:26:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 13:26:55 INFO - Running setup.py (path:/tmp/pip-506drC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 13:26: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)) 13:26:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 13:26:56 INFO - Running setup.py (path:/tmp/pip-0OzLAe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26:57 INFO - Downloading blessings-1.5.1.tar.gz 13:26:57 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 13:26:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 13:26:57 INFO - Installing collected packages: blessings 13:26:57 INFO - Running setup.py install for blessings 13:26:57 INFO - Successfully installed blessings 13:26:57 INFO - Cleaning up... 13:26:58 INFO - Return code: 0 13:26:58 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 13:26:58 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 13:26:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 13:26:58 INFO - Reading from file tmpfile_stdout 13:26:58 INFO - Current package versions: 13:26:58 INFO - argparse == 1.2.1 13:26:58 INFO - blessings == 1.5.1 13:26:58 INFO - blobuploader == 1.2.4 13:26:58 INFO - docopt == 0.6.1 13:26:58 INFO - manifestparser == 1.1 13:26:58 INFO - mozInstall == 1.12 13:26:58 INFO - mozcrash == 0.16 13:26:58 INFO - mozdebug == 0.1 13:26:58 INFO - mozdevice == 0.46 13:26:58 INFO - mozfile == 1.2 13:26:58 INFO - mozhttpd == 0.7 13:26:58 INFO - mozinfo == 0.8 13:26:58 INFO - mozleak == 0.1 13:26:58 INFO - mozlog == 3.0 13:26:58 INFO - moznetwork == 0.27 13:26:58 INFO - mozprocess == 0.22 13:26:58 INFO - mozprofile == 0.27 13:26:58 INFO - mozrunner == 6.11 13:26:58 INFO - mozscreenshot == 0.1 13:26:58 INFO - mozsystemmonitor == 0.0 13:26:58 INFO - moztest == 0.7 13:26:58 INFO - mozversion == 1.4 13:26:58 INFO - psutil == 3.1.1 13:26:58 INFO - requests == 1.2.3 13:26:58 INFO - wsgiref == 0.1.2 13:26:58 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:26: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')]} 13:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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 0x7f17a33a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f87350>, '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 0x1f88250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f886e0>, '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-1451337677.96287-1089953967', '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 13:26: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 13:26: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 13:26:58 INFO - Using env: {'DISPLAY': ':0', 13:26:58 INFO - 'HOME': '/home/cltbld', 13:26:58 INFO - 'LANG': 'en_US.UTF-8', 13:26:58 INFO - 'LANGUAGE': 'en_US:en', 13:26:58 INFO - 'LOGNAME': 'cltbld', 13:26:58 INFO - 'MAIL': '/var/mail/cltbld', 13:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:58 INFO - 'MOZ_NO_REMOTE': '1', 13:26:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:58 INFO - 'NO_EM_RESTART': '1', 13:26:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:26:58 INFO - 'PWD': '/builds/slave/test-pgo', 13:26:58 INFO - 'SHELL': '/bin/bash', 13:26:58 INFO - 'SHLVL': '1', 13:26:58 INFO - 'TERM': 'linux', 13:26:58 INFO - 'TMOUT': '86400', 13:26:58 INFO - 'USER': 'cltbld', 13:26:58 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451337677.96287-1089953967', 13:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:58 INFO - '_': '/tools/buildbot/bin/python'} 13:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26: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)) 13:26:58 INFO - Cleaning up... 13:26:58 INFO - Return code: 0 13:26:58 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 13:26: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')]} 13:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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 0x7f17a33a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f87350>, '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 0x1f88250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f886e0>, '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-1451337677.96287-1089953967', '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 13:26: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 13:26: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 13:26:58 INFO - Using env: {'DISPLAY': ':0', 13:26:58 INFO - 'HOME': '/home/cltbld', 13:26:58 INFO - 'LANG': 'en_US.UTF-8', 13:26:58 INFO - 'LANGUAGE': 'en_US:en', 13:26:58 INFO - 'LOGNAME': 'cltbld', 13:26:58 INFO - 'MAIL': '/var/mail/cltbld', 13:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:58 INFO - 'MOZ_NO_REMOTE': '1', 13:26:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:58 INFO - 'NO_EM_RESTART': '1', 13:26:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:26:58 INFO - 'PWD': '/builds/slave/test-pgo', 13:26:58 INFO - 'SHELL': '/bin/bash', 13:26:58 INFO - 'SHLVL': '1', 13:26:58 INFO - 'TERM': 'linux', 13:26:58 INFO - 'TMOUT': '86400', 13:26:58 INFO - 'USER': 'cltbld', 13:26:58 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451337677.96287-1089953967', 13:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:58 INFO - '_': '/tools/buildbot/bin/python'} 13:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:58 INFO - Downloading/unpacking jsonschema 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:26: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 13:27:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:27: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 13:27:00 INFO - Downloading/unpacking functools32 (from jsonschema) 13:27: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 13:27: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 13:27: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 13:27: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 13:27: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 13:27: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 13:27:00 INFO - Downloading functools32-3.2.3-2.tar.gz 13:27: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 13:27:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 13:27:00 INFO - warning: no files found matching '*.txt' 13:27:00 INFO - no previously-included directories found matching 'build' 13:27:00 INFO - no previously-included directories found matching 'dist' 13:27:00 INFO - no previously-included directories found matching '.git*' 13:27:00 INFO - Installing collected packages: jsonschema, functools32 13:27:00 INFO - Running setup.py install for functools32 13:27:00 INFO - warning: no files found matching '*.txt' 13:27:00 INFO - no previously-included directories found matching 'build' 13:27:00 INFO - no previously-included directories found matching 'dist' 13:27:00 INFO - no previously-included directories found matching '.git*' 13:27:00 INFO - Successfully installed jsonschema functools32 13:27:00 INFO - Cleaning up... 13:27:00 INFO - Return code: 0 13:27:00 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 13:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f17a33a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f87350>, '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 0x1f88250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f886e0>, '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-1451337677.96287-1089953967', '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 13:27:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 13:27:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:27:00 INFO - Using env: {'DISPLAY': ':0', 13:27:00 INFO - 'HOME': '/home/cltbld', 13:27:00 INFO - 'LANG': 'en_US.UTF-8', 13:27:00 INFO - 'LANGUAGE': 'en_US:en', 13:27:00 INFO - 'LOGNAME': 'cltbld', 13:27:00 INFO - 'MAIL': '/var/mail/cltbld', 13:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:00 INFO - 'MOZ_NO_REMOTE': '1', 13:27:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:00 INFO - 'NO_EM_RESTART': '1', 13:27:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:27:00 INFO - 'PWD': '/builds/slave/test-pgo', 13:27:00 INFO - 'SHELL': '/bin/bash', 13:27:00 INFO - 'SHLVL': '1', 13:27:00 INFO - 'TERM': 'linux', 13:27:00 INFO - 'TMOUT': '86400', 13:27:00 INFO - 'USER': 'cltbld', 13:27:00 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451337677.96287-1089953967', 13:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:00 INFO - '_': '/tools/buildbot/bin/python'} 13:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:01 INFO - Downloading/unpacking flake8 13:27: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 13:27: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 13:27: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 13:27: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 13:27: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 13:27: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 13:27:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:27:02 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 13:27:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:27:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:27:02 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 13:27:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:27:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27: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 13:27:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:27: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 13:27: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 13:27: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 13:27: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 13:27: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 13:27: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 13:27:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 13:27:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 13:27:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:27:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:27:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:27:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:27:03 INFO - no previously-included directories found matching 'docs/_build' 13:27:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:27:03 INFO - Running setup.py install for pep8 13:27:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:27:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:27:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:27:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:27:03 INFO - no previously-included directories found matching 'docs/_build' 13:27:03 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 13:27:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:27:03 INFO - Cleaning up... 13:27:03 INFO - Return code: 0 13:27:03 INFO - Running post-action listener: _resource_record_post_action 13:27:03 INFO - Running post-action listener: _start_resource_monitoring 13:27:03 INFO - Starting resource monitoring. 13:27:03 INFO - ##### 13:27:03 INFO - ##### Running install step. 13:27:03 INFO - ##### 13:27:03 INFO - Running pre-action listener: _resource_record_pre_action 13:27:03 INFO - Running main action method: install 13:27:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 13:27:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 13:27:03 INFO - Reading from file tmpfile_stdout 13:27:03 INFO - Detecting whether we're running mozinstall >=1.0... 13:27:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 13:27:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 13:27:03 INFO - Reading from file tmpfile_stdout 13:27:03 INFO - Output received: 13:27:03 INFO - Usage: mozinstall [options] installer 13:27:03 INFO - Options: 13:27:03 INFO - -h, --help show this help message and exit 13:27:03 INFO - -d DEST, --destination=DEST 13:27:03 INFO - Directory to install application into. [default: 13:27:03 INFO - "/builds/slave/test-pgo"] 13:27:03 INFO - --app=APP Application being installed. [default: firefox] 13:27:03 INFO - mkdir: /builds/slave/test-pgo/build/application 13:27:03 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'] 13:27:03 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 13:27:18 INFO - Reading from file tmpfile_stdout 13:27:18 INFO - Output received: 13:27:18 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 13:27:18 INFO - Running post-action listener: _resource_record_post_action 13:27:18 INFO - ##### 13:27:18 INFO - ##### Running run-tests step. 13:27:18 INFO - ##### 13:27:18 INFO - Running pre-action listener: _resource_record_pre_action 13:27:18 INFO - Running main action method: run_tests 13:27:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 13:27:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 13:27:18 INFO - Python 2.7.3 13:27:18 INFO - Return code: 0 13:27:18 INFO - grabbing minidump binary from tooltool 13:27: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')]} 13:27: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 0x1f87350>, '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 0x1f88250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f886e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 13:27: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 13:27: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 13:27:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:27:18 INFO - Return code: 0 13:27:18 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 13:27:18 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 13:27:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 13:27:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 13:27:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 13:27:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 13:27:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 13:27:19 INFO - Return code: 0 13:27: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/1451329830/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 13:27: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/1451329830/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --webServer localhost 13:27:19 INFO - Using env: {'DISPLAY': ':0', 13:27:19 INFO - 'HOME': '/home/cltbld', 13:27:19 INFO - 'LANG': 'en_US.UTF-8', 13:27:19 INFO - 'LANGUAGE': 'en_US:en', 13:27:19 INFO - 'LOGNAME': 'cltbld', 13:27:19 INFO - 'MAIL': '/var/mail/cltbld', 13:27:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 13:27:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 13:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:19 INFO - 'MOZ_NO_REMOTE': '1', 13:27:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 13:27:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:19 INFO - 'NO_EM_RESTART': '1', 13:27:19 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:27:19 INFO - 'PWD': '/builds/slave/test-pgo', 13:27:19 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 13:27:19 INFO - 'SHELL': '/bin/bash', 13:27:19 INFO - 'SHLVL': '1', 13:27:19 INFO - 'TERM': 'linux', 13:27:19 INFO - 'TMOUT': '86400', 13:27:19 INFO - 'USER': 'cltbld', 13:27:19 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451337677.96287-1089953967', 13:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:19 INFO - '_': '/tools/buildbot/bin/python'} 13:27: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/1451329830/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] with output_timeout 3600 13:27:19 INFO - mozversion INFO | application_buildid: 20151228111030 13:27:19 INFO - mozversion INFO | application_changeset: b86b3deb9b01d5a7e454c9a8f9b79120d8fa9e24 13:27:19 INFO - mozversion INFO | application_display_name: Nightly 13:27:19 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:27:19 INFO - mozversion INFO | application_name: Firefox 13:27:19 INFO - mozversion INFO | application_remotingname: firefox 13:27:19 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:27:19 INFO - mozversion INFO | application_vendor: Mozilla 13:27:19 INFO - mozversion INFO | application_version: 44.0 13:27:19 INFO - mozversion INFO | platform_buildid: 20151228111030 13:27:19 INFO - mozversion INFO | platform_changeset: b86b3deb9b01d5a7e454c9a8f9b79120d8fa9e24 13:27:19 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:27:19 INFO - mozversion INFO | platform_version: 44.0 13:27:19 INFO - 2015-12-28 13:27:19,339 DEBUG : using testdate: 1451338039 13:27:19 INFO - 2015-12-28 13:27:19,339 DEBUG : actual date: 1451338039 13:27:19 INFO - 2015-12-28 13:27:19,444 INFO : starting webserver on 'localhost:35893' 13:27:19 INFO - 2015-12-28 13:27:19,446 INFO : Starting test suite talos-linux64-ix-014 13:27:19 INFO - 2015-12-28 13:27:19,446 INFO : Starting test dromaeo_css 13:27:19 INFO - 2015-12-28 13:27:19,446 DEBUG : operating with platform_type : linux_ 13:27:19 INFO - 2015-12-28 13:27:19,446 INFO : Initialising browser for dromaeo_css test... 13:27:19 INFO - 2015-12-28 13:27:19,459 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUi8kGh/profile http://localhost:35893/getInfo.html 13:27:23 INFO - 2015-12-28 13:27:23,260 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:27:23 INFO - 2015-12-28 13:27:23,260 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:27:23 INFO - 2015-12-28 13:27:23,260 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:27:23 INFO - 2015-12-28 13:27:23,260 DEBUG : BROWSER_OUTPUT: __metrics 13:27:23 INFO - 2015-12-28 13:27:23,336 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] 13:27:24 INFO - 2015-12-28 13:27:24,705 INFO : Browser initialized. 13:27:24 INFO - 2015-12-28 13:27:24,706 INFO : Running cycle 1/1 for dromaeo_css test... 13:27:24 INFO - 2015-12-28 13:27:24,706 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUi8kGh/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:27:36 INFO - 2015-12-28 13:27:36,195 DEBUG : BROWSER_OUTPUT: RSS: Main: 129105920 13:27:36 INFO - 2015-12-28 13:27:36,195 DEBUG : BROWSER_OUTPUT: 13:28:27 INFO - 2015-12-28 13:28:27,009 DEBUG : BROWSER_OUTPUT: 1451338107008 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] 13:30:35 INFO - 2015-12-28 13:30:35,052 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35893/tests/dromaeo/cssquery-ext.html) 13:30:36 INFO - 2015-12-28 13:30:36,056 DEBUG : BROWSER_OUTPUT: RSS: Main: 229122048 13:30:36 INFO - 2015-12-28 13:30:36,056 DEBUG : BROWSER_OUTPUT: 13:33:38 INFO - 2015-12-28 13:33:38,080 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/dromaeo/cssquery-ext.html (next: http://localhost:35893/tests/dromaeo/cssquery-jquery.html) 13:33:38 INFO - 2015-12-28 13:33:38,643 DEBUG : BROWSER_OUTPUT: RSS: Main: 208039936 13:33:38 INFO - 2015-12-28 13:33:38,644 DEBUG : BROWSER_OUTPUT: 13:36:40 INFO - 2015-12-28 13:36:40,387 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35893/tests/dromaeo/cssquery-mootools.html) 13:36:40 INFO - 2015-12-28 13:36:40,955 DEBUG : BROWSER_OUTPUT: RSS: Main: 209498112 13:36:40 INFO - 2015-12-28 13:36:40,955 DEBUG : BROWSER_OUTPUT: 13:39:42 INFO - 2015-12-28 13:39:42,694 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35893/tests/dromaeo/cssquery-prototype.html) 13:39:43 INFO - 2015-12-28 13:39:43,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 191078400 13:39:43 INFO - 2015-12-28 13:39:43,256 DEBUG : BROWSER_OUTPUT: 13:42:45 INFO - 2015-12-28 13:42:45,645 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35893/tests/dromaeo/cssquery-yui.html) 13:42:46 INFO - 2015-12-28 13:42:46,218 DEBUG : BROWSER_OUTPUT: RSS: Main: 192352256 13:42:46 INFO - 2015-12-28 13:42:46,219 DEBUG : BROWSER_OUTPUT: 13:45:48 INFO - 2015-12-28 13:45:48,294 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/dromaeo/cssquery-yui.html (next: http://localhost:35893/tests/dromaeo/cssquery-dojo.html) 13:45:48 INFO - 2015-12-28 13:45:48,544 DEBUG : BROWSER_OUTPUT: RSS: Main: 237928448 13:45:48 INFO - 2015-12-28 13:45:48,545 DEBUG : BROWSER_OUTPUT: 13:45:48 INFO - 2015-12-28 13:45:48,545 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:45:48 INFO - 2015-12-28 13:45:48,545 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:45:48 INFO - 2015-12-28 13:45:48,545 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:45:48 INFO - 2015-12-28 13:45:48,545 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:45:48 INFO - 2015-12-28 13:45:48,547 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3316.2206881382876;3402.0474267205177;3402.4726167444055;3403.9788063580936;3429.537012503313;5600.775968961242;5625.662460252388;5634.4928956393915;5652.745626446809;5660.716964151797;5720.9713951430185;5747.59766816323;5764.452377024176;5771.249737534125;5780.624259423134;4995.500449955011;5210.478952104797;5245.527902488775;5308.70802105884;5330.946690533103;5169.819056333029;5240.764165612538;5249.448807875176;5253.500917412859;5262.079136151177;5123.205903085028;5228.816991405301;5252.448492908247;5256.737163141846;5264.894702105956;5222.242774797671;5244.895102097956;5258.0535503609335;5306.3632364116165;5310.070737620901;4935.605151587864;5013.623978201649;5050.974745126287;5169.198774190007;5229.08491014071;5443.482869127427;5448.318960129984;5460;5461.481159289861;5468.398476167618;6050.42520960508;6056.424639659225;6058.1518587397795;6066.575339726221;6068.150458935753;6014.609050411753;6046.96976515119;6128.96935515324;6135.32511423743;6148.538859585504;5741.655500669973;5745.971270143665;5746.885062298772;5757.424257574293;5765.336986246593;6264.749410023639;6287;6292.937070629327;6296.33888441707;6322.747090116436;5799.82600521985;5833.620814647034;5934.851628709317;5943;5990.490808281258;6109.633421994695;6156.415140561639;6159.630422174684;6160.599561028519;6167.876642467126;5941.1088336749835;6097.359777223416;6104.938950610437;6122.846928826815;6127.632342059491;5981.730822113015;6010.2186715726675;6117.176182440033;6138.662373569497;6143.078538219213;4604.355390245369;4610.700304480198;4613.400257966443;4637.7449240291435;4656.3713898623255;3998.8000599969887;4104.753714777123;4164.4586203793915;4179.540250572434;4203.936940945889;5645.717714114277;5677.403872593318;5715.399883012307;5721.427857214246;5724.398938111522;6055.333913269536;6072.119542666289;6074.3925607438905;6082.5438092142385;6087.54343424245;5612.242347283146;5678.3186017678145;5718.456746609066;5731.541476681856;5749.655020698771;5297.867553311198;5343.064963631349;5343.839684809462;5352.357717073976;5361.329833770778;6335.3981371769605;6340.1440805490665;6340.841478963062;6353.682315884187;6357.364263573698;5864.384239654775;5981.312149102909;6069.757209711562;6165.599236049642;6171.59884607499;6280.371962803866;6292.559520833498;6297.590656607306;6302.873942521032;6309.400606942333;5924.289085309791;5965.403459654;5965.522758179422;5965.552583556164;5985.670788106696;5922.407759224043;5968.253968253968;6056.0915862619195;6077.301110372364;6107.450329470369;5945.524358051432;5947.583669143119;5947.881042379042;5955.821325360246;6006.309274433496;5736.799212027559;5892.263467066617;5936.1689363488285;5948.821535353946;5951.880962380815;5746.109353050284;5810.331811841692;5813.82558523245;5823.4467725566;5842.795502157404;5951.702414879325;5989.371116032754;5990.4908082813445;6008.519318454426;6015.488683461955;5661.575381846295;5735.7705691773535;5875.3243377012195;5883.499902508334;5888.116782482491 13:45:48 INFO - 2015-12-28 13:45:48,550 DEBUG : BROWSER_OUTPUT: |1;ext.html;609.3145211636909;640.5004096804493;648.8604949935763;649.5290914087286;720.884658454647;36586.63413365869;44060.77969610151;44744;44791.552084479146;44846.7757661212;48105.037899241994;48617;48762.268565971535;48785;48792.2681159782;8151.429399942006;8321.791955201139;9365.906340936588;9540.522973851314;9546.761330966712;11126.777464450706;11421.25761825483;11423.029042531394;11425.485853136588;11426.143039272089;10877.129829613652;10973.780524389507;10997.450127493634;10997.780044399107;11007.394593297366;11037.337759734442;11103.38931358775;11127.387993660346;11130.443477826117;11144.944275278653;10909.509072091696;11016.394098324588;11035.282706624044;11040.227184097117;11042.668719938334;44505;44935.77532112351;45027;45033.774831125964;45034;3781.130340021783;3798.9810050949845;3870.612938706107;3963.346047902093;4502.459704835408;34050.48924266113;34142.48786268208;34162.31675366479;34220.82889585561;34482.31035379304;3937.98031009846;3994.2683145262313;4003.799810009488;4008;4052.108536122078;299264;302535;304521;305283;306054;60779;61624;70123.2987670127;70841;78892.60553697195;73652.63173684204;73692.63153684197;73977;73991.63004185053;74036.62981685057;18723.438296851116;18766.81233187678;18866.905665471586;18882.150303236387;18958.62082758337;14076.155430674193;14155.504557340444;14190.077644953046;14195.716085678228;14197.077189982618;23016.769832301798;23331.41671458193;23360.299191024296;23425.41436464068;23435.179768708353;8478.618462169217;8741.256993155526;8790.120987901286;8960.955195224113;8973.820523589491;971.8590804333473;1076.9299995500267;1084.8264277715527;1092.814371257485;1191.2614179208772;30151;30847.84576077105;31093.22266943299;31270.218244543612;31731.52402713961;1086.3481910853425;1105.4472763618191;1168.3690806964314;1198.3049831098117;1199.4602428907026;14759.483418080315;16290.83709162917;16313;16318.510444686679;17273.91363043177;418093;420325;424991;426641;427658;64329.356706433275;64436.35563644397;64884;64953;65025.34974650288;1376.800363947222;1400.4538230090272;1452.3174108169144;1516.726989141965;1593.9521814345587;32296.677033229367;32330;32347.19132021718;32389.514157287187;33033.17417064593;52241.21638175355;52688.47311526836;52701.472985269655;52786.472135278156;52848.73575632251;309826;309936;311001;311164;311463;36774.8161259192;37416.81291593525;37662.62337376591;37867.62132378641;38021.23957520889;48054;49317.50682493272;49346.259806103655;49572.25641615307;49600;40325.1934961305;41663;41745.58254417417;42713.786431067645;43313.783431082644;41966.79016604897;42153.57846421497;42422.575774241865;42909;43054.78472607617;39605.623662580336;41045.38431923464;41135.38296925489;41306.380404293355;41308;36064.27871442609;36385.352291367606;37116.628833711315;37460.2507949834;37682.43476347802;27492.58761118625;28094.43811123807;28108.85945570259;28286.71713282841;28335.574966375934 13:45:48 INFO - 2015-12-28 13:45:48,550 DEBUG : BROWSER_OUTPUT: |2;jquery.html;394.2765026176965;395.6300858697118;396.2491079404527;396.60735871487225;398.3447229307787;14057.78913316301;17934.461966141003;19544.804551954494;19631.705524417128;20594.485137871525;19141.521461963457;19341.22635094598;19823.801761982377;19846.30537931173;19856.20575176991;5806.564507661932;5951.464368206861;5961.344252132262;5962.79130230442;5966.7613295468345;5144.845654630367;5282.128448805943;5915.911261331084;5964.88070238595;5972.283326000886;3849.8652547160855;3939.5666476687543;5218.608604354669;5220.425753167148;5225.137852254374;5252.212168174785;5346.572274218053;5391.406945236021;5392.325959255093;5393.029254734127;4629.051044535862;4705.106029854317;4718.339432479455;4726.4328280606205;4735.573798358164;15118.244087795676;15270.465533706378;15324;15369.308381122877;16031.877768556205;2207.8629505804524;2215.412915577357;2227.721534808149;2233.944151396212;2300.1374484568287;10737.302075365076;10765.892341076646;11144.944275278573;11319.886801132048;11336.093112551058;1868.084638527112;1893.7632795900513;1908.9904550477158;1915.3009151659683;1926.5472613935715;67738;70683.64658176727;70960.6451967742;72056.63971680161;72761.6361918187;6923.584584924921;7054.611996340148;7060.823479412953;7090.326418990187;7124.786256412212;8417.70538031166;8424.620892059873;8425.452345597516;8453.577321133918;8461.576921153917;5710.428957104339;5722.54219662426;5751.396103409165;5798.10129429939;5811.767529298865;5888.3228428731245;5905.645661260338;5926.822195334147;5927.051671732502;5949.077892926603;3120.3135310231714;3129.8435078246;3139.560461535387;3151.6690747471184;3152.9684703153134;1239.1697562633058;1258.7230809221955;1263.7662032524013;1265.8734126587267;1290.3548225887057;524.3995625009419;536.9785208591691;539.3366159623606;547.855310517626;565.6436445039599;12756.234625922474;13256.939444844582;13503.257320847448;13533.578974207763;13627.386767595483;515.8400895722262;518.9896202075937;559.5579492201197;562.6877083218772;573.3864764701813;6875.209350924608;7001.124859392576;7157.928420715726;7212.242714515005;7225.277472252733;665764;667329;668361;684511;692899;47259.76370118196;47365.5263447368;47545.57363279107;72731.27268727245;82192.58903705444;621.201755743867;634.6097150252654;635.0632816595485;635.1742734445203;636.5989426661174;12298.090126076644;12303.630891073282;12515.311657858541;12560.246385216917;12621.621351359474;36948.630513694516;38111.80944095262;38491.23017539689;39126.60873391343;39182.41226381664;51352.74323628358;52036.73981630219;52934.47065529296;53569;53681.19478207752;7499.812504687427;7527.247275272429;7660.5403675779635;7667.731629392944;7678.846423071619;12445.25328480294;12553.748925021266;12565.18326308787;13671.863281367458;13792.793108103588;11951.641450756491;12092.818607720716;12100.697482563006;12100.697482563006;12252.571160009713;11183.217174797686;11257.268277561932;11564.306141631529;11661.125415594033;11872.881271187523;9673.372485318007;10714.357138571711;11986.700332491757;12005.219660722025;12406.503739850667;7399.667014984231;7463.104427468738;7532.8493430132185;7545.056867891513;7546.886796697944;11613.535458581562;11785.057195424517;11872.584459543874;11919.463624137108;12031.578894738643 13:45:48 INFO - 2015-12-28 13:45:48,554 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1608.7104321222182;1610.1063909530205;1854.184158970053;1877.568159323355;1915.473244857663;7442.888356674641;7556.96221518893;8142.104368519465;8457.112003239656;8459.788505287372;8240.752777416687;8414.116517765622;8453.45052571584;8501.489910605367;8512.233898949093;2441.7314095449487;2476.73994230605;2478.603423452248;2479.7024357077175;2480.0575781203206;2200.0759680934048;2236.798688118069;2351.3768851254345;2397.4845408237225;2456.4104614892385;2391.5695174868515;2391.964120538193;2421.418859473731;2421.6004359280787;2422.358075110097;2458.4960083182905;2484.7266800651914;2484.8136389770657;2488.7884529814987;2489.2781093482868;2484.527939691453;2525.078346403568;2525.8484490930605;2526.3305224115625;2526.633638122462;8509.702160424355;8574;8596.441231319945;8598.5700714964;8599.269062129726;5114.565261952738;5200.97399513;5201.765920533585;5204.3234379530795;5206.193040078794;3345.6152542457444;3361.243720162968;3437.089171369589;3459.913502162441;3472.1146107742366;1144.1075960750627;1156.4907795327094;1179.0037418381453;1180.8464899563003;1200.2858299311895;11596.710082247926;11620.941895290469;11623.302601843916;11629.418529073597;11633.302001879914;4671.392718946583;4710.317004034396;4716.88207794801;4741.739204343794;4751.83368582098;9793;9819.705408837746;9820.45987470696;9827.361221520578;9839.655612053544;5219.947800522022;5225.634205605647;5229;5233.973830130825;5240.0567897778765;4780.139574876486;4782.043591281688;4786.018866132435;4790;4803.135435621552;3690.760100593453;4022.1151346703678;4030.5163380393956;4052.675785937118;4071.776052317092;1191.267370567095;1293.997152207047;1336.62574479146;1355.4568824491673;1358.9796153057712;1210.8244304575787;1214.7813393589238;1266.1769849597652;1472.10201776916;1486.8661820436212;7989.241022102891;8045;8102.554359510165;8106.513609183467;8118.594070296462;1218.3177420644467;1225.9816102758466;1230.4524486603555;1243.881831226032;1256.874312568754;6410.8717825643225;6455.677216139175;6503.577267477599;6529.183852018497;6540.836479087966;10065.949670251603;10273.794524109477;10274.845877311845;10287.845682314772;10303.072723454776;9926.30515863897;10148.289619726549;10152.949235253776;10172.338797978256;10196.898031019744;7917.683292668344;7927.009123859518;7956.482828616122;7975.202479751978;7978.920210797818;7641.617919104134;7679.654415551426;7742.458027938215;7778.416618753503;7797.41519386037;8607.35444841652;9048.004719480983;9095.49974751395;9109.453432794053;9115;8985.595648195716;9225.985141634548;9248.630054797733;9322.394044387092;9340.065993400878;7743.032120984877;7800.024996875391;7802.6488808004915;7826.921730782847;7830.177831327856;7345.742898998509;7530.887036694345;8108.391870609846;8332.125126861592;8356.247937685637;7233.204347521873;7485.7754267372065;7765.53406795594;7871.803204919923;7902.407319450949;7304.306090921354;7681.615919203906;7707.807304817425;7816.374690024671;7847.9607601961625;7487.775366739006;7536.24637536242;7554.773356799305;7993.640286187019;8002.679892804455;7354.117505899327;7389.076365454318;7394.482386232912;7394.667239974322;7395.889061664187;7248.891266630899;7275.708971641075;7308.671109799965;7311.744088956861;7431.5912624804 13:45:48 INFO - 2015-12-28 13:45:48,554 DEBUG : BROWSER_OUTPUT: |4;prototype.html;346.34540718042894;352.85146847012976;357.85685725709715;362.13088587390246;362.76964127778854;5853.180554722337;5893.85265368366;5895.263092113486;5948.375420580836;5954.285485741706;5793.565482588812;5881.441263080012;5909.527237820975;5932.911006334908;5940.792072277461;4655.185342565054;4663.253879379283;4673.042026384592;4676.672632915697;4679.625629949613;4508.729476231437;4563.634909207272;4575.862724118265;4666.953330466703;4672.252439609663;4576.473705523874;4605.746683932382;4613.607843333304;4623.953760462386;4631.490536041049;4604.815807367701;4609.147307748078;4610.815567377301;4618.214903466419;4620.376249206348;4072.552019277877;4079.9592004080173;4082.203970225807;4082.5509193988637;4085.346344584882;5525.502704756551;5623.100303951389;5640.858978525529;5693.17448969897;5701.144828275792;5426.67439953604;5427.674339539641;5428.267183930158;5431.158170483582;5433.728313584305;6054.334023257438;6341.04884267359;6341.2707538633185;6342.6511541865175;6345.936540634627;4484.461864576239;4505.459344878635;4546.636269098464;4550.226461501519;4555.2711566149255;6430.228372595272;6455.419012288869;6465.094886715863;6479.416852483252;6484.351564843525;4672.042231342567;4673.579377856009;4682.695624784446;4685.0395668887795;4686.414198225222;4520.864374068783;4593.173228818845;4601.930971035437;4738.73936933465;4747.667663263607;5248.6325957182635;5266.236395722675;5268.683878967197;5270.947290527122;5274.997750427407;6280.528960327993;6284.340144284875;6288.308286088527;6289.49684025286;6289.842753931189;1641.7455294429383;1660.1616183827332;1661.9169041547875;1662.2935252517632;1662.3599914033184;1091.2361347057092;1102.2890235797865;1114.1254115519441;1119.6361182615683;1139.9829002564968;6061.909071363933;6112.449879510866;6194.225721784777;6195.287541932736;6196.287426945959;6528.738850445929;6530.085787989685;6530.869382612416;6535.3464653534265;6536.182977127859;6019.939800601938;6169.105479705508;6173.81478555644;6175.042868355412;6187.876242475215;3567.6432356764117;3577.409727395003;3606.350856845793;3608.891733248007;3619.1856832212543;6801.829954251084;6802.931970680329;6807.183138023469;6811.216710078305;6813.3527314905;6623.4038936495945;6768.864622707519;6779.254282028973;6779.830504237335;6781.287964763727;6785.660716964132;6794.9660251699415;6798.048273241651;6803.48973826965;6806.353396427331;6512;6515.674216289071;6575.473962082926;6582.8354291143105;6584.670766461753;6371.522135839923;6476.967615161894;6497.805065848032;6514.055461958084;6517.022446633043;6772.051912732123;6772.05191273232;6778.694958726771;6781.457483401415;6782.152230971129;4517.954820451753;4519.367288579536;4519.774011299356;4523.366728658057;4572.108438854524;6555.246146693191;6566.376194261537;6571.901421478778;6575.539712220098;6579.506537009839;6299.937000629935;6428.839279017875;6428.871422571616;6433.388828061326;6436.645984470899;6393.8721225576155;6405.103285540122;6405.583637063576;6410.45511131559;6413.935860641333;6301.6534090625455;6309.274433440213;6312.7159277833525;6318.431341179132;6323.20959880015;6316.178896743394;6388.8083357499345;6393.360663933569;6394.4884409246215;6401.455876250571;6231.345708700521;6231.750729970931;6234.75060997555;6235.719392627252;6239.126522286974 13:45:48 INFO - 2015-12-28 13:45:48,555 DEBUG : BROWSER_OUTPUT: |5;yui.html;844.1937949258457;949.2263804998927;953.7329547726639;962.8315044867146;968.3995922528034;14064.57806265811;14131.29343532823;14141.222232777192;14154.292285385729;14294.499692510764;13654.04421690482;13710.588682339521;13737;13745.24401157936;13765.518206862762;760.0499375780289;783.6786917363881;906.2704522859095;930;930.014184963937;919.4942781470198;923.0861447167323;923.2844545477242;926.735880274122;927.6150397585028;814.702633538757;974.3471873844542;976.0873583199731;976.0971101730906;981.1807141037242;819.0499021135485;937.6483818568037;957.9952100239524;961.1781926452852;961.216608464103;716.9247229040927;722.0072400449382;722.342668171961;722.9927700722978;723.9420846332281;14261.78607320891;14361.856381436157;14370.353334099991;14398.136111833324;14399.064060836012;13304.135231210037;13485.460581576725;13485.730285394236;13774.93375993609;14666.19335936523;11536.59621913229;11853.644390668294;11898.881011189864;12022.338771367571;12098.87901120995;3843.865464708722;3910.432987216838;3913.4129880517867;3914.843406263746;3917;52399;53425;54158;54234.72882635562;54490.72754636202;10671.626493072705;10848.566057357617;10889.401082940356;10896.727581810359;11066.612668556723;18718.625627487374;18751.90624046871;18801.05994700287;18843.90578047101;18844.15201315944;8591.742247732578;8613.440126391764;8674.91325086741;8693.217610415093;8968.641254349883;4775.761211939389;4819.879503012453;4828.493008234155;4842.176829938919;4853.19922212838;3384.779989300737;3399.2351720862607;3413.7951722896705;3417.470292104678;3428.2286485540553;526.6918852471271;545.6944111297686;545.9181122831527;549.8295528386213;557.631962904481;617.9042248451498;666.8466252761892;740.2671355358157;772.5441989226271;792.9960350198212;9043.638254469748;9256.72229833106;9420.221506262376;9457.44348679425;9726;590.5688847141611;686.1663079358603;700.9940735045226;749.6176949755663;760.2701406649678;4450.132224216247;4473.843415480443;4498.932516012263;4509.278515437514;4543.7728113594185;66508.66745666241;66736.66631666811;66745;66749.66625166843;66811;54420.183697244574;54779.17831232535;55326.72336638291;55565;56737;53460;53778.73110634422;54616.72691636596;55264.72367638136;56241.7187914066;9651.324407291422;9756.707298781048;9913.157381622643;9935.950320248641;9948.403095814274;40586.59413405828;40680.59319406768;41334.1733165329;41946.790266048476;42457.787711061246;49089.50910490992;50999;53727;54022.45977540332;54260.728696357844;9006.414583052081;9194.26445884341;9343.906560934303;9672.742543469303;9727.07592778685;12320.199187052813;12847;13305.733885322046;13735.793963090362;14114.717705646035;12337.444814983168;12431.62705118848;13313.866861331262;13416;13603.659908501972;11794.764104717686;12111.515539378326;12403.503859845505;13074.673133171747;13569.79645305341;12118.878811212127;12285.1400401971;12708.491660333482;12741.55404560836;12792.104552681223;9424.95287523558;9569.186619137452;9724;9809.264305177283;10540.209484288862;10555.652152314013;10648.627298044532;11142.610008649659;11301.378424186749;11302.830457543309 13:45:48 INFO - 2015-12-28 13:45:48,555 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:45:48 INFO - 2015-12-28 13:45:48,555 DEBUG : BROWSER_OUTPUT: __start_cc_report 13:45:48 INFO - 2015-12-28 13:45:48,555 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,760 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: __end_cc_report 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: __startTimestamp1451339148543__endTimestamp 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: Number of tests: 6 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5647.81 Median:5823.45 stddev:629.43 (10.8%) stddev-sans-first:604.20 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: Values: 3316.2 3402.0 3402.5 3404.0 3429.5 5600.8 5625.7 5634.5 5652.7 5660.7 5721.0 5747.6 5764.5 5771.2 5780.6 4995.5 5210.5 5245.5 5308.7 5330.9 5169.8 5240.8 5249.4 5253.5 5262.1 5123.2 5228.8 5252.4 5256.7 5264.9 5222.2 5244.9 5258.1 5306.4 5310.1 4935.6 5013.6 5051.0 5169.2 5229.1 5443.5 5448.3 5460.0 5461.5 5468.4 6050.4 6056.4 6058.2 6066.6 6068.2 6014.6 6047.0 6129.0 6135.3 6148.5 5741.7 5746.0 5746.9 5757.4 5765.3 6264.7 6287.0 6292.9 6296.3 6322.7 5799.8 5833.6 5934.9 5943.0 5990.5 6109.6 6156.4 6159.6 6160.6 6167.9 5941.1 6097.4 6104.9 6122.8 6127.6 5981.7 6010.2 6117.2 6138.7 6143.1 4604.4 4610.7 4613.4 4637.7 4656.4 3998.8 4104.8 4164.5 4179.5 4203.9 5645.7 5677.4 5715.4 5721.4 5724.4 6055.3 6072.1 6074.4 6082.5 6087.5 5612.2 5678.3 5718.5 5731.5 5749.7 5297.9 5343.1 5343.8 5352.4 5361.3 6335.4 6340.1 6340.8 6353.7 6357.4 5864.4 5981.3 6069.8 6165.6 6171.6 6280.4 6292.6 6297.6 6302.9 6309.4 5924.3 5965.4 5965.5 5965.6 5985.7 5922.4 5968.3 6056.1 6077.3 6107.5 5945.5 5947.6 5947.9 5955.8 6006.3 5736.8 5892.3 5936.2 5948.8 5951.9 5746.1 5810.3 5813.8 5823.4 5842.8 5951.7 5989.4 5990.5 6008.5 6015.5 5661.6 5735.8 5875.3 5883.5 5888.1 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54328.53 Median:37561.44 stddev:91793.35 (244.4%) stddev-sans-first:91962.24 13:45:48 INFO - 2015-12-28 13:45:48,556 DEBUG : BROWSER_OUTPUT: Values: 609.3 640.5 648.9 649.5 720.9 36586.6 44060.8 44744.0 44791.6 44846.8 48105.0 48617.0 48762.3 48785.0 48792.3 8151.4 8321.8 9365.9 9540.5 9546.8 11126.8 11421.3 11423.0 11425.5 11426.1 10877.1 10973.8 10997.5 10997.8 11007.4 11037.3 11103.4 11127.4 11130.4 11144.9 10909.5 11016.4 11035.3 11040.2 11042.7 44505.0 44935.8 45027.0 45033.8 45034.0 3781.1 3799.0 3870.6 3963.3 4502.5 34050.5 34142.5 34162.3 34220.8 34482.3 3938.0 3994.3 4003.8 4008.0 4052.1 299264.0 302535.0 304521.0 305283.0 306054.0 60779.0 61624.0 70123.3 70841.0 78892.6 73652.6 73692.6 73977.0 73991.6 74036.6 18723.4 18766.8 18866.9 18882.2 18958.6 14076.2 14155.5 14190.1 14195.7 14197.1 23016.8 23331.4 23360.3 23425.4 23435.2 8478.6 8741.3 8790.1 8961.0 8973.8 971.9 1076.9 1084.8 1092.8 1191.3 30151.0 30847.8 31093.2 31270.2 31731.5 1086.3 1105.4 1168.4 1198.3 1199.5 14759.5 16290.8 16313.0 16318.5 17273.9 418093.0 420325.0 424991.0 426641.0 427658.0 64329.4 64436.4 64884.0 64953.0 65025.3 1376.8 1400.5 1452.3 1516.7 1594.0 32296.7 32330.0 32347.2 32389.5 33033.2 52241.2 52688.5 52701.5 52786.5 52848.7 309826.0 309936.0 311001.0 311164.0 311463.0 36774.8 37416.8 37662.6 37867.6 38021.2 48054.0 49317.5 49346.3 49572.3 49600.0 40325.2 41663.0 41745.6 42713.8 43313.8 41966.8 42153.6 42422.6 42909.0 43054.8 39605.6 41045.4 41135.4 41306.4 41308.0 36064.3 36385.4 37116.6 37460.3 37682.4 27492.6 28094.4 28108.9 28286.7 28335.6 13:45:48 INFO - 2015-12-28 13:45:48,557 DEBUG : BROWSER_OUTPUT: 13:45:48 INFO - 2015-12-28 13:45:48,557 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31486.82 Median:4731.00 stddev:110456.35 (2334.7%) stddev-sans-first:110741.53 13:45:48 INFO - 2015-12-28 13:45:48,558 DEBUG : BROWSER_OUTPUT: Values: 394.3 395.6 396.2 396.6 398.3 14057.8 17934.5 19544.8 19631.7 20594.5 19141.5 19341.2 19823.8 19846.3 19856.2 5806.6 5951.5 5961.3 5962.8 5966.8 5144.8 5282.1 5915.9 5964.9 5972.3 3849.9 3939.6 5218.6 5220.4 5225.1 5252.2 5346.6 5391.4 5392.3 5393.0 4629.1 4705.1 4718.3 4726.4 4735.6 15118.2 15270.5 15324.0 15369.3 16031.9 2207.9 2215.4 2227.7 2233.9 2300.1 10737.3 10765.9 11144.9 11319.9 11336.1 1868.1 1893.8 1909.0 1915.3 1926.5 67738.0 70683.6 70960.6 72056.6 72761.6 6923.6 7054.6 7060.8 7090.3 7124.8 8417.7 8424.6 8425.5 8453.6 8461.6 5710.4 5722.5 5751.4 5798.1 5811.8 5888.3 5905.6 5926.8 5927.1 5949.1 3120.3 3129.8 3139.6 3151.7 3153.0 1239.2 1258.7 1263.8 1265.9 1290.4 524.4 537.0 539.3 547.9 565.6 12756.2 13256.9 13503.3 13533.6 13627.4 515.8 519.0 559.6 562.7 573.4 6875.2 7001.1 7157.9 7212.2 7225.3 665764.0 667329.0 668361.0 684511.0 692899.0 47259.8 47365.5 47545.6 72731.3 82192.6 621.2 634.6 635.1 635.2 636.6 12298.1 12303.6 12515.3 12560.2 12621.6 36948.6 38111.8 38491.2 39126.6 39182.4 51352.7 52036.7 52934.5 53569.0 53681.2 7499.8 7527.2 7660.5 7667.7 7678.8 12445.3 12553.7 12565.2 13671.9 13792.8 11951.6 12092.8 12100.7 12100.7 12252.6 11183.2 11257.3 11564.3 11661.1 11872.9 9673.4 10714.4 11986.7 12005.2 12406.5 7399.7 7463.1 7532.8 7545.1 7546.9 11613.5 11785.1 11872.6 11919.5 12031.6 13:45:48 INFO - 2015-12-28 13:45:48,558 DEBUG : BROWSER_OUTPUT: 13:45:48 INFO - 2015-12-28 13:45:48,558 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5974.55 Median:5227.32 stddev:3056.05 (58.5%) stddev-sans-first:3047.01 13:45:48 INFO - 2015-12-28 13:45:48,560 DEBUG : BROWSER_OUTPUT: Values: 1608.7 1610.1 1854.2 1877.6 1915.5 7442.9 7557.0 8142.1 8457.1 8459.8 8240.8 8414.1 8453.5 8501.5 8512.2 2441.7 2476.7 2478.6 2479.7 2480.1 2200.1 2236.8 2351.4 2397.5 2456.4 2391.6 2392.0 2421.4 2421.6 2422.4 2458.5 2484.7 2484.8 2488.8 2489.3 2484.5 2525.1 2525.8 2526.3 2526.6 8509.7 8574.0 8596.4 8598.6 8599.3 5114.6 5201.0 5201.8 5204.3 5206.2 3345.6 3361.2 3437.1 3459.9 3472.1 1144.1 1156.5 1179.0 1180.8 1200.3 11596.7 11620.9 11623.3 11629.4 11633.3 4671.4 4710.3 4716.9 4741.7 4751.8 9793.0 9819.7 9820.5 9827.4 9839.7 5219.9 5225.6 5229.0 5234.0 5240.1 4780.1 4782.0 4786.0 4790.0 4803.1 3690.8 4022.1 4030.5 4052.7 4071.8 1191.3 1294.0 1336.6 1355.5 1359.0 1210.8 1214.8 1266.2 1472.1 1486.9 7989.2 8045.0 8102.6 8106.5 8118.6 1218.3 1226.0 1230.5 1243.9 1256.9 6410.9 6455.7 6503.6 6529.2 6540.8 10065.9 10273.8 10274.8 10287.8 10303.1 9926.3 10148.3 10152.9 10172.3 10196.9 7917.7 7927.0 7956.5 7975.2 7978.9 7641.6 7679.7 7742.5 7778.4 7797.4 8607.4 9048.0 9095.5 9109.5 9115.0 8985.6 9226.0 9248.6 9322.4 9340.1 7743.0 7800.0 7802.6 7826.9 7830.2 7345.7 7530.9 8108.4 8332.1 8356.2 7233.2 7485.8 7765.5 7871.8 7902.4 7304.3 7681.6 7707.8 7816.4 7848.0 7487.8 7536.2 7554.8 7993.6 8002.7 7354.1 7389.1 7394.5 7394.7 7395.9 7248.9 7275.7 7308.7 7311.7 7431.6 13:45:48 INFO - 2015-12-28 13:45:48,560 DEBUG : BROWSER_OUTPUT: 13:45:48 INFO - 2015-12-28 13:45:48,560 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5337.11 Median:6037.14 stddev:1580.68 (26.2%) stddev-sans-first:1540.08 13:45:48 INFO - 2015-12-28 13:45:48,560 DEBUG : BROWSER_OUTPUT: Values: 346.3 352.9 357.9 362.1 362.8 5853.2 5893.9 5895.3 5948.4 5954.3 5793.6 5881.4 5909.5 5932.9 5940.8 4655.2 4663.3 4673.0 4676.7 4679.6 4508.7 4563.6 4575.9 4667.0 4672.3 4576.5 4605.7 4613.6 4624.0 4631.5 4604.8 4609.1 4610.8 4618.2 4620.4 4072.6 4080.0 4082.2 4082.6 4085.3 5525.5 5623.1 5640.9 5693.2 5701.1 5426.7 5427.7 5428.3 5431.2 5433.7 6054.3 6341.0 6341.3 6342.7 6345.9 4484.5 4505.5 4546.6 4550.2 4555.3 6430.2 6455.4 6465.1 6479.4 6484.4 4672.0 4673.6 4682.7 4685.0 4686.4 4520.9 4593.2 4601.9 4738.7 4747.7 5248.6 5266.2 5268.7 5270.9 5275.0 6280.5 6284.3 6288.3 6289.5 6289.8 1641.7 1660.2 1661.9 1662.3 1662.4 1091.2 1102.3 1114.1 1119.6 1140.0 6061.9 6112.4 6194.2 6195.3 6196.3 6528.7 6530.1 6530.9 6535.3 6536.2 6019.9 6169.1 6173.8 6175.0 6187.9 3567.6 3577.4 3606.4 3608.9 3619.2 6801.8 6802.9 6807.2 6811.2 6813.4 6623.4 6768.9 6779.3 6779.8 6781.3 6785.7 6795.0 6798.0 6803.5 6806.4 6512.0 6515.7 6575.5 6582.8 6584.7 6371.5 6477.0 6497.8 6514.1 6517.0 6772.1 6772.1 6778.7 6781.5 6782.2 4518.0 4519.4 4519.8 4523.4 4572.1 6555.2 6566.4 6571.9 6575.5 6579.5 6299.9 6428.8 6428.9 6433.4 6436.6 6393.9 6405.1 6405.6 6410.5 6413.9 6301.7 6309.3 6312.7 6318.4 6323.2 6316.2 6388.8 6393.4 6394.5 6401.5 6231.3 6231.8 6234.8 6235.7 6239.1 13:45:48 INFO - 2015-12-28 13:45:48,560 DEBUG : BROWSER_OUTPUT: 13:45:48 INFO - 2015-12-28 13:45:48,560 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15424.23 Median:53442.50 stddev:18307.20 (34.3%) stddev-sans-first:18325.82 13:45:48 INFO - 2015-12-28 13:45:48,560 DEBUG : BROWSER_OUTPUT: Values: 844.2 949.2 953.7 962.8 968.4 14064.6 14131.3 14141.2 14154.3 14294.5 13654.0 13710.6 13737.0 13745.2 13765.5 760.0 783.7 906.3 930.0 930.0 919.5 923.1 923.3 926.7 927.6 814.7 974.3 976.1 976.1 981.2 819.0 937.6 958.0 961.2 961.2 716.9 722.0 722.3 723.0 723.9 14261.8 14361.9 14370.4 14398.1 14399.1 13304.1 13485.5 13485.7 13774.9 14666.2 11536.6 11853.6 11898.9 12022.3 12098.9 3843.9 3910.4 3913.4 3914.8 3917.0 52399.0 53425.0 54158.0 54234.7 54490.7 10671.6 10848.6 10889.4 10896.7 11066.6 18718.6 18751.9 18801.1 18843.9 18844.2 8591.7 8613.4 8674.9 8693.2 8968.6 4775.8 4819.9 4828.5 4842.2 4853.2 3384.8 3399.2 3413.8 3417.5 3428.2 526.7 545.7 545.9 549.8 557.6 617.9 666.8 740.3 772.5 793.0 9043.6 9256.7 9420.2 9457.4 9726.0 590.6 686.2 701.0 749.6 760.3 4450.1 4473.8 4498.9 4509.3 4543.8 66508.7 66736.7 66745.0 66749.7 66811.0 54420.2 54779.2 55326.7 55565.0 56737.0 53460.0 53778.7 54616.7 55264.7 56241.7 9651.3 9756.7 9913.2 9936.0 9948.4 40586.6 40680.6 41334.2 41946.8 42457.8 49089.5 50999.0 53727.0 54022.5 54260.7 9006.4 9194.3 9343.9 9672.7 9727.1 12320.2 12847.0 13305.7 13735.8 14114.7 12337.4 12431.6 13313.9 13416.0 13603.7 11794.8 12111.5 12403.5 13074.7 13569.8 12118.9 12285.1 12708.5 12741.6 12792.1 9425.0 9569.2 9724.0 9809.3 10540.2 10555.7 10648.6 11142.6 11301.4 11302.8 13:45:48 INFO - 2015-12-28 13:45:48,560 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:45:48 INFO - 2015-12-28 13:45:48,560 DEBUG : BROWSER_OUTPUT: 13:45:49 INFO - 2015-12-28 13:45:49,161 INFO : Browser exited with error code: 0 13:45:49 INFO - 2015-12-28 13:45:49,182 INFO : Completed test dromaeo_css (00:18:29) 13:45:49 INFO - 2015-12-28 13:45:49,182 INFO : Starting test kraken 13:45:49 INFO - 2015-12-28 13:45:49,182 DEBUG : operating with platform_type : linux_ 13:45:49 INFO - 2015-12-28 13:45:49,182 INFO : Initialising browser for kraken test... 13:45:49 INFO - 2015-12-28 13:45:49,189 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6Jdzi9/profile http://localhost:35893/getInfo.html 13:45:52 INFO - 2015-12-28 13:45:52,818 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:45:52 INFO - 2015-12-28 13:45:52,818 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:45:52 INFO - 2015-12-28 13:45:52,818 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:45:52 INFO - 2015-12-28 13:45:52,818 DEBUG : BROWSER_OUTPUT: __metrics 13:45:52 INFO - 2015-12-28 13:45:52,894 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] 13:45:54 INFO - 2015-12-28 13:45:54,177 INFO : Browser initialized. 13:45:54 INFO - 2015-12-28 13:45:54,178 INFO : Running cycle 1/1 for kraken test... 13:45:54 INFO - 2015-12-28 13:45:54,178 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6Jdzi9/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:46:05 INFO - 2015-12-28 13:46:05,720 DEBUG : BROWSER_OUTPUT: RSS: Main: 128450560 13:46:05 INFO - 2015-12-28 13:46:05,721 DEBUG : BROWSER_OUTPUT: 13:46:08 INFO - 2015-12-28 13:46:08,158 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35893/tests/kraken/driver.html?testName=audio-beat-detection) 13:46:08 INFO - 2015-12-28 13:46:08,724 DEBUG : BROWSER_OUTPUT: RSS: Main: 164716544 13:46:08 INFO - 2015-12-28 13:46:08,725 DEBUG : BROWSER_OUTPUT: 13:46:11 INFO - 2015-12-28 13:46:11,245 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35893/tests/kraken/driver.html?testName=audio-dft) 13:46:11 INFO - 2015-12-28 13:46:11,807 DEBUG : BROWSER_OUTPUT: RSS: Main: 162697216 13:46:11 INFO - 2015-12-28 13:46:11,807 DEBUG : BROWSER_OUTPUT: 13:46:14 INFO - 2015-12-28 13:46:14,578 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35893/tests/kraken/driver.html?testName=audio-fft) 13:46:15 INFO - 2015-12-28 13:46:15,140 DEBUG : BROWSER_OUTPUT: RSS: Main: 158437376 13:46:15 INFO - 2015-12-28 13:46:15,140 DEBUG : BROWSER_OUTPUT: 13:46:17 INFO - 2015-12-28 13:46:17,118 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35893/tests/kraken/driver.html?testName=audio-oscillator) 13:46:17 INFO - 2015-12-28 13:46:17,678 DEBUG : BROWSER_OUTPUT: RSS: Main: 158490624 13:46:17 INFO - 2015-12-28 13:46:17,678 DEBUG : BROWSER_OUTPUT: 13:46:19 INFO - 2015-12-28 13:46:19,798 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35893/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:46:20 INFO - 2015-12-28 13:46:20,352 DEBUG : BROWSER_OUTPUT: RSS: Main: 157790208 13:46:20 INFO - 2015-12-28 13:46:20,352 DEBUG : BROWSER_OUTPUT: 13:46:23 INFO - 2015-12-28 13:46:23,255 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35893/tests/kraken/driver.html?testName=imaging-darkroom) 13:46:23 INFO - 2015-12-28 13:46:23,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 155865088 13:46:23 INFO - 2015-12-28 13:46:23,825 DEBUG : BROWSER_OUTPUT: 13:46:27 INFO - 2015-12-28 13:46:27,128 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35893/tests/kraken/driver.html?testName=imaging-desaturate) 13:46:27 INFO - 2015-12-28 13:46:27,694 DEBUG : BROWSER_OUTPUT: RSS: Main: 157224960 13:46:27 INFO - 2015-12-28 13:46:27,694 DEBUG : BROWSER_OUTPUT: 13:46:30 INFO - 2015-12-28 13:46:30,431 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35893/tests/kraken/driver.html?testName=json-parse-financial) 13:46:30 INFO - 2015-12-28 13:46:30,997 DEBUG : BROWSER_OUTPUT: RSS: Main: 157679616 13:46:30 INFO - 2015-12-28 13:46:30,997 DEBUG : BROWSER_OUTPUT: 13:46:32 INFO - 2015-12-28 13:46:32,531 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35893/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:46:33 INFO - 2015-12-28 13:46:33,085 DEBUG : BROWSER_OUTPUT: RSS: Main: 161050624 13:46:33 INFO - 2015-12-28 13:46:33,085 DEBUG : BROWSER_OUTPUT: 13:46:34 INFO - 2015-12-28 13:46:34,826 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35893/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:46:35 INFO - 2015-12-28 13:46:35,416 DEBUG : BROWSER_OUTPUT: RSS: Main: 162562048 13:46:35 INFO - 2015-12-28 13:46:35,416 DEBUG : BROWSER_OUTPUT: 13:46:37 INFO - 2015-12-28 13:46:37,368 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35893/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:46:37 INFO - 2015-12-28 13:46:37,935 DEBUG : BROWSER_OUTPUT: RSS: Main: 165806080 13:46:37 INFO - 2015-12-28 13:46:37,935 DEBUG : BROWSER_OUTPUT: 13:46:40 INFO - 2015-12-28 13:46:40,234 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35893/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:46:40 INFO - 2015-12-28 13:46:40,807 DEBUG : BROWSER_OUTPUT: RSS: Main: 169160704 13:46:40 INFO - 2015-12-28 13:46:40,807 DEBUG : BROWSER_OUTPUT: 13:46:43 INFO - 2015-12-28 13:46:43,262 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35893/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:46:43 INFO - 2015-12-28 13:46:43,830 DEBUG : BROWSER_OUTPUT: RSS: Main: 167305216 13:46:43 INFO - 2015-12-28 13:46:43,830 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,446 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35893/tests/kraken/driver.html?testName=ai-astar) 13:46:45 INFO - 2015-12-28 13:46:45,704 DEBUG : BROWSER_OUTPUT: RSS: Main: 226017280 13:46:45 INFO - 2015-12-28 13:46:45,704 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,704 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:46:45 INFO - 2015-12-28 13:46:45,704 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:46:45 INFO - 2015-12-28 13:46:45,704 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:46:45 INFO - 2015-12-28 13:46:45,704 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:46:45 INFO - 2015-12-28 13:46:45,704 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;104;108;109;110;99;108;107;108 13:46:45 INFO - 2015-12-28 13:46:45,705 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;121;154;160;122;144;147;122;160;122 13:46:45 INFO - 2015-12-28 13:46:45,705 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;158;160;159;162;163;161;164;159;159 13:46:45 INFO - 2015-12-28 13:46:45,705 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;71;72;148;71;71;70;70;150;70 13:46:45 INFO - 2015-12-28 13:46:45,705 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;133;89;90;89;129;89;90;131 13:46:45 INFO - 2015-12-28 13:46:45,705 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;109;108;109;109;110;109;109 13:46:45 INFO - 2015-12-28 13:46:45,705 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;146;144;145;145;145;145;145 13:46:45 INFO - 2015-12-28 13:46:45,706 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;95;95;95;95;98;96;96 13:46:45 INFO - 2015-12-28 13:46:45,706 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;56;57;56;55;55;57;64;55;55 13:46:45 INFO - 2015-12-28 13:46:45,706 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;63;63;64;64;64;64;64 13:46:45 INFO - 2015-12-28 13:46:45,706 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;84;77;77;77;77;77;77;79;77 13:46:45 INFO - 2015-12-28 13:46:45,706 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;112;113;110;121;114;110;108;114;108;108 13:46:45 INFO - 2015-12-28 13:46:45,706 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;156;132;133;142;143;139;138;138;137;140 13:46:45 INFO - 2015-12-28 13:46:45,707 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;62;62;63;62;63;61;60;62;63 13:46:45 INFO - 2015-12-28 13:46:45,707 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:46:45 INFO - 2015-12-28 13:46:45,707 DEBUG : BROWSER_OUTPUT: __start_cc_report 13:46:45 INFO - 2015-12-28 13:46:45,707 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,842 13:46:45 INFO - 2015-12-28 13:46:45,707 DEBUG : BROWSER_OUTPUT: __end_cc_report 13:46:45 INFO - 2015-12-28 13:46:45,707 DEBUG : BROWSER_OUTPUT: __startTimestamp1451339205698__endTimestamp 13:46:45 INFO - 2015-12-28 13:46:45,707 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,708 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:46:45 INFO - 2015-12-28 13:46:45,708 DEBUG : BROWSER_OUTPUT: Number of tests: 14 13:46:45 INFO - 2015-12-28 13:46:45,708 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,708 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.00 Median:108.50 stddev:3.23 (3.0%) stddev-sans-first:3.41 13:46:45 INFO - 2015-12-28 13:46:45,709 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 104.0 108.0 109.0 110.0 99.0 108.0 107.0 108.0 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.30 Median:149.00 stddev:16.71 (11.2%) stddev-sans-first:17.27 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: Values: 151.0 121.0 154.0 160.0 122.0 144.0 147.0 122.0 160.0 122.0 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.96 (1.2%) stddev-sans-first:2.07 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: Values: 161.0 158.0 160.0 159.0 162.0 163.0 161.0 164.0 159.0 159.0 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.30 Median:70.50 stddev:33.06 (46.9%) stddev-sans-first:34.53 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 72.0 148.0 71.0 71.0 70.0 70.0 150.0 70.0 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:101.80 Median:89.00 stddev:20.18 (22.7%) stddev-sans-first:20.86 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 133.0 89.0 90.0 89.0 129.0 89.0 90.0 131.0 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 13:46:45 INFO - 2015-12-28 13:46:45,710 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 109.0 108.0 109.0 109.0 110.0 109.0 109.0 13:46:45 INFO - 2015-12-28 13:46:45,711 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,711 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 13:46:45 INFO - 2015-12-28 13:46:45,711 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 146.0 144.0 145.0 145.0 145.0 145.0 145.0 13:46:45 INFO - 2015-12-28 13:46:45,711 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,711 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.95 (1.0%) stddev-sans-first:0.97 13:46:45 INFO - 2015-12-28 13:46:45,711 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 95.0 95.0 95.0 95.0 98.0 96.0 96.0 13:46:45 INFO - 2015-12-28 13:46:45,711 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,712 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.00 stddev:2.84 (5.1%) stddev-sans-first:2.87 13:46:45 INFO - 2015-12-28 13:46:45,712 DEBUG : BROWSER_OUTPUT: Values: 54.0 56.0 57.0 56.0 55.0 55.0 57.0 64.0 55.0 55.0 13:46:45 INFO - 2015-12-28 13:46:45,712 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,712 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:64.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 13:46:45 INFO - 2015-12-28 13:46:45,712 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 63.0 63.0 64.0 64.0 64.0 64.0 64.0 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.90 Median:77.00 stddev:2.23 (2.9%) stddev-sans-first:2.35 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: Values: 77.0 84.0 77.0 77.0 77.0 77.0 77.0 77.0 79.0 77.0 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:111.80 Median:112.50 stddev:4.02 (3.6%) stddev-sans-first:4.27 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: Values: 112.0 113.0 110.0 121.0 114.0 110.0 108.0 114.0 108.0 108.0 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.80 Median:139.50 stddev:6.66 (4.8%) stddev-sans-first:3.67 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: Values: 156.0 132.0 133.0 142.0 143.0 139.0 138.0 138.0 137.0 140.0 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.50 stddev:1.34 (2.1%) stddev-sans-first:1.00 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: Values: 65.0 62.0 62.0 63.0 62.0 63.0 61.0 60.0 62.0 63.0 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:46:45 INFO - 2015-12-28 13:46:45,713 DEBUG : BROWSER_OUTPUT: 13:46:46 INFO - 2015-12-28 13:46:46,274 INFO : Browser exited with error code: 0 13:46:46 INFO - 2015-12-28 13:46:46,334 INFO : Completed test kraken (00:00:57) 13:46:46 INFO - 2015-12-28 13:46:46,334 INFO : Starting test v8_7 13:46:46 INFO - 2015-12-28 13:46:46,334 DEBUG : operating with platform_type : linux_ 13:46:46 INFO - 2015-12-28 13:46:46,334 INFO : Initialising browser for v8_7 test... 13:46:46 INFO - 2015-12-28 13:46:46,340 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbhtTgO/profile http://localhost:35893/getInfo.html 13:46:50 INFO - 2015-12-28 13:46:50,145 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:46:50 INFO - 2015-12-28 13:46:50,145 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:46:50 INFO - 2015-12-28 13:46:50,145 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:46:50 INFO - 2015-12-28 13:46:50,145 DEBUG : BROWSER_OUTPUT: __metrics 13:46:50 INFO - 2015-12-28 13:46:50,274 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] 13:46:51 INFO - 2015-12-28 13:46:51,489 INFO : Browser initialized. 13:46:51 INFO - 2015-12-28 13:46:51,490 INFO : Running cycle 1/1 for v8_7 test... 13:46:51 INFO - 2015-12-28 13:46:51,490 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbhtTgO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:47:03 INFO - 2015-12-28 13:47:03,032 DEBUG : BROWSER_OUTPUT: RSS: Main: 129114112 13:47:03 INFO - 2015-12-28 13:47:03,032 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,343 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35893/tests/v8_7/run.html (next: http://localhost:35893/tests/v8_7/run.html) 13:47:24 INFO - 2015-12-28 13:47:24,593 DEBUG : BROWSER_OUTPUT: RSS: Main: 422567936 13:47:24 INFO - 2015-12-28 13:47:24,594 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,595 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:47:24 INFO - 2015-12-28 13:47:24,595 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:47:24 INFO - 2015-12-28 13:47:24,595 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:47:24 INFO - 2015-12-28 13:47:24,595 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:47:24 INFO - 2015-12-28 13:47:24,595 DEBUG : BROWSER_OUTPUT: |0;Richards;163.31863465621427 13:47:24 INFO - 2015-12-28 13:47:24,596 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;172.0282126268708 13:47:24 INFO - 2015-12-28 13:47:24,596 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.6420581655481 13:47:24 INFO - 2015-12-28 13:47:24,596 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4995.024875621891 13:47:24 INFO - 2015-12-28 13:47:24,596 DEBUG : BROWSER_OUTPUT: |4;RayTrace;949.667616334283 13:47:24 INFO - 2015-12-28 13:47:24,596 DEBUG : BROWSER_OUTPUT: |5;Earley;553.0973451327434 13:47:24 INFO - 2015-12-28 13:47:24,596 DEBUG : BROWSER_OUTPUT: |6;Boyer;10547.368421052632 13:47:24 INFO - 2015-12-28 13:47:24,596 DEBUG : BROWSER_OUTPUT: |7;RegExp;23904.761904761905 13:47:24 INFO - 2015-12-28 13:47:24,597 DEBUG : BROWSER_OUTPUT: |8;Splay;537.345513164965 13:47:24 INFO - 2015-12-28 13:47:24,597 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4812.5 13:47:24 INFO - 2015-12-28 13:47:24,597 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:47:24 INFO - 2015-12-28 13:47:24,597 DEBUG : BROWSER_OUTPUT: __startTimestamp1451339244594__endTimestamp 13:47:24 INFO - 2015-12-28 13:47:24,597 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,598 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:47:24 INFO - 2015-12-28 13:47:24,598 DEBUG : BROWSER_OUTPUT: Number of tests: 10 13:47:24 INFO - 2015-12-28 13:47:24,598 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,599 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.32 Median:163.32 stddev:0.00 (0.0%) 13:47:24 INFO - 2015-12-28 13:47:24,599 DEBUG : BROWSER_OUTPUT: Values: 163.3 13:47:24 INFO - 2015-12-28 13:47:24,599 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,599 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:172.03 Median:172.03 stddev:0.00 (0.0%) 13:47:24 INFO - 2015-12-28 13:47:24,599 DEBUG : BROWSER_OUTPUT: Values: 172.0 13:47:24 INFO - 2015-12-28 13:47:24,600 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,600 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.64 Median:279.64 stddev:0.00 (0.0%) 13:47:24 INFO - 2015-12-28 13:47:24,600 DEBUG : BROWSER_OUTPUT: Values: 279.6 13:47:24 INFO - 2015-12-28 13:47:24,600 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,600 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4995.02 Median:4995.02 stddev:0.00 (0.0%) 13:47:24 INFO - 2015-12-28 13:47:24,601 DEBUG : BROWSER_OUTPUT: Values: 4995.0 13:47:24 INFO - 2015-12-28 13:47:24,602 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,602 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:949.67 Median:949.67 stddev:0.00 (0.0%) 13:47:24 INFO - 2015-12-28 13:47:24,602 DEBUG : BROWSER_OUTPUT: Values: 949.7 13:47:24 INFO - 2015-12-28 13:47:24,602 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,602 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:553.10 Median:553.10 stddev:0.00 (0.0%) 13:47:24 INFO - 2015-12-28 13:47:24,602 DEBUG : BROWSER_OUTPUT: Values: 553.1 13:47:24 INFO - 2015-12-28 13:47:24,602 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10547.37 Median:10547.37 stddev:0.00 (0.0%) 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: Values: 10547.4 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23904.76 Median:23904.76 stddev:0.00 (0.0%) 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: Values: 23904.8 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:537.35 Median:537.35 stddev:0.00 (0.0%) 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: Values: 537.3 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4812.50 Median:4812.50 stddev:0.00 (0.0%) 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: Values: 4812.5 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:47:24 INFO - 2015-12-28 13:47:24,603 DEBUG : BROWSER_OUTPUT: 13:47:25 INFO - 2015-12-28 13:47:25,305 INFO : Browser exited with error code: 0 13:47:25 INFO - 2015-12-28 13:47:25,322 INFO : Completed test v8_7 (00:00:38) 13:47:25 INFO - 2015-12-28 13:47:25,689 INFO : Completed test suite (00:20:06) 13:47:25 INFO - 2015-12-28 13:47:25,689 DEBUG : Working with test: dromaeo_css 13:47:25 INFO - 2015-12-28 13:47:25,689 DEBUG : Generating results file: dromaeo_css 13:47:25 INFO - 2015-12-28 13:47:25,690 DEBUG : Working with test: kraken 13:47:25 INFO - 2015-12-28 13:47:25,690 DEBUG : Generating results file: kraken 13:47:25 INFO - 2015-12-28 13:47:25,691 INFO : javascript benchmark 13:47:25 INFO - 2015-12-28 13:47:25,691 DEBUG : Working with test: v8_7 13:47:25 INFO - 2015-12-28 13:47:25,691 DEBUG : Generating results file: v8_7 13:47:25 INFO - 2015-12-28 13:47:25,691 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:47:25 INFO - 2015-12-28 13:47:25,742 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 13:47:25 INFO - 2015-12-28 13:47:25,742 DEBUG : process_Request line: dromaeo_css 7295.44 graph.html#tests=[[72,53,35]] 13:47:25 INFO - 2015-12-28 13:47:25,743 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:47:25 INFO - 2015-12-28 13:47:25,854 DEBUG : process_Request line: kraken 1458.00 graph.html#tests=[[232,53,35]] 13:47:25 INFO - 2015-12-28 13:47:25,854 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:47:25 INFO - 2015-12-28 13:47:25,889 DEBUG : process_Request line: v8_7 23255.08 graph.html#tests=[[230,53,35]] 13:47:25 INFO - 2015-12-28 13:47:25,890 INFO : javascript benchmark 13:47:25 INFO - 2015-12-28 13:47:25,894 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3316.2206881382876, 3402.0474267205177, 3402.4726167444055, 3403.9788063580936, 3429.537012503313, 5600.775968961242, 5625.662460252388, 5634.4928956393915, 5652.745626446809, 5660.716964151797, 5720.9713951430185, 5747.59766816323, 5764.452377024176, 5771.249737534125, 5780.624259423134, 4995.500449955011, 5210.478952104797, 5245.527902488775, 5308.70802105884, 5330.946690533103, 5169.819056333029, 5240.764165612538, 5249.448807875176, 5253.500917412859, 5262.079136151177, 5123.205903085028, 5228.816991405301, 5252.448492908247, 5256.737163141846, 5264.894702105956, 5222.242774797671, 5244.895102097956, 5258.0535503609335, 5306.3632364116165, 5310.070737620901, 4935.605151587864, 5013.623978201649, 5050.974745126287, 5169.198774190007, 5229.08491014071, 5443.482869127427, 5448.318960129984, 5460.0, 5461.481159289861, 5468.398476167618, 6050.42520960508, 6056.424639659225, 6058.1518587397795, 6066.575339726221, 6068.150458935753, 6014.609050411753, 6046.96976515119, 6128.96935515324, 6135.32511423743, 6148.538859585504, 5741.655500669973, 5745.971270143665, 5746.885062298772, 5757.424257574293, 5765.336986246593, 6264.749410023639, 6287.0, 6292.937070629327, 6296.33888441707, 6322.747090116436, 5799.82600521985, 5833.620814647034, 5934.851628709317, 5943.0, 5990.490808281258, 6109.633421994695, 6156.415140561639, 6159.630422174684, 6160.599561028519, 6167.876642467126, 5941.1088336749835, 6097.359777223416, 6104.938950610437, 6122.846928826815, 6127.632342059491, 5981.730822113015, 6010.2186715726675, 6117.176182440033, 6138.662373569497, 6143.078538219213, 4604.355390245369, 4610.700304480198, 4613.400257966443, 4637.7449240291435, 4656.3713898623255, 3998.8000599969887, 4104.753714777123, 4164.4586203793915, 4179.540250572434, 4203.936940945889, 5645.717714114277, 5677.403872593318, 5715.399883012307, 5721.427857214246, 5724.398938111522, 6055.333913269536, 6072.119542666289, 6074.3925607438905, 6082.5438092142385, 6087.54343424245, 5612.242347283146, 5678.3186017678145, 5718.456746609066, 5731.541476681856, 5749.655020698771, 5297.867553311198, 5343.064963631349, 5343.839684809462, 5352.357717073976, 5361.329833770778, 6335.3981371769605, 6340.1440805490665, 6340.841478963062, 6353.682315884187, 6357.364263573698, 5864.384239654775, 5981.312149102909, 6069.757209711562, 6165.599236049642, 6171.59884607499, 6280.371962803866, 6292.559520833498, 6297.590656607306, 6302.873942521032, 6309.400606942333, 5924.289085309791, 5965.403459654, 5965.522758179422, 5965.552583556164, 5985.670788106696, 5922.407759224043, 5968.253968253968, 6056.0915862619195, 6077.301110372364, 6107.450329470369, 5945.524358051432, 5947.583669143119, 5947.881042379042, 5955.821325360246, 6006.309274433496, 5736.799212027559, 5892.263467066617, 5936.1689363488285, 5948.821535353946, 5951.880962380815, 5746.109353050284, 5810.331811841692, 5813.82558523245, 5823.4467725566, 5842.795502157404, 5951.702414879325, 5989.371116032754, 5990.4908082813445, 6008.519318454426, 6015.488683461955, 5661.575381846295, 5735.7705691773535, 5875.3243377012195, 5883.499902508334, 5888.116782482491], "unit": "ms", "name": "dojo.html", "value": 5606.65503253032}, {"lowerIsBetter": false, "replicates": [609.3145211636909, 640.5004096804493, 648.8604949935763, 649.5290914087286, 720.884658454647, 36586.63413365869, 44060.77969610151, 44744.0, 44791.552084479146, 44846.7757661212, 48105.037899241994, 48617.0, 48762.268565971535, 48785.0, 48792.2681159782, 8151.429399942006, 8321.791955201139, 9365.906340936588, 9540.522973851314, 9546.761330966712, 11126.777464450706, 11421.25761825483, 11423.029042531394, 11425.485853136588, 11426.143039272089, 10877.129829613652, 10973.780524389507, 10997.450127493634, 10997.780044399107, 11007.394593297366, 11037.337759734442, 11103.38931358775, 11127.387993660346, 11130.443477826117, 11144.944275278653, 10909.509072091696, 11016.394098324588, 11035.282706624044, 11040.227184097117, 11042.668719938334, 44505.0, 44935.77532112351, 45027.0, 45033.774831125964, 45034.0, 3781.130340021783, 3798.9810050949845, 3870.612938706107, 3963.346047902093, 4502.459704835408, 34050.48924266113, 34142.48786268208, 34162.31675366479, 34220.82889585561, 34482.31035379304, 3937.98031009846, 3994.2683145262313, 4003.799810009488, 4008.0, 4052.108536122078, 299264.0, 302535.0, 304521.0, 305283.0, 306054.0, 60779.0, 61624.0, 70123.2987670127, 70841.0, 78892.60553697195, 73652.63173684204, 73692.63153684197, 73977.0, 73991.63004185053, 74036.62981685057, 18723.438296851116, 18766.81233187678, 18866.905665471586, 18882.150303236387, 18958.62082758337, 14076.155430674193, 14155.504557340444, 14190.077644953046, 14195.716085678228, 14197.077189982618, 23016.769832301798, 23331.41671458193, 23360.299191024296, 23425.41436464068, 23435.179768708353, 8478.618462169217, 8741.256993155526, 8790.120987901286, 8960.955195224113, 8973.820523589491, 971.8590804333473, 1076.9299995500267, 1084.8264277715527, 1092.814371257485, 1191.2614179208772, 30151.0, 30847.84576077105, 31093.22266943299, 31270.218244543612, 31731.52402713961, 1086.3481910853425, 1105.4472763618191, 1168.3690806964314, 1198.3049831098117, 1199.4602428907026, 14759.483418080315, 16290.83709162917, 16313.0, 16318.510444686679, 17273.91363043177, 418093.0, 420325.0, 424991.0, 426641.0, 427658.0, 64329.356706433275, 64436.35563644397, 64884.0, 64953.0, 65025.34974650288, 1376.800363947222, 1400.4538230090272, 1452.3174108169144, 1516.726989141965, 1593.9521814345587, 32296.677033229367, 32330.0, 32347.19132021718, 32389.514157287187, 33033.17417064593, 52241.21638175355, 52688.47311526836, 52701.472985269655, 52786.472135278156, 52848.73575632251, 309826.0, 309936.0, 311001.0, 311164.0, 311463.0, 36774.8161259192, 37416.81291593525, 37662.62337376591, 37867.62132378641, 38021.23957520889, 48054.0, 49317.50682493272, 49346.259806103655, 49572.25641615307, 49600.0, 40325.1934961305, 41663.0, 41745.58254417417, 42713.786431067645, 43313.783431082644, 41966.79016604897, 42153.57846421497, 42422.575774241865, 42909.0, 43054.78472607617, 39605.623662580336, 41045.38431923464, 41135.38296925489, 41306.380404293355, 41308.0, 36064.27871442609, 36385.352291367606, 37116.628833711315, 37460.2507949834, 37682.43476347802, 27492.58761118625, 28094.43811123807, 28108.85945570259, 28286.71713282841, 28335.574966375934], "unit": "ms", "name": "ext.html", "value": 21350.30410140478}, {"lowerIsBetter": false, "replicates": [394.2765026176965, 395.6300858697118, 396.2491079404527, 396.60735871487225, 398.3447229307787, 14057.78913316301, 17934.461966141003, 19544.804551954494, 19631.705524417128, 20594.485137871525, 19141.521461963457, 19341.22635094598, 19823.801761982377, 19846.30537931173, 19856.20575176991, 5806.564507661932, 5951.464368206861, 5961.344252132262, 5962.79130230442, 5966.7613295468345, 5144.845654630367, 5282.128448805943, 5915.911261331084, 5964.88070238595, 5972.283326000886, 3849.8652547160855, 3939.5666476687543, 5218.608604354669, 5220.425753167148, 5225.137852254374, 5252.212168174785, 5346.572274218053, 5391.406945236021, 5392.325959255093, 5393.029254734127, 4629.051044535862, 4705.106029854317, 4718.339432479455, 4726.4328280606205, 4735.573798358164, 15118.244087795676, 15270.465533706378, 15324.0, 15369.308381122877, 16031.877768556205, 2207.8629505804524, 2215.412915577357, 2227.721534808149, 2233.944151396212, 2300.1374484568287, 10737.302075365076, 10765.892341076646, 11144.944275278573, 11319.886801132048, 11336.093112551058, 1868.084638527112, 1893.7632795900513, 1908.9904550477158, 1915.3009151659683, 1926.5472613935715, 67738.0, 70683.64658176727, 70960.6451967742, 72056.63971680161, 72761.6361918187, 6923.584584924921, 7054.611996340148, 7060.823479412953, 7090.326418990187, 7124.786256412212, 8417.70538031166, 8424.620892059873, 8425.452345597516, 8453.577321133918, 8461.576921153917, 5710.428957104339, 5722.54219662426, 5751.396103409165, 5798.10129429939, 5811.767529298865, 5888.3228428731245, 5905.645661260338, 5926.822195334147, 5927.051671732502, 5949.077892926603, 3120.3135310231714, 3129.8435078246, 3139.560461535387, 3151.6690747471184, 3152.9684703153134, 1239.1697562633058, 1258.7230809221955, 1263.7662032524013, 1265.8734126587267, 1290.3548225887057, 524.3995625009419, 536.9785208591691, 539.3366159623606, 547.855310517626, 565.6436445039599, 12756.234625922474, 13256.939444844582, 13503.257320847448, 13533.578974207763, 13627.386767595483, 515.8400895722262, 518.9896202075937, 559.5579492201197, 562.6877083218772, 573.3864764701813, 6875.209350924608, 7001.124859392576, 7157.928420715726, 7212.242714515005, 7225.277472252733, 665764.0, 667329.0, 668361.0, 684511.0, 692899.0, 47259.76370118196, 47365.5263447368, 47545.57363279107, 72731.27268727245, 82192.58903705444, 621.201755743867, 634.6097150252654, 635.0632816595485, 635.1742734445203, 636.5989426661174, 12298.090126076644, 12303.630891073282, 12515.311657858541, 12560.246385216917, 12621.621351359474, 36948.630513694516, 38111.80944095262, 38491.23017539689, 39126.60873391343, 39182.41226381664, 51352.74323628358, 52036.73981630219, 52934.47065529296, 53569.0, 53681.19478207752, 7499.812504687427, 7527.247275272429, 7660.5403675779635, 7667.731629392944, 7678.846423071619, 12445.25328480294, 12553.748925021266, 12565.18326308787, 13671.863281367458, 13792.793108103588, 11951.641450756491, 12092.818607720716, 12100.697482563006, 12100.697482563006, 12252.571160009713, 11183.217174797686, 11257.268277561932, 11564.306141631529, 11661.125415594033, 11872.881271187523, 9673.372485318007, 10714.357138571711, 11986.700332491757, 12005.219660722025, 12406.503739850667, 7399.667014984231, 7463.104427468738, 7532.8493430132185, 7545.056867891513, 7546.886796697944, 11613.535458581562, 11785.057195424517, 11872.584459543874, 11919.463624137108, 12031.578894738643], "unit": "ms", "name": "jquery.html", "value": 7629.03133037062}, {"lowerIsBetter": false, "replicates": [1608.7104321222182, 1610.1063909530205, 1854.184158970053, 1877.568159323355, 1915.473244857663, 7442.888356674641, 7556.96221518893, 8142.104368519465, 8457.112003239656, 8459.788505287372, 8240.752777416687, 8414.116517765622, 8453.45052571584, 8501.489910605367, 8512.233898949093, 2441.7314095449487, 2476.73994230605, 2478.603423452248, 2479.7024357077175, 2480.0575781203206, 2200.0759680934048, 2236.798688118069, 2351.3768851254345, 2397.4845408237225, 2456.4104614892385, 2391.5695174868515, 2391.964120538193, 2421.418859473731, 2421.6004359280787, 2422.358075110097, 2458.4960083182905, 2484.7266800651914, 2484.8136389770657, 2488.7884529814987, 2489.2781093482868, 2484.527939691453, 2525.078346403568, 2525.8484490930605, 2526.3305224115625, 2526.633638122462, 8509.702160424355, 8574.0, 8596.441231319945, 8598.5700714964, 8599.269062129726, 5114.565261952738, 5200.97399513, 5201.765920533585, 5204.3234379530795, 5206.193040078794, 3345.6152542457444, 3361.243720162968, 3437.089171369589, 3459.913502162441, 3472.1146107742366, 1144.1075960750627, 1156.4907795327094, 1179.0037418381453, 1180.8464899563003, 1200.2858299311895, 11596.710082247926, 11620.941895290469, 11623.302601843916, 11629.418529073597, 11633.302001879914, 4671.392718946583, 4710.317004034396, 4716.88207794801, 4741.739204343794, 4751.83368582098, 9793.0, 9819.705408837746, 9820.45987470696, 9827.361221520578, 9839.655612053544, 5219.947800522022, 5225.634205605647, 5229.0, 5233.973830130825, 5240.0567897778765, 4780.139574876486, 4782.043591281688, 4786.018866132435, 4790.0, 4803.135435621552, 3690.760100593453, 4022.1151346703678, 4030.5163380393956, 4052.675785937118, 4071.776052317092, 1191.267370567095, 1293.997152207047, 1336.62574479146, 1355.4568824491673, 1358.9796153057712, 1210.8244304575787, 1214.7813393589238, 1266.1769849597652, 1472.10201776916, 1486.8661820436212, 7989.241022102891, 8045.0, 8102.554359510165, 8106.513609183467, 8118.594070296462, 1218.3177420644467, 1225.9816102758466, 1230.4524486603555, 1243.881831226032, 1256.874312568754, 6410.8717825643225, 6455.677216139175, 6503.577267477599, 6529.183852018497, 6540.836479087966, 10065.949670251603, 10273.794524109477, 10274.845877311845, 10287.845682314772, 10303.072723454776, 9926.30515863897, 10148.289619726549, 10152.949235253776, 10172.338797978256, 10196.898031019744, 7917.683292668344, 7927.009123859518, 7956.482828616122, 7975.202479751978, 7978.920210797818, 7641.617919104134, 7679.654415551426, 7742.458027938215, 7778.416618753503, 7797.41519386037, 8607.35444841652, 9048.004719480983, 9095.49974751395, 9109.453432794053, 9115.0, 8985.595648195716, 9225.985141634548, 9248.630054797733, 9322.394044387092, 9340.065993400878, 7743.032120984877, 7800.024996875391, 7802.6488808004915, 7826.921730782847, 7830.177831327856, 7345.742898998509, 7530.887036694345, 8108.391870609846, 8332.125126861592, 8356.247937685637, 7233.204347521873, 7485.7754267372065, 7765.53406795594, 7871.803204919923, 7902.407319450949, 7304.306090921354, 7681.615919203906, 7707.807304817425, 7816.374690024671, 7847.9607601961625, 7487.775366739006, 7536.24637536242, 7554.773356799305, 7993.640286187019, 8002.679892804455, 7354.117505899327, 7389.076365454318, 7394.482386232912, 7394.667239974322, 7395.889061664187, 7248.891266630899, 7275.708971641075, 7308.671109799965, 7311.744088956861, 7431.5912624804], "unit": "ms", "name": "mootools.html", "value": 4932.485993455643}, {"lowerIsBetter": false, "replicates": [346.34540718042894, 352.85146847012976, 357.85685725709715, 362.13088587390246, 362.76964127778854, 5853.180554722337, 5893.85265368366, 5895.263092113486, 5948.375420580836, 5954.285485741706, 5793.565482588812, 5881.441263080012, 5909.527237820975, 5932.911006334908, 5940.792072277461, 4655.185342565054, 4663.253879379283, 4673.042026384592, 4676.672632915697, 4679.625629949613, 4508.729476231437, 4563.634909207272, 4575.862724118265, 4666.953330466703, 4672.252439609663, 4576.473705523874, 4605.746683932382, 4613.607843333304, 4623.953760462386, 4631.490536041049, 4604.815807367701, 4609.147307748078, 4610.815567377301, 4618.214903466419, 4620.376249206348, 4072.552019277877, 4079.9592004080173, 4082.203970225807, 4082.5509193988637, 4085.346344584882, 5525.502704756551, 5623.100303951389, 5640.858978525529, 5693.17448969897, 5701.144828275792, 5426.67439953604, 5427.674339539641, 5428.267183930158, 5431.158170483582, 5433.728313584305, 6054.334023257438, 6341.04884267359, 6341.2707538633185, 6342.6511541865175, 6345.936540634627, 4484.461864576239, 4505.459344878635, 4546.636269098464, 4550.226461501519, 4555.2711566149255, 6430.228372595272, 6455.419012288869, 6465.094886715863, 6479.416852483252, 6484.351564843525, 4672.042231342567, 4673.579377856009, 4682.695624784446, 4685.0395668887795, 4686.414198225222, 4520.864374068783, 4593.173228818845, 4601.930971035437, 4738.73936933465, 4747.667663263607, 5248.6325957182635, 5266.236395722675, 5268.683878967197, 5270.947290527122, 5274.997750427407, 6280.528960327993, 6284.340144284875, 6288.308286088527, 6289.49684025286, 6289.842753931189, 1641.7455294429383, 1660.1616183827332, 1661.9169041547875, 1662.2935252517632, 1662.3599914033184, 1091.2361347057092, 1102.2890235797865, 1114.1254115519441, 1119.6361182615683, 1139.9829002564968, 6061.909071363933, 6112.449879510866, 6194.225721784777, 6195.287541932736, 6196.287426945959, 6528.738850445929, 6530.085787989685, 6530.869382612416, 6535.3464653534265, 6536.182977127859, 6019.939800601938, 6169.105479705508, 6173.81478555644, 6175.042868355412, 6187.876242475215, 3567.6432356764117, 3577.409727395003, 3606.350856845793, 3608.891733248007, 3619.1856832212543, 6801.829954251084, 6802.931970680329, 6807.183138023469, 6811.216710078305, 6813.3527314905, 6623.4038936495945, 6768.864622707519, 6779.254282028973, 6779.830504237335, 6781.287964763727, 6785.660716964132, 6794.9660251699415, 6798.048273241651, 6803.48973826965, 6806.353396427331, 6512.0, 6515.674216289071, 6575.473962082926, 6582.8354291143105, 6584.670766461753, 6371.522135839923, 6476.967615161894, 6497.805065848032, 6514.055461958084, 6517.022446633043, 6772.051912732123, 6772.05191273232, 6778.694958726771, 6781.457483401415, 6782.152230971129, 4517.954820451753, 4519.367288579536, 4519.774011299356, 4523.366728658057, 4572.108438854524, 6555.246146693191, 6566.376194261537, 6571.901421478778, 6575.539712220098, 6579.506537009839, 6299.937000629935, 6428.839279017875, 6428.871422571616, 6433.388828061326, 6436.645984470899, 6393.8721225576155, 6405.103285540122, 6405.583637063576, 6410.45511131559, 6413.935860641333, 6301.6534090625455, 6309.274433440213, 6312.7159277833525, 6318.431341179132, 6323.20959880015, 6316.178896743394, 6388.8083357499345, 6393.360663933569, 6394.4884409246215, 6401.455876250571, 6231.345708700521, 6231.750729970931, 6234.75060997555, 6235.719392627252, 6239.126522286974], "unit": "ms", "name": "prototype.html", "value": 4831.422831547774}, {"lowerIsBetter": false, "replicates": [844.1937949258457, 949.2263804998927, 953.7329547726639, 962.8315044867146, 968.3995922528034, 14064.57806265811, 14131.29343532823, 14141.222232777192, 14154.292285385729, 14294.499692510764, 13654.04421690482, 13710.588682339521, 13737.0, 13745.24401157936, 13765.518206862762, 760.0499375780289, 783.6786917363881, 906.2704522859095, 930.0, 930.014184963937, 919.4942781470198, 923.0861447167323, 923.2844545477242, 926.735880274122, 927.6150397585028, 814.702633538757, 974.3471873844542, 976.0873583199731, 976.0971101730906, 981.1807141037242, 819.0499021135485, 937.6483818568037, 957.9952100239524, 961.1781926452852, 961.216608464103, 716.9247229040927, 722.0072400449382, 722.342668171961, 722.9927700722978, 723.9420846332281, 14261.78607320891, 14361.856381436157, 14370.353334099991, 14398.136111833324, 14399.064060836012, 13304.135231210037, 13485.460581576725, 13485.730285394236, 13774.93375993609, 14666.19335936523, 11536.59621913229, 11853.644390668294, 11898.881011189864, 12022.338771367571, 12098.87901120995, 3843.865464708722, 3910.432987216838, 3913.4129880517867, 3914.843406263746, 3917.0, 52399.0, 53425.0, 54158.0, 54234.72882635562, 54490.72754636202, 10671.626493072705, 10848.566057357617, 10889.401082940356, 10896.727581810359, 11066.612668556723, 18718.625627487374, 18751.90624046871, 18801.05994700287, 18843.90578047101, 18844.15201315944, 8591.742247732578, 8613.440126391764, 8674.91325086741, 8693.217610415093, 8968.641254349883, 4775.761211939389, 4819.879503012453, 4828.493008234155, 4842.176829938919, 4853.19922212838, 3384.779989300737, 3399.2351720862607, 3413.7951722896705, 3417.470292104678, 3428.2286485540553, 526.6918852471271, 545.6944111297686, 545.9181122831527, 549.8295528386213, 557.631962904481, 617.9042248451498, 666.8466252761892, 740.2671355358157, 772.5441989226271, 792.9960350198212, 9043.638254469748, 9256.72229833106, 9420.221506262376, 9457.44348679425, 9726.0, 590.5688847141611, 686.1663079358603, 700.9940735045226, 749.6176949755663, 760.2701406649678, 4450.132224216247, 4473.843415480443, 4498.932516012263, 4509.278515437514, 4543.7728113594185, 66508.66745666241, 66736.66631666811, 66745.0, 66749.66625166843, 66811.0, 54420.183697244574, 54779.17831232535, 55326.72336638291, 55565.0, 56737.0, 53460.0, 53778.73110634422, 54616.72691636596, 55264.72367638136, 56241.7187914066, 9651.324407291422, 9756.707298781048, 9913.157381622643, 9935.950320248641, 9948.403095814274, 40586.59413405828, 40680.59319406768, 41334.1733165329, 41946.790266048476, 42457.787711061246, 49089.50910490992, 50999.0, 53727.0, 54022.45977540332, 54260.728696357844, 9006.414583052081, 9194.26445884341, 9343.906560934303, 9672.742543469303, 9727.07592778685, 12320.199187052813, 12847.0, 13305.733885322046, 13735.793963090362, 14114.717705646035, 12337.444814983168, 12431.62705118848, 13313.866861331262, 13416.0, 13603.659908501972, 11794.764104717686, 12111.515539378326, 12403.503859845505, 13074.673133171747, 13569.79645305341, 12118.878811212127, 12285.1400401971, 12708.491660333482, 12741.55404560836, 12792.104552681223, 9424.95287523558, 9569.186619137452, 9724.0, 9809.264305177283, 10540.209484288862, 10555.652152314013, 10648.627298044532, 11142.610008649659, 11301.378424186749, 11302.830457543309], "unit": "ms", "name": "yui.html", "value": 6927.11244660928}], "name": "dromaeo_css", "value": 7295.439099308531}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 104.0, 108.0, 109.0, 110.0, 99.0, 108.0, 107.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.0}, {"lowerIsBetter": true, "replicates": [151.0, 121.0, 154.0, 160.0, 122.0, 144.0, 147.0, 122.0, 160.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.3}, {"lowerIsBetter": true, "replicates": [161.0, 158.0, 160.0, 159.0, 162.0, 163.0, 161.0, 164.0, 159.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.6}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 72.0, 148.0, 71.0, 71.0, 70.0, 70.0, 150.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 86.3}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 133.0, 89.0, 90.0, 89.0, 129.0, 89.0, 90.0, 131.0], "unit": "ms", "name": "audio-oscillator", "value": 101.8}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 108.0, 109.0, 109.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 146.0, 144.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.1}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 98.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [54.0, 56.0, 57.0, 56.0, 55.0, 55.0, 57.0, 64.0, 55.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.4}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 63.0, 63.0, 64.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.8}, {"lowerIsBetter": true, "replicates": [77.0, 84.0, 77.0, 77.0, 77.0, 77.0, 77.0, 77.0, 79.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.9}, {"lowerIsBetter": true, "replicates": [112.0, 113.0, 110.0, 121.0, 114.0, 110.0, 108.0, 114.0, 108.0, 108.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 111.8}, {"lowerIsBetter": true, "replicates": [156.0, 132.0, 133.0, 142.0, 143.0, 139.0, 138.0, 138.0, 137.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.8}, {"lowerIsBetter": true, "replicates": [65.0, 62.0, 62.0, 63.0, 62.0, 63.0, 61.0, 60.0, 62.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.3}], "name": "kraken", "value": 1458.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.31863465621427], "unit": "score", "name": "Richards", "value": 216.15414600000005}, {"lowerIsBetter": false, "replicates": [172.0282126268708], "unit": "score", "name": "DeltaBlue", "value": 384.34393400000005}, {"lowerIsBetter": false, "replicates": [279.6420581655481], "unit": "score", "name": "Encrypt", "value": 951.8632560000003}, {"lowerIsBetter": false, "replicates": [4995.024875621891], "unit": "score", "name": "Decrypt", "value": 53.289224103585696}, {"lowerIsBetter": false, "replicates": [949.667616334283], "unit": "score", "name": "RayTrace", "value": 779.2084170000002}, {"lowerIsBetter": false, "replicates": [553.0973451327434], "unit": "score", "name": "Earley", "value": 1204.9651040000003}, {"lowerIsBetter": false, "replicates": [10547.368421052632], "unit": "score", "name": "Boyer", "value": 63.187609780439146}, {"lowerIsBetter": false, "replicates": [23904.761904761905], "unit": "score", "name": "RegExp", "value": 38.10893426294821}, {"lowerIsBetter": false, "replicates": [537.345513164965], "unit": "score", "name": "Splay", "value": 151.65475100000006}, {"lowerIsBetter": false, "replicates": [4812.5], "unit": "score", "name": "NavierStokes", "value": 308.3636363636364}], "name": "v8_7", "value": 23255.07688847675}]} 13:47:25 INFO - RETURN: dromaeo_css: 7295.44 13:47:25 INFO - RETURN: kraken: 1458.00 13:47:25 INFO - RETURN: v8_7: 23255.08 13:47:25 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23255.08"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1458.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7295.44"}}} 13:47:25 INFO - Return code: 0 13:47:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 13:47:26 INFO - # TBPL SUCCESS # 13:47:26 INFO - Running post-action listener: _resource_record_post_action 13:47:26 INFO - Running post-run listener: _resource_record_post_run 13:47:27 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 4096; Write bytes: 513769472; Read time: 8; Write time: 746704 13:47:27 INFO - install - Wall time: 14s; CPU: 16.0%; Read bytes: 0; Write bytes: 226795520; Read time: 0; Write time: 512812 13:47:27 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 4096; Write bytes: 261877760; Read time: 8; Write time: 218552 13:47:27 INFO - Running post-run listener: _upload_blobber_files 13:47:27 INFO - Blob upload gear active. 13:47:27 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 13:47:27 INFO - Copying logs to upload dir... 13:47:27 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1256.979073 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2015-12-28 13:47:26.988225) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-28 13:47:26.991881) ========= 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-1451337677.96287-1089953967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010008 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451329830/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-28 13:47:27.043882) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:47:27.044224) ========= 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-1451337677.96287-1089953967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004598 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:47:27.094940) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-28 13:47:27.095276) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-28 13:47:27.095607) ========= ========= Total master_lag: 0.26 =========