builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-047 starttime: 1448544283.9 results: success (0) buildid: 20151126035113 builduid: 673fd583569b4f66b24b34108e3dd88e revision: 5fe77514a40b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:43.904865) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:43.905398) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:43.905682) ========= 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=f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006695 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:43.926364) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:43.926649) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:44.291826) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:44.292128) ========= 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=f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004735 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:44.348900) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:44.349184) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:44.349544) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:44.349806) ========= 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=f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-26 05:24:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.4M=0s 2015-11-26 05:24:45 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.406229 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:44.775553) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:44.775836) ========= 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=f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015110 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:44.828114) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-26 05:24:44.828467) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5fe77514a40b --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 5fe77514a40b --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=f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-26 05:24:45,240 Setting DEBUG logging. 2015-11-26 05:24:45,240 attempt 1/10 2015-11-26 05:24:45,240 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5fe77514a40b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-26 05:24:46,438 unpacking tar archive at: fx-team-5fe77514a40b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.454650 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-26 05:24:46.299769) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:46.300065) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:46.310892) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:46.311228) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 05:24:46.311659) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-11-26 05:24:46.311940) ========= /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=f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:24:46 INFO - MultiFileLogger online at 20151126 05:24:46 in /builds/slave/test-pgo 05:24:46 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 05:24:46 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:24:46 INFO - {'append_to_log': False, 05:24:46 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:24:46 INFO - 'blob_upload_branch': 'Fx-Team', 05:24:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:24:46 INFO - 'branch': 'Fx-Team', 05:24:46 INFO - 'buildbot_json_path': 'buildprops.json', 05:24:46 INFO - 'config_files': ('talos/linux_config.py',), 05:24:46 INFO - 'default_actions': ('clobber', 05:24:46 INFO - 'read-buildbot-config', 05:24:46 INFO - 'download-and-extract', 05:24:46 INFO - 'populate-webroot', 05:24:46 INFO - 'create-virtualenv', 05:24:46 INFO - 'install', 05:24:46 INFO - 'run-tests'), 05:24:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:24:46 INFO - 'download_minidump_stackwalk': True, 05:24:46 INFO - 'download_symbols': 'ondemand', 05:24:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:24:46 INFO - 'tooltool.py': '/tools/tooltool.py', 05:24:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:24:46 INFO - '/tools/misc-python/virtualenv.py')}, 05:24:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:24:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:24:46 INFO - 'installer_path': 'installer.exe', 05:24:46 INFO - 'log_level': 'info', 05:24:46 INFO - 'log_name': 'talos', 05:24:46 INFO - 'log_to_console': True, 05:24:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:24:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:24:46 INFO - 'opt_config_files': (), 05:24:46 INFO - 'pip_index': False, 05:24:46 INFO - 'sps_profile': False, 05:24:46 INFO - 'sps_profile_interval': 0, 05:24:46 INFO - 'suite': 'dromaeojs-e10s', 05:24:46 INFO - 'system_bits': '64', 05:24:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:24:46 INFO - 'title': 'talos-linux64-ix-047', 05:24:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:24:46 INFO - 'use_talos_json': True, 05:24:46 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:24:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:24:46 INFO - 'work_dir': 'build'} 05:24:46 INFO - ##### 05:24:46 INFO - ##### Running clobber step. 05:24:46 INFO - ##### 05:24:46 INFO - Running pre-action listener: _resource_record_pre_action 05:24:46 INFO - Running main action method: clobber 05:24:46 INFO - rmtree: /builds/slave/test-pgo/build 05:24:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:24:47 INFO - Running post-action listener: _resource_record_post_action 05:24:47 INFO - ##### 05:24:47 INFO - ##### Running read-buildbot-config step. 05:24:47 INFO - ##### 05:24:47 INFO - Running pre-action listener: _resource_record_pre_action 05:24:47 INFO - Running main action method: read_buildbot_config 05:24:47 INFO - Using buildbot properties: 05:24:47 INFO - { 05:24:47 INFO - "properties": { 05:24:47 INFO - "buildnumber": 109, 05:24:47 INFO - "product": "firefox", 05:24:47 INFO - "script_repo_revision": "production", 05:24:47 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:24:47 INFO - "repository": "", 05:24:47 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 05:24:47 INFO - "buildid": "20151126035113", 05:24:47 INFO - "slavename": "talos-linux64-ix-047", 05:24:47 INFO - "pgo_build": "True", 05:24:47 INFO - "basedir": "/builds/slave/test-pgo", 05:24:47 INFO - "project": "", 05:24:47 INFO - "platform": "ubuntu64_hw", 05:24:47 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:24:47 INFO - "slavebuilddir": "test", 05:24:47 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:24:47 INFO - "branch": "fx-team", 05:24:47 INFO - "repo_path": "integration/fx-team", 05:24:47 INFO - "stage_platform": "linux64-pgo", 05:24:47 INFO - "builduid": "673fd583569b4f66b24b34108e3dd88e", 05:24:47 INFO - "revision": "5fe77514a40b" 05:24:47 INFO - }, 05:24:47 INFO - "sourcestamp": { 05:24:47 INFO - "repository": "", 05:24:47 INFO - "hasPatch": false, 05:24:47 INFO - "project": "", 05:24:47 INFO - "branch": "fx-team-linux64-pgo-talos", 05:24:47 INFO - "changes": [ 05:24:47 INFO - { 05:24:47 INFO - "category": null, 05:24:47 INFO - "files": [ 05:24:47 INFO - { 05:24:47 INFO - "url": null, 05:24:47 INFO - "name": "https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 05:24:47 INFO - } 05:24:47 INFO - ], 05:24:47 INFO - "repository": "", 05:24:47 INFO - "rev": "5fe77514a40b", 05:24:47 INFO - "who": "sendchange", 05:24:47 INFO - "when": 1448544239, 05:24:47 INFO - "number": 6710756, 05:24:47 INFO - "comments": "", 05:24:47 INFO - "project": "", 05:24:47 INFO - "at": "Thu 26 Nov 2015 05:23:59", 05:24:47 INFO - "branch": "fx-team-linux64-pgo-talos", 05:24:47 INFO - "revlink": "", 05:24:47 INFO - "properties": [ 05:24:47 INFO - [ 05:24:47 INFO - "buildid", 05:24:47 INFO - "20151126035113", 05:24:47 INFO - "Change" 05:24:47 INFO - ], 05:24:47 INFO - [ 05:24:47 INFO - "builduid", 05:24:47 INFO - "673fd583569b4f66b24b34108e3dd88e", 05:24:47 INFO - "Change" 05:24:47 INFO - ], 05:24:47 INFO - [ 05:24:47 INFO - "pgo_build", 05:24:47 INFO - "True", 05:24:47 INFO - "Change" 05:24:47 INFO - ] 05:24:47 INFO - ], 05:24:47 INFO - "revision": "5fe77514a40b" 05:24:47 INFO - } 05:24:47 INFO - ], 05:24:47 INFO - "revision": "5fe77514a40b" 05:24:47 INFO - } 05:24:47 INFO - } 05:24:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 05:24:47 INFO - Running post-action listener: _resource_record_post_action 05:24:47 INFO - ##### 05:24:47 INFO - ##### Running download-and-extract step. 05:24:47 INFO - ##### 05:24:47 INFO - Running pre-action listener: _resource_record_pre_action 05:24:47 INFO - Running main action method: download_and_extract 05:24:47 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:47 INFO - https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:24:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/test_packages.json 05:24:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/test_packages.json 05:24:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:24:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:24:51 INFO - Downloaded 1302 bytes. 05:24:51 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:24:51 INFO - Using the following test package requirements: 05:24:51 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:24:51 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:24:51 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:51 INFO - u'jsshell-linux-x86_64.zip'], 05:24:51 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:24:51 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:24:51 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:24:51 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:24:51 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:24:51 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:24:51 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:24:51 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:24: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')]} 05:24:51 INFO - https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:24:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:24:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:24:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/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 05:24:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/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 05:24:58 INFO - Downloaded 22755760 bytes. 05:24: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 05:24:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:24: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 05:25:00 INFO - Return code: 0 05:25:00 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 05:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:00 INFO - https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:25:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:25:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:25:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/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 05:25:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/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 05:25:01 INFO - Downloaded 22755760 bytes. 05:25:01 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 05:25:01 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:25:01 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 05:25:02 INFO - Return code: 0 05:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:02 INFO - https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:25:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:25:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:25:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/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 05:25:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/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 05:25:04 INFO - Downloaded 11131717 bytes. 05:25:04 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 05:25:04 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:25:04 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 05:25:04 INFO - Return code: 0 05:25:04 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')]} 05:25:04 INFO - https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:25:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:25:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:25:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/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 05:25:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9dmDTOjHSimAMxcUnF7awQ/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 05:25:06 INFO - Downloaded 58386673 bytes. 05:25:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:25:06 INFO - mkdir: /builds/slave/test-pgo/properties 05:25:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:25:06 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:25:06 INFO - Contents: 05:25:06 INFO - build_url:https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:25:06 INFO - Running post-action listener: _resource_record_post_action 05:25:06 INFO - Running post-action listener: set_extra_try_arguments 05:25:06 INFO - ##### 05:25:06 INFO - ##### Running populate-webroot step. 05:25:06 INFO - ##### 05:25:06 INFO - Running pre-action listener: _resource_record_pre_action 05:25:06 INFO - Running main action method: populate_webroot 05:25:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:25:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:25:06 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:25:06 INFO - '../fennec_ids.txt'], 05:25:06 INFO - 'tests': ['tcheck2']}, 05:25:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:25:06 INFO - '--tppagecycles', 05:25:06 INFO - '7'], 05:25:06 INFO - 'tests': ['tsvgm']}}, 05:25:06 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:25:06 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:25:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:25:06 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:25:06 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:25:06 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:25:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:06 INFO - 'talos_options': ['--e10s'], 05:25:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:06 INFO - 'tests': ['damp', 'tps']}, 05:25:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:06 INFO - 'talos_options': ['--e10s'], 05:25:06 INFO - 'tests': ['damp', 'tps']}, 05:25:06 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:25:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:25:06 INFO - 'other': {'tests': ['a11yr', 05:25:06 INFO - 'ts_paint', 05:25:06 INFO - 'tpaint', 05:25:06 INFO - 'sessionrestore', 05:25:06 INFO - 'sessionrestore_no_auto_restore']}, 05:25:06 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:25:06 INFO - 'tests': ['a11yr', 05:25:06 INFO - 'ts_paint', 05:25:06 INFO - 'tpaint', 05:25:06 INFO - 'sessionrestore', 05:25:06 INFO - 'sessionrestore_no_auto_restore']}, 05:25:06 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:25:06 INFO - 'tests': ['a11yr', 05:25:06 INFO - 'ts_paint', 05:25:06 INFO - 'tpaint', 05:25:06 INFO - 'sessionrestore', 05:25:06 INFO - 'sessionrestore_no_auto_restore']}, 05:25:06 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:25:06 INFO - 'tests': ['a11yr', 05:25:06 INFO - 'ts_paint', 05:25:06 INFO - 'tpaint', 05:25:06 INFO - 'sessionrestore', 05:25:06 INFO - 'sessionrestore_no_auto_restore']}, 05:25:06 INFO - 'other_l64': {'tests': ['a11yr', 05:25:06 INFO - 'ts_paint', 05:25:06 INFO - 'tpaint', 05:25:06 INFO - 'sessionrestore', 05:25:06 INFO - 'sessionrestore_no_auto_restore']}, 05:25:06 INFO - 'other_nol64': {'tests': ['a11yr', 05:25:06 INFO - 'ts_paint', 05:25:06 INFO - 'tpaint', 05:25:06 INFO - 'sessionrestore', 05:25:06 INFO - 'sessionrestore_no_auto_restore']}, 05:25:06 INFO - 'svgr': {'tests': ['tsvgx', 05:25:06 INFO - 'tsvgr_opacity', 05:25:06 INFO - 'tart', 05:25:06 INFO - 'tscrollx', 05:25:06 INFO - 'cart']}, 05:25:06 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:25:06 INFO - 'tests': ['tsvgx', 05:25:06 INFO - 'tsvgr_opacity', 05:25:06 INFO - 'tart', 05:25:06 INFO - 'tscrollx', 05:25:06 INFO - 'cart']}, 05:25:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:06 INFO - 'tests': ['tp5o']}, 05:25:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:06 INFO - 'talos_options': ['--e10s'], 05:25:06 INFO - 'tests': ['tp5o']}, 05:25:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:06 INFO - 'talos_options': ['--xperf_path', 05:25:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:06 INFO - 'tests': ['tp5n']}, 05:25:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:06 INFO - 'talos_options': ['--e10s', 05:25:06 INFO - '--xperf_path', 05:25:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:06 INFO - 'tests': ['tp5n']}}, 05:25:06 INFO - 'talos.zip': {'path': '', 05:25:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:25:06 INFO - Running post-action listener: _resource_record_post_action 05:25:06 INFO - ##### 05:25:06 INFO - ##### Running create-virtualenv step. 05:25:06 INFO - ##### 05:25:06 INFO - Running pre-action listener: _resource_record_pre_action 05:25:06 INFO - Running main action method: create_virtualenv 05:25:06 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:25:06 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 05:25:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:25:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:25:06 INFO - Using real prefix '/usr' 05:25:06 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:25:06 INFO - Installing distribute.............................................................................................................................................................................................done. 05:25:08 INFO - Installing pip.................done. 05:25:08 INFO - Return code: 0 05:25:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:25:08 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')]} 05:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:08 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')]} 05:25:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:08 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 0x1de3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20107d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209f640>, '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 0x20a0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20a09d0>, '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': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', '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 05:25:08 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 05:25:08 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 05:25:08 INFO - Using env: {'DISPLAY': ':0', 05:25:08 INFO - 'HOME': '/home/cltbld', 05:25:08 INFO - 'LANG': 'en_US.UTF-8', 05:25:08 INFO - 'LANGUAGE': 'en_US:en', 05:25:08 INFO - 'LOGNAME': 'cltbld', 05:25:08 INFO - 'MAIL': '/var/mail/cltbld', 05:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:08 INFO - 'MOZ_NO_REMOTE': '1', 05:25:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:08 INFO - 'NO_EM_RESTART': '1', 05:25:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:25:08 INFO - 'PWD': '/builds/slave/test-pgo', 05:25:08 INFO - 'SHELL': '/bin/bash', 05:25:08 INFO - 'SHLVL': '1', 05:25:08 INFO - 'TERM': 'linux', 05:25:08 INFO - 'TMOUT': '86400', 05:25:08 INFO - 'USER': 'cltbld', 05:25:08 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', 05:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:08 INFO - '_': '/tools/buildbot/bin/python'} 05:25:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:09 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 05:25:09 INFO - Cleaning up... 05:25:09 INFO - Return code: 0 05:25:09 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:25: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')]} 05:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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', '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 0x1de3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20107d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209f640>, '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 0x20a0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20a09d0>, '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': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', '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 05:25: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 05:25: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 psutil>=0.7.1 05:25:09 INFO - Using env: {'DISPLAY': ':0', 05:25:09 INFO - 'HOME': '/home/cltbld', 05:25:09 INFO - 'LANG': 'en_US.UTF-8', 05:25:09 INFO - 'LANGUAGE': 'en_US:en', 05:25:09 INFO - 'LOGNAME': 'cltbld', 05:25:09 INFO - 'MAIL': '/var/mail/cltbld', 05:25:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:09 INFO - 'MOZ_NO_REMOTE': '1', 05:25:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:09 INFO - 'NO_EM_RESTART': '1', 05:25:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:25:09 INFO - 'PWD': '/builds/slave/test-pgo', 05:25:09 INFO - 'SHELL': '/bin/bash', 05:25:09 INFO - 'SHLVL': '1', 05:25:09 INFO - 'TERM': 'linux', 05:25:09 INFO - 'TMOUT': '86400', 05:25:09 INFO - 'USER': 'cltbld', 05:25:09 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', 05:25:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:09 INFO - '_': '/tools/buildbot/bin/python'} 05:25:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:09 INFO - Downloading/unpacking psutil>=0.7.1 05:25:09 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 05:25:09 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 05:25:09 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 05:25:09 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 05:25:09 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 05:25:09 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 05:25:10 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:25:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:25:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:25:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:11 INFO - Installing collected packages: psutil 05:25:11 INFO - Running setup.py install for psutil 05:25:11 INFO - building 'psutil._psutil_linux' extension 05:25:11 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 05:25:11 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 05:25:11 INFO - building 'psutil._psutil_posix' extension 05:25:11 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 05:25:11 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 05:25:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:11 INFO - Successfully installed psutil 05:25:11 INFO - Cleaning up... 05:25:11 INFO - Return code: 0 05:25:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:25:11 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')]} 05:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:11 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')]} 05:25:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:11 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 0x1de3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20107d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209f640>, '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 0x20a0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20a09d0>, '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': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', '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 05:25:11 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 05:25:11 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 05:25:11 INFO - Using env: {'DISPLAY': ':0', 05:25:11 INFO - 'HOME': '/home/cltbld', 05:25:11 INFO - 'LANG': 'en_US.UTF-8', 05:25:11 INFO - 'LANGUAGE': 'en_US:en', 05:25:11 INFO - 'LOGNAME': 'cltbld', 05:25:11 INFO - 'MAIL': '/var/mail/cltbld', 05:25:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:11 INFO - 'MOZ_NO_REMOTE': '1', 05:25:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:11 INFO - 'NO_EM_RESTART': '1', 05:25:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:25:11 INFO - 'PWD': '/builds/slave/test-pgo', 05:25:11 INFO - 'SHELL': '/bin/bash', 05:25:11 INFO - 'SHLVL': '1', 05:25:11 INFO - 'TERM': 'linux', 05:25:11 INFO - 'TMOUT': '86400', 05:25:11 INFO - 'USER': 'cltbld', 05:25:11 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', 05:25:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:11 INFO - '_': '/tools/buildbot/bin/python'} 05:25:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:25:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:25:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:25:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:25:13 INFO - Installing collected packages: mozsystemmonitor 05:25:13 INFO - Running setup.py install for mozsystemmonitor 05:25:13 INFO - Successfully installed mozsystemmonitor 05:25:13 INFO - Cleaning up... 05:25:13 INFO - Return code: 0 05:25:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:25: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')]} 05:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:13 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 0x1de3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20107d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209f640>, '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 0x20a0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20a09d0>, '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': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', '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 05:25:13 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 05:25:13 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 05:25:13 INFO - Using env: {'DISPLAY': ':0', 05:25:13 INFO - 'HOME': '/home/cltbld', 05:25:13 INFO - 'LANG': 'en_US.UTF-8', 05:25:13 INFO - 'LANGUAGE': 'en_US:en', 05:25:13 INFO - 'LOGNAME': 'cltbld', 05:25:13 INFO - 'MAIL': '/var/mail/cltbld', 05:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:13 INFO - 'MOZ_NO_REMOTE': '1', 05:25:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:13 INFO - 'NO_EM_RESTART': '1', 05:25:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:25:13 INFO - 'PWD': '/builds/slave/test-pgo', 05:25:13 INFO - 'SHELL': '/bin/bash', 05:25:13 INFO - 'SHLVL': '1', 05:25:13 INFO - 'TERM': 'linux', 05:25:13 INFO - 'TMOUT': '86400', 05:25:13 INFO - 'USER': 'cltbld', 05:25:13 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', 05:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:13 INFO - '_': '/tools/buildbot/bin/python'} 05:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:13 INFO - Downloading/unpacking blobuploader==1.2.4 05:25:13 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 05:25:13 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 05:25:13 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 05:25:13 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 05:25:13 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 05:25:13 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 05:25:15 INFO - Downloading blobuploader-1.2.4.tar.gz 05:25:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:25:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:25:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:25:15 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 05:25:15 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 05:25:15 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 05:25:15 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 05:25:15 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 05:25:15 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 05:25:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:25:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:25:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:25:16 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 05:25:16 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 05:25:16 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 05:25:16 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 05:25:16 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 05:25:16 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 05:25:16 INFO - Downloading docopt-0.6.1.tar.gz 05:25:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:25:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:25:16 INFO - Installing collected packages: blobuploader, requests, docopt 05:25:16 INFO - Running setup.py install for blobuploader 05:25:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:25:16 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:25:16 INFO - Running setup.py install for requests 05:25:16 INFO - Running setup.py install for docopt 05:25:16 INFO - Successfully installed blobuploader requests docopt 05:25:16 INFO - Cleaning up... 05:25:16 INFO - Return code: 0 05:25:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:25: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')]} 05:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:16 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 0x1de3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20107d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209f640>, '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 0x20a0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20a09d0>, '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': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', '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 05:25:16 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 05:25:16 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 05:25:16 INFO - Using env: {'DISPLAY': ':0', 05:25:16 INFO - 'HOME': '/home/cltbld', 05:25:16 INFO - 'LANG': 'en_US.UTF-8', 05:25:16 INFO - 'LANGUAGE': 'en_US:en', 05:25:16 INFO - 'LOGNAME': 'cltbld', 05:25:16 INFO - 'MAIL': '/var/mail/cltbld', 05:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:16 INFO - 'MOZ_NO_REMOTE': '1', 05:25:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:16 INFO - 'NO_EM_RESTART': '1', 05:25:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:25:16 INFO - 'PWD': '/builds/slave/test-pgo', 05:25:16 INFO - 'SHELL': '/bin/bash', 05:25:16 INFO - 'SHLVL': '1', 05:25:16 INFO - 'TERM': 'linux', 05:25:16 INFO - 'TMOUT': '86400', 05:25:16 INFO - 'USER': 'cltbld', 05:25:16 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', 05:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:16 INFO - '_': '/tools/buildbot/bin/python'} 05:25:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:25:17 INFO - Running setup.py (path:/tmp/pip-OkTe4y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:25:17 INFO - Running setup.py (path:/tmp/pip-lm8H1S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:25:17 INFO - Running setup.py (path:/tmp/pip-IXOPGJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:25:17 INFO - Running setup.py (path:/tmp/pip-AuSaNc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:25:17 INFO - Running setup.py (path:/tmp/pip-UkO8jj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:25:17 INFO - Running setup.py (path:/tmp/pip-2I0gb2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:25:17 INFO - Running setup.py (path:/tmp/pip-95KhIU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:25:17 INFO - Running setup.py (path:/tmp/pip-z_qPET-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:25:17 INFO - Running setup.py (path:/tmp/pip-Qmtg6p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:25:17 INFO - Running setup.py (path:/tmp/pip-5TO0tb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:25:17 INFO - Running setup.py (path:/tmp/pip-IKULvB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:25:17 INFO - Running setup.py (path:/tmp/pip-y7Ytgd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:25:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:25:17 INFO - Running setup.py (path:/tmp/pip-aEO0CG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:25:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:25:18 INFO - Running setup.py (path:/tmp/pip-VXf57Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:25:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:25:18 INFO - Running setup.py (path:/tmp/pip-eljyyO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:25:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:25:18 INFO - Running setup.py (path:/tmp/pip-Ve7rNZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:25:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:25:18 INFO - Running setup.py (path:/tmp/pip-nhwWlh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:25:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:25:18 INFO - Running setup.py install for manifestparser 05:25:18 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:25:18 INFO - Running setup.py install for mozcrash 05:25:18 INFO - Running setup.py install for mozdebug 05:25:18 INFO - Running setup.py install for mozdevice 05:25:18 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:25:18 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:25:18 INFO - Running setup.py install for mozfile 05:25:18 INFO - Running setup.py install for mozhttpd 05:25:18 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:25:18 INFO - Running setup.py install for mozinfo 05:25:19 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Running setup.py install for mozInstall 05:25:19 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Running setup.py install for mozleak 05:25:19 INFO - Running setup.py install for mozlog 05:25:19 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Running setup.py install for moznetwork 05:25:19 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Running setup.py install for mozprocess 05:25:19 INFO - Running setup.py install for mozprofile 05:25:19 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Running setup.py install for mozrunner 05:25:19 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:25:19 INFO - Running setup.py install for mozscreenshot 05:25:19 INFO - Running setup.py install for moztest 05:25:19 INFO - Running setup.py install for mozversion 05:25:20 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:25:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:25:20 INFO - Cleaning up... 05:25:20 INFO - Return code: 0 05:25:20 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:25: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')]} 05:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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', '-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 0x1de3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20107d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209f640>, '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 0x20a0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20a09d0>, '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': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', '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 05:25:20 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 05:25:20 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 05:25:20 INFO - Using env: {'DISPLAY': ':0', 05:25:20 INFO - 'HOME': '/home/cltbld', 05:25:20 INFO - 'LANG': 'en_US.UTF-8', 05:25:20 INFO - 'LANGUAGE': 'en_US:en', 05:25:20 INFO - 'LOGNAME': 'cltbld', 05:25:20 INFO - 'MAIL': '/var/mail/cltbld', 05:25:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:20 INFO - 'MOZ_NO_REMOTE': '1', 05:25:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:20 INFO - 'NO_EM_RESTART': '1', 05:25:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:25:20 INFO - 'PWD': '/builds/slave/test-pgo', 05:25:20 INFO - 'SHELL': '/bin/bash', 05:25:20 INFO - 'SHLVL': '1', 05:25:20 INFO - 'TERM': 'linux', 05:25:20 INFO - 'TMOUT': '86400', 05:25:20 INFO - 'USER': 'cltbld', 05:25:20 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', 05:25:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:20 INFO - '_': '/tools/buildbot/bin/python'} 05:25:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:25:20 INFO - Running setup.py (path:/tmp/pip-20e39e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:25:20 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)) 05:25:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:25:20 INFO - Running setup.py (path:/tmp/pip-xPsXuN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:25:20 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)) 05:25:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:25:20 INFO - Running setup.py (path:/tmp/pip-EURm3T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:25:20 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)) 05:25:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:25:20 INFO - Running setup.py (path:/tmp/pip-pH2DCO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:25:20 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)) 05:25:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:25:20 INFO - Running setup.py (path:/tmp/pip-BHQtgf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:25:20 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)) 05:25:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:25:20 INFO - Running setup.py (path:/tmp/pip-Ab8MsG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:25:20 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)) 05:25:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:25:20 INFO - Running setup.py (path:/tmp/pip-resREr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:25:20 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)) 05:25:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:25:20 INFO - Running setup.py (path:/tmp/pip-tFJzLD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:25:20 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)) 05:25:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:25:20 INFO - Running setup.py (path:/tmp/pip-gHtS8P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:25:20 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)) 05:25:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:25:20 INFO - Running setup.py (path:/tmp/pip-wPuIsW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:25:21 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)) 05:25:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:25:21 INFO - Running setup.py (path:/tmp/pip-qoralP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:25:21 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)) 05:25:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:25:21 INFO - Running setup.py (path:/tmp/pip-GDEbmA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:25:21 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)) 05:25:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:25:21 INFO - Running setup.py (path:/tmp/pip-A5bKpR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:25:21 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)) 05:25:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:25:21 INFO - Running setup.py (path:/tmp/pip-g6bs8B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:25:21 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)) 05:25:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:25:21 INFO - Running setup.py (path:/tmp/pip-uT7Dvm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:25:21 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)) 05:25:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:25:21 INFO - Running setup.py (path:/tmp/pip-tDmZvh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:25:21 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)) 05:25:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:25:21 INFO - Running setup.py (path:/tmp/pip-xYVOQg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:25:21 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)) 05:25:21 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)) 05:25:21 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)) 05:25:21 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)) 05:25:21 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)) 05:25:21 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)) 05:25:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:25:21 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 05:25:21 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 05:25:21 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 05:25:21 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 05:25:21 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 05:25:21 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 05:25:23 INFO - Downloading blessings-1.5.1.tar.gz 05:25:23 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 05:25:23 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:25:23 INFO - Installing collected packages: blessings 05:25:23 INFO - Running setup.py install for blessings 05:25:23 INFO - Successfully installed blessings 05:25:23 INFO - Cleaning up... 05:25:23 INFO - Return code: 0 05:25:23 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:25:23 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:25:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:25:23 INFO - Reading from file tmpfile_stdout 05:25:23 INFO - Current package versions: 05:25:23 INFO - argparse == 1.2.1 05:25:23 INFO - blessings == 1.5.1 05:25:23 INFO - blobuploader == 1.2.4 05:25:23 INFO - docopt == 0.6.1 05:25:23 INFO - manifestparser == 1.1 05:25:23 INFO - mozInstall == 1.12 05:25:23 INFO - mozcrash == 0.16 05:25:23 INFO - mozdebug == 0.1 05:25:23 INFO - mozdevice == 0.47 05:25:23 INFO - mozfile == 1.2 05:25:23 INFO - mozhttpd == 0.7 05:25:23 INFO - mozinfo == 0.9 05:25:23 INFO - mozleak == 0.1 05:25:23 INFO - mozlog == 3.1 05:25:23 INFO - moznetwork == 0.27 05:25:23 INFO - mozprocess == 0.22 05:25:23 INFO - mozprofile == 0.27 05:25:23 INFO - mozrunner == 6.11 05:25:23 INFO - mozscreenshot == 0.1 05:25:23 INFO - mozsystemmonitor == 0.0 05:25:23 INFO - moztest == 0.7 05:25:23 INFO - mozversion == 1.4 05:25:23 INFO - psutil == 3.1.1 05:25:23 INFO - requests == 1.2.3 05:25:23 INFO - wsgiref == 0.1.2 05:25:23 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:23 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 0x1de3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20107d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209f640>, '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 0x20a0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20a09d0>, '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': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', '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 05:25:23 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 05:25:23 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 05:25:23 INFO - Using env: {'DISPLAY': ':0', 05:25:23 INFO - 'HOME': '/home/cltbld', 05:25:23 INFO - 'LANG': 'en_US.UTF-8', 05:25:23 INFO - 'LANGUAGE': 'en_US:en', 05:25:23 INFO - 'LOGNAME': 'cltbld', 05:25:23 INFO - 'MAIL': '/var/mail/cltbld', 05:25:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:23 INFO - 'MOZ_NO_REMOTE': '1', 05:25:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:23 INFO - 'NO_EM_RESTART': '1', 05:25:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:25:23 INFO - 'PWD': '/builds/slave/test-pgo', 05:25:23 INFO - 'SHELL': '/bin/bash', 05:25:23 INFO - 'SHLVL': '1', 05:25:23 INFO - 'TERM': 'linux', 05:25:23 INFO - 'TMOUT': '86400', 05:25:23 INFO - 'USER': 'cltbld', 05:25:23 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', 05:25:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:23 INFO - '_': '/tools/buildbot/bin/python'} 05:25:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:23 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)) 05:25:23 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)) 05:25:23 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)) 05:25:23 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)) 05:25:23 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)) 05:25:23 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)) 05:25:23 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)) 05:25:23 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)) 05:25:23 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)) 05:25:23 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)) 05:25:23 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)) 05:25:23 INFO - Cleaning up... 05:25:23 INFO - Return code: 0 05:25:23 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:23 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 0x1de3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20107d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209f640>, '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 0x20a0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20a09d0>, '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': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', '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 05:25:23 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 05:25:23 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 05:25:23 INFO - Using env: {'DISPLAY': ':0', 05:25:23 INFO - 'HOME': '/home/cltbld', 05:25:23 INFO - 'LANG': 'en_US.UTF-8', 05:25:23 INFO - 'LANGUAGE': 'en_US:en', 05:25:23 INFO - 'LOGNAME': 'cltbld', 05:25:23 INFO - 'MAIL': '/var/mail/cltbld', 05:25:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:23 INFO - 'MOZ_NO_REMOTE': '1', 05:25:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:23 INFO - 'NO_EM_RESTART': '1', 05:25:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:25:23 INFO - 'PWD': '/builds/slave/test-pgo', 05:25:23 INFO - 'SHELL': '/bin/bash', 05:25:23 INFO - 'SHLVL': '1', 05:25:23 INFO - 'TERM': 'linux', 05:25:23 INFO - 'TMOUT': '86400', 05:25:23 INFO - 'USER': 'cltbld', 05:25:23 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', 05:25:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:23 INFO - '_': '/tools/buildbot/bin/python'} 05:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:24 INFO - Downloading/unpacking jsonschema 05:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:25:25 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 05:25:25 INFO - Downloading/unpacking functools32 (from jsonschema) 05:25:25 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 05:25:25 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 05:25:25 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 05:25:25 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 05:25:25 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 05:25:25 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 05:25:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:25:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:25:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:25:26 INFO - warning: no files found matching '*.txt' 05:25:26 INFO - no previously-included directories found matching 'build' 05:25:26 INFO - no previously-included directories found matching 'dist' 05:25:26 INFO - no previously-included directories found matching '.git*' 05:25:26 INFO - Installing collected packages: jsonschema, functools32 05:25:26 INFO - Running setup.py install for functools32 05:25:26 INFO - warning: no files found matching '*.txt' 05:25:26 INFO - no previously-included directories found matching 'build' 05:25:26 INFO - no previously-included directories found matching 'dist' 05:25:26 INFO - no previously-included directories found matching '.git*' 05:25:26 INFO - Successfully installed jsonschema functools32 05:25:26 INFO - Cleaning up... 05:25:26 INFO - Return code: 0 05:25:26 INFO - Running post-action listener: _resource_record_post_action 05:25:26 INFO - Running post-action listener: _start_resource_monitoring 05:25:26 INFO - Starting resource monitoring. 05:25:26 INFO - ##### 05:25:26 INFO - ##### Running install step. 05:25:26 INFO - ##### 05:25:26 INFO - Running pre-action listener: _resource_record_pre_action 05:25:26 INFO - Running main action method: install 05:25:26 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:25:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:25:26 INFO - Reading from file tmpfile_stdout 05:25:26 INFO - Detecting whether we're running mozinstall >=1.0... 05:25:26 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:25:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:25:26 INFO - Reading from file tmpfile_stdout 05:25:26 INFO - Output received: 05:25:26 INFO - Usage: mozinstall [options] installer 05:25:26 INFO - Options: 05:25:26 INFO - -h, --help show this help message and exit 05:25:26 INFO - -d DEST, --destination=DEST 05:25:26 INFO - Directory to install application into. [default: 05:25:26 INFO - "/builds/slave/test-pgo"] 05:25:26 INFO - --app=APP Application being installed. [default: firefox] 05:25:26 INFO - mkdir: /builds/slave/test-pgo/build/application 05:25:26 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'] 05:25:26 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 05:25:41 INFO - Reading from file tmpfile_stdout 05:25:41 INFO - Output received: 05:25:41 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:25:41 INFO - Running post-action listener: _resource_record_post_action 05:25:41 INFO - ##### 05:25:41 INFO - ##### Running run-tests step. 05:25:41 INFO - ##### 05:25:41 INFO - Running pre-action listener: _resource_record_pre_action 05:25:41 INFO - Running main action method: run_tests 05:25:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:25:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:25:41 INFO - Python 2.7.3 05:25:41 INFO - Return code: 0 05:25:41 INFO - grabbing minidump binary from tooltool 05:25:41 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')]} 05:25:41 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 0x209f640>, '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 0x20a0540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20a09d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:25:41 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 05:25:41 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 05:25:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:25:41 INFO - Return code: 0 05:25:41 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:25:41 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:25:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:25:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:25:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:25:41 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/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:25:41 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/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost 05:25:41 INFO - Using env: {'DISPLAY': ':0', 05:25:41 INFO - 'HOME': '/home/cltbld', 05:25:41 INFO - 'LANG': 'en_US.UTF-8', 05:25:41 INFO - 'LANGUAGE': 'en_US:en', 05:25:41 INFO - 'LOGNAME': 'cltbld', 05:25:41 INFO - 'MAIL': '/var/mail/cltbld', 05:25:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:25:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:25:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:41 INFO - 'MOZ_NO_REMOTE': '1', 05:25:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:25:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:41 INFO - 'NO_EM_RESTART': '1', 05:25:41 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:25:41 INFO - 'PWD': '/builds/slave/test-pgo', 05:25:41 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:25:41 INFO - 'SHELL': '/bin/bash', 05:25:41 INFO - 'SHLVL': '1', 05:25:41 INFO - 'TERM': 'linux', 05:25:41 INFO - 'TMOUT': '86400', 05:25:41 INFO - 'USER': 'cltbld', 05:25:41 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021', 05:25:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:41 INFO - '_': '/tools/buildbot/bin/python'} 05:25:41 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/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost'] with output_timeout 3600 05:25:41 INFO - mozversion INFO | application_buildid: 20151126035113 05:25:41 INFO - mozversion INFO | application_changeset: 5fe77514a40b016456ea9935fd9794f32bde3230 05:25:41 INFO - mozversion INFO | application_display_name: Nightly 05:25:41 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:25:41 INFO - mozversion INFO | application_name: Firefox 05:25:41 INFO - mozversion INFO | application_remotingname: firefox 05:25:41 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 05:25:41 INFO - mozversion INFO | application_vendor: Mozilla 05:25:41 INFO - mozversion INFO | application_version: 45.0a1 05:25:41 INFO - mozversion INFO | platform_buildid: 20151126035113 05:25:41 INFO - mozversion INFO | platform_changeset: 5fe77514a40b016456ea9935fd9794f32bde3230 05:25:41 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 05:25:41 INFO - mozversion INFO | platform_version: 45.0a1 05:25:41 INFO - 2015-11-26 05:25:41,433 DEBUG : using testdate: 1448544341 05:25:41 INFO - 2015-11-26 05:25:41,433 DEBUG : actual date: 1448544341 05:25:41 INFO - 2015-11-26 05:25:41,441 INFO : starting webserver on 'localhost:59911' 05:25:41 INFO - 2015-11-26 05:25:41,442 INFO : Starting test suite talos-linux64-ix-047.e 05:25:41 INFO - 2015-11-26 05:25:41,442 INFO : Starting test dromaeo_css 05:25:41 INFO - 2015-11-26 05:25:41,443 DEBUG : operating with platform_type : linux_ 05:25:41 INFO - 2015-11-26 05:25:41,443 INFO : Initialising browser for dromaeo_css test... 05:25:41 INFO - 2015-11-26 05:25:41,460 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZWxpxH/profile http://localhost:59911/getInfo.html 05:25:45 INFO - 2015-11-26 05:25:45,797 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:25:45 INFO - 2015-11-26 05:25:45,797 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:25:45 INFO - 2015-11-26 05:25:45,797 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:25:45 INFO - 2015-11-26 05:25:45,797 DEBUG : BROWSER_OUTPUT: __metrics 05:25:46 INFO - 2015-11-26 05:25:46,993 INFO : Browser initialized. 05:25:46 INFO - 2015-11-26 05:25:46,993 INFO : Running cycle 1/1 for dromaeo_css test... 05:25:46 INFO - 2015-11-26 05:25:46,994 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZWxpxH/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:25:58 INFO - 2015-11-26 05:25:58,730 DEBUG : BROWSER_OUTPUT: RSS: Main: 138817536 05:25:58 INFO - 2015-11-26 05:25:58,730 DEBUG : BROWSER_OUTPUT: 05:26:48 INFO - 2015-11-26 05:26:48,341 DEBUG : BROWSER_OUTPUT: 1448544408333 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] 05:28:57 INFO - 2015-11-26 05:28:57,550 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59911/tests/dromaeo/cssquery-ext.html) 05:28:58 INFO - 2015-11-26 05:28:58,639 DEBUG : BROWSER_OUTPUT: RSS: Main: 147836928 05:28:58 INFO - 2015-11-26 05:28:58,640 DEBUG : BROWSER_OUTPUT: 05:32:00 INFO - 2015-11-26 05:32:00,670 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/dromaeo/cssquery-ext.html (next: http://localhost:59911/tests/dromaeo/cssquery-jquery.html) 05:32:01 INFO - 2015-11-26 05:32:01,251 DEBUG : BROWSER_OUTPUT: RSS: Main: 142647296 05:32:01 INFO - 2015-11-26 05:32:01,251 DEBUG : BROWSER_OUTPUT: 05:35:02 INFO - 2015-11-26 05:35:02,927 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59911/tests/dromaeo/cssquery-mootools.html) 05:35:03 INFO - 2015-11-26 05:35:03,513 DEBUG : BROWSER_OUTPUT: RSS: Main: 143773696 05:35:03 INFO - 2015-11-26 05:35:03,513 DEBUG : BROWSER_OUTPUT: 05:38:05 INFO - 2015-11-26 05:38:05,135 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59911/tests/dromaeo/cssquery-prototype.html) 05:38:05 INFO - 2015-11-26 05:38:05,721 DEBUG : BROWSER_OUTPUT: RSS: Main: 142262272 05:38:05 INFO - 2015-11-26 05:38:05,721 DEBUG : BROWSER_OUTPUT: 05:41:07 INFO - 2015-11-26 05:41:07,987 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59911/tests/dromaeo/cssquery-yui.html) 05:41:08 INFO - 2015-11-26 05:41:08,581 DEBUG : BROWSER_OUTPUT: RSS: Main: 143142912 05:41:08 INFO - 2015-11-26 05:41:08,581 DEBUG : BROWSER_OUTPUT: 05:44:10 INFO - 2015-11-26 05:44:10,843 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/dromaeo/cssquery-yui.html (next: http://localhost:59911/tests/dromaeo/cssquery-dojo.html) 05:44:11 INFO - 2015-11-26 05:44:11,092 DEBUG : BROWSER_OUTPUT: RSS: Main: 145633280 05:44:11 INFO - 2015-11-26 05:44:11,093 DEBUG : BROWSER_OUTPUT: 05:44:11 INFO - 2015-11-26 05:44:11,093 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:44:11 INFO - 2015-11-26 05:44:11,093 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:44:11 INFO - 2015-11-26 05:44:11,093 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:44:11 INFO - 2015-11-26 05:44:11,094 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:44:11 INFO - 2015-11-26 05:44:11,094 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3176.9364612707745;3235.1588586967396;3266.3140740444537;3273.7217336526396;3283.8193899335542;5261.947380526193;5481.314835645544;5483.698396588186;5495.422980587041;5504.256925315081;5415.458454154593;5420.457954204578;5431.266778984836;5542.7782888684505;5546.805861794828;4968.0560693468215;5044;5117.130087885069;5124.5131712487255;5129.948700513003;5048.394192696863;5069.873253168664;5189.014087323397;5200.791968321281;5201.895962080756;4941.555260026615;5074.923876141824;5083.415407228179;5179.14544100223;5194.662346947436;4938.604911607063;5018.447970723218;5020.974895125538;5035.0685123252315;5045.117104506697;5020.497950204986;5072.188449848007;5072.239164125411;5081.060003899291;5094.490550944913;5189.065968125736;5218.6868787872845;5294.126469132589;5314.92027619586;5370.650907691027;5967.075103358986;5969.850753731148;5975.790847320323;5976.820695379146;5990.071538911476;5993.370696076893;6007.7596896124105;6010.519158467312;6012.398760123952;6016.69916504173;5332.200169974535;5446.428125046853;5453.127499600085;5555.138953462219;5593.272874526327;6073.119397687399;6077.878442431215;6079.604825686316;6080.300765412034;6087.452129308295;5836.270466191726;5871.295444546682;5874.882502349929;5893.705314734246;5909.5863289569315;5760.164776107441;5821.592488525763;5851.765929362863;5892.175095486636;5953.732082056231;5901.58688891782;5908.763649454059;5935.050391937356;5948.524118070631;5948.821535353946;5804.912926306109;5883.235179426732;5922.526197904157;5936.22829032223;5950.375210602824;4516.051629157913;4556.589906908328;4559.224931761609;4562.041971186021;4600.907981840344;4012.598740125964;4040.373742069984;4067.7559346439307;4099.631033207026;4121.423000779893;5463.098588732814;5587.3015873015875;5682.857928551819;5718.7426565804635;5724.656520608777;5895.322037965689;5915.822525324247;5948.315943666447;5972.283326000907;6015.0977353396465;5622.493975542139;5682.857928551736;5691.231683722644;5705.229793977843;5707.4577915098;5031.597472202215;5137.768800403991;5208.166693329049;5215.843524694265;5228.607854410934;6048.546359023091;6065.2418447694035;6123.069106197319;6136.23297087864;6136.2636483622255;5948.910766338595;5985.790497332572;5988.5508586855285;6006.489448396936;6033.758649653964;6106.1146133811235;6110;6124.38756124384;6161.72272247741;6163.291221509584;5781.82654520365;5842.561807864342;5898.882022359615;5898.94101058984;5906.114082887601;5849.61977471463;5935.376785437639;5947.9702601486715;5950.642961422329;5976.671283079472;5816.854578635568;5859;5864.413558644102;5871.911921321269;5882.49998750111;5749.568782341257;5836.445537673914;5845.590808643354;5848.093545500455;5850.5904586678525;5571.415001424792;5683.658980461186;5717.22817419643;5735.254416925773;5804.332501762351;5888.17565540816;5925.762969481345;5929.347771745191;5941;5973.253343332083;5763.4812866842185;5784.913226301524;5786.276715410574;5806.854828629318;5810.6513609183585 05:44:11 INFO - 2015-11-26 05:44:11,094 DEBUG : BROWSER_OUTPUT: |1;ext.html;569.4618585436759;576.7837061102086;585.7276366489582;586.5249148189966;587.1838144978481;38617.80691096544;39599.80200098999;39601.40597891027;39752.602473975254;39881.20237595246;43248;43392.566074339244;43668.344974825406;43742.56257437425;43787.56212437875;8730.781730456725;8793.692220772275;8852.601632926533;8871.512066836322;8872.645094196225;8655.870161947576;8687.913120868805;8747.300215982707;8754.3871928965;8757.25563327118;8718.34612404069;8783.824323513525;8801.515916624583;8803.77990550235;8810;8734.86897696535;8743.256823170039;8807.987081485648;8823.558822058903;8824;8612.39713220075;8767.079456657088;8770;8772.824543509094;8776.473411595261;39941.20117597632;40553.594464055575;40597.79701101505;40613.79693101515;40632.18735625271;3251.3775337669886;3286.9506957395656;3373.4939759036342;3403.5915690117095;3540.628234035415;29739.2922723224;30168.396632067455;30478.54282185769;30580;31279.687203127905;3518.9120271993147;3547.3260080584605;3640.5813331466957;3690.317291301118;3693.4829123922673;254953;255942;257303;257374;257729.99999999814;41980.58019419828;42276;57561.712191438775;57782.13326800102;57874.71062644744;41364.58635413668;41403.792981035505;41500.37749433761;41530.79234603808;41787.58212417837;14595.124292542481;14785.63035924089;14787.92606036963;14801.48194813198;14806.555803325917;11219.887801122048;11334.489947952361;11344.602938897264;11352.489137988645;11356.659300221007;13922.930385348009;14404.351804168837;14459.060161089496;14490.782638260434;14507.419703211965;5741.138829175574;5741.684207368551;5809.883802324014;5849.532037436995;5860.589758716934;935.8876934767871;951.8143961927497;969.9806003880024;1037.941299874124;1065.3128731967838;28506.42987140287;28653.28366790847;28769.71230287712;29083.56374654382;29295.560566591517;1032.0917592518686;1065.4299949526967;1075.333293358123;1080.7352198711367;1086.0659832544006;14847.70304593902;15320.616984575252;16664.75002874958;17119.914400428166;17140.828591713922;367943;372336;372838;375224;377571;55692.72153639287;55958;56107;56452;56463.43536564582;1361.4758318047623;1364.0656150536913;1373.9656508587366;1395.2628976923795;1406.9718605628034;29141.417171656023;30555.388892221585;32212.83893580517;33054.834725826215;33207.833960830045;50199.24701129559;51222.231666525775;51284.23073653824;51340.486595135066;51352.74323628358;271235;272416;273540;274259;275115;43010.13979720451;46327.76836115798;46469.282457404006;47301;47321;45268;45524.77237613791;45553.54446455493;46488.53511464977;46665.3000205004;37520.24959500849;38600.227995439374;38823.61176388313;38874.22251555009;39496.210075798896;37380.439293410076;38238.42642360422;38647;38953;39172.41241381439;38044.61955380411;38214.23571528609;40919.795401022806;41332.58667413287;41519.1696166081;31715.524267135548;34323.828380857936;34538.30923381468;34640.30719385548;35114.648853511135;25413.87293063523;26140.73859261383;26244.737552624993;26329.8947462984;26658 05:44:11 INFO - 2015-11-26 05:44:11,096 DEBUG : BROWSER_OUTPUT: |2;jquery.html;333.5230620213095;334.9363620912026;336.7608997611697;337.5611704783781;337.809137837122;16493.587660308498;16588.170591470425;17489.562760930967;17790.64418711625;18379.816201837977;10897.727556811084;12158.331291778948;15935.52193434196;16031.919840400811;16050.59873503163;4960.900781984358;5540.667559946396;5541.196526503654;5542.91685624716;5549.084401073818;5468.070428027245;5470.8085217017415;5500.119980803072;5517.972410137963;5526.668399895999;4996.750162491879;5031.849044528652;5043.798248070073;5143.794248230066;5291.391489978663;5197.5322220999915;5215.6349055566125;5224.7387630618505;5230.110881150214;5231.529162375386;4996.150654388726;5131.974340128276;5161.974190129063;5181.740912954375;5191.5846732261325;12409.813852792215;14505.347259373353;16674.58313542171;19253.614927701507;19307.613847723107;1988.09541658545;2011.6781314989532;2042.234162189179;2046.2531176120608;2196.0447205465675;8887.644494220223;9169.679061232891;9215.585298661576;9238.122378374044;9350.859737103881;1798.8740788144835;1815.0652414006797;1816.5731053202487;1837.3018253063883;1848.3900312896712;60378.698106509626;62026;62796.372036279965;63440;66094.66952665253;9599.520023998912;9704.660336888175;9722.368046076983;9779.706608801747;9885.406875587487;12658.936705316415;12718.618441446772;12737.490500380067;12794.744105117845;12831.909287710649;7593.392528597699;7601.961990190013;7725.266099720518;7833.804154896173;7911.169327220674;7406.370458511087;7422.443316751157;7432.9256707433315;7789.571573563395;7818.022747156606;4257.1698518788535;4288.120935208275;4301.354796780445;4318.395424640553;4420.535843736361;1788.6512130134759;1801.4775715042754;1801.7207332863165;1813.4650278167971;1819.1631849349446;799.2207597592301;950.273041123541;963.2775418436173;1033.679559336608;1040.0275742181084;15712.68574628501;15824.208789560476;15982.680346393006;16419.096949667644;16431.424900128677;875.2210532625841;876.8903887014123;890.955452227386;906.7189171356769;1017.7710015246511;9054.411463254868;9457.959624441306;9467.479288639053;9470;9538.284628652878;493181;510267;513809;516313.11338536645;520809;74953;76329.61835190789;80175.19824801678;81746.59126704447;83547.16452835394;1109.6504601050642;1111.344306858957;1114.3481063577897;1124.241137232365;1154.4054811771944;14508;15504;15677.294521746777;15748.60628484297;16035.118068506345;19511.414657560297;32623.836880815445;33133.83433082916;33232.33535329328;33497.16257093592;42074.1585168301;45139;45308;46259.537404625524;46542.767286163355;9519.857202142111;9658.806823863342;9675.854862176931;9733.61065557398;9736.75658108553;11350.148738844455;16850.1574921251;16963.83036169672;17507.21217545188;17520.123993800004;14040.929795350958;14263.572092837232;14487.85512144865;14707.852921470649;14725.337359818619;13873.930630346784;13963.441462341392;14184.787228191792;14500.564983050524;14626.341814618156;14023.158610483404;14123.2938353084;14545.490907818175;14633.560993170222;14908.18005009735;9315.72052838416;9322.067793220624;9399.295052870926;9508.477033763209;9547.856782148412;10304.948475257575;10312.381257124596;10878;15223;15388.538343849703 05:44:11 INFO - 2015-11-26 05:44:11,097 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1499.3627708224003;1734.089602958447;1775.8934463932158;1790.4449620617609;1800.9909950450249;7780.494267872585;7924.722634707772;8000.239977202164;8040.517568945867;8176.059753128377;8120.187981201877;8134.023917129938;8181.7136400226;8187.345012399009;8200.876986845187;2252.999999999996;2279.658051292303;2282.9771702283015;2284.4060544258446;2285.6228722260807;2285.48576570272;2307.065638416436;2322.7096612923383;2322.779335963081;2325.03511042917;2257.0294773247497;2257.119723307911;2279.8290128240365;2298.0807676929194;2299.5171014087064;2264.3772962435296;2268.3081660093667;2284.8286378521593;2285.4743409015937;2289.095807156164;2325.7906788388955;2335.4628435459767;2342.8945697443655;2343.718753749544;2350.318407661776;7822.921770782333;7885.408594355446;7899.526028438255;7904.328132108778;7919.128895821455;4604.930926036112;4641.396618439581;4681.625469962395;4688.038952014829;4699.224627936421;2929.4434057529215;2941.7352438280445;2974.2713035306274;2997.610310659622;3023.3650933304034;931.9627214911463;953.0802775321848;956.0773853231595;967.5404183013083;977.5942983661772;10856.620018299322;10903.400312982783;10932;10955.068819150301;10962.835557466644;4129.8967525811495;4138.234426631083;4179.623833854967;4193.266178418765;4276.700630955803;8936.24041956441;8938.10618938114;8969.50667713282;8986.05646407131;8990.280777537911;4745.620350371962;4766.094442055998;4772.093302272557;4776.307435421844;4794.184988551954;4305.16049370376;4332.891677708082;4338.848140315074;4349.847755328612;4405.7797110144365;3478.7564870458823;3481.3385456763135;3508.7017603503987;3547.3260080584605;3563.5367402237544;1092.7759809239087;1092.9781404371868;1114.2868564118971;1114.7436089699415;1115.5091759625739;1077.9407132607625;1086.5273605981424;1087.7498175419698;1212.7028877924965;1300.3888172558882;7409.333160015624;7440.776776696708;7540.773776786705;7544.547327160388;7560.924390756132;502.3971234518592;503.30543849487475;509.93625796775405;510.1531457780067;511.7211119939642;5873.177755114288;5894.086416605433;5908.438698323653;5930.673812940244;5944.167816505693;9133.360664753403;9288.164065234161;9418.670346537838;9501.334906556613;9651.420914745138;9331.440113593206;9339.112784285482;9359.485228312507;9369.718908432758;9394.624215031323;7303.123625165014;7305.5981920994855;7413.962930185422;7436;7462.291082347168;7190.568565886064;7199.928000719926;7214.242504537158;7217.097862767154;7220.819479513054;8184.508929464251;8332.916670833214;8467.407281490237;8538.359623028173;8559.61481733336;8426.620802064044;8458;8495.74512764618;8597.570121493774;8635.611397487002;6695.665216739046;6756.628385438651;6899.206591242071;6973.895391569232;7002.2647622000995;7680.423968202295;7681.731139410093;7701.92298077012;7739.071311442438;7748.57382843949;7105.32499412555;7115.324044215791;7248.275172482709;7308.744193953186;7359.190489046094;6984.441244700513;7023.719051237893;7027.4729395294535;7087.114110736158;7088.964555177191;7161.4986950912935;7204.207537170802;7242.96378518104;7244.203137654748;7281.05346304977;6625.701843416961;6667.033280174251;6673.432758215606;6679.966600166968;6698.799036028927;6495.870082598416;6540.182477190351;6584.209894812461;6597.50518711089;6606.735730570724 05:44:11 INFO - 2015-11-26 05:44:11,098 DEBUG : BROWSER_OUTPUT: |4;prototype.html;328.96874796894315;332.42822345565617;333.77636983221237;342.78062040294213;343.83152255394856;6225.221847269091;6265.749370025199;6301.401366870152;6333.461655759254;6342.7462901484005;6160.1375807387;6203.534734894878;6208.968955155229;6218.284897236819;6277.246730392348;4769.427668679746;4782.234842425212;4790.329353890449;4803.63972702047;4821.397325334333;4814.951850481503;4818.108649899762;4820.204666230085;4833.3233347331225;4845.200541910598;4683.180443422404;4779.091972525226;4783.521647835206;4794.304825800275;4795.712257264558;4748.145333839908;4766.642501812361;4771.236602143657;4774.116788394123;4797.95202047982;4174.895627609304;4179.665626749853;4183.874483765492;4184.351425529047;4193.643540299078;5977.701114944235;5981.611195272337;5983.730732117066;5988.610740301867;6004.579679422399;5423.430539793304;5472.699001554913;5494.945050549483;5499.890002199973;5570.916436253459;6626.469882409396;6647.900281495733;6650.800475985729;6658.600483970978;6666.166729158856;4574.062317224961;4590.770461476946;4598.425196850394;4598.563136502061;4602;6725.66371681414;6857.211420686635;6892.862142757167;6898.172219333712;6932.792016239521;4740.905181896412;4747.240441529339;4748.715077095385;4761.166795810719;4768.237082066922;4799.736014519235;4806.447258565309;4812.374391329105;4818.590419814285;4825.372701548777;5552.444755524496;5564.527015203753;5566.860828479321;5567.638103523258;5581.246531718247;6545.8363540910905;6625.436837868835;6664.333566643394;6668.199816022108;6719.697613607399;1604.0936870668268;1650.2821272746394;1651.3972400073887;1652.1326303690419;1681.7813684220973;1060.1042119409087;1104.6851647280648;1106.4246591772232;1106.7509810292781;1113.059464752283;6411.326810684905;6443.96778016107;6444.065610486547;6444.9355506444335;6452.838489071918;6845.520813543004;6859.828504287433;6867.038614593961;6872.484563657746;6883.621400822903;6337.588056776295;6374.649394283359;6410.0705397717065;6422.614643121427;6431.163948686641;3736.6076561961145;3797.5442946846;3812.4090765931323;3815.3323168445413;3818.0836599215986;7263.128424589083;7264.455165862476;7265.200827908926;7265.309795569412;7275.927240727631;7018.33325834045;7162.4628152888745;7179.1026121734785;7184.281571842774;7188.784336469915;7163.498555101197;7228.819279518054;7230.987661727362;7249.456290778107;7270.200277969419;6927.93072069293;6933.098697169537;6936.79189624312;6938.479614029069;6952.374286314053;6768.153980752406;6892.724291028303;6917.100776898971;6932.410745086724;6944.687489063105;7195.280471952763;7237.457190710613;7239.927600724136;7240.52936559122;7241.818954526179;4684.718916864999;4722.787474563722;4727.62179025684;4728.527147285382;4732.929006064981;6838.179418469616;6949.409300209539;6964.199117101395;6981.02265682815;6981.162260528566;6755.2231493378895;6760.019797129487;6761.864762704817;6772.7290908365085;6775.728970841111;6747.122874026345;6753.662316884234;6766.289539598468;6777.525573210025;6795.320467953363;6489.448396886318;6630.270670226368;6632.038354438483;6637.834054148685;6657.633830139389;6790;6792.93207067923;6803.081583986099;6812.318768123347;6814.4548436124;6479.481641468766;6574.309697481695;6628.668566571749;6631.071649969104;6636 05:44:11 INFO - 2015-11-26 05:44:11,100 DEBUG : BROWSER_OUTPUT: |5;yui.html;698.231944860653;724.6738967464639;755.3013462547143;824.6989848705223;825.2490233887164;12861.5498457554;13282.203067815926;13284.601461956132;13380.598582042541;13425;12066.517339306418;12349.814752778715;12356.382180890965;12411.069169812255;12413.503459861595;814.7107776739276;822.411975437562;829.6888666749969;842.296682270305;846.3990566697652;845.4082142500274;847.8134810341746;848.6478111583685;848.6817443458702;850.863861782115;869.1178453869328;875.544716747291;877.627008521379;879.4371602174614;881.9823603527925;868.847951608466;870.24723614074;870.3037569944051;870.4777133719781;871.808202195516;642.6979319719771;642.7107801489302;645.399778206268;645.586824432359;645.7610684046886;12901.612951611467;13081.149725267827;13102.606921792267;13141.86858131416;13170.86829131706;11072.833907491395;11883.762324753456;11889.524419023315;11895.583654572136;11922.10584206188;9533;9704.417734935927;9745.31782775206;9933.205343572568;9945.651902183461;3466.4626982817704;3469.132716820795;3509.982450087733;3516.8241587920757;3518.595361533405;51782.48217517814;51867.740661296455;53068.73465632686;53149.202761958986;53197;8825.470471771714;8980.55097245135;8984.730458086267;9087.863682044775;9092.27261819053;18445.53886152857;18507.167177477048;18525.258989640533;18559.164837582342;18585.34951276699;7173.10336207974;7372.336489715952;7432.368248698921;7588.582627955516;7716.112647045662;4079.632833044981;4167.395727619478;4204.285271503852;4276.786160691953;4430.379746835446;3077.98461007698;3086.213015680987;3088.922776930595;3103.953440698391;3109.689031096872;541.5261646059698;557.9733290744967;558.891016251827;569.0440060697985;569.9658020518782;453.4898239480584;498.2028753993581;533.3226801961487;639.0984928424899;653.6437641485402;7538.359239464707;7549.6225188740345;7667.309942105126;7848.882266766003;7931;512.6155383462403;533.1602725707027;547.6221407228999;564.350996354196;633.5818359882455;3654.232611151635;3720.739548231652;3832.559255685576;3979.5025621797276;3997.8600748973645;58786.70606646939;60333.17334613221;60339;60382;60400.39599604036;53122.468775312525;53427.19859202115;53730;53878.191827122624;54122.72938635282;48479;48830;48954.26568601474;49558;49895;7973.784275522727;8208.261256486707;8234.382421318545;8265.669373225004;8278.612097526262;36011.819940900124;36427.27145457023;36495.81752091329;36822.44766328454;37088.81455592705;41482.58517414787;43130.353044704985;43860.342094869244;45231.54768452405;45880.31179532243;10044.146247569039;10153;10349.120324772479;10474.68575942723;10572.682819515427;11230.887691122984;11337.716557086138;11350;11475.483603237708;11926.403679816149;10380.169586433218;10511.369317840954;10571.894281057092;10592.841107383549;10616.575336986434;10232.846507302247;10244.282900196915;10363.11913487332;10591.311564748266;10606.098481629147;10201.591936322464;10559.736006599589;10681;10795.56817727282;10983.231173817756;9231.676891308773;9310.115539023782;9348.53257337144;9420.811583768149;9633.614655413985;8219.383546233938;8536.573171341532;8697.434666746642;9124.635014599342;9173.954130229306 05:44:11 INFO - 2015-11-26 05:44:11,100 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:44:11 INFO - 2015-11-26 05:44:11,100 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:44:11 INFO - 2015-11-26 05:44:11,100 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,299 05:44:11 INFO - 2015-11-26 05:44:11,100 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:44:11 INFO - 2015-11-26 05:44:11,100 DEBUG : BROWSER_OUTPUT: __startTimestamp1448545451090__endTimestamp 05:44:11 INFO - 2015-11-26 05:44:11,101 DEBUG : BROWSER_OUTPUT: 05:44:11 INFO - 2015-11-26 05:44:11,101 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:44:11 INFO - 2015-11-26 05:44:11,101 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:44:11 INFO - 2015-11-26 05:44:11,101 DEBUG : BROWSER_OUTPUT: 05:44:11 INFO - 2015-11-26 05:44:11,101 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5519.04 Median:5781.83 stddev:622.49 (10.8%) stddev-sans-first:596.67 05:44:11 INFO - 2015-11-26 05:44:11,102 DEBUG : BROWSER_OUTPUT: Values: 3176.9 3235.2 3266.3 3273.7 3283.8 5261.9 5481.3 5483.7 5495.4 5504.3 5415.5 5420.5 5431.3 5542.8 5546.8 4968.1 5044.0 5117.1 5124.5 5129.9 5048.4 5069.9 5189.0 5200.8 5201.9 4941.6 5074.9 5083.4 5179.1 5194.7 4938.6 5018.4 5021.0 5035.1 5045.1 5020.5 5072.2 5072.2 5081.1 5094.5 5189.1 5218.7 5294.1 5314.9 5370.7 5967.1 5969.9 5975.8 5976.8 5990.1 5993.4 6007.8 6010.5 6012.4 6016.7 5332.2 5446.4 5453.1 5555.1 5593.3 6073.1 6077.9 6079.6 6080.3 6087.5 5836.3 5871.3 5874.9 5893.7 5909.6 5760.2 5821.6 5851.8 5892.2 5953.7 5901.6 5908.8 5935.1 5948.5 5948.8 5804.9 5883.2 5922.5 5936.2 5950.4 4516.1 4556.6 4559.2 4562.0 4600.9 4012.6 4040.4 4067.8 4099.6 4121.4 5463.1 5587.3 5682.9 5718.7 5724.7 5895.3 5915.8 5948.3 5972.3 6015.1 5622.5 5682.9 5691.2 5705.2 5707.5 5031.6 5137.8 5208.2 5215.8 5228.6 6048.5 6065.2 6123.1 6136.2 6136.3 5948.9 5985.8 5988.6 6006.5 6033.8 6106.1 6110.0 6124.4 6161.7 6163.3 5781.8 5842.6 5898.9 5898.9 5906.1 5849.6 5935.4 5948.0 5950.6 5976.7 5816.9 5859.0 5864.4 5871.9 5882.5 5749.6 5836.4 5845.6 5848.1 5850.6 5571.4 5683.7 5717.2 5735.3 5804.3 5888.2 5925.8 5929.3 5941.0 5973.3 5763.5 5784.9 5786.3 5806.9 5810.7 05:44:11 INFO - 2015-11-26 05:44:11,102 DEBUG : BROWSER_OUTPUT: 05:44:11 INFO - 2015-11-26 05:44:11,103 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47348.06 Median:38848.92 stddev:80000.48 (205.9%) stddev-sans-first:80147.80 05:44:11 INFO - 2015-11-26 05:44:11,103 DEBUG : BROWSER_OUTPUT: Values: 569.5 576.8 585.7 586.5 587.2 38617.8 39599.8 39601.4 39752.6 39881.2 43248.0 43392.6 43668.3 43742.6 43787.6 8730.8 8793.7 8852.6 8871.5 8872.6 8655.9 8687.9 8747.3 8754.4 8757.3 8718.3 8783.8 8801.5 8803.8 8810.0 8734.9 8743.3 8808.0 8823.6 8824.0 8612.4 8767.1 8770.0 8772.8 8776.5 39941.2 40553.6 40597.8 40613.8 40632.2 3251.4 3287.0 3373.5 3403.6 3540.6 29739.3 30168.4 30478.5 30580.0 31279.7 3518.9 3547.3 3640.6 3690.3 3693.5 254953.0 255942.0 257303.0 257374.0 257730.0 41980.6 42276.0 57561.7 57782.1 57874.7 41364.6 41403.8 41500.4 41530.8 41787.6 14595.1 14785.6 14787.9 14801.5 14806.6 11219.9 11334.5 11344.6 11352.5 11356.7 13922.9 14404.4 14459.1 14490.8 14507.4 5741.1 5741.7 5809.9 5849.5 5860.6 935.9 951.8 970.0 1037.9 1065.3 28506.4 28653.3 28769.7 29083.6 29295.6 1032.1 1065.4 1075.3 1080.7 1086.1 14847.7 15320.6 16664.8 17119.9 17140.8 367943.0 372336.0 372838.0 375224.0 377571.0 55692.7 55958.0 56107.0 56452.0 56463.4 1361.5 1364.1 1374.0 1395.3 1407.0 29141.4 30555.4 32212.8 33054.8 33207.8 50199.2 51222.2 51284.2 51340.5 51352.7 271235.0 272416.0 273540.0 274259.0 275115.0 43010.1 46327.8 46469.3 47301.0 47321.0 45268.0 45524.8 45553.5 46488.5 46665.3 37520.2 38600.2 38823.6 38874.2 39496.2 37380.4 38238.4 38647.0 38953.0 39172.4 38044.6 38214.2 40919.8 41332.6 41519.2 31715.5 34323.8 34538.3 34640.3 35114.6 25413.9 26140.7 26244.7 26329.9 26658.0 05:44:11 INFO - 2015-11-26 05:44:11,103 DEBUG : BROWSER_OUTPUT: 05:44:11 INFO - 2015-11-26 05:44:11,103 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27637.17 Median:4294.74 stddev:83578.39 (1946.1%) stddev-sans-first:83787.71 05:44:11 INFO - 2015-11-26 05:44:11,104 DEBUG : BROWSER_OUTPUT: Values: 333.5 334.9 336.8 337.6 337.8 16493.6 16588.2 17489.6 17790.6 18379.8 10897.7 12158.3 15935.5 16031.9 16050.6 4960.9 5540.7 5541.2 5542.9 5549.1 5468.1 5470.8 5500.1 5518.0 5526.7 4996.8 5031.8 5043.8 5143.8 5291.4 5197.5 5215.6 5224.7 5230.1 5231.5 4996.2 5132.0 5162.0 5181.7 5191.6 12409.8 14505.3 16674.6 19253.6 19307.6 1988.1 2011.7 2042.2 2046.3 2196.0 8887.6 9169.7 9215.6 9238.1 9350.9 1798.9 1815.1 1816.6 1837.3 1848.4 60378.7 62026.0 62796.4 63440.0 66094.7 9599.5 9704.7 9722.4 9779.7 9885.4 12658.9 12718.6 12737.5 12794.7 12831.9 7593.4 7602.0 7725.3 7833.8 7911.2 7406.4 7422.4 7432.9 7789.6 7818.0 4257.2 4288.1 4301.4 4318.4 4420.5 1788.7 1801.5 1801.7 1813.5 1819.2 799.2 950.3 963.3 1033.7 1040.0 15712.7 15824.2 15982.7 16419.1 16431.4 875.2 876.9 891.0 906.7 1017.8 9054.4 9458.0 9467.5 9470.0 9538.3 493181.0 510267.0 513809.0 516313.1 520809.0 74953.0 76329.6 80175.2 81746.6 83547.2 1109.7 1111.3 1114.3 1124.2 1154.4 14508.0 15504.0 15677.3 15748.6 16035.1 19511.4 32623.8 33133.8 33232.3 33497.2 42074.2 45139.0 45308.0 46259.5 46542.8 9519.9 9658.8 9675.9 9733.6 9736.8 11350.1 16850.2 16963.8 17507.2 17520.1 14040.9 14263.6 14487.9 14707.9 14725.3 13873.9 13963.4 14184.8 14500.6 14626.3 14023.2 14123.3 14545.5 14633.6 14908.2 9315.7 9322.1 9399.3 9508.5 9547.9 10304.9 10312.4 10878.0 15223.0 15388.5 05:44:11 INFO - 2015-11-26 05:44:11,105 DEBUG : BROWSER_OUTPUT: 05:44:11 INFO - 2015-11-26 05:44:11,105 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5508.61 Median:6646.37 stddev:2896.90 (43.6%) stddev-sans-first:2889.35 05:44:11 INFO - 2015-11-26 05:44:11,106 DEBUG : BROWSER_OUTPUT: Values: 1499.4 1734.1 1775.9 1790.4 1801.0 7780.5 7924.7 8000.2 8040.5 8176.1 8120.2 8134.0 8181.7 8187.3 8200.9 2253.0 2279.7 2283.0 2284.4 2285.6 2285.5 2307.1 2322.7 2322.8 2325.0 2257.0 2257.1 2279.8 2298.1 2299.5 2264.4 2268.3 2284.8 2285.5 2289.1 2325.8 2335.5 2342.9 2343.7 2350.3 7822.9 7885.4 7899.5 7904.3 7919.1 4604.9 4641.4 4681.6 4688.0 4699.2 2929.4 2941.7 2974.3 2997.6 3023.4 932.0 953.1 956.1 967.5 977.6 10856.6 10903.4 10932.0 10955.1 10962.8 4129.9 4138.2 4179.6 4193.3 4276.7 8936.2 8938.1 8969.5 8986.1 8990.3 4745.6 4766.1 4772.1 4776.3 4794.2 4305.2 4332.9 4338.8 4349.8 4405.8 3478.8 3481.3 3508.7 3547.3 3563.5 1092.8 1093.0 1114.3 1114.7 1115.5 1077.9 1086.5 1087.7 1212.7 1300.4 7409.3 7440.8 7540.8 7544.5 7560.9 502.4 503.3 509.9 510.2 511.7 5873.2 5894.1 5908.4 5930.7 5944.2 9133.4 9288.2 9418.7 9501.3 9651.4 9331.4 9339.1 9359.5 9369.7 9394.6 7303.1 7305.6 7414.0 7436.0 7462.3 7190.6 7199.9 7214.2 7217.1 7220.8 8184.5 8332.9 8467.4 8538.4 8559.6 8426.6 8458.0 8495.7 8597.6 8635.6 6695.7 6756.6 6899.2 6973.9 7002.3 7680.4 7681.7 7701.9 7739.1 7748.6 7105.3 7115.3 7248.3 7308.7 7359.2 6984.4 7023.7 7027.5 7087.1 7089.0 7161.5 7204.2 7243.0 7244.2 7281.1 6625.7 6667.0 6673.4 6680.0 6698.8 6495.9 6540.2 6584.2 6597.5 6606.7 05:44:11 INFO - 2015-11-26 05:44:11,106 DEBUG : BROWSER_OUTPUT: 05:44:11 INFO - 2015-11-26 05:44:11,106 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5602.43 Median:6358.70 stddev:1706.90 (26.8%) stddev-sans-first:1665.16 05:44:11 INFO - 2015-11-26 05:44:11,107 DEBUG : BROWSER_OUTPUT: Values: 329.0 332.4 333.8 342.8 343.8 6225.2 6265.7 6301.4 6333.5 6342.7 6160.1 6203.5 6209.0 6218.3 6277.2 4769.4 4782.2 4790.3 4803.6 4821.4 4815.0 4818.1 4820.2 4833.3 4845.2 4683.2 4779.1 4783.5 4794.3 4795.7 4748.1 4766.6 4771.2 4774.1 4798.0 4174.9 4179.7 4183.9 4184.4 4193.6 5977.7 5981.6 5983.7 5988.6 6004.6 5423.4 5472.7 5494.9 5499.9 5570.9 6626.5 6647.9 6650.8 6658.6 6666.2 4574.1 4590.8 4598.4 4598.6 4602.0 6725.7 6857.2 6892.9 6898.2 6932.8 4740.9 4747.2 4748.7 4761.2 4768.2 4799.7 4806.4 4812.4 4818.6 4825.4 5552.4 5564.5 5566.9 5567.6 5581.2 6545.8 6625.4 6664.3 6668.2 6719.7 1604.1 1650.3 1651.4 1652.1 1681.8 1060.1 1104.7 1106.4 1106.8 1113.1 6411.3 6444.0 6444.1 6444.9 6452.8 6845.5 6859.8 6867.0 6872.5 6883.6 6337.6 6374.6 6410.1 6422.6 6431.2 3736.6 3797.5 3812.4 3815.3 3818.1 7263.1 7264.5 7265.2 7265.3 7275.9 7018.3 7162.5 7179.1 7184.3 7188.8 7163.5 7228.8 7231.0 7249.5 7270.2 6927.9 6933.1 6936.8 6938.5 6952.4 6768.2 6892.7 6917.1 6932.4 6944.7 7195.3 7237.5 7239.9 7240.5 7241.8 4684.7 4722.8 4727.6 4728.5 4732.9 6838.2 6949.4 6964.2 6981.0 6981.2 6755.2 6760.0 6761.9 6772.7 6775.7 6747.1 6753.7 6766.3 6777.5 6795.3 6489.4 6630.3 6632.0 6637.8 6657.6 6790.0 6792.9 6803.1 6812.3 6814.5 6479.5 6574.3 6628.7 6631.1 6636.0 05:44:11 INFO - 2015-11-26 05:44:11,107 DEBUG : BROWSER_OUTPUT: 05:44:11 INFO - 2015-11-26 05:44:11,107 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13879.13 Median:53095.60 stddev:16826.31 (31.7%) stddev-sans-first:16844.40 05:44:11 INFO - 2015-11-26 05:44:11,108 DEBUG : BROWSER_OUTPUT: Values: 698.2 724.7 755.3 824.7 825.2 12861.5 13282.2 13284.6 13380.6 13425.0 12066.5 12349.8 12356.4 12411.1 12413.5 814.7 822.4 829.7 842.3 846.4 845.4 847.8 848.6 848.7 850.9 869.1 875.5 877.6 879.4 882.0 868.8 870.2 870.3 870.5 871.8 642.7 642.7 645.4 645.6 645.8 12901.6 13081.1 13102.6 13141.9 13170.9 11072.8 11883.8 11889.5 11895.6 11922.1 9533.0 9704.4 9745.3 9933.2 9945.7 3466.5 3469.1 3510.0 3516.8 3518.6 51782.5 51867.7 53068.7 53149.2 53197.0 8825.5 8980.6 8984.7 9087.9 9092.3 18445.5 18507.2 18525.3 18559.2 18585.3 7173.1 7372.3 7432.4 7588.6 7716.1 4079.6 4167.4 4204.3 4276.8 4430.4 3078.0 3086.2 3088.9 3104.0 3109.7 541.5 558.0 558.9 569.0 570.0 453.5 498.2 533.3 639.1 653.6 7538.4 7549.6 7667.3 7848.9 7931.0 512.6 533.2 547.6 564.4 633.6 3654.2 3720.7 3832.6 3979.5 3997.9 58786.7 60333.2 60339.0 60382.0 60400.4 53122.5 53427.2 53730.0 53878.2 54122.7 48479.0 48830.0 48954.3 49558.0 49895.0 7973.8 8208.3 8234.4 8265.7 8278.6 36011.8 36427.3 36495.8 36822.4 37088.8 41482.6 43130.4 43860.3 45231.5 45880.3 10044.1 10153.0 10349.1 10474.7 10572.7 11230.9 11337.7 11350.0 11475.5 11926.4 10380.2 10511.4 10571.9 10592.8 10616.6 10232.8 10244.3 10363.1 10591.3 10606.1 10201.6 10559.7 10681.0 10795.6 10983.2 9231.7 9310.1 9348.5 9420.8 9633.6 8219.4 8536.6 8697.4 9124.6 9174.0 05:44:11 INFO - 2015-11-26 05:44:11,108 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:44:11 INFO - 2015-11-26 05:44:11,108 DEBUG : BROWSER_OUTPUT: 05:44:11 INFO - 2015-11-26 05:44:11,717 INFO : Browser exited with error code: 0 05:44:11 INFO - 2015-11-26 05:44:11,736 INFO : Completed test dromaeo_css (00:18:30) 05:44:11 INFO - 2015-11-26 05:44:11,736 INFO : Starting test kraken 05:44:11 INFO - 2015-11-26 05:44:11,736 DEBUG : operating with platform_type : linux_ 05:44:11 INFO - 2015-11-26 05:44:11,736 INFO : Initialising browser for kraken test... 05:44:11 INFO - 2015-11-26 05:44:11,742 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBryGtn/profile http://localhost:59911/getInfo.html 05:44:16 INFO - 2015-11-26 05:44:16,163 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:44:16 INFO - 2015-11-26 05:44:16,163 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:44:16 INFO - 2015-11-26 05:44:16,163 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:44:16 INFO - 2015-11-26 05:44:16,163 DEBUG : BROWSER_OUTPUT: __metrics 05:44:17 INFO - 2015-11-26 05:44:17,169 INFO : Browser initialized. 05:44:17 INFO - 2015-11-26 05:44:17,169 INFO : Running cycle 1/1 for kraken test... 05:44:17 INFO - 2015-11-26 05:44:17,170 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBryGtn/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:44:28 INFO - 2015-11-26 05:44:28,919 DEBUG : BROWSER_OUTPUT: RSS: Main: 138862592 05:44:28 INFO - 2015-11-26 05:44:28,919 DEBUG : BROWSER_OUTPUT: 05:44:31 INFO - 2015-11-26 05:44:31,440 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59911/tests/kraken/driver.html?testName=audio-beat-detection) 05:44:32 INFO - 2015-11-26 05:44:32,041 DEBUG : BROWSER_OUTPUT: RSS: Main: 141197312 05:44:32 INFO - 2015-11-26 05:44:32,041 DEBUG : BROWSER_OUTPUT: 05:44:34 INFO - 2015-11-26 05:44:34,684 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59911/tests/kraken/driver.html?testName=audio-dft) 05:44:35 INFO - 2015-11-26 05:44:35,273 DEBUG : BROWSER_OUTPUT: RSS: Main: 140910592 05:44:35 INFO - 2015-11-26 05:44:35,273 DEBUG : BROWSER_OUTPUT: 05:44:38 INFO - 2015-11-26 05:44:38,105 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59911/tests/kraken/driver.html?testName=audio-fft) 05:44:38 INFO - 2015-11-26 05:44:38,678 DEBUG : BROWSER_OUTPUT: RSS: Main: 135184384 05:44:38 INFO - 2015-11-26 05:44:38,678 DEBUG : BROWSER_OUTPUT: 05:44:40 INFO - 2015-11-26 05:44:40,670 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59911/tests/kraken/driver.html?testName=audio-oscillator) 05:44:41 INFO - 2015-11-26 05:44:41,251 DEBUG : BROWSER_OUTPUT: RSS: Main: 136224768 05:44:41 INFO - 2015-11-26 05:44:41,253 DEBUG : BROWSER_OUTPUT: 05:44:43 INFO - 2015-11-26 05:44:43,451 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59911/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:44:44 INFO - 2015-11-26 05:44:44,024 DEBUG : BROWSER_OUTPUT: RSS: Main: 136896512 05:44:44 INFO - 2015-11-26 05:44:44,024 DEBUG : BROWSER_OUTPUT: 05:44:46 INFO - 2015-11-26 05:44:46,995 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59911/tests/kraken/driver.html?testName=imaging-darkroom) 05:44:47 INFO - 2015-11-26 05:44:47,584 DEBUG : BROWSER_OUTPUT: RSS: Main: 138735616 05:44:47 INFO - 2015-11-26 05:44:47,585 DEBUG : BROWSER_OUTPUT: 05:44:51 INFO - 2015-11-26 05:44:51,012 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59911/tests/kraken/driver.html?testName=imaging-desaturate) 05:44:51 INFO - 2015-11-26 05:44:51,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 138870784 05:44:51 INFO - 2015-11-26 05:44:51,599 DEBUG : BROWSER_OUTPUT: 05:44:54 INFO - 2015-11-26 05:44:54,442 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59911/tests/kraken/driver.html?testName=json-parse-financial) 05:44:55 INFO - 2015-11-26 05:44:55,036 DEBUG : BROWSER_OUTPUT: RSS: Main: 137797632 05:44:55 INFO - 2015-11-26 05:44:55,036 DEBUG : BROWSER_OUTPUT: 05:44:56 INFO - 2015-11-26 05:44:56,645 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59911/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:44:57 INFO - 2015-11-26 05:44:57,218 DEBUG : BROWSER_OUTPUT: RSS: Main: 138633216 05:44:57 INFO - 2015-11-26 05:44:57,218 DEBUG : BROWSER_OUTPUT: 05:44:59 INFO - 2015-11-26 05:44:59,073 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59911/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:44:59 INFO - 2015-11-26 05:44:59,695 DEBUG : BROWSER_OUTPUT: RSS: Main: 138452992 05:44:59 INFO - 2015-11-26 05:44:59,695 DEBUG : BROWSER_OUTPUT: 05:45:01 INFO - 2015-11-26 05:45:01,808 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59911/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:45:02 INFO - 2015-11-26 05:45:02,396 DEBUG : BROWSER_OUTPUT: RSS: Main: 137842688 05:45:02 INFO - 2015-11-26 05:45:02,396 DEBUG : BROWSER_OUTPUT: 05:45:04 INFO - 2015-11-26 05:45:04,843 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:45:05 INFO - 2015-11-26 05:45:05,445 DEBUG : BROWSER_OUTPUT: RSS: Main: 138035200 05:45:05 INFO - 2015-11-26 05:45:05,445 DEBUG : BROWSER_OUTPUT: 05:45:08 INFO - 2015-11-26 05:45:08,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:45:08 INFO - 2015-11-26 05:45:08,615 DEBUG : BROWSER_OUTPUT: RSS: Main: 138862592 05:45:08 INFO - 2015-11-26 05:45:08,615 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,292 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59911/tests/kraken/driver.html?testName=ai-astar) 05:45:10 INFO - 2015-11-26 05:45:10,550 DEBUG : BROWSER_OUTPUT: RSS: Main: 138711040 05:45:10 INFO - 2015-11-26 05:45:10,550 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,550 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:45:10 INFO - 2015-11-26 05:45:10,551 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:45:10 INFO - 2015-11-26 05:45:10,551 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:45:10 INFO - 2015-11-26 05:45:10,551 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:45:10 INFO - 2015-11-26 05:45:10,551 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;105;111;112;123;123;112;111;111;112 05:45:10 INFO - 2015-11-26 05:45:10,551 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;129;161;157;129;150;156;125;160;128 05:45:10 INFO - 2015-11-26 05:45:10,551 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;162;161;163;161;165;160;162;170;163 05:45:10 INFO - 2015-11-26 05:45:10,551 DEBUG : BROWSER_OUTPUT: |3;audio-fft;74;72;73;72;74;141;72;77;73;71 05:45:10 INFO - 2015-11-26 05:45:10,551 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;144;94;93;94;139;92;93;135 05:45:10 INFO - 2015-11-26 05:45:10,552 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;110;110;111;111;110;110;111 05:45:10 INFO - 2015-11-26 05:45:10,552 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;147;147;147;146;146;147;147;149 05:45:10 INFO - 2015-11-26 05:45:10,552 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;97;97;96;96;96;96;97;97;98 05:45:10 INFO - 2015-11-26 05:45:10,552 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;63;61;62;61;63;63;63;61;61;59 05:45:10 INFO - 2015-11-26 05:45:10,552 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;71;70;70;71;71;72;71;72;71;71 05:45:10 INFO - 2015-11-26 05:45:10,552 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;84;86;87;88;84;93;85;86;94 05:45:10 INFO - 2015-11-26 05:45:10,553 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;130;114;121;122;119;125;129;115;132;114 05:45:10 INFO - 2015-11-26 05:45:10,553 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;151;156;148;153;149;149;151;139;150;139 05:45:10 INFO - 2015-11-26 05:45:10,553 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;70;71;66;66;67;67;66;68;66;68 05:45:10 INFO - 2015-11-26 05:45:10,554 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:45:10 INFO - 2015-11-26 05:45:10,554 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:45:10 INFO - 2015-11-26 05:45:10,554 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,705 05:45:10 INFO - 2015-11-26 05:45:10,554 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:45:10 INFO - 2015-11-26 05:45:10,554 DEBUG : BROWSER_OUTPUT: __startTimestamp1448545510544__endTimestamp 05:45:10 INFO - 2015-11-26 05:45:10,555 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,555 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:45:10 INFO - 2015-11-26 05:45:10,555 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:45:10 INFO - 2015-11-26 05:45:10,555 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,555 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:113.20 Median:112.00 stddev:5.57 (5.0%) stddev-sans-first:5.89 05:45:10 INFO - 2015-11-26 05:45:10,555 DEBUG : BROWSER_OUTPUT: Values: 112.0 105.0 111.0 112.0 123.0 123.0 112.0 111.0 111.0 112.0 05:45:10 INFO - 2015-11-26 05:45:10,556 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,556 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.50 Median:153.00 stddev:14.89 (9.7%) stddev-sans-first:15.66 05:45:10 INFO - 2015-11-26 05:45:10,556 DEBUG : BROWSER_OUTPUT: Values: 150.0 129.0 161.0 157.0 129.0 150.0 156.0 125.0 160.0 128.0 05:45:10 INFO - 2015-11-26 05:45:10,556 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,557 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.00 Median:163.00 stddev:2.83 (1.7%) stddev-sans-first:3.00 05:45:10 INFO - 2015-11-26 05:45:10,557 DEBUG : BROWSER_OUTPUT: Values: 163.0 162.0 161.0 163.0 161.0 165.0 160.0 162.0 170.0 163.0 05:45:10 INFO - 2015-11-26 05:45:10,557 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,558 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.90 Median:73.00 stddev:21.53 (29.5%) stddev-sans-first:22.73 05:45:10 INFO - 2015-11-26 05:45:10,558 DEBUG : BROWSER_OUTPUT: Values: 74.0 72.0 73.0 72.0 74.0 141.0 72.0 77.0 73.0 71.0 05:45:10 INFO - 2015-11-26 05:45:10,558 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,558 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.00 Median:93.00 stddev:22.42 (24.1%) stddev-sans-first:23.20 05:45:10 INFO - 2015-11-26 05:45:10,559 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 144.0 94.0 93.0 94.0 139.0 92.0 93.0 135.0 05:45:10 INFO - 2015-11-26 05:45:10,559 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,559 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.40 Median:110.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:45:10 INFO - 2015-11-26 05:45:10,559 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 110.0 110.0 111.0 111.0 110.0 110.0 111.0 05:45:10 INFO - 2015-11-26 05:45:10,560 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,560 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 05:45:10 INFO - 2015-11-26 05:45:10,560 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 147.0 147.0 147.0 146.0 146.0 147.0 147.0 149.0 05:45:10 INFO - 2015-11-26 05:45:10,561 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,561 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.70 Median:97.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 05:45:10 INFO - 2015-11-26 05:45:10,561 DEBUG : BROWSER_OUTPUT: Values: 97.0 97.0 97.0 96.0 96.0 96.0 96.0 97.0 97.0 98.0 05:45:10 INFO - 2015-11-26 05:45:10,561 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,562 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:61.70 Median:62.50 stddev:1.34 (2.1%) stddev-sans-first:1.33 05:45:10 INFO - 2015-11-26 05:45:10,562 DEBUG : BROWSER_OUTPUT: Values: 63.0 61.0 62.0 61.0 63.0 63.0 63.0 61.0 61.0 59.0 05:45:10 INFO - 2015-11-26 05:45:10,562 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,563 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:0.67 (0.9%) stddev-sans-first:0.71 05:45:10 INFO - 2015-11-26 05:45:10,563 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 70.0 71.0 71.0 72.0 71.0 72.0 71.0 71.0 05:45:10 INFO - 2015-11-26 05:45:10,563 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,563 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:86.50 stddev:3.74 (4.3%) stddev-sans-first:3.68 05:45:10 INFO - 2015-11-26 05:45:10,564 DEBUG : BROWSER_OUTPUT: Values: 83.0 84.0 86.0 87.0 88.0 84.0 93.0 85.0 86.0 94.0 05:45:10 INFO - 2015-11-26 05:45:10,564 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,565 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.10 Median:123.50 stddev:6.74 (5.5%) stddev-sans-first:6.51 05:45:10 INFO - 2015-11-26 05:45:10,565 DEBUG : BROWSER_OUTPUT: Values: 130.0 114.0 121.0 122.0 119.0 125.0 129.0 115.0 132.0 114.0 05:45:10 INFO - 2015-11-26 05:45:10,565 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,566 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.50 Median:150.50 stddev:5.50 (3.7%) stddev-sans-first:5.76 05:45:10 INFO - 2015-11-26 05:45:10,566 DEBUG : BROWSER_OUTPUT: Values: 151.0 156.0 148.0 153.0 149.0 149.0 151.0 139.0 150.0 139.0 05:45:10 INFO - 2015-11-26 05:45:10,566 DEBUG : BROWSER_OUTPUT: 05:45:10 INFO - 2015-11-26 05:45:10,566 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:1.78 (2.6%) stddev-sans-first:1.64 05:45:10 INFO - 2015-11-26 05:45:10,566 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 66.0 66.0 67.0 67.0 66.0 68.0 66.0 68.0 05:45:10 INFO - 2015-11-26 05:45:10,566 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:45:10 INFO - 2015-11-26 05:45:10,566 DEBUG : BROWSER_OUTPUT: 05:45:11 INFO - 2015-11-26 05:45:11,441 INFO : Browser exited with error code: 0 05:45:11 INFO - 2015-11-26 05:45:11,496 INFO : Completed test kraken (00:00:59) 05:45:11 INFO - 2015-11-26 05:45:11,496 INFO : Starting test v8_7 05:45:11 INFO - 2015-11-26 05:45:11,496 DEBUG : operating with platform_type : linux_ 05:45:11 INFO - 2015-11-26 05:45:11,496 INFO : Initialising browser for v8_7 test... 05:45:11 INFO - 2015-11-26 05:45:11,502 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpC5VRDN/profile http://localhost:59911/getInfo.html 05:45:15 INFO - 2015-11-26 05:45:15,730 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:45:15 INFO - 2015-11-26 05:45:15,730 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:45:15 INFO - 2015-11-26 05:45:15,730 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:45:15 INFO - 2015-11-26 05:45:15,730 DEBUG : BROWSER_OUTPUT: __metrics 05:45:16 INFO - 2015-11-26 05:45:16,769 INFO : Browser initialized. 05:45:16 INFO - 2015-11-26 05:45:16,769 INFO : Running cycle 1/1 for v8_7 test... 05:45:16 INFO - 2015-11-26 05:45:16,770 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpC5VRDN/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:45:28 INFO - 2015-11-26 05:45:28,584 DEBUG : BROWSER_OUTPUT: RSS: Main: 141017088 05:45:28 INFO - 2015-11-26 05:45:28,585 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,005 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59911/tests/v8_7/run.html (next: http://localhost:59911/tests/v8_7/run.html) 05:45:50 INFO - 2015-11-26 05:45:50,256 DEBUG : BROWSER_OUTPUT: RSS: Main: 137670656 05:45:50 INFO - 2015-11-26 05:45:50,257 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,258 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:45:50 INFO - 2015-11-26 05:45:50,258 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:45:50 INFO - 2015-11-26 05:45:50,258 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:45:50 INFO - 2015-11-26 05:45:50,259 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:45:50 INFO - 2015-11-26 05:45:50,259 DEBUG : BROWSER_OUTPUT: |0;Richards;166.86133822793258 05:45:50 INFO - 2015-11-26 05:45:50,259 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;187.75816748028538 05:45:50 INFO - 2015-11-26 05:45:50,259 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.4857462269424 05:45:50 INFO - 2015-11-26 05:45:50,260 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5170.103092783505 05:45:50 INFO - 2015-11-26 05:45:50,260 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1034.126163391934 05:45:50 INFO - 2015-11-26 05:45:50,261 DEBUG : BROWSER_OUTPUT: |5;Earley;694.9270326615705 05:45:50 INFO - 2015-11-26 05:45:50,261 DEBUG : BROWSER_OUTPUT: |6;Boyer;12228.915662650603 05:45:50 INFO - 2015-11-26 05:45:50,261 DEBUG : BROWSER_OUTPUT: |7;RegExp;24780.48780487805 05:45:50 INFO - 2015-11-26 05:45:50,261 DEBUG : BROWSER_OUTPUT: |8;Splay;686.8131868131868 05:45:50 INFO - 2015-11-26 05:45:50,262 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4878.048780487805 05:45:50 INFO - 2015-11-26 05:45:50,262 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:45:50 INFO - 2015-11-26 05:45:50,262 DEBUG : BROWSER_OUTPUT: __startTimestamp1448545550256__endTimestamp 05:45:50 INFO - 2015-11-26 05:45:50,263 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,263 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:45:50 INFO - 2015-11-26 05:45:50,263 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:45:50 INFO - 2015-11-26 05:45:50,263 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,264 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.86 Median:166.86 stddev:0.00 (0.0%) 05:45:50 INFO - 2015-11-26 05:45:50,264 DEBUG : BROWSER_OUTPUT: Values: 166.9 05:45:50 INFO - 2015-11-26 05:45:50,264 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,264 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:187.76 Median:187.76 stddev:0.00 (0.0%) 05:45:50 INFO - 2015-11-26 05:45:50,265 DEBUG : BROWSER_OUTPUT: Values: 187.8 05:45:50 INFO - 2015-11-26 05:45:50,265 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,265 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.49 Median:279.49 stddev:0.00 (0.0%) 05:45:50 INFO - 2015-11-26 05:45:50,266 DEBUG : BROWSER_OUTPUT: Values: 279.5 05:45:50 INFO - 2015-11-26 05:45:50,266 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,266 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 05:45:50 INFO - 2015-11-26 05:45:50,266 DEBUG : BROWSER_OUTPUT: Values: 5170.1 05:45:50 INFO - 2015-11-26 05:45:50,266 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,267 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1034.13 Median:1034.13 stddev:0.00 (0.0%) 05:45:50 INFO - 2015-11-26 05:45:50,267 DEBUG : BROWSER_OUTPUT: Values: 1034.1 05:45:50 INFO - 2015-11-26 05:45:50,267 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,267 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:694.93 Median:694.93 stddev:0.00 (0.0%) 05:45:50 INFO - 2015-11-26 05:45:50,268 DEBUG : BROWSER_OUTPUT: Values: 694.9 05:45:50 INFO - 2015-11-26 05:45:50,268 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,268 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12228.92 Median:12228.92 stddev:0.00 (0.0%) 05:45:50 INFO - 2015-11-26 05:45:50,268 DEBUG : BROWSER_OUTPUT: Values: 12228.9 05:45:50 INFO - 2015-11-26 05:45:50,268 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,269 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24780.49 Median:24780.49 stddev:0.00 (0.0%) 05:45:50 INFO - 2015-11-26 05:45:50,269 DEBUG : BROWSER_OUTPUT: Values: 24780.5 05:45:50 INFO - 2015-11-26 05:45:50,269 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,269 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:686.81 Median:686.81 stddev:0.00 (0.0%) 05:45:50 INFO - 2015-11-26 05:45:50,269 DEBUG : BROWSER_OUTPUT: Values: 686.8 05:45:50 INFO - 2015-11-26 05:45:50,269 DEBUG : BROWSER_OUTPUT: 05:45:50 INFO - 2015-11-26 05:45:50,269 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4878.05 Median:4878.05 stddev:0.00 (0.0%) 05:45:50 INFO - 2015-11-26 05:45:50,269 DEBUG : BROWSER_OUTPUT: Values: 4878.0 05:45:50 INFO - 2015-11-26 05:45:50,269 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:45:50 INFO - 2015-11-26 05:45:50,269 DEBUG : BROWSER_OUTPUT: 05:45:51 INFO - 2015-11-26 05:45:51,057 INFO : Browser exited with error code: 0 05:45:51 INFO - 2015-11-26 05:45:51,076 INFO : Completed test v8_7 (00:00:39) 05:45:51 INFO - 2015-11-26 05:45:51,273 INFO : Completed test suite (00:20:09) 05:45:51 INFO - 2015-11-26 05:45:51,274 DEBUG : Working with test: dromaeo_css 05:45:51 INFO - 2015-11-26 05:45:51,274 DEBUG : Generating results file: dromaeo_css 05:45:51 INFO - 2015-11-26 05:45:51,275 DEBUG : Working with test: kraken 05:45:51 INFO - 2015-11-26 05:45:51,275 DEBUG : Generating results file: kraken 05:45:51 INFO - 2015-11-26 05:45:51,275 INFO : javascript benchmark 05:45:51 INFO - 2015-11-26 05:45:51,275 DEBUG : Working with test: v8_7 05:45:51 INFO - 2015-11-26 05:45:51,275 DEBUG : Generating results file: v8_7 05:45:51 INFO - 2015-11-26 05:45:51,276 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:45:51 INFO - 2015-11-26 05:45:51,338 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 05:45:51 INFO - 2015-11-26 05:45:51,338 DEBUG : process_Request line: dromaeo_css 7020.33 graph.html#tests=[[72,64,43]] 05:45:51 INFO - 2015-11-26 05:45:51,338 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:45:51 INFO - 2015-11-26 05:45:51,371 DEBUG : process_Request line: kraken 1519.50 graph.html#tests=[[232,64,43]] 05:45:51 INFO - 2015-11-26 05:45:51,371 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:45:51 INFO - 2015-11-26 05:45:51,495 DEBUG : process_Request line: v8_7 21260.76 graph.html#tests=[[230,64,43]] 05:45:51 INFO - 2015-11-26 05:45:51,496 INFO : javascript benchmark 05:45:51 INFO - 2015-11-26 05:45:51,500 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3176.9364612707745, 3235.1588586967396, 3266.3140740444537, 3273.7217336526396, 3283.8193899335542, 5261.947380526193, 5481.314835645544, 5483.698396588186, 5495.422980587041, 5504.256925315081, 5415.458454154593, 5420.457954204578, 5431.266778984836, 5542.7782888684505, 5546.805861794828, 4968.0560693468215, 5044.0, 5117.130087885069, 5124.5131712487255, 5129.948700513003, 5048.394192696863, 5069.873253168664, 5189.014087323397, 5200.791968321281, 5201.895962080756, 4941.555260026615, 5074.923876141824, 5083.415407228179, 5179.14544100223, 5194.662346947436, 4938.604911607063, 5018.447970723218, 5020.974895125538, 5035.0685123252315, 5045.117104506697, 5020.497950204986, 5072.188449848007, 5072.239164125411, 5081.060003899291, 5094.490550944913, 5189.065968125736, 5218.6868787872845, 5294.126469132589, 5314.92027619586, 5370.650907691027, 5967.075103358986, 5969.850753731148, 5975.790847320323, 5976.820695379146, 5990.071538911476, 5993.370696076893, 6007.7596896124105, 6010.519158467312, 6012.398760123952, 6016.69916504173, 5332.200169974535, 5446.428125046853, 5453.127499600085, 5555.138953462219, 5593.272874526327, 6073.119397687399, 6077.878442431215, 6079.604825686316, 6080.300765412034, 6087.452129308295, 5836.270466191726, 5871.295444546682, 5874.882502349929, 5893.705314734246, 5909.5863289569315, 5760.164776107441, 5821.592488525763, 5851.765929362863, 5892.175095486636, 5953.732082056231, 5901.58688891782, 5908.763649454059, 5935.050391937356, 5948.524118070631, 5948.821535353946, 5804.912926306109, 5883.235179426732, 5922.526197904157, 5936.22829032223, 5950.375210602824, 4516.051629157913, 4556.589906908328, 4559.224931761609, 4562.041971186021, 4600.907981840344, 4012.598740125964, 4040.373742069984, 4067.7559346439307, 4099.631033207026, 4121.423000779893, 5463.098588732814, 5587.3015873015875, 5682.857928551819, 5718.7426565804635, 5724.656520608777, 5895.322037965689, 5915.822525324247, 5948.315943666447, 5972.283326000907, 6015.0977353396465, 5622.493975542139, 5682.857928551736, 5691.231683722644, 5705.229793977843, 5707.4577915098, 5031.597472202215, 5137.768800403991, 5208.166693329049, 5215.843524694265, 5228.607854410934, 6048.546359023091, 6065.2418447694035, 6123.069106197319, 6136.23297087864, 6136.2636483622255, 5948.910766338595, 5985.790497332572, 5988.5508586855285, 6006.489448396936, 6033.758649653964, 6106.1146133811235, 6110.0, 6124.38756124384, 6161.72272247741, 6163.291221509584, 5781.82654520365, 5842.561807864342, 5898.882022359615, 5898.94101058984, 5906.114082887601, 5849.61977471463, 5935.376785437639, 5947.9702601486715, 5950.642961422329, 5976.671283079472, 5816.854578635568, 5859.0, 5864.413558644102, 5871.911921321269, 5882.49998750111, 5749.568782341257, 5836.445537673914, 5845.590808643354, 5848.093545500455, 5850.5904586678525, 5571.415001424792, 5683.658980461186, 5717.22817419643, 5735.254416925773, 5804.332501762351, 5888.17565540816, 5925.762969481345, 5929.347771745191, 5941.0, 5973.253343332083, 5763.4812866842185, 5784.913226301524, 5786.276715410574, 5806.854828629318, 5810.6513609183585], "unit": "ms", "name": "dojo.html", "value": 5477.550761680922}, {"lowerIsBetter": false, "replicates": [569.4618585436759, 576.7837061102086, 585.7276366489582, 586.5249148189966, 587.1838144978481, 38617.80691096544, 39599.80200098999, 39601.40597891027, 39752.602473975254, 39881.20237595246, 43248.0, 43392.566074339244, 43668.344974825406, 43742.56257437425, 43787.56212437875, 8730.781730456725, 8793.692220772275, 8852.601632926533, 8871.512066836322, 8872.645094196225, 8655.870161947576, 8687.913120868805, 8747.300215982707, 8754.3871928965, 8757.25563327118, 8718.34612404069, 8783.824323513525, 8801.515916624583, 8803.77990550235, 8810.0, 8734.86897696535, 8743.256823170039, 8807.987081485648, 8823.558822058903, 8824.0, 8612.39713220075, 8767.079456657088, 8770.0, 8772.824543509094, 8776.473411595261, 39941.20117597632, 40553.594464055575, 40597.79701101505, 40613.79693101515, 40632.18735625271, 3251.3775337669886, 3286.9506957395656, 3373.4939759036342, 3403.5915690117095, 3540.628234035415, 29739.2922723224, 30168.396632067455, 30478.54282185769, 30580.0, 31279.687203127905, 3518.9120271993147, 3547.3260080584605, 3640.5813331466957, 3690.317291301118, 3693.4829123922673, 254953.0, 255942.0, 257303.0, 257374.0, 257729.99999999814, 41980.58019419828, 42276.0, 57561.712191438775, 57782.13326800102, 57874.71062644744, 41364.58635413668, 41403.792981035505, 41500.37749433761, 41530.79234603808, 41787.58212417837, 14595.124292542481, 14785.63035924089, 14787.92606036963, 14801.48194813198, 14806.555803325917, 11219.887801122048, 11334.489947952361, 11344.602938897264, 11352.489137988645, 11356.659300221007, 13922.930385348009, 14404.351804168837, 14459.060161089496, 14490.782638260434, 14507.419703211965, 5741.138829175574, 5741.684207368551, 5809.883802324014, 5849.532037436995, 5860.589758716934, 935.8876934767871, 951.8143961927497, 969.9806003880024, 1037.941299874124, 1065.3128731967838, 28506.42987140287, 28653.28366790847, 28769.71230287712, 29083.56374654382, 29295.560566591517, 1032.0917592518686, 1065.4299949526967, 1075.333293358123, 1080.7352198711367, 1086.0659832544006, 14847.70304593902, 15320.616984575252, 16664.75002874958, 17119.914400428166, 17140.828591713922, 367943.0, 372336.0, 372838.0, 375224.0, 377571.0, 55692.72153639287, 55958.0, 56107.0, 56452.0, 56463.43536564582, 1361.4758318047623, 1364.0656150536913, 1373.9656508587366, 1395.2628976923795, 1406.9718605628034, 29141.417171656023, 30555.388892221585, 32212.83893580517, 33054.834725826215, 33207.833960830045, 50199.24701129559, 51222.231666525775, 51284.23073653824, 51340.486595135066, 51352.74323628358, 271235.0, 272416.0, 273540.0, 274259.0, 275115.0, 43010.13979720451, 46327.76836115798, 46469.282457404006, 47301.0, 47321.0, 45268.0, 45524.77237613791, 45553.54446455493, 46488.53511464977, 46665.3000205004, 37520.24959500849, 38600.227995439374, 38823.61176388313, 38874.22251555009, 39496.210075798896, 37380.439293410076, 38238.42642360422, 38647.0, 38953.0, 39172.41241381439, 38044.61955380411, 38214.23571528609, 40919.795401022806, 41332.58667413287, 41519.1696166081, 31715.524267135548, 34323.828380857936, 34538.30923381468, 34640.30719385548, 35114.648853511135, 25413.87293063523, 26140.73859261383, 26244.737552624993, 26329.8947462984, 26658.0], "unit": "ms", "name": "ext.html", "value": 18512.129033111818}, {"lowerIsBetter": false, "replicates": [333.5230620213095, 334.9363620912026, 336.7608997611697, 337.5611704783781, 337.809137837122, 16493.587660308498, 16588.170591470425, 17489.562760930967, 17790.64418711625, 18379.816201837977, 10897.727556811084, 12158.331291778948, 15935.52193434196, 16031.919840400811, 16050.59873503163, 4960.900781984358, 5540.667559946396, 5541.196526503654, 5542.91685624716, 5549.084401073818, 5468.070428027245, 5470.8085217017415, 5500.119980803072, 5517.972410137963, 5526.668399895999, 4996.750162491879, 5031.849044528652, 5043.798248070073, 5143.794248230066, 5291.391489978663, 5197.5322220999915, 5215.6349055566125, 5224.7387630618505, 5230.110881150214, 5231.529162375386, 4996.150654388726, 5131.974340128276, 5161.974190129063, 5181.740912954375, 5191.5846732261325, 12409.813852792215, 14505.347259373353, 16674.58313542171, 19253.614927701507, 19307.613847723107, 1988.09541658545, 2011.6781314989532, 2042.234162189179, 2046.2531176120608, 2196.0447205465675, 8887.644494220223, 9169.679061232891, 9215.585298661576, 9238.122378374044, 9350.859737103881, 1798.8740788144835, 1815.0652414006797, 1816.5731053202487, 1837.3018253063883, 1848.3900312896712, 60378.698106509626, 62026.0, 62796.372036279965, 63440.0, 66094.66952665253, 9599.520023998912, 9704.660336888175, 9722.368046076983, 9779.706608801747, 9885.406875587487, 12658.936705316415, 12718.618441446772, 12737.490500380067, 12794.744105117845, 12831.909287710649, 7593.392528597699, 7601.961990190013, 7725.266099720518, 7833.804154896173, 7911.169327220674, 7406.370458511087, 7422.443316751157, 7432.9256707433315, 7789.571573563395, 7818.022747156606, 4257.1698518788535, 4288.120935208275, 4301.354796780445, 4318.395424640553, 4420.535843736361, 1788.6512130134759, 1801.4775715042754, 1801.7207332863165, 1813.4650278167971, 1819.1631849349446, 799.2207597592301, 950.273041123541, 963.2775418436173, 1033.679559336608, 1040.0275742181084, 15712.68574628501, 15824.208789560476, 15982.680346393006, 16419.096949667644, 16431.424900128677, 875.2210532625841, 876.8903887014123, 890.955452227386, 906.7189171356769, 1017.7710015246511, 9054.411463254868, 9457.959624441306, 9467.479288639053, 9470.0, 9538.284628652878, 493181.0, 510267.0, 513809.0, 516313.11338536645, 520809.0, 74953.0, 76329.61835190789, 80175.19824801678, 81746.59126704447, 83547.16452835394, 1109.6504601050642, 1111.344306858957, 1114.3481063577897, 1124.241137232365, 1154.4054811771944, 14508.0, 15504.0, 15677.294521746777, 15748.60628484297, 16035.118068506345, 19511.414657560297, 32623.836880815445, 33133.83433082916, 33232.33535329328, 33497.16257093592, 42074.1585168301, 45139.0, 45308.0, 46259.537404625524, 46542.767286163355, 9519.857202142111, 9658.806823863342, 9675.854862176931, 9733.61065557398, 9736.75658108553, 11350.148738844455, 16850.1574921251, 16963.83036169672, 17507.21217545188, 17520.123993800004, 14040.929795350958, 14263.572092837232, 14487.85512144865, 14707.852921470649, 14725.337359818619, 13873.930630346784, 13963.441462341392, 14184.787228191792, 14500.564983050524, 14626.341814618156, 14023.158610483404, 14123.2938353084, 14545.490907818175, 14633.560993170222, 14908.18005009735, 9315.72052838416, 9322.067793220624, 9399.295052870926, 9508.477033763209, 9547.856782148412, 10304.948475257575, 10312.381257124596, 10878.0, 15223.0, 15388.538343849703], "unit": "ms", "name": "jquery.html", "value": 8617.168642914974}, {"lowerIsBetter": false, "replicates": [1499.3627708224003, 1734.089602958447, 1775.8934463932158, 1790.4449620617609, 1800.9909950450249, 7780.494267872585, 7924.722634707772, 8000.239977202164, 8040.517568945867, 8176.059753128377, 8120.187981201877, 8134.023917129938, 8181.7136400226, 8187.345012399009, 8200.876986845187, 2252.999999999996, 2279.658051292303, 2282.9771702283015, 2284.4060544258446, 2285.6228722260807, 2285.48576570272, 2307.065638416436, 2322.7096612923383, 2322.779335963081, 2325.03511042917, 2257.0294773247497, 2257.119723307911, 2279.8290128240365, 2298.0807676929194, 2299.5171014087064, 2264.3772962435296, 2268.3081660093667, 2284.8286378521593, 2285.4743409015937, 2289.095807156164, 2325.7906788388955, 2335.4628435459767, 2342.8945697443655, 2343.718753749544, 2350.318407661776, 7822.921770782333, 7885.408594355446, 7899.526028438255, 7904.328132108778, 7919.128895821455, 4604.930926036112, 4641.396618439581, 4681.625469962395, 4688.038952014829, 4699.224627936421, 2929.4434057529215, 2941.7352438280445, 2974.2713035306274, 2997.610310659622, 3023.3650933304034, 931.9627214911463, 953.0802775321848, 956.0773853231595, 967.5404183013083, 977.5942983661772, 10856.620018299322, 10903.400312982783, 10932.0, 10955.068819150301, 10962.835557466644, 4129.8967525811495, 4138.234426631083, 4179.623833854967, 4193.266178418765, 4276.700630955803, 8936.24041956441, 8938.10618938114, 8969.50667713282, 8986.05646407131, 8990.280777537911, 4745.620350371962, 4766.094442055998, 4772.093302272557, 4776.307435421844, 4794.184988551954, 4305.16049370376, 4332.891677708082, 4338.848140315074, 4349.847755328612, 4405.7797110144365, 3478.7564870458823, 3481.3385456763135, 3508.7017603503987, 3547.3260080584605, 3563.5367402237544, 1092.7759809239087, 1092.9781404371868, 1114.2868564118971, 1114.7436089699415, 1115.5091759625739, 1077.9407132607625, 1086.5273605981424, 1087.7498175419698, 1212.7028877924965, 1300.3888172558882, 7409.333160015624, 7440.776776696708, 7540.773776786705, 7544.547327160388, 7560.924390756132, 502.3971234518592, 503.30543849487475, 509.93625796775405, 510.1531457780067, 511.7211119939642, 5873.177755114288, 5894.086416605433, 5908.438698323653, 5930.673812940244, 5944.167816505693, 9133.360664753403, 9288.164065234161, 9418.670346537838, 9501.334906556613, 9651.420914745138, 9331.440113593206, 9339.112784285482, 9359.485228312507, 9369.718908432758, 9394.624215031323, 7303.123625165014, 7305.5981920994855, 7413.962930185422, 7436.0, 7462.291082347168, 7190.568565886064, 7199.928000719926, 7214.242504537158, 7217.097862767154, 7220.819479513054, 8184.508929464251, 8332.916670833214, 8467.407281490237, 8538.359623028173, 8559.61481733336, 8426.620802064044, 8458.0, 8495.74512764618, 8597.570121493774, 8635.611397487002, 6695.665216739046, 6756.628385438651, 6899.206591242071, 6973.895391569232, 7002.2647622000995, 7680.423968202295, 7681.731139410093, 7701.92298077012, 7739.071311442438, 7748.57382843949, 7105.32499412555, 7115.324044215791, 7248.275172482709, 7308.744193953186, 7359.190489046094, 6984.441244700513, 7023.719051237893, 7027.4729395294535, 7087.114110736158, 7088.964555177191, 7161.4986950912935, 7204.207537170802, 7242.96378518104, 7244.203137654748, 7281.05346304977, 6625.701843416961, 6667.033280174251, 6673.432758215606, 6679.966600166968, 6698.799036028927, 6495.870082598416, 6540.182477190351, 6584.209894812461, 6597.50518711089, 6606.735730570724], "unit": "ms", "name": "mootools.html", "value": 4428.890449231109}, {"lowerIsBetter": false, "replicates": [328.96874796894315, 332.42822345565617, 333.77636983221237, 342.78062040294213, 343.83152255394856, 6225.221847269091, 6265.749370025199, 6301.401366870152, 6333.461655759254, 6342.7462901484005, 6160.1375807387, 6203.534734894878, 6208.968955155229, 6218.284897236819, 6277.246730392348, 4769.427668679746, 4782.234842425212, 4790.329353890449, 4803.63972702047, 4821.397325334333, 4814.951850481503, 4818.108649899762, 4820.204666230085, 4833.3233347331225, 4845.200541910598, 4683.180443422404, 4779.091972525226, 4783.521647835206, 4794.304825800275, 4795.712257264558, 4748.145333839908, 4766.642501812361, 4771.236602143657, 4774.116788394123, 4797.95202047982, 4174.895627609304, 4179.665626749853, 4183.874483765492, 4184.351425529047, 4193.643540299078, 5977.701114944235, 5981.611195272337, 5983.730732117066, 5988.610740301867, 6004.579679422399, 5423.430539793304, 5472.699001554913, 5494.945050549483, 5499.890002199973, 5570.916436253459, 6626.469882409396, 6647.900281495733, 6650.800475985729, 6658.600483970978, 6666.166729158856, 4574.062317224961, 4590.770461476946, 4598.425196850394, 4598.563136502061, 4602.0, 6725.66371681414, 6857.211420686635, 6892.862142757167, 6898.172219333712, 6932.792016239521, 4740.905181896412, 4747.240441529339, 4748.715077095385, 4761.166795810719, 4768.237082066922, 4799.736014519235, 4806.447258565309, 4812.374391329105, 4818.590419814285, 4825.372701548777, 5552.444755524496, 5564.527015203753, 5566.860828479321, 5567.638103523258, 5581.246531718247, 6545.8363540910905, 6625.436837868835, 6664.333566643394, 6668.199816022108, 6719.697613607399, 1604.0936870668268, 1650.2821272746394, 1651.3972400073887, 1652.1326303690419, 1681.7813684220973, 1060.1042119409087, 1104.6851647280648, 1106.4246591772232, 1106.7509810292781, 1113.059464752283, 6411.326810684905, 6443.96778016107, 6444.065610486547, 6444.9355506444335, 6452.838489071918, 6845.520813543004, 6859.828504287433, 6867.038614593961, 6872.484563657746, 6883.621400822903, 6337.588056776295, 6374.649394283359, 6410.0705397717065, 6422.614643121427, 6431.163948686641, 3736.6076561961145, 3797.5442946846, 3812.4090765931323, 3815.3323168445413, 3818.0836599215986, 7263.128424589083, 7264.455165862476, 7265.200827908926, 7265.309795569412, 7275.927240727631, 7018.33325834045, 7162.4628152888745, 7179.1026121734785, 7184.281571842774, 7188.784336469915, 7163.498555101197, 7228.819279518054, 7230.987661727362, 7249.456290778107, 7270.200277969419, 6927.93072069293, 6933.098697169537, 6936.79189624312, 6938.479614029069, 6952.374286314053, 6768.153980752406, 6892.724291028303, 6917.100776898971, 6932.410745086724, 6944.687489063105, 7195.280471952763, 7237.457190710613, 7239.927600724136, 7240.52936559122, 7241.818954526179, 4684.718916864999, 4722.787474563722, 4727.62179025684, 4728.527147285382, 4732.929006064981, 6838.179418469616, 6949.409300209539, 6964.199117101395, 6981.02265682815, 6981.162260528566, 6755.2231493378895, 6760.019797129487, 6761.864762704817, 6772.7290908365085, 6775.728970841111, 6747.122874026345, 6753.662316884234, 6766.289539598468, 6777.525573210025, 6795.320467953363, 6489.448396886318, 6630.270670226368, 6632.038354438483, 6637.834054148685, 6657.633830139389, 6790.0, 6792.93207067923, 6803.081583986099, 6812.318768123347, 6814.4548436124, 6479.481641468766, 6574.309697481695, 6628.668566571749, 6631.071649969104, 6636.0], "unit": "ms", "name": "prototype.html", "value": 5036.996351643793}, {"lowerIsBetter": false, "replicates": [698.231944860653, 724.6738967464639, 755.3013462547143, 824.6989848705223, 825.2490233887164, 12861.5498457554, 13282.203067815926, 13284.601461956132, 13380.598582042541, 13425.0, 12066.517339306418, 12349.814752778715, 12356.382180890965, 12411.069169812255, 12413.503459861595, 814.7107776739276, 822.411975437562, 829.6888666749969, 842.296682270305, 846.3990566697652, 845.4082142500274, 847.8134810341746, 848.6478111583685, 848.6817443458702, 850.863861782115, 869.1178453869328, 875.544716747291, 877.627008521379, 879.4371602174614, 881.9823603527925, 868.847951608466, 870.24723614074, 870.3037569944051, 870.4777133719781, 871.808202195516, 642.6979319719771, 642.7107801489302, 645.399778206268, 645.586824432359, 645.7610684046886, 12901.612951611467, 13081.149725267827, 13102.606921792267, 13141.86858131416, 13170.86829131706, 11072.833907491395, 11883.762324753456, 11889.524419023315, 11895.583654572136, 11922.10584206188, 9533.0, 9704.417734935927, 9745.31782775206, 9933.205343572568, 9945.651902183461, 3466.4626982817704, 3469.132716820795, 3509.982450087733, 3516.8241587920757, 3518.595361533405, 51782.48217517814, 51867.740661296455, 53068.73465632686, 53149.202761958986, 53197.0, 8825.470471771714, 8980.55097245135, 8984.730458086267, 9087.863682044775, 9092.27261819053, 18445.53886152857, 18507.167177477048, 18525.258989640533, 18559.164837582342, 18585.34951276699, 7173.10336207974, 7372.336489715952, 7432.368248698921, 7588.582627955516, 7716.112647045662, 4079.632833044981, 4167.395727619478, 4204.285271503852, 4276.786160691953, 4430.379746835446, 3077.98461007698, 3086.213015680987, 3088.922776930595, 3103.953440698391, 3109.689031096872, 541.5261646059698, 557.9733290744967, 558.891016251827, 569.0440060697985, 569.9658020518782, 453.4898239480584, 498.2028753993581, 533.3226801961487, 639.0984928424899, 653.6437641485402, 7538.359239464707, 7549.6225188740345, 7667.309942105126, 7848.882266766003, 7931.0, 512.6155383462403, 533.1602725707027, 547.6221407228999, 564.350996354196, 633.5818359882455, 3654.232611151635, 3720.739548231652, 3832.559255685576, 3979.5025621797276, 3997.8600748973645, 58786.70606646939, 60333.17334613221, 60339.0, 60382.0, 60400.39599604036, 53122.468775312525, 53427.19859202115, 53730.0, 53878.191827122624, 54122.72938635282, 48479.0, 48830.0, 48954.26568601474, 49558.0, 49895.0, 7973.784275522727, 8208.261256486707, 8234.382421318545, 8265.669373225004, 8278.612097526262, 36011.819940900124, 36427.27145457023, 36495.81752091329, 36822.44766328454, 37088.81455592705, 41482.58517414787, 43130.353044704985, 43860.342094869244, 45231.54768452405, 45880.31179532243, 10044.146247569039, 10153.0, 10349.120324772479, 10474.68575942723, 10572.682819515427, 11230.887691122984, 11337.716557086138, 11350.0, 11475.483603237708, 11926.403679816149, 10380.169586433218, 10511.369317840954, 10571.894281057092, 10592.841107383549, 10616.575336986434, 10232.846507302247, 10244.282900196915, 10363.11913487332, 10591.311564748266, 10606.098481629147, 10201.591936322464, 10559.736006599589, 10681.0, 10795.56817727282, 10983.231173817756, 9231.676891308773, 9310.115539023782, 9348.53257337144, 9420.811583768149, 9633.614655413985, 8219.383546233938, 8536.573171341532, 8697.434666746642, 9124.635014599342, 9173.954130229306], "unit": "ms", "name": "yui.html", "value": 6140.949967689547}], "name": "dromaeo_css", "value": 7020.333514823052}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 105.0, 111.0, 112.0, 123.0, 123.0, 112.0, 111.0, 111.0, 112.0], "unit": "ms", "name": "ai-astar", "value": 113.2}, {"lowerIsBetter": true, "replicates": [150.0, 129.0, 161.0, 157.0, 129.0, 150.0, 156.0, 125.0, 160.0, 128.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.5}, {"lowerIsBetter": true, "replicates": [163.0, 162.0, 161.0, 163.0, 161.0, 165.0, 160.0, 162.0, 170.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 163.0}, {"lowerIsBetter": true, "replicates": [74.0, 72.0, 73.0, 72.0, 74.0, 141.0, 72.0, 77.0, 73.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 79.9}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 144.0, 94.0, 93.0, 94.0, 139.0, 92.0, 93.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 107.0}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 110.0, 110.0, 111.0, 111.0, 110.0, 110.0, 111.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.4}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 147.0, 149.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.0}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 97.0, 96.0, 96.0, 96.0, 96.0, 97.0, 97.0, 98.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.7}, {"lowerIsBetter": true, "replicates": [63.0, 61.0, 62.0, 61.0, 63.0, 63.0, 63.0, 61.0, 61.0, 59.0], "unit": "ms", "name": "json-parse-financial", "value": 61.7}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 70.0, 71.0, 71.0, 72.0, 71.0, 72.0, 71.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 71.0}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 86.0, 87.0, 88.0, 84.0, 93.0, 85.0, 86.0, 94.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 87.0}, {"lowerIsBetter": true, "replicates": [130.0, 114.0, 121.0, 122.0, 119.0, 125.0, 129.0, 115.0, 132.0, 114.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 122.1}, {"lowerIsBetter": true, "replicates": [151.0, 156.0, 148.0, 153.0, 149.0, 149.0, 151.0, 139.0, 150.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.5}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 66.0, 66.0, 67.0, 67.0, 66.0, 68.0, 66.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.5}], "name": "kraken", "value": 1519.5}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.86133822793258], "unit": "score", "name": "Richards", "value": 211.56488599999994}, {"lowerIsBetter": false, "replicates": [187.75816748028538], "unit": "score", "name": "DeltaBlue", "value": 352.14446799999996}, {"lowerIsBetter": false, "replicates": [279.4857462269424], "unit": "score", "name": "Encrypt", "value": 952.3956179999999}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [1034.126163391934], "unit": "score", "name": "RayTrace", "value": 715.5693630000001}, {"lowerIsBetter": false, "replicates": [694.9270326615705], "unit": "score", "name": "Earley", "value": 959.0402569999998}, {"lowerIsBetter": false, "replicates": [12228.915662650603], "unit": "score", "name": "Boyer", "value": 54.498944827586165}, {"lowerIsBetter": false, "replicates": [24780.48780487805], "unit": "score", "name": "RegExp", "value": 36.7621899606299}, {"lowerIsBetter": false, "replicates": [686.8131868131868], "unit": "score", "name": "Splay", "value": 118.65089600000005}, {"lowerIsBetter": false, "replicates": [4878.048780487805], "unit": "score", "name": "NavierStokes", "value": 304.2200000000002}], "name": "v8_7", "value": 21260.76486314256}]} 05:45:51 INFO - RETURN: dromaeo_css: 7020.33 05:45:51 INFO - RETURN: kraken: 1519.50 05:45:51 INFO - RETURN: v8_7: 21260.76 05:45:51 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21260.76"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1519.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "7020.33"}}} 05:45:51 INFO - Return code: 0 05:45:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:45:51 INFO - # TBPL SUCCESS # 05:45:51 INFO - Running post-action listener: _resource_record_post_action 05:45:51 INFO - Running post-run listener: _resource_record_post_run 05:45:52 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 4096; Write bytes: 511766528; Read time: 16; Write time: 379656 05:45:52 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 164970496; Read time: 0; Write time: 199988 05:45:52 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 4096; Write bytes: 275800064; Read time: 16; Write time: 119988 05:45:52 INFO - Running post-run listener: _upload_blobber_files 05:45:52 INFO - Blob upload gear active. 05:45:52 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:45:52 INFO - Copying logs to upload dir... 05:45:52 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1266.065504 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-11-26 05:45:52.400276) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:52.403793) ========= 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=f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010172 build_url: 'https://queue.taskcluster.net/v1/task/9dmDTOjHSimAMxcUnF7awQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:52.455716) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:52.456073) ========= 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=f5f5a079460e1a9be8676904000001c1-1448544086.604467-1701846021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004523 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:45:52.506158) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 05:45:52.506488) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 05:45:52.506794) ========= ========= Total master_lag: 0.25 =========