builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-063 starttime: 1450732557.31 results: success (0) buildid: 20151221105629 builduid: 260eaeeed23a4567be4d669099f92851 revision: abf1e06c22c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.307994) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.308438) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.308721) ========= 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=edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006437 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.331531) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.331825) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.344715) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.344989) ========= 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=edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004575 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.394426) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.394721) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.395188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.395468) ========= 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=edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-21 13:15:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2015-12-21 13:15:59 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151831 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.559681) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.559981) ========= 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=edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019580 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.643358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:57.643744) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev abf1e06c22c9 --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 abf1e06c22c9 --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=edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-21 13:15:59,529 Setting DEBUG logging. 2015-12-21 13:15:59,529 attempt 1/10 2015-12-21 13:15:59,529 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/abf1e06c22c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-21 13:15:59,766 unpacking tar archive at: mozilla-beta-abf1e06c22c9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.521904 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:58.180263) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:58.180793) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:58.190271) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:58.190803) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 13:15:58.191399) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2015-12-21 13:15:58.191838) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:16:00 INFO - MultiFileLogger online at 20151221 13:16:00 in /builds/slave/test-pgo 13:16:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 13:16:00 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 13:16:00 INFO - {'append_to_log': False, 13:16:00 INFO - 'base_work_dir': '/builds/slave/test-pgo', 13:16:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:16:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 13:16:00 INFO - 'branch': 'Mozilla-Beta', 13:16:00 INFO - 'buildbot_json_path': 'buildprops.json', 13:16:00 INFO - 'config_files': ('talos/linux_config.py',), 13:16:00 INFO - 'default_actions': ('clobber', 13:16:00 INFO - 'read-buildbot-config', 13:16:00 INFO - 'download-and-extract', 13:16:00 INFO - 'populate-webroot', 13:16:00 INFO - 'create-virtualenv', 13:16:00 INFO - 'install', 13:16:00 INFO - 'run-tests'), 13:16:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:16:00 INFO - 'download_minidump_stackwalk': True, 13:16:00 INFO - 'download_symbols': 'ondemand', 13:16:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:16:00 INFO - 'tooltool.py': '/tools/tooltool.py', 13:16:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:16:00 INFO - '/tools/misc-python/virtualenv.py')}, 13:16:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:16:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:16:00 INFO - 'installer_path': 'installer.exe', 13:16:00 INFO - 'log_level': 'info', 13:16:00 INFO - 'log_name': 'talos', 13:16:00 INFO - 'log_to_console': True, 13:16:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:16:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:16:00 INFO - 'opt_config_files': (), 13:16:00 INFO - 'pip_index': False, 13:16:00 INFO - 'sps_profile': False, 13:16:00 INFO - 'sps_profile_interval': 0, 13:16:00 INFO - 'suite': 'dromaeojs', 13:16:00 INFO - 'system_bits': '64', 13:16:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:16:00 INFO - 'title': 'talos-linux64-ix-063', 13:16:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:16:00 INFO - 'use_talos_json': True, 13:16:00 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 13:16:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:16:00 INFO - 'work_dir': 'build'} 13:16:00 INFO - ##### 13:16:00 INFO - ##### Running clobber step. 13:16:00 INFO - ##### 13:16:00 INFO - Running pre-action listener: _resource_record_pre_action 13:16:00 INFO - Running main action method: clobber 13:16:00 INFO - rmtree: /builds/slave/test-pgo/build 13:16:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 13:16:00 INFO - Running post-action listener: _resource_record_post_action 13:16:00 INFO - ##### 13:16:00 INFO - ##### Running read-buildbot-config step. 13:16:00 INFO - ##### 13:16:00 INFO - Running pre-action listener: _resource_record_pre_action 13:16:00 INFO - Running main action method: read_buildbot_config 13:16:00 INFO - Using buildbot properties: 13:16:00 INFO - { 13:16:00 INFO - "properties": { 13:16:00 INFO - "buildnumber": 38, 13:16:00 INFO - "product": "firefox", 13:16:00 INFO - "script_repo_revision": "production", 13:16:00 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:16:00 INFO - "repository": "", 13:16:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 13:16:00 INFO - "buildid": "20151221105629", 13:16:00 INFO - "slavename": "talos-linux64-ix-063", 13:16:00 INFO - "pgo_build": "True", 13:16:00 INFO - "basedir": "/builds/slave/test-pgo", 13:16:00 INFO - "project": "", 13:16:00 INFO - "platform": "ubuntu64_hw", 13:16:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:16:00 INFO - "slavebuilddir": "test", 13:16:00 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 13:16:00 INFO - "branch": "mozilla-beta", 13:16:00 INFO - "repo_path": "releases/mozilla-beta", 13:16:00 INFO - "stage_platform": "linux64-pgo", 13:16:00 INFO - "builduid": "260eaeeed23a4567be4d669099f92851", 13:16:00 INFO - "revision": "abf1e06c22c9" 13:16:00 INFO - }, 13:16:00 INFO - "sourcestamp": { 13:16:00 INFO - "repository": "", 13:16:00 INFO - "hasPatch": false, 13:16:00 INFO - "project": "", 13:16:00 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 13:16:00 INFO - "changes": [ 13:16:00 INFO - { 13:16:00 INFO - "category": null, 13:16:00 INFO - "files": [ 13:16:00 INFO - { 13:16:00 INFO - "url": null, 13:16:00 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/firefox-44.0.en-US.linux-x86_64.tar.bz2" 13:16:00 INFO - } 13:16:00 INFO - ], 13:16:00 INFO - "repository": "", 13:16:00 INFO - "rev": "abf1e06c22c9", 13:16:00 INFO - "who": "sendchange", 13:16:00 INFO - "when": 1450732535, 13:16:00 INFO - "number": 7382525, 13:16:00 INFO - "comments": "Bug 720589 - mMatchCounts may be accessed with a nonexisting index. r=neil a=ritu ba=jorgev", 13:16:00 INFO - "project": "", 13:16:00 INFO - "at": "Mon 21 Dec 2015 13:15:35", 13:16:00 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 13:16:00 INFO - "revlink": "", 13:16:00 INFO - "properties": [ 13:16:00 INFO - [ 13:16:00 INFO - "buildid", 13:16:00 INFO - "20151221105629", 13:16:00 INFO - "Change" 13:16:00 INFO - ], 13:16:00 INFO - [ 13:16:00 INFO - "builduid", 13:16:00 INFO - "260eaeeed23a4567be4d669099f92851", 13:16:00 INFO - "Change" 13:16:00 INFO - ], 13:16:00 INFO - [ 13:16:00 INFO - "pgo_build", 13:16:00 INFO - "True", 13:16:00 INFO - "Change" 13:16:00 INFO - ] 13:16:00 INFO - ], 13:16:00 INFO - "revision": "abf1e06c22c9" 13:16:00 INFO - } 13:16:00 INFO - ], 13:16:00 INFO - "revision": "abf1e06c22c9" 13:16:00 INFO - } 13:16:00 INFO - } 13:16:00 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/firefox-44.0.en-US.linux-x86_64.tar.bz2. 13:16:00 INFO - Running post-action listener: _resource_record_post_action 13:16:00 INFO - ##### 13:16:00 INFO - ##### Running download-and-extract step. 13:16:00 INFO - ##### 13:16:00 INFO - Running pre-action listener: _resource_record_pre_action 13:16:00 INFO - Running main action method: download_and_extract 13:16:00 INFO - mkdir: /builds/slave/test-pgo/build/tests 13:16: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:16:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/test_packages.json 13:16:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 13:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 13:16:00 INFO - Downloaded 1270 bytes. 13:16:00 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 13:16:00 INFO - Using the following test package requirements: 13:16:00 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 13:16:00 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:16:00 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 13:16:00 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:16:00 INFO - u'jsshell-linux-x86_64.zip'], 13:16:00 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:16:00 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 13:16:00 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 13:16:00 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:16:00 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 13:16:00 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:16:00 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 13:16:00 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:16:00 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 13:16:00 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 13:16:00 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:16:00 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 13:16:00 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:16: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:16:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:16:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/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:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/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:16:02 INFO - Downloaded 22571576 bytes. 13:16:02 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:16:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:16:02 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:16:03 INFO - Return code: 0 13:16:03 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:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:03 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:16:03 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/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:16:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/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:16:03 INFO - Downloaded 22571576 bytes. 13:16:03 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:16:03 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:16:03 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:16:05 INFO - Return code: 0 13:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:05 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 13:16:05 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/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:16:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/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:16:10 INFO - Downloaded 11131970 bytes. 13:16:10 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:16:10 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 13:16:10 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:16:10 INFO - Return code: 0 13:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/firefox-44.0.en-US.linux-x86_64.tar.bz2 13:16:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/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:16:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/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:16:14 INFO - Downloaded 56620718 bytes. 13:16:14 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/firefox-44.0.en-US.linux-x86_64.tar.bz2 13:16:14 INFO - mkdir: /builds/slave/test-pgo/properties 13:16:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 13:16:14 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 13:16:14 INFO - Contents: 13:16:14 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/firefox-44.0.en-US.linux-x86_64.tar.bz2 13:16:14 INFO - Running post-action listener: _resource_record_post_action 13:16:14 INFO - Running post-action listener: set_extra_try_arguments 13:16:14 INFO - ##### 13:16:14 INFO - ##### Running populate-webroot step. 13:16:14 INFO - ##### 13:16:14 INFO - Running pre-action listener: _resource_record_pre_action 13:16:14 INFO - Running main action method: populate_webroot 13:16:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:16:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:16:14 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 13:16:14 INFO - '../fennec_ids.txt'], 13:16:14 INFO - 'tests': ['tcheck2']}, 13:16:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:16:14 INFO - '--tppagecycles', 13:16:14 INFO - '7'], 13:16:14 INFO - 'tests': ['tsvgm']}}, 13:16:14 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 13:16:14 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 13:16:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:16:14 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 13:16:14 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 13:16:14 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 13:16:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:16:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:14 INFO - 'talos_options': ['--e10s'], 13:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:16:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:14 INFO - 'tests': ['damp', 'tps']}, 13:16:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:14 INFO - 'talos_options': ['--e10s'], 13:16:14 INFO - 'tests': ['damp', 'tps']}, 13:16:14 INFO - 'g3': {'tests': ['dromaeo_dom']}, 13:16:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:16:14 INFO - 'other': {'tests': ['a11yr', 13:16:14 INFO - 'ts_paint', 13:16:14 INFO - 'tpaint', 13:16:14 INFO - 'sessionrestore', 13:16:14 INFO - 'sessionrestore_no_auto_restore']}, 13:16:14 INFO - 'other-e10s': {'talos_options': ['--e10s'], 13:16:14 INFO - 'tests': ['a11yr', 13:16:14 INFO - 'ts_paint', 13:16:14 INFO - 'tpaint', 13:16:14 INFO - 'sessionrestore', 13:16:14 INFO - 'sessionrestore_no_auto_restore']}, 13:16:14 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 13:16:14 INFO - 'tests': ['a11yr', 13:16:14 INFO - 'ts_paint', 13:16:14 INFO - 'tpaint', 13:16:14 INFO - 'sessionrestore', 13:16:14 INFO - 'sessionrestore_no_auto_restore']}, 13:16:14 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 13:16:14 INFO - 'tests': ['a11yr', 13:16:14 INFO - 'ts_paint', 13:16:14 INFO - 'tpaint', 13:16:14 INFO - 'sessionrestore', 13:16:14 INFO - 'sessionrestore_no_auto_restore']}, 13:16:14 INFO - 'other_l64': {'tests': ['a11yr', 13:16:14 INFO - 'ts_paint', 13:16:14 INFO - 'tpaint', 13:16:14 INFO - 'sessionrestore', 13:16:14 INFO - 'sessionrestore_no_auto_restore']}, 13:16:14 INFO - 'other_nol64': {'tests': ['a11yr', 13:16:14 INFO - 'ts_paint', 13:16:14 INFO - 'tpaint', 13:16:14 INFO - 'sessionrestore', 13:16:14 INFO - 'sessionrestore_no_auto_restore']}, 13:16:14 INFO - 'svgr': {'tests': ['tsvgx', 13:16:14 INFO - 'tsvgr_opacity', 13:16:14 INFO - 'tart', 13:16:14 INFO - 'tscrollx', 13:16:14 INFO - 'cart']}, 13:16:14 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 13:16:14 INFO - 'tests': ['tsvgx', 13:16:14 INFO - 'tsvgr_opacity', 13:16:14 INFO - 'tart', 13:16:14 INFO - 'tscrollx', 13:16:14 INFO - 'cart']}, 13:16:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:14 INFO - 'tests': ['tp5o']}, 13:16:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:14 INFO - 'talos_options': ['--e10s'], 13:16:14 INFO - 'tests': ['tp5o']}, 13:16:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:14 INFO - 'talos_options': ['--xperf_path', 13:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:16:14 INFO - 'tests': ['tp5n']}, 13:16:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:14 INFO - 'talos_options': ['--e10s', 13:16:14 INFO - '--xperf_path', 13:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:16:14 INFO - 'tests': ['tp5n']}}, 13:16:14 INFO - 'talos.zip': {'path': '', 13:16:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:16:14 INFO - Running post-action listener: _resource_record_post_action 13:16:14 INFO - ##### 13:16:14 INFO - ##### Running create-virtualenv step. 13:16:14 INFO - ##### 13:16:14 INFO - Running pre-action listener: _resource_record_pre_action 13:16:14 INFO - Running main action method: create_virtualenv 13:16:14 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 13:16:14 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:16:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 13:16:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:16:14 INFO - Using real prefix '/usr' 13:16:14 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 13:16:15 INFO - Installing distribute.............................................................................................................................................................................................done. 13:16:17 INFO - Installing pip.................done. 13:16:17 INFO - Return code: 0 13:16:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 13:16:17 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:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:17 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:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:17 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 0x7f4b84ce5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a87600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b16350>, '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 0x1b17250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b176e0>, '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': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', '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:16:17 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:16:17 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:16:17 INFO - Using env: {'DISPLAY': ':0', 13:16:17 INFO - 'HOME': '/home/cltbld', 13:16:17 INFO - 'LANG': 'en_US.UTF-8', 13:16:17 INFO - 'LANGUAGE': 'en_US:en', 13:16:17 INFO - 'LOGNAME': 'cltbld', 13:16:17 INFO - 'MAIL': '/var/mail/cltbld', 13:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:17 INFO - 'MOZ_NO_REMOTE': '1', 13:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:17 INFO - 'NO_EM_RESTART': '1', 13:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:16:17 INFO - 'PWD': '/builds/slave/test-pgo', 13:16:17 INFO - 'SHELL': '/bin/bash', 13:16:17 INFO - 'SHLVL': '1', 13:16:17 INFO - 'TERM': 'linux', 13:16:17 INFO - 'TMOUT': '86400', 13:16:17 INFO - 'USER': 'cltbld', 13:16:17 INFO - 'XDG_SESSION_COOKIE': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', 13:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:17 INFO - '_': '/tools/buildbot/bin/python'} 13:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:17 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:16:17 INFO - Cleaning up... 13:16:17 INFO - Return code: 0 13:16:17 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 13:16:17 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:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:17 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:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:17 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 0x7f4b84ce5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a87600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b16350>, '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 0x1b17250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b176e0>, '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': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', '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:16:17 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:16:17 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:16:17 INFO - Using env: {'DISPLAY': ':0', 13:16:17 INFO - 'HOME': '/home/cltbld', 13:16:17 INFO - 'LANG': 'en_US.UTF-8', 13:16:17 INFO - 'LANGUAGE': 'en_US:en', 13:16:17 INFO - 'LOGNAME': 'cltbld', 13:16:17 INFO - 'MAIL': '/var/mail/cltbld', 13:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:17 INFO - 'MOZ_NO_REMOTE': '1', 13:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:17 INFO - 'NO_EM_RESTART': '1', 13:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:16:17 INFO - 'PWD': '/builds/slave/test-pgo', 13:16:17 INFO - 'SHELL': '/bin/bash', 13:16:17 INFO - 'SHLVL': '1', 13:16:17 INFO - 'TERM': 'linux', 13:16:17 INFO - 'TMOUT': '86400', 13:16:17 INFO - 'USER': 'cltbld', 13:16:17 INFO - 'XDG_SESSION_COOKIE': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', 13:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:17 INFO - '_': '/tools/buildbot/bin/python'} 13:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:17 INFO - Downloading/unpacking psutil>=0.7.1 13:16:17 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:16:17 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:16:17 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:16:17 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:16:17 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:16:17 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:16:19 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 13:16:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 13:16:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 13:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:16:19 INFO - Installing collected packages: psutil 13:16:19 INFO - Running setup.py install for psutil 13:16:19 INFO - building 'psutil._psutil_linux' extension 13:16:19 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:16:19 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:16:19 INFO - building 'psutil._psutil_posix' extension 13:16:19 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:16:19 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:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:16:19 INFO - Successfully installed psutil 13:16:19 INFO - Cleaning up... 13:16:19 INFO - Return code: 0 13:16:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 13:16:19 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:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:19 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:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:19 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 0x7f4b84ce5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a87600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b16350>, '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 0x1b17250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b176e0>, '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': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', '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:16:19 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:16:19 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:16:19 INFO - Using env: {'DISPLAY': ':0', 13:16:19 INFO - 'HOME': '/home/cltbld', 13:16:19 INFO - 'LANG': 'en_US.UTF-8', 13:16:19 INFO - 'LANGUAGE': 'en_US:en', 13:16:19 INFO - 'LOGNAME': 'cltbld', 13:16:19 INFO - 'MAIL': '/var/mail/cltbld', 13:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:19 INFO - 'MOZ_NO_REMOTE': '1', 13:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:19 INFO - 'NO_EM_RESTART': '1', 13:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:16:19 INFO - 'PWD': '/builds/slave/test-pgo', 13:16:19 INFO - 'SHELL': '/bin/bash', 13:16:19 INFO - 'SHLVL': '1', 13:16:19 INFO - 'TERM': 'linux', 13:16:19 INFO - 'TMOUT': '86400', 13:16:19 INFO - 'USER': 'cltbld', 13:16:19 INFO - 'XDG_SESSION_COOKIE': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', 13:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:19 INFO - '_': '/tools/buildbot/bin/python'} 13:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:16:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:16:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:16:21 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:16:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 13:16:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:16:21 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:16:21 INFO - Installing collected packages: mozsystemmonitor 13:16:21 INFO - Running setup.py install for mozsystemmonitor 13:16:21 INFO - Successfully installed mozsystemmonitor 13:16:21 INFO - Cleaning up... 13:16:21 INFO - Return code: 0 13:16:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 13:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:21 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4b84ce5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a87600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b16350>, '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 0x1b17250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b176e0>, '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': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', '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:16:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 13:16:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:16:21 INFO - Using env: {'DISPLAY': ':0', 13:16:21 INFO - 'HOME': '/home/cltbld', 13:16:21 INFO - 'LANG': 'en_US.UTF-8', 13:16:21 INFO - 'LANGUAGE': 'en_US:en', 13:16:21 INFO - 'LOGNAME': 'cltbld', 13:16:21 INFO - 'MAIL': '/var/mail/cltbld', 13:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:21 INFO - 'MOZ_NO_REMOTE': '1', 13:16:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:21 INFO - 'NO_EM_RESTART': '1', 13:16:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:16:21 INFO - 'PWD': '/builds/slave/test-pgo', 13:16:21 INFO - 'SHELL': '/bin/bash', 13:16:21 INFO - 'SHLVL': '1', 13:16:21 INFO - 'TERM': 'linux', 13:16:21 INFO - 'TMOUT': '86400', 13:16:21 INFO - 'USER': 'cltbld', 13:16:21 INFO - 'XDG_SESSION_COOKIE': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', 13:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:21 INFO - '_': '/tools/buildbot/bin/python'} 13:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:22 INFO - Downloading/unpacking blobuploader==1.2.4 13:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:23 INFO - Downloading blobuploader-1.2.4.tar.gz 13:16:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 13:16:23 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:16:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:16:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:16:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:16:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 13:16:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 13:16:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:16:24 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:16:24 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:16:24 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:16:24 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:16:24 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:16:24 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:16:24 INFO - Downloading docopt-0.6.1.tar.gz 13:16:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 13:16:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 13:16:24 INFO - Installing collected packages: blobuploader, requests, docopt 13:16:24 INFO - Running setup.py install for blobuploader 13:16:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:16:24 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 13:16:24 INFO - Running setup.py install for requests 13:16:25 INFO - Running setup.py install for docopt 13:16:25 INFO - Successfully installed blobuploader requests docopt 13:16:25 INFO - Cleaning up... 13:16:25 INFO - Return code: 0 13:16:25 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:16:25 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:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:25 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:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:25 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 0x7f4b84ce5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a87600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b16350>, '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 0x1b17250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b176e0>, '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': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', '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:16:25 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:16:25 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:16:25 INFO - Using env: {'DISPLAY': ':0', 13:16:25 INFO - 'HOME': '/home/cltbld', 13:16:25 INFO - 'LANG': 'en_US.UTF-8', 13:16:25 INFO - 'LANGUAGE': 'en_US:en', 13:16:25 INFO - 'LOGNAME': 'cltbld', 13:16:25 INFO - 'MAIL': '/var/mail/cltbld', 13:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:25 INFO - 'MOZ_NO_REMOTE': '1', 13:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:25 INFO - 'NO_EM_RESTART': '1', 13:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:16:25 INFO - 'PWD': '/builds/slave/test-pgo', 13:16:25 INFO - 'SHELL': '/bin/bash', 13:16:25 INFO - 'SHLVL': '1', 13:16:25 INFO - 'TERM': 'linux', 13:16:25 INFO - 'TMOUT': '86400', 13:16:25 INFO - 'USER': 'cltbld', 13:16:25 INFO - 'XDG_SESSION_COOKIE': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', 13:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:25 INFO - '_': '/tools/buildbot/bin/python'} 13:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:16:25 INFO - Running setup.py (path:/tmp/pip-a8LuDM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:16:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:16:25 INFO - Running setup.py (path:/tmp/pip-6ZCrJf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:16:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:16:25 INFO - Running setup.py (path:/tmp/pip-fOqBe8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:16:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:16:25 INFO - Running setup.py (path:/tmp/pip-VTp0Vw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:16:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 13:16:25 INFO - Running setup.py (path:/tmp/pip-zvy10u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 13:16:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:16:25 INFO - Running setup.py (path:/tmp/pip-Lhx8xb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:16:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:16:25 INFO - Running setup.py (path:/tmp/pip-NOmpIh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:16:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:16:25 INFO - Running setup.py (path:/tmp/pip-OAe7Ut-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:16:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 13:16:25 INFO - Running setup.py (path:/tmp/pip-qPeUI6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 13:16:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 13:16:26 INFO - Running setup.py (path:/tmp/pip-11Zmpd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 13:16:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:16:26 INFO - Running setup.py (path:/tmp/pip-AQ4UFK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:16:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:16:26 INFO - Running setup.py (path:/tmp/pip-kBqdPS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:16:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:16:26 INFO - Running setup.py (path:/tmp/pip-oLNN9F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:16:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:16:26 INFO - Running setup.py (path:/tmp/pip-gLgiRB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:16:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:16:26 INFO - Running setup.py (path:/tmp/pip-wBaV_N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:16:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 13:16:26 INFO - Running setup.py (path:/tmp/pip-bBGgpk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 13:16:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 13:16:26 INFO - Running setup.py (path:/tmp/pip-b9D39D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 13:16:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:16:26 INFO - Running setup.py install for manifestparser 13:16:26 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 13:16:26 INFO - Running setup.py install for mozcrash 13:16:26 INFO - Running setup.py install for mozdebug 13:16:26 INFO - Running setup.py install for mozdevice 13:16:27 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Running setup.py install for mozfile 13:16:27 INFO - Running setup.py install for mozhttpd 13:16:27 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Running setup.py install for mozinfo 13:16:27 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Running setup.py install for mozInstall 13:16:27 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Running setup.py install for mozleak 13:16:27 INFO - Running setup.py install for mozlog 13:16:27 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Running setup.py install for moznetwork 13:16:27 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Running setup.py install for mozprocess 13:16:27 INFO - Running setup.py install for mozprofile 13:16:27 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 13:16:27 INFO - Running setup.py install for mozrunner 13:16:28 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 13:16:28 INFO - Running setup.py install for mozscreenshot 13:16:28 INFO - Running setup.py install for moztest 13:16:28 INFO - Running setup.py install for mozversion 13:16:28 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 13:16:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:16:28 INFO - Cleaning up... 13:16:28 INFO - Return code: 0 13:16:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:16:28 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:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:28 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:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:28 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 0x7f4b84ce5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a87600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b16350>, '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 0x1b17250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b176e0>, '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': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', '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:16:28 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:16:28 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:16:28 INFO - Using env: {'DISPLAY': ':0', 13:16:28 INFO - 'HOME': '/home/cltbld', 13:16:28 INFO - 'LANG': 'en_US.UTF-8', 13:16:28 INFO - 'LANGUAGE': 'en_US:en', 13:16:28 INFO - 'LOGNAME': 'cltbld', 13:16:28 INFO - 'MAIL': '/var/mail/cltbld', 13:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:28 INFO - 'MOZ_NO_REMOTE': '1', 13:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:28 INFO - 'NO_EM_RESTART': '1', 13:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:16:28 INFO - 'PWD': '/builds/slave/test-pgo', 13:16:28 INFO - 'SHELL': '/bin/bash', 13:16:28 INFO - 'SHLVL': '1', 13:16:28 INFO - 'TERM': 'linux', 13:16:28 INFO - 'TMOUT': '86400', 13:16:28 INFO - 'USER': 'cltbld', 13:16:28 INFO - 'XDG_SESSION_COOKIE': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', 13:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:28 INFO - '_': '/tools/buildbot/bin/python'} 13:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:16:28 INFO - Running setup.py (path:/tmp/pip-Hj3rcI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:16:28 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:16:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:16:28 INFO - Running setup.py (path:/tmp/pip-TBj3qa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:16:28 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:16:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:16:28 INFO - Running setup.py (path:/tmp/pip-4flmSS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:16:28 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:16:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:16:28 INFO - Running setup.py (path:/tmp/pip-mUDlHA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:16:28 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:16:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 13:16:28 INFO - Running setup.py (path:/tmp/pip-4G0qcM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 13:16:28 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:16:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:16:28 INFO - Running setup.py (path:/tmp/pip-dJgZ1x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:16:29 INFO - Running setup.py (path:/tmp/pip-zC21LN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:16:29 INFO - Running setup.py (path:/tmp/pip-e2Yz9c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 13:16:29 INFO - Running setup.py (path:/tmp/pip-Bh9TLs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 13:16:29 INFO - Running setup.py (path:/tmp/pip-BPHztp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:16:29 INFO - Running setup.py (path:/tmp/pip-NEEiH6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:16:29 INFO - Running setup.py (path:/tmp/pip-7bMzkE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:16:29 INFO - Running setup.py (path:/tmp/pip-KuE3Q_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:16:29 INFO - Running setup.py (path:/tmp/pip-5Ek2Qx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:16:29 INFO - Running setup.py (path:/tmp/pip-5iw__h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 13:16:29 INFO - Running setup.py (path:/tmp/pip-LMXhxD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 13:16:29 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:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 13:16:29 INFO - Running setup.py (path:/tmp/pip-67O7FG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 13:16:29 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:16:29 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:16:29 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:16:29 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:16:29 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:16:29 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:16:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 13:16:29 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:16:29 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:16:29 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:16:29 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:16:29 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:16:29 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:16:31 INFO - Downloading blessings-1.5.1.tar.gz 13:16:31 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:16:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 13:16:31 INFO - Installing collected packages: blessings 13:16:31 INFO - Running setup.py install for blessings 13:16:31 INFO - Successfully installed blessings 13:16:31 INFO - Cleaning up... 13:16:31 INFO - Return code: 0 13:16:31 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 13:16:31 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 13:16:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 13:16:31 INFO - Reading from file tmpfile_stdout 13:16:31 INFO - Current package versions: 13:16:31 INFO - argparse == 1.2.1 13:16:31 INFO - blessings == 1.5.1 13:16:31 INFO - blobuploader == 1.2.4 13:16:31 INFO - docopt == 0.6.1 13:16:31 INFO - manifestparser == 1.1 13:16:31 INFO - mozInstall == 1.12 13:16:31 INFO - mozcrash == 0.16 13:16:31 INFO - mozdebug == 0.1 13:16:31 INFO - mozdevice == 0.46 13:16:31 INFO - mozfile == 1.2 13:16:31 INFO - mozhttpd == 0.7 13:16:31 INFO - mozinfo == 0.8 13:16:31 INFO - mozleak == 0.1 13:16:31 INFO - mozlog == 3.0 13:16:31 INFO - moznetwork == 0.27 13:16:31 INFO - mozprocess == 0.22 13:16:31 INFO - mozprofile == 0.27 13:16:31 INFO - mozrunner == 6.11 13:16:31 INFO - mozscreenshot == 0.1 13:16:31 INFO - mozsystemmonitor == 0.0 13:16:31 INFO - moztest == 0.7 13:16:31 INFO - mozversion == 1.4 13:16:31 INFO - psutil == 3.1.1 13:16:31 INFO - requests == 1.2.3 13:16:31 INFO - wsgiref == 0.1.2 13:16:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:16: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:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16: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:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:31 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 0x7f4b84ce5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a87600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b16350>, '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 0x1b17250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b176e0>, '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': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', '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:16:31 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:16:31 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:16:31 INFO - Using env: {'DISPLAY': ':0', 13:16:31 INFO - 'HOME': '/home/cltbld', 13:16:31 INFO - 'LANG': 'en_US.UTF-8', 13:16:31 INFO - 'LANGUAGE': 'en_US:en', 13:16:31 INFO - 'LOGNAME': 'cltbld', 13:16:31 INFO - 'MAIL': '/var/mail/cltbld', 13:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:31 INFO - 'MOZ_NO_REMOTE': '1', 13:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:31 INFO - 'NO_EM_RESTART': '1', 13:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:16:31 INFO - 'PWD': '/builds/slave/test-pgo', 13:16:31 INFO - 'SHELL': '/bin/bash', 13:16:31 INFO - 'SHLVL': '1', 13:16:31 INFO - 'TERM': 'linux', 13:16:31 INFO - 'TMOUT': '86400', 13:16:31 INFO - 'USER': 'cltbld', 13:16:31 INFO - 'XDG_SESSION_COOKIE': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', 13:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:31 INFO - '_': '/tools/buildbot/bin/python'} 13:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:32 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:16:32 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:16:32 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:16:32 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:16:32 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:16:32 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:16:32 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:16:32 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:16:32 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:16:32 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:16:32 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:16:32 INFO - Cleaning up... 13:16:32 INFO - Return code: 0 13:16:32 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 13:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:32 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 0x7f4b84ce5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a87600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b16350>, '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 0x1b17250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b176e0>, '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': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', '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:16:32 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:16:32 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:16:32 INFO - Using env: {'DISPLAY': ':0', 13:16:32 INFO - 'HOME': '/home/cltbld', 13:16:32 INFO - 'LANG': 'en_US.UTF-8', 13:16:32 INFO - 'LANGUAGE': 'en_US:en', 13:16:32 INFO - 'LOGNAME': 'cltbld', 13:16:32 INFO - 'MAIL': '/var/mail/cltbld', 13:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:32 INFO - 'MOZ_NO_REMOTE': '1', 13:16:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:32 INFO - 'NO_EM_RESTART': '1', 13:16:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:16:32 INFO - 'PWD': '/builds/slave/test-pgo', 13:16:32 INFO - 'SHELL': '/bin/bash', 13:16:32 INFO - 'SHLVL': '1', 13:16:32 INFO - 'TERM': 'linux', 13:16:32 INFO - 'TMOUT': '86400', 13:16:32 INFO - 'USER': 'cltbld', 13:16:32 INFO - 'XDG_SESSION_COOKIE': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', 13:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:32 INFO - '_': '/tools/buildbot/bin/python'} 13:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:32 INFO - Downloading/unpacking jsonschema 13:16:32 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:16:32 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:16:32 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:16:32 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:16:32 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:16:32 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:16:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:16:34 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:16:34 INFO - Downloading/unpacking functools32 (from jsonschema) 13:16:34 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:16:34 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:16:34 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:16:34 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:16:34 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:16:34 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:16:34 INFO - Downloading functools32-3.2.3-2.tar.gz 13:16:34 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:16:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 13:16:34 INFO - warning: no files found matching '*.txt' 13:16:34 INFO - no previously-included directories found matching 'build' 13:16:34 INFO - no previously-included directories found matching 'dist' 13:16:34 INFO - no previously-included directories found matching '.git*' 13:16:34 INFO - Installing collected packages: jsonschema, functools32 13:16:34 INFO - Running setup.py install for functools32 13:16:34 INFO - warning: no files found matching '*.txt' 13:16:34 INFO - no previously-included directories found matching 'build' 13:16:34 INFO - no previously-included directories found matching 'dist' 13:16:34 INFO - no previously-included directories found matching '.git*' 13:16:34 INFO - Successfully installed jsonschema functools32 13:16:34 INFO - Cleaning up... 13:16:34 INFO - Return code: 0 13:16:34 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 13:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:34 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 0x7f4b84ce5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a87600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b16350>, '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 0x1b17250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b176e0>, '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': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', '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:16:34 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:16:34 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:16:34 INFO - Using env: {'DISPLAY': ':0', 13:16:34 INFO - 'HOME': '/home/cltbld', 13:16:34 INFO - 'LANG': 'en_US.UTF-8', 13:16:34 INFO - 'LANGUAGE': 'en_US:en', 13:16:34 INFO - 'LOGNAME': 'cltbld', 13:16:34 INFO - 'MAIL': '/var/mail/cltbld', 13:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:34 INFO - 'MOZ_NO_REMOTE': '1', 13:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:34 INFO - 'NO_EM_RESTART': '1', 13:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:16:34 INFO - 'PWD': '/builds/slave/test-pgo', 13:16:34 INFO - 'SHELL': '/bin/bash', 13:16:34 INFO - 'SHLVL': '1', 13:16:34 INFO - 'TERM': 'linux', 13:16:34 INFO - 'TMOUT': '86400', 13:16:34 INFO - 'USER': 'cltbld', 13:16:34 INFO - 'XDG_SESSION_COOKIE': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', 13:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:34 INFO - '_': '/tools/buildbot/bin/python'} 13:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:34 INFO - Downloading/unpacking flake8 13:16:34 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:16:34 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:16:34 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:16:34 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:16:34 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:16:34 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:16:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:16:36 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:16:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:16:36 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:16:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 13:16:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 13:16:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:16:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:16:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:16:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:16:37 INFO - no previously-included directories found matching 'docs/_build' 13:16:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:16:37 INFO - Running setup.py install for pep8 13:16:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:16:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:16:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:16:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:16:37 INFO - no previously-included directories found matching 'docs/_build' 13:16:37 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 13:16:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:16:37 INFO - Cleaning up... 13:16:37 INFO - Return code: 0 13:16:37 INFO - Running post-action listener: _resource_record_post_action 13:16:37 INFO - Running post-action listener: _start_resource_monitoring 13:16:37 INFO - Starting resource monitoring. 13:16:37 INFO - ##### 13:16:37 INFO - ##### Running install step. 13:16:37 INFO - ##### 13:16:37 INFO - Running pre-action listener: _resource_record_pre_action 13:16:37 INFO - Running main action method: install 13:16:37 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 13:16:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 13:16:38 INFO - Reading from file tmpfile_stdout 13:16:38 INFO - Detecting whether we're running mozinstall >=1.0... 13:16:38 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 13:16:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 13:16:38 INFO - Reading from file tmpfile_stdout 13:16:38 INFO - Output received: 13:16:38 INFO - Usage: mozinstall [options] installer 13:16:38 INFO - Options: 13:16:38 INFO - -h, --help show this help message and exit 13:16:38 INFO - -d DEST, --destination=DEST 13:16:38 INFO - Directory to install application into. [default: 13:16:38 INFO - "/builds/slave/test-pgo"] 13:16:38 INFO - --app=APP Application being installed. [default: firefox] 13:16:38 INFO - mkdir: /builds/slave/test-pgo/build/application 13:16:38 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:16:38 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:16:52 INFO - Reading from file tmpfile_stdout 13:16:52 INFO - Output received: 13:16:52 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 13:16:52 INFO - Running post-action listener: _resource_record_post_action 13:16:52 INFO - ##### 13:16:52 INFO - ##### Running run-tests step. 13:16:52 INFO - ##### 13:16:52 INFO - Running pre-action listener: _resource_record_pre_action 13:16:52 INFO - Running main action method: run_tests 13:16:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 13:16:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 13:16:52 INFO - Python 2.7.3 13:16:52 INFO - Return code: 0 13:16:52 INFO - grabbing minidump binary from tooltool 13:16:52 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:16:52 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 0x1b16350>, '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 0x1b17250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b176e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 13:16:52 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:16:52 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:16:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:16:52 INFO - Return code: 0 13:16:52 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 13:16:52 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 13:16:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 13:16:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 13:16:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 13:16:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 13:16:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 13:16:53 INFO - Return code: 0 13:16:53 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/1450724189/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 13:16:53 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/1450724189/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --webServer localhost 13:16:53 INFO - Using env: {'DISPLAY': ':0', 13:16:53 INFO - 'HOME': '/home/cltbld', 13:16:53 INFO - 'LANG': 'en_US.UTF-8', 13:16:53 INFO - 'LANGUAGE': 'en_US:en', 13:16:53 INFO - 'LOGNAME': 'cltbld', 13:16:53 INFO - 'MAIL': '/var/mail/cltbld', 13:16:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 13:16:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 13:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:53 INFO - 'MOZ_NO_REMOTE': '1', 13:16:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 13:16:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:53 INFO - 'NO_EM_RESTART': '1', 13:16:53 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:16:53 INFO - 'PWD': '/builds/slave/test-pgo', 13:16:53 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 13:16:53 INFO - 'SHELL': '/bin/bash', 13:16:53 INFO - 'SHLVL': '1', 13:16:53 INFO - 'TERM': 'linux', 13:16:53 INFO - 'TMOUT': '86400', 13:16:53 INFO - 'USER': 'cltbld', 13:16:53 INFO - 'XDG_SESSION_COOKIE': 'edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471', 13:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:53 INFO - '_': '/tools/buildbot/bin/python'} 13:16:53 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/1450724189/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost'] with output_timeout 3600 13:16:53 INFO - mozversion INFO | application_buildid: 20151221105629 13:16:53 INFO - mozversion INFO | application_changeset: abf1e06c22c999073d0311268572998ef49bcf61 13:16:53 INFO - mozversion INFO | application_display_name: Nightly 13:16:53 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:16:53 INFO - mozversion INFO | application_name: Firefox 13:16:53 INFO - mozversion INFO | application_remotingname: firefox 13:16:53 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:16:53 INFO - mozversion INFO | application_vendor: Mozilla 13:16:53 INFO - mozversion INFO | application_version: 44.0 13:16:53 INFO - mozversion INFO | platform_buildid: 20151221105629 13:16:53 INFO - mozversion INFO | platform_changeset: abf1e06c22c999073d0311268572998ef49bcf61 13:16:53 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:16:53 INFO - mozversion INFO | platform_version: 43.0 13:16:53 INFO - 2015-12-21 13:16:53,696 DEBUG : using testdate: 1450732613 13:16:53 INFO - 2015-12-21 13:16:53,696 DEBUG : actual date: 1450732613 13:16:53 INFO - 2015-12-21 13:16:53,704 INFO : starting webserver on 'localhost:47853' 13:16:53 INFO - 2015-12-21 13:16:53,705 INFO : Starting test suite talos-linux64-ix-063 13:16:53 INFO - 2015-12-21 13:16:53,706 INFO : Starting test dromaeo_css 13:16:53 INFO - 2015-12-21 13:16:53,706 DEBUG : operating with platform_type : linux_ 13:16:53 INFO - 2015-12-21 13:16:53,706 INFO : Initialising browser for dromaeo_css test... 13:16:53 INFO - 2015-12-21 13:16:53,723 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp12sN8q/profile http://localhost:47853/getInfo.html 13:16:57 INFO - 2015-12-21 13:16:57,362 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:16:57 INFO - 2015-12-21 13:16:57,363 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:16:57 INFO - 2015-12-21 13:16:57,363 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:16:57 INFO - 2015-12-21 13:16:57,363 DEBUG : BROWSER_OUTPUT: __metrics 13:16:57 INFO - 2015-12-21 13:16:57,431 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:16:58 INFO - 2015-12-21 13:16:58,870 INFO : Browser initialized. 13:16:58 INFO - 2015-12-21 13:16:58,871 INFO : Running cycle 1/1 for dromaeo_css test... 13:16:58 INFO - 2015-12-21 13:16:58,871 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp12sN8q/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:17:10 INFO - 2015-12-21 13:17:10,409 DEBUG : BROWSER_OUTPUT: RSS: Main: 128913408 13:17:10 INFO - 2015-12-21 13:17:10,410 DEBUG : BROWSER_OUTPUT: 13:18:01 INFO - 2015-12-21 13:18:01,272 DEBUG : BROWSER_OUTPUT: 1450732681269 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:20:09 INFO - 2015-12-21 13:20:09,382 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47853/tests/dromaeo/cssquery-ext.html) 13:20:10 INFO - 2015-12-21 13:20:10,354 DEBUG : BROWSER_OUTPUT: RSS: Main: 215740416 13:20:10 INFO - 2015-12-21 13:20:10,354 DEBUG : BROWSER_OUTPUT: 13:23:12 INFO - 2015-12-21 13:23:12,347 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/dromaeo/cssquery-ext.html (next: http://localhost:47853/tests/dromaeo/cssquery-jquery.html) 13:23:12 INFO - 2015-12-21 13:23:12,909 DEBUG : BROWSER_OUTPUT: RSS: Main: 202108928 13:23:12 INFO - 2015-12-21 13:23:12,909 DEBUG : BROWSER_OUTPUT: 13:26:14 INFO - 2015-12-21 13:26:14,600 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47853/tests/dromaeo/cssquery-mootools.html) 13:26:15 INFO - 2015-12-21 13:26:15,162 DEBUG : BROWSER_OUTPUT: RSS: Main: 199274496 13:26:15 INFO - 2015-12-21 13:26:15,162 DEBUG : BROWSER_OUTPUT: 13:29:16 INFO - 2015-12-21 13:29:16,852 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47853/tests/dromaeo/cssquery-prototype.html) 13:29:17 INFO - 2015-12-21 13:29:17,421 DEBUG : BROWSER_OUTPUT: RSS: Main: 188432384 13:29:17 INFO - 2015-12-21 13:29:17,421 DEBUG : BROWSER_OUTPUT: 13:32:19 INFO - 2015-12-21 13:32:19,697 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47853/tests/dromaeo/cssquery-yui.html) 13:32:20 INFO - 2015-12-21 13:32:20,263 DEBUG : BROWSER_OUTPUT: RSS: Main: 192512000 13:32:20 INFO - 2015-12-21 13:32:20,263 DEBUG : BROWSER_OUTPUT: 13:35:22 INFO - 2015-12-21 13:35:22,323 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/dromaeo/cssquery-yui.html (next: http://localhost:47853/tests/dromaeo/cssquery-dojo.html) 13:35:22 INFO - 2015-12-21 13:35:22,566 DEBUG : BROWSER_OUTPUT: RSS: Main: 235028480 13:35:22 INFO - 2015-12-21 13:35:22,566 DEBUG : BROWSER_OUTPUT: 13:35:22 INFO - 2015-12-21 13:35:22,568 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:35:22 INFO - 2015-12-21 13:35:22,568 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:35:22 INFO - 2015-12-21 13:35:22,568 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:35:22 INFO - 2015-12-21 13:35:22,568 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:35:22 INFO - 2015-12-21 13:35:22,572 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3231.5798946137;3354.781939173955;3359.7648164628454;3384.3569721752865;3417.999999999999;5482.80810171644;5502.284702988616;5514.420985796493;5522.91715624265;5540.168974653799;5474.343078830536;5486.451354864512;5595.468430499106;5597.216389705444;5601.859953501154;5011.649184557083;5053.292539044527;5088.058709138802;5161.225816127573;5175.560077393426;5083.110455670261;5112.054269960069;5115.667481613702;5124.026434977342;5130.358705161855;5071.112555302826;5083.720395378253;5130.692158470485;5158.664686795365;5160.277561141444;5181.300524429192;5202.4537423570355;5222.686638801684;5226.947730522684;5256.237845512417;4943.579795717368;5018.974905125488;5140.485951404829;5146.511081447256;5197.454267301917;5346.251524786533;5409;5413.052715774724;5435.374931882805;5436.728163591844;5947.10793380992;5974.402559744034;5980.401959804029;5981.760729570856;5982.491488223506;5883.970580147072;5967.373425790273;5985.850353741148;6021.2473440819895;6075;5608.775648974036;5617.550595952314;5620.494155526022;5627.859303517363;5686.488216060575;6054.334023257438;6083.422074902877;6085.817425477154;6089.2997305310455;6095.299040610298;5724.599278050497;5817.301923769175;5868.4424979626865;5921.171036054956;5970.432808883149;5985;6026.819195424145;6028.849278768066;6031.125486804476;6032.4570788629235;5982.551308651869;5988.431099045583;5997.670128142907;5997.880042399128;5998;5860.208871802337;5915.615484993462;5944.167816505693;5997.400259973968;6005.759769609167;4601.539846015372;4605.424321959755;4610.631149508032;4612.584867361954;4626.768661566908;4126;4127.628513433805;4133.256013917464;4135.793210339471;4156.625903668684;5500.257465242221;5573.136163894603;5612.550995920316;5648.774049037993;5665.886682266331;6040.09398590215;6047.455728984412;6054.364291749391;6056.454919057218;6063.151158837766;5682;5691.459311365414;5696.430356964271;5696.515796157373;5699.544036477155;5129.281900533928;5149.948500514947;5210.7915683373;5232;5237.895242095213;6156.538259630545;6180.783672571439;6188.9690551546955;6192.411720886508;6193.907091393633;5864.560157988253;5979.073243647242;5981.820545383645;5983.132445795422;5995.400459953969;6101.84745381369;6141.84645383869;6170.197874276315;6185.505159587312;6194.318624951342;5946.286445626552;5956.523478121827;5962.70186490665;5986.610870293417;5994.790182343597;5798.391168927202;5886.882262354815;5978.252718410199;5985.700714964321;5987.64074155552;5808.099744539603;5906.26171728534;5906.320773111147;5913.586048976703;5928.525717942468;5869.295684517885;5871.178035075008;5872.500837428866;5878.500327472213;5879.176915231957;5648.745806438802;5722.7710891563875;5753.05074662676;5782.045962416154;5786.161006654096;5761.107028410603;5811.331696854916;5812.680302583399;5825.184474173534;5914.34942156371;5684.687342196219;5693.686847223443;5694.373618902;5698.316202055614;5711.714414279187 13:35:22 INFO - 2015-12-21 13:35:22,572 DEBUG : BROWSER_OUTPUT: |1;ext.html;564.6799573046864;592.9822105336839;596.1594152245334;613.3590398034054;650.6226388694557;34701.132471688186;43372.78313608431;43831;43863.78068109655;43962.12075758483;47647.28529072058;48323.27515087277;48365.7581712091;48374.75812620932;48449.273260901115;8371.372147088961;8491.532965686885;9174.449533028006;9319.906800932005;9367.437953722765;10583.894161058368;10874;10875.510602022889;10882.782344353109;10898.891011089867;10444.26890117692;10515.106215971651;10523.368597884151;10525.421101839396;10526.526306316235;10609.204309676767;10689.037986581166;10690.198235132357;10703.304285221435;10711.982361675668;10381.740456488573;10474.842877356845;10493.265471417004;10688.893111068868;10776;44639.77680111611;44777.55222447799;44975.32537011948;45129.774351128035;45166;3702.7037836973245;3772.660460558563;3819.9427008594894;3877.0888841122314;4328.04782947751;33105;33148.33703325944;33604.663953360396;33857;33891;3374.8481318340732;3390.8135052572097;3399.9730714951843;3419.3161367726307;3451.430513965193;305369;305891;307320.4633976838;308709;308753;64580.35419645837;65823;69125;69176.30823691799;69679;73928;75406;75675.62162189154;75685.62157189178;76507.61746191345;16494.917525412537;16565.751513727304;16611.91694041522;16633.50099497017;16686.749698754527;12957.935210323889;13050.021248406407;13096.345182741015;13103.803442948363;13120.540781072617;22952.311430657108;23423.7657623425;23456.179033733737;23467.53590872968;23544.29367118989;7646.044244469442;7769.72805951789;7889.644965976545;7913.327367173854;7938.206179382131;973.6202880876464;992.5880759484733;1020.4846552491093;1038.4288641247283;1111.1610733896018;29981.250468738457;30101;30938.845305773328;31018.22454438612;31111.688883111332;1103.951246316003;1148.2765857509714;1176.264834478451;1198.6703656494533;1206.2762342594374;13766.793498097537;14337.498187563593;14532.346044428026;14858.851411485746;15836.445724399591;395921;399700;399944;400790;401604;58539.70730146407;58690.41309586935;58703;58844;58860;1344.5831792144465;1387.424218949125;1465.6848777512673;1507.2840400809528;1589.9983010703183;29913.551296730133;30514.389712206077;30520.847395762878;31219.531707024868;31515.36969260556;48662.513374867216;48891.26663099985;49032;49056.264156036974;49077.75461122814;289137;291066;291161;293217;293310;35523.64476355203;37337.4399384004;37589.624103758615;37635.81182094072;38131.23737525178;45706.54293457156;46493.76753116213;46643.766781165876;47203.52796471991;47678;38289.4256586157;39413.60586394214;39626;39762.40356394714;40385.39421908733;39451.80274098726;39602.40596390999;39796.80101599474;39840.01199910076;40355;38228.235435291695;38384;38485.80757096309;39067.609323907534;39309.80345098256;33673.66326336705;34225;34348.851323788156;34731.305373891875;35152.29695406129;26676.333091672863;27325.590116148673;27362;27400.314992125357;28016.859915701105 13:35:22 INFO - 2015-12-21 13:35:22,572 DEBUG : BROWSER_OUTPUT: |2;jquery.html;371.5225934673945;374.43460374833995;375.49800796812747;381.4869001193394;382.9578746337904;15898.602534936603;17337.73993390097;17589.560260993483;19651.60696786065;19755.802441975593;13289.667758306046;13693.109947853383;13703.520376786815;18731.719024214646;19037.238510459563;3936.0946982194114;4919.385076865392;5027.421846487664;5028.723420211887;5029.119904016782;4010.13782036862;5332.066888294552;5471.452854714536;5477.589180811435;5479.315085614298;4991.575716064139;5370.167624018264;5386.622936394454;5390.811321603745;5402.756875940572;5093;5107.080725469414;5114.590832733373;5116.795328186868;5133.768980395891;4429.379886815848;4458.77706114693;4637.95362046382;4641.025384669224;4644;14786.18675972821;14792.556223313319;15069;15436.536903892902;15446.382144714198;2147.280660978574;2165.7184566006476;2183.443221978401;2188.2559929624003;2217.0355895185644;11115.666530004031;11219.495122719416;11235.606753763579;11268.661940141808;11286.153538484565;1840.346676929684;1875.6436277107443;1904.561950751321;1920.9615807683767;1926.7976862429384;65767.34232657708;65878;67167;67299;67575;7711.537307761552;7903.091144518339;7979.760607181678;7990;8043.11525732169;9679.564419601134;9683.806323873483;9692.321537492307;9692.612295508101;9692.660756873476;6424.036394540856;6436.452901503425;6458.903116453257;6515;6583.177102862142;6640.236372817188;6656.70044847983;6697.296783837627;6753.088333074972;6758.628275444799;3420.4698271767916;3660.633936606318;3695;3727.5154229950476;3731.141837377364;1266.5187228852976;1278.0797825565764;1281.8525869525124;1292.75437666843;1305.9673508162182;530.5755395683437;562.1511517608441;576.0207647000117;578.2136294639287;585.7920438244447;11287.153463490271;12807.61577152687;13112;13223.140495867738;13569.457221711022;555.7304707216997;557.1336571631152;557.3033707865169;563.6420872745836;578.7048605211372;7114.39527640146;7315.890261646079;7358.080239970004;7380.483366164317;7516.473846830779;651617;665028;670877;670894;673019;82854;83089;83539;84539;87052;619.4672581579838;620.9068639703991;626.4518546272012;627.0093252660785;628.905664150372;12414.937925310316;12460.563880264148;12561.246325220516;12626.873731262569;12643.304618246084;38996.41505377365;39065.414018789175;39384;40157.397639036026;40430.595694042684;47854.28218576655;48388.51611483981;49582.752086239336;50048.74975625099;52771.20843187432;7454.776356709307;7494.100707914867;7604;7605.315521603082;7614.466987311057;13402.93298533501;13521.391537381038;13579.592612221253;13848.30758462093;13952.302384880511;11392.601258955896;11772.352520611448;11870;11981.76036479283;12052.578159764716;11843.407829608657;11949.321628092648;12108.878911210775;12266;12295.07786915967;12295.385230738248;12349.814752778535;12606.49574017029;12645.87354126447;12813.551525696555;7754.883676744967;7817.374610031297;7823.530588164728;7849.17583653731;7849.568273744999;11714.707132321419;11877.578336479723;12067.75864482688;12284.078694098158;12432.937835310766 13:35:22 INFO - 2015-12-21 13:35:22,573 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1578.6526964067907;1583.2242201321349;1782.1178516634263;1787.5888545634512;1843.106093544631;7076.433885289177;7201.495895287332;7916.089649690281;8233.588320583976;8251.339892808575;7683.538987660744;7827.530348179099;7902.051753789539;7914.327282181007;7917.406194535419;2361.03197688948;2419.250032489925;2426.708794944609;2431.8176136789725;2432.720237172721;2207.260567709819;2253.9323820285335;2414.2998530426157;2420.8910599022997;2482.6648402465617;2352.3178278299356;2354.682117914085;2356.387339291788;2358.1157066100213;2362.031567057507;2419.443527988564;2428.76926691964;2432.70807503099;2437.427204606916;2441.157800558813;2379.274321331993;2414.794742446894;2418.7823095921276;2420.673209116764;2422.2612103308484;7932.365410767125;8010.759677209693;8032.1566235545015;8036.517808931483;8043.477173983672;5019.447860735316;5045.47022562633;5084.51697088776;5090.211017292326;5100.642954993152;3229.9838500807346;3288.046466524705;3356.748243881694;3383.9830800846084;3403.4724617684296;1038.3250886923486;1057.7038429239826;1067.1302888146136;1082.2802836113974;1085.5386460754148;10831.187660925383;10834.079103276232;10842.620508282173;10855.45722713869;10863.782724345469;4631.490536041033;4647.9767601161775;4690.319903614025;4692.319613656001;4694.43666759991;9307.674231401868;9315.068493150766;9321.114494123047;9340.925793533695;9374.437533747998;5109.846704598868;5119;5127.87180320495;5132.28148059272;5132.666376685504;4656.511066338054;4661.463931647836;4671.532846715301;4680.648951328596;4680.765961701902;3495.9126021849656;3901.3367727486398;3936.7834769087976;3956.841726330972;3982.9402558961638;1239.5661518468644;1269.2004037456343;1304.6086174147717;1314.1589382795019;1315.2963164706837;1235.6972541727334;1248.294713486877;1317.1636011133;1491.4407097338499;1505.533284681752;7726.9613651932505;7776.611169441505;7815.882761758578;7819.413543984224;7858.685652573948;1195.1514424758518;1213.8664746877837;1226.466487078124;1236.8824961628632;1243.6828608704902;6140.263168419819;6177.042558403455;6213.96893015532;6242.188515492957;6254.21822272216;9514.238860891111;9749.220062395134;9780.364276322016;9783.021697830301;9790.902090979142;9497.192738617296;9668.323217374716;9685.515724213761;9689.12797848197;9694.22446204302;7564.546127232384;7613.771586852404;7645.082590089114;7658.310752032233;7674.117476490163;7384.815379615337;7464.664090116066;7489.363404110493;7499.475036747375;7516.173220945582;8417.116202798617;8562.143785621389;8610.65557377698;8611.095834937241;8717.607707653298;8706.1729135732;8872.73381798547;8877.822443551222;8901.910980890108;9012.549372531215;7510.399168066432;7557.05536807899;7569.6593653284635;7578.810529736801;7591.734289299848;6844;7207.702613529615;7808.921910781047;7900.525968441912;7935.246151615587;7088.149422069385;7117.288271172842;7135.179454362815;7191.17301510333;7192.280771922766;7102.680379383043;7182.569045857265;7201.783946481614;7306.817329566803;7309.853802923805;7418.629068546659;7482.70069197226;7495.250474952461;7499.062617172854;7509.51188172767;6695.09616201826;6752.561083529555;6808.62552559614;6825.8634827302185;6835.829104272234;6981.616011119438;7023.613701246481;7024.911138773498;7027.578345299298;7032.613206273704 13:35:22 INFO - 2015-12-21 13:35:22,574 DEBUG : BROWSER_OUTPUT: |4;prototype.html;363.54738350753314;371.32789650732167;371.8995871114799;372.2833545425056;381.0587848015403;5865.76536938522;5901.439363260483;5993.790217342394;6003.699815009249;6042.486388656964;5806.680632565207;5885.293764748226;5898;5901.616394934326;5916.5266778657715;4617.122746678137;4620.7689615519175;4622.445306563217;4623.79192936317;4625.722456652612;4623.283391074388;4624.121416930772;4626.236670949306;4629.282461218517;4639.373684552576;4520.615747661453;4601.263797792354;4613.976930115345;4656.604188643969;4658.650601204923;4634.698744581591;4644.5123262057505;4646.326282689008;4648.511906249829;4657.9068418631605;3941.980290098531;4118.320477121271;4126.298529250034;4126.91746165078;4131.462909821734;5540.501354878081;5620.297462817148;5655.321361436613;5667.716614169275;5687.687177205252;5332.600054995891;5338.866528336784;5343.786248550054;5345.599080069011;5353.143497040455;6219.315875253718;6226.906596401058;6227.937720622781;6233.438990490832;6233.625982441068;4433.850532957806;4562.8631141065825;4589.151007063704;4596.471405788344;4602.125596136723;6519.152510173647;6523.315051919528;6526.673666316665;6528.771492997722;6529.477641788693;4722.95277047232;4729.881752956204;4731.408573928259;4735.431748190239;4737.526247375304;4770.332153498513;4771.06964141989;4772.284157376422;4775.403074615673;4778.235482322812;5187.299714538564;5286.656367336111;5289.100852855024;5290.761915713802;5293.57651387888;6252;6295.811125666146;6296.748130074746;6300.653464059429;6303.212098487689;1667.474745455202;1686.9578260543585;1687.687777761118;1688.611619327537;1688.6960347137633;1127.7575321305958;1134.659602119361;1136.846525719034;1150.3443037468562;1166.5567084507832;6081.787137450168;6089.360617135226;6092.817215483543;6093.908591371133;6095.7866474674065;6373.298937116913;6419.101325814389;6431.517636177306;6441.32366101562;6445.129907462527;5950.494207992283;6038.698065096815;6048.848778780566;6051.0923361496125;6052.273727152769;3554;3605.2969670914445;3607.621199773986;3617.8914632561064;3620.420732682758;6511.251206111263;6606.372394622503;6632.1709786276715;6714.597124172566;6726.192856857208;6621.801345959629;6641.933580664228;6650.301718319603;6650.70071846768;6654.367835055662;6767;6776.830579235559;6780.7287708492095;6785.287544807823;6786.558873673294;6442.162518872517;6584.901226481702;6585.901211481927;6587.275399705933;6598.967005164944;6400.359964003563;6419.358064193543;6472.546921715623;6479.773207937699;6480.513961453004;6702.597844129173;6721.462283017445;6725;6726.831829204309;6777.423918966943;4503.527129651339;4588.655850811269;4591.3572099906705;4591.862244132682;4596.724196548218;6489.156409666713;6492.642904640101;6504.60972341661;6507.316731743099;6528.67356632176;6398.5201109916015;6408.29508754046;6408.455281300955;6422.967885160544;6427.485801135991;6271.153394291712;6355.078513615407;6376.808695739135;6378.936210637834;6405.455536279468;6205.968970155121;6223.159873417031;6250.562460627894;6267.937320626917;6269.96865015672;6366.4588509977175;6384.712687928962;6387.808365749035;6397.136386587751;6399.04014397844;6091.634501929899;6264.342244064308;6267.435930766253;6281;6309.589876658186 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: |5;yui.html;876.5179151466692;985.261054209343;988.649029594494;989.7179303898386;1001.0990108901992;13946.860531394683;15569.532914012569;15575;15575.221238938064;15589.376424942988;14474.710505789877;14550.417983280682;14552.272386380691;14576.562703118923;14579.854201457982;796.0288448093316;804.2962407893093;911.0752586125088;927.3925578745915;934.5140526925994;929.4748467116058;945.5744914788373;946.0870260198907;951.9333646644737;953.8950715421333;828.5318794880903;976.1605019683067;1000.7798284377461;1002.0830939690175;1002.578916854919;823.0987069159291;965.7971825916566;986.6448078691665;989.4953573677441;992.3648864726581;723.6526467295674;731.042334541752;731.5025782467919;736.4476642518111;736.9189389167187;13368.465261389532;13427.932860335733;13431.12697674648;13435.26106064166;13450.86549134506;13509.662258443617;14021.369038393195;14090.154590724589;14103.78844317336;14640.780388294184;11473;11533.308001519936;11690.941545292304;11790.764184716343;11824.645260642195;3691.5570131584113;3743.4384842273603;3796.8291426885858;3809.3524100902914;3814.1608846053823;50805.22181723622;51172;53182;53195.46804531944;53463.19805202925;8847.867281990775;8922.73231803047;8998.955005224932;9156.954215228881;11352.489137988645;13961.092528985584;14046.578602641937;14064.43742250319;14068.507602234078;14075.225862577656;7660.961695191489;7857.724979625686;7933.682652693943;7938.841223175504;8071.192880711882;4813.614910807127;4868;4889.755512224375;4960.70235785854;5001.924971125436;3111.891083812101;3137.419577378192;3144;3145.4495463293833;3145.7640676949322;493.12716491809294;496.8335607571504;499.47555067179314;501.13804256679185;503.9521245481673;606.75123199488;657.5288041319012;682.0689758479739;696.1194089476816;706.3642721550646;9297.814043719087;9710.854337184948;9728.708138755848;9736.368934069911;9780.021997800162;547.2023906708419;555.2615022020702;653.6764301670703;693.2028167607292;701.5650296816007;4287.142571485653;4379.1241751649795;4494.977525112419;4505.684602077823;4521.965752027961;66510.66744666245;67715.66142169356;67756;67823.32176678268;67836;58106.70946645324;59186.11220831691;59670.70164649149;60069.098963515586;60120;52255.47744522582;53592;53830.461695383325;54160.187597186865;54784.452155477935;7893.1317555070045;8135.959320203361;8247.463914845515;8326.83346333082;8508.787280318042;39896.40155397613;40360.79819601001;40641.577544786975;41237.79381103075;41287.793561032006;52947.73526132345;53462.73268633632;53594;54096;55017.1747423781;12740.235585864877;12990.090693651353;13208;13342.399592018577;13502.797458037941;13288.800667989795;13437.260950647807;13632.182069075887;13869.653258668613;13871.098378605762;12584.18202816814;12898;13230.338483076;13353.66615834612;13563.525276615666;12955.805662914876;12957.352132393155;13065.804012939623;13388.19670819754;13667.453301867814;11818.468168932246;12127;12416.130870839315;12686.619401417973;12939.935300323437;10165.796684066425;10536.53703244984;10679.145668346358;10960.013598776148;11049.558017679525;9885.950570247103;10071.244656650633;10709.464526773474;10974.451277436256;11099 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: __start_cc_report 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,709 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: __end_cc_report 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: __startTimestamp1450733722567__endTimestamp 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: Number of tests: 6 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5559.50 Median:5724.60 stddev:606.11 (10.6%) stddev-sans-first:579.80 13:35:22 INFO - 2015-12-21 13:35:22,575 DEBUG : BROWSER_OUTPUT: Values: 3231.6 3354.8 3359.8 3384.4 3418.0 5482.8 5502.3 5514.4 5522.9 5540.2 5474.3 5486.5 5595.5 5597.2 5601.9 5011.6 5053.3 5088.1 5161.2 5175.6 5083.1 5112.1 5115.7 5124.0 5130.4 5071.1 5083.7 5130.7 5158.7 5160.3 5181.3 5202.5 5222.7 5226.9 5256.2 4943.6 5019.0 5140.5 5146.5 5197.5 5346.3 5409.0 5413.1 5435.4 5436.7 5947.1 5974.4 5980.4 5981.8 5982.5 5884.0 5967.4 5985.9 6021.2 6075.0 5608.8 5617.6 5620.5 5627.9 5686.5 6054.3 6083.4 6085.8 6089.3 6095.3 5724.6 5817.3 5868.4 5921.2 5970.4 5985.0 6026.8 6028.8 6031.1 6032.5 5982.6 5988.4 5997.7 5997.9 5998.0 5860.2 5915.6 5944.2 5997.4 6005.8 4601.5 4605.4 4610.6 4612.6 4626.8 4126.0 4127.6 4133.3 4135.8 4156.6 5500.3 5573.1 5612.6 5648.8 5665.9 6040.1 6047.5 6054.4 6056.5 6063.2 5682.0 5691.5 5696.4 5696.5 5699.5 5129.3 5149.9 5210.8 5232.0 5237.9 6156.5 6180.8 6189.0 6192.4 6193.9 5864.6 5979.1 5981.8 5983.1 5995.4 6101.8 6141.8 6170.2 6185.5 6194.3 5946.3 5956.5 5962.7 5986.6 5994.8 5798.4 5886.9 5978.3 5985.7 5987.6 5808.1 5906.3 5906.3 5913.6 5928.5 5869.3 5871.2 5872.5 5878.5 5879.2 5648.7 5722.8 5753.1 5782.0 5786.2 5761.1 5811.3 5812.7 5825.2 5914.3 5684.7 5693.7 5694.4 5698.3 5711.7 13:35:22 INFO - 2015-12-21 13:35:22,576 DEBUG : BROWSER_OUTPUT: 13:35:22 INFO - 2015-12-21 13:35:22,576 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:52265.43 Median:19076.28 stddev:88116.17 (461.9%) stddev-sans-first:88277.86 13:35:22 INFO - 2015-12-21 13:35:22,577 DEBUG : BROWSER_OUTPUT: Values: 564.7 593.0 596.2 613.4 650.6 34701.1 43372.8 43831.0 43863.8 43962.1 47647.3 48323.3 48365.8 48374.8 48449.3 8371.4 8491.5 9174.4 9319.9 9367.4 10583.9 10874.0 10875.5 10882.8 10898.9 10444.3 10515.1 10523.4 10525.4 10526.5 10609.2 10689.0 10690.2 10703.3 10712.0 10381.7 10474.8 10493.3 10688.9 10776.0 44639.8 44777.6 44975.3 45129.8 45166.0 3702.7 3772.7 3819.9 3877.1 4328.0 33105.0 33148.3 33604.7 33857.0 33891.0 3374.8 3390.8 3400.0 3419.3 3451.4 305369.0 305891.0 307320.5 308709.0 308753.0 64580.4 65823.0 69125.0 69176.3 69679.0 73928.0 75406.0 75675.6 75685.6 76507.6 16494.9 16565.8 16611.9 16633.5 16686.7 12957.9 13050.0 13096.3 13103.8 13120.5 22952.3 23423.8 23456.2 23467.5 23544.3 7646.0 7769.7 7889.6 7913.3 7938.2 973.6 992.6 1020.5 1038.4 1111.2 29981.3 30101.0 30938.8 31018.2 31111.7 1104.0 1148.3 1176.3 1198.7 1206.3 13766.8 14337.5 14532.3 14858.9 15836.4 395921.0 399700.0 399944.0 400790.0 401604.0 58539.7 58690.4 58703.0 58844.0 58860.0 1344.6 1387.4 1465.7 1507.3 1590.0 29913.6 30514.4 30520.8 31219.5 31515.4 48662.5 48891.3 49032.0 49056.3 49077.8 289137.0 291066.0 291161.0 293217.0 293310.0 35523.6 37337.4 37589.6 37635.8 38131.2 45706.5 46493.8 46643.8 47203.5 47678.0 38289.4 39413.6 39626.0 39762.4 40385.4 39451.8 39602.4 39796.8 39840.0 40355.0 38228.2 38384.0 38485.8 39067.6 39309.8 33673.7 34225.0 34348.9 34731.3 35152.3 26676.3 27325.6 27362.0 27400.3 28016.9 13:35:22 INFO - 2015-12-21 13:35:22,577 DEBUG : BROWSER_OUTPUT: 13:35:22 INFO - 2015-12-21 13:35:22,577 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31770.60 Median:4642.51 stddev:109065.84 (2349.3%) stddev-sans-first:109346.31 13:35:22 INFO - 2015-12-21 13:35:22,577 DEBUG : BROWSER_OUTPUT: Values: 371.5 374.4 375.5 381.5 383.0 15898.6 17337.7 17589.6 19651.6 19755.8 13289.7 13693.1 13703.5 18731.7 19037.2 3936.1 4919.4 5027.4 5028.7 5029.1 4010.1 5332.1 5471.5 5477.6 5479.3 4991.6 5370.2 5386.6 5390.8 5402.8 5093.0 5107.1 5114.6 5116.8 5133.8 4429.4 4458.8 4638.0 4641.0 4644.0 14786.2 14792.6 15069.0 15436.5 15446.4 2147.3 2165.7 2183.4 2188.3 2217.0 11115.7 11219.5 11235.6 11268.7 11286.2 1840.3 1875.6 1904.6 1921.0 1926.8 65767.3 65878.0 67167.0 67299.0 67575.0 7711.5 7903.1 7979.8 7990.0 8043.1 9679.6 9683.8 9692.3 9692.6 9692.7 6424.0 6436.5 6458.9 6515.0 6583.2 6640.2 6656.7 6697.3 6753.1 6758.6 3420.5 3660.6 3695.0 3727.5 3731.1 1266.5 1278.1 1281.9 1292.8 1306.0 530.6 562.2 576.0 578.2 585.8 11287.2 12807.6 13112.0 13223.1 13569.5 555.7 557.1 557.3 563.6 578.7 7114.4 7315.9 7358.1 7380.5 7516.5 651617.0 665028.0 670877.0 670894.0 673019.0 82854.0 83089.0 83539.0 84539.0 87052.0 619.5 620.9 626.5 627.0 628.9 12414.9 12460.6 12561.2 12626.9 12643.3 38996.4 39065.4 39384.0 40157.4 40430.6 47854.3 48388.5 49582.8 50048.7 52771.2 7454.8 7494.1 7604.0 7605.3 7614.5 13402.9 13521.4 13579.6 13848.3 13952.3 11392.6 11772.4 11870.0 11981.8 12052.6 11843.4 11949.3 12108.9 12266.0 12295.1 12295.4 12349.8 12606.5 12645.9 12813.6 7754.9 7817.4 7823.5 7849.2 7849.6 11714.7 11877.6 12067.8 12284.1 12432.9 13:35:22 INFO - 2015-12-21 13:35:22,577 DEBUG : BROWSER_OUTPUT: 13:35:22 INFO - 2015-12-21 13:35:22,578 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5716.70 Median:6723.83 stddev:2873.38 (42.7%) stddev-sans-first:2864.60 13:35:22 INFO - 2015-12-21 13:35:22,578 DEBUG : BROWSER_OUTPUT: Values: 1578.7 1583.2 1782.1 1787.6 1843.1 7076.4 7201.5 7916.1 8233.6 8251.3 7683.5 7827.5 7902.1 7914.3 7917.4 2361.0 2419.3 2426.7 2431.8 2432.7 2207.3 2253.9 2414.3 2420.9 2482.7 2352.3 2354.7 2356.4 2358.1 2362.0 2419.4 2428.8 2432.7 2437.4 2441.2 2379.3 2414.8 2418.8 2420.7 2422.3 7932.4 8010.8 8032.2 8036.5 8043.5 5019.4 5045.5 5084.5 5090.2 5100.6 3230.0 3288.0 3356.7 3384.0 3403.5 1038.3 1057.7 1067.1 1082.3 1085.5 10831.2 10834.1 10842.6 10855.5 10863.8 4631.5 4648.0 4690.3 4692.3 4694.4 9307.7 9315.1 9321.1 9340.9 9374.4 5109.8 5119.0 5127.9 5132.3 5132.7 4656.5 4661.5 4671.5 4680.6 4680.8 3495.9 3901.3 3936.8 3956.8 3982.9 1239.6 1269.2 1304.6 1314.2 1315.3 1235.7 1248.3 1317.2 1491.4 1505.5 7727.0 7776.6 7815.9 7819.4 7858.7 1195.2 1213.9 1226.5 1236.9 1243.7 6140.3 6177.0 6214.0 6242.2 6254.2 9514.2 9749.2 9780.4 9783.0 9790.9 9497.2 9668.3 9685.5 9689.1 9694.2 7564.5 7613.8 7645.1 7658.3 7674.1 7384.8 7464.7 7489.4 7499.5 7516.2 8417.1 8562.1 8610.7 8611.1 8717.6 8706.2 8872.7 8877.8 8901.9 9012.5 7510.4 7557.1 7569.7 7578.8 7591.7 6844.0 7207.7 7808.9 7900.5 7935.2 7088.1 7117.3 7135.2 7191.2 7192.3 7102.7 7182.6 7201.8 7306.8 7309.9 7418.6 7482.7 7495.3 7499.1 7509.5 6695.1 6752.6 6808.6 6825.9 6835.8 6981.6 7023.6 7024.9 7027.6 7032.6 13:35:22 INFO - 2015-12-21 13:35:22,578 DEBUG : BROWSER_OUTPUT: 13:35:22 INFO - 2015-12-21 13:35:22,579 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5323.81 Median:6040.59 stddev:1555.96 (25.8%) stddev-sans-first:1515.13 13:35:22 INFO - 2015-12-21 13:35:22,579 DEBUG : BROWSER_OUTPUT: Values: 363.5 371.3 371.9 372.3 381.1 5865.8 5901.4 5993.8 6003.7 6042.5 5806.7 5885.3 5898.0 5901.6 5916.5 4617.1 4620.8 4622.4 4623.8 4625.7 4623.3 4624.1 4626.2 4629.3 4639.4 4520.6 4601.3 4614.0 4656.6 4658.7 4634.7 4644.5 4646.3 4648.5 4657.9 3942.0 4118.3 4126.3 4126.9 4131.5 5540.5 5620.3 5655.3 5667.7 5687.7 5332.6 5338.9 5343.8 5345.6 5353.1 6219.3 6226.9 6227.9 6233.4 6233.6 4433.9 4562.9 4589.2 4596.5 4602.1 6519.2 6523.3 6526.7 6528.8 6529.5 4723.0 4729.9 4731.4 4735.4 4737.5 4770.3 4771.1 4772.3 4775.4 4778.2 5187.3 5286.7 5289.1 5290.8 5293.6 6252.0 6295.8 6296.7 6300.7 6303.2 1667.5 1687.0 1687.7 1688.6 1688.7 1127.8 1134.7 1136.8 1150.3 1166.6 6081.8 6089.4 6092.8 6093.9 6095.8 6373.3 6419.1 6431.5 6441.3 6445.1 5950.5 6038.7 6048.8 6051.1 6052.3 3554.0 3605.3 3607.6 3617.9 3620.4 6511.3 6606.4 6632.2 6714.6 6726.2 6621.8 6641.9 6650.3 6650.7 6654.4 6767.0 6776.8 6780.7 6785.3 6786.6 6442.2 6584.9 6585.9 6587.3 6599.0 6400.4 6419.4 6472.5 6479.8 6480.5 6702.6 6721.5 6725.0 6726.8 6777.4 4503.5 4588.7 4591.4 4591.9 4596.7 6489.2 6492.6 6504.6 6507.3 6528.7 6398.5 6408.3 6408.5 6423.0 6427.5 6271.2 6355.1 6376.8 6378.9 6405.5 6206.0 6223.2 6250.6 6267.9 6270.0 6366.5 6384.7 6387.8 6397.1 6399.0 6091.6 6264.3 6267.4 6281.0 6309.6 13:35:22 INFO - 2015-12-21 13:35:22,579 DEBUG : BROWSER_OUTPUT: 13:35:22 INFO - 2015-12-21 13:35:22,579 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15444.30 Median:4878.88 stddev:18549.27 (380.2%) stddev-sans-first:18569.05 13:35:22 INFO - 2015-12-21 13:35:22,579 DEBUG : BROWSER_OUTPUT: Values: 876.5 985.3 988.6 989.7 1001.1 13946.9 15569.5 15575.0 15575.2 15589.4 14474.7 14550.4 14552.3 14576.6 14579.9 796.0 804.3 911.1 927.4 934.5 929.5 945.6 946.1 951.9 953.9 828.5 976.2 1000.8 1002.1 1002.6 823.1 965.8 986.6 989.5 992.4 723.7 731.0 731.5 736.4 736.9 13368.5 13427.9 13431.1 13435.3 13450.9 13509.7 14021.4 14090.2 14103.8 14640.8 11473.0 11533.3 11690.9 11790.8 11824.6 3691.6 3743.4 3796.8 3809.4 3814.2 50805.2 51172.0 53182.0 53195.5 53463.2 8847.9 8922.7 8999.0 9157.0 11352.5 13961.1 14046.6 14064.4 14068.5 14075.2 7661.0 7857.7 7933.7 7938.8 8071.2 4813.6 4868.0 4889.8 4960.7 5001.9 3111.9 3137.4 3144.0 3145.4 3145.8 493.1 496.8 499.5 501.1 504.0 606.8 657.5 682.1 696.1 706.4 9297.8 9710.9 9728.7 9736.4 9780.0 547.2 555.3 653.7 693.2 701.6 4287.1 4379.1 4495.0 4505.7 4522.0 66510.7 67715.7 67756.0 67823.3 67836.0 58106.7 59186.1 59670.7 60069.1 60120.0 52255.5 53592.0 53830.5 54160.2 54784.5 7893.1 8136.0 8247.5 8326.8 8508.8 39896.4 40360.8 40641.6 41237.8 41287.8 52947.7 53462.7 53594.0 54096.0 55017.2 12740.2 12990.1 13208.0 13342.4 13502.8 13288.8 13437.3 13632.2 13869.7 13871.1 12584.2 12898.0 13230.3 13353.7 13563.5 12955.8 12957.4 13065.8 13388.2 13667.5 11818.5 12127.0 12416.1 12686.6 12939.9 10165.8 10536.5 10679.1 10960.0 11049.6 9886.0 10071.2 10709.5 10974.5 11099.0 13:35:22 INFO - 2015-12-21 13:35:22,579 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:35:22 INFO - 2015-12-21 13:35:22,579 DEBUG : BROWSER_OUTPUT: 13:35:23 INFO - 2015-12-21 13:35:23,190 INFO : Browser exited with error code: 0 13:35:23 INFO - 2015-12-21 13:35:23,212 INFO : Completed test dromaeo_css (00:18:29) 13:35:23 INFO - 2015-12-21 13:35:23,212 INFO : Starting test kraken 13:35:23 INFO - 2015-12-21 13:35:23,212 DEBUG : operating with platform_type : linux_ 13:35:23 INFO - 2015-12-21 13:35:23,212 INFO : Initialising browser for kraken test... 13:35:23 INFO - 2015-12-21 13:35:23,218 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4qhFVf/profile http://localhost:47853/getInfo.html 13:35:26 INFO - 2015-12-21 13:35:26,744 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:35:26 INFO - 2015-12-21 13:35:26,744 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:35:26 INFO - 2015-12-21 13:35:26,744 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:35:26 INFO - 2015-12-21 13:35:26,744 DEBUG : BROWSER_OUTPUT: __metrics 13:35:26 INFO - 2015-12-21 13:35:26,821 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:35:28 INFO - 2015-12-21 13:35:28,006 INFO : Browser initialized. 13:35:28 INFO - 2015-12-21 13:35:28,006 INFO : Running cycle 1/1 for kraken test... 13:35:28 INFO - 2015-12-21 13:35:28,007 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4qhFVf/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:35:39 INFO - 2015-12-21 13:35:39,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 128950272 13:35:39 INFO - 2015-12-21 13:35:39,521 DEBUG : BROWSER_OUTPUT: 13:35:41 INFO - 2015-12-21 13:35:41,944 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47853/tests/kraken/driver.html?testName=audio-beat-detection) 13:35:42 INFO - 2015-12-21 13:35:42,513 DEBUG : BROWSER_OUTPUT: RSS: Main: 163745792 13:35:42 INFO - 2015-12-21 13:35:42,513 DEBUG : BROWSER_OUTPUT: 13:35:45 INFO - 2015-12-21 13:35:45,098 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47853/tests/kraken/driver.html?testName=audio-dft) 13:35:45 INFO - 2015-12-21 13:35:45,664 DEBUG : BROWSER_OUTPUT: RSS: Main: 160284672 13:35:45 INFO - 2015-12-21 13:35:45,664 DEBUG : BROWSER_OUTPUT: 13:35:48 INFO - 2015-12-21 13:35:48,443 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47853/tests/kraken/driver.html?testName=audio-fft) 13:35:49 INFO - 2015-12-21 13:35:49,009 DEBUG : BROWSER_OUTPUT: RSS: Main: 158044160 13:35:49 INFO - 2015-12-21 13:35:49,009 DEBUG : BROWSER_OUTPUT: 13:35:50 INFO - 2015-12-21 13:35:50,973 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47853/tests/kraken/driver.html?testName=audio-oscillator) 13:35:51 INFO - 2015-12-21 13:35:51,555 DEBUG : BROWSER_OUTPUT: RSS: Main: 156078080 13:35:51 INFO - 2015-12-21 13:35:51,555 DEBUG : BROWSER_OUTPUT: 13:35:53 INFO - 2015-12-21 13:35:53,693 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47853/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:35:54 INFO - 2015-12-21 13:35:54,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 156381184 13:35:54 INFO - 2015-12-21 13:35:54,255 DEBUG : BROWSER_OUTPUT: 13:35:57 INFO - 2015-12-21 13:35:57,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47853/tests/kraken/driver.html?testName=imaging-darkroom) 13:35:57 INFO - 2015-12-21 13:35:57,688 DEBUG : BROWSER_OUTPUT: RSS: Main: 154267648 13:35:57 INFO - 2015-12-21 13:35:57,688 DEBUG : BROWSER_OUTPUT: 13:36:01 INFO - 2015-12-21 13:36:01,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47853/tests/kraken/driver.html?testName=imaging-desaturate) 13:36:01 INFO - 2015-12-21 13:36:01,595 DEBUG : BROWSER_OUTPUT: RSS: Main: 156807168 13:36:01 INFO - 2015-12-21 13:36:01,595 DEBUG : BROWSER_OUTPUT: 13:36:04 INFO - 2015-12-21 13:36:04,357 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47853/tests/kraken/driver.html?testName=json-parse-financial) 13:36:04 INFO - 2015-12-21 13:36:04,927 DEBUG : BROWSER_OUTPUT: RSS: Main: 154427392 13:36:04 INFO - 2015-12-21 13:36:04,927 DEBUG : BROWSER_OUTPUT: 13:36:06 INFO - 2015-12-21 13:36:06,448 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47853/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:36:07 INFO - 2015-12-21 13:36:07,001 DEBUG : BROWSER_OUTPUT: RSS: Main: 159342592 13:36:07 INFO - 2015-12-21 13:36:07,001 DEBUG : BROWSER_OUTPUT: 13:36:08 INFO - 2015-12-21 13:36:08,732 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47853/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:36:09 INFO - 2015-12-21 13:36:09,319 DEBUG : BROWSER_OUTPUT: RSS: Main: 157839360 13:36:09 INFO - 2015-12-21 13:36:09,319 DEBUG : BROWSER_OUTPUT: 13:36:11 INFO - 2015-12-21 13:36:11,316 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47853/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:36:11 INFO - 2015-12-21 13:36:11,890 DEBUG : BROWSER_OUTPUT: RSS: Main: 163471360 13:36:11 INFO - 2015-12-21 13:36:11,890 DEBUG : BROWSER_OUTPUT: 13:36:14 INFO - 2015-12-21 13:36:14,204 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47853/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:36:14 INFO - 2015-12-21 13:36:14,785 DEBUG : BROWSER_OUTPUT: RSS: Main: 165781504 13:36:14 INFO - 2015-12-21 13:36:14,785 DEBUG : BROWSER_OUTPUT: 13:36:17 INFO - 2015-12-21 13:36:17,185 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47853/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:36:17 INFO - 2015-12-21 13:36:17,751 DEBUG : BROWSER_OUTPUT: RSS: Main: 166072320 13:36:17 INFO - 2015-12-21 13:36:17,751 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,368 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47853/tests/kraken/driver.html?testName=ai-astar) 13:36:19 INFO - 2015-12-21 13:36:19,618 DEBUG : BROWSER_OUTPUT: RSS: Main: 223260672 13:36:19 INFO - 2015-12-21 13:36:19,618 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,619 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:36:19 INFO - 2015-12-21 13:36:19,619 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;106;110;113;112;101;109;109;109 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;142;123;162;163;128;148;150;129;173;133 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;161;160;160;161;163;162;160;160 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;72;139;71;73;71;72;164;71 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;90;134;89;90;91;134;89;91;136 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;109;109;109;110;109;110;111 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;144;146;146;145;145;145;145;145 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;96 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;55;55;55;56;55;57;55;53;56 13:36:19 INFO - 2015-12-21 13:36:19,620 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;63;62;63;63;63;62;62;62;63 13:36:19 INFO - 2015-12-21 13:36:19,621 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;79;86;79;79;80;85;78;81;79 13:36:19 INFO - 2015-12-21 13:36:19,621 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;105;107;112;108;108;121;120;112;110;109 13:36:19 INFO - 2015-12-21 13:36:19,621 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;142;138;136;138;131;131;133;135;136 13:36:19 INFO - 2015-12-21 13:36:19,621 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;62;64;62;63;62;65;62;63;62 13:36:19 INFO - 2015-12-21 13:36:19,621 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:36:19 INFO - 2015-12-21 13:36:19,621 DEBUG : BROWSER_OUTPUT: __start_cc_report 13:36:19 INFO - 2015-12-21 13:36:19,621 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,877 13:36:19 INFO - 2015-12-21 13:36:19,621 DEBUG : BROWSER_OUTPUT: __end_cc_report 13:36:19 INFO - 2015-12-21 13:36:19,621 DEBUG : BROWSER_OUTPUT: __startTimestamp1450733779618__endTimestamp 13:36:19 INFO - 2015-12-21 13:36:19,621 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,625 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:36:19 INFO - 2015-12-21 13:36:19,625 DEBUG : BROWSER_OUTPUT: Number of tests: 14 13:36:19 INFO - 2015-12-21 13:36:19,625 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,625 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.80 Median:109.50 stddev:3.33 (3.0%) stddev-sans-first:3.53 13:36:19 INFO - 2015-12-21 13:36:19,625 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 106.0 110.0 113.0 112.0 101.0 109.0 109.0 109.0 13:36:19 INFO - 2015-12-21 13:36:19,625 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,625 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.10 Median:149.00 stddev:17.04 (11.4%) stddev-sans-first:18.04 13:36:19 INFO - 2015-12-21 13:36:19,626 DEBUG : BROWSER_OUTPUT: Values: 142.0 123.0 162.0 163.0 128.0 148.0 150.0 129.0 173.0 133.0 13:36:19 INFO - 2015-12-21 13:36:19,626 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,626 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 13:36:19 INFO - 2015-12-21 13:36:19,626 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 161.0 160.0 160.0 161.0 163.0 162.0 160.0 160.0 13:36:19 INFO - 2015-12-21 13:36:19,626 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,626 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.60 Median:71.50 stddev:34.20 (47.8%) stddev-sans-first:35.74 13:36:19 INFO - 2015-12-21 13:36:19,626 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 72.0 139.0 71.0 73.0 71.0 72.0 164.0 71.0 13:36:19 INFO - 2015-12-21 13:36:19,626 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,627 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.40 Median:90.00 stddev:21.59 (24.0%) stddev-sans-first:22.35 13:36:19 INFO - 2015-12-21 13:36:19,627 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 134.0 89.0 90.0 91.0 134.0 89.0 91.0 136.0 13:36:19 INFO - 2015-12-21 13:36:19,627 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,627 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:109.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 13:36:19 INFO - 2015-12-21 13:36:19,627 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 109.0 109.0 109.0 110.0 109.0 110.0 111.0 13:36:19 INFO - 2015-12-21 13:36:19,627 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,627 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 13:36:19 INFO - 2015-12-21 13:36:19,628 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 144.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 13:36:19 INFO - 2015-12-21 13:36:19,628 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,628 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:36:19 INFO - 2015-12-21 13:36:19,628 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 13:36:19 INFO - 2015-12-21 13:36:19,628 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,628 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.50 stddev:1.06 (1.9%) stddev-sans-first:1.09 13:36:19 INFO - 2015-12-21 13:36:19,628 DEBUG : BROWSER_OUTPUT: Values: 56.0 55.0 55.0 55.0 56.0 55.0 57.0 55.0 53.0 56.0 13:36:19 INFO - 2015-12-21 13:36:19,629 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,629 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:63.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 13:36:19 INFO - 2015-12-21 13:36:19,629 DEBUG : BROWSER_OUTPUT: Values: 62.0 63.0 62.0 63.0 63.0 63.0 62.0 62.0 62.0 63.0 13:36:19 INFO - 2015-12-21 13:36:19,629 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,629 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:79.50 stddev:2.84 (3.6%) stddev-sans-first:2.87 13:36:19 INFO - 2015-12-21 13:36:19,629 DEBUG : BROWSER_OUTPUT: Values: 78.0 79.0 86.0 79.0 79.0 80.0 85.0 78.0 81.0 79.0 13:36:19 INFO - 2015-12-21 13:36:19,629 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,630 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:111.20 Median:111.00 stddev:5.35 (4.8%) stddev-sans-first:5.18 13:36:19 INFO - 2015-12-21 13:36:19,630 DEBUG : BROWSER_OUTPUT: Values: 105.0 107.0 112.0 108.0 108.0 121.0 120.0 112.0 110.0 109.0 13:36:19 INFO - 2015-12-21 13:36:19,630 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,630 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.10 Median:137.00 stddev:3.78 (2.8%) stddev-sans-first:3.57 13:36:19 INFO - 2015-12-21 13:36:19,630 DEBUG : BROWSER_OUTPUT: Values: 141.0 142.0 138.0 136.0 138.0 131.0 131.0 133.0 135.0 136.0 13:36:19 INFO - 2015-12-21 13:36:19,630 DEBUG : BROWSER_OUTPUT: 13:36:19 INFO - 2015-12-21 13:36:19,630 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:1.03 (1.6%) stddev-sans-first:1.09 13:36:19 INFO - 2015-12-21 13:36:19,630 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 64.0 62.0 63.0 62.0 65.0 62.0 63.0 62.0 13:36:19 INFO - 2015-12-21 13:36:19,630 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:36:19 INFO - 2015-12-21 13:36:19,630 DEBUG : BROWSER_OUTPUT: 13:36:20 INFO - 2015-12-21 13:36:20,282 INFO : Browser exited with error code: 0 13:36:20 INFO - 2015-12-21 13:36:20,334 INFO : Completed test kraken (00:00:57) 13:36:20 INFO - 2015-12-21 13:36:20,334 INFO : Starting test v8_7 13:36:20 INFO - 2015-12-21 13:36:20,334 DEBUG : operating with platform_type : linux_ 13:36:20 INFO - 2015-12-21 13:36:20,334 INFO : Initialising browser for v8_7 test... 13:36:20 INFO - 2015-12-21 13:36:20,340 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfSbwiG/profile http://localhost:47853/getInfo.html 13:36:23 INFO - 2015-12-21 13:36:23,979 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:36:23 INFO - 2015-12-21 13:36:23,979 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:36:23 INFO - 2015-12-21 13:36:23,979 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:36:23 INFO - 2015-12-21 13:36:23,979 DEBUG : BROWSER_OUTPUT: __metrics 13:36:24 INFO - 2015-12-21 13:36:24,144 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:36:25 INFO - 2015-12-21 13:36:25,242 INFO : Browser initialized. 13:36:25 INFO - 2015-12-21 13:36:25,243 INFO : Running cycle 1/1 for v8_7 test... 13:36:25 INFO - 2015-12-21 13:36:25,243 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfSbwiG/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:36:36 INFO - 2015-12-21 13:36:36,761 DEBUG : BROWSER_OUTPUT: RSS: Main: 128565248 13:36:36 INFO - 2015-12-21 13:36:36,761 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,083 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47853/tests/v8_7/run.html (next: http://localhost:47853/tests/v8_7/run.html) 13:36:58 INFO - 2015-12-21 13:36:58,333 DEBUG : BROWSER_OUTPUT: RSS: Main: 422105088 13:36:58 INFO - 2015-12-21 13:36:58,334 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,334 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:36:58 INFO - 2015-12-21 13:36:58,334 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:36:58 INFO - 2015-12-21 13:36:58,334 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:36:58 INFO - 2015-12-21 13:36:58,334 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:36:58 INFO - 2015-12-21 13:36:58,334 DEBUG : BROWSER_OUTPUT: |0;Richards;162.73393002441009 13:36:58 INFO - 2015-12-21 13:36:58,335 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;171.7032967032967 13:36:58 INFO - 2015-12-21 13:36:58,335 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.4857462269424 13:36:58 INFO - 2015-12-21 13:36:58,335 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5005 13:36:58 INFO - 2015-12-21 13:36:58,335 DEBUG : BROWSER_OUTPUT: |4;RayTrace;953.2888465204957 13:36:58 INFO - 2015-12-21 13:36:58,335 DEBUG : BROWSER_OUTPUT: |5;Earley;550.9641873278237 13:36:58 INFO - 2015-12-21 13:36:58,335 DEBUG : BROWSER_OUTPUT: |6;Boyer;10536.842105263158 13:36:58 INFO - 2015-12-21 13:36:58,335 DEBUG : BROWSER_OUTPUT: |7;RegExp;23928.571428571428 13:36:58 INFO - 2015-12-21 13:36:58,336 DEBUG : BROWSER_OUTPUT: |8;Splay;534.9706353443673 13:36:58 INFO - 2015-12-21 13:36:58,336 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 13:36:58 INFO - 2015-12-21 13:36:58,336 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:36:58 INFO - 2015-12-21 13:36:58,336 DEBUG : BROWSER_OUTPUT: __startTimestamp1450733818332__endTimestamp 13:36:58 INFO - 2015-12-21 13:36:58,336 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,337 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:36:58 INFO - 2015-12-21 13:36:58,337 DEBUG : BROWSER_OUTPUT: Number of tests: 10 13:36:58 INFO - 2015-12-21 13:36:58,337 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,337 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:162.73 Median:162.73 stddev:0.00 (0.0%) 13:36:58 INFO - 2015-12-21 13:36:58,337 DEBUG : BROWSER_OUTPUT: Values: 162.7 13:36:58 INFO - 2015-12-21 13:36:58,338 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,338 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:171.70 Median:171.70 stddev:0.00 (0.0%) 13:36:58 INFO - 2015-12-21 13:36:58,338 DEBUG : BROWSER_OUTPUT: Values: 171.7 13:36:58 INFO - 2015-12-21 13:36:58,338 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,338 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.49 Median:279.49 stddev:0.00 (0.0%) 13:36:58 INFO - 2015-12-21 13:36:58,338 DEBUG : BROWSER_OUTPUT: Values: 279.5 13:36:58 INFO - 2015-12-21 13:36:58,339 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,339 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5005.00 Median:5005.00 stddev:0.00 (0.0%) 13:36:58 INFO - 2015-12-21 13:36:58,339 DEBUG : BROWSER_OUTPUT: Values: 5005.0 13:36:58 INFO - 2015-12-21 13:36:58,339 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,339 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:953.29 Median:953.29 stddev:0.00 (0.0%) 13:36:58 INFO - 2015-12-21 13:36:58,340 DEBUG : BROWSER_OUTPUT: Values: 953.3 13:36:58 INFO - 2015-12-21 13:36:58,340 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,340 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:550.96 Median:550.96 stddev:0.00 (0.0%) 13:36:58 INFO - 2015-12-21 13:36:58,340 DEBUG : BROWSER_OUTPUT: Values: 551.0 13:36:58 INFO - 2015-12-21 13:36:58,341 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,341 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10536.84 Median:10536.84 stddev:0.00 (0.0%) 13:36:58 INFO - 2015-12-21 13:36:58,341 DEBUG : BROWSER_OUTPUT: Values: 10536.8 13:36:58 INFO - 2015-12-21 13:36:58,341 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,341 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23928.57 Median:23928.57 stddev:0.00 (0.0%) 13:36:58 INFO - 2015-12-21 13:36:58,342 DEBUG : BROWSER_OUTPUT: Values: 23928.6 13:36:58 INFO - 2015-12-21 13:36:58,342 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,342 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:534.97 Median:534.97 stddev:0.00 (0.0%) 13:36:58 INFO - 2015-12-21 13:36:58,342 DEBUG : BROWSER_OUTPUT: Values: 535.0 13:36:58 INFO - 2015-12-21 13:36:58,342 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,342 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 13:36:58 INFO - 2015-12-21 13:36:58,342 DEBUG : BROWSER_OUTPUT: Values: 4830.9 13:36:58 INFO - 2015-12-21 13:36:58,342 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:36:58 INFO - 2015-12-21 13:36:58,342 DEBUG : BROWSER_OUTPUT: 13:36:58 INFO - 2015-12-21 13:36:58,990 INFO : Browser exited with error code: 0 13:36:59 INFO - 2015-12-21 13:36:59,004 INFO : Completed test v8_7 (00:00:38) 13:36:59 INFO - 2015-12-21 13:36:59,416 INFO : Completed test suite (00:20:05) 13:36:59 INFO - 2015-12-21 13:36:59,417 DEBUG : Working with test: dromaeo_css 13:36:59 INFO - 2015-12-21 13:36:59,417 DEBUG : Generating results file: dromaeo_css 13:36:59 INFO - 2015-12-21 13:36:59,418 DEBUG : Working with test: kraken 13:36:59 INFO - 2015-12-21 13:36:59,418 DEBUG : Generating results file: kraken 13:36:59 INFO - 2015-12-21 13:36:59,419 INFO : javascript benchmark 13:36:59 INFO - 2015-12-21 13:36:59,419 DEBUG : Working with test: v8_7 13:36:59 INFO - 2015-12-21 13:36:59,419 DEBUG : Generating results file: v8_7 13:36:59 INFO - 2015-12-21 13:36:59,419 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:36:59 INFO - 2015-12-21 13:36:59,468 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 13:36:59 INFO - 2015-12-21 13:36:59,468 DEBUG : process_Request line: dromaeo_css 7185.85 graph.html#tests=[[72,53,35]] 13:36:59 INFO - 2015-12-21 13:36:59,469 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:36:59 INFO - 2015-12-21 13:36:59,572 DEBUG : process_Request line: kraken 1463.90 graph.html#tests=[[232,53,35]] 13:36:59 INFO - 2015-12-21 13:36:59,572 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:36:59 INFO - 2015-12-21 13:36:59,605 DEBUG : process_Request line: v8_7 23266.11 graph.html#tests=[[230,53,35]] 13:36:59 INFO - 2015-12-21 13:36:59,606 INFO : javascript benchmark 13:36:59 INFO - 2015-12-21 13:36:59,610 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3231.5798946137, 3354.781939173955, 3359.7648164628454, 3384.3569721752865, 3417.999999999999, 5482.80810171644, 5502.284702988616, 5514.420985796493, 5522.91715624265, 5540.168974653799, 5474.343078830536, 5486.451354864512, 5595.468430499106, 5597.216389705444, 5601.859953501154, 5011.649184557083, 5053.292539044527, 5088.058709138802, 5161.225816127573, 5175.560077393426, 5083.110455670261, 5112.054269960069, 5115.667481613702, 5124.026434977342, 5130.358705161855, 5071.112555302826, 5083.720395378253, 5130.692158470485, 5158.664686795365, 5160.277561141444, 5181.300524429192, 5202.4537423570355, 5222.686638801684, 5226.947730522684, 5256.237845512417, 4943.579795717368, 5018.974905125488, 5140.485951404829, 5146.511081447256, 5197.454267301917, 5346.251524786533, 5409.0, 5413.052715774724, 5435.374931882805, 5436.728163591844, 5947.10793380992, 5974.402559744034, 5980.401959804029, 5981.760729570856, 5982.491488223506, 5883.970580147072, 5967.373425790273, 5985.850353741148, 6021.2473440819895, 6075.0, 5608.775648974036, 5617.550595952314, 5620.494155526022, 5627.859303517363, 5686.488216060575, 6054.334023257438, 6083.422074902877, 6085.817425477154, 6089.2997305310455, 6095.299040610298, 5724.599278050497, 5817.301923769175, 5868.4424979626865, 5921.171036054956, 5970.432808883149, 5985.0, 6026.819195424145, 6028.849278768066, 6031.125486804476, 6032.4570788629235, 5982.551308651869, 5988.431099045583, 5997.670128142907, 5997.880042399128, 5998.0, 5860.208871802337, 5915.615484993462, 5944.167816505693, 5997.400259973968, 6005.759769609167, 4601.539846015372, 4605.424321959755, 4610.631149508032, 4612.584867361954, 4626.768661566908, 4126.0, 4127.628513433805, 4133.256013917464, 4135.793210339471, 4156.625903668684, 5500.257465242221, 5573.136163894603, 5612.550995920316, 5648.774049037993, 5665.886682266331, 6040.09398590215, 6047.455728984412, 6054.364291749391, 6056.454919057218, 6063.151158837766, 5682.0, 5691.459311365414, 5696.430356964271, 5696.515796157373, 5699.544036477155, 5129.281900533928, 5149.948500514947, 5210.7915683373, 5232.0, 5237.895242095213, 6156.538259630545, 6180.783672571439, 6188.9690551546955, 6192.411720886508, 6193.907091393633, 5864.560157988253, 5979.073243647242, 5981.820545383645, 5983.132445795422, 5995.400459953969, 6101.84745381369, 6141.84645383869, 6170.197874276315, 6185.505159587312, 6194.318624951342, 5946.286445626552, 5956.523478121827, 5962.70186490665, 5986.610870293417, 5994.790182343597, 5798.391168927202, 5886.882262354815, 5978.252718410199, 5985.700714964321, 5987.64074155552, 5808.099744539603, 5906.26171728534, 5906.320773111147, 5913.586048976703, 5928.525717942468, 5869.295684517885, 5871.178035075008, 5872.500837428866, 5878.500327472213, 5879.176915231957, 5648.745806438802, 5722.7710891563875, 5753.05074662676, 5782.045962416154, 5786.161006654096, 5761.107028410603, 5811.331696854916, 5812.680302583399, 5825.184474173534, 5914.34942156371, 5684.687342196219, 5693.686847223443, 5694.373618902, 5698.316202055614, 5711.714414279187], "unit": "ms", "name": "dojo.html", "value": 5520.673903721406}, {"lowerIsBetter": false, "replicates": [564.6799573046864, 592.9822105336839, 596.1594152245334, 613.3590398034054, 650.6226388694557, 34701.132471688186, 43372.78313608431, 43831.0, 43863.78068109655, 43962.12075758483, 47647.28529072058, 48323.27515087277, 48365.7581712091, 48374.75812620932, 48449.273260901115, 8371.372147088961, 8491.532965686885, 9174.449533028006, 9319.906800932005, 9367.437953722765, 10583.894161058368, 10874.0, 10875.510602022889, 10882.782344353109, 10898.891011089867, 10444.26890117692, 10515.106215971651, 10523.368597884151, 10525.421101839396, 10526.526306316235, 10609.204309676767, 10689.037986581166, 10690.198235132357, 10703.304285221435, 10711.982361675668, 10381.740456488573, 10474.842877356845, 10493.265471417004, 10688.893111068868, 10776.0, 44639.77680111611, 44777.55222447799, 44975.32537011948, 45129.774351128035, 45166.0, 3702.7037836973245, 3772.660460558563, 3819.9427008594894, 3877.0888841122314, 4328.04782947751, 33105.0, 33148.33703325944, 33604.663953360396, 33857.0, 33891.0, 3374.8481318340732, 3390.8135052572097, 3399.9730714951843, 3419.3161367726307, 3451.430513965193, 305369.0, 305891.0, 307320.4633976838, 308709.0, 308753.0, 64580.35419645837, 65823.0, 69125.0, 69176.30823691799, 69679.0, 73928.0, 75406.0, 75675.62162189154, 75685.62157189178, 76507.61746191345, 16494.917525412537, 16565.751513727304, 16611.91694041522, 16633.50099497017, 16686.749698754527, 12957.935210323889, 13050.021248406407, 13096.345182741015, 13103.803442948363, 13120.540781072617, 22952.311430657108, 23423.7657623425, 23456.179033733737, 23467.53590872968, 23544.29367118989, 7646.044244469442, 7769.72805951789, 7889.644965976545, 7913.327367173854, 7938.206179382131, 973.6202880876464, 992.5880759484733, 1020.4846552491093, 1038.4288641247283, 1111.1610733896018, 29981.250468738457, 30101.0, 30938.845305773328, 31018.22454438612, 31111.688883111332, 1103.951246316003, 1148.2765857509714, 1176.264834478451, 1198.6703656494533, 1206.2762342594374, 13766.793498097537, 14337.498187563593, 14532.346044428026, 14858.851411485746, 15836.445724399591, 395921.0, 399700.0, 399944.0, 400790.0, 401604.0, 58539.70730146407, 58690.41309586935, 58703.0, 58844.0, 58860.0, 1344.5831792144465, 1387.424218949125, 1465.6848777512673, 1507.2840400809528, 1589.9983010703183, 29913.551296730133, 30514.389712206077, 30520.847395762878, 31219.531707024868, 31515.36969260556, 48662.513374867216, 48891.26663099985, 49032.0, 49056.264156036974, 49077.75461122814, 289137.0, 291066.0, 291161.0, 293217.0, 293310.0, 35523.64476355203, 37337.4399384004, 37589.624103758615, 37635.81182094072, 38131.23737525178, 45706.54293457156, 46493.76753116213, 46643.766781165876, 47203.52796471991, 47678.0, 38289.4256586157, 39413.60586394214, 39626.0, 39762.40356394714, 40385.39421908733, 39451.80274098726, 39602.40596390999, 39796.80101599474, 39840.01199910076, 40355.0, 38228.235435291695, 38384.0, 38485.80757096309, 39067.609323907534, 39309.80345098256, 33673.66326336705, 34225.0, 34348.851323788156, 34731.305373891875, 35152.29695406129, 26676.333091672863, 27325.590116148673, 27362.0, 27400.314992125357, 28016.859915701105], "unit": "ms", "name": "ext.html", "value": 20405.10663567496}, {"lowerIsBetter": false, "replicates": [371.5225934673945, 374.43460374833995, 375.49800796812747, 381.4869001193394, 382.9578746337904, 15898.602534936603, 17337.73993390097, 17589.560260993483, 19651.60696786065, 19755.802441975593, 13289.667758306046, 13693.109947853383, 13703.520376786815, 18731.719024214646, 19037.238510459563, 3936.0946982194114, 4919.385076865392, 5027.421846487664, 5028.723420211887, 5029.119904016782, 4010.13782036862, 5332.066888294552, 5471.452854714536, 5477.589180811435, 5479.315085614298, 4991.575716064139, 5370.167624018264, 5386.622936394454, 5390.811321603745, 5402.756875940572, 5093.0, 5107.080725469414, 5114.590832733373, 5116.795328186868, 5133.768980395891, 4429.379886815848, 4458.77706114693, 4637.95362046382, 4641.025384669224, 4644.0, 14786.18675972821, 14792.556223313319, 15069.0, 15436.536903892902, 15446.382144714198, 2147.280660978574, 2165.7184566006476, 2183.443221978401, 2188.2559929624003, 2217.0355895185644, 11115.666530004031, 11219.495122719416, 11235.606753763579, 11268.661940141808, 11286.153538484565, 1840.346676929684, 1875.6436277107443, 1904.561950751321, 1920.9615807683767, 1926.7976862429384, 65767.34232657708, 65878.0, 67167.0, 67299.0, 67575.0, 7711.537307761552, 7903.091144518339, 7979.760607181678, 7990.0, 8043.11525732169, 9679.564419601134, 9683.806323873483, 9692.321537492307, 9692.612295508101, 9692.660756873476, 6424.036394540856, 6436.452901503425, 6458.903116453257, 6515.0, 6583.177102862142, 6640.236372817188, 6656.70044847983, 6697.296783837627, 6753.088333074972, 6758.628275444799, 3420.4698271767916, 3660.633936606318, 3695.0, 3727.5154229950476, 3731.141837377364, 1266.5187228852976, 1278.0797825565764, 1281.8525869525124, 1292.75437666843, 1305.9673508162182, 530.5755395683437, 562.1511517608441, 576.0207647000117, 578.2136294639287, 585.7920438244447, 11287.153463490271, 12807.61577152687, 13112.0, 13223.140495867738, 13569.457221711022, 555.7304707216997, 557.1336571631152, 557.3033707865169, 563.6420872745836, 578.7048605211372, 7114.39527640146, 7315.890261646079, 7358.080239970004, 7380.483366164317, 7516.473846830779, 651617.0, 665028.0, 670877.0, 670894.0, 673019.0, 82854.0, 83089.0, 83539.0, 84539.0, 87052.0, 619.4672581579838, 620.9068639703991, 626.4518546272012, 627.0093252660785, 628.905664150372, 12414.937925310316, 12460.563880264148, 12561.246325220516, 12626.873731262569, 12643.304618246084, 38996.41505377365, 39065.414018789175, 39384.0, 40157.397639036026, 40430.595694042684, 47854.28218576655, 48388.51611483981, 49582.752086239336, 50048.74975625099, 52771.20843187432, 7454.776356709307, 7494.100707914867, 7604.0, 7605.315521603082, 7614.466987311057, 13402.93298533501, 13521.391537381038, 13579.592612221253, 13848.30758462093, 13952.302384880511, 11392.601258955896, 11772.352520611448, 11870.0, 11981.76036479283, 12052.578159764716, 11843.407829608657, 11949.321628092648, 12108.878911210775, 12266.0, 12295.07786915967, 12295.385230738248, 12349.814752778535, 12606.49574017029, 12645.87354126447, 12813.551525696555, 7754.883676744967, 7817.374610031297, 7823.530588164728, 7849.17583653731, 7849.568273744999, 11714.707132321419, 11877.578336479723, 12067.75864482688, 12284.078694098158, 12432.937835310766], "unit": "ms", "name": "jquery.html", "value": 7760.631594766999}, {"lowerIsBetter": false, "replicates": [1578.6526964067907, 1583.2242201321349, 1782.1178516634263, 1787.5888545634512, 1843.106093544631, 7076.433885289177, 7201.495895287332, 7916.089649690281, 8233.588320583976, 8251.339892808575, 7683.538987660744, 7827.530348179099, 7902.051753789539, 7914.327282181007, 7917.406194535419, 2361.03197688948, 2419.250032489925, 2426.708794944609, 2431.8176136789725, 2432.720237172721, 2207.260567709819, 2253.9323820285335, 2414.2998530426157, 2420.8910599022997, 2482.6648402465617, 2352.3178278299356, 2354.682117914085, 2356.387339291788, 2358.1157066100213, 2362.031567057507, 2419.443527988564, 2428.76926691964, 2432.70807503099, 2437.427204606916, 2441.157800558813, 2379.274321331993, 2414.794742446894, 2418.7823095921276, 2420.673209116764, 2422.2612103308484, 7932.365410767125, 8010.759677209693, 8032.1566235545015, 8036.517808931483, 8043.477173983672, 5019.447860735316, 5045.47022562633, 5084.51697088776, 5090.211017292326, 5100.642954993152, 3229.9838500807346, 3288.046466524705, 3356.748243881694, 3383.9830800846084, 3403.4724617684296, 1038.3250886923486, 1057.7038429239826, 1067.1302888146136, 1082.2802836113974, 1085.5386460754148, 10831.187660925383, 10834.079103276232, 10842.620508282173, 10855.45722713869, 10863.782724345469, 4631.490536041033, 4647.9767601161775, 4690.319903614025, 4692.319613656001, 4694.43666759991, 9307.674231401868, 9315.068493150766, 9321.114494123047, 9340.925793533695, 9374.437533747998, 5109.846704598868, 5119.0, 5127.87180320495, 5132.28148059272, 5132.666376685504, 4656.511066338054, 4661.463931647836, 4671.532846715301, 4680.648951328596, 4680.765961701902, 3495.9126021849656, 3901.3367727486398, 3936.7834769087976, 3956.841726330972, 3982.9402558961638, 1239.5661518468644, 1269.2004037456343, 1304.6086174147717, 1314.1589382795019, 1315.2963164706837, 1235.6972541727334, 1248.294713486877, 1317.1636011133, 1491.4407097338499, 1505.533284681752, 7726.9613651932505, 7776.611169441505, 7815.882761758578, 7819.413543984224, 7858.685652573948, 1195.1514424758518, 1213.8664746877837, 1226.466487078124, 1236.8824961628632, 1243.6828608704902, 6140.263168419819, 6177.042558403455, 6213.96893015532, 6242.188515492957, 6254.21822272216, 9514.238860891111, 9749.220062395134, 9780.364276322016, 9783.021697830301, 9790.902090979142, 9497.192738617296, 9668.323217374716, 9685.515724213761, 9689.12797848197, 9694.22446204302, 7564.546127232384, 7613.771586852404, 7645.082590089114, 7658.310752032233, 7674.117476490163, 7384.815379615337, 7464.664090116066, 7489.363404110493, 7499.475036747375, 7516.173220945582, 8417.116202798617, 8562.143785621389, 8610.65557377698, 8611.095834937241, 8717.607707653298, 8706.1729135732, 8872.73381798547, 8877.822443551222, 8901.910980890108, 9012.549372531215, 7510.399168066432, 7557.05536807899, 7569.6593653284635, 7578.810529736801, 7591.734289299848, 6844.0, 7207.702613529615, 7808.921910781047, 7900.525968441912, 7935.246151615587, 7088.149422069385, 7117.288271172842, 7135.179454362815, 7191.17301510333, 7192.280771922766, 7102.680379383043, 7182.569045857265, 7201.783946481614, 7306.817329566803, 7309.853802923805, 7418.629068546659, 7482.70069197226, 7495.250474952461, 7499.062617172854, 7509.51188172767, 6695.09616201826, 6752.561083529555, 6808.62552559614, 6825.8634827302185, 6835.829104272234, 6981.616011119438, 7023.613701246481, 7024.911138773498, 7027.578345299298, 7032.613206273704], "unit": "ms", "name": "mootools.html", "value": 4749.3175960642875}, {"lowerIsBetter": false, "replicates": [363.54738350753314, 371.32789650732167, 371.8995871114799, 372.2833545425056, 381.0587848015403, 5865.76536938522, 5901.439363260483, 5993.790217342394, 6003.699815009249, 6042.486388656964, 5806.680632565207, 5885.293764748226, 5898.0, 5901.616394934326, 5916.5266778657715, 4617.122746678137, 4620.7689615519175, 4622.445306563217, 4623.79192936317, 4625.722456652612, 4623.283391074388, 4624.121416930772, 4626.236670949306, 4629.282461218517, 4639.373684552576, 4520.615747661453, 4601.263797792354, 4613.976930115345, 4656.604188643969, 4658.650601204923, 4634.698744581591, 4644.5123262057505, 4646.326282689008, 4648.511906249829, 4657.9068418631605, 3941.980290098531, 4118.320477121271, 4126.298529250034, 4126.91746165078, 4131.462909821734, 5540.501354878081, 5620.297462817148, 5655.321361436613, 5667.716614169275, 5687.687177205252, 5332.600054995891, 5338.866528336784, 5343.786248550054, 5345.599080069011, 5353.143497040455, 6219.315875253718, 6226.906596401058, 6227.937720622781, 6233.438990490832, 6233.625982441068, 4433.850532957806, 4562.8631141065825, 4589.151007063704, 4596.471405788344, 4602.125596136723, 6519.152510173647, 6523.315051919528, 6526.673666316665, 6528.771492997722, 6529.477641788693, 4722.95277047232, 4729.881752956204, 4731.408573928259, 4735.431748190239, 4737.526247375304, 4770.332153498513, 4771.06964141989, 4772.284157376422, 4775.403074615673, 4778.235482322812, 5187.299714538564, 5286.656367336111, 5289.100852855024, 5290.761915713802, 5293.57651387888, 6252.0, 6295.811125666146, 6296.748130074746, 6300.653464059429, 6303.212098487689, 1667.474745455202, 1686.9578260543585, 1687.687777761118, 1688.611619327537, 1688.6960347137633, 1127.7575321305958, 1134.659602119361, 1136.846525719034, 1150.3443037468562, 1166.5567084507832, 6081.787137450168, 6089.360617135226, 6092.817215483543, 6093.908591371133, 6095.7866474674065, 6373.298937116913, 6419.101325814389, 6431.517636177306, 6441.32366101562, 6445.129907462527, 5950.494207992283, 6038.698065096815, 6048.848778780566, 6051.0923361496125, 6052.273727152769, 3554.0, 3605.2969670914445, 3607.621199773986, 3617.8914632561064, 3620.420732682758, 6511.251206111263, 6606.372394622503, 6632.1709786276715, 6714.597124172566, 6726.192856857208, 6621.801345959629, 6641.933580664228, 6650.301718319603, 6650.70071846768, 6654.367835055662, 6767.0, 6776.830579235559, 6780.7287708492095, 6785.287544807823, 6786.558873673294, 6442.162518872517, 6584.901226481702, 6585.901211481927, 6587.275399705933, 6598.967005164944, 6400.359964003563, 6419.358064193543, 6472.546921715623, 6479.773207937699, 6480.513961453004, 6702.597844129173, 6721.462283017445, 6725.0, 6726.831829204309, 6777.423918966943, 4503.527129651339, 4588.655850811269, 4591.3572099906705, 4591.862244132682, 4596.724196548218, 6489.156409666713, 6492.642904640101, 6504.60972341661, 6507.316731743099, 6528.67356632176, 6398.5201109916015, 6408.29508754046, 6408.455281300955, 6422.967885160544, 6427.485801135991, 6271.153394291712, 6355.078513615407, 6376.808695739135, 6378.936210637834, 6405.455536279468, 6205.968970155121, 6223.159873417031, 6250.562460627894, 6267.937320626917, 6269.96865015672, 6366.4588509977175, 6384.712687928962, 6387.808365749035, 6397.136386587751, 6399.04014397844, 6091.634501929899, 6264.342244064308, 6267.435930766253, 6281.0, 6309.589876658186], "unit": "ms", "name": "prototype.html", "value": 4833.488968440894}, {"lowerIsBetter": false, "replicates": [876.5179151466692, 985.261054209343, 988.649029594494, 989.7179303898386, 1001.0990108901992, 13946.860531394683, 15569.532914012569, 15575.0, 15575.221238938064, 15589.376424942988, 14474.710505789877, 14550.417983280682, 14552.272386380691, 14576.562703118923, 14579.854201457982, 796.0288448093316, 804.2962407893093, 911.0752586125088, 927.3925578745915, 934.5140526925994, 929.4748467116058, 945.5744914788373, 946.0870260198907, 951.9333646644737, 953.8950715421333, 828.5318794880903, 976.1605019683067, 1000.7798284377461, 1002.0830939690175, 1002.578916854919, 823.0987069159291, 965.7971825916566, 986.6448078691665, 989.4953573677441, 992.3648864726581, 723.6526467295674, 731.042334541752, 731.5025782467919, 736.4476642518111, 736.9189389167187, 13368.465261389532, 13427.932860335733, 13431.12697674648, 13435.26106064166, 13450.86549134506, 13509.662258443617, 14021.369038393195, 14090.154590724589, 14103.78844317336, 14640.780388294184, 11473.0, 11533.308001519936, 11690.941545292304, 11790.764184716343, 11824.645260642195, 3691.5570131584113, 3743.4384842273603, 3796.8291426885858, 3809.3524100902914, 3814.1608846053823, 50805.22181723622, 51172.0, 53182.0, 53195.46804531944, 53463.19805202925, 8847.867281990775, 8922.73231803047, 8998.955005224932, 9156.954215228881, 11352.489137988645, 13961.092528985584, 14046.578602641937, 14064.43742250319, 14068.507602234078, 14075.225862577656, 7660.961695191489, 7857.724979625686, 7933.682652693943, 7938.841223175504, 8071.192880711882, 4813.614910807127, 4868.0, 4889.755512224375, 4960.70235785854, 5001.924971125436, 3111.891083812101, 3137.419577378192, 3144.0, 3145.4495463293833, 3145.7640676949322, 493.12716491809294, 496.8335607571504, 499.47555067179314, 501.13804256679185, 503.9521245481673, 606.75123199488, 657.5288041319012, 682.0689758479739, 696.1194089476816, 706.3642721550646, 9297.814043719087, 9710.854337184948, 9728.708138755848, 9736.368934069911, 9780.021997800162, 547.2023906708419, 555.2615022020702, 653.6764301670703, 693.2028167607292, 701.5650296816007, 4287.142571485653, 4379.1241751649795, 4494.977525112419, 4505.684602077823, 4521.965752027961, 66510.66744666245, 67715.66142169356, 67756.0, 67823.32176678268, 67836.0, 58106.70946645324, 59186.11220831691, 59670.70164649149, 60069.098963515586, 60120.0, 52255.47744522582, 53592.0, 53830.461695383325, 54160.187597186865, 54784.452155477935, 7893.1317555070045, 8135.959320203361, 8247.463914845515, 8326.83346333082, 8508.787280318042, 39896.40155397613, 40360.79819601001, 40641.577544786975, 41237.79381103075, 41287.793561032006, 52947.73526132345, 53462.73268633632, 53594.0, 54096.0, 55017.1747423781, 12740.235585864877, 12990.090693651353, 13208.0, 13342.399592018577, 13502.797458037941, 13288.800667989795, 13437.260950647807, 13632.182069075887, 13869.653258668613, 13871.098378605762, 12584.18202816814, 12898.0, 13230.338483076, 13353.66615834612, 13563.525276615666, 12955.805662914876, 12957.352132393155, 13065.804012939623, 13388.19670819754, 13667.453301867814, 11818.468168932246, 12127.0, 12416.130870839315, 12686.619401417973, 12939.935300323437, 10165.796684066425, 10536.53703244984, 10679.145668346358, 10960.013598776148, 11049.558017679525, 9885.950570247103, 10071.244656650633, 10709.464526773474, 10974.451277436256, 11099.0], "unit": "ms", "name": "yui.html", "value": 6859.782941594455}], "name": "dromaeo_css", "value": 7185.848201161142}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 106.0, 110.0, 113.0, 112.0, 101.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.8}, {"lowerIsBetter": true, "replicates": [142.0, 123.0, 162.0, 163.0, 128.0, 148.0, 150.0, 129.0, 173.0, 133.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.1}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 161.0, 160.0, 160.0, 161.0, 163.0, 162.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.8}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 72.0, 139.0, 71.0, 73.0, 71.0, 72.0, 164.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 87.6}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 134.0, 89.0, 90.0, 91.0, 134.0, 89.0, 91.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 103.4}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 111.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 144.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.2}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 55.0, 55.0, 56.0, 55.0, 57.0, 55.0, 53.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 55.3}, {"lowerIsBetter": true, "replicates": [62.0, 63.0, 62.0, 63.0, 63.0, 63.0, 62.0, 62.0, 62.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 62.5}, {"lowerIsBetter": true, "replicates": [78.0, 79.0, 86.0, 79.0, 79.0, 80.0, 85.0, 78.0, 81.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.4}, {"lowerIsBetter": true, "replicates": [105.0, 107.0, 112.0, 108.0, 108.0, 121.0, 120.0, 112.0, 110.0, 109.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 111.2}, {"lowerIsBetter": true, "replicates": [141.0, 142.0, 138.0, 136.0, 138.0, 131.0, 131.0, 133.0, 135.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 136.1}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 64.0, 62.0, 63.0, 62.0, 65.0, 62.0, 63.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.8}], "name": "kraken", "value": 1463.8999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [162.73393002441009], "unit": "score", "name": "Richards", "value": 216.93078999999992}, {"lowerIsBetter": false, "replicates": [171.7032967032967], "unit": "score", "name": "DeltaBlue", "value": 385.071232}, {"lowerIsBetter": false, "replicates": [279.4857462269424], "unit": "score", "name": "Encrypt", "value": 952.3956179999999}, {"lowerIsBetter": false, "replicates": [5005.0], "unit": "score", "name": "Decrypt", "value": 53.18301698301701}, {"lowerIsBetter": false, "replicates": [953.2888465204957], "unit": "score", "name": "RayTrace", "value": 776.2484609999998}, {"lowerIsBetter": false, "replicates": [550.9641873278237], "unit": "score", "name": "Earley", "value": 1209.6303449999996}, {"lowerIsBetter": false, "replicates": [10536.842105263158], "unit": "score", "name": "Boyer", "value": 63.25073426573423}, {"lowerIsBetter": false, "replicates": [23928.571428571428], "unit": "score", "name": "RegExp", "value": 38.071014925373106}, {"lowerIsBetter": false, "replicates": [534.9706353443673], "unit": "score", "name": "Splay", "value": 152.3279870259481}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 23266.107706101684}]} 13:36:59 INFO - RETURN: dromaeo_css: 7185.85 13:36:59 INFO - RETURN: kraken: 1463.90 13:36:59 INFO - RETURN: v8_7: 23266.11 13:36:59 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23266.11"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1463.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7185.85"}}} 13:36:59 INFO - Return code: 0 13:36:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 13:36:59 INFO - # TBPL SUCCESS # 13:36:59 INFO - Running post-action listener: _resource_record_post_action 13:36:59 INFO - Running post-run listener: _resource_record_post_run 13:37:00 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 315392; Write bytes: 369635328; Read time: 48; Write time: 285864 13:37:00 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 109658112; Read time: 0; Write time: 114476 13:37:00 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 315392; Write bytes: 259690496; Read time: 48; Write time: 171364 13:37:00 INFO - Running post-run listener: _upload_blobber_files 13:37:00 INFO - Blob upload gear active. 13:37:00 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 13:37:00 INFO - Copying logs to upload dir... 13:37:00 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1260.827074 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2015-12-21 13:36:59.023057) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-21 13:36:59.026892) ========= 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=edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009990 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450724189/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-21 13:36:59.078361) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 13:36:59.078713) ========= 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=edca6ca23558fe0608d91a4e00000196-1450732533.719026-458792471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004586 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 13:36:59.128448) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 13:36:59.128783) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 13:36:59.129389) ========= ========= Total master_lag: 0.24 =========