builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-019 starttime: 1448422968.9 results: success (0) buildid: 20151124173005 builduid: d2f08f29c500421c8d9a4ce721b90239 revision: f39db2865764809d45ea45f70e1ea0d56bdcc082 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.902192) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.902652) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.902971) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006638 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.927952) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.928277) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.943843) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.944229) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004678 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.993606) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.993909) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.994258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:48.997681) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 19:42:49-- 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% 221M=0s 2015-11-24 19:42:49 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161447 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.171275) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.174708) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017228 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.224080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.227582) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f39db2865764809d45ea45f70e1ea0d56bdcc082 --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 f39db2865764809d45ea45f70e1ea0d56bdcc082 --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=d60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 19:42:49,855 truncating revision to first 12 chars 2015-11-24 19:42:49,855 Setting DEBUG logging. 2015-11-24 19:42:49,855 attempt 1/10 2015-11-24 19:42:49,855 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f39db2865764?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 19:42:50,326 unpacking tar archive at: fx-team-f39db2865764/testing/mozharness/ program finished with exit code 0 elapsedTime=0.787742 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.028901) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.029359) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.039326) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.039665) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.040102) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-11-24 19:42:50.040400) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=d60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:42:50 INFO - MultiFileLogger online at 20151124 19:42:50 in /builds/slave/test-pgo 19:42:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 19:42:50 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 19:42:50 INFO - {'append_to_log': False, 19:42:50 INFO - 'base_work_dir': '/builds/slave/test-pgo', 19:42:50 INFO - 'blob_upload_branch': 'Fx-Team', 19:42:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 19:42:50 INFO - 'branch': 'Fx-Team', 19:42:50 INFO - 'buildbot_json_path': 'buildprops.json', 19:42:50 INFO - 'config_files': ('talos/linux_config.py',), 19:42:50 INFO - 'default_actions': ('clobber', 19:42:50 INFO - 'read-buildbot-config', 19:42:50 INFO - 'download-and-extract', 19:42:50 INFO - 'populate-webroot', 19:42:50 INFO - 'create-virtualenv', 19:42:50 INFO - 'install', 19:42:50 INFO - 'run-tests'), 19:42:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:42:50 INFO - 'download_minidump_stackwalk': True, 19:42:50 INFO - 'download_symbols': 'ondemand', 19:42:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:42:50 INFO - 'tooltool.py': '/tools/tooltool.py', 19:42:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:42:50 INFO - '/tools/misc-python/virtualenv.py')}, 19:42:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:42:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:42:50 INFO - 'installer_path': 'installer.exe', 19:42:50 INFO - 'log_level': 'info', 19:42:50 INFO - 'log_name': 'talos', 19:42:50 INFO - 'log_to_console': True, 19:42:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:42:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:42:50 INFO - 'opt_config_files': (), 19:42:50 INFO - 'pip_index': False, 19:42:50 INFO - 'sps_profile': False, 19:42:50 INFO - 'sps_profile_interval': 0, 19:42:50 INFO - 'suite': 'dromaeojs-e10s', 19:42:50 INFO - 'system_bits': '64', 19:42:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:42:50 INFO - 'title': 'talos-linux64-ix-019', 19:42:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:42:50 INFO - 'use_talos_json': True, 19:42:50 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 19:42:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:42:50 INFO - 'work_dir': 'build'} 19:42:50 INFO - ##### 19:42:50 INFO - ##### Running clobber step. 19:42:50 INFO - ##### 19:42:50 INFO - Running pre-action listener: _resource_record_pre_action 19:42:50 INFO - Running main action method: clobber 19:42:50 INFO - rmtree: /builds/slave/test-pgo/build 19:42:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 19:42:51 INFO - Running post-action listener: _resource_record_post_action 19:42:51 INFO - ##### 19:42:51 INFO - ##### Running read-buildbot-config step. 19:42:51 INFO - ##### 19:42:51 INFO - Running pre-action listener: _resource_record_pre_action 19:42:51 INFO - Running main action method: read_buildbot_config 19:42:51 INFO - Using buildbot properties: 19:42:51 INFO - { 19:42:51 INFO - "properties": { 19:42:51 INFO - "buildnumber": 108, 19:42:51 INFO - "product": "firefox", 19:42:51 INFO - "script_repo_revision": "production", 19:42:51 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 19:42:51 INFO - "repository": "", 19:42:51 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 19:42:51 INFO - "buildid": "20151124173005", 19:42:51 INFO - "slavename": "talos-linux64-ix-019", 19:42:51 INFO - "pgo_build": "True", 19:42:51 INFO - "basedir": "/builds/slave/test-pgo", 19:42:51 INFO - "project": "", 19:42:51 INFO - "platform": "ubuntu64_hw", 19:42:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:42:51 INFO - "slavebuilddir": "test", 19:42:51 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 19:42:51 INFO - "branch": "fx-team", 19:42:51 INFO - "repo_path": "integration/fx-team", 19:42:51 INFO - "stage_platform": "linux64-pgo", 19:42:51 INFO - "builduid": "d2f08f29c500421c8d9a4ce721b90239", 19:42:51 INFO - "revision": "f39db2865764809d45ea45f70e1ea0d56bdcc082" 19:42:51 INFO - }, 19:42:51 INFO - "sourcestamp": { 19:42:51 INFO - "repository": "", 19:42:51 INFO - "hasPatch": false, 19:42:51 INFO - "project": "", 19:42:51 INFO - "branch": "fx-team-linux64-pgo-talos", 19:42:51 INFO - "changes": [ 19:42:51 INFO - { 19:42:51 INFO - "category": null, 19:42:51 INFO - "files": [ 19:42:51 INFO - { 19:42:51 INFO - "url": null, 19:42:51 INFO - "name": "https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 19:42:51 INFO - } 19:42:51 INFO - ], 19:42:51 INFO - "repository": "", 19:42:51 INFO - "rev": "f39db2865764809d45ea45f70e1ea0d56bdcc082", 19:42:51 INFO - "who": "sendchange", 19:42:51 INFO - "when": 1448422951, 19:42:51 INFO - "number": 6703557, 19:42:51 INFO - "comments": "", 19:42:51 INFO - "project": "", 19:42:51 INFO - "at": "Tue 24 Nov 2015 19:42:31", 19:42:51 INFO - "branch": "fx-team-linux64-pgo-talos", 19:42:51 INFO - "revlink": "", 19:42:51 INFO - "properties": [ 19:42:51 INFO - [ 19:42:51 INFO - "buildid", 19:42:51 INFO - "20151124173005", 19:42:51 INFO - "Change" 19:42:51 INFO - ], 19:42:51 INFO - [ 19:42:51 INFO - "builduid", 19:42:51 INFO - "d2f08f29c500421c8d9a4ce721b90239", 19:42:51 INFO - "Change" 19:42:51 INFO - ], 19:42:51 INFO - [ 19:42:51 INFO - "pgo_build", 19:42:51 INFO - "True", 19:42:51 INFO - "Change" 19:42:51 INFO - ] 19:42:51 INFO - ], 19:42:51 INFO - "revision": "f39db2865764809d45ea45f70e1ea0d56bdcc082" 19:42:51 INFO - } 19:42:51 INFO - ], 19:42:51 INFO - "revision": "f39db2865764809d45ea45f70e1ea0d56bdcc082" 19:42:51 INFO - } 19:42:51 INFO - } 19:42:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 19:42:51 INFO - Running post-action listener: _resource_record_post_action 19:42:51 INFO - ##### 19:42:51 INFO - ##### Running download-and-extract step. 19:42:51 INFO - ##### 19:42:51 INFO - Running pre-action listener: _resource_record_pre_action 19:42:51 INFO - Running main action method: download_and_extract 19:42:51 INFO - mkdir: /builds/slave/test-pgo/build/tests 19:42: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')]} 19:42:51 INFO - https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 19:42:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/test_packages.json 19:42:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/test_packages.json 19:42:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 19:42:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 19:42:52 INFO - Downloaded 1302 bytes. 19:42:52 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 19:42:52 INFO - Using the following test package requirements: 19:42:52 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 19:42:52 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:42:52 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:52 INFO - u'jsshell-linux-x86_64.zip'], 19:42:52 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:42:52 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 19:42:52 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:42:52 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:42:52 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:42:52 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 19:42:52 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:42:52 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:42:52 INFO - https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:42:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:42:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:42:53 INFO - Downloaded 22530453 bytes. 19:42:53 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 19:42:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:53 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 19:42:54 INFO - Return code: 0 19:42:54 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 19:42: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')]} 19:42:54 INFO - https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:42:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:42:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:42:58 INFO - Downloaded 22530453 bytes. 19:42:58 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 19:42:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:58 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 19:42:59 INFO - Return code: 0 19:42: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')]} 19:42:59 INFO - https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:42:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 19:42:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 19:42:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:42:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:43:00 INFO - Downloaded 11131717 bytes. 19:43:00 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 19:43:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 19:43:00 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 19:43:01 INFO - Return code: 0 19:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:01 INFO - https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:43:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:43:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:43:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:43:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:43:02 INFO - Downloaded 58394943 bytes. 19:43:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:43:02 INFO - mkdir: /builds/slave/test-pgo/properties 19:43:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 19:43:02 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 19:43:02 INFO - Contents: 19:43:02 INFO - build_url:https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:43:02 INFO - Running post-action listener: _resource_record_post_action 19:43:02 INFO - Running post-action listener: set_extra_try_arguments 19:43:02 INFO - ##### 19:43:02 INFO - ##### Running populate-webroot step. 19:43:02 INFO - ##### 19:43:02 INFO - Running pre-action listener: _resource_record_pre_action 19:43:02 INFO - Running main action method: populate_webroot 19:43:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:43:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:43:02 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 19:43:02 INFO - '../fennec_ids.txt'], 19:43:02 INFO - 'tests': ['tcheck2']}, 19:43:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:43:02 INFO - '--tppagecycles', 19:43:02 INFO - '7'], 19:43:02 INFO - 'tests': ['tsvgm']}}, 19:43:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 19:43:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 19:43:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:43:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:43:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 19:43:02 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:43:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:43:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:02 INFO - 'talos_options': ['--e10s'], 19:43:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:43:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:02 INFO - 'tests': ['damp', 'tps']}, 19:43:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:02 INFO - 'talos_options': ['--e10s'], 19:43:02 INFO - 'tests': ['damp', 'tps']}, 19:43:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 19:43:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:43:02 INFO - 'other': {'tests': ['a11yr', 19:43:02 INFO - 'ts_paint', 19:43:02 INFO - 'tpaint', 19:43:02 INFO - 'sessionrestore', 19:43:02 INFO - 'sessionrestore_no_auto_restore']}, 19:43:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 19:43:02 INFO - 'tests': ['a11yr', 19:43:02 INFO - 'ts_paint', 19:43:02 INFO - 'tpaint', 19:43:02 INFO - 'sessionrestore', 19:43:02 INFO - 'sessionrestore_no_auto_restore']}, 19:43:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 19:43:02 INFO - 'tests': ['a11yr', 19:43:02 INFO - 'ts_paint', 19:43:02 INFO - 'tpaint', 19:43:02 INFO - 'sessionrestore', 19:43:02 INFO - 'sessionrestore_no_auto_restore']}, 19:43:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 19:43:02 INFO - 'tests': ['a11yr', 19:43:02 INFO - 'ts_paint', 19:43:02 INFO - 'tpaint', 19:43:02 INFO - 'sessionrestore', 19:43:02 INFO - 'sessionrestore_no_auto_restore']}, 19:43:02 INFO - 'other_l64': {'tests': ['a11yr', 19:43:02 INFO - 'ts_paint', 19:43:02 INFO - 'tpaint', 19:43:02 INFO - 'sessionrestore', 19:43:02 INFO - 'sessionrestore_no_auto_restore']}, 19:43:02 INFO - 'other_nol64': {'tests': ['a11yr', 19:43:02 INFO - 'ts_paint', 19:43:02 INFO - 'tpaint', 19:43:02 INFO - 'sessionrestore', 19:43:02 INFO - 'sessionrestore_no_auto_restore']}, 19:43:02 INFO - 'svgr': {'tests': ['tsvgx', 19:43:02 INFO - 'tsvgr_opacity', 19:43:02 INFO - 'tart', 19:43:02 INFO - 'tscrollx', 19:43:02 INFO - 'cart']}, 19:43:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 19:43:02 INFO - 'tests': ['tsvgx', 19:43:02 INFO - 'tsvgr_opacity', 19:43:02 INFO - 'tart', 19:43:02 INFO - 'tscrollx', 19:43:02 INFO - 'cart']}, 19:43:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:02 INFO - 'tests': ['tp5o']}, 19:43:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:02 INFO - 'talos_options': ['--e10s'], 19:43:02 INFO - 'tests': ['tp5o']}, 19:43:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:02 INFO - 'talos_options': ['--xperf_path', 19:43:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:43:02 INFO - 'tests': ['tp5n']}, 19:43:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:02 INFO - 'talos_options': ['--e10s', 19:43:02 INFO - '--xperf_path', 19:43:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:43:02 INFO - 'tests': ['tp5n']}}, 19:43:02 INFO - 'talos.zip': {'path': '', 19:43:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:43:02 INFO - Running post-action listener: _resource_record_post_action 19:43:02 INFO - ##### 19:43:02 INFO - ##### Running create-virtualenv step. 19:43:02 INFO - ##### 19:43:02 INFO - Running pre-action listener: _resource_record_pre_action 19:43:02 INFO - Running main action method: create_virtualenv 19:43:02 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 19:43:02 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 19:43:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 19:43:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:43:02 INFO - Using real prefix '/usr' 19:43:02 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 19:43:03 INFO - Installing distribute.............................................................................................................................................................................................done. 19:43:05 INFO - Installing pip.................done. 19:43:05 INFO - Return code: 0 19:43:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 19:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145d640>, '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 0x145e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145e9d0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', '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 19:43:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 19:43:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:43:05 INFO - Using env: {'DISPLAY': ':0', 19:43:05 INFO - 'HOME': '/home/cltbld', 19:43:05 INFO - 'LANG': 'en_US.UTF-8', 19:43:05 INFO - 'LANGUAGE': 'en_US:en', 19:43:05 INFO - 'LOGNAME': 'cltbld', 19:43:05 INFO - 'MAIL': '/var/mail/cltbld', 19:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:05 INFO - 'MOZ_NO_REMOTE': '1', 19:43:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:05 INFO - 'NO_EM_RESTART': '1', 19:43:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:05 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:05 INFO - 'SHELL': '/bin/bash', 19:43:05 INFO - 'SHLVL': '1', 19:43:05 INFO - 'TERM': 'linux', 19:43:05 INFO - 'TMOUT': '86400', 19:43:05 INFO - 'USER': 'cltbld', 19:43:05 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', 19:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:05 INFO - '_': '/tools/buildbot/bin/python'} 19:43:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:05 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 19:43:05 INFO - Cleaning up... 19:43:05 INFO - Return code: 0 19:43:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 19:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11a1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145d640>, '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 0x145e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145e9d0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', '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 19:43:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 19:43:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 19:43:05 INFO - Using env: {'DISPLAY': ':0', 19:43:05 INFO - 'HOME': '/home/cltbld', 19:43:05 INFO - 'LANG': 'en_US.UTF-8', 19:43:05 INFO - 'LANGUAGE': 'en_US:en', 19:43:05 INFO - 'LOGNAME': 'cltbld', 19:43:05 INFO - 'MAIL': '/var/mail/cltbld', 19:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:05 INFO - 'MOZ_NO_REMOTE': '1', 19:43:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:05 INFO - 'NO_EM_RESTART': '1', 19:43:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:05 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:05 INFO - 'SHELL': '/bin/bash', 19:43:05 INFO - 'SHLVL': '1', 19:43:05 INFO - 'TERM': 'linux', 19:43:05 INFO - 'TMOUT': '86400', 19:43:05 INFO - 'USER': 'cltbld', 19:43:05 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', 19:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:05 INFO - '_': '/tools/buildbot/bin/python'} 19:43:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:05 INFO - Downloading/unpacking psutil>=0.7.1 19:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:07 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 19:43:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 19:43:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 19:43:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:43:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:43:07 INFO - Installing collected packages: psutil 19:43:07 INFO - Running setup.py install for psutil 19:43:07 INFO - building 'psutil._psutil_linux' extension 19:43:07 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 19:43:07 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 19:43:07 INFO - building 'psutil._psutil_posix' extension 19:43:07 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 19:43:07 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 19:43:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:43:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:43:07 INFO - Successfully installed psutil 19:43:07 INFO - Cleaning up... 19:43:07 INFO - Return code: 0 19:43:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 19:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11a1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145d640>, '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 0x145e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145e9d0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', '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 19:43:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 19:43:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 19:43:07 INFO - Using env: {'DISPLAY': ':0', 19:43:07 INFO - 'HOME': '/home/cltbld', 19:43:07 INFO - 'LANG': 'en_US.UTF-8', 19:43:07 INFO - 'LANGUAGE': 'en_US:en', 19:43:07 INFO - 'LOGNAME': 'cltbld', 19:43:07 INFO - 'MAIL': '/var/mail/cltbld', 19:43:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:07 INFO - 'MOZ_NO_REMOTE': '1', 19:43:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:07 INFO - 'NO_EM_RESTART': '1', 19:43:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:07 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:07 INFO - 'SHELL': '/bin/bash', 19:43:07 INFO - 'SHLVL': '1', 19:43:07 INFO - 'TERM': 'linux', 19:43:07 INFO - 'TMOUT': '86400', 19:43:07 INFO - 'USER': 'cltbld', 19:43:07 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', 19:43:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:07 INFO - '_': '/tools/buildbot/bin/python'} 19:43:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:43:08 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 19:43:08 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 19:43:08 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 19:43:08 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 19:43:08 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 19:43:08 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 19:43:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:43:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 19:43:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:43:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:43:09 INFO - Installing collected packages: mozsystemmonitor 19:43:09 INFO - Running setup.py install for mozsystemmonitor 19:43:09 INFO - Successfully installed mozsystemmonitor 19:43:09 INFO - Cleaning up... 19:43:09 INFO - Return code: 0 19:43:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 19:43:09 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')]} 19:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:09 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')]} 19:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:09 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 0x11a1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145d640>, '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 0x145e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145e9d0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', '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 19:43:09 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 19:43:09 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 19:43:09 INFO - Using env: {'DISPLAY': ':0', 19:43:09 INFO - 'HOME': '/home/cltbld', 19:43:09 INFO - 'LANG': 'en_US.UTF-8', 19:43:09 INFO - 'LANGUAGE': 'en_US:en', 19:43:09 INFO - 'LOGNAME': 'cltbld', 19:43:09 INFO - 'MAIL': '/var/mail/cltbld', 19:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:09 INFO - 'MOZ_NO_REMOTE': '1', 19:43:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:09 INFO - 'NO_EM_RESTART': '1', 19:43:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:09 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:09 INFO - 'SHELL': '/bin/bash', 19:43:09 INFO - 'SHLVL': '1', 19:43:09 INFO - 'TERM': 'linux', 19:43:09 INFO - 'TMOUT': '86400', 19:43:09 INFO - 'USER': 'cltbld', 19:43:09 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', 19:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:09 INFO - '_': '/tools/buildbot/bin/python'} 19:43:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:10 INFO - Downloading/unpacking blobuploader==1.2.4 19:43:10 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 19:43:10 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 19:43:10 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 19:43:10 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 19:43:10 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 19:43:10 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 19:43:11 INFO - Downloading blobuploader-1.2.4.tar.gz 19:43:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 19:43:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:43:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:43:11 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 19:43:11 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 19:43:11 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 19:43:11 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 19:43:11 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 19:43:11 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 19:43:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 19:43:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 19:43:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:43:12 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 19:43:12 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 19:43:12 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 19:43:12 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 19:43:12 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 19:43:12 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 19:43:12 INFO - Downloading docopt-0.6.1.tar.gz 19:43:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 19:43:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 19:43:12 INFO - Installing collected packages: blobuploader, requests, docopt 19:43:12 INFO - Running setup.py install for blobuploader 19:43:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:43:12 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 19:43:12 INFO - Running setup.py install for requests 19:43:13 INFO - Running setup.py install for docopt 19:43:13 INFO - Successfully installed blobuploader requests docopt 19:43:13 INFO - Cleaning up... 19:43:13 INFO - Return code: 0 19:43:13 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:43:13 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')]} 19:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:13 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')]} 19:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:13 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 0x11a1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145d640>, '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 0x145e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145e9d0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', '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 19:43:13 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 19:43:13 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 19:43:13 INFO - Using env: {'DISPLAY': ':0', 19:43:13 INFO - 'HOME': '/home/cltbld', 19:43:13 INFO - 'LANG': 'en_US.UTF-8', 19:43:13 INFO - 'LANGUAGE': 'en_US:en', 19:43:13 INFO - 'LOGNAME': 'cltbld', 19:43:13 INFO - 'MAIL': '/var/mail/cltbld', 19:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:13 INFO - 'MOZ_NO_REMOTE': '1', 19:43:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:13 INFO - 'NO_EM_RESTART': '1', 19:43:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:13 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:13 INFO - 'SHELL': '/bin/bash', 19:43:13 INFO - 'SHLVL': '1', 19:43:13 INFO - 'TERM': 'linux', 19:43:13 INFO - 'TMOUT': '86400', 19:43:13 INFO - 'USER': 'cltbld', 19:43:13 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', 19:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:13 INFO - '_': '/tools/buildbot/bin/python'} 19:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:43:13 INFO - Running setup.py (path:/tmp/pip-C0oQf9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:43:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:43:13 INFO - Running setup.py (path:/tmp/pip-kSLZPy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:43:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:43:13 INFO - Running setup.py (path:/tmp/pip-GITg5H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:43:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:43:13 INFO - Running setup.py (path:/tmp/pip-KIZdqU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:43:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:43:13 INFO - Running setup.py (path:/tmp/pip-FOeRiT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:43:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:43:13 INFO - Running setup.py (path:/tmp/pip-WLqkoc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:43:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:43:13 INFO - Running setup.py (path:/tmp/pip-j6CuSs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:43:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:43:13 INFO - Running setup.py (path:/tmp/pip-XYbTch-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:43:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:43:13 INFO - Running setup.py (path:/tmp/pip-c7jxe2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:43:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:43:14 INFO - Running setup.py (path:/tmp/pip-reONfJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:43:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:43:14 INFO - Running setup.py (path:/tmp/pip-9kKyq9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:43:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:43:14 INFO - Running setup.py (path:/tmp/pip-ZceVd8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:43:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:43:14 INFO - Running setup.py (path:/tmp/pip-0uBN87-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:43:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:43:14 INFO - Running setup.py (path:/tmp/pip-sNAuER-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:43:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:43:14 INFO - Running setup.py (path:/tmp/pip-cJcfMn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:43:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:43:14 INFO - Running setup.py (path:/tmp/pip-ols5bk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:43:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:43:14 INFO - Running setup.py (path:/tmp/pip-5aXgDi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:43:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:43:14 INFO - Running setup.py install for manifestparser 19:43:14 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 19:43:14 INFO - Running setup.py install for mozcrash 19:43:14 INFO - Running setup.py install for mozdebug 19:43:14 INFO - Running setup.py install for mozdevice 19:43:15 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Running setup.py install for mozfile 19:43:15 INFO - Running setup.py install for mozhttpd 19:43:15 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Running setup.py install for mozinfo 19:43:15 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Running setup.py install for mozInstall 19:43:15 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Running setup.py install for mozleak 19:43:15 INFO - Running setup.py install for mozlog 19:43:15 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Running setup.py install for moznetwork 19:43:15 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Running setup.py install for mozprocess 19:43:15 INFO - Running setup.py install for mozprofile 19:43:15 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 19:43:15 INFO - Running setup.py install for mozrunner 19:43:16 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 19:43:16 INFO - Running setup.py install for mozscreenshot 19:43:16 INFO - Running setup.py install for moztest 19:43:16 INFO - Running setup.py install for mozversion 19:43:16 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 19:43:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:43:16 INFO - Cleaning up... 19:43:16 INFO - Return code: 0 19:43:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:43:16 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')]} 19:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:16 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')]} 19:43:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:16 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 0x11a1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145d640>, '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 0x145e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145e9d0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', '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 19:43:16 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 19:43:16 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 19:43:16 INFO - Using env: {'DISPLAY': ':0', 19:43:16 INFO - 'HOME': '/home/cltbld', 19:43:16 INFO - 'LANG': 'en_US.UTF-8', 19:43:16 INFO - 'LANGUAGE': 'en_US:en', 19:43:16 INFO - 'LOGNAME': 'cltbld', 19:43:16 INFO - 'MAIL': '/var/mail/cltbld', 19:43:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:16 INFO - 'MOZ_NO_REMOTE': '1', 19:43:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:16 INFO - 'NO_EM_RESTART': '1', 19:43:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:16 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:16 INFO - 'SHELL': '/bin/bash', 19:43:16 INFO - 'SHLVL': '1', 19:43:16 INFO - 'TERM': 'linux', 19:43:16 INFO - 'TMOUT': '86400', 19:43:16 INFO - 'USER': 'cltbld', 19:43:16 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', 19:43:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:16 INFO - '_': '/tools/buildbot/bin/python'} 19:43:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:43:16 INFO - Running setup.py (path:/tmp/pip-SjknPt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:43:16 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)) 19:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:43:16 INFO - Running setup.py (path:/tmp/pip-XJ5exb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:43:16 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)) 19:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:43:16 INFO - Running setup.py (path:/tmp/pip-t3M4AJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:43:16 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)) 19:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:43:16 INFO - Running setup.py (path:/tmp/pip-fbY1uK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:43:16 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)) 19:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:43:16 INFO - Running setup.py (path:/tmp/pip-SATuC9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:43:16 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)) 19:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:43:17 INFO - Running setup.py (path:/tmp/pip-rjmGVr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:43:17 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:43:17 INFO - Running setup.py (path:/tmp/pip-hJawaz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:43:17 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:43:17 INFO - Running setup.py (path:/tmp/pip-Ma1eKV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:43:17 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:43:17 INFO - Running setup.py (path:/tmp/pip-kPSB0j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:43:17 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:43:17 INFO - Running setup.py (path:/tmp/pip-oQAXUR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:43:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:43:17 INFO - Running setup.py (path:/tmp/pip-2wkfxQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:43:17 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:43:17 INFO - Running setup.py (path:/tmp/pip-9FmaM2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:43:17 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:43:17 INFO - Running setup.py (path:/tmp/pip-vYrw92-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:43:17 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:43:17 INFO - Running setup.py (path:/tmp/pip-2zmYFh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:43:17 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:43:17 INFO - Running setup.py (path:/tmp/pip-Qqk4T6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:43:17 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:43:17 INFO - Running setup.py (path:/tmp/pip-RvNfFN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:43:17 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)) 19:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:43:17 INFO - Running setup.py (path:/tmp/pip-wNqz30-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:43:17 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)) 19:43:17 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)) 19:43:17 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)) 19:43:17 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)) 19:43:17 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)) 19:43:17 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)) 19:43:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 19:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:19 INFO - Downloading blessings-1.5.1.tar.gz 19:43:19 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 19:43:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 19:43:19 INFO - Installing collected packages: blessings 19:43:19 INFO - Running setup.py install for blessings 19:43:19 INFO - Successfully installed blessings 19:43:19 INFO - Cleaning up... 19:43:19 INFO - Return code: 0 19:43:19 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 19:43:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:43:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:43:19 INFO - Reading from file tmpfile_stdout 19:43:19 INFO - Current package versions: 19:43:19 INFO - argparse == 1.2.1 19:43:19 INFO - blessings == 1.5.1 19:43:19 INFO - blobuploader == 1.2.4 19:43:19 INFO - docopt == 0.6.1 19:43:19 INFO - manifestparser == 1.1 19:43:19 INFO - mozInstall == 1.12 19:43:19 INFO - mozcrash == 0.16 19:43:19 INFO - mozdebug == 0.1 19:43:19 INFO - mozdevice == 0.47 19:43:19 INFO - mozfile == 1.2 19:43:19 INFO - mozhttpd == 0.7 19:43:19 INFO - mozinfo == 0.9 19:43:19 INFO - mozleak == 0.1 19:43:19 INFO - mozlog == 3.1 19:43:19 INFO - moznetwork == 0.27 19:43:19 INFO - mozprocess == 0.22 19:43:19 INFO - mozprofile == 0.27 19:43:19 INFO - mozrunner == 6.11 19:43:19 INFO - mozscreenshot == 0.1 19:43:19 INFO - mozsystemmonitor == 0.0 19:43:19 INFO - moztest == 0.7 19:43:19 INFO - mozversion == 1.4 19:43:19 INFO - psutil == 3.1.1 19:43:19 INFO - requests == 1.2.3 19:43:19 INFO - wsgiref == 0.1.2 19:43:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x11a1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145d640>, '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 0x145e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145e9d0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', '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 19:43:19 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 19:43:19 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 19:43:19 INFO - Using env: {'DISPLAY': ':0', 19:43:19 INFO - 'HOME': '/home/cltbld', 19:43:19 INFO - 'LANG': 'en_US.UTF-8', 19:43:19 INFO - 'LANGUAGE': 'en_US:en', 19:43:19 INFO - 'LOGNAME': 'cltbld', 19:43:19 INFO - 'MAIL': '/var/mail/cltbld', 19:43:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:19 INFO - 'MOZ_NO_REMOTE': '1', 19:43:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:19 INFO - 'NO_EM_RESTART': '1', 19:43:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:19 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:19 INFO - 'SHELL': '/bin/bash', 19:43:19 INFO - 'SHLVL': '1', 19:43:19 INFO - 'TERM': 'linux', 19:43:19 INFO - 'TMOUT': '86400', 19:43:19 INFO - 'USER': 'cltbld', 19:43:19 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', 19:43:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:19 INFO - '_': '/tools/buildbot/bin/python'} 19:43:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:20 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)) 19:43:20 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)) 19:43:20 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)) 19:43:20 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)) 19:43:20 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)) 19:43:20 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)) 19:43:20 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)) 19:43:20 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)) 19:43:20 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)) 19:43:20 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)) 19:43:20 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)) 19:43:20 INFO - Cleaning up... 19:43:20 INFO - Return code: 0 19:43:20 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 19:43: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')]} 19:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43: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')]} 19:43:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:20 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145d640>, '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 0x145e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145e9d0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', '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 19:43:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 19:43:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:43:20 INFO - Using env: {'DISPLAY': ':0', 19:43:20 INFO - 'HOME': '/home/cltbld', 19:43:20 INFO - 'LANG': 'en_US.UTF-8', 19:43:20 INFO - 'LANGUAGE': 'en_US:en', 19:43:20 INFO - 'LOGNAME': 'cltbld', 19:43:20 INFO - 'MAIL': '/var/mail/cltbld', 19:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:20 INFO - 'MOZ_NO_REMOTE': '1', 19:43:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:20 INFO - 'NO_EM_RESTART': '1', 19:43:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:20 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:20 INFO - 'SHELL': '/bin/bash', 19:43:20 INFO - 'SHLVL': '1', 19:43:20 INFO - 'TERM': 'linux', 19:43:20 INFO - 'TMOUT': '86400', 19:43:20 INFO - 'USER': 'cltbld', 19:43:20 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', 19:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:20 INFO - '_': '/tools/buildbot/bin/python'} 19:43:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:20 INFO - Downloading/unpacking jsonschema 19:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:43:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 19:43:22 INFO - Downloading/unpacking functools32 (from jsonschema) 19:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:22 INFO - Downloading functools32-3.2.3-2.tar.gz 19:43:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 19:43:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 19:43:22 INFO - warning: no files found matching '*.txt' 19:43:22 INFO - no previously-included directories found matching 'build' 19:43:22 INFO - no previously-included directories found matching 'dist' 19:43:22 INFO - no previously-included directories found matching '.git*' 19:43:22 INFO - Installing collected packages: jsonschema, functools32 19:43:22 INFO - Running setup.py install for functools32 19:43:22 INFO - warning: no files found matching '*.txt' 19:43:22 INFO - no previously-included directories found matching 'build' 19:43:22 INFO - no previously-included directories found matching 'dist' 19:43:22 INFO - no previously-included directories found matching '.git*' 19:43:22 INFO - Successfully installed jsonschema functools32 19:43:22 INFO - Cleaning up... 19:43:22 INFO - Return code: 0 19:43:22 INFO - Running post-action listener: _resource_record_post_action 19:43:22 INFO - Running post-action listener: _start_resource_monitoring 19:43:22 INFO - Starting resource monitoring. 19:43:22 INFO - ##### 19:43:22 INFO - ##### Running install step. 19:43:22 INFO - ##### 19:43:22 INFO - Running pre-action listener: _resource_record_pre_action 19:43:22 INFO - Running main action method: install 19:43:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:43:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:43:22 INFO - Reading from file tmpfile_stdout 19:43:22 INFO - Detecting whether we're running mozinstall >=1.0... 19:43:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 19:43:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 19:43:22 INFO - Reading from file tmpfile_stdout 19:43:22 INFO - Output received: 19:43:22 INFO - Usage: mozinstall [options] installer 19:43:22 INFO - Options: 19:43:22 INFO - -h, --help show this help message and exit 19:43:22 INFO - -d DEST, --destination=DEST 19:43:22 INFO - Directory to install application into. [default: 19:43:22 INFO - "/builds/slave/test-pgo"] 19:43:22 INFO - --app=APP Application being installed. [default: firefox] 19:43:22 INFO - mkdir: /builds/slave/test-pgo/build/application 19:43:22 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'] 19:43:22 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 19:43:37 INFO - Reading from file tmpfile_stdout 19:43:37 INFO - Output received: 19:43:37 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 19:43:37 INFO - Running post-action listener: _resource_record_post_action 19:43:37 INFO - ##### 19:43:37 INFO - ##### Running run-tests step. 19:43:37 INFO - ##### 19:43:37 INFO - Running pre-action listener: _resource_record_pre_action 19:43:37 INFO - Running main action method: run_tests 19:43:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 19:43:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 19:43:37 INFO - Python 2.7.3 19:43:37 INFO - Return code: 0 19:43:37 INFO - grabbing minidump binary from tooltool 19:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:37 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 0x145d640>, '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 0x145e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145e9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 19:43:37 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 19:43:37 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 19:43:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:43:37 INFO - Return code: 0 19:43:37 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 19:43:37 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 19:43:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 19:43:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 19:43:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 19:43:37 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 19:43:37 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-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --webServer localhost 19:43:37 INFO - Using env: {'DISPLAY': ':0', 19:43:37 INFO - 'HOME': '/home/cltbld', 19:43:37 INFO - 'LANG': 'en_US.UTF-8', 19:43:37 INFO - 'LANGUAGE': 'en_US:en', 19:43:37 INFO - 'LOGNAME': 'cltbld', 19:43:37 INFO - 'MAIL': '/var/mail/cltbld', 19:43:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:43:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 19:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:37 INFO - 'MOZ_NO_REMOTE': '1', 19:43:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:37 INFO - 'NO_EM_RESTART': '1', 19:43:37 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:37 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:37 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 19:43:37 INFO - 'SHELL': '/bin/bash', 19:43:37 INFO - 'SHLVL': '1', 19:43:37 INFO - 'TERM': 'linux', 19:43:37 INFO - 'TMOUT': '86400', 19:43:37 INFO - 'USER': 'cltbld', 19:43:37 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050', 19:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:37 INFO - '_': '/tools/buildbot/bin/python'} 19:43:37 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost'] with output_timeout 3600 19:43:37 INFO - mozversion INFO | application_buildid: 20151124173005 19:43:37 INFO - mozversion INFO | application_changeset: f39db2865764809d45ea45f70e1ea0d56bdcc082 19:43:37 INFO - mozversion INFO | application_display_name: Nightly 19:43:37 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:43:37 INFO - mozversion INFO | application_name: Firefox 19:43:37 INFO - mozversion INFO | application_remotingname: firefox 19:43:37 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 19:43:37 INFO - mozversion INFO | application_vendor: Mozilla 19:43:37 INFO - mozversion INFO | application_version: 45.0a1 19:43:37 INFO - mozversion INFO | platform_buildid: 20151124173005 19:43:37 INFO - mozversion INFO | platform_changeset: f39db2865764809d45ea45f70e1ea0d56bdcc082 19:43:37 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 19:43:37 INFO - mozversion INFO | platform_version: 45.0a1 19:43:37 INFO - 2015-11-24 19:43:37,775 DEBUG : using testdate: 1448423017 19:43:37 INFO - 2015-11-24 19:43:37,775 DEBUG : actual date: 1448423017 19:43:37 INFO - 2015-11-24 19:43:37,784 INFO : starting webserver on 'localhost:59286' 19:43:37 INFO - 2015-11-24 19:43:37,785 INFO : Starting test suite talos-linux64-ix-019.e 19:43:37 INFO - 2015-11-24 19:43:37,785 INFO : Starting test dromaeo_css 19:43:37 INFO - 2015-11-24 19:43:37,785 DEBUG : operating with platform_type : linux_ 19:43:37 INFO - 2015-11-24 19:43:37,786 INFO : Initialising browser for dromaeo_css test... 19:43:37 INFO - 2015-11-24 19:43:37,802 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpL4fASX/profile http://localhost:59286/getInfo.html 19:43:42 INFO - 2015-11-24 19:43:42,284 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:43:42 INFO - 2015-11-24 19:43:42,285 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:43:42 INFO - 2015-11-24 19:43:42,285 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:43:42 INFO - 2015-11-24 19:43:42,285 DEBUG : BROWSER_OUTPUT: __metrics 19:43:43 INFO - 2015-11-24 19:43:43,281 INFO : Browser initialized. 19:43:43 INFO - 2015-11-24 19:43:43,282 INFO : Running cycle 1/1 for dromaeo_css test... 19:43:43 INFO - 2015-11-24 19:43:43,282 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpL4fASX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:43:54 INFO - 2015-11-24 19:43:54,964 DEBUG : BROWSER_OUTPUT: RSS: Main: 138444800 19:43:54 INFO - 2015-11-24 19:43:54,964 DEBUG : BROWSER_OUTPUT: 19:44:44 INFO - 2015-11-24 19:44:44,577 DEBUG : BROWSER_OUTPUT: 1448423084576 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] 19:46:53 INFO - 2015-11-24 19:46:53,397 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59286/tests/dromaeo/cssquery-ext.html) 19:46:55 INFO - 2015-11-24 19:46:55,435 DEBUG : BROWSER_OUTPUT: RSS: Main: 148217856 19:46:55 INFO - 2015-11-24 19:46:55,435 DEBUG : BROWSER_OUTPUT: 19:49:57 INFO - 2015-11-24 19:49:57,520 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/dromaeo/cssquery-ext.html (next: http://localhost:59286/tests/dromaeo/cssquery-jquery.html) 19:49:58 INFO - 2015-11-24 19:49:58,107 DEBUG : BROWSER_OUTPUT: RSS: Main: 142536704 19:49:58 INFO - 2015-11-24 19:49:58,107 DEBUG : BROWSER_OUTPUT: 19:52:59 INFO - 2015-11-24 19:52:59,749 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59286/tests/dromaeo/cssquery-mootools.html) 19:53:00 INFO - 2015-11-24 19:53:00,334 DEBUG : BROWSER_OUTPUT: RSS: Main: 143159296 19:53:00 INFO - 2015-11-24 19:53:00,334 DEBUG : BROWSER_OUTPUT: 19:56:02 INFO - 2015-11-24 19:56:02,001 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59286/tests/dromaeo/cssquery-prototype.html) 19:56:02 INFO - 2015-11-24 19:56:02,602 DEBUG : BROWSER_OUTPUT: RSS: Main: 141590528 19:56:02 INFO - 2015-11-24 19:56:02,602 DEBUG : BROWSER_OUTPUT: 19:59:04 INFO - 2015-11-24 19:59:04,877 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59286/tests/dromaeo/cssquery-yui.html) 19:59:05 INFO - 2015-11-24 19:59:05,460 DEBUG : BROWSER_OUTPUT: RSS: Main: 142057472 19:59:05 INFO - 2015-11-24 19:59:05,460 DEBUG : BROWSER_OUTPUT: 20:02:07 INFO - 2015-11-24 20:02:07,757 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/dromaeo/cssquery-yui.html (next: http://localhost:59286/tests/dromaeo/cssquery-dojo.html) 20:02:08 INFO - 2015-11-24 20:02:08,007 DEBUG : BROWSER_OUTPUT: RSS: Main: 144482304 20:02:08 INFO - 2015-11-24 20:02:08,007 DEBUG : BROWSER_OUTPUT: 20:02:08 INFO - 2015-11-24 20:02:08,007 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:02:08 INFO - 2015-11-24 20:02:08,007 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:02:08 INFO - 2015-11-24 20:02:08,007 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:02:08 INFO - 2015-11-24 20:02:08,007 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:02:08 INFO - 2015-11-24 20:02:08,007 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3241.6434192238853;3302.3395320935806;3332.850021749021;3343.1976325681844;3358.983205083974;5255.500727430897;5423.457654234575;5438.265834112389;5442.8911421771545;5451.618386712932;5290.523852853252;5303.734813259341;5422.376426710929;5440.510354068133;5496.1755736639425;4942.233953737176;5026.447090820026;5082;5116.667416617926;5129.128048231791;4989.675671081368;5064.5695115915005;5081.18701007837;5144.768485418146;5172.922406163911;4817.638677099231;4945.431275403303;4960.131976904064;5005.124103281929;5056.570191533724;5023.422306434771;5044.319016932704;5046.34397528319;5052.267421223902;5103.106956282672;4997.800087996476;5019.272205530214;5020.673656212334;5025.969676216367;5029.874253143701;5102.974485127587;5157.638965272433;5194.480551944813;5261.921071183935;5281.260623512711;6013.1581578579035;6017.277926648786;6032.366601506823;6039.607425517372;6061.393860613904;5747.568932330092;6074.210352654127;6089.421504957022;6091.2995005574485;6104.267487901436;5513.724313784335;5607.52336051432;5710.543156547466;5726.742296596663;5758.3665796762325;6104.847378815476;6120.806442743622;6153.630782153085;6156.045812899008;6157.384261573807;5712.714364281769;5839.036558967808;5925.881482370329;5951.226340575698;5966.522678185735;5906.616069955439;5965.612235204698;5977.312609049928;5981.162637230791;5994.220751302303;5908.320543137508;5930.525557955353;5971.940280597225;5980.671063091485;5981.611195272293;5743.62666426681;5801.3328467225965;5886.705664716833;5927.436893495111;5989.970050149721;4491.236489796743;4552.567506086916;4562.726236425825;4592.380028696083;4594.7702614869795;4106.7125301228625;4116.855910043134;4130.876073717794;4134.1731653668785;4161.25097482456;5453.372862120827;5529.198266251371;5613.382527921925;5776.711164441761;5783.65298082108;5959.970200148971;5976.581639285208;5978.760849566055;5986.551008674367;6026.246719160105;5655.4910058094965;5656.604037717402;5660.8867822643315;5661.915071273934;5663.263775709132;4874;4967.453580106186;4969.329140566049;4973.403191617029;5039.067772462033;6035.336113027563;6090.299615544269;6134.344744984565;6149.846253843601;6166.876662466726;6045.244344456943;6069.848253793691;6098.871708733898;6100.23747031621;6103.992841181333;5932.199153114274;6231.9094158521175;6332.050192471166;6340.682965851597;6341.207349081365;5776.826695199151;5798.333191682842;5902.557308201816;5936.970315148396;5979.162917191684;6060.787872424444;6064.757409703562;6070.271567411939;6071.453569178795;6101.694915254131;5547.7780888764;5943.257092863392;5952.642841429528;5955.731992060281;5957.880842383215;5721.771129154788;5727.341355744143;5732.885342293214;5739.7417116229035;5742;5596;5638.097904335286;5639.182318563703;5640.32316122068;5640.4077571856005;5724.169995350734;5731.541476681772;5768.365479797135;5773.16289138081;5783.479486846204;5638.5489160867855;5660.264165658438;5788.623739456922;5818.214541037075;5859.824205273849 20:02:08 INFO - 2015-11-24 20:02:08,009 DEBUG : BROWSER_OUTPUT: |1;ext.html;556.1351908941123;585.3210276079743;595.2054007899454;602.3915844996553;604.9455549000592;38086;38282.61717382825;38511;38646.613533864656;38988.80505597475;42760.35859462103;43687.563124368746;43963.34054989178;43971;44079.33880991788;8621.655133794657;8787.648494060262;8791.20879120879;8797.07630698777;8809.867851982226;8610.827783444327;8695.39132260742;8707.60815763289;8733.825323493558;8737.21365077143;8667.653293868238;8741.12588741124;8752.431091979033;8758.824823503526;8769.95615021924;8528.78678033051;8742;8742;8745.387822852403;8758.56207189638;8591.613377398033;8649.567521623894;8681.652733890636;8737.912620873774;8737.956310218471;40049.79975100106;40440.595594043974;40451;40546.18907621861;40613.39079913774;3374.3598421317124;3409.539712138854;3444.7588668793196;3468.202313467891;3869.28418242626;29452.55821162684;29616;29949.700502995125;30155.849220753975;30204.697953020408;3765.1151979284846;3766.6233376662112;3861.6524512793985;3884.7086468514976;3892.630200130983;263322;263347.9999999981;264387;264644;265323;55739;56421;57663;64772.35227647758;65563.34436655667;61718.69140654358;61744;61848.76302473925;62096;62358.37641623616;14913.403463861541;15004.24978751058;15112.168830714416;15115.31981060833;15193.468228611946;11930.522779088738;11958.701032474257;11974;12019.098567607465;12129.514819407124;19545.413637590897;19708.31020914261;19739.605207895762;19814.90092549528;20052.39842804718;8038.63826127814;8131.227533384319;8228.341732661373;8269.51148793223;8348;974.181687382602;1020.3265844542566;1043.2905624175482;1086.2070688397357;1087.8259478483405;27133.86433067822;27393.19590615527;27507.724922750916;27732.44535109327;27892.581611275848;1034.0693375961694;1046.8325067989238;1049.1711547877244;1049.4962418039383;1136.6135513925306;13633.522826701217;13726.45094196221;13892;13931.651708707363;14471.927640361731;313211;317738;318492;319102;319560;52448.951020979366;52588;52685.47314526806;52697.7365113172;52737;1339.5378594384922;1343.2612063365111;1376.6971266321593;1377.5178687459509;1377.7726675098697;26826.731732683205;27181.1845644631;27927.441451171268;27982.3004424891;28275.717242827308;45251.54748452473;45517.77241113773;45555.31667024931;45581.54418455773;45587.088258233984;287694;287992;288233;289391;289649;31621.52567711532;32493.18767030843;32971.17572060622;33113.66886331106;33294.66705333012;40520.69259493808;42875;43773;43820;44231.77884110559;36853.815730921175;37190.28408553909;37669.434958476195;38201.808990954865;38908.41637375498;36652.45021324629;36868.81565592155;37406.812965936086;37425.69216716847;37770;36426;36459.635403645625;36607.81696091503;36739.26521469502;37016;31228.843855781484;33253;33282.83358583289;33783.32433351369;33903.15242118967;25385.873070634527;25413.61879571771;25748.227553173434;25789.871050644626;26043.479130417665 20:02:08 INFO - 2015-11-24 20:02:08,010 DEBUG : BROWSER_OUTPUT: |2;jquery.html;359.94420864765954;362.3152242262125;364.20784793075086;364.43876430297337;364.7082334132694;14102.435902563886;14385;15953.282102305395;16717.08056056918;17474;16047.598810029755;16236.431724889631;16642.08468534233;16736.916315418435;16744.581385465368;5392.487713667196;5498.0653288940775;5527.613067085305;5528.281323427948;5552.250446189753;5223.555997740177;5309.362876454831;5341.4925582069645;5385.246065550826;5483.890322193553;5243.79024839006;5311.601629877755;5323.22813192087;5598.516393155817;5619.4942455179025;5518.089515229983;5523.530499907512;5540.750666220029;5600.635958662674;5622.212890195356;5026.170681837492;5302.284191634157;5304.6286759926825;5450.264214331094;5472.2338872557875;12524.624261272085;12570.622881313575;12795.104342696015;12832.294223817686;12919.160254583516;2127.57448510297;2165.1231251343233;2204.6252137136726;2230.9107635694713;2248.325502349305;9218.078192180796;9266.305027122926;9494.430334179971;9668.613255469843;9714.9514252429;1843.2074208090517;1862.255097960813;1893.57394586217;1907.3133671878252;1912.8947907865063;60446.69776651132;61041.6947915262;64158.999999999534;64373.999999999534;64530.67734661297;9988.500574971222;10313.84529232062;10369.533370998322;10395.688129356131;10443.68668939933;13505;13566;13571.1857288563;13591;13591.864081359257;7631.3131818136635;7676.884846727192;7701.537907725555;7710.036245469317;7846;7364.116306043309;7399.6670149843385;7404.481686281909;7432.85134297311;7436.739714110088;4258.361245813091;4410.955890441054;4472.44094488189;4483.21543729846;4488.12481566098;1682.528891910267;1718.7679663245551;1796.3802488141832;1804.6661367647027;1812.909354532268;893.678275820704;927.3137877970388;963.982997937169;978.3445091788519;1019.2508506247901;16734.49796506107;16815.579610509834;16947.745783813007;16982;17278.30886764515;925.6158694141856;932.2588542109039;1010.8989101089979;1018.9898101019044;1026.5996261458038;9551.713448596553;9802;9818.950905245429;9882.456464894356;9889.40654629896;528837;529521;530822;536899;551499;77189.61405192938;79407;80623;81007.59496202481;81011;1103.2056919018294;1103.7130346110068;1138.9373584452935;1153.4463457540428;1163.8777928317575;15131.773023404437;15202.543923682308;15236.466723664618;15239.847601524287;15477.380904763684;34053.31893362168;35846.46230306495;36502;36674;37469.06327341838;45348;45654.77172614116;46268.768656156506;46691.29963050489;47149.29276060793;12856.807147892601;14671.92664036673;15007.647782143056;15145.091294522364;15189.544313670607;11092.33445993243;11449.828252576384;11627.941860290644;11664.475098620413;18801.341953031577;10139.290249682452;10342.741431464274;10368.066873981075;10378.06597406237;10437.843432348369;9778.75553111178;9961.302708810314;10016.39901605906;10117.392956422638;10163.593456261668;10155.339902906582;10260.486975651038;10338.534765935401;10367.27429079957;10759.30064545802;14794.926025369805;14831.555053348417;15012.7748083781;15133.394664213309;15153;14737.11577305365;14990.700185996;15207.935444518778;15241.085534867943;16051.197440127713 20:02:08 INFO - 2015-11-24 20:02:08,014 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1519.5897107780906;1696.4062578097664;1733.4626265857585;1756.9824301756978;1770.247644750981;7323;7549.50928189668;7578.431617628686;7618.428617853662;7667.7316293929725;7599.202083781206;7648.579328136951;7655.502392344508;7667.7316293929725;7721.7683469496005;2316.698829152212;2347.072906202049;2348.1194552042984;2348.377679914824;2348.6477028445697;2298.2990187992655;2344.9648255276097;2354.5997180479358;2368.4197371643972;2371.7509661485556;2213.8671679699187;2258.2096266306826;2300.620397634397;2319;2320.8027317678016;2283.474800795818;2313.780190881872;2320.5358928214423;2330.8718020509036;2334.48641298914;2254.966175507369;2339.883005849718;2354.5761762882676;2354.799842013431;2356.5640356534095;7752;7764.805879852993;7832.138464768871;7832.56920869352;7866.1740517245435;4517.870532366908;4671.229247174212;4691.906161876777;4698.107359601699;4714.127886341003;3003.039027511197;3042.421939831447;3084.7686423518326;3093.1957691000275;3123.109913674592;926.4719110107242;938.7606160429046;951.6288647427509;995.6764051683159;1039.2621238920376;10165.59337626494;10169.440680762555;10232.232582556264;10236.795264094677;10254.794904101951;4208.684348673862;4273.850415235452;4282.571742825755;4309.719868208556;4338.522762496123;8444.071152173256;8453.66185352579;8479.830403392021;8504.107068757816;8524.488530688179;4571.085782843445;4579.564941330568;4584.33527138563;4607.285870690049;4632.050429661978;4139.793010349531;4181.080162364275;4206.179794940019;4220.873373798791;4240.618344349024;3458.3774920514047;3469.462233353834;3501.1597216668324;3523.224890524081;3554.662307080823;1092.7158938675843;1099.8515200447837;1107.9390633515234;1109.97327472088;1110.4614262082882;1072.9570817167382;1074.978500429987;1079.071998081649;1194.498310709504;1264.3235868810143;7012.859742805115;7066.752663656747;7102.3962963148715;7153.785386438415;7167;1149.706824759681;1154.215133709069;1186.7863784518697;1197.9940100299443;1202.3086725133082;5642.04085305499;5655.915161272585;5671.2060311556415;5692.430756924357;5733.770649173986;8711.390202685878;8766.517841518778;9051.954740226389;9103.635854565744;9124.31567632417;8850.690225842065;8897.599608017656;8900.287976961827;8900.510471924106;8935;6819.863602728017;7098.041764361745;7114.78655640342;7165.498415110892;7255.746048888263;7005.684744186626;7039.859202815813;7052.188998265266;7101.751438699621;7104.11198600175;7294.525855819355;7983.281504664608;7991.400644951768;8000.319972802377;8006.759797206093;7904.628720481699;7967.442279040411;8055.234752698484;8184.181581841768;8220.3834712398;6313.463355614824;6826.419754320938;6926.06498122747;6974.79075627732;7008.929910701032;7295;7312.890306645511;7335.523190992569;7341;7362.7423040193335;6867.587944723333;6892.310768923067;6940.236573976959;6986.266442023718;7054;6634.170728658918;6842.55523390978;6890.173179218526;6908.378245957888;6962.443004559725;6890.93109068923;6972.337627925339;6991.40573051276;7015.579065256101;7016.298370162943;6369.8726025480155;6373.044043393528;6397.4562162216735;6413.775517856852;6441.452476539359;6533.8693226136165;6562.048502967138;6585.6377899216;6615.735370585123;6621.1392518972225 20:02:08 INFO - 2015-11-24 20:02:08,014 DEBUG : BROWSER_OUTPUT: |4;prototype.html;332.9495441880866;337.5881424661912;343.78169862137537;345.7044227185756;348.90230735394084;6235.968820155898;6260.060990851363;6333.524985626079;6341.746330146789;6386.744530218785;6131.938680613192;6232.096346029834;6253.155823963763;6254.124422580831;6261.686915654222;4775.832845850396;4796.040791841646;4808.975955120237;4818.325434439182;4827.372441582598;4820.3733514643045;4823.807047718087;4824.2040063389495;4825.420949486067;4828.589569886564;4705.1765940960195;4756.262779269219;4788.186008378584;4789.832355867546;4799.640026997971;4759.881002974918;4776.832810851621;4778.928316075262;4781.832635857763;4782.617390608743;4187.769672668002;4188.623023927861;4190.811413486401;4194.2450358935375;4194.2869712149;5910.67491287979;5911.231539899785;5965.582409231356;5967.015442451991;5969.164316995624;5465.808696695636;5599.048161812501;5605.635633683838;5606;5617.859553511154;6592.80221593353;6618.271990081087;6632.137822083098;6632.767853125166;6646.302138275508;4670.906581868344;4679.27471241958;4685.4377474702915;4689.132510485537;4689.648276379251;6646.368594983469;6801.319868013258;6835.111435513351;6879.58722476653;6906.102206713095;4740.217864052462;4750.524947505291;4751.786169622375;4754.23932170851;4759.405074365704;4803.735794531334;4813.15769740294;4814.470408255089;4815.975920120377;4823.397075365579;5561.026820306431;5566.192902029183;5573.721313934287;5577.609567330329;5586.329640443091;6571.390009447716;6712.160979877515;6713.731450742013;6719.0593316935665;6721.327867213337;1683.3350826423493;1709.4273418404725;1720.5956600198942;1731.2382551677474;1732.1079643983358;1151.6257216404701;1154.463174623804;1160.22265082395;1168.199783148546;1182.804837201852;6356.173697419306;6364.949783285706;6370.362963703593;6379.393957574023;6390.137331460286;6772.525923185428;6805.149356330458;6808.761693340709;6818.454523638097;6820.386165245052;6198.969005154945;6275.403836635513;6352.237731472253;6355.237371515355;6362.968185159045;3695.094701798023;3758.5113935188797;3769.547654281449;3779.7732136071922;3788.431735239681;7220.92779072213;7224.566526008456;7241.637918104074;7250.093738282715;7263.89104163438;6994.230634630187;7114.217436082027;7125.287471252833;7166.749163779347;7176.102987126609;7210.67551960163;7262.891056634155;7264.237255088246;7268.454865884974;7271.091113610799;6890.758823441155;6936.826579335557;6943.201531823703;6949.339812717783;6979.336962988508;6762.459003279628;6886.898096304768;6904.758333458305;6910.792676219721;6919.100516932766;7222.96388518054;7225.783226503213;7237.240089790706;7256.673449694671;7259.6007219601315;4602.286645569942;4636.8145274188655;4637.489876113557;4640.234361330343;4659.953400465952;6799.5920244785475;6948.513604047668;6963.860722785617;6976.7558135465015;6985.240829872274;6762.797116086525;6768.627725474949;6772.627505487048;6785.253622101466;6785.626790526569;6728.529002969941;6760.005798289418;6760.628165450751;6763.053172555945;6786.898196527155;6460.192475940507;6634.800955971328;6637.535372523877;6643.56816806906;6654.168228971379;6782.084418603426;6793.830154246183;6795.694193761194;6804.353586409283;6813.7955861324235;6571.080048793077;6605.372489613479;6606.768763093269;6623.105880706235;6628.204615446178 20:02:08 INFO - 2015-11-24 20:02:08,015 DEBUG : BROWSER_OUTPUT: |5;yui.html;764.9005629268196;779.0767939991111;780.7735756630576;822.0874828939876;836.2724429746122;13688.863111368883;13800.447982080705;13909.16545007299;13935.163890166585;13954.232517211574;12463.875361246384;12875.227486350825;13320.134191277584;13340.666483337922;13359.33203339834;830.954297513638;833.7207035643066;837.656560810068;840.4831028917233;845.1548451548451;826.396730386818;838.2372041442288;839.6977088248226;840.9243168114868;842.4439869686008;867.2931560777946;877.1447838357614;878.6441491196076;879.1779685993585;880.2781718990431;860.114082495028;862.202462721986;866.2939704141105;870.5168631409597;870.9825803483958;638.2596188421455;642.8328634555003;646.3149061994301;646.385933363302;648.0861984601736;11921.821172682417;12061.879381206163;12158.635240942785;12185.268883866907;12200.267983920905;10806.243562950596;10975.560977560888;11073.612423565137;11205.607803726913;11428.257163284443;9594.13652771247;9869.080571556804;9965.611761774233;10007.699769006942;10037.29738918276;3477.51314815926;3507.158282012324;3511.929761404783;3520.1551627609447;3562.661547153042;41505.1698966022;41812;46178.307325390146;46515.53484465146;47102.528974710156;9935.304528683067;10071.949640251752;10127.139193168516;10328.948355258326;10750;18138.09309534518;18251.908740456212;18287.72568411475;18298;18320;7639;7692.538447693157;7704.075510938611;7745.264199901;7852.332551733052;4442.000549876317;4506.526814684476;4526.02690421561;4534.79593418297;4548.545145485425;3075.3234288456506;3079.876804927778;3080.6765289644713;3082;3098.4732595458827;592.5437413191818;597.9850503737441;600.069891667913;603.3122240645667;604.3865476541314;676.1075380497795;678.0201810712731;685.8088407537969;688.1913751342153;694.5485434467536;8591.183837535424;8694.914238932843;8717.825643487095;8775.473471591726;8969.596368163448;523.7198099017007;571.6855729348841;666.5733930284622;693.774523279936;717.0749732844572;3966.4050392441363;4021;4090.284200264942;4145.253854306254;4217.40956266123;55806.16290755642;56096.719516402976;56363.43636563664;56668.71665641645;56712;47872.521274786806;50656;51391.22913156306;51591;52163.47836521662;46381.5361846384;47180.52819471761;47385.28922066172;47581.762091189325;47887.281690775366;8550.444221125606;8824.779380515538;8881.822363552821;8941.776455588662;8945.686900958435;34224.82887585546;34524.82737586296;35087.29825403529;35853.462198066525;35957.64042359543;41813.790931046366;45015.77492112519;45745.54254457413;45818.312725309814;45905.540944590124;10836.783264334827;10995.120390368911;11270.009825034931;11307.321560706385;11323.830142547704;12055.434798754002;12521.248725076524;12737.235765854079;13034.739305213652;13040.54358097462;11732.237404568676;11839.644810655695;11887.881121188677;12358.320292383645;12563.685907852378;11565.479553419947;11714.589989350332;11858.456228739205;11884.465199066235;12005.149024655975;11396.658100257006;11439.256448330832;11485.942570287096;11625.128115391548;12103.576374826838;8854.513001784964;9154.26765858743;9298.581563829779;9365.625374984924;9633.759156021155;8930.86603700958;8937.865932010895;9707.476056225574;9709.9029009709;9800.36297640651 20:02:08 INFO - 2015-11-24 20:02:08,015 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:02:08 INFO - 2015-11-24 20:02:08,015 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:02:08 INFO - 2015-11-24 20:02:08,015 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,304 20:02:08 INFO - 2015-11-24 20:02:08,015 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:02:08 INFO - 2015-11-24 20:02:08,015 DEBUG : BROWSER_OUTPUT: __startTimestamp1448424128006__endTimestamp 20:02:08 INFO - 2015-11-24 20:02:08,015 DEBUG : BROWSER_OUTPUT: 20:02:08 INFO - 2015-11-24 20:02:08,016 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:02:08 INFO - 2015-11-24 20:02:08,016 DEBUG : BROWSER_OUTPUT: Number of tests: 6 20:02:08 INFO - 2015-11-24 20:02:08,016 DEBUG : BROWSER_OUTPUT: 20:02:08 INFO - 2015-11-24 20:02:08,016 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5517.39 Median:5726.74 stddev:634.97 (11.1%) stddev-sans-first:611.31 20:02:08 INFO - 2015-11-24 20:02:08,016 DEBUG : BROWSER_OUTPUT: Values: 3241.6 3302.3 3332.9 3343.2 3359.0 5255.5 5423.5 5438.3 5442.9 5451.6 5290.5 5303.7 5422.4 5440.5 5496.2 4942.2 5026.4 5082.0 5116.7 5129.1 4989.7 5064.6 5081.2 5144.8 5172.9 4817.6 4945.4 4960.1 5005.1 5056.6 5023.4 5044.3 5046.3 5052.3 5103.1 4997.8 5019.3 5020.7 5026.0 5029.9 5103.0 5157.6 5194.5 5261.9 5281.3 6013.2 6017.3 6032.4 6039.6 6061.4 5747.6 6074.2 6089.4 6091.3 6104.3 5513.7 5607.5 5710.5 5726.7 5758.4 6104.8 6120.8 6153.6 6156.0 6157.4 5712.7 5839.0 5925.9 5951.2 5966.5 5906.6 5965.6 5977.3 5981.2 5994.2 5908.3 5930.5 5971.9 5980.7 5981.6 5743.6 5801.3 5886.7 5927.4 5990.0 4491.2 4552.6 4562.7 4592.4 4594.8 4106.7 4116.9 4130.9 4134.2 4161.3 5453.4 5529.2 5613.4 5776.7 5783.7 5960.0 5976.6 5978.8 5986.6 6026.2 5655.5 5656.6 5660.9 5661.9 5663.3 4874.0 4967.5 4969.3 4973.4 5039.1 6035.3 6090.3 6134.3 6149.8 6166.9 6045.2 6069.8 6098.9 6100.2 6104.0 5932.2 6231.9 6332.1 6340.7 6341.2 5776.8 5798.3 5902.6 5937.0 5979.2 6060.8 6064.8 6070.3 6071.5 6101.7 5547.8 5943.3 5952.6 5955.7 5957.9 5721.8 5727.3 5732.9 5739.7 5742.0 5596.0 5638.1 5639.2 5640.3 5640.4 5724.2 5731.5 5768.4 5773.2 5783.5 5638.5 5660.3 5788.6 5818.2 5859.8 20:02:08 INFO - 2015-11-24 20:02:08,016 DEBUG : BROWSER_OUTPUT: 20:02:08 INFO - 2015-11-24 20:02:08,016 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46341.76 Median:37103.14 stddev:75981.02 (204.8%) stddev-sans-first:76116.40 20:02:08 INFO - 2015-11-24 20:02:08,020 DEBUG : BROWSER_OUTPUT: Values: 556.1 585.3 595.2 602.4 604.9 38086.0 38282.6 38511.0 38646.6 38988.8 42760.4 43687.6 43963.3 43971.0 44079.3 8621.7 8787.6 8791.2 8797.1 8809.9 8610.8 8695.4 8707.6 8733.8 8737.2 8667.7 8741.1 8752.4 8758.8 8770.0 8528.8 8742.0 8742.0 8745.4 8758.6 8591.6 8649.6 8681.7 8737.9 8738.0 40049.8 40440.6 40451.0 40546.2 40613.4 3374.4 3409.5 3444.8 3468.2 3869.3 29452.6 29616.0 29949.7 30155.8 30204.7 3765.1 3766.6 3861.7 3884.7 3892.6 263322.0 263348.0 264387.0 264644.0 265323.0 55739.0 56421.0 57663.0 64772.4 65563.3 61718.7 61744.0 61848.8 62096.0 62358.4 14913.4 15004.2 15112.2 15115.3 15193.5 11930.5 11958.7 11974.0 12019.1 12129.5 19545.4 19708.3 19739.6 19814.9 20052.4 8038.6 8131.2 8228.3 8269.5 8348.0 974.2 1020.3 1043.3 1086.2 1087.8 27133.9 27393.2 27507.7 27732.4 27892.6 1034.1 1046.8 1049.2 1049.5 1136.6 13633.5 13726.5 13892.0 13931.7 14471.9 313211.0 317738.0 318492.0 319102.0 319560.0 52449.0 52588.0 52685.5 52697.7 52737.0 1339.5 1343.3 1376.7 1377.5 1377.8 26826.7 27181.2 27927.4 27982.3 28275.7 45251.5 45517.8 45555.3 45581.5 45587.1 287694.0 287992.0 288233.0 289391.0 289649.0 31621.5 32493.2 32971.2 33113.7 33294.7 40520.7 42875.0 43773.0 43820.0 44231.8 36853.8 37190.3 37669.4 38201.8 38908.4 36652.5 36868.8 37406.8 37425.7 37770.0 36426.0 36459.6 36607.8 36739.3 37016.0 31228.8 33253.0 33282.8 33783.3 33903.2 25385.9 25413.6 25748.2 25789.9 26043.5 20:02:08 INFO - 2015-11-24 20:02:08,020 DEBUG : BROWSER_OUTPUT: 20:02:08 INFO - 2015-11-24 20:02:08,020 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28480.24 Median:18150.82 stddev:87575.18 (482.5%) stddev-sans-first:87795.40 20:02:08 INFO - 2015-11-24 20:02:08,020 DEBUG : BROWSER_OUTPUT: Values: 359.9 362.3 364.2 364.4 364.7 14102.4 14385.0 15953.3 16717.1 17474.0 16047.6 16236.4 16642.1 16736.9 16744.6 5392.5 5498.1 5527.6 5528.3 5552.3 5223.6 5309.4 5341.5 5385.2 5483.9 5243.8 5311.6 5323.2 5598.5 5619.5 5518.1 5523.5 5540.8 5600.6 5622.2 5026.2 5302.3 5304.6 5450.3 5472.2 12524.6 12570.6 12795.1 12832.3 12919.2 2127.6 2165.1 2204.6 2230.9 2248.3 9218.1 9266.3 9494.4 9668.6 9715.0 1843.2 1862.3 1893.6 1907.3 1912.9 60446.7 61041.7 64159.0 64374.0 64530.7 9988.5 10313.8 10369.5 10395.7 10443.7 13505.0 13566.0 13571.2 13591.0 13591.9 7631.3 7676.9 7701.5 7710.0 7846.0 7364.1 7399.7 7404.5 7432.9 7436.7 4258.4 4411.0 4472.4 4483.2 4488.1 1682.5 1718.8 1796.4 1804.7 1812.9 893.7 927.3 964.0 978.3 1019.3 16734.5 16815.6 16947.7 16982.0 17278.3 925.6 932.3 1010.9 1019.0 1026.6 9551.7 9802.0 9819.0 9882.5 9889.4 528837.0 529521.0 530822.0 536899.0 551499.0 77189.6 79407.0 80623.0 81007.6 81011.0 1103.2 1103.7 1138.9 1153.4 1163.9 15131.8 15202.5 15236.5 15239.8 15477.4 34053.3 35846.5 36502.0 36674.0 37469.1 45348.0 45654.8 46268.8 46691.3 47149.3 12856.8 14671.9 15007.6 15145.1 15189.5 11092.3 11449.8 11627.9 11664.5 18801.3 10139.3 10342.7 10368.1 10378.1 10437.8 9778.8 9961.3 10016.4 10117.4 10163.6 10155.3 10260.5 10338.5 10367.3 10759.3 14794.9 14831.6 15012.8 15133.4 15153.0 14737.1 14990.7 15207.9 15241.1 16051.2 20:02:08 INFO - 2015-11-24 20:02:08,020 DEBUG : BROWSER_OUTPUT: 20:02:08 INFO - 2015-11-24 20:02:08,020 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5342.09 Median:6427.61 stddev:2684.66 (41.8%) stddev-sans-first:2676.81 20:02:08 INFO - 2015-11-24 20:02:08,022 DEBUG : BROWSER_OUTPUT: Values: 1519.6 1696.4 1733.5 1757.0 1770.2 7323.0 7549.5 7578.4 7618.4 7667.7 7599.2 7648.6 7655.5 7667.7 7721.8 2316.7 2347.1 2348.1 2348.4 2348.6 2298.3 2345.0 2354.6 2368.4 2371.8 2213.9 2258.2 2300.6 2319.0 2320.8 2283.5 2313.8 2320.5 2330.9 2334.5 2255.0 2339.9 2354.6 2354.8 2356.6 7752.0 7764.8 7832.1 7832.6 7866.2 4517.9 4671.2 4691.9 4698.1 4714.1 3003.0 3042.4 3084.8 3093.2 3123.1 926.5 938.8 951.6 995.7 1039.3 10165.6 10169.4 10232.2 10236.8 10254.8 4208.7 4273.9 4282.6 4309.7 4338.5 8444.1 8453.7 8479.8 8504.1 8524.5 4571.1 4579.6 4584.3 4607.3 4632.1 4139.8 4181.1 4206.2 4220.9 4240.6 3458.4 3469.5 3501.2 3523.2 3554.7 1092.7 1099.9 1107.9 1110.0 1110.5 1073.0 1075.0 1079.1 1194.5 1264.3 7012.9 7066.8 7102.4 7153.8 7167.0 1149.7 1154.2 1186.8 1198.0 1202.3 5642.0 5655.9 5671.2 5692.4 5733.8 8711.4 8766.5 9052.0 9103.6 9124.3 8850.7 8897.6 8900.3 8900.5 8935.0 6819.9 7098.0 7114.8 7165.5 7255.7 7005.7 7039.9 7052.2 7101.8 7104.1 7294.5 7983.3 7991.4 8000.3 8006.8 7904.6 7967.4 8055.2 8184.2 8220.4 6313.5 6826.4 6926.1 6974.8 7008.9 7295.0 7312.9 7335.5 7341.0 7362.7 6867.6 6892.3 6940.2 6986.3 7054.0 6634.2 6842.6 6890.2 6908.4 6962.4 6890.9 6972.3 6991.4 7015.6 7016.3 6369.9 6373.0 6397.5 6413.8 6441.5 6533.9 6562.0 6585.6 6615.7 6621.1 20:02:08 INFO - 2015-11-24 20:02:08,022 DEBUG : BROWSER_OUTPUT: 20:02:08 INFO - 2015-11-24 20:02:08,022 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5600.63 Median:6346.99 stddev:1693.32 (26.7%) stddev-sans-first:1651.27 20:02:08 INFO - 2015-11-24 20:02:08,023 DEBUG : BROWSER_OUTPUT: Values: 332.9 337.6 343.8 345.7 348.9 6236.0 6260.1 6333.5 6341.7 6386.7 6131.9 6232.1 6253.2 6254.1 6261.7 4775.8 4796.0 4809.0 4818.3 4827.4 4820.4 4823.8 4824.2 4825.4 4828.6 4705.2 4756.3 4788.2 4789.8 4799.6 4759.9 4776.8 4778.9 4781.8 4782.6 4187.8 4188.6 4190.8 4194.2 4194.3 5910.7 5911.2 5965.6 5967.0 5969.2 5465.8 5599.0 5605.6 5606.0 5617.9 6592.8 6618.3 6632.1 6632.8 6646.3 4670.9 4679.3 4685.4 4689.1 4689.6 6646.4 6801.3 6835.1 6879.6 6906.1 4740.2 4750.5 4751.8 4754.2 4759.4 4803.7 4813.2 4814.5 4816.0 4823.4 5561.0 5566.2 5573.7 5577.6 5586.3 6571.4 6712.2 6713.7 6719.1 6721.3 1683.3 1709.4 1720.6 1731.2 1732.1 1151.6 1154.5 1160.2 1168.2 1182.8 6356.2 6364.9 6370.4 6379.4 6390.1 6772.5 6805.1 6808.8 6818.5 6820.4 6199.0 6275.4 6352.2 6355.2 6363.0 3695.1 3758.5 3769.5 3779.8 3788.4 7220.9 7224.6 7241.6 7250.1 7263.9 6994.2 7114.2 7125.3 7166.7 7176.1 7210.7 7262.9 7264.2 7268.5 7271.1 6890.8 6936.8 6943.2 6949.3 6979.3 6762.5 6886.9 6904.8 6910.8 6919.1 7223.0 7225.8 7237.2 7256.7 7259.6 4602.3 4636.8 4637.5 4640.2 4660.0 6799.6 6948.5 6963.9 6976.8 6985.2 6762.8 6768.6 6772.6 6785.3 6785.6 6728.5 6760.0 6760.6 6763.1 6786.9 6460.2 6634.8 6637.5 6643.6 6654.2 6782.1 6793.8 6795.7 6804.4 6813.8 6571.1 6605.4 6606.8 6623.1 6628.2 20:02:08 INFO - 2015-11-24 20:02:08,024 DEBUG : BROWSER_OUTPUT: 20:02:08 INFO - 2015-11-24 20:02:08,024 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13687.94 Median:46809.03 stddev:15691.27 (33.5%) stddev-sans-first:15705.28 20:02:08 INFO - 2015-11-24 20:02:08,025 DEBUG : BROWSER_OUTPUT: Values: 764.9 779.1 780.8 822.1 836.3 13688.9 13800.4 13909.2 13935.2 13954.2 12463.9 12875.2 13320.1 13340.7 13359.3 831.0 833.7 837.7 840.5 845.2 826.4 838.2 839.7 840.9 842.4 867.3 877.1 878.6 879.2 880.3 860.1 862.2 866.3 870.5 871.0 638.3 642.8 646.3 646.4 648.1 11921.8 12061.9 12158.6 12185.3 12200.3 10806.2 10975.6 11073.6 11205.6 11428.3 9594.1 9869.1 9965.6 10007.7 10037.3 3477.5 3507.2 3511.9 3520.2 3562.7 41505.2 41812.0 46178.3 46515.5 47102.5 9935.3 10071.9 10127.1 10328.9 10750.0 18138.1 18251.9 18287.7 18298.0 18320.0 7639.0 7692.5 7704.1 7745.3 7852.3 4442.0 4506.5 4526.0 4534.8 4548.5 3075.3 3079.9 3080.7 3082.0 3098.5 592.5 598.0 600.1 603.3 604.4 676.1 678.0 685.8 688.2 694.5 8591.2 8694.9 8717.8 8775.5 8969.6 523.7 571.7 666.6 693.8 717.1 3966.4 4021.0 4090.3 4145.3 4217.4 55806.2 56096.7 56363.4 56668.7 56712.0 47872.5 50656.0 51391.2 51591.0 52163.5 46381.5 47180.5 47385.3 47581.8 47887.3 8550.4 8824.8 8881.8 8941.8 8945.7 34224.8 34524.8 35087.3 35853.5 35957.6 41813.8 45015.8 45745.5 45818.3 45905.5 10836.8 10995.1 11270.0 11307.3 11323.8 12055.4 12521.2 12737.2 13034.7 13040.5 11732.2 11839.6 11887.9 12358.3 12563.7 11565.5 11714.6 11858.5 11884.5 12005.1 11396.7 11439.3 11485.9 11625.1 12103.6 8854.5 9154.3 9298.6 9365.6 9633.8 8930.9 8937.9 9707.5 9709.9 9800.4 20:02:08 INFO - 2015-11-24 20:02:08,025 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:02:08 INFO - 2015-11-24 20:02:08,025 DEBUG : BROWSER_OUTPUT: 20:02:08 INFO - 2015-11-24 20:02:08,729 INFO : Browser exited with error code: 0 20:02:08 INFO - 2015-11-24 20:02:08,751 INFO : Completed test dromaeo_css (00:18:30) 20:02:08 INFO - 2015-11-24 20:02:08,751 INFO : Starting test kraken 20:02:08 INFO - 2015-11-24 20:02:08,751 DEBUG : operating with platform_type : linux_ 20:02:08 INFO - 2015-11-24 20:02:08,751 INFO : Initialising browser for kraken test... 20:02:08 INFO - 2015-11-24 20:02:08,758 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1X3cqa/profile http://localhost:59286/getInfo.html 20:02:13 INFO - 2015-11-24 20:02:13,320 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:02:13 INFO - 2015-11-24 20:02:13,320 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:02:13 INFO - 2015-11-24 20:02:13,320 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:02:13 INFO - 2015-11-24 20:02:13,320 DEBUG : BROWSER_OUTPUT: __metrics 20:02:14 INFO - 2015-11-24 20:02:14,317 INFO : Browser initialized. 20:02:14 INFO - 2015-11-24 20:02:14,318 INFO : Running cycle 1/1 for kraken test... 20:02:14 INFO - 2015-11-24 20:02:14,318 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1X3cqa/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:02:26 INFO - 2015-11-24 20:02:26,007 DEBUG : BROWSER_OUTPUT: RSS: Main: 138829824 20:02:26 INFO - 2015-11-24 20:02:26,007 DEBUG : BROWSER_OUTPUT: 20:02:28 INFO - 2015-11-24 20:02:28,482 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59286/tests/kraken/driver.html?testName=audio-beat-detection) 20:02:29 INFO - 2015-11-24 20:02:29,088 DEBUG : BROWSER_OUTPUT: RSS: Main: 141508608 20:02:29 INFO - 2015-11-24 20:02:29,088 DEBUG : BROWSER_OUTPUT: 20:02:31 INFO - 2015-11-24 20:02:31,730 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59286/tests/kraken/driver.html?testName=audio-dft) 20:02:32 INFO - 2015-11-24 20:02:32,315 DEBUG : BROWSER_OUTPUT: RSS: Main: 139583488 20:02:32 INFO - 2015-11-24 20:02:32,316 DEBUG : BROWSER_OUTPUT: 20:02:35 INFO - 2015-11-24 20:02:35,167 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59286/tests/kraken/driver.html?testName=audio-fft) 20:02:35 INFO - 2015-11-24 20:02:35,736 DEBUG : BROWSER_OUTPUT: RSS: Main: 135864320 20:02:35 INFO - 2015-11-24 20:02:35,736 DEBUG : BROWSER_OUTPUT: 20:02:37 INFO - 2015-11-24 20:02:37,674 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59286/tests/kraken/driver.html?testName=audio-oscillator) 20:02:38 INFO - 2015-11-24 20:02:38,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 136155136 20:02:38 INFO - 2015-11-24 20:02:38,296 DEBUG : BROWSER_OUTPUT: 20:02:40 INFO - 2015-11-24 20:02:40,494 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59286/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:02:41 INFO - 2015-11-24 20:02:41,068 DEBUG : BROWSER_OUTPUT: RSS: Main: 136998912 20:02:41 INFO - 2015-11-24 20:02:41,068 DEBUG : BROWSER_OUTPUT: 20:02:44 INFO - 2015-11-24 20:02:44,108 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59286/tests/kraken/driver.html?testName=imaging-darkroom) 20:02:44 INFO - 2015-11-24 20:02:44,689 DEBUG : BROWSER_OUTPUT: RSS: Main: 138665984 20:02:44 INFO - 2015-11-24 20:02:44,689 DEBUG : BROWSER_OUTPUT: 20:02:48 INFO - 2015-11-24 20:02:48,104 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59286/tests/kraken/driver.html?testName=imaging-desaturate) 20:02:48 INFO - 2015-11-24 20:02:48,705 DEBUG : BROWSER_OUTPUT: RSS: Main: 138678272 20:02:48 INFO - 2015-11-24 20:02:48,706 DEBUG : BROWSER_OUTPUT: 20:02:51 INFO - 2015-11-24 20:02:51,550 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59286/tests/kraken/driver.html?testName=json-parse-financial) 20:02:52 INFO - 2015-11-24 20:02:52,145 DEBUG : BROWSER_OUTPUT: RSS: Main: 138903552 20:02:52 INFO - 2015-11-24 20:02:52,146 DEBUG : BROWSER_OUTPUT: 20:02:53 INFO - 2015-11-24 20:02:53,755 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59286/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:02:54 INFO - 2015-11-24 20:02:54,327 DEBUG : BROWSER_OUTPUT: RSS: Main: 138158080 20:02:54 INFO - 2015-11-24 20:02:54,328 DEBUG : BROWSER_OUTPUT: 20:02:56 INFO - 2015-11-24 20:02:56,172 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59286/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:02:56 INFO - 2015-11-24 20:02:56,778 DEBUG : BROWSER_OUTPUT: RSS: Main: 138223616 20:02:56 INFO - 2015-11-24 20:02:56,778 DEBUG : BROWSER_OUTPUT: 20:02:58 INFO - 2015-11-24 20:02:58,836 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59286/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:02:59 INFO - 2015-11-24 20:02:59,422 DEBUG : BROWSER_OUTPUT: RSS: Main: 138326016 20:02:59 INFO - 2015-11-24 20:02:59,422 DEBUG : BROWSER_OUTPUT: 20:03:01 INFO - 2015-11-24 20:03:01,865 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:03:02 INFO - 2015-11-24 20:03:02,459 DEBUG : BROWSER_OUTPUT: RSS: Main: 138141696 20:03:02 INFO - 2015-11-24 20:03:02,459 DEBUG : BROWSER_OUTPUT: 20:03:05 INFO - 2015-11-24 20:03:05,004 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:03:05 INFO - 2015-11-24 20:03:05,605 DEBUG : BROWSER_OUTPUT: RSS: Main: 137936896 20:03:05 INFO - 2015-11-24 20:03:05,605 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,254 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59286/tests/kraken/driver.html?testName=ai-astar) 20:03:07 INFO - 2015-11-24 20:03:07,512 DEBUG : BROWSER_OUTPUT: RSS: Main: 139440128 20:03:07 INFO - 2015-11-24 20:03:07,512 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,512 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:03:07 INFO - 2015-11-24 20:03:07,513 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:03:07 INFO - 2015-11-24 20:03:07,513 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:03:07 INFO - 2015-11-24 20:03:07,513 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:03:07 INFO - 2015-11-24 20:03:07,513 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;104;112;111;111;124;112;112;111;109 20:03:07 INFO - 2015-11-24 20:03:07,513 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;126;150;170;126;153;154;131;167;126 20:03:07 INFO - 2015-11-24 20:03:07,513 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;161;163;164;162;162;162;164;178;162 20:03:07 INFO - 2015-11-24 20:03:07,513 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;73;71;72;80;72;125;72;71;73 20:03:07 INFO - 2015-11-24 20:03:07,513 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;137;93;92;94;137;92;93;134 20:03:07 INFO - 2015-11-24 20:03:07,514 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;109;110;110;110;110;110;110 20:03:07 INFO - 2015-11-24 20:03:07,514 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;152;147;147;146;146;147;147;146;147;147 20:03:07 INFO - 2015-11-24 20:03:07,514 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;95;99;95;97;96;97 20:03:07 INFO - 2015-11-24 20:03:07,514 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;65;67;62;61;61;64;62;61;69;63 20:03:07 INFO - 2015-11-24 20:03:07,514 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;70;70;71;70;71;72;71;71;72 20:03:07 INFO - 2015-11-24 20:03:07,514 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;84;84;92;87;83;85;86;85;84 20:03:07 INFO - 2015-11-24 20:03:07,514 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;129;120;118;120;117;126;120;115;125;118 20:03:07 INFO - 2015-11-24 20:03:07,514 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;148;147;144;153;147;152;147;149;148 20:03:07 INFO - 2015-11-24 20:03:07,515 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;67;68;67;67;67;66;67;68;67 20:03:07 INFO - 2015-11-24 20:03:07,515 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:03:07 INFO - 2015-11-24 20:03:07,515 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:03:07 INFO - 2015-11-24 20:03:07,515 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,710 20:03:07 INFO - 2015-11-24 20:03:07,515 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:03:07 INFO - 2015-11-24 20:03:07,515 DEBUG : BROWSER_OUTPUT: __startTimestamp1448424187507__endTimestamp 20:03:07 INFO - 2015-11-24 20:03:07,515 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,516 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:03:07 INFO - 2015-11-24 20:03:07,516 DEBUG : BROWSER_OUTPUT: Number of tests: 14 20:03:07 INFO - 2015-11-24 20:03:07,516 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,516 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:4.95 (4.4%) stddev-sans-first:5.24 20:03:07 INFO - 2015-11-24 20:03:07,516 DEBUG : BROWSER_OUTPUT: Values: 113.0 104.0 112.0 111.0 111.0 124.0 112.0 112.0 111.0 109.0 20:03:07 INFO - 2015-11-24 20:03:07,516 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,516 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.40 Median:152.00 stddev:16.97 (11.2%) stddev-sans-first:17.88 20:03:07 INFO - 2015-11-24 20:03:07,517 DEBUG : BROWSER_OUTPUT: Values: 151.0 126.0 150.0 170.0 126.0 153.0 154.0 131.0 167.0 126.0 20:03:07 INFO - 2015-11-24 20:03:07,517 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,517 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.10 Median:163.00 stddev:4.98 (3.1%) stddev-sans-first:5.26 20:03:07 INFO - 2015-11-24 20:03:07,517 DEBUG : BROWSER_OUTPUT: Values: 163.0 161.0 163.0 164.0 162.0 162.0 162.0 164.0 178.0 162.0 20:03:07 INFO - 2015-11-24 20:03:07,517 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,517 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.20 Median:72.50 stddev:16.65 (23.0%) stddev-sans-first:17.55 20:03:07 INFO - 2015-11-24 20:03:07,517 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 71.0 72.0 80.0 72.0 125.0 72.0 71.0 73.0 20:03:07 INFO - 2015-11-24 20:03:07,518 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,518 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.80 Median:93.00 stddev:20.86 (22.4%) stddev-sans-first:21.61 20:03:07 INFO - 2015-11-24 20:03:07,518 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 137.0 93.0 92.0 94.0 137.0 92.0 93.0 134.0 20:03:07 INFO - 2015-11-24 20:03:07,518 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,518 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 20:03:07 INFO - 2015-11-24 20:03:07,518 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 109.0 110.0 110.0 110.0 110.0 110.0 110.0 20:03:07 INFO - 2015-11-24 20:03:07,518 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,518 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:1.75 (1.2%) stddev-sans-first:0.50 20:03:07 INFO - 2015-11-24 20:03:07,519 DEBUG : BROWSER_OUTPUT: Values: 152.0 147.0 147.0 146.0 146.0 147.0 147.0 146.0 147.0 147.0 20:03:07 INFO - 2015-11-24 20:03:07,519 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,519 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:1.16 (1.2%) stddev-sans-first:1.22 20:03:07 INFO - 2015-11-24 20:03:07,519 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 95.0 99.0 95.0 97.0 96.0 97.0 20:03:07 INFO - 2015-11-24 20:03:07,519 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,519 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:63.50 Median:63.50 stddev:2.76 (4.3%) stddev-sans-first:2.87 20:03:07 INFO - 2015-11-24 20:03:07,519 DEBUG : BROWSER_OUTPUT: Values: 65.0 67.0 62.0 61.0 61.0 64.0 62.0 61.0 69.0 63.0 20:03:07 INFO - 2015-11-24 20:03:07,520 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,520 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:0.79 (1.1%) stddev-sans-first:0.78 20:03:07 INFO - 2015-11-24 20:03:07,520 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 70.0 71.0 70.0 71.0 72.0 71.0 71.0 72.0 20:03:07 INFO - 2015-11-24 20:03:07,520 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,520 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:85.00 stddev:2.59 (3.0%) stddev-sans-first:2.70 20:03:07 INFO - 2015-11-24 20:03:07,520 DEBUG : BROWSER_OUTPUT: Values: 84.0 84.0 84.0 92.0 87.0 83.0 85.0 86.0 85.0 84.0 20:03:07 INFO - 2015-11-24 20:03:07,520 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,521 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.80 Median:120.00 stddev:4.44 (3.7%) stddev-sans-first:3.59 20:03:07 INFO - 2015-11-24 20:03:07,521 DEBUG : BROWSER_OUTPUT: Values: 129.0 120.0 118.0 120.0 117.0 126.0 120.0 115.0 125.0 118.0 20:03:07 INFO - 2015-11-24 20:03:07,521 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,521 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.20 Median:148.00 stddev:4.42 (3.0%) stddev-sans-first:2.74 20:03:07 INFO - 2015-11-24 20:03:07,521 DEBUG : BROWSER_OUTPUT: Values: 137.0 148.0 147.0 144.0 153.0 147.0 152.0 147.0 149.0 148.0 20:03:07 INFO - 2015-11-24 20:03:07,521 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,521 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 20:03:07 INFO - 2015-11-24 20:03:07,521 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 68.0 67.0 67.0 67.0 66.0 67.0 68.0 67.0 20:03:07 INFO - 2015-11-24 20:03:07,521 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:03:07 INFO - 2015-11-24 20:03:07,521 DEBUG : BROWSER_OUTPUT: 20:03:08 INFO - 2015-11-24 20:03:08,590 INFO : Browser exited with error code: 0 20:03:08 INFO - 2015-11-24 20:03:08,640 INFO : Completed test kraken (00:00:59) 20:03:08 INFO - 2015-11-24 20:03:08,640 INFO : Starting test v8_7 20:03:08 INFO - 2015-11-24 20:03:08,640 DEBUG : operating with platform_type : linux_ 20:03:08 INFO - 2015-11-24 20:03:08,641 INFO : Initialising browser for v8_7 test... 20:03:08 INFO - 2015-11-24 20:03:08,647 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdrN6o9/profile http://localhost:59286/getInfo.html 20:03:13 INFO - 2015-11-24 20:03:13,168 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:03:13 INFO - 2015-11-24 20:03:13,168 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:03:13 INFO - 2015-11-24 20:03:13,168 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:03:13 INFO - 2015-11-24 20:03:13,168 DEBUG : BROWSER_OUTPUT: __metrics 20:03:14 INFO - 2015-11-24 20:03:14,245 INFO : Browser initialized. 20:03:14 INFO - 2015-11-24 20:03:14,246 INFO : Running cycle 1/1 for v8_7 test... 20:03:14 INFO - 2015-11-24 20:03:14,246 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdrN6o9/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:03:26 INFO - 2015-11-24 20:03:26,006 DEBUG : BROWSER_OUTPUT: RSS: Main: 138903552 20:03:26 INFO - 2015-11-24 20:03:26,006 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,356 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59286/tests/v8_7/run.html (next: http://localhost:59286/tests/v8_7/run.html) 20:03:47 INFO - 2015-11-24 20:03:47,606 DEBUG : BROWSER_OUTPUT: RSS: Main: 137531392 20:03:47 INFO - 2015-11-24 20:03:47,606 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,607 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:03:47 INFO - 2015-11-24 20:03:47,608 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:03:47 INFO - 2015-11-24 20:03:47,608 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:03:47 INFO - 2015-11-24 20:03:47,608 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:03:47 INFO - 2015-11-24 20:03:47,608 DEBUG : BROWSER_OUTPUT: |0;Richards;166.19577862722286 20:03:47 INFO - 2015-11-24 20:03:47,608 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;191.90174630589138 20:03:47 INFO - 2015-11-24 20:03:47,608 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.0336040324839 20:03:47 INFO - 2015-11-24 20:03:47,608 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5235.602094240838 20:03:47 INFO - 2015-11-24 20:03:47,609 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1092.7947598253274 20:03:47 INFO - 2015-11-24 20:03:47,609 DEBUG : BROWSER_OUTPUT: |5;Earley;715.8196134574088 20:03:47 INFO - 2015-11-24 20:03:47,609 DEBUG : BROWSER_OUTPUT: |6;Boyer;12228.915662650603 20:03:47 INFO - 2015-11-24 20:03:47,609 DEBUG : BROWSER_OUTPUT: |7;RegExp;25000 20:03:47 INFO - 2015-11-24 20:03:47,609 DEBUG : BROWSER_OUTPUT: |8;Splay;638.1620931716656 20:03:47 INFO - 2015-11-24 20:03:47,610 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4854.368932038835 20:03:47 INFO - 2015-11-24 20:03:47,610 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:03:47 INFO - 2015-11-24 20:03:47,610 DEBUG : BROWSER_OUTPUT: __startTimestamp1448424227606__endTimestamp 20:03:47 INFO - 2015-11-24 20:03:47,610 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,611 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:03:47 INFO - 2015-11-24 20:03:47,611 DEBUG : BROWSER_OUTPUT: Number of tests: 10 20:03:47 INFO - 2015-11-24 20:03:47,611 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,611 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.20 Median:166.20 stddev:0.00 (0.0%) 20:03:47 INFO - 2015-11-24 20:03:47,611 DEBUG : BROWSER_OUTPUT: Values: 166.2 20:03:47 INFO - 2015-11-24 20:03:47,611 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,612 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:191.90 Median:191.90 stddev:0.00 (0.0%) 20:03:47 INFO - 2015-11-24 20:03:47,612 DEBUG : BROWSER_OUTPUT: Values: 191.9 20:03:47 INFO - 2015-11-24 20:03:47,612 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,612 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.03 Median:280.03 stddev:0.00 (0.0%) 20:03:47 INFO - 2015-11-24 20:03:47,612 DEBUG : BROWSER_OUTPUT: Values: 280.0 20:03:47 INFO - 2015-11-24 20:03:47,613 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,613 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5235.60 Median:5235.60 stddev:0.00 (0.0%) 20:03:47 INFO - 2015-11-24 20:03:47,613 DEBUG : BROWSER_OUTPUT: Values: 5235.6 20:03:47 INFO - 2015-11-24 20:03:47,613 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,613 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1092.79 Median:1092.79 stddev:0.00 (0.0%) 20:03:47 INFO - 2015-11-24 20:03:47,613 DEBUG : BROWSER_OUTPUT: Values: 1092.8 20:03:47 INFO - 2015-11-24 20:03:47,613 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,613 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:715.82 Median:715.82 stddev:0.00 (0.0%) 20:03:47 INFO - 2015-11-24 20:03:47,613 DEBUG : BROWSER_OUTPUT: Values: 715.8 20:03:47 INFO - 2015-11-24 20:03:47,614 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,614 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12228.92 Median:12228.92 stddev:0.00 (0.0%) 20:03:47 INFO - 2015-11-24 20:03:47,614 DEBUG : BROWSER_OUTPUT: Values: 12228.9 20:03:47 INFO - 2015-11-24 20:03:47,614 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,614 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25000.00 Median:25000.00 stddev:0.00 (0.0%) 20:03:47 INFO - 2015-11-24 20:03:47,614 DEBUG : BROWSER_OUTPUT: Values: 25000.0 20:03:47 INFO - 2015-11-24 20:03:47,614 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,614 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:638.16 Median:638.16 stddev:0.00 (0.0%) 20:03:47 INFO - 2015-11-24 20:03:47,614 DEBUG : BROWSER_OUTPUT: Values: 638.2 20:03:47 INFO - 2015-11-24 20:03:47,614 DEBUG : BROWSER_OUTPUT: 20:03:47 INFO - 2015-11-24 20:03:47,615 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4854.37 Median:4854.37 stddev:0.00 (0.0%) 20:03:47 INFO - 2015-11-24 20:03:47,616 DEBUG : BROWSER_OUTPUT: Values: 4854.4 20:03:47 INFO - 2015-11-24 20:03:47,616 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:03:47 INFO - 2015-11-24 20:03:47,616 DEBUG : BROWSER_OUTPUT: 20:03:48 INFO - 2015-11-24 20:03:48,565 INFO : Browser exited with error code: 0 20:03:48 INFO - 2015-11-24 20:03:48,577 INFO : Completed test v8_7 (00:00:39) 20:03:48 INFO - 2015-11-24 20:03:48,680 INFO : Completed test suite (00:20:10) 20:03:48 INFO - 2015-11-24 20:03:48,680 DEBUG : Working with test: dromaeo_css 20:03:48 INFO - 2015-11-24 20:03:48,680 DEBUG : Generating results file: dromaeo_css 20:03:48 INFO - 2015-11-24 20:03:48,681 DEBUG : Working with test: kraken 20:03:48 INFO - 2015-11-24 20:03:48,681 DEBUG : Generating results file: kraken 20:03:48 INFO - 2015-11-24 20:03:48,681 INFO : javascript benchmark 20:03:48 INFO - 2015-11-24 20:03:48,682 DEBUG : Working with test: v8_7 20:03:48 INFO - 2015-11-24 20:03:48,682 DEBUG : Generating results file: v8_7 20:03:48 INFO - 2015-11-24 20:03:48,682 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:03:48 INFO - 2015-11-24 20:03:48,732 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 20:03:48 INFO - 2015-11-24 20:03:48,732 DEBUG : process_Request line: dromaeo_css 7082.99 graph.html#tests=[[72,64,43]] 20:03:48 INFO - 2015-11-24 20:03:48,732 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:03:48 INFO - 2015-11-24 20:03:48,762 DEBUG : process_Request line: kraken 1513.60 graph.html#tests=[[232,64,43]] 20:03:48 INFO - 2015-11-24 20:03:48,762 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:03:48 INFO - 2015-11-24 20:03:48,794 DEBUG : process_Request line: v8_7 21159.24 graph.html#tests=[[230,64,43]] 20:03:48 INFO - 2015-11-24 20:03:48,798 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3241.6434192238853, 3302.3395320935806, 3332.850021749021, 3343.1976325681844, 3358.983205083974, 5255.500727430897, 5423.457654234575, 5438.265834112389, 5442.8911421771545, 5451.618386712932, 5290.523852853252, 5303.734813259341, 5422.376426710929, 5440.510354068133, 5496.1755736639425, 4942.233953737176, 5026.447090820026, 5082.0, 5116.667416617926, 5129.128048231791, 4989.675671081368, 5064.5695115915005, 5081.18701007837, 5144.768485418146, 5172.922406163911, 4817.638677099231, 4945.431275403303, 4960.131976904064, 5005.124103281929, 5056.570191533724, 5023.422306434771, 5044.319016932704, 5046.34397528319, 5052.267421223902, 5103.106956282672, 4997.800087996476, 5019.272205530214, 5020.673656212334, 5025.969676216367, 5029.874253143701, 5102.974485127587, 5157.638965272433, 5194.480551944813, 5261.921071183935, 5281.260623512711, 6013.1581578579035, 6017.277926648786, 6032.366601506823, 6039.607425517372, 6061.393860613904, 5747.568932330092, 6074.210352654127, 6089.421504957022, 6091.2995005574485, 6104.267487901436, 5513.724313784335, 5607.52336051432, 5710.543156547466, 5726.742296596663, 5758.3665796762325, 6104.847378815476, 6120.806442743622, 6153.630782153085, 6156.045812899008, 6157.384261573807, 5712.714364281769, 5839.036558967808, 5925.881482370329, 5951.226340575698, 5966.522678185735, 5906.616069955439, 5965.612235204698, 5977.312609049928, 5981.162637230791, 5994.220751302303, 5908.320543137508, 5930.525557955353, 5971.940280597225, 5980.671063091485, 5981.611195272293, 5743.62666426681, 5801.3328467225965, 5886.705664716833, 5927.436893495111, 5989.970050149721, 4491.236489796743, 4552.567506086916, 4562.726236425825, 4592.380028696083, 4594.7702614869795, 4106.7125301228625, 4116.855910043134, 4130.876073717794, 4134.1731653668785, 4161.25097482456, 5453.372862120827, 5529.198266251371, 5613.382527921925, 5776.711164441761, 5783.65298082108, 5959.970200148971, 5976.581639285208, 5978.760849566055, 5986.551008674367, 6026.246719160105, 5655.4910058094965, 5656.604037717402, 5660.8867822643315, 5661.915071273934, 5663.263775709132, 4874.0, 4967.453580106186, 4969.329140566049, 4973.403191617029, 5039.067772462033, 6035.336113027563, 6090.299615544269, 6134.344744984565, 6149.846253843601, 6166.876662466726, 6045.244344456943, 6069.848253793691, 6098.871708733898, 6100.23747031621, 6103.992841181333, 5932.199153114274, 6231.9094158521175, 6332.050192471166, 6340.682965851597, 6341.207349081365, 5776.826695199151, 5798.333191682842, 5902.557308201816, 5936.970315148396, 5979.162917191684, 6060.787872424444, 6064.757409703562, 6070.271567411939, 6071.453569178795, 6101.694915254131, 5547.7780888764, 5943.257092863392, 5952.642841429528, 5955.731992060281, 5957.880842383215, 5721.771129154788, 5727.341355744143, 5732.885342293214, 5739.7417116229035, 5742.0, 5596.0, 5638.097904335286, 5639.182318563703, 5640.32316122068, 5640.4077571856005, 5724.169995350734, 5731.541476681772, 5768.365479797135, 5773.16289138081, 5783.479486846204, 5638.5489160867855, 5660.264165658438, 5788.623739456922, 5818.214541037075, 5859.824205273849], "unit": "ms", "name": "dojo.html", "value": 5475.491286495476}, {"lowerIsBetter": false, "replicates": [556.1351908941123, 585.3210276079743, 595.2054007899454, 602.3915844996553, 604.9455549000592, 38086.0, 38282.61717382825, 38511.0, 38646.613533864656, 38988.80505597475, 42760.35859462103, 43687.563124368746, 43963.34054989178, 43971.0, 44079.33880991788, 8621.655133794657, 8787.648494060262, 8791.20879120879, 8797.07630698777, 8809.867851982226, 8610.827783444327, 8695.39132260742, 8707.60815763289, 8733.825323493558, 8737.21365077143, 8667.653293868238, 8741.12588741124, 8752.431091979033, 8758.824823503526, 8769.95615021924, 8528.78678033051, 8742.0, 8742.0, 8745.387822852403, 8758.56207189638, 8591.613377398033, 8649.567521623894, 8681.652733890636, 8737.912620873774, 8737.956310218471, 40049.79975100106, 40440.595594043974, 40451.0, 40546.18907621861, 40613.39079913774, 3374.3598421317124, 3409.539712138854, 3444.7588668793196, 3468.202313467891, 3869.28418242626, 29452.55821162684, 29616.0, 29949.700502995125, 30155.849220753975, 30204.697953020408, 3765.1151979284846, 3766.6233376662112, 3861.6524512793985, 3884.7086468514976, 3892.630200130983, 263322.0, 263347.9999999981, 264387.0, 264644.0, 265323.0, 55739.0, 56421.0, 57663.0, 64772.35227647758, 65563.34436655667, 61718.69140654358, 61744.0, 61848.76302473925, 62096.0, 62358.37641623616, 14913.403463861541, 15004.24978751058, 15112.168830714416, 15115.31981060833, 15193.468228611946, 11930.522779088738, 11958.701032474257, 11974.0, 12019.098567607465, 12129.514819407124, 19545.413637590897, 19708.31020914261, 19739.605207895762, 19814.90092549528, 20052.39842804718, 8038.63826127814, 8131.227533384319, 8228.341732661373, 8269.51148793223, 8348.0, 974.181687382602, 1020.3265844542566, 1043.2905624175482, 1086.2070688397357, 1087.8259478483405, 27133.86433067822, 27393.19590615527, 27507.724922750916, 27732.44535109327, 27892.581611275848, 1034.0693375961694, 1046.8325067989238, 1049.1711547877244, 1049.4962418039383, 1136.6135513925306, 13633.522826701217, 13726.45094196221, 13892.0, 13931.651708707363, 14471.927640361731, 313211.0, 317738.0, 318492.0, 319102.0, 319560.0, 52448.951020979366, 52588.0, 52685.47314526806, 52697.7365113172, 52737.0, 1339.5378594384922, 1343.2612063365111, 1376.6971266321593, 1377.5178687459509, 1377.7726675098697, 26826.731732683205, 27181.1845644631, 27927.441451171268, 27982.3004424891, 28275.717242827308, 45251.54748452473, 45517.77241113773, 45555.31667024931, 45581.54418455773, 45587.088258233984, 287694.0, 287992.0, 288233.0, 289391.0, 289649.0, 31621.52567711532, 32493.18767030843, 32971.17572060622, 33113.66886331106, 33294.66705333012, 40520.69259493808, 42875.0, 43773.0, 43820.0, 44231.77884110559, 36853.815730921175, 37190.28408553909, 37669.434958476195, 38201.808990954865, 38908.41637375498, 36652.45021324629, 36868.81565592155, 37406.812965936086, 37425.69216716847, 37770.0, 36426.0, 36459.635403645625, 36607.81696091503, 36739.26521469502, 37016.0, 31228.843855781484, 33253.0, 33282.83358583289, 33783.32433351369, 33903.15242118967, 25385.873070634527, 25413.61879571771, 25748.227553173434, 25789.871050644626, 26043.479130417665], "unit": "ms", "name": "ext.html", "value": 18604.109001528137}, {"lowerIsBetter": false, "replicates": [359.94420864765954, 362.3152242262125, 364.20784793075086, 364.43876430297337, 364.7082334132694, 14102.435902563886, 14385.0, 15953.282102305395, 16717.08056056918, 17474.0, 16047.598810029755, 16236.431724889631, 16642.08468534233, 16736.916315418435, 16744.581385465368, 5392.487713667196, 5498.0653288940775, 5527.613067085305, 5528.281323427948, 5552.250446189753, 5223.555997740177, 5309.362876454831, 5341.4925582069645, 5385.246065550826, 5483.890322193553, 5243.79024839006, 5311.601629877755, 5323.22813192087, 5598.516393155817, 5619.4942455179025, 5518.089515229983, 5523.530499907512, 5540.750666220029, 5600.635958662674, 5622.212890195356, 5026.170681837492, 5302.284191634157, 5304.6286759926825, 5450.264214331094, 5472.2338872557875, 12524.624261272085, 12570.622881313575, 12795.104342696015, 12832.294223817686, 12919.160254583516, 2127.57448510297, 2165.1231251343233, 2204.6252137136726, 2230.9107635694713, 2248.325502349305, 9218.078192180796, 9266.305027122926, 9494.430334179971, 9668.613255469843, 9714.9514252429, 1843.2074208090517, 1862.255097960813, 1893.57394586217, 1907.3133671878252, 1912.8947907865063, 60446.69776651132, 61041.6947915262, 64158.999999999534, 64373.999999999534, 64530.67734661297, 9988.500574971222, 10313.84529232062, 10369.533370998322, 10395.688129356131, 10443.68668939933, 13505.0, 13566.0, 13571.1857288563, 13591.0, 13591.864081359257, 7631.3131818136635, 7676.884846727192, 7701.537907725555, 7710.036245469317, 7846.0, 7364.116306043309, 7399.6670149843385, 7404.481686281909, 7432.85134297311, 7436.739714110088, 4258.361245813091, 4410.955890441054, 4472.44094488189, 4483.21543729846, 4488.12481566098, 1682.528891910267, 1718.7679663245551, 1796.3802488141832, 1804.6661367647027, 1812.909354532268, 893.678275820704, 927.3137877970388, 963.982997937169, 978.3445091788519, 1019.2508506247901, 16734.49796506107, 16815.579610509834, 16947.745783813007, 16982.0, 17278.30886764515, 925.6158694141856, 932.2588542109039, 1010.8989101089979, 1018.9898101019044, 1026.5996261458038, 9551.713448596553, 9802.0, 9818.950905245429, 9882.456464894356, 9889.40654629896, 528837.0, 529521.0, 530822.0, 536899.0, 551499.0, 77189.61405192938, 79407.0, 80623.0, 81007.59496202481, 81011.0, 1103.2056919018294, 1103.7130346110068, 1138.9373584452935, 1153.4463457540428, 1163.8777928317575, 15131.773023404437, 15202.543923682308, 15236.466723664618, 15239.847601524287, 15477.380904763684, 34053.31893362168, 35846.46230306495, 36502.0, 36674.0, 37469.06327341838, 45348.0, 45654.77172614116, 46268.768656156506, 46691.29963050489, 47149.29276060793, 12856.807147892601, 14671.92664036673, 15007.647782143056, 15145.091294522364, 15189.544313670607, 11092.33445993243, 11449.828252576384, 11627.941860290644, 11664.475098620413, 18801.341953031577, 10139.290249682452, 10342.741431464274, 10368.066873981075, 10378.06597406237, 10437.843432348369, 9778.75553111178, 9961.302708810314, 10016.39901605906, 10117.392956422638, 10163.593456261668, 10155.339902906582, 10260.486975651038, 10338.534765935401, 10367.27429079957, 10759.30064545802, 14794.926025369805, 14831.555053348417, 15012.7748083781, 15133.394664213309, 15153.0, 14737.11577305365, 14990.700185996, 15207.935444518778, 15241.085534867943, 16051.197440127713], "unit": "ms", "name": "jquery.html", "value": 8736.711746615347}, {"lowerIsBetter": false, "replicates": [1519.5897107780906, 1696.4062578097664, 1733.4626265857585, 1756.9824301756978, 1770.247644750981, 7323.0, 7549.50928189668, 7578.431617628686, 7618.428617853662, 7667.7316293929725, 7599.202083781206, 7648.579328136951, 7655.502392344508, 7667.7316293929725, 7721.7683469496005, 2316.698829152212, 2347.072906202049, 2348.1194552042984, 2348.377679914824, 2348.6477028445697, 2298.2990187992655, 2344.9648255276097, 2354.5997180479358, 2368.4197371643972, 2371.7509661485556, 2213.8671679699187, 2258.2096266306826, 2300.620397634397, 2319.0, 2320.8027317678016, 2283.474800795818, 2313.780190881872, 2320.5358928214423, 2330.8718020509036, 2334.48641298914, 2254.966175507369, 2339.883005849718, 2354.5761762882676, 2354.799842013431, 2356.5640356534095, 7752.0, 7764.805879852993, 7832.138464768871, 7832.56920869352, 7866.1740517245435, 4517.870532366908, 4671.229247174212, 4691.906161876777, 4698.107359601699, 4714.127886341003, 3003.039027511197, 3042.421939831447, 3084.7686423518326, 3093.1957691000275, 3123.109913674592, 926.4719110107242, 938.7606160429046, 951.6288647427509, 995.6764051683159, 1039.2621238920376, 10165.59337626494, 10169.440680762555, 10232.232582556264, 10236.795264094677, 10254.794904101951, 4208.684348673862, 4273.850415235452, 4282.571742825755, 4309.719868208556, 4338.522762496123, 8444.071152173256, 8453.66185352579, 8479.830403392021, 8504.107068757816, 8524.488530688179, 4571.085782843445, 4579.564941330568, 4584.33527138563, 4607.285870690049, 4632.050429661978, 4139.793010349531, 4181.080162364275, 4206.179794940019, 4220.873373798791, 4240.618344349024, 3458.3774920514047, 3469.462233353834, 3501.1597216668324, 3523.224890524081, 3554.662307080823, 1092.7158938675843, 1099.8515200447837, 1107.9390633515234, 1109.97327472088, 1110.4614262082882, 1072.9570817167382, 1074.978500429987, 1079.071998081649, 1194.498310709504, 1264.3235868810143, 7012.859742805115, 7066.752663656747, 7102.3962963148715, 7153.785386438415, 7167.0, 1149.706824759681, 1154.215133709069, 1186.7863784518697, 1197.9940100299443, 1202.3086725133082, 5642.04085305499, 5655.915161272585, 5671.2060311556415, 5692.430756924357, 5733.770649173986, 8711.390202685878, 8766.517841518778, 9051.954740226389, 9103.635854565744, 9124.31567632417, 8850.690225842065, 8897.599608017656, 8900.287976961827, 8900.510471924106, 8935.0, 6819.863602728017, 7098.041764361745, 7114.78655640342, 7165.498415110892, 7255.746048888263, 7005.684744186626, 7039.859202815813, 7052.188998265266, 7101.751438699621, 7104.11198600175, 7294.525855819355, 7983.281504664608, 7991.400644951768, 8000.319972802377, 8006.759797206093, 7904.628720481699, 7967.442279040411, 8055.234752698484, 8184.181581841768, 8220.3834712398, 6313.463355614824, 6826.419754320938, 6926.06498122747, 6974.79075627732, 7008.929910701032, 7295.0, 7312.890306645511, 7335.523190992569, 7341.0, 7362.7423040193335, 6867.587944723333, 6892.310768923067, 6940.236573976959, 6986.266442023718, 7054.0, 6634.170728658918, 6842.55523390978, 6890.173179218526, 6908.378245957888, 6962.443004559725, 6890.93109068923, 6972.337627925339, 6991.40573051276, 7015.579065256101, 7016.298370162943, 6369.8726025480155, 6373.044043393528, 6397.4562162216735, 6413.775517856852, 6441.452476539359, 6533.8693226136165, 6562.048502967138, 6585.6377899216, 6615.735370585123, 6621.1392518972225], "unit": "ms", "name": "mootools.html", "value": 4432.457924508278}, {"lowerIsBetter": false, "replicates": [332.9495441880866, 337.5881424661912, 343.78169862137537, 345.7044227185756, 348.90230735394084, 6235.968820155898, 6260.060990851363, 6333.524985626079, 6341.746330146789, 6386.744530218785, 6131.938680613192, 6232.096346029834, 6253.155823963763, 6254.124422580831, 6261.686915654222, 4775.832845850396, 4796.040791841646, 4808.975955120237, 4818.325434439182, 4827.372441582598, 4820.3733514643045, 4823.807047718087, 4824.2040063389495, 4825.420949486067, 4828.589569886564, 4705.1765940960195, 4756.262779269219, 4788.186008378584, 4789.832355867546, 4799.640026997971, 4759.881002974918, 4776.832810851621, 4778.928316075262, 4781.832635857763, 4782.617390608743, 4187.769672668002, 4188.623023927861, 4190.811413486401, 4194.2450358935375, 4194.2869712149, 5910.67491287979, 5911.231539899785, 5965.582409231356, 5967.015442451991, 5969.164316995624, 5465.808696695636, 5599.048161812501, 5605.635633683838, 5606.0, 5617.859553511154, 6592.80221593353, 6618.271990081087, 6632.137822083098, 6632.767853125166, 6646.302138275508, 4670.906581868344, 4679.27471241958, 4685.4377474702915, 4689.132510485537, 4689.648276379251, 6646.368594983469, 6801.319868013258, 6835.111435513351, 6879.58722476653, 6906.102206713095, 4740.217864052462, 4750.524947505291, 4751.786169622375, 4754.23932170851, 4759.405074365704, 4803.735794531334, 4813.15769740294, 4814.470408255089, 4815.975920120377, 4823.397075365579, 5561.026820306431, 5566.192902029183, 5573.721313934287, 5577.609567330329, 5586.329640443091, 6571.390009447716, 6712.160979877515, 6713.731450742013, 6719.0593316935665, 6721.327867213337, 1683.3350826423493, 1709.4273418404725, 1720.5956600198942, 1731.2382551677474, 1732.1079643983358, 1151.6257216404701, 1154.463174623804, 1160.22265082395, 1168.199783148546, 1182.804837201852, 6356.173697419306, 6364.949783285706, 6370.362963703593, 6379.393957574023, 6390.137331460286, 6772.525923185428, 6805.149356330458, 6808.761693340709, 6818.454523638097, 6820.386165245052, 6198.969005154945, 6275.403836635513, 6352.237731472253, 6355.237371515355, 6362.968185159045, 3695.094701798023, 3758.5113935188797, 3769.547654281449, 3779.7732136071922, 3788.431735239681, 7220.92779072213, 7224.566526008456, 7241.637918104074, 7250.093738282715, 7263.89104163438, 6994.230634630187, 7114.217436082027, 7125.287471252833, 7166.749163779347, 7176.102987126609, 7210.67551960163, 7262.891056634155, 7264.237255088246, 7268.454865884974, 7271.091113610799, 6890.758823441155, 6936.826579335557, 6943.201531823703, 6949.339812717783, 6979.336962988508, 6762.459003279628, 6886.898096304768, 6904.758333458305, 6910.792676219721, 6919.100516932766, 7222.96388518054, 7225.783226503213, 7237.240089790706, 7256.673449694671, 7259.6007219601315, 4602.286645569942, 4636.8145274188655, 4637.489876113557, 4640.234361330343, 4659.953400465952, 6799.5920244785475, 6948.513604047668, 6963.860722785617, 6976.7558135465015, 6985.240829872274, 6762.797116086525, 6768.627725474949, 6772.627505487048, 6785.253622101466, 6785.626790526569, 6728.529002969941, 6760.005798289418, 6760.628165450751, 6763.053172555945, 6786.898196527155, 6460.192475940507, 6634.800955971328, 6637.535372523877, 6643.56816806906, 6654.168228971379, 6782.084418603426, 6793.830154246183, 6795.694193761194, 6804.353586409283, 6813.7955861324235, 6571.080048793077, 6605.372489613479, 6606.768763093269, 6623.105880706235, 6628.204615446178], "unit": "ms", "name": "prototype.html", "value": 5048.636597652104}, {"lowerIsBetter": false, "replicates": [764.9005629268196, 779.0767939991111, 780.7735756630576, 822.0874828939876, 836.2724429746122, 13688.863111368883, 13800.447982080705, 13909.16545007299, 13935.163890166585, 13954.232517211574, 12463.875361246384, 12875.227486350825, 13320.134191277584, 13340.666483337922, 13359.33203339834, 830.954297513638, 833.7207035643066, 837.656560810068, 840.4831028917233, 845.1548451548451, 826.396730386818, 838.2372041442288, 839.6977088248226, 840.9243168114868, 842.4439869686008, 867.2931560777946, 877.1447838357614, 878.6441491196076, 879.1779685993585, 880.2781718990431, 860.114082495028, 862.202462721986, 866.2939704141105, 870.5168631409597, 870.9825803483958, 638.2596188421455, 642.8328634555003, 646.3149061994301, 646.385933363302, 648.0861984601736, 11921.821172682417, 12061.879381206163, 12158.635240942785, 12185.268883866907, 12200.267983920905, 10806.243562950596, 10975.560977560888, 11073.612423565137, 11205.607803726913, 11428.257163284443, 9594.13652771247, 9869.080571556804, 9965.611761774233, 10007.699769006942, 10037.29738918276, 3477.51314815926, 3507.158282012324, 3511.929761404783, 3520.1551627609447, 3562.661547153042, 41505.1698966022, 41812.0, 46178.307325390146, 46515.53484465146, 47102.528974710156, 9935.304528683067, 10071.949640251752, 10127.139193168516, 10328.948355258326, 10750.0, 18138.09309534518, 18251.908740456212, 18287.72568411475, 18298.0, 18320.0, 7639.0, 7692.538447693157, 7704.075510938611, 7745.264199901, 7852.332551733052, 4442.000549876317, 4506.526814684476, 4526.02690421561, 4534.79593418297, 4548.545145485425, 3075.3234288456506, 3079.876804927778, 3080.6765289644713, 3082.0, 3098.4732595458827, 592.5437413191818, 597.9850503737441, 600.069891667913, 603.3122240645667, 604.3865476541314, 676.1075380497795, 678.0201810712731, 685.8088407537969, 688.1913751342153, 694.5485434467536, 8591.183837535424, 8694.914238932843, 8717.825643487095, 8775.473471591726, 8969.596368163448, 523.7198099017007, 571.6855729348841, 666.5733930284622, 693.774523279936, 717.0749732844572, 3966.4050392441363, 4021.0, 4090.284200264942, 4145.253854306254, 4217.40956266123, 55806.16290755642, 56096.719516402976, 56363.43636563664, 56668.71665641645, 56712.0, 47872.521274786806, 50656.0, 51391.22913156306, 51591.0, 52163.47836521662, 46381.5361846384, 47180.52819471761, 47385.28922066172, 47581.762091189325, 47887.281690775366, 8550.444221125606, 8824.779380515538, 8881.822363552821, 8941.776455588662, 8945.686900958435, 34224.82887585546, 34524.82737586296, 35087.29825403529, 35853.462198066525, 35957.64042359543, 41813.790931046366, 45015.77492112519, 45745.54254457413, 45818.312725309814, 45905.540944590124, 10836.783264334827, 10995.120390368911, 11270.009825034931, 11307.321560706385, 11323.830142547704, 12055.434798754002, 12521.248725076524, 12737.235765854079, 13034.739305213652, 13040.54358097462, 11732.237404568676, 11839.644810655695, 11887.881121188677, 12358.320292383645, 12563.685907852378, 11565.479553419947, 11714.589989350332, 11858.456228739205, 11884.465199066235, 12005.149024655975, 11396.658100257006, 11439.256448330832, 11485.942570287096, 11625.128115391548, 12103.576374826838, 8854.513001784964, 9154.26765858743, 9298.581563829779, 9365.625374984924, 9633.759156021155, 8930.86603700958, 8937.865932010895, 9707.476056225574, 9709.9029009709, 9800.36297640651], "unit": "ms", "name": "yui.html", "value": 6339.648348686045}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 104.0, 112.0, 111.0, 111.0, 124.0, 112.0, 112.0, 111.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 111.9}, {"lowerIsBetter": true, "replicates": [151.0, 126.0, 150.0, 170.0, 126.0, 153.0, 154.0, 131.0, 167.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.4}, {"lowerIsBetter": true, "replicates": [163.0, 161.0, 163.0, 164.0, 162.0, 162.0, 162.0, 164.0, 178.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 164.1}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 71.0, 72.0, 80.0, 72.0, 125.0, 72.0, 71.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 78.2}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 137.0, 93.0, 92.0, 94.0, 137.0, 92.0, 93.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [152.0, 147.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.2}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 99.0, 95.0, 97.0, 96.0, 97.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [65.0, 67.0, 62.0, 61.0, 61.0, 64.0, 62.0, 61.0, 69.0, 63.0], "unit": "ms", "name": "json-parse-financial", "value": 63.5}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 70.0, 71.0, 70.0, 71.0, 72.0, 71.0, 71.0, 72.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.8}, {"lowerIsBetter": true, "replicates": [84.0, 84.0, 84.0, 92.0, 87.0, 83.0, 85.0, 86.0, 85.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.4}, {"lowerIsBetter": true, "replicates": [129.0, 120.0, 118.0, 120.0, 117.0, 126.0, 120.0, 115.0, 125.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.8}, {"lowerIsBetter": true, "replicates": [137.0, 148.0, 147.0, 144.0, 153.0, 147.0, 152.0, 147.0, 149.0, 148.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 147.2}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 66.0, 67.0, 68.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.1}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.19577862722286], "unit": "score", "name": "Richards", "value": 212.41213400000004}, {"lowerIsBetter": false, "replicates": [191.90174630589138], "unit": "score", "name": "DeltaBlue", "value": 344.54089799999997}, {"lowerIsBetter": false, "replicates": [280.0336040324839], "unit": "score", "name": "Encrypt", "value": 950.5323509999997}, {"lowerIsBetter": false, "replicates": [5235.602094240838], "unit": "score", "name": "Decrypt", "value": 50.84057100000005}, {"lowerIsBetter": false, "replicates": [1092.7947598253274], "unit": "score", "name": "RayTrace", "value": 677.1527712287716}, {"lowerIsBetter": false, "replicates": [715.8196134574088], "unit": "score", "name": "Earley", "value": 931.0488110000001}, {"lowerIsBetter": false, "replicates": [12228.915662650603], "unit": "score", "name": "Boyer", "value": 54.498944827586165}, {"lowerIsBetter": false, "replicates": [25000.0], "unit": "score", "name": "RegExp", "value": 36.43939999999999}, {"lowerIsBetter": false, "replicates": [638.1620931716656], "unit": "score", "name": "Splay", "value": 127.69639699999996}, {"lowerIsBetter": false, "replicates": [4854.368932038835], "unit": "score", "name": "NavierStokes", "value": 305.7040000000001}], "name": "v8_7"}]} 20:03:48 INFO - RETURN: dromaeo_css: 7082.99 20:03:48 INFO - RETURN: kraken: 1513.60 20:03:48 INFO - RETURN: v8_7: 21159.24 20:03:48 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21159.24"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1513.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "7082.99"}}} 20:03:48 INFO - Return code: 0 20:03:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 20:03:48 INFO - # TBPL SUCCESS # 20:03:48 INFO - Running post-action listener: _resource_record_post_action 20:03:48 INFO - Running post-run listener: _resource_record_post_run 20:03:49 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 12288; Write bytes: 524775424; Read time: 176; Write time: 629092 20:03:49 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 4096; Write bytes: 177782784; Read time: 100; Write time: 329508 20:03:50 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 8192; Write bytes: 275988480; Read time: 76; Write time: 215008 20:03:50 INFO - Running post-run listener: _upload_blobber_files 20:03:50 INFO - Blob upload gear active. 20:03:50 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 20:03:50 INFO - Copying logs to upload dir... 20:03:50 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1259.415212 ========= master_lag: 7.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-11-24 20:03:56.753849) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 20:03:56.757911) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010158 build_url: 'https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.31 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 20:03:57.075754) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 20:03:57.076275) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448422730.592884-1970797050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004505 ========= master_lag: 0.38 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 20:03:57.458933) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 20:03:57.459427) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 20:03:57.460361) ========= ========= Total master_lag: 8.10 =========