builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1447899980.83 results: success (0) buildid: 20151118154243 builduid: 727aaa071685489fa01eb34de8ac55b5 revision: 91404fe9f051 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.830052) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.830477) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.830783) ========= 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=70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006659 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.855576) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.855928) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.873342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.873697) ========= 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=70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004651 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.922457) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.922820) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.923183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:20.923455) ========= 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=70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-18 18:26:20-- 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% 16.8M=0.001s 2015-11-18 18:26:21 (16.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.190261 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:21.152852) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:21.153209) ========= 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=70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019686 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:21.210346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-18 18:26:21.210678) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 91404fe9f051 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 91404fe9f051 --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=70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-18 18:26:21,236 Setting DEBUG logging. 2015-11-18 18:26:21,236 attempt 1/10 2015-11-18 18:26:21,236 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/91404fe9f051?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-18 18:26:22,449 unpacking tar archive at: fx-team-91404fe9f051/testing/mozharness/ program finished with exit code 0 elapsedTime=1.508896 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-18 18:26:22.732591) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:22.732883) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:22.742520) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:22.742786) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 18:26:22.743166) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2015-11-18 18:26:22.743446) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:26:22 INFO - MultiFileLogger online at 20151118 18:26:22 in /builds/slave/test-pgo 18:26:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:26:22 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:26:22 INFO - {'append_to_log': False, 18:26:22 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:26:22 INFO - 'blob_upload_branch': 'Fx-Team', 18:26:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:26:22 INFO - 'branch': 'Fx-Team', 18:26:22 INFO - 'buildbot_json_path': 'buildprops.json', 18:26:22 INFO - 'config_files': ('talos/linux_config.py',), 18:26:22 INFO - 'default_actions': ('clobber', 18:26:22 INFO - 'read-buildbot-config', 18:26:22 INFO - 'download-and-extract', 18:26:22 INFO - 'populate-webroot', 18:26:22 INFO - 'create-virtualenv', 18:26:22 INFO - 'install', 18:26:22 INFO - 'run-tests'), 18:26:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:26:22 INFO - 'download_minidump_stackwalk': True, 18:26:22 INFO - 'download_symbols': 'ondemand', 18:26:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:26:22 INFO - 'tooltool.py': '/tools/tooltool.py', 18:26:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:26:22 INFO - '/tools/misc-python/virtualenv.py')}, 18:26:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:26:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:26:22 INFO - 'installer_path': 'installer.exe', 18:26:22 INFO - 'log_level': 'info', 18:26:22 INFO - 'log_name': 'talos', 18:26:22 INFO - 'log_to_console': True, 18:26:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:26:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:26:22 INFO - 'opt_config_files': (), 18:26:22 INFO - 'pip_index': False, 18:26:22 INFO - 'sps_profile': False, 18:26:22 INFO - 'sps_profile_interval': 0, 18:26:22 INFO - 'suite': 'dromaeojs', 18:26:22 INFO - 'system_bits': '64', 18:26:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:26:22 INFO - 'title': 'talos-linux64-ix-015', 18:26:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:26:22 INFO - 'use_talos_json': True, 18:26:22 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:26:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:26:22 INFO - 'work_dir': 'build'} 18:26:22 INFO - ##### 18:26:22 INFO - ##### Running clobber step. 18:26:22 INFO - ##### 18:26:22 INFO - Running pre-action listener: _resource_record_pre_action 18:26:22 INFO - Running main action method: clobber 18:26:22 INFO - rmtree: /builds/slave/test-pgo/build 18:26:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:26:23 INFO - Running post-action listener: _resource_record_post_action 18:26:23 INFO - ##### 18:26:23 INFO - ##### Running read-buildbot-config step. 18:26:23 INFO - ##### 18:26:23 INFO - Running pre-action listener: _resource_record_pre_action 18:26:23 INFO - Running main action method: read_buildbot_config 18:26:23 INFO - Using buildbot properties: 18:26:23 INFO - { 18:26:23 INFO - "properties": { 18:26:23 INFO - "buildnumber": 499, 18:26:23 INFO - "product": "firefox", 18:26:23 INFO - "script_repo_revision": "production", 18:26:23 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:26:23 INFO - "repository": "", 18:26:23 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 18:26:23 INFO - "buildid": "20151118154243", 18:26:23 INFO - "slavename": "talos-linux64-ix-015", 18:26:23 INFO - "pgo_build": "True", 18:26:23 INFO - "basedir": "/builds/slave/test-pgo", 18:26:23 INFO - "project": "", 18:26:23 INFO - "platform": "ubuntu64_hw", 18:26:23 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:26:23 INFO - "slavebuilddir": "test", 18:26:23 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 18:26:23 INFO - "branch": "fx-team", 18:26:23 INFO - "repo_path": "integration/fx-team", 18:26:23 INFO - "stage_platform": "linux64-pgo", 18:26:23 INFO - "builduid": "727aaa071685489fa01eb34de8ac55b5", 18:26:23 INFO - "revision": "91404fe9f051" 18:26:23 INFO - }, 18:26:23 INFO - "sourcestamp": { 18:26:23 INFO - "repository": "", 18:26:23 INFO - "hasPatch": false, 18:26:23 INFO - "project": "", 18:26:23 INFO - "branch": "fx-team-linux64-pgo-talos", 18:26:23 INFO - "changes": [ 18:26:23 INFO - { 18:26:23 INFO - "category": null, 18:26:23 INFO - "files": [ 18:26:23 INFO - { 18:26:23 INFO - "url": null, 18:26:23 INFO - "name": "https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:26:23 INFO - } 18:26:23 INFO - ], 18:26:23 INFO - "repository": "", 18:26:23 INFO - "rev": "91404fe9f051", 18:26:23 INFO - "who": "mozci-bot@mozilla.com", 18:26:23 INFO - "when": 1447899942, 18:26:23 INFO - "number": 6680565, 18:26:23 INFO - "comments": "", 18:26:23 INFO - "project": "", 18:26:23 INFO - "at": "Wed 18 Nov 2015 18:25:42", 18:26:23 INFO - "branch": "fx-team-linux64-pgo-talos", 18:26:23 INFO - "revlink": "", 18:26:23 INFO - "properties": [ 18:26:23 INFO - [ 18:26:23 INFO - "buildid", 18:26:23 INFO - "20151118154243", 18:26:23 INFO - "Change" 18:26:23 INFO - ], 18:26:23 INFO - [ 18:26:23 INFO - "builduid", 18:26:23 INFO - "727aaa071685489fa01eb34de8ac55b5", 18:26:23 INFO - "Change" 18:26:23 INFO - ], 18:26:23 INFO - [ 18:26:23 INFO - "pgo_build", 18:26:23 INFO - "True", 18:26:23 INFO - "Change" 18:26:23 INFO - ] 18:26:23 INFO - ], 18:26:23 INFO - "revision": "91404fe9f051" 18:26:23 INFO - } 18:26:23 INFO - ], 18:26:23 INFO - "revision": "91404fe9f051" 18:26:23 INFO - } 18:26:23 INFO - } 18:26:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:26:23 INFO - Running post-action listener: _resource_record_post_action 18:26:23 INFO - ##### 18:26:23 INFO - ##### Running download-and-extract step. 18:26:23 INFO - ##### 18:26:23 INFO - Running pre-action listener: _resource_record_pre_action 18:26:23 INFO - Running main action method: download_and_extract 18:26:23 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:26:23 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')]} 18:26:23 INFO - https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:26:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/test_packages.json 18:26:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/test_packages.json 18:26:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:26:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:26:25 INFO - Downloaded 1302 bytes. 18:26:25 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:26:25 INFO - Using the following test package requirements: 18:26:25 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:26:25 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:26:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:26:25 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:26:25 INFO - u'jsshell-linux-x86_64.zip'], 18:26:25 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:26:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:26:25 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:26:25 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:26:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:26:25 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:26:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:26:25 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:26:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:26:25 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:26:25 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:26:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:26:25 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:26: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')]} 18:26:25 INFO - https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:26:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:26:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:26:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:26:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:26:27 INFO - Downloaded 22474769 bytes. 18:26:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:26:27 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:26:27 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:26:28 INFO - Return code: 0 18:26:28 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:26: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')]} 18:26:28 INFO - https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:26:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:26:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:26:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:26:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:26:44 INFO - Downloaded 22474769 bytes. 18:26:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:26:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:26:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:26:45 INFO - Return code: 0 18:26:45 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')]} 18:26:45 INFO - https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:26:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:26:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:26:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:26:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:26:46 INFO - Downloaded 11130997 bytes. 18:26:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:26:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:26:46 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:26:47 INFO - Return code: 0 18:26:47 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')]} 18:26:47 INFO - https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:26:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:26:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:26:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:26:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:26:48 INFO - Downloaded 57209861 bytes. 18:26:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:26:48 INFO - mkdir: /builds/slave/test-pgo/properties 18:26:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:26:48 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:26:48 INFO - Contents: 18:26:48 INFO - build_url:https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:26:48 INFO - Running post-action listener: _resource_record_post_action 18:26:48 INFO - Running post-action listener: set_extra_try_arguments 18:26:48 INFO - ##### 18:26:48 INFO - ##### Running populate-webroot step. 18:26:48 INFO - ##### 18:26:48 INFO - Running pre-action listener: _resource_record_pre_action 18:26:48 INFO - Running main action method: populate_webroot 18:26:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:26:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:26:48 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:26:48 INFO - '../fennec_ids.txt'], 18:26:48 INFO - 'tests': ['tcheck2']}, 18:26:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:26:48 INFO - '--tppagecycles', 18:26:48 INFO - '7'], 18:26:48 INFO - 'tests': ['tsvgm']}}, 18:26:48 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:26:48 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:26:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:26:48 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:26:48 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:26:48 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:26:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:48 INFO - 'talos_options': ['--e10s'], 18:26:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:48 INFO - 'tests': ['damp', 'tps']}, 18:26:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:48 INFO - 'talos_options': ['--e10s'], 18:26:48 INFO - 'tests': ['damp', 'tps']}, 18:26:48 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:26:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:26:48 INFO - 'other': {'tests': ['a11yr', 18:26:48 INFO - 'ts_paint', 18:26:48 INFO - 'tpaint', 18:26:48 INFO - 'sessionrestore', 18:26:48 INFO - 'sessionrestore_no_auto_restore']}, 18:26:48 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:26:48 INFO - 'tests': ['a11yr', 18:26:48 INFO - 'ts_paint', 18:26:48 INFO - 'tpaint', 18:26:48 INFO - 'sessionrestore', 18:26:48 INFO - 'sessionrestore_no_auto_restore']}, 18:26:48 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:26:48 INFO - 'tests': ['a11yr', 18:26:48 INFO - 'ts_paint', 18:26:48 INFO - 'tpaint', 18:26:48 INFO - 'sessionrestore', 18:26:48 INFO - 'sessionrestore_no_auto_restore']}, 18:26:48 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:26:48 INFO - 'tests': ['a11yr', 18:26:48 INFO - 'ts_paint', 18:26:48 INFO - 'tpaint', 18:26:48 INFO - 'sessionrestore', 18:26:48 INFO - 'sessionrestore_no_auto_restore']}, 18:26:48 INFO - 'other_l64': {'tests': ['a11yr', 18:26:48 INFO - 'ts_paint', 18:26:48 INFO - 'tpaint', 18:26:48 INFO - 'sessionrestore', 18:26:48 INFO - 'sessionrestore_no_auto_restore']}, 18:26:48 INFO - 'other_nol64': {'tests': ['a11yr', 18:26:48 INFO - 'ts_paint', 18:26:48 INFO - 'tpaint', 18:26:48 INFO - 'sessionrestore', 18:26:48 INFO - 'sessionrestore_no_auto_restore']}, 18:26:48 INFO - 'svgr': {'tests': ['tsvgx', 18:26:48 INFO - 'tsvgr_opacity', 18:26:48 INFO - 'tart', 18:26:48 INFO - 'tscrollx', 18:26:48 INFO - 'cart']}, 18:26:48 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:26:48 INFO - 'tests': ['tsvgx', 18:26:48 INFO - 'tsvgr_opacity', 18:26:48 INFO - 'tart', 18:26:48 INFO - 'tscrollx', 18:26:48 INFO - 'cart']}, 18:26:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:48 INFO - 'tests': ['tp5o']}, 18:26:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:48 INFO - 'talos_options': ['--e10s'], 18:26:48 INFO - 'tests': ['tp5o']}, 18:26:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:48 INFO - 'talos_options': ['--xperf_path', 18:26:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:48 INFO - 'tests': ['tp5n']}, 18:26:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:48 INFO - 'talos_options': ['--e10s', 18:26:48 INFO - '--xperf_path', 18:26:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:48 INFO - 'tests': ['tp5n']}}, 18:26:48 INFO - 'talos.zip': {'path': '', 18:26:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:26:48 INFO - Running post-action listener: _resource_record_post_action 18:26:48 INFO - ##### 18:26:48 INFO - ##### Running create-virtualenv step. 18:26:48 INFO - ##### 18:26:48 INFO - Running pre-action listener: _resource_record_pre_action 18:26:48 INFO - Running main action method: create_virtualenv 18:26:48 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:26:48 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 18:26:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:26:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:26:48 INFO - Using real prefix '/usr' 18:26:48 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:26:49 INFO - Installing distribute.............................................................................................................................................................................................done. 18:26:51 INFO - Installing pip.................done. 18:26:51 INFO - Return code: 0 18:26:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0517aedc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe786c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf2a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ed70>, '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 0xf6fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ffb0>, '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': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', '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 18:26:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 18:26:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:26:51 INFO - Using env: {'DISPLAY': ':0', 18:26:51 INFO - 'HOME': '/home/cltbld', 18:26:51 INFO - 'LANG': 'en_US.UTF-8', 18:26:51 INFO - 'LANGUAGE': 'en_US:en', 18:26:51 INFO - 'LOGNAME': 'cltbld', 18:26:51 INFO - 'MAIL': '/var/mail/cltbld', 18:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:51 INFO - 'MOZ_NO_REMOTE': '1', 18:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:51 INFO - 'NO_EM_RESTART': '1', 18:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:26:51 INFO - 'PWD': '/builds/slave/test-pgo', 18:26:51 INFO - 'SHELL': '/bin/bash', 18:26:51 INFO - 'SHLVL': '1', 18:26:51 INFO - 'TERM': 'linux', 18:26:51 INFO - 'TMOUT': '86400', 18:26:51 INFO - 'USER': 'cltbld', 18:26:51 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', 18:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:51 INFO - '_': '/tools/buildbot/bin/python'} 18:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:51 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 18:26:51 INFO - Cleaning up... 18:26:51 INFO - Return code: 0 18:26:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f0517aedc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe786c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf2a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ed70>, '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 0xf6fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ffb0>, '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': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', '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 18:26:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 18:26:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:26:51 INFO - Using env: {'DISPLAY': ':0', 18:26:51 INFO - 'HOME': '/home/cltbld', 18:26:51 INFO - 'LANG': 'en_US.UTF-8', 18:26:51 INFO - 'LANGUAGE': 'en_US:en', 18:26:51 INFO - 'LOGNAME': 'cltbld', 18:26:51 INFO - 'MAIL': '/var/mail/cltbld', 18:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:51 INFO - 'MOZ_NO_REMOTE': '1', 18:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:51 INFO - 'NO_EM_RESTART': '1', 18:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:26:51 INFO - 'PWD': '/builds/slave/test-pgo', 18:26:51 INFO - 'SHELL': '/bin/bash', 18:26:51 INFO - 'SHLVL': '1', 18:26:51 INFO - 'TERM': 'linux', 18:26:51 INFO - 'TMOUT': '86400', 18:26:51 INFO - 'USER': 'cltbld', 18:26:51 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', 18:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:51 INFO - '_': '/tools/buildbot/bin/python'} 18:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:51 INFO - Downloading/unpacking psutil>=0.7.1 18:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:53 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:26:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:26:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:26:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:26:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:26:53 INFO - Installing collected packages: psutil 18:26:53 INFO - Running setup.py install for psutil 18:26:53 INFO - building 'psutil._psutil_linux' extension 18:26:53 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 18:26:53 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 18:26:53 INFO - building 'psutil._psutil_posix' extension 18:26:53 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 18:26:53 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 18:26:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:26:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:26:53 INFO - Successfully installed psutil 18:26:53 INFO - Cleaning up... 18:26:54 INFO - Return code: 0 18:26:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f0517aedc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe786c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf2a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ed70>, '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 0xf6fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ffb0>, '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': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', '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 18:26:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 18:26:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --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 18:26:54 INFO - Using env: {'DISPLAY': ':0', 18:26:54 INFO - 'HOME': '/home/cltbld', 18:26:54 INFO - 'LANG': 'en_US.UTF-8', 18:26:54 INFO - 'LANGUAGE': 'en_US:en', 18:26:54 INFO - 'LOGNAME': 'cltbld', 18:26:54 INFO - 'MAIL': '/var/mail/cltbld', 18:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:54 INFO - 'MOZ_NO_REMOTE': '1', 18:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:54 INFO - 'NO_EM_RESTART': '1', 18:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:26:54 INFO - 'PWD': '/builds/slave/test-pgo', 18:26:54 INFO - 'SHELL': '/bin/bash', 18:26:54 INFO - 'SHLVL': '1', 18:26:54 INFO - 'TERM': 'linux', 18:26:54 INFO - 'TMOUT': '86400', 18:26:54 INFO - 'USER': 'cltbld', 18:26:54 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', 18:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:54 INFO - '_': '/tools/buildbot/bin/python'} 18:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:26:54 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 18:26:54 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 18:26:54 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 18:26:54 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 18:26:54 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 18:26:54 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 18:26:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:26:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:26:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:26:55 INFO - Installing collected packages: mozsystemmonitor 18:26:55 INFO - Running setup.py install for mozsystemmonitor 18:26:56 INFO - Successfully installed mozsystemmonitor 18:26:56 INFO - Cleaning up... 18:26:56 INFO - Return code: 0 18:26:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:26:56 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')]} 18:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:56 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')]} 18:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:56 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 0x7f0517aedc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe786c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf2a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ed70>, '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 0xf6fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ffb0>, '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': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', '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 18:26:56 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 18:26:56 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 18:26:56 INFO - Using env: {'DISPLAY': ':0', 18:26:56 INFO - 'HOME': '/home/cltbld', 18:26:56 INFO - 'LANG': 'en_US.UTF-8', 18:26:56 INFO - 'LANGUAGE': 'en_US:en', 18:26:56 INFO - 'LOGNAME': 'cltbld', 18:26:56 INFO - 'MAIL': '/var/mail/cltbld', 18:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:56 INFO - 'MOZ_NO_REMOTE': '1', 18:26:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:56 INFO - 'NO_EM_RESTART': '1', 18:26:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:26:56 INFO - 'PWD': '/builds/slave/test-pgo', 18:26:56 INFO - 'SHELL': '/bin/bash', 18:26:56 INFO - 'SHLVL': '1', 18:26:56 INFO - 'TERM': 'linux', 18:26:56 INFO - 'TMOUT': '86400', 18:26:56 INFO - 'USER': 'cltbld', 18:26:56 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', 18:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:56 INFO - '_': '/tools/buildbot/bin/python'} 18:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:56 INFO - Downloading/unpacking blobuploader==1.2.4 18:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:57 INFO - Downloading blobuploader-1.2.4.tar.gz 18:26:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:26:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:26:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:26:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:26:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:58 INFO - Downloading docopt-0.6.1.tar.gz 18:26:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:26:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:26:58 INFO - Installing collected packages: blobuploader, requests, docopt 18:26:58 INFO - Running setup.py install for blobuploader 18:26:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:26:58 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:26:58 INFO - Running setup.py install for requests 18:26:59 INFO - Running setup.py install for docopt 18:26:59 INFO - Successfully installed blobuploader requests docopt 18:26:59 INFO - Cleaning up... 18:26:59 INFO - Return code: 0 18:26:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:26:59 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')]} 18:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:59 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')]} 18:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:59 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 0x7f0517aedc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe786c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf2a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ed70>, '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 0xf6fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ffb0>, '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': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', '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 18:26:59 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 18:26:59 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 18:26:59 INFO - Using env: {'DISPLAY': ':0', 18:26:59 INFO - 'HOME': '/home/cltbld', 18:26:59 INFO - 'LANG': 'en_US.UTF-8', 18:26:59 INFO - 'LANGUAGE': 'en_US:en', 18:26:59 INFO - 'LOGNAME': 'cltbld', 18:26:59 INFO - 'MAIL': '/var/mail/cltbld', 18:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:59 INFO - 'MOZ_NO_REMOTE': '1', 18:26:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:59 INFO - 'NO_EM_RESTART': '1', 18:26:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:26:59 INFO - 'PWD': '/builds/slave/test-pgo', 18:26:59 INFO - 'SHELL': '/bin/bash', 18:26:59 INFO - 'SHLVL': '1', 18:26:59 INFO - 'TERM': 'linux', 18:26:59 INFO - 'TMOUT': '86400', 18:26:59 INFO - 'USER': 'cltbld', 18:26:59 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', 18:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:59 INFO - '_': '/tools/buildbot/bin/python'} 18:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:26:59 INFO - Running setup.py (path:/tmp/pip-G_OUoT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:26:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:26:59 INFO - Running setup.py (path:/tmp/pip-FmKc9b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:26:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:26:59 INFO - Running setup.py (path:/tmp/pip-bBFdGp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:26:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:26:59 INFO - Running setup.py (path:/tmp/pip-CKO_Iw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:26:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:26:59 INFO - Running setup.py (path:/tmp/pip-SF5q9v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:26:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:26:59 INFO - Running setup.py (path:/tmp/pip-oBu4z8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:26:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:26:59 INFO - Running setup.py (path:/tmp/pip-BwTNCp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:26:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:26:59 INFO - Running setup.py (path:/tmp/pip-H_UEfG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:27:00 INFO - Running setup.py (path:/tmp/pip-lMHvR1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:27:00 INFO - Running setup.py (path:/tmp/pip-SrX47r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:27:00 INFO - Running setup.py (path:/tmp/pip-COPbuO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:27:00 INFO - Running setup.py (path:/tmp/pip-Oo9PLX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:27:00 INFO - Running setup.py (path:/tmp/pip-qZr4BK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:27:00 INFO - Running setup.py (path:/tmp/pip-W1elCn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:27:00 INFO - Running setup.py (path:/tmp/pip-9LtinF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:27:00 INFO - Running setup.py (path:/tmp/pip-90lIhX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:27:00 INFO - Running setup.py (path:/tmp/pip-TxHgpu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:27:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:27:00 INFO - Running setup.py install for manifestparser 18:27:00 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:27:00 INFO - Running setup.py install for mozcrash 18:27:00 INFO - Running setup.py install for mozdebug 18:27:00 INFO - Running setup.py install for mozdevice 18:27:01 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:27:01 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:27:01 INFO - Running setup.py install for mozfile 18:27:01 INFO - Running setup.py install for mozhttpd 18:27:01 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:27:01 INFO - Running setup.py install for mozinfo 18:27:01 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:27:01 INFO - Running setup.py install for mozInstall 18:27:01 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:27:01 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:27:01 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:27:01 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:27:01 INFO - Running setup.py install for mozleak 18:27:01 INFO - Running setup.py install for mozlog 18:27:01 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:27:01 INFO - Running setup.py install for moznetwork 18:27:01 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:27:01 INFO - Running setup.py install for mozprocess 18:27:01 INFO - Running setup.py install for mozprofile 18:27:02 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:27:02 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:27:02 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:27:02 INFO - Running setup.py install for mozrunner 18:27:02 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:27:02 INFO - Running setup.py install for mozscreenshot 18:27:02 INFO - Running setup.py install for moztest 18:27:02 INFO - Running setup.py install for mozversion 18:27:02 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:27:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:27:02 INFO - Cleaning up... 18:27:02 INFO - Return code: 0 18:27:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:27:02 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')]} 18:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:02 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')]} 18:27:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:02 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 0x7f0517aedc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe786c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf2a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ed70>, '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 0xf6fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ffb0>, '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': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', '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 18:27:02 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 18:27:02 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 18:27:02 INFO - Using env: {'DISPLAY': ':0', 18:27:02 INFO - 'HOME': '/home/cltbld', 18:27:02 INFO - 'LANG': 'en_US.UTF-8', 18:27:02 INFO - 'LANGUAGE': 'en_US:en', 18:27:02 INFO - 'LOGNAME': 'cltbld', 18:27:02 INFO - 'MAIL': '/var/mail/cltbld', 18:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:02 INFO - 'MOZ_NO_REMOTE': '1', 18:27:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:02 INFO - 'NO_EM_RESTART': '1', 18:27:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:02 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:02 INFO - 'SHELL': '/bin/bash', 18:27:02 INFO - 'SHLVL': '1', 18:27:02 INFO - 'TERM': 'linux', 18:27:02 INFO - 'TMOUT': '86400', 18:27:02 INFO - 'USER': 'cltbld', 18:27:02 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', 18:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:02 INFO - '_': '/tools/buildbot/bin/python'} 18:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:27:02 INFO - Running setup.py (path:/tmp/pip-0mIfjW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:27:02 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)) 18:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:27:02 INFO - Running setup.py (path:/tmp/pip-a7ZE6B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:27:02 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)) 18:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:27:02 INFO - Running setup.py (path:/tmp/pip-X4S0Gs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:27:02 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)) 18:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:27:02 INFO - Running setup.py (path:/tmp/pip-8IzKUe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:27:03 INFO - Running setup.py (path:/tmp/pip-4XjtKD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:27:03 INFO - Running setup.py (path:/tmp/pip-4yl3IY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:27:03 INFO - Running setup.py (path:/tmp/pip-_2Xe3i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:27:03 INFO - Running setup.py (path:/tmp/pip-E23BVa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:27:03 INFO - Running setup.py (path:/tmp/pip-Dwdfx0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:27:03 INFO - Running setup.py (path:/tmp/pip-Kifvyb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:27:03 INFO - Running setup.py (path:/tmp/pip-CUmnjn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:27:03 INFO - Running setup.py (path:/tmp/pip-TajXjt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:27:03 INFO - Running setup.py (path:/tmp/pip-Vpea6J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:27:03 INFO - Running setup.py (path:/tmp/pip-GZd85j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:27:03 INFO - Running setup.py (path:/tmp/pip-uNg8sP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:27:03 INFO - Running setup.py (path:/tmp/pip-jRBRxR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:27:03 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)) 18:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:27:03 INFO - Running setup.py (path:/tmp/pip-4DJMmG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:27:03 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)) 18:27:03 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)) 18:27:03 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)) 18:27:03 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)) 18:27:03 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.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:27:03 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.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:27:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:05 INFO - Downloading blessings-1.5.1.tar.gz 18:27:05 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 18:27:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:27:05 INFO - Installing collected packages: blessings 18:27:05 INFO - Running setup.py install for blessings 18:27:05 INFO - Successfully installed blessings 18:27:05 INFO - Cleaning up... 18:27:05 INFO - Return code: 0 18:27:05 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:27:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:27:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:27:06 INFO - Reading from file tmpfile_stdout 18:27:06 INFO - Current package versions: 18:27:06 INFO - argparse == 1.2.1 18:27:06 INFO - blessings == 1.5.1 18:27:06 INFO - blobuploader == 1.2.4 18:27:06 INFO - docopt == 0.6.1 18:27:06 INFO - manifestparser == 1.1 18:27:06 INFO - mozInstall == 1.12 18:27:06 INFO - mozcrash == 0.16 18:27:06 INFO - mozdebug == 0.1 18:27:06 INFO - mozdevice == 0.47 18:27:06 INFO - mozfile == 1.2 18:27:06 INFO - mozhttpd == 0.7 18:27:06 INFO - mozinfo == 0.9 18:27:06 INFO - mozleak == 0.1 18:27:06 INFO - mozlog == 3.0 18:27:06 INFO - moznetwork == 0.27 18:27:06 INFO - mozprocess == 0.22 18:27:06 INFO - mozprofile == 0.27 18:27:06 INFO - mozrunner == 6.11 18:27:06 INFO - mozscreenshot == 0.1 18:27:06 INFO - mozsystemmonitor == 0.0 18:27:06 INFO - moztest == 0.7 18:27:06 INFO - mozversion == 1.4 18:27:06 INFO - psutil == 3.1.1 18:27:06 INFO - requests == 1.2.3 18:27:06 INFO - wsgiref == 0.1.2 18:27:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:27:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f0517aedc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe786c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf2a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ed70>, '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 0xf6fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ffb0>, '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': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', '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 18:27:06 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 18:27:06 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 18:27:06 INFO - Using env: {'DISPLAY': ':0', 18:27:06 INFO - 'HOME': '/home/cltbld', 18:27:06 INFO - 'LANG': 'en_US.UTF-8', 18:27:06 INFO - 'LANGUAGE': 'en_US:en', 18:27:06 INFO - 'LOGNAME': 'cltbld', 18:27:06 INFO - 'MAIL': '/var/mail/cltbld', 18:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:06 INFO - 'MOZ_NO_REMOTE': '1', 18:27:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:06 INFO - 'NO_EM_RESTART': '1', 18:27:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:06 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:06 INFO - 'SHELL': '/bin/bash', 18:27:06 INFO - 'SHLVL': '1', 18:27:06 INFO - 'TERM': 'linux', 18:27:06 INFO - 'TMOUT': '86400', 18:27:06 INFO - 'USER': 'cltbld', 18:27:06 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', 18:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:06 INFO - '_': '/tools/buildbot/bin/python'} 18:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:06 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)) 18:27:06 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)) 18:27:06 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)) 18:27:06 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)) 18:27:06 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)) 18:27:06 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)) 18:27:06 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)) 18:27:06 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)) 18:27:06 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)) 18:27:06 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)) 18:27:06 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)) 18:27:06 INFO - Cleaning up... 18:27:06 INFO - Return code: 0 18:27:06 INFO - Running post-action listener: _resource_record_post_action 18:27:06 INFO - Running post-action listener: _start_resource_monitoring 18:27:06 INFO - Starting resource monitoring. 18:27:06 INFO - ##### 18:27:06 INFO - ##### Running install step. 18:27:06 INFO - ##### 18:27:06 INFO - Running pre-action listener: _resource_record_pre_action 18:27:06 INFO - Running main action method: install 18:27:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:27:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:27:06 INFO - Reading from file tmpfile_stdout 18:27:06 INFO - Detecting whether we're running mozinstall >=1.0... 18:27:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:27:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:27:06 INFO - Reading from file tmpfile_stdout 18:27:06 INFO - Output received: 18:27:06 INFO - Usage: mozinstall [options] installer 18:27:06 INFO - Options: 18:27:06 INFO - -h, --help show this help message and exit 18:27:06 INFO - -d DEST, --destination=DEST 18:27:06 INFO - Directory to install application into. [default: 18:27:06 INFO - "/builds/slave/test-pgo"] 18:27:06 INFO - --app=APP Application being installed. [default: firefox] 18:27:06 INFO - mkdir: /builds/slave/test-pgo/build/application 18:27:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:27:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:27:20 INFO - Reading from file tmpfile_stdout 18:27:20 INFO - Output received: 18:27:20 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:27:20 INFO - Running post-action listener: _resource_record_post_action 18:27:20 INFO - ##### 18:27:20 INFO - ##### Running run-tests step. 18:27:20 INFO - ##### 18:27:20 INFO - Running pre-action listener: _resource_record_pre_action 18:27:20 INFO - Running main action method: run_tests 18:27:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:27:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:27:20 INFO - Python 2.7.3 18:27:20 INFO - Return code: 0 18:27:20 INFO - grabbing minidump binary from tooltool 18:27:20 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')]} 18:27:20 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 0xf6ed70>, '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 0xf6fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ffb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:27:20 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 18:27:20 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 18:27:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:27:21 INFO - Return code: 0 18:27:21 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:27:21 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:27:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:27:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:27:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:27:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:27:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost 18:27:21 INFO - Using env: {'DISPLAY': ':0', 18:27:21 INFO - 'HOME': '/home/cltbld', 18:27:21 INFO - 'LANG': 'en_US.UTF-8', 18:27:21 INFO - 'LANGUAGE': 'en_US:en', 18:27:21 INFO - 'LOGNAME': 'cltbld', 18:27:21 INFO - 'MAIL': '/var/mail/cltbld', 18:27:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:27:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:27:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:21 INFO - 'MOZ_NO_REMOTE': '1', 18:27:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:27:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:21 INFO - 'NO_EM_RESTART': '1', 18:27:21 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:21 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:21 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:27:21 INFO - 'SHELL': '/bin/bash', 18:27:21 INFO - 'SHLVL': '1', 18:27:21 INFO - 'TERM': 'linux', 18:27:21 INFO - 'TMOUT': '86400', 18:27:21 INFO - 'USER': 'cltbld', 18:27:21 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727', 18:27:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:21 INFO - '_': '/tools/buildbot/bin/python'} 18:27:21 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost'] with output_timeout 3600 18:27:21 INFO - mozversion INFO | application_buildid: 20151118154243 18:27:21 INFO - mozversion INFO | application_changeset: 91404fe9f051540726004881c023b61e1ef91684 18:27:21 INFO - mozversion INFO | application_display_name: Nightly 18:27:21 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:27:21 INFO - mozversion INFO | application_name: Firefox 18:27:21 INFO - mozversion INFO | application_remotingname: firefox 18:27:21 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 18:27:21 INFO - mozversion INFO | application_vendor: Mozilla 18:27:21 INFO - mozversion INFO | application_version: 45.0a1 18:27:21 INFO - mozversion INFO | platform_buildid: 20151118154243 18:27:21 INFO - mozversion INFO | platform_changeset: 91404fe9f051540726004881c023b61e1ef91684 18:27:21 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 18:27:21 INFO - mozversion INFO | platform_version: 45.0a1 18:27:21 INFO - 2015-11-18 18:27:21,228 DEBUG : using testdate: 1447900041 18:27:21 INFO - 2015-11-18 18:27:21,228 DEBUG : actual date: 1447900041 18:27:21 INFO - 2015-11-18 18:27:21,237 INFO : starting webserver on 'localhost:37127' 18:27:21 INFO - 2015-11-18 18:27:21,239 INFO : Starting test suite talos-linux64-ix-015 18:27:21 INFO - 2015-11-18 18:27:21,239 INFO : Starting test dromaeo_css 18:27:21 INFO - 2015-11-18 18:27:21,239 DEBUG : operating with platform_type : linux_ 18:27:21 INFO - 2015-11-18 18:27:21,240 INFO : Initialising browser for dromaeo_css test... 18:27:21 INFO - 2015-11-18 18:27:21,252 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp5SB3nt/profile http://localhost:37127/getInfo.html 18:27:26 INFO - 2015-11-18 18:27:26,665 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:27:26 INFO - 2015-11-18 18:27:26,665 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:27:26 INFO - 2015-11-18 18:27:26,665 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:27:26 INFO - 2015-11-18 18:27:26,665 DEBUG : BROWSER_OUTPUT: __metrics 18:27:26 INFO - 2015-11-18 18:27:26,753 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:27:28 INFO - 2015-11-18 18:27:28,173 INFO : Browser initialized. 18:27:28 INFO - 2015-11-18 18:27:28,173 INFO : Running cycle 1/1 for dromaeo_css test... 18:27:28 INFO - 2015-11-18 18:27:28,173 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp5SB3nt/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:27:40 INFO - 2015-11-18 18:27:40,007 DEBUG : BROWSER_OUTPUT: RSS: Main: 138981376 18:27:40 INFO - 2015-11-18 18:27:40,007 DEBUG : BROWSER_OUTPUT: 18:28:31 INFO - 2015-11-18 18:28:31,493 DEBUG : BROWSER_OUTPUT: 1447900111491 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] 18:30:40 INFO - 2015-11-18 18:30:40,057 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37127/tests/dromaeo/cssquery-ext.html) 18:30:41 INFO - 2015-11-18 18:30:41,086 DEBUG : BROWSER_OUTPUT: RSS: Main: 228155392 18:30:41 INFO - 2015-11-18 18:30:41,086 DEBUG : BROWSER_OUTPUT: 18:33:43 INFO - 2015-11-18 18:33:43,473 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/dromaeo/cssquery-ext.html (next: http://localhost:37127/tests/dromaeo/cssquery-jquery.html) 18:33:44 INFO - 2015-11-18 18:33:44,038 DEBUG : BROWSER_OUTPUT: RSS: Main: 219570176 18:33:44 INFO - 2015-11-18 18:33:44,038 DEBUG : BROWSER_OUTPUT: 18:36:45 INFO - 2015-11-18 18:36:45,836 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37127/tests/dromaeo/cssquery-mootools.html) 18:36:46 INFO - 2015-11-18 18:36:46,409 DEBUG : BROWSER_OUTPUT: RSS: Main: 226226176 18:36:46 INFO - 2015-11-18 18:36:46,409 DEBUG : BROWSER_OUTPUT: 18:39:48 INFO - 2015-11-18 18:39:48,380 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37127/tests/dromaeo/cssquery-prototype.html) 18:39:48 INFO - 2015-11-18 18:39:48,949 DEBUG : BROWSER_OUTPUT: RSS: Main: 206053376 18:39:48 INFO - 2015-11-18 18:39:48,949 DEBUG : BROWSER_OUTPUT: 18:42:51 INFO - 2015-11-18 18:42:51,434 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37127/tests/dromaeo/cssquery-yui.html) 18:42:52 INFO - 2015-11-18 18:42:52,016 DEBUG : BROWSER_OUTPUT: RSS: Main: 208388096 18:42:52 INFO - 2015-11-18 18:42:52,016 DEBUG : BROWSER_OUTPUT: 18:45:54 INFO - 2015-11-18 18:45:54,580 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/dromaeo/cssquery-yui.html (next: http://localhost:37127/tests/dromaeo/cssquery-dojo.html) 18:45:54 INFO - 2015-11-18 18:45:54,826 DEBUG : BROWSER_OUTPUT: RSS: Main: 313131008 18:45:54 INFO - 2015-11-18 18:45:54,826 DEBUG : BROWSER_OUTPUT: 18:45:54 INFO - 2015-11-18 18:45:54,826 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:45:54 INFO - 2015-11-18 18:45:54,827 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:45:54 INFO - 2015-11-18 18:45:54,827 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:45:54 INFO - 2015-11-18 18:45:54,827 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:45:54 INFO - 2015-11-18 18:45:54,828 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3191.744660427166;3262.6084869815627;3295.2091498040477;3303.587051618548;3308.619508756494;5197.740112994345;5323.414424413316;5364.678119312844;5377;5377.381601115868;5418.539424148952;5447.373552041517;5479.424660410659;5480.068388373976;5502.477264659861;4891.315215869781;4964.975175124119;4978.8257410990445;5097.898042039157;5124.282600435942;4913.656044076916;5047.747612619354;5078.162103252977;5098.617603679739;5122.871928201806;4932.926006109911;4997.350344455216;5031;5032.6477146599755;5048.545630893237;4950.3069570260195;4985.576226020793;4990.226514890198;4997.675151115166;5015.39815222172;4810.230363141881;4917.901641967141;4951.90096198074;4990.850274491771;5036.11867923112;5223.582113430954;5302.1781623848365;5336.306280183551;5340.385855626575;5367.355917289911;5893.086571581412;5925.229720136354;5927.051671732502;5930.347661757203;5934.703264836784;5802.912956305659;5805.767769289223;5927.703614819242;5928.703564821785;5940.34656187819;5549.361823390281;5555.694436805974;5558.749856256478;5570.108782594806;5618.971905140448;6004.519638428916;6020.458158765732;6024.096385542204;6080.908786368208;6086.360932102154;5712.257406537124;5767.22142510764;5818.359980402152;5884.852878678067;5885.176075349455;5805.071188609802;5841.357450680422;5844.415558444121;5864.970675146682;5912.17229587858;5830.883382332329;5831.446012628793;5832.679202643811;5839.240898683144;5844.620099693507;5706.942930570641;5725.456081672234;5811.15738217956;5815.76736930518;5865.384134665798;4560.863174104782;4563.973106051112;4565.543445655474;4566.429196350457;4610.147122782296;4091.91816163681;4092.959070409258;4095.9180816383505;4101.26177288085;4140.213359461693;5466.835994920159;5576.86057848557;5586.55307575393;5636.520895723827;5691.231683722644;5887.234659494238;5903.527717782653;5903.675297858659;5905.202797622266;5915.231019967377;5498.422665620053;5575.470330318613;5582.748776305076;5585.134304182858;5586.190002449704;5005.374328208974;5063.620228482805;5099;5148.253503242084;5150.072986862401;6074.574779765462;6086.7260973255425;6090.086487026892;6091.5126789857595;6102.115193296947;5717.485426311652;5883.058710606282;5886.705664716833;5890.705464726832;5894.233749612695;6024.698765061642;6033.818985430444;6062.211912451353;6089.295106532991;6127.969360153171;5852.3269823969085;5869.73586188614;5878.118282257696;5880.382559831156;5898.380670029756;5795.565332600157;5961.105834124916;5965.761369545169;5968.015277479168;5981.371955944737;5690.089585666274;5736.340320863154;5763.884722305615;5794.739236734441;5843.094320380348;5728.914066288926;5765.56758243125;5769.9711501442225;5777.335606405317;5785.161151633074;5546.584006199632;5676.262085928803;5687.914681279702;5693.345265294544;5695.174199741097;5681.914771278517;5790.073588225863;5810.186573879576;5839.065749480063;5851.385604511635;5710.628809127394;5713.8285851424525;5717.656940583578;5728.198052272768;5768.1636162757 18:45:54 INFO - 2015-11-18 18:45:54,828 DEBUG : BROWSER_OUTPUT: |1;ext.html;598.6109029131065;606.5026678123938;606.8240210339002;615.1080932647662;618.2210414877259;31578.526322105157;39779.2044159117;40573.59426405735;40604.390934135976;40639;43114.7844260779;43662.56337436625;43686.56313436865;43715.56284437155;43996.780016099874;7594.253321736004;7713.498622589542;8710.041895391518;8896.37725359225;8903.287736981027;10443.112335451497;10623.734406639818;10636.78726425471;10689.83965240522;10691.411972341519;10328.845067323959;10383.688489345332;10393.220508461858;10396.688099357032;10407.73980650486;7830.099538553054;7998.600069996477;10341.27611067222;10405.687829365132;10711.143108551298;10200.795984080276;10286.897131028669;10344.275900686955;10347.172226221885;10351.948240258751;40055.79972100121;40284.39573406401;40521.79739101315;40635.593644063476;40666;3354.983225083859;3407.5910890693026;3447.8620855165764;3688.354537955847;3723.7393382463238;30283;30336.8483157585;30390.392192156254;30952.8452357739;31417.528737068962;3388.519110017754;3481.9825900870333;3486.5467489226494;3494.458358954341;3547.7693949893173;272600;272716;273939;274559;274989;51304.48695512997;52206;57667.423325766205;57965.42034579685;58050.419495804505;66746.33253667402;67462;67859;67968.66015669989;68021.65989170122;13720.519781807589;13885.097468664504;13885.652858678412;13896.791548126786;13899.374528146258;10928.398938058324;11036.392998385167;11040.889591104147;11070.39112848801;11072.667819965414;18807;18841.340553080576;18885.244590216236;18919;18950.24199032023;7865.803354916173;8078.313343365762;8160.30637395828;8186.959065204635;8268.793280168044;903.1916434790826;991.4992928571025;991.5141580625442;997.1673652500099;1098.2477003252798;28576.285592859927;28672.13983580496;28675;28920.83690806209;29163.41673166525;1017.5014243021014;1046.99476502617;1052.1162223732101;1065.174489770434;1124.8481455003634;14840.480583179537;15082.472113475975;15259.847401526065;15287.464938727091;15594.376224951102;272825;276904;277431;277457;277764;49644;49877.25184122241;49889;49915;50005;1299.5191779041816;1326.5754958413409;1333.2333908002938;1333.8065980432978;1340.2360654426884;26696.466070678867;28561.28596785097;28650;28884.42231155407;28889;44851;44937.550624494645;45002.32496512489;45021;45052.77473612742;304129;305394;305427;306045;306452;28141.763697175087;31001.37997240088;31326.843365783938;31440.213994649403;31756.20609484689;37811;38393;38830.4175437363;38865.6113438862;39008.21983560256;34709.47935780915;34923.65076349204;35023.824880875436;35070.47394289139;35680.464793027604;28656.28359290951;33082.83458582692;33634.15914602154;34606;35470.822645886605;32108.197295066875;32439.513407298437;32487.350252995282;35925.64074359223;36240.275194495436;29885.252868678457;32370;33036.33927321488;33325.33349333048;33344.66655333416;24814.37964050913;25131.623025654997;25183.24450266495;25279.494410112064;25302.987880484576 18:45:54 INFO - 2015-11-18 18:45:54,829 DEBUG : BROWSER_OUTPUT: |2;jquery.html;357.70310642167004;358.42651757188503;359.5218359581753;361.33694670280033;364.82123759357904;13818;14024.439022439115;16722.33110675573;17105.914470427648;17708.91145544272;11784.941075294633;12165.08761842861;16492.17539123042;17273.654526909453;17287.30850765971;4482.23801953667;4584.4498660160825;4587.266037433995;4594.839180628679;4595.816167353302;3558.946615800752;3629.1652919828457;4521.706089104197;4557.908841823161;4896.43690975537;4888.462269150391;4914;4935.259711043336;4936.06214819185;4972.428170760355;4656.883577910546;4775.80896764129;4914.090893184754;4920.941997470543;4923.335349727777;4139;4140.213359461723;4142.357934520154;4147.97926010368;4149.751014939114;12957.352132393342;13412.865871341357;13438.865611343957;13445.126066805626;13471.932640336834;1839.9724004139948;1887.8395336396286;1937.8740381875134;1961.4802077449488;1993.8404927605757;9693.515324233831;9731.075547822946;9920.107190352903;9937;10055;1608.4450864451746;1652.4877288040745;1690;1729;1748.2133040131864;60804;62258.37741622526;62394;62481.68759156221;62939.685301573656;6951.374376306157;7062.823429414306;7070.257622949516;7095.077639906779;7121.857562848714;8344.290735287446;8433.03020152678;8438.324934005264;8457.703980360659;8477.703280385156;5507.752151153208;5509.724513774296;5511.476409741068;5515.338159420895;5553.500184983371;5624.409437009137;5645.407232240637;5667.716614169275;5674.893395787864;5678.835838653066;3263.7878537894962;3287.2767991041933;3291.358185153872;3299.4555898276517;3315.5689760331;1128.1651577832508;1141.0985321596013;1145.4559084434827;1160.2516376936828;1203.7351782607811;461.8406649705846;464.30354468297554;473.2522614269446;479.99520004800206;490.8453837041321;10660.307080039773;11371;11493.482793274155;11516.193866429438;11791.88208117908;438.1565486438657;478.10832796833654;481.3549843210115;491.85736136520006;500.0499051801606;5999.820005399845;6174.536909731788;6360.936390636127;6382.234131904202;6400.167978162809;508750;511775;515172;515874;532404;75597.9999999989;76351;76452;76564.61717691488;77474;522.3653261287554;527.2039220776655;527.5568522441168;527.59111688441;527.7994362142362;10157.593696252363;10375.740606484898;10568.788624227318;10584.153267738408;10697.732556686145;35674;35977.38101785374;36205;36394.27211455809;36459;46973.72630568707;47691;48796.5120348792;48977.755111224214;49751;6707.228668703162;6794.252632210551;6862.897056544056;6871.209810871814;6913.654317284217;12247.142700010914;12267.202631828903;12409.634940156395;12510.061745368952;12549.937250314055;10708.625198118028;10745.892541074489;10993.120550356112;11024.228304018641;11051.060659844003;10431.843522347323;10610.363378197333;10863.511141998471;10865.402402867918;11158.163137764537;10491.265611407129;10680.519376628226;10683.465826708789;10874;10930.72673183145;6711.094002309821;6834.589924604539;6859.691313890787;6860.142482189726;6861.2795656455355;10418.843717344398;10657.840132398176;10840.186985975926;10915.672529824118;10936.671899843017 18:45:54 INFO - 2015-11-18 18:45:54,830 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1381.468134768114;1482.7775833624958;1559.828418873924;1688.3753011385788;1810.3211295764088;6796.116504854368;7278.381337586305;7436.925630743691;7455.2172021937595;7460.888086678698;7475.364594009516;7597.2402759724;7600.5059671121335;7621.504602200853;7621.695132194719;2003.1286390420214;2034.8168664820164;2126.553423781;2249.4601295689;2288.6109361408517;2297.5749486345;2307.6077066898583;2308.042162502559;2309.6535519672098;2314.9698384218946;2185.8797766122857;2256.8645881247094;2257.119723307911;2258.5883822610867;2259.1076524772707;1981.603679264153;2033.4103110098054;2240.5630901974123;2281.908723651052;2290.713660792401;2196.769339219375;2209.7348318201757;2227.8663280203077;2234.530748542808;2260.8530445520987;7639.579823109727;7680.078390593108;7710.498817576839;7711.807204819925;7716.7684969451;4696.577308042259;4792.209285467929;4799.1601469743;4799.7360145192;4801.18379875422;3100.914142319431;3117.267442151093;3127.8279694616785;3168.588083549147;3170;980.9705708828747;983.3362480325823;1008.7831116310028;1012.2256473797551;1013.8276492996208;10541.73645658857;10547.84178237327;10560.47197640115;10564.26050176488;10565.15478761705;4233.428487154257;4270.487541494978;4323.870283891489;4376.846810361622;4390.3414487827085;9150.81698366029;9168.908310916806;9178.954105229564;9188.58651360677;9191;4756.7145971241835;4764.118638051971;4765.451973023147;4776.331313616097;4783.1868582341085;4359.346098085312;4361.454818147731;4387.363832244307;4394.252976993918;4397.560243975641;3681.963180368162;3719.8512059517316;3740.251949610089;3742.8315725792404;3786.73492855503;1179.9705007374712;1193.8209268609778;1199.3403628004562;1213.3144773203112;1214.7813393589238;977.4770497783652;1296.0603562417173;1298.6363818130937;1315.81578578999;1319.2678063674562;6547.541672082908;7324.743633972786;7563;7567.394608431422;7574.394048476109;696.8606278744271;703.6692754405422;1183.928964262147;1231.322772475153;1254.2975933477283;5636.774529018875;5729.2265544151005;5737.08206686928;5743.569232307594;5745.942540574624;8923.553822308988;9005.324600654978;9015.27877769777;9058.5017824019;9160.541972901328;8956.149165829236;8956.596953137125;9042.412243204171;9127.863082053775;9135.908640913505;7148.428125749928;7191.604461754654;7237.167725711505;7247.05788247535;7298.306660867315;7176.605286709281;7223.313785190398;7246.818829529093;7254.310840470147;7267.418606511361;8021.077576078826;8038.316743076904;8322.791930201793;8347.248747612744;8361.707340243061;8242;8298.253157215879;8385.95807020961;8386.70646527393;8414.116517765546;6482.384173503509;6702.162229721285;6704.731810727516;6716.3955244028275;6716.597004179765;7289.854202916018;7391.0761154855645;7394.926050739639;7424.294692004251;7432.479726419315;6965.47758918073;7093.467989900675;7150.785476435715;7206.89189662166;7235.2764723529335;7021.613811240227;7056.682449289691;7111;7129.750458733919;7167.283271673;6415.3263907289065;6536.477081833346;6554.279029306867;7088.184858741104;7090.042844215965;6303.180586523906;6508.153939987771;6538.476921846336;6543.312952140147;6556.967215164084;6532.085508028975;6559.376859198369;6599.241087275025;6613.570117942319;6620.569662971891 18:45:54 INFO - 2015-11-18 18:45:54,831 DEBUG : BROWSER_OUTPUT: |4;prototype.html;320.06700468135386;324.68019001283733;329.03920551988193;330.87922908138523;331.61863856564946;5888.234529511163;5899.380565040673;5938.910916336253;5951.851203719898;5996.8200953971345;5858.5606079543995;5877.794277200299;5890.999999999989;5892.322382925965;5896.616719913213;4505.436820397458;4512.661550383735;4518.096380723852;4523.117991991563;4524;4362.040351122764;4514.413126293577;4524.3213517972235;4535.659825513099;4539.978504836402;4474.261746811772;4483.327500874862;4483.3723278741;4491.932621010687;4517.751523666197;4485;4500;4508.504064552896;4514.097180563884;4514.142312960544;3708.9443658345144;3794.4118661607495;3798.1074447504816;3810.942835857464;3813.6186381361917;5578.856334451428;5694.658320500783;5713.4000929902595;5729.942700572982;5733.512651424592;5234.8691282717855;5259.105951988171;5266.1837415200325;5270.604704647167;5285.86785330366;5865.325487568942;6054.394560543955;6232.470240029603;6400.583962042452;6409.359064093554;4305.806238719234;4311.611954924072;4319.52485226628;4322.373255877911;4325.048489332342;6572.86854262912;6645.966770166118;6651.301613330627;6655.93344066558;6656.467482601429;4479.59683628475;4486.205517792909;4490.663200259994;4492.618127459203;4494.662900282492;4407.823687052482;4521.118381915495;4537.478190008125;4540.636749060067;4552.135094332132;5301.125314323171;5317.760700768475;5318.97340513295;5325.041492531304;5328.786848526015;6380.170577824853;6430.453411460078;6434.903476447857;6448.290688024314;6450.903236451457;1556.688662267551;1570.0736565426225;1571.8761085823692;1572.1196130167143;1572.3710515793546;1022.7801022780138;1024.5696807340935;1031.4224034540532;1038.9740256493649;1055.087349442742;6212.689365531795;6225;6237.407446292595;6242.469390101892;6242.65665388408;6605.174353205849;6610.4050635443045;6611.338866113447;6621.106150669694;6624.337566243337;6087.391260873877;6226.1905952225925;6228.190335256388;6235;6235.937640623626;3560.127768696686;3587.2466781976077;3592.3713350163616;3595.8921232363073;3598.478220658042;6620.933790662128;6702.430293425016;6754.763583274562;6806.931930680729;6815.386615204656;6848.417884479875;6850.143732033496;6857.657117144223;6860;6876.3526042473;6762.120924279812;6916.585004899722;6921.688524016431;6925.549839260432;6930.203026651899;6654.201495820533;6677;6681.198256209286;6683.064370988162;6688.83277918056;6635.966820165868;6636.668166591748;6642.435392991457;6646.2356828965285;6650.83372915681;6786.558873673195;6928.237893831775;6935.618540980093;6954.095967524392;6962.1297337832775;4449.755263460541;4485.955140448554;4486.573775491323;4492.842750503717;4495.280755079171;6640.76757313511;6646.90029649546;6656;6662.733490660513;6670;6275.937240627535;6474.1907261592305;6479.254885688206;6479.319671434431;6481.708323125565;6470.547061705635;6478.4169424752;6478.902816457851;6481.935180648322;6484.5460817742305;6351.872962540816;6360.459360954371;6363.172787537591;6367.172267605367;6375.394337537927;6350.079238510482;6489.480841532761;6500.967495162494;6504.121943537752;6505.447037001719;6332.335104813928;6340.112384266298;6340.112384266298;6349.555531112963;6353.77761778354 18:45:54 INFO - 2015-11-18 18:45:54,832 DEBUG : BROWSER_OUTPUT: |5;yui.html;694.8992396102565;733.2887099513473;814.1329484099433;818.3412353055791;857.6312185760121;13178.406971686272;13400.93299533501;13461.1923284603;13494.190348579079;13506.122102063358;11588.884111158886;11611.767764644703;11642.592509262178;11655.650330490098;11690.707732306697;615.47991946805;638.5881106686185;768.6656304507529;792.7344339646226;795.6817273090782;803.0323460230437;803.6865622407237;808.8827120067587;809.8218391953761;810.1372038778711;676.7326905872178;701.9157701075878;842.2041171093289;847.8389106069858;848.9278411335044;794.3406972213078;827.6234313387455;827.962741676626;828.49461828285;828.6229765456703;607.6809674920657;609.3875654966774;611.034565386688;611.1657587393221;611.6177389131792;12528.937355313255;12623.558175463815;12625.24248545081;12640.304783236918;12655.493780248778;11980.940095299555;12443.066769992287;12565.497380104785;12600.243985360908;12627.93686031564;8760.693375731882;8923.375363724543;8945.46327220369;8986.505742184178;9008.774780630536;3545.9290814183573;3562.3231585998838;3562.3943929532043;3564.3584154852115;3580.6777390034763;45330.54669453296;45678.5432145681;47950.760246198544;48411.51588484105;48417.03165936697;10145.746356341004;10150.137238334677;10162.796744065077;10205.336653117673;10389.324693894872;17106.657866842594;17129.400470983455;17146.82853171477;17187.65624687499;17196.31214751421;6965.05971693825;7044.6125463100025;7046.541974771623;7091.326323999212;7159.928400715926;3904.3362628353248;4072.3755857287038;4160.729552579072;4163.770992595376;4170.7706076166105;3137.3568418474156;3143.0413723814454;3173.841307934594;3177.507486339621;3185.2355434695505;483.7363636817943;499.0567826807337;502.12880652068634;503.2099603622351;511.2688854937475;560.1653536230987;625.0186017440988;632.6046221111806;642.5084810120262;713.9740589425235;8341.45780524264;8344.165583441607;8478.74563763088;8496.915030849736;8498.320134389234;516.8113339319625;563.2818156850049;620.6593757483813;621.9129321894939;665.9034440006269;3879.612038796154;3920.5883382244456;3961.2473630010204;4086.693497987663;4134.421181034658;55991;57370;57416.42583574111;60377.69811150916;60420.39579604236;50342.74828625907;50999.74500127476;51055.744721276154;56713.14930276049;56888.146677799865;50823;51272.74363628158;51511.22733159005;51667.48332516702;52601.7369913148;8485.703000395204;8621.82756344857;8695.173958473935;8752.643549738617;8797.824043519222;34716.30567388688;35186.296274073866;35656;35791.82104089463;35914.28171436609;43778.12443751171;45816.77091614521;46207.53792462032;46229;46516;11190.272632278877;11304.943475282571;11359.318440893572;11464.885351146382;11794.52821887115;12402.25586464815;12416;12544.686382840502;12630;12965.870341296844;10858.67423977282;11224.66326010221;11868.703282418008;12242;12244.938775306422;10726.249162558857;10794.622188223375;11246.212765106364;11259.774804504028;11421.657350279505;7667.211162609603;10685.751670268468;10803.081738052353;11245.325280483197;11382.829257561309;9934.751631209278;10145.9492702536;10198.694039178836;10243.07812296897;10453.477326133814;8682.218600326001;9383.483908384831;9693.369930954465;9760.951195243979;9784.119429251401 18:45:54 INFO - 2015-11-18 18:45:54,833 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:45:54 INFO - 2015-11-18 18:45:54,833 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:45:54 INFO - 2015-11-18 18:45:54,833 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,801 18:45:54 INFO - 2015-11-18 18:45:54,833 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:45:54 INFO - 2015-11-18 18:45:54,833 DEBUG : BROWSER_OUTPUT: __startTimestamp1447901154825__endTimestamp 18:45:54 INFO - 2015-11-18 18:45:54,833 DEBUG : BROWSER_OUTPUT: 18:45:54 INFO - 2015-11-18 18:45:54,833 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:45:54 INFO - 2015-11-18 18:45:54,833 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:45:54 INFO - 2015-11-18 18:45:54,834 DEBUG : BROWSER_OUTPUT: 18:45:54 INFO - 2015-11-18 18:45:54,834 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5468.24 Median:5706.94 stddev:603.47 (10.6%) stddev-sans-first:578.29 18:45:54 INFO - 2015-11-18 18:45:54,834 DEBUG : BROWSER_OUTPUT: Values: 3191.7 3262.6 3295.2 3303.6 3308.6 5197.7 5323.4 5364.7 5377.0 5377.4 5418.5 5447.4 5479.4 5480.1 5502.5 4891.3 4965.0 4978.8 5097.9 5124.3 4913.7 5047.7 5078.2 5098.6 5122.9 4932.9 4997.4 5031.0 5032.6 5048.5 4950.3 4985.6 4990.2 4997.7 5015.4 4810.2 4917.9 4951.9 4990.9 5036.1 5223.6 5302.2 5336.3 5340.4 5367.4 5893.1 5925.2 5927.1 5930.3 5934.7 5802.9 5805.8 5927.7 5928.7 5940.3 5549.4 5555.7 5558.7 5570.1 5619.0 6004.5 6020.5 6024.1 6080.9 6086.4 5712.3 5767.2 5818.4 5884.9 5885.2 5805.1 5841.4 5844.4 5865.0 5912.2 5830.9 5831.4 5832.7 5839.2 5844.6 5706.9 5725.5 5811.2 5815.8 5865.4 4560.9 4564.0 4565.5 4566.4 4610.1 4091.9 4093.0 4095.9 4101.3 4140.2 5466.8 5576.9 5586.6 5636.5 5691.2 5887.2 5903.5 5903.7 5905.2 5915.2 5498.4 5575.5 5582.7 5585.1 5586.2 5005.4 5063.6 5099.0 5148.3 5150.1 6074.6 6086.7 6090.1 6091.5 6102.1 5717.5 5883.1 5886.7 5890.7 5894.2 6024.7 6033.8 6062.2 6089.3 6128.0 5852.3 5869.7 5878.1 5880.4 5898.4 5795.6 5961.1 5965.8 5968.0 5981.4 5690.1 5736.3 5763.9 5794.7 5843.1 5728.9 5765.6 5770.0 5777.3 5785.2 5546.6 5676.3 5687.9 5693.3 5695.2 5681.9 5790.1 5810.2 5839.1 5851.4 5710.6 5713.8 5717.7 5728.2 5768.2 18:45:54 INFO - 2015-11-18 18:45:54,834 DEBUG : BROWSER_OUTPUT: 18:45:54 INFO - 2015-11-18 18:45:54,834 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45561.40 Median:31667.37 stddev:74527.71 (235.3%) stddev-sans-first:74660.31 18:45:54 INFO - 2015-11-18 18:45:54,834 DEBUG : BROWSER_OUTPUT: Values: 598.6 606.5 606.8 615.1 618.2 31578.5 39779.2 40573.6 40604.4 40639.0 43114.8 43662.6 43686.6 43715.6 43996.8 7594.3 7713.5 8710.0 8896.4 8903.3 10443.1 10623.7 10636.8 10689.8 10691.4 10328.8 10383.7 10393.2 10396.7 10407.7 7830.1 7998.6 10341.3 10405.7 10711.1 10200.8 10286.9 10344.3 10347.2 10351.9 40055.8 40284.4 40521.8 40635.6 40666.0 3355.0 3407.6 3447.9 3688.4 3723.7 30283.0 30336.8 30390.4 30952.8 31417.5 3388.5 3482.0 3486.5 3494.5 3547.8 272600.0 272716.0 273939.0 274559.0 274989.0 51304.5 52206.0 57667.4 57965.4 58050.4 66746.3 67462.0 67859.0 67968.7 68021.7 13720.5 13885.1 13885.7 13896.8 13899.4 10928.4 11036.4 11040.9 11070.4 11072.7 18807.0 18841.3 18885.2 18919.0 18950.2 7865.8 8078.3 8160.3 8187.0 8268.8 903.2 991.5 991.5 997.2 1098.2 28576.3 28672.1 28675.0 28920.8 29163.4 1017.5 1047.0 1052.1 1065.2 1124.8 14840.5 15082.5 15259.8 15287.5 15594.4 272825.0 276904.0 277431.0 277457.0 277764.0 49644.0 49877.3 49889.0 49915.0 50005.0 1299.5 1326.6 1333.2 1333.8 1340.2 26696.5 28561.3 28650.0 28884.4 28889.0 44851.0 44937.6 45002.3 45021.0 45052.8 304129.0 305394.0 305427.0 306045.0 306452.0 28141.8 31001.4 31326.8 31440.2 31756.2 37811.0 38393.0 38830.4 38865.6 39008.2 34709.5 34923.7 35023.8 35070.5 35680.5 28656.3 33082.8 33634.2 34606.0 35470.8 32108.2 32439.5 32487.4 35925.6 36240.3 29885.3 32370.0 33036.3 33325.3 33344.7 24814.4 25131.6 25183.2 25279.5 25303.0 18:45:54 INFO - 2015-11-18 18:45:54,834 DEBUG : BROWSER_OUTPUT: 18:45:54 INFO - 2015-11-18 18:45:54,835 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26409.44 Median:4539.81 stddev:84804.49 (1868.0%) stddev-sans-first:85019.83 18:45:54 INFO - 2015-11-18 18:45:54,836 DEBUG : BROWSER_OUTPUT: Values: 357.7 358.4 359.5 361.3 364.8 13818.0 14024.4 16722.3 17105.9 17708.9 11784.9 12165.1 16492.2 17273.7 17287.3 4482.2 4584.4 4587.3 4594.8 4595.8 3558.9 3629.2 4521.7 4557.9 4896.4 4888.5 4914.0 4935.3 4936.1 4972.4 4656.9 4775.8 4914.1 4920.9 4923.3 4139.0 4140.2 4142.4 4148.0 4149.8 12957.4 13412.9 13438.9 13445.1 13471.9 1840.0 1887.8 1937.9 1961.5 1993.8 9693.5 9731.1 9920.1 9937.0 10055.0 1608.4 1652.5 1690.0 1729.0 1748.2 60804.0 62258.4 62394.0 62481.7 62939.7 6951.4 7062.8 7070.3 7095.1 7121.9 8344.3 8433.0 8438.3 8457.7 8477.7 5507.8 5509.7 5511.5 5515.3 5553.5 5624.4 5645.4 5667.7 5674.9 5678.8 3263.8 3287.3 3291.4 3299.5 3315.6 1128.2 1141.1 1145.5 1160.3 1203.7 461.8 464.3 473.3 480.0 490.8 10660.3 11371.0 11493.5 11516.2 11791.9 438.2 478.1 481.4 491.9 500.0 5999.8 6174.5 6360.9 6382.2 6400.2 508750.0 511775.0 515172.0 515874.0 532404.0 75598.0 76351.0 76452.0 76564.6 77474.0 522.4 527.2 527.6 527.6 527.8 10157.6 10375.7 10568.8 10584.2 10697.7 35674.0 35977.4 36205.0 36394.3 36459.0 46973.7 47691.0 48796.5 48977.8 49751.0 6707.2 6794.3 6862.9 6871.2 6913.7 12247.1 12267.2 12409.6 12510.1 12549.9 10708.6 10745.9 10993.1 11024.2 11051.1 10431.8 10610.4 10863.5 10865.4 11158.2 10491.3 10680.5 10683.5 10874.0 10930.7 6711.1 6834.6 6859.7 6860.1 6861.3 10418.8 10657.8 10840.2 10915.7 10936.7 18:45:54 INFO - 2015-11-18 18:45:54,836 DEBUG : BROWSER_OUTPUT: 18:45:54 INFO - 2015-11-18 18:45:54,836 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5409.87 Median:6537.48 stddev:2766.32 (42.3%) stddev-sans-first:2757.50 18:45:54 INFO - 2015-11-18 18:45:54,836 DEBUG : BROWSER_OUTPUT: Values: 1381.5 1482.8 1559.8 1688.4 1810.3 6796.1 7278.4 7436.9 7455.2 7460.9 7475.4 7597.2 7600.5 7621.5 7621.7 2003.1 2034.8 2126.6 2249.5 2288.6 2297.6 2307.6 2308.0 2309.7 2315.0 2185.9 2256.9 2257.1 2258.6 2259.1 1981.6 2033.4 2240.6 2281.9 2290.7 2196.8 2209.7 2227.9 2234.5 2260.9 7639.6 7680.1 7710.5 7711.8 7716.8 4696.6 4792.2 4799.2 4799.7 4801.2 3100.9 3117.3 3127.8 3168.6 3170.0 981.0 983.3 1008.8 1012.2 1013.8 10541.7 10547.8 10560.5 10564.3 10565.2 4233.4 4270.5 4323.9 4376.8 4390.3 9150.8 9168.9 9179.0 9188.6 9191.0 4756.7 4764.1 4765.5 4776.3 4783.2 4359.3 4361.5 4387.4 4394.3 4397.6 3682.0 3719.9 3740.3 3742.8 3786.7 1180.0 1193.8 1199.3 1213.3 1214.8 977.5 1296.1 1298.6 1315.8 1319.3 6547.5 7324.7 7563.0 7567.4 7574.4 696.9 703.7 1183.9 1231.3 1254.3 5636.8 5729.2 5737.1 5743.6 5745.9 8923.6 9005.3 9015.3 9058.5 9160.5 8956.1 8956.6 9042.4 9127.9 9135.9 7148.4 7191.6 7237.2 7247.1 7298.3 7176.6 7223.3 7246.8 7254.3 7267.4 8021.1 8038.3 8322.8 8347.2 8361.7 8242.0 8298.3 8386.0 8386.7 8414.1 6482.4 6702.2 6704.7 6716.4 6716.6 7289.9 7391.1 7394.9 7424.3 7432.5 6965.5 7093.5 7150.8 7206.9 7235.3 7021.6 7056.7 7111.0 7129.8 7167.3 6415.3 6536.5 6554.3 7088.2 7090.0 6303.2 6508.2 6538.5 6543.3 6557.0 6532.1 6559.4 6599.2 6613.6 6620.6 18:45:54 INFO - 2015-11-18 18:45:54,836 DEBUG : BROWSER_OUTPUT: 18:45:54 INFO - 2015-11-18 18:45:54,836 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5332.69 Median:6025.61 stddev:1647.76 (27.3%) stddev-sans-first:1608.86 18:45:54 INFO - 2015-11-18 18:45:54,837 DEBUG : BROWSER_OUTPUT: Values: 320.1 324.7 329.0 330.9 331.6 5888.2 5899.4 5938.9 5951.9 5996.8 5858.6 5877.8 5891.0 5892.3 5896.6 4505.4 4512.7 4518.1 4523.1 4524.0 4362.0 4514.4 4524.3 4535.7 4540.0 4474.3 4483.3 4483.4 4491.9 4517.8 4485.0 4500.0 4508.5 4514.1 4514.1 3708.9 3794.4 3798.1 3810.9 3813.6 5578.9 5694.7 5713.4 5729.9 5733.5 5234.9 5259.1 5266.2 5270.6 5285.9 5865.3 6054.4 6232.5 6400.6 6409.4 4305.8 4311.6 4319.5 4322.4 4325.0 6572.9 6646.0 6651.3 6655.9 6656.5 4479.6 4486.2 4490.7 4492.6 4494.7 4407.8 4521.1 4537.5 4540.6 4552.1 5301.1 5317.8 5319.0 5325.0 5328.8 6380.2 6430.5 6434.9 6448.3 6450.9 1556.7 1570.1 1571.9 1572.1 1572.4 1022.8 1024.6 1031.4 1039.0 1055.1 6212.7 6225.0 6237.4 6242.5 6242.7 6605.2 6610.4 6611.3 6621.1 6624.3 6087.4 6226.2 6228.2 6235.0 6235.9 3560.1 3587.2 3592.4 3595.9 3598.5 6620.9 6702.4 6754.8 6806.9 6815.4 6848.4 6850.1 6857.7 6860.0 6876.4 6762.1 6916.6 6921.7 6925.5 6930.2 6654.2 6677.0 6681.2 6683.1 6688.8 6636.0 6636.7 6642.4 6646.2 6650.8 6786.6 6928.2 6935.6 6954.1 6962.1 4449.8 4486.0 4486.6 4492.8 4495.3 6640.8 6646.9 6656.0 6662.7 6670.0 6275.9 6474.2 6479.3 6479.3 6481.7 6470.5 6478.4 6478.9 6481.9 6484.5 6351.9 6360.5 6363.2 6367.2 6375.4 6350.1 6489.5 6501.0 6504.1 6505.4 6332.3 6340.1 6340.1 6349.6 6353.8 18:45:54 INFO - 2015-11-18 18:45:54,837 DEBUG : BROWSER_OUTPUT: 18:45:54 INFO - 2015-11-18 18:45:54,837 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13884.74 Median:24217.25 stddev:16504.09 (68.2%) stddev-sans-first:16520.66 18:45:54 INFO - 2015-11-18 18:45:54,838 DEBUG : BROWSER_OUTPUT: Values: 694.9 733.3 814.1 818.3 857.6 13178.4 13400.9 13461.2 13494.2 13506.1 11588.9 11611.8 11642.6 11655.7 11690.7 615.5 638.6 768.7 792.7 795.7 803.0 803.7 808.9 809.8 810.1 676.7 701.9 842.2 847.8 848.9 794.3 827.6 828.0 828.5 828.6 607.7 609.4 611.0 611.2 611.6 12528.9 12623.6 12625.2 12640.3 12655.5 11980.9 12443.1 12565.5 12600.2 12627.9 8760.7 8923.4 8945.5 8986.5 9008.8 3545.9 3562.3 3562.4 3564.4 3580.7 45330.5 45678.5 47950.8 48411.5 48417.0 10145.7 10150.1 10162.8 10205.3 10389.3 17106.7 17129.4 17146.8 17187.7 17196.3 6965.1 7044.6 7046.5 7091.3 7159.9 3904.3 4072.4 4160.7 4163.8 4170.8 3137.4 3143.0 3173.8 3177.5 3185.2 483.7 499.1 502.1 503.2 511.3 560.2 625.0 632.6 642.5 714.0 8341.5 8344.2 8478.7 8496.9 8498.3 516.8 563.3 620.7 621.9 665.9 3879.6 3920.6 3961.2 4086.7 4134.4 55991.0 57370.0 57416.4 60377.7 60420.4 50342.7 50999.7 51055.7 56713.1 56888.1 50823.0 51272.7 51511.2 51667.5 52601.7 8485.7 8621.8 8695.2 8752.6 8797.8 34716.3 35186.3 35656.0 35791.8 35914.3 43778.1 45816.8 46207.5 46229.0 46516.0 11190.3 11304.9 11359.3 11464.9 11794.5 12402.3 12416.0 12544.7 12630.0 12965.9 10858.7 11224.7 11868.7 12242.0 12244.9 10726.2 10794.6 11246.2 11259.8 11421.7 7667.2 10685.8 10803.1 11245.3 11382.8 9934.8 10145.9 10198.7 10243.1 10453.5 8682.2 9383.5 9693.4 9761.0 9784.1 18:45:54 INFO - 2015-11-18 18:45:54,838 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:45:54 INFO - 2015-11-18 18:45:54,838 DEBUG : BROWSER_OUTPUT: 18:45:55 INFO - 2015-11-18 18:45:55,581 INFO : Browser exited with error code: 0 18:45:55 INFO - 2015-11-18 18:45:55,599 INFO : Completed test dromaeo_css (00:18:34) 18:45:55 INFO - 2015-11-18 18:45:55,599 INFO : Starting test kraken 18:45:55 INFO - 2015-11-18 18:45:55,600 DEBUG : operating with platform_type : linux_ 18:45:55 INFO - 2015-11-18 18:45:55,600 INFO : Initialising browser for kraken test... 18:45:55 INFO - 2015-11-18 18:45:55,606 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc9LcPZ/profile http://localhost:37127/getInfo.html 18:45:59 INFO - 2015-11-18 18:45:59,626 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:45:59 INFO - 2015-11-18 18:45:59,626 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:45:59 INFO - 2015-11-18 18:45:59,626 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:45:59 INFO - 2015-11-18 18:45:59,627 DEBUG : BROWSER_OUTPUT: __metrics 18:45:59 INFO - 2015-11-18 18:45:59,715 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:46:01 INFO - 2015-11-18 18:46:01,075 INFO : Browser initialized. 18:46:01 INFO - 2015-11-18 18:46:01,076 INFO : Running cycle 1/1 for kraken test... 18:46:01 INFO - 2015-11-18 18:46:01,076 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc9LcPZ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:46:12 INFO - 2015-11-18 18:46:12,837 DEBUG : BROWSER_OUTPUT: RSS: Main: 139198464 18:46:12 INFO - 2015-11-18 18:46:12,837 DEBUG : BROWSER_OUTPUT: 18:46:15 INFO - 2015-11-18 18:46:15,277 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37127/tests/kraken/driver.html?testName=audio-beat-detection) 18:46:15 INFO - 2015-11-18 18:46:15,871 DEBUG : BROWSER_OUTPUT: RSS: Main: 178749440 18:46:15 INFO - 2015-11-18 18:46:15,872 DEBUG : BROWSER_OUTPUT: 18:46:18 INFO - 2015-11-18 18:46:18,468 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37127/tests/kraken/driver.html?testName=audio-dft) 18:46:19 INFO - 2015-11-18 18:46:19,050 DEBUG : BROWSER_OUTPUT: RSS: Main: 168402944 18:46:19 INFO - 2015-11-18 18:46:19,050 DEBUG : BROWSER_OUTPUT: 18:46:21 INFO - 2015-11-18 18:46:21,868 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37127/tests/kraken/driver.html?testName=audio-fft) 18:46:22 INFO - 2015-11-18 18:46:22,429 DEBUG : BROWSER_OUTPUT: RSS: Main: 150360064 18:46:22 INFO - 2015-11-18 18:46:22,430 DEBUG : BROWSER_OUTPUT: 18:46:24 INFO - 2015-11-18 18:46:24,391 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37127/tests/kraken/driver.html?testName=audio-oscillator) 18:46:24 INFO - 2015-11-18 18:46:24,964 DEBUG : BROWSER_OUTPUT: RSS: Main: 155480064 18:46:24 INFO - 2015-11-18 18:46:24,964 DEBUG : BROWSER_OUTPUT: 18:46:27 INFO - 2015-11-18 18:46:27,157 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37127/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:46:27 INFO - 2015-11-18 18:46:27,722 DEBUG : BROWSER_OUTPUT: RSS: Main: 154832896 18:46:27 INFO - 2015-11-18 18:46:27,723 DEBUG : BROWSER_OUTPUT: 18:46:30 INFO - 2015-11-18 18:46:30,601 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37127/tests/kraken/driver.html?testName=imaging-darkroom) 18:46:31 INFO - 2015-11-18 18:46:31,167 DEBUG : BROWSER_OUTPUT: RSS: Main: 156131328 18:46:31 INFO - 2015-11-18 18:46:31,167 DEBUG : BROWSER_OUTPUT: 18:46:34 INFO - 2015-11-18 18:46:34,582 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37127/tests/kraken/driver.html?testName=imaging-desaturate) 18:46:35 INFO - 2015-11-18 18:46:35,147 DEBUG : BROWSER_OUTPUT: RSS: Main: 157732864 18:46:35 INFO - 2015-11-18 18:46:35,147 DEBUG : BROWSER_OUTPUT: 18:46:37 INFO - 2015-11-18 18:46:37,951 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37127/tests/kraken/driver.html?testName=json-parse-financial) 18:46:38 INFO - 2015-11-18 18:46:38,516 DEBUG : BROWSER_OUTPUT: RSS: Main: 156065792 18:46:38 INFO - 2015-11-18 18:46:38,517 DEBUG : BROWSER_OUTPUT: 18:46:40 INFO - 2015-11-18 18:46:40,050 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37127/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:46:40 INFO - 2015-11-18 18:46:40,611 DEBUG : BROWSER_OUTPUT: RSS: Main: 168415232 18:46:40 INFO - 2015-11-18 18:46:40,612 DEBUG : BROWSER_OUTPUT: 18:46:42 INFO - 2015-11-18 18:46:42,426 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37127/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:46:43 INFO - 2015-11-18 18:46:43,020 DEBUG : BROWSER_OUTPUT: RSS: Main: 168878080 18:46:43 INFO - 2015-11-18 18:46:43,020 DEBUG : BROWSER_OUTPUT: 18:46:45 INFO - 2015-11-18 18:46:45,117 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37127/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:46:45 INFO - 2015-11-18 18:46:45,690 DEBUG : BROWSER_OUTPUT: RSS: Main: 175947776 18:46:45 INFO - 2015-11-18 18:46:45,690 DEBUG : BROWSER_OUTPUT: 18:46:48 INFO - 2015-11-18 18:46:48,064 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37127/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:46:48 INFO - 2015-11-18 18:46:48,644 DEBUG : BROWSER_OUTPUT: RSS: Main: 182149120 18:46:48 INFO - 2015-11-18 18:46:48,644 DEBUG : BROWSER_OUTPUT: 18:46:51 INFO - 2015-11-18 18:46:51,171 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37127/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:46:51 INFO - 2015-11-18 18:46:51,744 DEBUG : BROWSER_OUTPUT: RSS: Main: 180068352 18:46:51 INFO - 2015-11-18 18:46:51,745 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,426 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37127/tests/kraken/driver.html?testName=ai-astar) 18:46:53 INFO - 2015-11-18 18:46:53,677 DEBUG : BROWSER_OUTPUT: RSS: Main: 239939584 18:46:53 INFO - 2015-11-18 18:46:53,677 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,678 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:46:53 INFO - 2015-11-18 18:46:53,678 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:46:53 INFO - 2015-11-18 18:46:53,678 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:46:53 INFO - 2015-11-18 18:46:53,679 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:46:53 INFO - 2015-11-18 18:46:53,679 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;105;110;111;110;119;110;109;109;109 18:46:53 INFO - 2015-11-18 18:46:53,679 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;155;124;155;168;126;147;151;126;164;126 18:46:53 INFO - 2015-11-18 18:46:53,679 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;161;162;161;161;159;162;164;160;160 18:46:53 INFO - 2015-11-18 18:46:53,680 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;85;91;72;71;72;102;92;72 18:46:53 INFO - 2015-11-18 18:46:53,680 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;90;130;91;91;92;135;91;91;143 18:46:53 INFO - 2015-11-18 18:46:53,680 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;110;109;109;109;109;109;110 18:46:53 INFO - 2015-11-18 18:46:53,681 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;146;147;148;145;145;145;146;146 18:46:53 INFO - 2015-11-18 18:46:53,681 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;95;95;96;96;95;95;95 18:46:53 INFO - 2015-11-18 18:46:53,681 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;59;57;59;58;58;57;66;55;56 18:46:53 INFO - 2015-11-18 18:46:53,681 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;69;68;68;68;69;68;68;68;68 18:46:53 INFO - 2015-11-18 18:46:53,682 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;83;101;85;85;85;86;92;87;85 18:46:53 INFO - 2015-11-18 18:46:53,682 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;115;120;124;122;117;116;121;116;123 18:46:53 INFO - 2015-11-18 18:46:53,682 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;139;149;149;151;141;138;160;146;140 18:46:53 INFO - 2015-11-18 18:46:53,682 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;68;69;68;67;68;69;72;67;68 18:46:53 INFO - 2015-11-18 18:46:53,683 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:46:53 INFO - 2015-11-18 18:46:53,683 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:46:53 INFO - 2015-11-18 18:46:53,683 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,924 18:46:53 INFO - 2015-11-18 18:46:53,683 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:46:53 INFO - 2015-11-18 18:46:53,684 DEBUG : BROWSER_OUTPUT: __startTimestamp1447901213676__endTimestamp 18:46:53 INFO - 2015-11-18 18:46:53,684 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,684 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:46:53 INFO - 2015-11-18 18:46:53,685 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:46:53 INFO - 2015-11-18 18:46:53,686 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,686 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.30 Median:110.00 stddev:3.50 (3.2%) stddev-sans-first:3.70 18:46:53 INFO - 2015-11-18 18:46:53,686 DEBUG : BROWSER_OUTPUT: Values: 111.0 105.0 110.0 111.0 110.0 119.0 110.0 109.0 109.0 109.0 18:46:53 INFO - 2015-11-18 18:46:53,686 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,686 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.20 Median:153.00 stddev:17.15 (11.2%) stddev-sans-first:17.74 18:46:53 INFO - 2015-11-18 18:46:53,686 DEBUG : BROWSER_OUTPUT: Values: 155.0 124.0 155.0 168.0 126.0 147.0 151.0 126.0 164.0 126.0 18:46:53 INFO - 2015-11-18 18:46:53,686 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,686 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.40 (0.9%) stddev-sans-first:1.45 18:46:53 INFO - 2015-11-18 18:46:53,686 DEBUG : BROWSER_OUTPUT: Values: 162.0 161.0 162.0 161.0 161.0 159.0 162.0 164.0 160.0 160.0 18:46:53 INFO - 2015-11-18 18:46:53,686 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,687 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.10 Median:72.00 stddev:11.43 (15.9%) stddev-sans-first:11.74 18:46:53 INFO - 2015-11-18 18:46:53,687 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 85.0 91.0 72.0 71.0 72.0 102.0 92.0 72.0 18:46:53 INFO - 2015-11-18 18:46:53,687 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,687 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.50 Median:91.00 stddev:21.96 (24.1%) stddev-sans-first:22.74 18:46:53 INFO - 2015-11-18 18:46:53,687 DEBUG : BROWSER_OUTPUT: Values: 91.0 90.0 130.0 91.0 91.0 92.0 135.0 91.0 91.0 143.0 18:46:53 INFO - 2015-11-18 18:46:53,687 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,687 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 18:46:53 INFO - 2015-11-18 18:46:53,687 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 110.0 18:46:53 INFO - 2015-11-18 18:46:53,687 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,688 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.99 (0.7%) stddev-sans-first:1.05 18:46:53 INFO - 2015-11-18 18:46:53,688 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 146.0 147.0 148.0 145.0 145.0 145.0 146.0 146.0 18:46:53 INFO - 2015-11-18 18:46:53,688 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,688 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 18:46:53 INFO - 2015-11-18 18:46:53,688 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 18:46:53 INFO - 2015-11-18 18:46:53,688 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,688 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.00 stddev:2.98 (5.1%) stddev-sans-first:3.16 18:46:53 INFO - 2015-11-18 18:46:53,688 DEBUG : BROWSER_OUTPUT: Values: 58.0 59.0 57.0 59.0 58.0 58.0 57.0 66.0 55.0 56.0 18:46:53 INFO - 2015-11-18 18:46:53,688 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,689 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:0.57 (0.8%) stddev-sans-first:0.44 18:46:53 INFO - 2015-11-18 18:46:53,689 DEBUG : BROWSER_OUTPUT: Values: 67.0 69.0 68.0 68.0 68.0 69.0 68.0 68.0 68.0 68.0 18:46:53 INFO - 2015-11-18 18:46:53,689 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,689 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:85.00 stddev:5.47 (6.4%) stddev-sans-first:5.59 18:46:53 INFO - 2015-11-18 18:46:53,689 DEBUG : BROWSER_OUTPUT: Values: 83.0 83.0 101.0 85.0 85.0 85.0 86.0 92.0 87.0 85.0 18:46:53 INFO - 2015-11-18 18:46:53,689 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,689 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.10 Median:120.50 stddev:3.28 (2.7%) stddev-sans-first:3.39 18:46:53 INFO - 2015-11-18 18:46:53,689 DEBUG : BROWSER_OUTPUT: Values: 117.0 115.0 120.0 124.0 122.0 117.0 116.0 121.0 116.0 123.0 18:46:53 INFO - 2015-11-18 18:46:53,689 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,690 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.20 Median:147.50 stddev:7.11 (4.8%) stddev-sans-first:7.18 18:46:53 INFO - 2015-11-18 18:46:53,690 DEBUG : BROWSER_OUTPUT: Values: 139.0 139.0 149.0 149.0 151.0 141.0 138.0 160.0 146.0 140.0 18:46:53 INFO - 2015-11-18 18:46:53,690 DEBUG : BROWSER_OUTPUT: 18:46:53 INFO - 2015-11-18 18:46:53,690 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:1.43 (2.1%) stddev-sans-first:1.51 18:46:53 INFO - 2015-11-18 18:46:53,690 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 69.0 68.0 67.0 68.0 69.0 72.0 67.0 68.0 18:46:53 INFO - 2015-11-18 18:46:53,690 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:46:53 INFO - 2015-11-18 18:46:53,690 DEBUG : BROWSER_OUTPUT: 18:46:54 INFO - 2015-11-18 18:46:54,501 INFO : Browser exited with error code: 0 18:46:54 INFO - 2015-11-18 18:46:54,552 INFO : Completed test kraken (00:00:58) 18:46:54 INFO - 2015-11-18 18:46:54,552 INFO : Starting test v8_7 18:46:54 INFO - 2015-11-18 18:46:54,552 DEBUG : operating with platform_type : linux_ 18:46:54 INFO - 2015-11-18 18:46:54,552 INFO : Initialising browser for v8_7 test... 18:46:54 INFO - 2015-11-18 18:46:54,558 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4fcyUa/profile http://localhost:37127/getInfo.html 18:46:58 INFO - 2015-11-18 18:46:58,599 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:46:58 INFO - 2015-11-18 18:46:58,600 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:46:58 INFO - 2015-11-18 18:46:58,600 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:46:58 INFO - 2015-11-18 18:46:58,600 DEBUG : BROWSER_OUTPUT: __metrics 18:46:58 INFO - 2015-11-18 18:46:58,696 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:47:00 INFO - 2015-11-18 18:47:00,128 INFO : Browser initialized. 18:47:00 INFO - 2015-11-18 18:47:00,129 INFO : Running cycle 1/1 for v8_7 test... 18:47:00 INFO - 2015-11-18 18:47:00,129 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4fcyUa/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:47:11 INFO - 2015-11-18 18:47:11,815 DEBUG : BROWSER_OUTPUT: RSS: Main: 139464704 18:47:11 INFO - 2015-11-18 18:47:11,815 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,225 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37127/tests/v8_7/run.html (next: http://localhost:37127/tests/v8_7/run.html) 18:47:33 INFO - 2015-11-18 18:47:33,475 DEBUG : BROWSER_OUTPUT: RSS: Main: 572145664 18:47:33 INFO - 2015-11-18 18:47:33,475 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,475 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:47:33 INFO - 2015-11-18 18:47:33,475 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:47:33 INFO - 2015-11-18 18:47:33,476 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:47:33 INFO - 2015-11-18 18:47:33,476 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:47:33 INFO - 2015-11-18 18:47:33,476 DEBUG : BROWSER_OUTPUT: |0;Richards;163.21201240411295 18:47:33 INFO - 2015-11-18 18:47:33,476 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;189.60940462646948 18:47:33 INFO - 2015-11-18 18:47:33,476 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.42624789680315 18:47:33 INFO - 2015-11-18 18:47:33,476 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5040.201005025126 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1107.3008849557523 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: |5;Earley;779.423226812159 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: |6;Boyer;12219.512195121952 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: |7;RegExp;25743.589743589742 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: |8;Splay;584.7953216374269 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4859.223300970873 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: __startTimestamp1447901253473__endTimestamp 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:47:33 INFO - 2015-11-18 18:47:33,477 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,478 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.21 Median:163.21 stddev:0.00 (0.0%) 18:47:33 INFO - 2015-11-18 18:47:33,478 DEBUG : BROWSER_OUTPUT: Values: 163.2 18:47:33 INFO - 2015-11-18 18:47:33,478 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,478 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:189.61 Median:189.61 stddev:0.00 (0.0%) 18:47:33 INFO - 2015-11-18 18:47:33,478 DEBUG : BROWSER_OUTPUT: Values: 189.6 18:47:33 INFO - 2015-11-18 18:47:33,478 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,478 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.43 Median:280.43 stddev:0.00 (0.0%) 18:47:33 INFO - 2015-11-18 18:47:33,478 DEBUG : BROWSER_OUTPUT: Values: 280.4 18:47:33 INFO - 2015-11-18 18:47:33,478 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,479 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5040.20 Median:5040.20 stddev:0.00 (0.0%) 18:47:33 INFO - 2015-11-18 18:47:33,479 DEBUG : BROWSER_OUTPUT: Values: 5040.2 18:47:33 INFO - 2015-11-18 18:47:33,479 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1107.30 Median:1107.30 stddev:0.00 (0.0%) 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: Values: 1107.3 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:779.42 Median:779.42 stddev:0.00 (0.0%) 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: Values: 779.4 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12219.51 Median:12219.51 stddev:0.00 (0.0%) 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: Values: 12219.5 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25743.59 Median:25743.59 stddev:0.00 (0.0%) 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: Values: 25743.6 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:584.80 Median:584.80 stddev:0.00 (0.0%) 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: Values: 584.8 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,480 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4859.22 Median:4859.22 stddev:0.00 (0.0%) 18:47:33 INFO - 2015-11-18 18:47:33,481 DEBUG : BROWSER_OUTPUT: Values: 4859.2 18:47:33 INFO - 2015-11-18 18:47:33,481 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:47:33 INFO - 2015-11-18 18:47:33,481 DEBUG : BROWSER_OUTPUT: 18:47:34 INFO - 2015-11-18 18:47:34,313 INFO : Browser exited with error code: 0 18:47:34 INFO - 2015-11-18 18:47:34,330 INFO : Completed test v8_7 (00:00:39) 18:47:34 INFO - 2015-11-18 18:47:34,470 INFO : Completed test suite (00:20:13) 18:47:34 INFO - 2015-11-18 18:47:34,470 DEBUG : Working with test: dromaeo_css 18:47:34 INFO - 2015-11-18 18:47:34,470 DEBUG : Generating results file: dromaeo_css 18:47:34 INFO - 2015-11-18 18:47:34,471 DEBUG : Working with test: kraken 18:47:34 INFO - 2015-11-18 18:47:34,471 DEBUG : Generating results file: kraken 18:47:34 INFO - 2015-11-18 18:47:34,472 INFO : javascript benchmark 18:47:34 INFO - 2015-11-18 18:47:34,472 DEBUG : Working with test: v8_7 18:47:34 INFO - 2015-11-18 18:47:34,472 DEBUG : Generating results file: v8_7 18:47:34 INFO - 2015-11-18 18:47:34,472 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:47:34 INFO - 2015-11-18 18:47:34,532 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 18:47:34 INFO - 2015-11-18 18:47:34,532 DEBUG : process_Request line: dromaeo_css 6709.94 graph.html#tests=[[72,64,35]] 18:47:34 INFO - 2015-11-18 18:47:34,532 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:47:34 INFO - 2015-11-18 18:47:34,575 DEBUG : process_Request line: kraken 1497.20 graph.html#tests=[[232,64,35]] 18:47:34 INFO - 2015-11-18 18:47:34,575 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:47:34 INFO - 2015-11-18 18:47:34,613 DEBUG : process_Request line: v8_7 21213.86 graph.html#tests=[[230,64,35]] 18:47:34 INFO - 2015-11-18 18:47:34,615 INFO : javascript benchmark 18:47:34 INFO - 2015-11-18 18:47:34,617 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3191.744660427166, 3262.6084869815627, 3295.2091498040477, 3303.587051618548, 3308.619508756494, 5197.740112994345, 5323.414424413316, 5364.678119312844, 5377.0, 5377.381601115868, 5418.539424148952, 5447.373552041517, 5479.424660410659, 5480.068388373976, 5502.477264659861, 4891.315215869781, 4964.975175124119, 4978.8257410990445, 5097.898042039157, 5124.282600435942, 4913.656044076916, 5047.747612619354, 5078.162103252977, 5098.617603679739, 5122.871928201806, 4932.926006109911, 4997.350344455216, 5031.0, 5032.6477146599755, 5048.545630893237, 4950.3069570260195, 4985.576226020793, 4990.226514890198, 4997.675151115166, 5015.39815222172, 4810.230363141881, 4917.901641967141, 4951.90096198074, 4990.850274491771, 5036.11867923112, 5223.582113430954, 5302.1781623848365, 5336.306280183551, 5340.385855626575, 5367.355917289911, 5893.086571581412, 5925.229720136354, 5927.051671732502, 5930.347661757203, 5934.703264836784, 5802.912956305659, 5805.767769289223, 5927.703614819242, 5928.703564821785, 5940.34656187819, 5549.361823390281, 5555.694436805974, 5558.749856256478, 5570.108782594806, 5618.971905140448, 6004.519638428916, 6020.458158765732, 6024.096385542204, 6080.908786368208, 6086.360932102154, 5712.257406537124, 5767.22142510764, 5818.359980402152, 5884.852878678067, 5885.176075349455, 5805.071188609802, 5841.357450680422, 5844.415558444121, 5864.970675146682, 5912.17229587858, 5830.883382332329, 5831.446012628793, 5832.679202643811, 5839.240898683144, 5844.620099693507, 5706.942930570641, 5725.456081672234, 5811.15738217956, 5815.76736930518, 5865.384134665798, 4560.863174104782, 4563.973106051112, 4565.543445655474, 4566.429196350457, 4610.147122782296, 4091.91816163681, 4092.959070409258, 4095.9180816383505, 4101.26177288085, 4140.213359461693, 5466.835994920159, 5576.86057848557, 5586.55307575393, 5636.520895723827, 5691.231683722644, 5887.234659494238, 5903.527717782653, 5903.675297858659, 5905.202797622266, 5915.231019967377, 5498.422665620053, 5575.470330318613, 5582.748776305076, 5585.134304182858, 5586.190002449704, 5005.374328208974, 5063.620228482805, 5099.0, 5148.253503242084, 5150.072986862401, 6074.574779765462, 6086.7260973255425, 6090.086487026892, 6091.5126789857595, 6102.115193296947, 5717.485426311652, 5883.058710606282, 5886.705664716833, 5890.705464726832, 5894.233749612695, 6024.698765061642, 6033.818985430444, 6062.211912451353, 6089.295106532991, 6127.969360153171, 5852.3269823969085, 5869.73586188614, 5878.118282257696, 5880.382559831156, 5898.380670029756, 5795.565332600157, 5961.105834124916, 5965.761369545169, 5968.015277479168, 5981.371955944737, 5690.089585666274, 5736.340320863154, 5763.884722305615, 5794.739236734441, 5843.094320380348, 5728.914066288926, 5765.56758243125, 5769.9711501442225, 5777.335606405317, 5785.161151633074, 5546.584006199632, 5676.262085928803, 5687.914681279702, 5693.345265294544, 5695.174199741097, 5681.914771278517, 5790.073588225863, 5810.186573879576, 5839.065749480063, 5851.385604511635, 5710.628809127394, 5713.8285851424525, 5717.656940583578, 5728.198052272768, 5768.1636162757], "unit": "ms", "name": "dojo.html", "value": 5429.230721684577}, {"lowerIsBetter": false, "replicates": [598.6109029131065, 606.5026678123938, 606.8240210339002, 615.1080932647662, 618.2210414877259, 31578.526322105157, 39779.2044159117, 40573.59426405735, 40604.390934135976, 40639.0, 43114.7844260779, 43662.56337436625, 43686.56313436865, 43715.56284437155, 43996.780016099874, 7594.253321736004, 7713.498622589542, 8710.041895391518, 8896.37725359225, 8903.287736981027, 10443.112335451497, 10623.734406639818, 10636.78726425471, 10689.83965240522, 10691.411972341519, 10328.845067323959, 10383.688489345332, 10393.220508461858, 10396.688099357032, 10407.73980650486, 7830.099538553054, 7998.600069996477, 10341.27611067222, 10405.687829365132, 10711.143108551298, 10200.795984080276, 10286.897131028669, 10344.275900686955, 10347.172226221885, 10351.948240258751, 40055.79972100121, 40284.39573406401, 40521.79739101315, 40635.593644063476, 40666.0, 3354.983225083859, 3407.5910890693026, 3447.8620855165764, 3688.354537955847, 3723.7393382463238, 30283.0, 30336.8483157585, 30390.392192156254, 30952.8452357739, 31417.528737068962, 3388.519110017754, 3481.9825900870333, 3486.5467489226494, 3494.458358954341, 3547.7693949893173, 272600.0, 272716.0, 273939.0, 274559.0, 274989.0, 51304.48695512997, 52206.0, 57667.423325766205, 57965.42034579685, 58050.419495804505, 66746.33253667402, 67462.0, 67859.0, 67968.66015669989, 68021.65989170122, 13720.519781807589, 13885.097468664504, 13885.652858678412, 13896.791548126786, 13899.374528146258, 10928.398938058324, 11036.392998385167, 11040.889591104147, 11070.39112848801, 11072.667819965414, 18807.0, 18841.340553080576, 18885.244590216236, 18919.0, 18950.24199032023, 7865.803354916173, 8078.313343365762, 8160.30637395828, 8186.959065204635, 8268.793280168044, 903.1916434790826, 991.4992928571025, 991.5141580625442, 997.1673652500099, 1098.2477003252798, 28576.285592859927, 28672.13983580496, 28675.0, 28920.83690806209, 29163.41673166525, 1017.5014243021014, 1046.99476502617, 1052.1162223732101, 1065.174489770434, 1124.8481455003634, 14840.480583179537, 15082.472113475975, 15259.847401526065, 15287.464938727091, 15594.376224951102, 272825.0, 276904.0, 277431.0, 277457.0, 277764.0, 49644.0, 49877.25184122241, 49889.0, 49915.0, 50005.0, 1299.5191779041816, 1326.5754958413409, 1333.2333908002938, 1333.8065980432978, 1340.2360654426884, 26696.466070678867, 28561.28596785097, 28650.0, 28884.42231155407, 28889.0, 44851.0, 44937.550624494645, 45002.32496512489, 45021.0, 45052.77473612742, 304129.0, 305394.0, 305427.0, 306045.0, 306452.0, 28141.763697175087, 31001.37997240088, 31326.843365783938, 31440.213994649403, 31756.20609484689, 37811.0, 38393.0, 38830.4175437363, 38865.6113438862, 39008.21983560256, 34709.47935780915, 34923.65076349204, 35023.824880875436, 35070.47394289139, 35680.464793027604, 28656.28359290951, 33082.83458582692, 33634.15914602154, 34606.0, 35470.822645886605, 32108.197295066875, 32439.513407298437, 32487.350252995282, 35925.64074359223, 36240.275194495436, 29885.252868678457, 32370.0, 33036.33927321488, 33325.33349333048, 33344.66655333416, 24814.37964050913, 25131.623025654997, 25183.24450266495, 25279.494410112064, 25302.987880484576], "unit": "ms", "name": "ext.html", "value": 18510.604950469602}, {"lowerIsBetter": false, "replicates": [357.70310642167004, 358.42651757188503, 359.5218359581753, 361.33694670280033, 364.82123759357904, 13818.0, 14024.439022439115, 16722.33110675573, 17105.914470427648, 17708.91145544272, 11784.941075294633, 12165.08761842861, 16492.17539123042, 17273.654526909453, 17287.30850765971, 4482.23801953667, 4584.4498660160825, 4587.266037433995, 4594.839180628679, 4595.816167353302, 3558.946615800752, 3629.1652919828457, 4521.706089104197, 4557.908841823161, 4896.43690975537, 4888.462269150391, 4914.0, 4935.259711043336, 4936.06214819185, 4972.428170760355, 4656.883577910546, 4775.80896764129, 4914.090893184754, 4920.941997470543, 4923.335349727777, 4139.0, 4140.213359461723, 4142.357934520154, 4147.97926010368, 4149.751014939114, 12957.352132393342, 13412.865871341357, 13438.865611343957, 13445.126066805626, 13471.932640336834, 1839.9724004139948, 1887.8395336396286, 1937.8740381875134, 1961.4802077449488, 1993.8404927605757, 9693.515324233831, 9731.075547822946, 9920.107190352903, 9937.0, 10055.0, 1608.4450864451746, 1652.4877288040745, 1690.0, 1729.0, 1748.2133040131864, 60804.0, 62258.37741622526, 62394.0, 62481.68759156221, 62939.685301573656, 6951.374376306157, 7062.823429414306, 7070.257622949516, 7095.077639906779, 7121.857562848714, 8344.290735287446, 8433.03020152678, 8438.324934005264, 8457.703980360659, 8477.703280385156, 5507.752151153208, 5509.724513774296, 5511.476409741068, 5515.338159420895, 5553.500184983371, 5624.409437009137, 5645.407232240637, 5667.716614169275, 5674.893395787864, 5678.835838653066, 3263.7878537894962, 3287.2767991041933, 3291.358185153872, 3299.4555898276517, 3315.5689760331, 1128.1651577832508, 1141.0985321596013, 1145.4559084434827, 1160.2516376936828, 1203.7351782607811, 461.8406649705846, 464.30354468297554, 473.2522614269446, 479.99520004800206, 490.8453837041321, 10660.307080039773, 11371.0, 11493.482793274155, 11516.193866429438, 11791.88208117908, 438.1565486438657, 478.10832796833654, 481.3549843210115, 491.85736136520006, 500.0499051801606, 5999.820005399845, 6174.536909731788, 6360.936390636127, 6382.234131904202, 6400.167978162809, 508750.0, 511775.0, 515172.0, 515874.0, 532404.0, 75597.9999999989, 76351.0, 76452.0, 76564.61717691488, 77474.0, 522.3653261287554, 527.2039220776655, 527.5568522441168, 527.59111688441, 527.7994362142362, 10157.593696252363, 10375.740606484898, 10568.788624227318, 10584.153267738408, 10697.732556686145, 35674.0, 35977.38101785374, 36205.0, 36394.27211455809, 36459.0, 46973.72630568707, 47691.0, 48796.5120348792, 48977.755111224214, 49751.0, 6707.228668703162, 6794.252632210551, 6862.897056544056, 6871.209810871814, 6913.654317284217, 12247.142700010914, 12267.202631828903, 12409.634940156395, 12510.061745368952, 12549.937250314055, 10708.625198118028, 10745.892541074489, 10993.120550356112, 11024.228304018641, 11051.060659844003, 10431.843522347323, 10610.363378197333, 10863.511141998471, 10865.402402867918, 11158.163137764537, 10491.265611407129, 10680.519376628226, 10683.465826708789, 10874.0, 10930.72673183145, 6711.094002309821, 6834.589924604539, 6859.691313890787, 6860.142482189726, 6861.2795656455355, 10418.843717344398, 10657.840132398176, 10840.186985975926, 10915.672529824118, 10936.671899843017], "unit": "ms", "name": "jquery.html", "value": 6890.235757320414}, {"lowerIsBetter": false, "replicates": [1381.468134768114, 1482.7775833624958, 1559.828418873924, 1688.3753011385788, 1810.3211295764088, 6796.116504854368, 7278.381337586305, 7436.925630743691, 7455.2172021937595, 7460.888086678698, 7475.364594009516, 7597.2402759724, 7600.5059671121335, 7621.504602200853, 7621.695132194719, 2003.1286390420214, 2034.8168664820164, 2126.553423781, 2249.4601295689, 2288.6109361408517, 2297.5749486345, 2307.6077066898583, 2308.042162502559, 2309.6535519672098, 2314.9698384218946, 2185.8797766122857, 2256.8645881247094, 2257.119723307911, 2258.5883822610867, 2259.1076524772707, 1981.603679264153, 2033.4103110098054, 2240.5630901974123, 2281.908723651052, 2290.713660792401, 2196.769339219375, 2209.7348318201757, 2227.8663280203077, 2234.530748542808, 2260.8530445520987, 7639.579823109727, 7680.078390593108, 7710.498817576839, 7711.807204819925, 7716.7684969451, 4696.577308042259, 4792.209285467929, 4799.1601469743, 4799.7360145192, 4801.18379875422, 3100.914142319431, 3117.267442151093, 3127.8279694616785, 3168.588083549147, 3170.0, 980.9705708828747, 983.3362480325823, 1008.7831116310028, 1012.2256473797551, 1013.8276492996208, 10541.73645658857, 10547.84178237327, 10560.47197640115, 10564.26050176488, 10565.15478761705, 4233.428487154257, 4270.487541494978, 4323.870283891489, 4376.846810361622, 4390.3414487827085, 9150.81698366029, 9168.908310916806, 9178.954105229564, 9188.58651360677, 9191.0, 4756.7145971241835, 4764.118638051971, 4765.451973023147, 4776.331313616097, 4783.1868582341085, 4359.346098085312, 4361.454818147731, 4387.363832244307, 4394.252976993918, 4397.560243975641, 3681.963180368162, 3719.8512059517316, 3740.251949610089, 3742.8315725792404, 3786.73492855503, 1179.9705007374712, 1193.8209268609778, 1199.3403628004562, 1213.3144773203112, 1214.7813393589238, 977.4770497783652, 1296.0603562417173, 1298.6363818130937, 1315.81578578999, 1319.2678063674562, 6547.541672082908, 7324.743633972786, 7563.0, 7567.394608431422, 7574.394048476109, 696.8606278744271, 703.6692754405422, 1183.928964262147, 1231.322772475153, 1254.2975933477283, 5636.774529018875, 5729.2265544151005, 5737.08206686928, 5743.569232307594, 5745.942540574624, 8923.553822308988, 9005.324600654978, 9015.27877769777, 9058.5017824019, 9160.541972901328, 8956.149165829236, 8956.596953137125, 9042.412243204171, 9127.863082053775, 9135.908640913505, 7148.428125749928, 7191.604461754654, 7237.167725711505, 7247.05788247535, 7298.306660867315, 7176.605286709281, 7223.313785190398, 7246.818829529093, 7254.310840470147, 7267.418606511361, 8021.077576078826, 8038.316743076904, 8322.791930201793, 8347.248747612744, 8361.707340243061, 8242.0, 8298.253157215879, 8385.95807020961, 8386.70646527393, 8414.116517765546, 6482.384173503509, 6702.162229721285, 6704.731810727516, 6716.3955244028275, 6716.597004179765, 7289.854202916018, 7391.0761154855645, 7394.926050739639, 7424.294692004251, 7432.479726419315, 6965.47758918073, 7093.467989900675, 7150.785476435715, 7206.89189662166, 7235.2764723529335, 7021.613811240227, 7056.682449289691, 7111.0, 7129.750458733919, 7167.283271673, 6415.3263907289065, 6536.477081833346, 6554.279029306867, 7088.184858741104, 7090.042844215965, 6303.180586523906, 6508.153939987771, 6538.476921846336, 6543.312952140147, 6556.967215164084, 6532.085508028975, 6559.376859198369, 6599.241087275025, 6613.570117942319, 6620.569662971891], "unit": "ms", "name": "mootools.html", "value": 4458.090402630126}, {"lowerIsBetter": false, "replicates": [320.06700468135386, 324.68019001283733, 329.03920551988193, 330.87922908138523, 331.61863856564946, 5888.234529511163, 5899.380565040673, 5938.910916336253, 5951.851203719898, 5996.8200953971345, 5858.5606079543995, 5877.794277200299, 5890.999999999989, 5892.322382925965, 5896.616719913213, 4505.436820397458, 4512.661550383735, 4518.096380723852, 4523.117991991563, 4524.0, 4362.040351122764, 4514.413126293577, 4524.3213517972235, 4535.659825513099, 4539.978504836402, 4474.261746811772, 4483.327500874862, 4483.3723278741, 4491.932621010687, 4517.751523666197, 4485.0, 4500.0, 4508.504064552896, 4514.097180563884, 4514.142312960544, 3708.9443658345144, 3794.4118661607495, 3798.1074447504816, 3810.942835857464, 3813.6186381361917, 5578.856334451428, 5694.658320500783, 5713.4000929902595, 5729.942700572982, 5733.512651424592, 5234.8691282717855, 5259.105951988171, 5266.1837415200325, 5270.604704647167, 5285.86785330366, 5865.325487568942, 6054.394560543955, 6232.470240029603, 6400.583962042452, 6409.359064093554, 4305.806238719234, 4311.611954924072, 4319.52485226628, 4322.373255877911, 4325.048489332342, 6572.86854262912, 6645.966770166118, 6651.301613330627, 6655.93344066558, 6656.467482601429, 4479.59683628475, 4486.205517792909, 4490.663200259994, 4492.618127459203, 4494.662900282492, 4407.823687052482, 4521.118381915495, 4537.478190008125, 4540.636749060067, 4552.135094332132, 5301.125314323171, 5317.760700768475, 5318.97340513295, 5325.041492531304, 5328.786848526015, 6380.170577824853, 6430.453411460078, 6434.903476447857, 6448.290688024314, 6450.903236451457, 1556.688662267551, 1570.0736565426225, 1571.8761085823692, 1572.1196130167143, 1572.3710515793546, 1022.7801022780138, 1024.5696807340935, 1031.4224034540532, 1038.9740256493649, 1055.087349442742, 6212.689365531795, 6225.0, 6237.407446292595, 6242.469390101892, 6242.65665388408, 6605.174353205849, 6610.4050635443045, 6611.338866113447, 6621.106150669694, 6624.337566243337, 6087.391260873877, 6226.1905952225925, 6228.190335256388, 6235.0, 6235.937640623626, 3560.127768696686, 3587.2466781976077, 3592.3713350163616, 3595.8921232363073, 3598.478220658042, 6620.933790662128, 6702.430293425016, 6754.763583274562, 6806.931930680729, 6815.386615204656, 6848.417884479875, 6850.143732033496, 6857.657117144223, 6860.0, 6876.3526042473, 6762.120924279812, 6916.585004899722, 6921.688524016431, 6925.549839260432, 6930.203026651899, 6654.201495820533, 6677.0, 6681.198256209286, 6683.064370988162, 6688.83277918056, 6635.966820165868, 6636.668166591748, 6642.435392991457, 6646.2356828965285, 6650.83372915681, 6786.558873673195, 6928.237893831775, 6935.618540980093, 6954.095967524392, 6962.1297337832775, 4449.755263460541, 4485.955140448554, 4486.573775491323, 4492.842750503717, 4495.280755079171, 6640.76757313511, 6646.90029649546, 6656.0, 6662.733490660513, 6670.0, 6275.937240627535, 6474.1907261592305, 6479.254885688206, 6479.319671434431, 6481.708323125565, 6470.547061705635, 6478.4169424752, 6478.902816457851, 6481.935180648322, 6484.5460817742305, 6351.872962540816, 6360.459360954371, 6363.172787537591, 6367.172267605367, 6375.394337537927, 6350.079238510482, 6489.480841532761, 6500.967495162494, 6504.121943537752, 6505.447037001719, 6332.335104813928, 6340.112384266298, 6340.112384266298, 6349.555531112963, 6353.77761778354], "unit": "ms", "name": "prototype.html", "value": 4789.626965801105}, {"lowerIsBetter": false, "replicates": [694.8992396102565, 733.2887099513473, 814.1329484099433, 818.3412353055791, 857.6312185760121, 13178.406971686272, 13400.93299533501, 13461.1923284603, 13494.190348579079, 13506.122102063358, 11588.884111158886, 11611.767764644703, 11642.592509262178, 11655.650330490098, 11690.707732306697, 615.47991946805, 638.5881106686185, 768.6656304507529, 792.7344339646226, 795.6817273090782, 803.0323460230437, 803.6865622407237, 808.8827120067587, 809.8218391953761, 810.1372038778711, 676.7326905872178, 701.9157701075878, 842.2041171093289, 847.8389106069858, 848.9278411335044, 794.3406972213078, 827.6234313387455, 827.962741676626, 828.49461828285, 828.6229765456703, 607.6809674920657, 609.3875654966774, 611.034565386688, 611.1657587393221, 611.6177389131792, 12528.937355313255, 12623.558175463815, 12625.24248545081, 12640.304783236918, 12655.493780248778, 11980.940095299555, 12443.066769992287, 12565.497380104785, 12600.243985360908, 12627.93686031564, 8760.693375731882, 8923.375363724543, 8945.46327220369, 8986.505742184178, 9008.774780630536, 3545.9290814183573, 3562.3231585998838, 3562.3943929532043, 3564.3584154852115, 3580.6777390034763, 45330.54669453296, 45678.5432145681, 47950.760246198544, 48411.51588484105, 48417.03165936697, 10145.746356341004, 10150.137238334677, 10162.796744065077, 10205.336653117673, 10389.324693894872, 17106.657866842594, 17129.400470983455, 17146.82853171477, 17187.65624687499, 17196.31214751421, 6965.05971693825, 7044.6125463100025, 7046.541974771623, 7091.326323999212, 7159.928400715926, 3904.3362628353248, 4072.3755857287038, 4160.729552579072, 4163.770992595376, 4170.7706076166105, 3137.3568418474156, 3143.0413723814454, 3173.841307934594, 3177.507486339621, 3185.2355434695505, 483.7363636817943, 499.0567826807337, 502.12880652068634, 503.2099603622351, 511.2688854937475, 560.1653536230987, 625.0186017440988, 632.6046221111806, 642.5084810120262, 713.9740589425235, 8341.45780524264, 8344.165583441607, 8478.74563763088, 8496.915030849736, 8498.320134389234, 516.8113339319625, 563.2818156850049, 620.6593757483813, 621.9129321894939, 665.9034440006269, 3879.612038796154, 3920.5883382244456, 3961.2473630010204, 4086.693497987663, 4134.421181034658, 55991.0, 57370.0, 57416.42583574111, 60377.69811150916, 60420.39579604236, 50342.74828625907, 50999.74500127476, 51055.744721276154, 56713.14930276049, 56888.146677799865, 50823.0, 51272.74363628158, 51511.22733159005, 51667.48332516702, 52601.7369913148, 8485.703000395204, 8621.82756344857, 8695.173958473935, 8752.643549738617, 8797.824043519222, 34716.30567388688, 35186.296274073866, 35656.0, 35791.82104089463, 35914.28171436609, 43778.12443751171, 45816.77091614521, 46207.53792462032, 46229.0, 46516.0, 11190.272632278877, 11304.943475282571, 11359.318440893572, 11464.885351146382, 11794.52821887115, 12402.25586464815, 12416.0, 12544.686382840502, 12630.0, 12965.870341296844, 10858.67423977282, 11224.66326010221, 11868.703282418008, 12242.0, 12244.938775306422, 10726.249162558857, 10794.622188223375, 11246.212765106364, 11259.774804504028, 11421.657350279505, 7667.211162609603, 10685.751670268468, 10803.081738052353, 11245.325280483197, 11382.829257561309, 9934.751631209278, 10145.9492702536, 10198.694039178836, 10243.07812296897, 10453.477326133814, 8682.218600326001, 9383.483908384831, 9693.369930954465, 9760.951195243979, 9784.119429251401], "unit": "ms", "name": "yui.html", "value": 6172.06937089546}], "name": "dromaeo_css", "value": 6709.937727291122}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 105.0, 110.0, 111.0, 110.0, 119.0, 110.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 110.3}, {"lowerIsBetter": true, "replicates": [155.0, 124.0, 155.0, 168.0, 126.0, 147.0, 151.0, 126.0, 164.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.2}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 162.0, 161.0, 161.0, 159.0, 162.0, 164.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 161.2}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 85.0, 91.0, 72.0, 71.0, 72.0, 102.0, 92.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.1}, {"lowerIsBetter": true, "replicates": [91.0, 90.0, 130.0, 91.0, 91.0, 92.0, 135.0, 91.0, 91.0, 143.0], "unit": "ms", "name": "audio-oscillator", "value": 104.5}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 147.0, 148.0, 145.0, 145.0, 145.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.9}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [58.0, 59.0, 57.0, 59.0, 58.0, 58.0, 57.0, 66.0, 55.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 58.3}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 68.0, 68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.1}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 101.0, 85.0, 85.0, 85.0, 86.0, 92.0, 87.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 87.2}, {"lowerIsBetter": true, "replicates": [117.0, 115.0, 120.0, 124.0, 122.0, 117.0, 116.0, 121.0, 116.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.1}, {"lowerIsBetter": true, "replicates": [139.0, 139.0, 149.0, 149.0, 151.0, 141.0, 138.0, 160.0, 146.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 145.2}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 69.0, 68.0, 67.0, 68.0, 69.0, 72.0, 67.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 68.4}], "name": "kraken", "value": 1497.1999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.21201240411295], "unit": "score", "name": "Richards", "value": 216.29535399999995}, {"lowerIsBetter": false, "replicates": [189.60940462646948], "unit": "score", "name": "DeltaBlue", "value": 348.7063319999999}, {"lowerIsBetter": false, "replicates": [280.42624789680315], "unit": "score", "name": "Encrypt", "value": 949.2014460000001}, {"lowerIsBetter": false, "replicates": [5040.201005025126], "unit": "score", "name": "Decrypt", "value": 52.81158424725827}, {"lowerIsBetter": false, "replicates": [1107.3008849557523], "unit": "score", "name": "RayTrace", "value": 668.2817742257745}, {"lowerIsBetter": false, "replicates": [779.423226812159], "unit": "score", "name": "Earley", "value": 855.072029}, {"lowerIsBetter": false, "replicates": [12219.512195121952], "unit": "score", "name": "Boyer", "value": 54.540884231536886}, {"lowerIsBetter": false, "replicates": [25743.589743589742], "unit": "score", "name": "RegExp", "value": 35.38686752988046}, {"lowerIsBetter": false, "replicates": [584.7953216374269], "unit": "score", "name": "Splay", "value": 139.34960999999998}, {"lowerIsBetter": false, "replicates": [4859.223300970873], "unit": "score", "name": "NavierStokes", "value": 305.39860139860156}], "name": "v8_7", "value": 21213.855425065005}]} 18:47:34 INFO - RETURN: dromaeo_css: 6709.94 18:47:34 INFO - RETURN: kraken: 1497.20 18:47:34 INFO - RETURN: v8_7: 21213.86 18:47:34 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "21213.86"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1497.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6709.94"}}} 18:47:34 INFO - Return code: 0 18:47:34 INFO - # TBPL SUCCESS # 18:47:34 INFO - Running post-action listener: _resource_record_post_action 18:47:34 INFO - Running post-run listener: _resource_record_post_run 18:47:35 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 4096; Write bytes: 495779840; Read time: 16; Write time: 435624 18:47:35 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 137748480; Read time: 0; Write time: 188636 18:47:35 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 0; Write bytes: 358031360; Read time: 0; Write time: 246988 18:47:35 INFO - Running post-run listener: _upload_blobber_files 18:47:35 INFO - Blob upload gear active. 18:47:35 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:47:35 INFO - Copying logs to upload dir... 18:47:35 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1273.062874 ========= master_lag: 19.78 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2015-11-18 18:47:55.588168) ========= ========= Started set props: build_url (results: 0, elapsed: 1 secs) (at 2015-11-18 18:47:55.592460) ========= 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=70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009954 build_url: 'https://queue.taskcluster.net/v1/task/FIz-zW-JQTyDkWGBGCv3Jg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 1.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 1 secs) (at 2015-11-18 18:47:56.625182) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:47:56.625515) ========= 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=70a720fcaf18edea6683c2dc000001ad-1447898261.469164-718422727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004587 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:47:56.649399) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 18:47:56.649711) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 18:47:56.650010) ========= ========= Total master_lag: 20.98 =========