builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1448538951.15 results: success (0) buildid: 20151126023023 builduid: ce9dd20f0fda4214967006d0a6abe6ea revision: fc6459ef44a46187cfa5f38f4e4e157940de58a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.146325) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.146778) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.147136) ========= 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=6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005728 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.164131) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.164433) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.172944) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.173241) ========= 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=6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004092 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.221319) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.221679) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.222062) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.222385) ========= 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=6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-26 03:55:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.9M=0s 2015-11-26 03:55:52 (41.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.188481 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.423130) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.423549) ========= 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=6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015469 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.473030) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:51.473371) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fc6459ef44a46187cfa5f38f4e4e157940de58a5 --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 fc6459ef44a46187cfa5f38f4e4e157940de58a5 --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=6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-26 03:55:52,282 truncating revision to first 12 chars 2015-11-26 03:55:52,282 Setting DEBUG logging. 2015-11-26 03:55:52,282 attempt 1/10 2015-11-26 03:55:52,282 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fc6459ef44a4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-26 03:55:52,533 unpacking tar archive at: fx-team-fc6459ef44a4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.518444 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:52.028870) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:52.029321) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:52.041040) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:52.041427) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 03:55:52.041895) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2015-11-26 03:55:52.042200) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:55:52 INFO - MultiFileLogger online at 20151126 03:55:52 in /builds/slave/test-pgo 03:55:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 03:55:52 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 03:55:52 INFO - {'append_to_log': False, 03:55:52 INFO - 'base_work_dir': '/builds/slave/test-pgo', 03:55:52 INFO - 'blob_upload_branch': 'Fx-Team', 03:55:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 03:55:52 INFO - 'branch': 'Fx-Team', 03:55:52 INFO - 'buildbot_json_path': 'buildprops.json', 03:55:52 INFO - 'config_files': ('talos/linux_config.py',), 03:55:52 INFO - 'default_actions': ('clobber', 03:55:52 INFO - 'read-buildbot-config', 03:55:52 INFO - 'download-and-extract', 03:55:52 INFO - 'populate-webroot', 03:55:52 INFO - 'create-virtualenv', 03:55:52 INFO - 'install', 03:55:52 INFO - 'run-tests'), 03:55:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:55:52 INFO - 'download_minidump_stackwalk': True, 03:55:52 INFO - 'download_symbols': 'ondemand', 03:55:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:55:52 INFO - 'tooltool.py': '/tools/tooltool.py', 03:55:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:55:52 INFO - '/tools/misc-python/virtualenv.py')}, 03:55:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:55:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:55:52 INFO - 'installer_path': 'installer.exe', 03:55:52 INFO - 'log_level': 'info', 03:55:52 INFO - 'log_name': 'talos', 03:55:52 INFO - 'log_to_console': True, 03:55:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:55:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:55:52 INFO - 'opt_config_files': (), 03:55:52 INFO - 'pip_index': False, 03:55:52 INFO - 'sps_profile': False, 03:55:52 INFO - 'sps_profile_interval': 0, 03:55:52 INFO - 'suite': 'dromaeojs', 03:55:52 INFO - 'system_bits': '64', 03:55:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:55:52 INFO - 'title': 'talos-linux64-ix-017', 03:55:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:55:52 INFO - 'use_talos_json': True, 03:55:52 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 03:55:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:55:52 INFO - 'work_dir': 'build'} 03:55:52 INFO - ##### 03:55:52 INFO - ##### Running clobber step. 03:55:52 INFO - ##### 03:55:52 INFO - Running pre-action listener: _resource_record_pre_action 03:55:52 INFO - Running main action method: clobber 03:55:52 INFO - rmtree: /builds/slave/test-pgo/build 03:55:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 03:55:53 INFO - Running post-action listener: _resource_record_post_action 03:55:53 INFO - ##### 03:55:53 INFO - ##### Running read-buildbot-config step. 03:55:53 INFO - ##### 03:55:53 INFO - Running pre-action listener: _resource_record_pre_action 03:55:53 INFO - Running main action method: read_buildbot_config 03:55:53 INFO - Using buildbot properties: 03:55:53 INFO - { 03:55:53 INFO - "properties": { 03:55:53 INFO - "buildnumber": 804, 03:55:53 INFO - "product": "firefox", 03:55:53 INFO - "script_repo_revision": "production", 03:55:53 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 03:55:53 INFO - "repository": "", 03:55:53 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 03:55:53 INFO - "buildid": "20151126023023", 03:55:53 INFO - "slavename": "talos-linux64-ix-017", 03:55:53 INFO - "pgo_build": "True", 03:55:53 INFO - "basedir": "/builds/slave/test-pgo", 03:55:53 INFO - "project": "", 03:55:53 INFO - "platform": "ubuntu64_hw", 03:55:53 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:55:53 INFO - "slavebuilddir": "test", 03:55:53 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 03:55:53 INFO - "branch": "fx-team", 03:55:53 INFO - "repo_path": "integration/fx-team", 03:55:53 INFO - "stage_platform": "linux64-pgo", 03:55:53 INFO - "builduid": "ce9dd20f0fda4214967006d0a6abe6ea", 03:55:53 INFO - "revision": "fc6459ef44a46187cfa5f38f4e4e157940de58a5" 03:55:53 INFO - }, 03:55:53 INFO - "sourcestamp": { 03:55:53 INFO - "repository": "", 03:55:53 INFO - "hasPatch": false, 03:55:53 INFO - "project": "", 03:55:53 INFO - "branch": "fx-team-linux64-pgo-talos", 03:55:53 INFO - "changes": [ 03:55:53 INFO - { 03:55:53 INFO - "category": null, 03:55:53 INFO - "files": [ 03:55:53 INFO - { 03:55:53 INFO - "url": null, 03:55:53 INFO - "name": "https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 03:55:53 INFO - } 03:55:53 INFO - ], 03:55:53 INFO - "repository": "", 03:55:53 INFO - "rev": "fc6459ef44a46187cfa5f38f4e4e157940de58a5", 03:55:53 INFO - "who": "sendchange", 03:55:53 INFO - "when": 1448538663, 03:55:53 INFO - "number": 6710237, 03:55:53 INFO - "comments": "", 03:55:53 INFO - "project": "", 03:55:53 INFO - "at": "Thu 26 Nov 2015 03:51:03", 03:55:53 INFO - "branch": "fx-team-linux64-pgo-talos", 03:55:53 INFO - "revlink": "", 03:55:53 INFO - "properties": [ 03:55:53 INFO - [ 03:55:53 INFO - "buildid", 03:55:53 INFO - "20151126023023", 03:55:53 INFO - "Change" 03:55:53 INFO - ], 03:55:53 INFO - [ 03:55:53 INFO - "builduid", 03:55:53 INFO - "ce9dd20f0fda4214967006d0a6abe6ea", 03:55:53 INFO - "Change" 03:55:53 INFO - ], 03:55:53 INFO - [ 03:55:53 INFO - "pgo_build", 03:55:53 INFO - "True", 03:55:53 INFO - "Change" 03:55:53 INFO - ] 03:55:53 INFO - ], 03:55:53 INFO - "revision": "fc6459ef44a46187cfa5f38f4e4e157940de58a5" 03:55:53 INFO - } 03:55:53 INFO - ], 03:55:53 INFO - "revision": "fc6459ef44a46187cfa5f38f4e4e157940de58a5" 03:55:53 INFO - } 03:55:53 INFO - } 03:55:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 03:55:53 INFO - Running post-action listener: _resource_record_post_action 03:55:53 INFO - ##### 03:55:53 INFO - ##### Running download-and-extract step. 03:55:53 INFO - ##### 03:55:53 INFO - Running pre-action listener: _resource_record_pre_action 03:55:53 INFO - Running main action method: download_and_extract 03:55:53 INFO - mkdir: /builds/slave/test-pgo/build/tests 03:55:53 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')]} 03:55:53 INFO - https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:55:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/test_packages.json 03:55:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/test_packages.json 03:55:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 03:55:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 03:55:54 INFO - Downloaded 1302 bytes. 03:55:54 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 03:55:54 INFO - Using the following test package requirements: 03:55:54 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:55:54 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:55:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:55:54 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:55:54 INFO - u'jsshell-linux-x86_64.zip'], 03:55:54 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:55:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:55:54 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:55:54 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:55:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:55:54 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:55:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:55:54 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:55:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:55:54 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:55:54 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:55:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:55:54 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:55:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:55:54 INFO - https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:55:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:55:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:55:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/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 03:55:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/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 03:55:55 INFO - Downloaded 22753925 bytes. 03:55:55 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 03:55:55 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:55:55 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 03:55:56 INFO - Return code: 0 03:55:56 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 03:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:55:56 INFO - https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:55:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:55:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:55:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/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 03:55:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/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 03:55:57 INFO - Downloaded 22753925 bytes. 03:55:57 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 03:55:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:55:57 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 03:55:59 INFO - Return code: 0 03:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:55:59 INFO - https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:55:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:55:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:55:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/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 03:55:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/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 03:56:00 INFO - Downloaded 11131717 bytes. 03:56:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 03:56:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:56:00 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 03:56:00 INFO - Return code: 0 03:56: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')]} 03:56:00 INFO - https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:56:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:56:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:56:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/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 03:56:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXW5IbiSSOqycTytqzbjlQ/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 03:56:01 INFO - Downloaded 58393569 bytes. 03:56:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:56:01 INFO - mkdir: /builds/slave/test-pgo/properties 03:56:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 03:56:01 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 03:56:01 INFO - Contents: 03:56:01 INFO - build_url:https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:56:01 INFO - Running post-action listener: _resource_record_post_action 03:56:01 INFO - Running post-action listener: set_extra_try_arguments 03:56:01 INFO - ##### 03:56:01 INFO - ##### Running populate-webroot step. 03:56:01 INFO - ##### 03:56:01 INFO - Running pre-action listener: _resource_record_pre_action 03:56:01 INFO - Running main action method: populate_webroot 03:56:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:56:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:56:01 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 03:56:01 INFO - '../fennec_ids.txt'], 03:56:01 INFO - 'tests': ['tcheck2']}, 03:56:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:56:01 INFO - '--tppagecycles', 03:56:01 INFO - '7'], 03:56:01 INFO - 'tests': ['tsvgm']}}, 03:56:01 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:56:01 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:56:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:56:01 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:56:01 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:56:01 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:56:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:01 INFO - 'talos_options': ['--e10s'], 03:56:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:01 INFO - 'tests': ['damp', 'tps']}, 03:56:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:01 INFO - 'talos_options': ['--e10s'], 03:56:01 INFO - 'tests': ['damp', 'tps']}, 03:56:01 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:56:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:56:01 INFO - 'other': {'tests': ['a11yr', 03:56:01 INFO - 'ts_paint', 03:56:01 INFO - 'tpaint', 03:56:01 INFO - 'sessionrestore', 03:56:01 INFO - 'sessionrestore_no_auto_restore']}, 03:56:01 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:56:01 INFO - 'tests': ['a11yr', 03:56:01 INFO - 'ts_paint', 03:56:01 INFO - 'tpaint', 03:56:01 INFO - 'sessionrestore', 03:56:01 INFO - 'sessionrestore_no_auto_restore']}, 03:56:01 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:56:01 INFO - 'tests': ['a11yr', 03:56:01 INFO - 'ts_paint', 03:56:01 INFO - 'tpaint', 03:56:01 INFO - 'sessionrestore', 03:56:01 INFO - 'sessionrestore_no_auto_restore']}, 03:56:01 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:56:01 INFO - 'tests': ['a11yr', 03:56:01 INFO - 'ts_paint', 03:56:01 INFO - 'tpaint', 03:56:01 INFO - 'sessionrestore', 03:56:01 INFO - 'sessionrestore_no_auto_restore']}, 03:56:01 INFO - 'other_l64': {'tests': ['a11yr', 03:56:01 INFO - 'ts_paint', 03:56:01 INFO - 'tpaint', 03:56:01 INFO - 'sessionrestore', 03:56:01 INFO - 'sessionrestore_no_auto_restore']}, 03:56:01 INFO - 'other_nol64': {'tests': ['a11yr', 03:56:01 INFO - 'ts_paint', 03:56:01 INFO - 'tpaint', 03:56:01 INFO - 'sessionrestore', 03:56:01 INFO - 'sessionrestore_no_auto_restore']}, 03:56:01 INFO - 'svgr': {'tests': ['tsvgx', 03:56:01 INFO - 'tsvgr_opacity', 03:56:01 INFO - 'tart', 03:56:01 INFO - 'tscrollx', 03:56:01 INFO - 'cart']}, 03:56:01 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:56:01 INFO - 'tests': ['tsvgx', 03:56:01 INFO - 'tsvgr_opacity', 03:56:01 INFO - 'tart', 03:56:01 INFO - 'tscrollx', 03:56:01 INFO - 'cart']}, 03:56:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:01 INFO - 'tests': ['tp5o']}, 03:56:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:01 INFO - 'talos_options': ['--e10s'], 03:56:01 INFO - 'tests': ['tp5o']}, 03:56:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:01 INFO - 'talos_options': ['--xperf_path', 03:56:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:01 INFO - 'tests': ['tp5n']}, 03:56:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:01 INFO - 'talos_options': ['--e10s', 03:56:01 INFO - '--xperf_path', 03:56:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:01 INFO - 'tests': ['tp5n']}}, 03:56:01 INFO - 'talos.zip': {'path': '', 03:56:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:56:01 INFO - Running post-action listener: _resource_record_post_action 03:56:01 INFO - ##### 03:56:01 INFO - ##### Running create-virtualenv step. 03:56:01 INFO - ##### 03:56:01 INFO - Running pre-action listener: _resource_record_pre_action 03:56:01 INFO - Running main action method: create_virtualenv 03:56:01 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 03:56:01 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 03:56:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 03:56:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:56:01 INFO - Using real prefix '/usr' 03:56:01 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 03:56:02 INFO - Installing distribute.............................................................................................................................................................................................done. 03:56:04 INFO - Installing pip.................done. 03:56:04 INFO - Return code: 0 03:56:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 03:56: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')]} 03:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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')]} 03:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:04 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 0x2179030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2435640>, '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 0x2436540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24369d0>, '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': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', '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 03:56:04 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 03:56:04 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 03:56:04 INFO - Using env: {'DISPLAY': ':0', 03:56:04 INFO - 'HOME': '/home/cltbld', 03:56:04 INFO - 'LANG': 'en_US.UTF-8', 03:56:04 INFO - 'LANGUAGE': 'en_US:en', 03:56:04 INFO - 'LOGNAME': 'cltbld', 03:56:04 INFO - 'MAIL': '/var/mail/cltbld', 03:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:04 INFO - 'MOZ_NO_REMOTE': '1', 03:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:04 INFO - 'NO_EM_RESTART': '1', 03:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:56:04 INFO - 'PWD': '/builds/slave/test-pgo', 03:56:04 INFO - 'SHELL': '/bin/bash', 03:56:04 INFO - 'SHLVL': '1', 03:56:04 INFO - 'TERM': 'linux', 03:56:04 INFO - 'TMOUT': '86400', 03:56:04 INFO - 'USER': 'cltbld', 03:56:04 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', 03:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:04 INFO - '_': '/tools/buildbot/bin/python'} 03:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:04 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 03:56:04 INFO - Cleaning up... 03:56:04 INFO - Return code: 0 03:56:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 03:56: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')]} 03:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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')]} 03:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:04 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 0x2179030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2435640>, '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 0x2436540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24369d0>, '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': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', '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 03:56:04 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 03:56:04 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 03:56:04 INFO - Using env: {'DISPLAY': ':0', 03:56:04 INFO - 'HOME': '/home/cltbld', 03:56:04 INFO - 'LANG': 'en_US.UTF-8', 03:56:04 INFO - 'LANGUAGE': 'en_US:en', 03:56:04 INFO - 'LOGNAME': 'cltbld', 03:56:04 INFO - 'MAIL': '/var/mail/cltbld', 03:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:04 INFO - 'MOZ_NO_REMOTE': '1', 03:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:04 INFO - 'NO_EM_RESTART': '1', 03:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:56:04 INFO - 'PWD': '/builds/slave/test-pgo', 03:56:04 INFO - 'SHELL': '/bin/bash', 03:56:04 INFO - 'SHLVL': '1', 03:56:04 INFO - 'TERM': 'linux', 03:56:04 INFO - 'TMOUT': '86400', 03:56:04 INFO - 'USER': 'cltbld', 03:56:04 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', 03:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:04 INFO - '_': '/tools/buildbot/bin/python'} 03:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:04 INFO - Downloading/unpacking psutil>=0.7.1 03:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:06 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 03:56:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 03:56:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 03:56:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:06 INFO - Installing collected packages: psutil 03:56:06 INFO - Running setup.py install for psutil 03:56:06 INFO - building 'psutil._psutil_linux' extension 03:56:06 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 03:56:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:56:07 INFO - building 'psutil._psutil_posix' extension 03:56:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:56:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:56:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:07 INFO - Successfully installed psutil 03:56:07 INFO - Cleaning up... 03:56:07 INFO - Return code: 0 03:56:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 03:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2179030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2435640>, '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 0x2436540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24369d0>, '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': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', '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 03:56:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 03:56:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:56:07 INFO - Using env: {'DISPLAY': ':0', 03:56:07 INFO - 'HOME': '/home/cltbld', 03:56:07 INFO - 'LANG': 'en_US.UTF-8', 03:56:07 INFO - 'LANGUAGE': 'en_US:en', 03:56:07 INFO - 'LOGNAME': 'cltbld', 03:56:07 INFO - 'MAIL': '/var/mail/cltbld', 03:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:07 INFO - 'MOZ_NO_REMOTE': '1', 03:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:07 INFO - 'NO_EM_RESTART': '1', 03:56:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:56:07 INFO - 'PWD': '/builds/slave/test-pgo', 03:56:07 INFO - 'SHELL': '/bin/bash', 03:56:07 INFO - 'SHLVL': '1', 03:56:07 INFO - 'TERM': 'linux', 03:56:07 INFO - 'TMOUT': '86400', 03:56:07 INFO - 'USER': 'cltbld', 03:56:07 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', 03:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:07 INFO - '_': '/tools/buildbot/bin/python'} 03:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:56:07 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 03:56:07 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 03:56:07 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 03:56:07 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 03:56:07 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 03:56:07 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 03:56:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:56:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 03:56:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:56:09 INFO - Installing collected packages: mozsystemmonitor 03:56:09 INFO - Running setup.py install for mozsystemmonitor 03:56:09 INFO - Successfully installed mozsystemmonitor 03:56:09 INFO - Cleaning up... 03:56:09 INFO - Return code: 0 03:56:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 03:56: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')]} 03:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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')]} 03:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2179030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2435640>, '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 0x2436540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24369d0>, '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': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', '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 03:56:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 03:56:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:56:09 INFO - Using env: {'DISPLAY': ':0', 03:56:09 INFO - 'HOME': '/home/cltbld', 03:56:09 INFO - 'LANG': 'en_US.UTF-8', 03:56:09 INFO - 'LANGUAGE': 'en_US:en', 03:56:09 INFO - 'LOGNAME': 'cltbld', 03:56:09 INFO - 'MAIL': '/var/mail/cltbld', 03:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:09 INFO - 'MOZ_NO_REMOTE': '1', 03:56:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:09 INFO - 'NO_EM_RESTART': '1', 03:56:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:56:09 INFO - 'PWD': '/builds/slave/test-pgo', 03:56:09 INFO - 'SHELL': '/bin/bash', 03:56:09 INFO - 'SHLVL': '1', 03:56:09 INFO - 'TERM': 'linux', 03:56:09 INFO - 'TMOUT': '86400', 03:56:09 INFO - 'USER': 'cltbld', 03:56:09 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', 03:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:09 INFO - '_': '/tools/buildbot/bin/python'} 03:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:09 INFO - Downloading/unpacking blobuploader==1.2.4 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56:11 INFO - Downloading blobuploader-1.2.4.tar.gz 03:56:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 03:56:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:56:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 03:56:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 03:56:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56:12 INFO - Downloading docopt-0.6.1.tar.gz 03:56:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 03:56:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 03:56:12 INFO - Installing collected packages: blobuploader, requests, docopt 03:56:12 INFO - Running setup.py install for blobuploader 03:56:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:56:12 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 03:56:12 INFO - Running setup.py install for requests 03:56:12 INFO - Running setup.py install for docopt 03:56:12 INFO - Successfully installed blobuploader requests docopt 03:56:12 INFO - Cleaning up... 03:56:12 INFO - Return code: 0 03:56:12 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:56:12 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')]} 03:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:12 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')]} 03:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:12 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 0x2179030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2435640>, '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 0x2436540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24369d0>, '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': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', '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 03:56:12 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 03:56:12 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 03:56:12 INFO - Using env: {'DISPLAY': ':0', 03:56:12 INFO - 'HOME': '/home/cltbld', 03:56:12 INFO - 'LANG': 'en_US.UTF-8', 03:56:12 INFO - 'LANGUAGE': 'en_US:en', 03:56:12 INFO - 'LOGNAME': 'cltbld', 03:56:12 INFO - 'MAIL': '/var/mail/cltbld', 03:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:12 INFO - 'MOZ_NO_REMOTE': '1', 03:56:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:12 INFO - 'NO_EM_RESTART': '1', 03:56:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:56:12 INFO - 'PWD': '/builds/slave/test-pgo', 03:56:12 INFO - 'SHELL': '/bin/bash', 03:56:12 INFO - 'SHLVL': '1', 03:56:12 INFO - 'TERM': 'linux', 03:56:12 INFO - 'TMOUT': '86400', 03:56:12 INFO - 'USER': 'cltbld', 03:56:12 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', 03:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:12 INFO - '_': '/tools/buildbot/bin/python'} 03:56:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:56:12 INFO - Running setup.py (path:/tmp/pip-Pbjtip-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:56:13 INFO - Running setup.py (path:/tmp/pip-A3u6i_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:56:13 INFO - Running setup.py (path:/tmp/pip-Mrwh7o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:56:13 INFO - Running setup.py (path:/tmp/pip-ElQ4hx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:56:13 INFO - Running setup.py (path:/tmp/pip-QBa4pW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:56:13 INFO - Running setup.py (path:/tmp/pip-oh_1TS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:56:13 INFO - Running setup.py (path:/tmp/pip-7sqAbW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:56:13 INFO - Running setup.py (path:/tmp/pip-P6pXYU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:56:13 INFO - Running setup.py (path:/tmp/pip-c_Gmwd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:56:13 INFO - Running setup.py (path:/tmp/pip-hA1wQn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:56:13 INFO - Running setup.py (path:/tmp/pip-N0Jl9Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:56:13 INFO - Running setup.py (path:/tmp/pip-ZD2sp3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:56:13 INFO - Running setup.py (path:/tmp/pip-7WXEpi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:56:13 INFO - Running setup.py (path:/tmp/pip-yVHNEb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:56:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:56:13 INFO - Running setup.py (path:/tmp/pip-TuKqMb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:56:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:56:14 INFO - Running setup.py (path:/tmp/pip-GSBpsk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:56:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:56:14 INFO - Running setup.py (path:/tmp/pip-fwmnE8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:56:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:56:14 INFO - Running setup.py install for manifestparser 03:56:14 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 03:56:14 INFO - Running setup.py install for mozcrash 03:56:14 INFO - Running setup.py install for mozdebug 03:56:14 INFO - Running setup.py install for mozdevice 03:56:14 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 03:56:14 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 03:56:14 INFO - Running setup.py install for mozfile 03:56:14 INFO - Running setup.py install for mozhttpd 03:56:14 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 03:56:14 INFO - Running setup.py install for mozinfo 03:56:14 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 03:56:14 INFO - Running setup.py install for mozInstall 03:56:14 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 03:56:14 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 03:56:14 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 03:56:14 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 03:56:14 INFO - Running setup.py install for mozleak 03:56:15 INFO - Running setup.py install for mozlog 03:56:15 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 03:56:15 INFO - Running setup.py install for moznetwork 03:56:15 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 03:56:15 INFO - Running setup.py install for mozprocess 03:56:15 INFO - Running setup.py install for mozprofile 03:56:15 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 03:56:15 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 03:56:15 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 03:56:15 INFO - Running setup.py install for mozrunner 03:56:15 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 03:56:15 INFO - Running setup.py install for mozscreenshot 03:56:15 INFO - Running setup.py install for moztest 03:56:15 INFO - Running setup.py install for mozversion 03:56:15 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 03:56:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:56:15 INFO - Cleaning up... 03:56:15 INFO - Return code: 0 03:56:15 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:56:15 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')]} 03:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:15 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')]} 03:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:15 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 0x2179030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2435640>, '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 0x2436540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24369d0>, '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': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', '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 03:56:15 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 03:56:15 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 03:56:15 INFO - Using env: {'DISPLAY': ':0', 03:56:15 INFO - 'HOME': '/home/cltbld', 03:56:15 INFO - 'LANG': 'en_US.UTF-8', 03:56:15 INFO - 'LANGUAGE': 'en_US:en', 03:56:15 INFO - 'LOGNAME': 'cltbld', 03:56:15 INFO - 'MAIL': '/var/mail/cltbld', 03:56:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:15 INFO - 'MOZ_NO_REMOTE': '1', 03:56:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:15 INFO - 'NO_EM_RESTART': '1', 03:56:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:56:15 INFO - 'PWD': '/builds/slave/test-pgo', 03:56:15 INFO - 'SHELL': '/bin/bash', 03:56:15 INFO - 'SHLVL': '1', 03:56:15 INFO - 'TERM': 'linux', 03:56:15 INFO - 'TMOUT': '86400', 03:56:15 INFO - 'USER': 'cltbld', 03:56:15 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', 03:56:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:15 INFO - '_': '/tools/buildbot/bin/python'} 03:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:56:16 INFO - Running setup.py (path:/tmp/pip-nVzkx8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:56:16 INFO - Running setup.py (path:/tmp/pip-zEkomd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:56:16 INFO - Running setup.py (path:/tmp/pip-4dfUDi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:56:16 INFO - Running setup.py (path:/tmp/pip-cb62hu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:56:16 INFO - Running setup.py (path:/tmp/pip-egZEep-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:56:16 INFO - Running setup.py (path:/tmp/pip-Fwyk1G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:56:16 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)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:56:16 INFO - Running setup.py (path:/tmp/pip-SQ249k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:56:16 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)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:56:16 INFO - Running setup.py (path:/tmp/pip-34nWFG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:56:16 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)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:56:16 INFO - Running setup.py (path:/tmp/pip-YE8Gvt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:56:16 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)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:56:16 INFO - Running setup.py (path:/tmp/pip-biuKRV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:56:16 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)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:56:16 INFO - Running setup.py (path:/tmp/pip-DvAITY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:56:16 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)) 03:56:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:56:16 INFO - Running setup.py (path:/tmp/pip-JW8NNl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 03:56:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:56:17 INFO - Running setup.py (path:/tmp/pip-HKgXfI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 03:56:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:56:17 INFO - Running setup.py (path:/tmp/pip-2MQxZK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 03:56:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:56:17 INFO - Running setup.py (path:/tmp/pip-iqOZbS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 03:56:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:56:17 INFO - Running setup.py (path:/tmp/pip-P_R2f2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 03:56:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:56:17 INFO - Running setup.py (path:/tmp/pip-fmm8hq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:56:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 03:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:19 INFO - Downloading blessings-1.5.1.tar.gz 03:56:19 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 03:56:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 03:56:19 INFO - Installing collected packages: blessings 03:56:19 INFO - Running setup.py install for blessings 03:56:19 INFO - Successfully installed blessings 03:56:19 INFO - Cleaning up... 03:56:19 INFO - Return code: 0 03:56:19 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 03:56:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:56:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:56:19 INFO - Reading from file tmpfile_stdout 03:56:19 INFO - Current package versions: 03:56:19 INFO - argparse == 1.2.1 03:56:19 INFO - blessings == 1.5.1 03:56:19 INFO - blobuploader == 1.2.4 03:56:19 INFO - docopt == 0.6.1 03:56:19 INFO - manifestparser == 1.1 03:56:19 INFO - mozInstall == 1.12 03:56:19 INFO - mozcrash == 0.16 03:56:19 INFO - mozdebug == 0.1 03:56:19 INFO - mozdevice == 0.47 03:56:19 INFO - mozfile == 1.2 03:56:19 INFO - mozhttpd == 0.7 03:56:19 INFO - mozinfo == 0.9 03:56:19 INFO - mozleak == 0.1 03:56:19 INFO - mozlog == 3.1 03:56:19 INFO - moznetwork == 0.27 03:56:19 INFO - mozprocess == 0.22 03:56:19 INFO - mozprofile == 0.27 03:56:19 INFO - mozrunner == 6.11 03:56:19 INFO - mozscreenshot == 0.1 03:56:19 INFO - mozsystemmonitor == 0.0 03:56:19 INFO - moztest == 0.7 03:56:19 INFO - mozversion == 1.4 03:56:19 INFO - psutil == 3.1.1 03:56:19 INFO - requests == 1.2.3 03:56:19 INFO - wsgiref == 0.1.2 03:56:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2179030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2435640>, '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 0x2436540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24369d0>, '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': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', '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 03:56:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 03:56:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:56:19 INFO - Using env: {'DISPLAY': ':0', 03:56:19 INFO - 'HOME': '/home/cltbld', 03:56:19 INFO - 'LANG': 'en_US.UTF-8', 03:56:19 INFO - 'LANGUAGE': 'en_US:en', 03:56:19 INFO - 'LOGNAME': 'cltbld', 03:56:19 INFO - 'MAIL': '/var/mail/cltbld', 03:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:19 INFO - 'MOZ_NO_REMOTE': '1', 03:56:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:19 INFO - 'NO_EM_RESTART': '1', 03:56:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:56:19 INFO - 'PWD': '/builds/slave/test-pgo', 03:56:19 INFO - 'SHELL': '/bin/bash', 03:56:19 INFO - 'SHLVL': '1', 03:56:19 INFO - 'TERM': 'linux', 03:56:19 INFO - 'TMOUT': '86400', 03:56:19 INFO - 'USER': 'cltbld', 03:56:19 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', 03:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:19 INFO - '_': '/tools/buildbot/bin/python'} 03:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:19 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)) 03:56:19 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)) 03:56:19 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)) 03:56:19 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)) 03:56:19 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)) 03:56:19 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)) 03:56:19 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)) 03:56:19 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)) 03:56:19 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)) 03:56:19 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)) 03:56:19 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)) 03:56:19 INFO - Cleaning up... 03:56:19 INFO - Return code: 0 03:56:19 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 03:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2179030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2435640>, '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 0x2436540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24369d0>, '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': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', '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 03:56:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 03:56:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:56:19 INFO - Using env: {'DISPLAY': ':0', 03:56:19 INFO - 'HOME': '/home/cltbld', 03:56:19 INFO - 'LANG': 'en_US.UTF-8', 03:56:19 INFO - 'LANGUAGE': 'en_US:en', 03:56:19 INFO - 'LOGNAME': 'cltbld', 03:56:19 INFO - 'MAIL': '/var/mail/cltbld', 03:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:19 INFO - 'MOZ_NO_REMOTE': '1', 03:56:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:19 INFO - 'NO_EM_RESTART': '1', 03:56:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:56:19 INFO - 'PWD': '/builds/slave/test-pgo', 03:56:19 INFO - 'SHELL': '/bin/bash', 03:56:19 INFO - 'SHLVL': '1', 03:56:19 INFO - 'TERM': 'linux', 03:56:19 INFO - 'TMOUT': '86400', 03:56:19 INFO - 'USER': 'cltbld', 03:56:19 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', 03:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:19 INFO - '_': '/tools/buildbot/bin/python'} 03:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:20 INFO - Downloading/unpacking jsonschema 03:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:56:21 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 03:56:21 INFO - Downloading/unpacking functools32 (from jsonschema) 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56:21 INFO - Downloading functools32-3.2.3-2.tar.gz 03:56:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 03:56:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 03:56:21 INFO - warning: no files found matching '*.txt' 03:56:21 INFO - no previously-included directories found matching 'build' 03:56:21 INFO - no previously-included directories found matching 'dist' 03:56:21 INFO - no previously-included directories found matching '.git*' 03:56:21 INFO - Installing collected packages: jsonschema, functools32 03:56:22 INFO - Running setup.py install for functools32 03:56:22 INFO - warning: no files found matching '*.txt' 03:56:22 INFO - no previously-included directories found matching 'build' 03:56:22 INFO - no previously-included directories found matching 'dist' 03:56:22 INFO - no previously-included directories found matching '.git*' 03:56:22 INFO - Successfully installed jsonschema functools32 03:56:22 INFO - Cleaning up... 03:56:22 INFO - Return code: 0 03:56:22 INFO - Running post-action listener: _resource_record_post_action 03:56:22 INFO - Running post-action listener: _start_resource_monitoring 03:56:22 INFO - Starting resource monitoring. 03:56:22 INFO - ##### 03:56:22 INFO - ##### Running install step. 03:56:22 INFO - ##### 03:56:22 INFO - Running pre-action listener: _resource_record_pre_action 03:56:22 INFO - Running main action method: install 03:56:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:56:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:56:22 INFO - Reading from file tmpfile_stdout 03:56:22 INFO - Detecting whether we're running mozinstall >=1.0... 03:56:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 03:56:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 03:56:22 INFO - Reading from file tmpfile_stdout 03:56:22 INFO - Output received: 03:56:22 INFO - Usage: mozinstall [options] installer 03:56:22 INFO - Options: 03:56:22 INFO - -h, --help show this help message and exit 03:56:22 INFO - -d DEST, --destination=DEST 03:56:22 INFO - Directory to install application into. [default: 03:56:22 INFO - "/builds/slave/test-pgo"] 03:56:22 INFO - --app=APP Application being installed. [default: firefox] 03:56:22 INFO - mkdir: /builds/slave/test-pgo/build/application 03:56:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 03:56:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 03:56:37 INFO - Reading from file tmpfile_stdout 03:56:37 INFO - Output received: 03:56:37 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 03:56:37 INFO - Running post-action listener: _resource_record_post_action 03:56:37 INFO - ##### 03:56:37 INFO - ##### Running run-tests step. 03:56:37 INFO - ##### 03:56:37 INFO - Running pre-action listener: _resource_record_pre_action 03:56:37 INFO - Running main action method: run_tests 03:56:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 03:56:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 03:56:37 INFO - Python 2.7.3 03:56:37 INFO - Return code: 0 03:56:37 INFO - grabbing minidump binary from tooltool 03:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2435640>, '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 0x2436540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24369d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 03:56:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 03:56:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:56:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:56:37 INFO - Return code: 0 03:56:37 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 03:56:37 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 03:56:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 03:56:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 03:56:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 03:56:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 03:56:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost 03:56:37 INFO - Using env: {'DISPLAY': ':0', 03:56:37 INFO - 'HOME': '/home/cltbld', 03:56:37 INFO - 'LANG': 'en_US.UTF-8', 03:56:37 INFO - 'LANGUAGE': 'en_US:en', 03:56:37 INFO - 'LOGNAME': 'cltbld', 03:56:37 INFO - 'MAIL': '/var/mail/cltbld', 03:56:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:56:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 03:56:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:37 INFO - 'MOZ_NO_REMOTE': '1', 03:56:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:56:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:37 INFO - 'NO_EM_RESTART': '1', 03:56:37 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:56:37 INFO - 'PWD': '/builds/slave/test-pgo', 03:56:37 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 03:56:37 INFO - 'SHELL': '/bin/bash', 03:56:37 INFO - 'SHLVL': '1', 03:56:37 INFO - 'TERM': 'linux', 03:56:37 INFO - 'TMOUT': '86400', 03:56:37 INFO - 'USER': 'cltbld', 03:56:37 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950', 03:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:37 INFO - '_': '/tools/buildbot/bin/python'} 03:56:37 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost'] with output_timeout 3600 03:56:37 INFO - mozversion INFO | application_buildid: 20151126023023 03:56:37 INFO - mozversion INFO | application_changeset: fc6459ef44a46187cfa5f38f4e4e157940de58a5 03:56:37 INFO - mozversion INFO | application_display_name: Nightly 03:56:37 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:56:37 INFO - mozversion INFO | application_name: Firefox 03:56:37 INFO - mozversion INFO | application_remotingname: firefox 03:56:37 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 03:56:37 INFO - mozversion INFO | application_vendor: Mozilla 03:56:37 INFO - mozversion INFO | application_version: 45.0a1 03:56:37 INFO - mozversion INFO | platform_buildid: 20151126023023 03:56:37 INFO - mozversion INFO | platform_changeset: fc6459ef44a46187cfa5f38f4e4e157940de58a5 03:56:37 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 03:56:37 INFO - mozversion INFO | platform_version: 45.0a1 03:56:37 INFO - 2015-11-26 03:56:37,355 DEBUG : using testdate: 1448538997 03:56:37 INFO - 2015-11-26 03:56:37,355 DEBUG : actual date: 1448538997 03:56:37 INFO - 2015-11-26 03:56:37,448 INFO : starting webserver on 'localhost:54118' 03:56:37 INFO - 2015-11-26 03:56:37,449 INFO : Starting test suite talos-linux64-ix-017 03:56:37 INFO - 2015-11-26 03:56:37,449 INFO : Starting test dromaeo_css 03:56:37 INFO - 2015-11-26 03:56:37,449 DEBUG : operating with platform_type : linux_ 03:56:37 INFO - 2015-11-26 03:56:37,450 INFO : Initialising browser for dromaeo_css test... 03:56:37 INFO - 2015-11-26 03:56:37,461 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXoAeMj/profile http://localhost:54118/getInfo.html 03:56:40 INFO - 2015-11-26 03:56:40,984 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:56:40 INFO - 2015-11-26 03:56:40,984 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:56:40 INFO - 2015-11-26 03:56:40,984 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:56:40 INFO - 2015-11-26 03:56:40,985 DEBUG : BROWSER_OUTPUT: __metrics 03:56:41 INFO - 2015-11-26 03:56:41,129 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:56:42 INFO - 2015-11-26 03:56:42,580 INFO : Browser initialized. 03:56:42 INFO - 2015-11-26 03:56:42,580 INFO : Running cycle 1/1 for dromaeo_css test... 03:56:42 INFO - 2015-11-26 03:56:42,580 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXoAeMj/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:56:54 INFO - 2015-11-26 03:56:54,292 DEBUG : BROWSER_OUTPUT: RSS: Main: 139161600 03:56:54 INFO - 2015-11-26 03:56:54,292 DEBUG : BROWSER_OUTPUT: 03:57:45 INFO - 2015-11-26 03:57:45,869 DEBUG : BROWSER_OUTPUT: 1448539065865 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] 03:59:54 INFO - 2015-11-26 03:59:54,753 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54118/tests/dromaeo/cssquery-ext.html) 03:59:55 INFO - 2015-11-26 03:59:55,749 DEBUG : BROWSER_OUTPUT: RSS: Main: 236318720 03:59:55 INFO - 2015-11-26 03:59:55,750 DEBUG : BROWSER_OUTPUT: 04:02:57 INFO - 2015-11-26 04:02:57,994 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/dromaeo/cssquery-ext.html (next: http://localhost:54118/tests/dromaeo/cssquery-jquery.html) 04:02:58 INFO - 2015-11-26 04:02:58,567 DEBUG : BROWSER_OUTPUT: RSS: Main: 218562560 04:02:58 INFO - 2015-11-26 04:02:58,567 DEBUG : BROWSER_OUTPUT: 04:06:00 INFO - 2015-11-26 04:06:00,589 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54118/tests/dromaeo/cssquery-mootools.html) 04:06:01 INFO - 2015-11-26 04:06:01,161 DEBUG : BROWSER_OUTPUT: RSS: Main: 213905408 04:06:01 INFO - 2015-11-26 04:06:01,162 DEBUG : BROWSER_OUTPUT: 04:09:03 INFO - 2015-11-26 04:09:03,099 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54118/tests/dromaeo/cssquery-prototype.html) 04:09:03 INFO - 2015-11-26 04:09:03,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 203034624 04:09:03 INFO - 2015-11-26 04:09:03,700 DEBUG : BROWSER_OUTPUT: 04:12:06 INFO - 2015-11-26 04:12:06,086 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54118/tests/dromaeo/cssquery-yui.html) 04:12:06 INFO - 2015-11-26 04:12:06,688 DEBUG : BROWSER_OUTPUT: RSS: Main: 207028224 04:12:06 INFO - 2015-11-26 04:12:06,688 DEBUG : BROWSER_OUTPUT: 04:15:09 INFO - 2015-11-26 04:15:09,152 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/dromaeo/cssquery-yui.html (next: http://localhost:54118/tests/dromaeo/cssquery-dojo.html) 04:15:09 INFO - 2015-11-26 04:15:09,409 DEBUG : BROWSER_OUTPUT: RSS: Main: 256073728 04:15:09 INFO - 2015-11-26 04:15:09,410 DEBUG : BROWSER_OUTPUT: 04:15:09 INFO - 2015-11-26 04:15:09,410 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:15:09 INFO - 2015-11-26 04:15:09,410 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:15:09 INFO - 2015-11-26 04:15:09,410 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:15:09 INFO - 2015-11-26 04:15:09,411 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:15:09 INFO - 2015-11-26 04:15:09,411 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3145.7640676949227;3211.6948889855457;3243.0433022258444;3251.2684645954664;3271.5910511186103;5159.303494028305;5205.297284866542;5282.894342113165;5417.187421886714;5431.538319242864;5281.656692314997;5319.414864364917;5338.06583847827;5341.332333458317;5461.808836690717;4877.926831097537;4907.9018419631675;4914.287428322891;4965.329680493123;4993.375828021497;5011.072951503965;5019.54824065834;5078.822241221558;5092.312537807404;5094.261332106842;4783.856484305476;4793.18515852306;4873.537013983666;4909.705417674951;4938.456769755324;4896.706197628154;4911.45973942866;4916.557509824133;4930.260460930853;4950.2822090796635;4894.043414421211;4894.486078961693;4895.363602731658;4896.877578060541;4969.602431805447;4991.32617096691;5103.106956282636;5116.079105760961;5123.692578445305;5133.178691409356;5688.061469857468;5705.885882282331;5707.68607726579;5712.82861514151;5715.2855893013375;5741.971290143522;5756.079027355645;5765.135229715576;5803;5913.763449462016;5526.778928842841;5536.86157846049;5540.445955404468;5551.861203469905;5595.552355811526;5899.911501327483;5951.077582974646;5996.040633498619;5996.16053752475;6011.5190784737115;5463.836084917459;5549.750261238254;5619.072852979212;5795.681237531892;5825.475707186373;5894.941050589439;5896.439838215363;5898.911516327258;5903.321118071391;5917.31950825652;5841.802430501737;5862.70686465675;5867.559933005042;5870.471657550841;5878.059510478303;5587.1339942309005;5715.028445164332;5719.942800572025;5836;5885.058390657474;4532.0709254602725;4538.931916021263;4542.727436353764;4545.727256364628;4574.977125114419;4020.0552870075508;4035.1122752994293;4037.8990525237105;4082.0203151243486;4089.5705950875326;5217.399999000164;5340.786368545292;5403.297571315782;5462.0987537056735;5526.336839579276;5817.127430885316;5871.089981052944;5879.588428810028;5881.05903055509;5884.735186916599;5222.843314700565;5525.143602741581;5535.640183388067;5548.278723765965;5549.056660367747;4881.341018962466;5025.095482813129;5090.974545127251;5096.311997880239;5142.023015627019;5984.521238301013;6007.759689612367;6020.307664618537;6023.096535519707;6025.276966763929;5523.8895222096135;5619;5824.679642619697;5839.35767065614;5843.824685259449;5702.515286200553;5982;6076.088586712029;6077.580865039038;6134.294556126102;5705.743241554057;5752.07966725322;5754.827355179351;5756.107803290497;5759.424057594375;5495.505404513613;5559.527440167631;5835.328937172279;5853.268341457318;5857;5489.58828087887;5546.944530554642;5636.633618814928;5651.4348565143155;5706.28671416073;5672.971635141798;5677.829665110054;5704.429557044263;5711.600187986801;5713.171590119493;5143.588512918883;5332.68003919766;5579.637323574117;5599.132134519156;5603.719814009365;5690.516306113861;5780.190773291659;5839.970800145972;5841.1238314253205;5841.50347220491;5512.090505066619;5537.307836520435;5537.8061767837935;5551.444855514416;5557.888842223214 04:15:09 INFO - 2015-11-26 04:15:09,414 DEBUG : BROWSER_OUTPUT: |1;ext.html;572.2503520388289;585.6720236667469;598.6408155106936;618.5855476830523;625.159160928551;31365.84317078414;31910.68089319106;31922.201944951386;31970.360592788187;39626;42288.788556057174;42842.35736463955;42900;42912.57087429125;42981.785091074504;8459.323254139685;8740.781480462976;8754.124587541226;8758.343124265673;8760.649574017063;7778.8833167502535;7956.960215198916;7960.4825686330205;7964.840703185932;8587.699430519933;10404.115650169706;10583.73540661482;10648.574057037747;10649;10664.573417063308;10243.38539687621;10354.689359319233;10360.430176340336;10362.27464077515;10380.636677716282;7855;8050.959745201237;8055.879161812578;10155.187584993182;10295.691129266133;30272.394552108835;43030.56969430328;43757.56242437599;43981.34027989583;44055.33916991248;3243.221626809572;3330.400527904976;3337.8497967591275;3346.866125354983;3717.7955212463303;29094.563581546292;29265;29575.70424295751;29704;29912.55131173034;3863.459115723801;3892.5134358205223;3904.958164709376;3931.5282166140246;4001.0597509585223;243096;244731;244899;244902;245557;55526.16710749342;55871.44128558744;56011.43988560144;56018.719906400205;56026.43973560212;59144;59397;59446.108308375406;59447.702761486784;59470.405295947356;14692.485762998458;14924.104553726811;15018;15022.924885375503;15043.849561504463;12096.33470159132;12206.267623942415;12213.633590992284;12221.69445763863;12224.511019559119;18947.14842331026;19107.808921910604;19202.13590388436;19349.709754353695;19408.12663430149;7836.529808211526;8071.5157090573575;8107.878381824277;8197.34421246299;8235.95882020586;954.8185844689486;965.2133511188359;1015.6496008876926;1018.4856647393019;1029.361795686679;25428.982840686534;25500.48999020046;25518.23445296644;25541.361465963128;25543.744562554137;1045.7751583409604;1046.9268999275803;1074.505727365405;1105.6848798092506;1137.6700756780606;13944.372503237379;13967.161970281815;14204.28978551089;14215.218163001135;15171.924140379448;309537;313039;313132;313548;313669;43810;44444.77777611091;58839.41160588339;60466.69766651138;60612.39387606156;1394.463131694305;1398.356755892301;1408.8520705325793;1409.7180563887264;1413.3639862062114;28525;28669;29919.700802992564;30625.23436914095;32628;48548.27177592268;49532.75233623809;49584;49631.503684962685;49713.751431242614;298880;300000;301639;302452;302613;32498;32622.34755304928;33130;33143.33713325768;33166.834165829016;42608;42771.14457710766;43400.348994765736;43604.12791744084;43716.56283437125;36244.63755362518;36493.635063649024;36971;37363.252734945694;37532;35716;36448.205917325016;37310.44034339433;37324;37366.813165934;35998.4600231002;36224.45663315105;36238.27523449569;40251.19497609973;41593.37609935793;32364.676353236166;33100.83449582737;33225.50161747624;33357.16607084842;33769.324613508084;25668.871655641604;25723.742762572136;25874.35314117162;25932.8703356482;26296.8685156573 04:15:09 INFO - 2015-11-26 04:15:09,416 DEBUG : BROWSER_OUTPUT: |2;jquery.html;338.7070184290588;341.9025577710353;361.73593276907854;362.26279521174416;363.353231248378;13625.795613065788;13971.231582262997;16176.110313932744;16667.833321666796;17511.29954801808;13435.596932092029;14381.496647617336;14658.340374683139;14675.266236688176;16327.673446531062;5198.844034678966;5326.493983071621;5327.733613319338;5333.519983201511;5334.91997620036;4014.9397759033636;4127.195196936584;5361.571074314065;5393.946060539383;5763.135529670562;5805.187273781653;5829.358770535237;5844.707764611774;5858.677772722499;5867.383924687911;5676.432356764331;5815.912761308584;5818.563607729416;5822.155787410802;5832.562557808182;5200.8179713710215;5230.816921407771;5234.528892399664;5235.6073294502685;5238.319018527605;19502.414927552196;19852.60294794096;19875.701864472045;20025.8998705007;20607.381778546667;1918.8464922806143;2264.2527965771255;2330.685357476753;2342.367560758603;2557.629143774161;8660.047394786568;8787.384883058188;8876.556172191365;8911.376203665746;9023.819523609556;1774.4942691332908;1782.0911335219132;1801.639672065592;1806.3948577226645;1828.4606041217874;52735.20897186545;55073;59427.70286148541;61778.99999999955;62549.06176407357;8956.552172391355;9355.204807591295;9815.901840981498;9934.602615895283;10085.848712269322;13118.672033199056;13168.60494185176;13255.469781208836;13258.071934964653;13262.53581124656;7771.7668469944865;7795.14253432122;7834.294913457816;7873.645685944031;7900.44696871225;7497.400207983348;7529.284717951786;7563.205863384375;7577.734779282699;7638.197989211053;4276.294411422143;4351.412559304454;4355.847545335898;4377.978110109493;4401.141777353385;1620.8541231289241;1639.7294446416208;1643.7973116024584;1644.4244514420097;1736.4877361178483;841.486693117198;970.5583959298502;997.4814092162516;1022.1805186767566;1068.326954018963;14886.915831783559;15652.68694626101;15761.763573546406;16502.17489125539;16682.833171668128;937.723371605378;952.9850708995036;965.3001573859036;1020.7754294055296;1049.3284298049255;9177.770555736026;9200.171984521425;9566.61733530651;9766.609335626637;9785.217182625373;510300;516164;521068;521534;539103;77270;77744;78661.60669196732;78789;79890.60054699806;1183.6922400175845;1191.6484637032097;1193.5046955513537;1194.498310709504;1212.4059210986552;15080.170590617621;15178.772318415453;15211.467598633995;15220.771688424462;15537.533873983799;34628.78799242015;34893.47659785156;35216;35689;37399;45019.32471012872;45389.31916021196;47153;47267.29099063448;47980;9191.816163676824;9584.37701549397;9692.22462203008;9700.175485083846;9715.805683886425;15190.46833360827;15284.388624454898;15373.461928832437;15655.13896735645;15694.058356498646;11610;11753.764924701629;12134.817977730518;13140.60578182656;14045.929770351082;13916.791248131489;14024.298785060502;14025.438982440588;14041.578752637437;14046.1572305662;11644.184907056424;11855.34795586251;13605.115667481581;14114.082584631966;14500.419983200554;8168.7957801055445;8300.792480188044;9035.638574456949;9419.717408477756;9465.432074075577;9778.951105244429;9818.214542836698;13675.316234188052;14012.089214201043;14130.576082717535 04:15:09 INFO - 2015-11-26 04:15:09,417 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1317.3347459532947;1494.1184701026395;1532.126687787961;1689.662067586483;1799.8290162434573;6459.838504037396;7062.540934839239;7368.300011498899;7386.298301661333;7397.519161254515;7408.888866667005;7544.886826697604;7545.962270188655;7550.886736698954;7576.431767617423;1941.3884626342729;1972.142118178597;1976.7529058867642;2252.2905284835224;2258.7063681721434;2269.716285464317;2318.5942460069505;2323.779240972105;2323.9419014524688;2324.337563794319;2223.0996446439217;2241.7309922809286;2280.9771902281013;2281.064763446987;2281.2813963601498;1940.8544359173047;1986.9403917882487;2207.3377986604114;2257.3792207143;2262.17430637816;2264.230161745015;2268.160780511221;2279.4985103277254;2288.290629904735;2288.942776430586;7497.062867141607;7589.696412143508;7596;7596.240375962415;7599.050118735158;4598.2642777155825;4673.205555055649;4683.110209060268;4705.835295764632;4713.575778179946;3141.8271995040263;3142.1987393214813;3149.7637677174303;3176.9841150794327;3253.056613582058;1024.6615135145898;1050.6532844161409;1053.9736506587396;1066.4347895615335;1096.4572536594437;10192.74518137045;10283.280170388076;10289.485525723683;10301.27891047627;10331.07020368163;4064.7154699171365;4170.35359519275;4171.20747058058;4188.8324467020975;4232.957670423318;8500.489970601784;8517.318614510825;8517.403781735218;8526.147385261424;8535.402521823537;4630.282306242538;4658.091672123904;4660.347551342815;4667.95332046682;4673.065386922629;4260.595243451867;4271.551487093872;4321.654267658642;4331.436913201263;4332.696711230246;3442.3631628148873;3498.632643572439;3502.194495266103;3506.1059429845227;3538.168530395355;1088.3469918049107;1100.4442756408123;1103.5034234594305;1103.939283339424;1110.4170310587006;1311.9147255428365;1315.5987423835631;1355.8169647097516;1369.1579678497653;1369.589123263037;6387.457066149336;6689.5986240825705;7217.566945983258;7247.347738703542;7277.017602623578;615.5414216408805;622.7415622516695;624.6345887655772;632.0677001422864;632.7785275153659;5214.0875346814155;5227.451117632594;5233.319668443078;5247.868803279948;5270.130428479258;8544.700935467354;9157.954210228907;9161.496117713456;9171.403858749294;9173.908260917438;8936.642534298686;9042.276617870555;9057.637694492147;9087.454752714726;9126.634934602675;6700.932990670129;6859.691313890787;6929.272426395257;6929.272426395358;7080;6973.19808222061;6973.6164510952385;7038.049863268393;7041.3310735480045;7056.611886346095;7432.145303290191;7564.962694690056;7761.72833950809;7778.105517865518;7785.532868027936;7963.920360796318;7972.322352599862;8036.87944680842;8111.351091912515;8125.3905957052275;6300.936990630035;6535.640539770168;6583.769568065095;6605.372489613479;6615.073889655356;7175.103112110986;7305.707771689073;7319.707211711685;7321.853562928605;7323.523970941872;6571.769988050395;6677.43241824431;6698;6839;6888.793336199922;6649.102371179829;6747.055412242388;6798.082258894986;6825.146856642919;6887.931120688729;6362.650054246876;6528.510361722795;6906.827329316808;6935.445164386938;6961.269066747918;6062.120992456157;6066.363031881765;6070.484008859296;6074.787382441594;6085.4523092921845;6077.361877003028;6120.969395152996;6122.051082082285;6124.38756124384;6149.200603921461 04:15:09 INFO - 2015-11-26 04:15:09,421 DEBUG : BROWSER_OUTPUT: |4;prototype.html;342.2914566846627;345.1165017555059;351.2763706764065;352.86591095383744;353.27049642488277;5880.470757631812;5985.730642121104;6002.639841609506;6015;6015.849603759908;5918.378570250115;5928.762849486026;5938.792142275021;5941.64350138992;5947.851303717409;4543.068670922469;4568.5431456854385;4617.191991401516;4621.006483606024;4622.214223581982;4610.377599024139;4611.884702882421;4616.169089563878;4620.468646105707;4623.653226008046;4551.908961820762;4562.749048802315;4563.634909207255;4565;4572.885677858047;4374.781260936957;4471.128130014648;4471.955280447187;4546.568076032805;4566.269396896497;3991.600839916014;4007.198560287954;4009.0979529605897;4025.0943537704075;4034.9596504034876;5452.345718513804;5615.129654903456;5627.2684551008515;5634.3520495143175;5638.548916086703;5197.922031169535;5234.790608375661;5313.867153321159;5315.415304316522;5318.335208098988;6250.093736408241;6269.185005949243;6275.905861412082;6275.937240627581;6279.843003924892;4385.7149285296355;4410.977945110286;4411.955880441186;4424.601785839258;4424.623906967911;6326.936730632727;6500.707468163944;6503.739850405978;6506.804795856132;6530.902036469457;4549.363089167519;4558.88602784933;4559.247724125482;4561.29299958507;4567.086582683477;4609.423822022247;4615.330777037311;4615.515370886076;4622.537746225351;4623.42207224097;5293;5297.364316282071;5299.417064122943;5301.0723123453245;5311.044012077863;6167.229096362955;6339.873202535923;6343.048542718537;6343.524235682342;6344.904826427607;1624.5694890853933;1643.9013659180487;1645.9588510287338;1647.6622292430263;1648.307710761459;1113.5935383584933;1120.7310245541012;1122.1639878290562;1134.3307448605362;1148.7300484386162;6158.076288556752;6158.291796443466;6160.537959653044;6169.321374648785;6169.660668663177;6518.511111666644;6541.836454088686;6544.70548825304;6558.409743123142;6566.770163044271;6036.275646922398;6145.170401995673;6149.2620885494025;6153.8461538461;6163.38366163389;3518.9970858305774;3535.5227044348685;3547.396942519726;3550.5029295898594;3556.5732112146707;6715.865682686319;6757.831054223684;6765.019072234499;6769.593824370553;6772.153480814898;6528.477721782246;6647.036179753909;6663.36698013688;6671.699773510204;6685.431738302202;6801.625910574966;6813.897791533131;6817.761378351734;6831.419329356961;6838.623875686885;6606.702698378681;6607.603543787388;6610.9669451652435;6611.537192396486;6613.933860661332;6378.77674281398;6511.479081673549;6522.315156908646;6528.183977002875;6530.249021362414;6788.083608712958;6820.658967051529;6832.829179270558;6838.692258848463;6853.7943861684225;4582.679212455096;4585.220512512948;4585.266357382803;4588.2888152336445;4589.862304130881;6417.486601071996;6579.572327798678;6580.374864387875;6590.1103351046995;6595.109660196004;6415.358464153734;6417.486601071996;6426.196725409324;6428.8071357859335;6431.067495213074;6407.167068281094;6414.775482858077;6415.903761443676;6416.711247993945;6416.839579010375;6185.134081228542;6269.780557680459;6295.087212354091;6297.307296197323;6301.180846489927;6430.099786030053;6435.292117866938;6444.615492267891;6446.871062578816;6464.256610489854;6202.627842329294;6275.843103922439;6284.183056202664;6285.654289014148;6300.054991751274 04:15:09 INFO - 2015-11-26 04:15:09,421 DEBUG : BROWSER_OUTPUT: |5;yui.html;754.5548126605304;758.2947858491603;792.6433105102701;812.4312980913359;817.6320655704927;12170.148089633718;12410.875891241085;12449.626511204679;12450.81323780144;12453.564125255618;14431.206283654396;14597;14607.78088328676;14636;14651.633709157277;687.5530904911823;697.2051860878603;828.0229329391316;842.6629348260699;859.9355048371366;828.7638023163399;832.5587438657521;832.7127141136298;838.053000109875;838.1116017021945;884.8672699095155;888.866669999502;892.3173772064376;893.9821203575924;894.7539426657656;721.476929226312;731.2395109086543;856.7772379181396;872.8734333521668;874.2525141004413;645.177398816508;645.9806205813833;647.2491909385137;648.3710800523485;649.04590252329;12298.877011229863;12408.689782755413;12458.688532786662;12477.50089996399;12488.313142777144;11480.081593472501;12093.395330233454;12681.302528360937;12752.681182970407;12753.362331883462;10091.243156763272;10270.794584108351;10371.948140259326;10376.740581485448;10630.30902991303;3597.730170237243;3647.3799454092605;3650.908727281813;3696.3346597612417;3791.393377059685;44103.558964410266;44855.327170092474;50705.746471267405;51169.23246151311;51288.74355628198;9315.301352398597;9749.853752193723;9756.121949024622;9859.802803943881;10122.089011988955;17797.377091801725;17828.732569011474;17835;17845.643087138185;17885.642287154184;7246.782596522113;7349.0078839356;7393.260673932564;7445.627718614048;7546;4045.59544045599;4232.153569286155;4269.957300426956;4296.419983302277;4304.5910638489295;3190.9042728718177;3203.2312245061016;3208.5508028875975;3221.1624977506017;3225.59680039995;522.3914140026826;546.5791340667664;555.3419198250086;564.881374911265;565.9207710920474;590.8729623130962;604.2507290959179;694.8818700820873;708.2598684374841;716.2014353995257;8190.385721070944;8195.139510351446;8259.876101858477;8357.916420835836;8455.534945577929;584.3834754334218;635.9599236452427;658.2397331082607;684.3430306905327;713.4363852556422;3738.4953031340965;4088.836446542105;4136.855210067633;4258.61672449481;4379.5182529921685;58361;58822;59468.10797838036;59532.70233648804;59704.402955970756;49870;49999;51062.74468627633;51231;51923.48076519262;44861.327080093826;46410.303845442344;47032.294515582296;47688.99999999931;47772.28341574879;8217.260446559838;8264.586770661563;8680.218780309802;8720.607572659119;8755.518446485505;35671.28657426889;35741.10647233736;36198.27603447864;36753.816230918674;37002.259954801295;42571.14857702891;42598.14803703847;43947.56052439435;46035.76982115068;46185.307220392395;10353.327033742782;10388.272820902464;10414.583416663248;10465.110465610509;10749.516271767632;11564.363959982282;11567.652970410902;11623;11677.824832627348;11846.940765296118;10777.89222107769;10966.506507207036;11032.669019929415;11172.88827111751;11373.158028772532;10553.63062292816;10764.838527422251;10830.945845270724;11064.944675276894;11104.444777760918;8173.965918549439;9436.339456238273;9807.9019209807;11014.889851101387;11081.501332439899;8762.737117886474;9407.341486095907;9428.528573571431;9455.716328510156;9758.316917815684;9004.90995090067;9185.816283674156;9303.72088837336;9342.579583918876;9534.666286679932 04:15:09 INFO - 2015-11-26 04:15:09,421 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:15:09 INFO - 2015-11-26 04:15:09,421 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:15:09 INFO - 2015-11-26 04:15:09,421 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,824 04:15:09 INFO - 2015-11-26 04:15:09,421 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:15:09 INFO - 2015-11-26 04:15:09,421 DEBUG : BROWSER_OUTPUT: __startTimestamp1448540109407__endTimestamp 04:15:09 INFO - 2015-11-26 04:15:09,422 DEBUG : BROWSER_OUTPUT: 04:15:09 INFO - 2015-11-26 04:15:09,422 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:15:09 INFO - 2015-11-26 04:15:09,422 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:15:09 INFO - 2015-11-26 04:15:09,422 DEBUG : BROWSER_OUTPUT: 04:15:09 INFO - 2015-11-26 04:15:09,422 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5379.12 Median:5549.06 stddev:595.89 (10.7%) stddev-sans-first:571.38 04:15:09 INFO - 2015-11-26 04:15:09,423 DEBUG : BROWSER_OUTPUT: Values: 3145.8 3211.7 3243.0 3251.3 3271.6 5159.3 5205.3 5282.9 5417.2 5431.5 5281.7 5319.4 5338.1 5341.3 5461.8 4877.9 4907.9 4914.3 4965.3 4993.4 5011.1 5019.5 5078.8 5092.3 5094.3 4783.9 4793.2 4873.5 4909.7 4938.5 4896.7 4911.5 4916.6 4930.3 4950.3 4894.0 4894.5 4895.4 4896.9 4969.6 4991.3 5103.1 5116.1 5123.7 5133.2 5688.1 5705.9 5707.7 5712.8 5715.3 5742.0 5756.1 5765.1 5803.0 5913.8 5526.8 5536.9 5540.4 5551.9 5595.6 5899.9 5951.1 5996.0 5996.2 6011.5 5463.8 5549.8 5619.1 5795.7 5825.5 5894.9 5896.4 5898.9 5903.3 5917.3 5841.8 5862.7 5867.6 5870.5 5878.1 5587.1 5715.0 5719.9 5836.0 5885.1 4532.1 4538.9 4542.7 4545.7 4575.0 4020.1 4035.1 4037.9 4082.0 4089.6 5217.4 5340.8 5403.3 5462.1 5526.3 5817.1 5871.1 5879.6 5881.1 5884.7 5222.8 5525.1 5535.6 5548.3 5549.1 4881.3 5025.1 5091.0 5096.3 5142.0 5984.5 6007.8 6020.3 6023.1 6025.3 5523.9 5619.0 5824.7 5839.4 5843.8 5702.5 5982.0 6076.1 6077.6 6134.3 5705.7 5752.1 5754.8 5756.1 5759.4 5495.5 5559.5 5835.3 5853.3 5857.0 5489.6 5546.9 5636.6 5651.4 5706.3 5673.0 5677.8 5704.4 5711.6 5713.2 5143.6 5332.7 5579.6 5599.1 5603.7 5690.5 5780.2 5840.0 5841.1 5841.5 5512.1 5537.3 5537.8 5551.4 5557.9 04:15:09 INFO - 2015-11-26 04:15:09,423 DEBUG : BROWSER_OUTPUT: 04:15:09 INFO - 2015-11-26 04:15:09,424 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45905.35 Median:33136.67 stddev:75092.22 (226.6%) stddev-sans-first:75225.73 04:15:09 INFO - 2015-11-26 04:15:09,424 DEBUG : BROWSER_OUTPUT: Values: 572.3 585.7 598.6 618.6 625.2 31365.8 31910.7 31922.2 31970.4 39626.0 42288.8 42842.4 42900.0 42912.6 42981.8 8459.3 8740.8 8754.1 8758.3 8760.6 7778.9 7957.0 7960.5 7964.8 8587.7 10404.1 10583.7 10648.6 10649.0 10664.6 10243.4 10354.7 10360.4 10362.3 10380.6 7855.0 8051.0 8055.9 10155.2 10295.7 30272.4 43030.6 43757.6 43981.3 44055.3 3243.2 3330.4 3337.8 3346.9 3717.8 29094.6 29265.0 29575.7 29704.0 29912.6 3863.5 3892.5 3905.0 3931.5 4001.1 243096.0 244731.0 244899.0 244902.0 245557.0 55526.2 55871.4 56011.4 56018.7 56026.4 59144.0 59397.0 59446.1 59447.7 59470.4 14692.5 14924.1 15018.0 15022.9 15043.8 12096.3 12206.3 12213.6 12221.7 12224.5 18947.1 19107.8 19202.1 19349.7 19408.1 7836.5 8071.5 8107.9 8197.3 8236.0 954.8 965.2 1015.6 1018.5 1029.4 25429.0 25500.5 25518.2 25541.4 25543.7 1045.8 1046.9 1074.5 1105.7 1137.7 13944.4 13967.2 14204.3 14215.2 15171.9 309537.0 313039.0 313132.0 313548.0 313669.0 43810.0 44444.8 58839.4 60466.7 60612.4 1394.5 1398.4 1408.9 1409.7 1413.4 28525.0 28669.0 29919.7 30625.2 32628.0 48548.3 49532.8 49584.0 49631.5 49713.8 298880.0 300000.0 301639.0 302452.0 302613.0 32498.0 32622.3 33130.0 33143.3 33166.8 42608.0 42771.1 43400.3 43604.1 43716.6 36244.6 36493.6 36971.0 37363.3 37532.0 35716.0 36448.2 37310.4 37324.0 37366.8 35998.5 36224.5 36238.3 40251.2 41593.4 32364.7 33100.8 33225.5 33357.2 33769.3 25668.9 25723.7 25874.4 25932.9 26296.9 04:15:09 INFO - 2015-11-26 04:15:09,424 DEBUG : BROWSER_OUTPUT: 04:15:09 INFO - 2015-11-26 04:15:09,424 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27919.70 Median:4313.85 stddev:85272.64 (1976.7%) stddev-sans-first:85486.70 04:15:09 INFO - 2015-11-26 04:15:09,425 DEBUG : BROWSER_OUTPUT: Values: 338.7 341.9 361.7 362.3 363.4 13625.8 13971.2 16176.1 16667.8 17511.3 13435.6 14381.5 14658.3 14675.3 16327.7 5198.8 5326.5 5327.7 5333.5 5334.9 4014.9 4127.2 5361.6 5393.9 5763.1 5805.2 5829.4 5844.7 5858.7 5867.4 5676.4 5815.9 5818.6 5822.2 5832.6 5200.8 5230.8 5234.5 5235.6 5238.3 19502.4 19852.6 19875.7 20025.9 20607.4 1918.8 2264.3 2330.7 2342.4 2557.6 8660.0 8787.4 8876.6 8911.4 9023.8 1774.5 1782.1 1801.6 1806.4 1828.5 52735.2 55073.0 59427.7 61779.0 62549.1 8956.6 9355.2 9815.9 9934.6 10085.8 13118.7 13168.6 13255.5 13258.1 13262.5 7771.8 7795.1 7834.3 7873.6 7900.4 7497.4 7529.3 7563.2 7577.7 7638.2 4276.3 4351.4 4355.8 4378.0 4401.1 1620.9 1639.7 1643.8 1644.4 1736.5 841.5 970.6 997.5 1022.2 1068.3 14886.9 15652.7 15761.8 16502.2 16682.8 937.7 953.0 965.3 1020.8 1049.3 9177.8 9200.2 9566.6 9766.6 9785.2 510300.0 516164.0 521068.0 521534.0 539103.0 77270.0 77744.0 78661.6 78789.0 79890.6 1183.7 1191.6 1193.5 1194.5 1212.4 15080.2 15178.8 15211.5 15220.8 15537.5 34628.8 34893.5 35216.0 35689.0 37399.0 45019.3 45389.3 47153.0 47267.3 47980.0 9191.8 9584.4 9692.2 9700.2 9715.8 15190.5 15284.4 15373.5 15655.1 15694.1 11610.0 11753.8 12134.8 13140.6 14045.9 13916.8 14024.3 14025.4 14041.6 14046.2 11644.2 11855.3 13605.1 14114.1 14500.4 8168.8 8300.8 9035.6 9419.7 9465.4 9779.0 9818.2 13675.3 14012.1 14130.6 04:15:09 INFO - 2015-11-26 04:15:09,425 DEBUG : BROWSER_OUTPUT: 04:15:09 INFO - 2015-11-26 04:15:09,425 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5234.63 Median:6103.21 stddev:2680.25 (43.9%) stddev-sans-first:2671.59 04:15:09 INFO - 2015-11-26 04:15:09,425 DEBUG : BROWSER_OUTPUT: Values: 1317.3 1494.1 1532.1 1689.7 1799.8 6459.8 7062.5 7368.3 7386.3 7397.5 7408.9 7544.9 7546.0 7550.9 7576.4 1941.4 1972.1 1976.8 2252.3 2258.7 2269.7 2318.6 2323.8 2323.9 2324.3 2223.1 2241.7 2281.0 2281.1 2281.3 1940.9 1986.9 2207.3 2257.4 2262.2 2264.2 2268.2 2279.5 2288.3 2288.9 7497.1 7589.7 7596.0 7596.2 7599.1 4598.3 4673.2 4683.1 4705.8 4713.6 3141.8 3142.2 3149.8 3177.0 3253.1 1024.7 1050.7 1054.0 1066.4 1096.5 10192.7 10283.3 10289.5 10301.3 10331.1 4064.7 4170.4 4171.2 4188.8 4233.0 8500.5 8517.3 8517.4 8526.1 8535.4 4630.3 4658.1 4660.3 4668.0 4673.1 4260.6 4271.6 4321.7 4331.4 4332.7 3442.4 3498.6 3502.2 3506.1 3538.2 1088.3 1100.4 1103.5 1103.9 1110.4 1311.9 1315.6 1355.8 1369.2 1369.6 6387.5 6689.6 7217.6 7247.3 7277.0 615.5 622.7 624.6 632.1 632.8 5214.1 5227.5 5233.3 5247.9 5270.1 8544.7 9158.0 9161.5 9171.4 9173.9 8936.6 9042.3 9057.6 9087.5 9126.6 6700.9 6859.7 6929.3 6929.3 7080.0 6973.2 6973.6 7038.0 7041.3 7056.6 7432.1 7565.0 7761.7 7778.1 7785.5 7963.9 7972.3 8036.9 8111.4 8125.4 6300.9 6535.6 6583.8 6605.4 6615.1 7175.1 7305.7 7319.7 7321.9 7323.5 6571.8 6677.4 6698.0 6839.0 6888.8 6649.1 6747.1 6798.1 6825.1 6887.9 6362.7 6528.5 6906.8 6935.4 6961.3 6062.1 6066.4 6070.5 6074.8 6085.5 6077.4 6121.0 6122.1 6124.4 6149.2 04:15:09 INFO - 2015-11-26 04:15:09,426 DEBUG : BROWSER_OUTPUT: 04:15:09 INFO - 2015-11-26 04:15:09,426 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5322.38 Median:6090.72 stddev:1592.52 (26.1%) stddev-sans-first:1552.50 04:15:09 INFO - 2015-11-26 04:15:09,426 DEBUG : BROWSER_OUTPUT: Values: 342.3 345.1 351.3 352.9 353.3 5880.5 5985.7 6002.6 6015.0 6015.8 5918.4 5928.8 5938.8 5941.6 5947.9 4543.1 4568.5 4617.2 4621.0 4622.2 4610.4 4611.9 4616.2 4620.5 4623.7 4551.9 4562.7 4563.6 4565.0 4572.9 4374.8 4471.1 4472.0 4546.6 4566.3 3991.6 4007.2 4009.1 4025.1 4035.0 5452.3 5615.1 5627.3 5634.4 5638.5 5197.9 5234.8 5313.9 5315.4 5318.3 6250.1 6269.2 6275.9 6275.9 6279.8 4385.7 4411.0 4412.0 4424.6 4424.6 6326.9 6500.7 6503.7 6506.8 6530.9 4549.4 4558.9 4559.2 4561.3 4567.1 4609.4 4615.3 4615.5 4622.5 4623.4 5293.0 5297.4 5299.4 5301.1 5311.0 6167.2 6339.9 6343.0 6343.5 6344.9 1624.6 1643.9 1646.0 1647.7 1648.3 1113.6 1120.7 1122.2 1134.3 1148.7 6158.1 6158.3 6160.5 6169.3 6169.7 6518.5 6541.8 6544.7 6558.4 6566.8 6036.3 6145.2 6149.3 6153.8 6163.4 3519.0 3535.5 3547.4 3550.5 3556.6 6715.9 6757.8 6765.0 6769.6 6772.2 6528.5 6647.0 6663.4 6671.7 6685.4 6801.6 6813.9 6817.8 6831.4 6838.6 6606.7 6607.6 6611.0 6611.5 6613.9 6378.8 6511.5 6522.3 6528.2 6530.2 6788.1 6820.7 6832.8 6838.7 6853.8 4582.7 4585.2 4585.3 4588.3 4589.9 6417.5 6579.6 6580.4 6590.1 6595.1 6415.4 6417.5 6426.2 6428.8 6431.1 6407.2 6414.8 6415.9 6416.7 6416.8 6185.1 6269.8 6295.1 6297.3 6301.2 6430.1 6435.3 6444.6 6446.9 6464.3 6202.6 6275.8 6284.2 6285.7 6300.1 04:15:09 INFO - 2015-11-26 04:15:09,426 DEBUG : BROWSER_OUTPUT: 04:15:09 INFO - 2015-11-26 04:15:09,426 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13724.57 Median:49934.50 stddev:16150.44 (32.3%) stddev-sans-first:16166.38 04:15:09 INFO - 2015-11-26 04:15:09,427 DEBUG : BROWSER_OUTPUT: Values: 754.6 758.3 792.6 812.4 817.6 12170.1 12410.9 12449.6 12450.8 12453.6 14431.2 14597.0 14607.8 14636.0 14651.6 687.6 697.2 828.0 842.7 859.9 828.8 832.6 832.7 838.1 838.1 884.9 888.9 892.3 894.0 894.8 721.5 731.2 856.8 872.9 874.3 645.2 646.0 647.2 648.4 649.0 12298.9 12408.7 12458.7 12477.5 12488.3 11480.1 12093.4 12681.3 12752.7 12753.4 10091.2 10270.8 10371.9 10376.7 10630.3 3597.7 3647.4 3650.9 3696.3 3791.4 44103.6 44855.3 50705.7 51169.2 51288.7 9315.3 9749.9 9756.1 9859.8 10122.1 17797.4 17828.7 17835.0 17845.6 17885.6 7246.8 7349.0 7393.3 7445.6 7546.0 4045.6 4232.2 4270.0 4296.4 4304.6 3190.9 3203.2 3208.6 3221.2 3225.6 522.4 546.6 555.3 564.9 565.9 590.9 604.3 694.9 708.3 716.2 8190.4 8195.1 8259.9 8357.9 8455.5 584.4 636.0 658.2 684.3 713.4 3738.5 4088.8 4136.9 4258.6 4379.5 58361.0 58822.0 59468.1 59532.7 59704.4 49870.0 49999.0 51062.7 51231.0 51923.5 44861.3 46410.3 47032.3 47689.0 47772.3 8217.3 8264.6 8680.2 8720.6 8755.5 35671.3 35741.1 36198.3 36753.8 37002.3 42571.1 42598.1 43947.6 46035.8 46185.3 10353.3 10388.3 10414.6 10465.1 10749.5 11564.4 11567.7 11623.0 11677.8 11846.9 10777.9 10966.5 11032.7 11172.9 11373.2 10553.6 10764.8 10830.9 11064.9 11104.4 8174.0 9436.3 9807.9 11014.9 11081.5 8762.7 9407.3 9428.5 9455.7 9758.3 9004.9 9185.8 9303.7 9342.6 9534.7 04:15:09 INFO - 2015-11-26 04:15:09,427 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:15:09 INFO - 2015-11-26 04:15:09,427 DEBUG : BROWSER_OUTPUT: 04:15:10 INFO - 2015-11-26 04:15:10,109 INFO : Browser exited with error code: 0 04:15:10 INFO - 2015-11-26 04:15:10,128 INFO : Completed test dromaeo_css (00:18:32) 04:15:10 INFO - 2015-11-26 04:15:10,128 INFO : Starting test kraken 04:15:10 INFO - 2015-11-26 04:15:10,128 DEBUG : operating with platform_type : linux_ 04:15:10 INFO - 2015-11-26 04:15:10,128 INFO : Initialising browser for kraken test... 04:15:10 INFO - 2015-11-26 04:15:10,134 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDnPWv_/profile http://localhost:54118/getInfo.html 04:15:13 INFO - 2015-11-26 04:15:13,508 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:15:13 INFO - 2015-11-26 04:15:13,509 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:15:13 INFO - 2015-11-26 04:15:13,509 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:15:13 INFO - 2015-11-26 04:15:13,509 DEBUG : BROWSER_OUTPUT: __metrics 04:15:13 INFO - 2015-11-26 04:15:13,591 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:15:14 INFO - 2015-11-26 04:15:14,654 INFO : Browser initialized. 04:15:14 INFO - 2015-11-26 04:15:14,655 INFO : Running cycle 1/1 for kraken test... 04:15:14 INFO - 2015-11-26 04:15:14,655 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDnPWv_/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:15:26 INFO - 2015-11-26 04:15:26,412 DEBUG : BROWSER_OUTPUT: RSS: Main: 139128832 04:15:26 INFO - 2015-11-26 04:15:26,412 DEBUG : BROWSER_OUTPUT: 04:15:28 INFO - 2015-11-26 04:15:28,875 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54118/tests/kraken/driver.html?testName=audio-beat-detection) 04:15:29 INFO - 2015-11-26 04:15:29,460 DEBUG : BROWSER_OUTPUT: RSS: Main: 175366144 04:15:29 INFO - 2015-11-26 04:15:29,460 DEBUG : BROWSER_OUTPUT: 04:15:32 INFO - 2015-11-26 04:15:32,077 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54118/tests/kraken/driver.html?testName=audio-dft) 04:15:32 INFO - 2015-11-26 04:15:32,643 DEBUG : BROWSER_OUTPUT: RSS: Main: 165330944 04:15:32 INFO - 2015-11-26 04:15:32,643 DEBUG : BROWSER_OUTPUT: 04:15:35 INFO - 2015-11-26 04:15:35,473 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54118/tests/kraken/driver.html?testName=audio-fft) 04:15:36 INFO - 2015-11-26 04:15:36,074 DEBUG : BROWSER_OUTPUT: RSS: Main: 149835776 04:15:36 INFO - 2015-11-26 04:15:36,074 DEBUG : BROWSER_OUTPUT: 04:15:38 INFO - 2015-11-26 04:15:38,026 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54118/tests/kraken/driver.html?testName=audio-oscillator) 04:15:38 INFO - 2015-11-26 04:15:38,589 DEBUG : BROWSER_OUTPUT: RSS: Main: 153690112 04:15:38 INFO - 2015-11-26 04:15:38,589 DEBUG : BROWSER_OUTPUT: 04:15:40 INFO - 2015-11-26 04:15:40,791 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54118/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:15:41 INFO - 2015-11-26 04:15:41,354 DEBUG : BROWSER_OUTPUT: RSS: Main: 154783744 04:15:41 INFO - 2015-11-26 04:15:41,354 DEBUG : BROWSER_OUTPUT: 04:15:44 INFO - 2015-11-26 04:15:44,225 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54118/tests/kraken/driver.html?testName=imaging-darkroom) 04:15:44 INFO - 2015-11-26 04:15:44,806 DEBUG : BROWSER_OUTPUT: RSS: Main: 154796032 04:15:44 INFO - 2015-11-26 04:15:44,807 DEBUG : BROWSER_OUTPUT: 04:15:48 INFO - 2015-11-26 04:15:48,152 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54118/tests/kraken/driver.html?testName=imaging-desaturate) 04:15:48 INFO - 2015-11-26 04:15:48,724 DEBUG : BROWSER_OUTPUT: RSS: Main: 155115520 04:15:48 INFO - 2015-11-26 04:15:48,725 DEBUG : BROWSER_OUTPUT: 04:15:51 INFO - 2015-11-26 04:15:51,467 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54118/tests/kraken/driver.html?testName=json-parse-financial) 04:15:52 INFO - 2015-11-26 04:15:52,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 154611712 04:15:52 INFO - 2015-11-26 04:15:52,037 DEBUG : BROWSER_OUTPUT: 04:15:53 INFO - 2015-11-26 04:15:53,570 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54118/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:15:54 INFO - 2015-11-26 04:15:54,136 DEBUG : BROWSER_OUTPUT: RSS: Main: 167301120 04:15:54 INFO - 2015-11-26 04:15:54,136 DEBUG : BROWSER_OUTPUT: 04:15:55 INFO - 2015-11-26 04:15:55,958 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54118/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:15:56 INFO - 2015-11-26 04:15:56,566 DEBUG : BROWSER_OUTPUT: RSS: Main: 167321600 04:15:56 INFO - 2015-11-26 04:15:56,566 DEBUG : BROWSER_OUTPUT: 04:15:58 INFO - 2015-11-26 04:15:58,664 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54118/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:15:59 INFO - 2015-11-26 04:15:59,234 DEBUG : BROWSER_OUTPUT: RSS: Main: 172265472 04:15:59 INFO - 2015-11-26 04:15:59,234 DEBUG : BROWSER_OUTPUT: 04:16:01 INFO - 2015-11-26 04:16:01,669 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54118/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:16:02 INFO - 2015-11-26 04:16:02,242 DEBUG : BROWSER_OUTPUT: RSS: Main: 181489664 04:16:02 INFO - 2015-11-26 04:16:02,243 DEBUG : BROWSER_OUTPUT: 04:16:04 INFO - 2015-11-26 04:16:04,750 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54118/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:16:05 INFO - 2015-11-26 04:16:05,324 DEBUG : BROWSER_OUTPUT: RSS: Main: 180576256 04:16:05 INFO - 2015-11-26 04:16:05,324 DEBUG : BROWSER_OUTPUT: 04:16:06 INFO - 2015-11-26 04:16:06,993 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54118/tests/kraken/driver.html?testName=ai-astar) 04:16:07 INFO - 2015-11-26 04:16:07,243 DEBUG : BROWSER_OUTPUT: RSS: Main: 239226880 04:16:07 INFO - 2015-11-26 04:16:07,243 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,246 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:16:07 INFO - 2015-11-26 04:16:07,247 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:16:07 INFO - 2015-11-26 04:16:07,247 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:16:07 INFO - 2015-11-26 04:16:07,247 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:16:07 INFO - 2015-11-26 04:16:07,247 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;112;105;110;112;113;98;109;108;105 04:16:07 INFO - 2015-11-26 04:16:07,247 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;123;163;167;127;149;158;123;164;124 04:16:07 INFO - 2015-11-26 04:16:07,247 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;162;161;161;163;161;162;161;163;162 04:16:07 INFO - 2015-11-26 04:16:07,247 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;88;97;73;71;71;102;91;71 04:16:07 INFO - 2015-11-26 04:16:07,248 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;94;146;92;94;95;156;93;94;133 04:16:07 INFO - 2015-11-26 04:16:07,248 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;109;110;109;110;110;109;109 04:16:07 INFO - 2015-11-26 04:16:07,248 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;147;146;145;145;145;145;146;145;146 04:16:07 INFO - 2015-11-26 04:16:07,248 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;96;95;96;95;95 04:16:07 INFO - 2015-11-26 04:16:07,248 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;56;57;57;56;57;57;56;57;55 04:16:07 INFO - 2015-11-26 04:16:07,248 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;71;70;72;72;71;71;71;71;71 04:16:07 INFO - 2015-11-26 04:16:07,248 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;91;83;92;84;85;86;86;86;92;89 04:16:07 INFO - 2015-11-26 04:16:07,248 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;124;116;112;121;123;126;126;122;129 04:16:07 INFO - 2015-11-26 04:16:07,249 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;147;144;146;146;145;145;147;146;135 04:16:07 INFO - 2015-11-26 04:16:07,249 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;69;68;66;65;67;67;64;65;66 04:16:07 INFO - 2015-11-26 04:16:07,249 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:16:07 INFO - 2015-11-26 04:16:07,249 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:16:07 INFO - 2015-11-26 04:16:07,249 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,931 04:16:07 INFO - 2015-11-26 04:16:07,249 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:16:07 INFO - 2015-11-26 04:16:07,249 DEBUG : BROWSER_OUTPUT: __startTimestamp1448540167245__endTimestamp 04:16:07 INFO - 2015-11-26 04:16:07,249 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,252 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.10 Median:111.00 stddev:4.48 (4.0%) stddev-sans-first:4.74 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: Values: 109.0 112.0 105.0 110.0 112.0 113.0 98.0 109.0 108.0 105.0 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.60 Median:153.50 stddev:18.54 (12.1%) stddev-sans-first:19.63 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: Values: 148.0 123.0 163.0 167.0 127.0 149.0 158.0 123.0 164.0 124.0 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:0.83 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: Values: 160.0 162.0 161.0 161.0 163.0 161.0 162.0 161.0 163.0 162.0 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.70 Median:72.50 stddev:12.43 (17.1%) stddev-sans-first:12.78 04:16:07 INFO - 2015-11-26 04:16:07,253 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 88.0 97.0 73.0 71.0 71.0 102.0 91.0 71.0 04:16:07 INFO - 2015-11-26 04:16:07,254 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,254 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:108.90 Median:93.50 stddev:25.51 (27.3%) stddev-sans-first:26.32 04:16:07 INFO - 2015-11-26 04:16:07,254 DEBUG : BROWSER_OUTPUT: Values: 92.0 94.0 146.0 92.0 94.0 95.0 156.0 93.0 94.0 133.0 04:16:07 INFO - 2015-11-26 04:16:07,254 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,254 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 04:16:07 INFO - 2015-11-26 04:16:07,254 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 109.0 110.0 109.0 110.0 110.0 109.0 109.0 04:16:07 INFO - 2015-11-26 04:16:07,254 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,254 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 04:16:07 INFO - 2015-11-26 04:16:07,255 DEBUG : BROWSER_OUTPUT: Values: 145.0 147.0 146.0 145.0 145.0 145.0 145.0 146.0 145.0 146.0 04:16:07 INFO - 2015-11-26 04:16:07,255 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,255 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:16:07 INFO - 2015-11-26 04:16:07,255 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 04:16:07 INFO - 2015-11-26 04:16:07,255 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,255 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:0.84 (1.5%) stddev-sans-first:0.73 04:16:07 INFO - 2015-11-26 04:16:07,255 DEBUG : BROWSER_OUTPUT: Values: 58.0 56.0 57.0 57.0 56.0 57.0 57.0 56.0 57.0 55.0 04:16:07 INFO - 2015-11-26 04:16:07,255 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,256 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:0.67 (0.9%) stddev-sans-first:0.60 04:16:07 INFO - 2015-11-26 04:16:07,256 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 70.0 72.0 72.0 71.0 71.0 71.0 71.0 71.0 04:16:07 INFO - 2015-11-26 04:16:07,256 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,256 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:87.50 stddev:3.34 (3.8%) stddev-sans-first:3.28 04:16:07 INFO - 2015-11-26 04:16:07,256 DEBUG : BROWSER_OUTPUT: Values: 91.0 83.0 92.0 84.0 85.0 86.0 86.0 86.0 92.0 89.0 04:16:07 INFO - 2015-11-26 04:16:07,256 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,256 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.70 Median:123.50 stddev:5.14 (4.2%) stddev-sans-first:5.28 04:16:07 INFO - 2015-11-26 04:16:07,256 DEBUG : BROWSER_OUTPUT: Values: 118.0 124.0 116.0 112.0 121.0 123.0 126.0 126.0 122.0 129.0 04:16:07 INFO - 2015-11-26 04:16:07,257 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,257 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.20 Median:146.00 stddev:3.68 (2.5%) stddev-sans-first:3.71 04:16:07 INFO - 2015-11-26 04:16:07,257 DEBUG : BROWSER_OUTPUT: Values: 141.0 147.0 144.0 146.0 146.0 145.0 145.0 147.0 146.0 135.0 04:16:07 INFO - 2015-11-26 04:16:07,257 DEBUG : BROWSER_OUTPUT: 04:16:07 INFO - 2015-11-26 04:16:07,257 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.71 (2.6%) stddev-sans-first:1.58 04:16:07 INFO - 2015-11-26 04:16:07,257 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 68.0 66.0 65.0 67.0 67.0 64.0 65.0 66.0 04:16:07 INFO - 2015-11-26 04:16:07,257 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:16:07 INFO - 2015-11-26 04:16:07,257 DEBUG : BROWSER_OUTPUT: 04:16:08 INFO - 2015-11-26 04:16:08,025 INFO : Browser exited with error code: 0 04:16:08 INFO - 2015-11-26 04:16:08,078 INFO : Completed test kraken (00:00:57) 04:16:08 INFO - 2015-11-26 04:16:08,078 INFO : Starting test v8_7 04:16:08 INFO - 2015-11-26 04:16:08,078 DEBUG : operating with platform_type : linux_ 04:16:08 INFO - 2015-11-26 04:16:08,079 INFO : Initialising browser for v8_7 test... 04:16:08 INFO - 2015-11-26 04:16:08,085 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp68dVtz/profile http://localhost:54118/getInfo.html 04:16:11 INFO - 2015-11-26 04:16:11,625 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:16:11 INFO - 2015-11-26 04:16:11,625 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:16:11 INFO - 2015-11-26 04:16:11,625 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:16:11 INFO - 2015-11-26 04:16:11,625 DEBUG : BROWSER_OUTPUT: __metrics 04:16:11 INFO - 2015-11-26 04:16:11,762 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:16:12 INFO - 2015-11-26 04:16:12,805 INFO : Browser initialized. 04:16:12 INFO - 2015-11-26 04:16:12,805 INFO : Running cycle 1/1 for v8_7 test... 04:16:12 INFO - 2015-11-26 04:16:12,806 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp68dVtz/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:16:24 INFO - 2015-11-26 04:16:24,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 138706944 04:16:24 INFO - 2015-11-26 04:16:24,424 DEBUG : BROWSER_OUTPUT: 04:16:45 INFO - 2015-11-26 04:16:45,809 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54118/tests/v8_7/run.html (next: http://localhost:54118/tests/v8_7/run.html) 04:16:46 INFO - 2015-11-26 04:16:46,059 DEBUG : BROWSER_OUTPUT: RSS: Main: 602279936 04:16:46 INFO - 2015-11-26 04:16:46,059 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,061 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:16:46 INFO - 2015-11-26 04:16:46,061 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:16:46 INFO - 2015-11-26 04:16:46,061 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:16:46 INFO - 2015-11-26 04:16:46,061 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:16:46 INFO - 2015-11-26 04:16:46,061 DEBUG : BROWSER_OUTPUT: |0;Richards;164.0150893882237 04:16:46 INFO - 2015-11-26 04:16:46,061 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.1370607649 04:16:46 INFO - 2015-11-26 04:16:46,062 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.12570781426956 04:16:46 INFO - 2015-11-26 04:16:46,062 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5229.166666666667 04:16:46 INFO - 2015-11-26 04:16:46,062 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1127.3957158962796 04:16:46 INFO - 2015-11-26 04:16:46,062 DEBUG : BROWSER_OUTPUT: |5;Earley;778.988326848249 04:16:46 INFO - 2015-11-26 04:16:46,062 DEBUG : BROWSER_OUTPUT: |6;Boyer;12268.292682926829 04:16:46 INFO - 2015-11-26 04:16:46,062 DEBUG : BROWSER_OUTPUT: |7;RegExp;25100 04:16:46 INFO - 2015-11-26 04:16:46,062 DEBUG : BROWSER_OUTPUT: |8;Splay;680.7065217391304 04:16:46 INFO - 2015-11-26 04:16:46,063 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 04:16:46 INFO - 2015-11-26 04:16:46,063 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:16:46 INFO - 2015-11-26 04:16:46,063 DEBUG : BROWSER_OUTPUT: __startTimestamp1448540206060__endTimestamp 04:16:46 INFO - 2015-11-26 04:16:46,063 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,063 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:16:46 INFO - 2015-11-26 04:16:46,064 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:16:46 INFO - 2015-11-26 04:16:46,064 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,064 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.02 Median:164.02 stddev:0.00 (0.0%) 04:16:46 INFO - 2015-11-26 04:16:46,064 DEBUG : BROWSER_OUTPUT: Values: 164.0 04:16:46 INFO - 2015-11-26 04:16:46,064 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,065 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.14 Median:194.14 stddev:0.00 (0.0%) 04:16:46 INFO - 2015-11-26 04:16:46,065 DEBUG : BROWSER_OUTPUT: Values: 194.1 04:16:46 INFO - 2015-11-26 04:16:46,065 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,065 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.13 Median:283.13 stddev:0.00 (0.0%) 04:16:46 INFO - 2015-11-26 04:16:46,065 DEBUG : BROWSER_OUTPUT: Values: 283.1 04:16:46 INFO - 2015-11-26 04:16:46,066 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,066 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5229.17 Median:5229.17 stddev:0.00 (0.0%) 04:16:46 INFO - 2015-11-26 04:16:46,066 DEBUG : BROWSER_OUTPUT: Values: 5229.2 04:16:46 INFO - 2015-11-26 04:16:46,066 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,066 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1127.40 Median:1127.40 stddev:0.00 (0.0%) 04:16:46 INFO - 2015-11-26 04:16:46,067 DEBUG : BROWSER_OUTPUT: Values: 1127.4 04:16:46 INFO - 2015-11-26 04:16:46,067 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,067 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:778.99 Median:778.99 stddev:0.00 (0.0%) 04:16:46 INFO - 2015-11-26 04:16:46,067 DEBUG : BROWSER_OUTPUT: Values: 779.0 04:16:46 INFO - 2015-11-26 04:16:46,067 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,067 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12268.29 Median:12268.29 stddev:0.00 (0.0%) 04:16:46 INFO - 2015-11-26 04:16:46,067 DEBUG : BROWSER_OUTPUT: Values: 12268.3 04:16:46 INFO - 2015-11-26 04:16:46,067 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,067 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25100.00 Median:25100.00 stddev:0.00 (0.0%) 04:16:46 INFO - 2015-11-26 04:16:46,068 DEBUG : BROWSER_OUTPUT: Values: 25100.0 04:16:46 INFO - 2015-11-26 04:16:46,068 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,068 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:680.71 Median:680.71 stddev:0.00 (0.0%) 04:16:46 INFO - 2015-11-26 04:16:46,068 DEBUG : BROWSER_OUTPUT: Values: 680.7 04:16:46 INFO - 2015-11-26 04:16:46,068 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,068 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 04:16:46 INFO - 2015-11-26 04:16:46,068 DEBUG : BROWSER_OUTPUT: Values: 4830.9 04:16:46 INFO - 2015-11-26 04:16:46,068 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:16:46 INFO - 2015-11-26 04:16:46,068 DEBUG : BROWSER_OUTPUT: 04:16:46 INFO - 2015-11-26 04:16:46,905 INFO : Browser exited with error code: 0 04:16:46 INFO - 2015-11-26 04:16:46,922 INFO : Completed test v8_7 (00:00:38) 04:16:47 INFO - 2015-11-26 04:16:47,070 INFO : Completed test suite (00:20:09) 04:16:47 INFO - 2015-11-26 04:16:47,070 DEBUG : Working with test: dromaeo_css 04:16:47 INFO - 2015-11-26 04:16:47,070 DEBUG : Generating results file: dromaeo_css 04:16:47 INFO - 2015-11-26 04:16:47,071 DEBUG : Working with test: kraken 04:16:47 INFO - 2015-11-26 04:16:47,072 DEBUG : Generating results file: kraken 04:16:47 INFO - 2015-11-26 04:16:47,072 INFO : javascript benchmark 04:16:47 INFO - 2015-11-26 04:16:47,072 DEBUG : Working with test: v8_7 04:16:47 INFO - 2015-11-26 04:16:47,072 DEBUG : Generating results file: v8_7 04:16:47 INFO - 2015-11-26 04:16:47,072 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:16:47 INFO - 2015-11-26 04:16:47,126 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 04:16:47 INFO - 2015-11-26 04:16:47,126 DEBUG : process_Request line: dromaeo_css 6937.42 graph.html#tests=[[72,64,35]] 04:16:47 INFO - 2015-11-26 04:16:47,127 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:16:47 INFO - 2015-11-26 04:16:47,160 DEBUG : process_Request line: kraken 1501.60 graph.html#tests=[[232,64,35]] 04:16:47 INFO - 2015-11-26 04:16:47,160 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:16:47 INFO - 2015-11-26 04:16:47,190 DEBUG : process_Request line: v8_7 20759.13 graph.html#tests=[[230,64,35]] 04:16:47 INFO - 2015-11-26 04:16:47,191 INFO : javascript benchmark 04:16:47 INFO - 2015-11-26 04:16:47,195 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3145.7640676949227, 3211.6948889855457, 3243.0433022258444, 3251.2684645954664, 3271.5910511186103, 5159.303494028305, 5205.297284866542, 5282.894342113165, 5417.187421886714, 5431.538319242864, 5281.656692314997, 5319.414864364917, 5338.06583847827, 5341.332333458317, 5461.808836690717, 4877.926831097537, 4907.9018419631675, 4914.287428322891, 4965.329680493123, 4993.375828021497, 5011.072951503965, 5019.54824065834, 5078.822241221558, 5092.312537807404, 5094.261332106842, 4783.856484305476, 4793.18515852306, 4873.537013983666, 4909.705417674951, 4938.456769755324, 4896.706197628154, 4911.45973942866, 4916.557509824133, 4930.260460930853, 4950.2822090796635, 4894.043414421211, 4894.486078961693, 4895.363602731658, 4896.877578060541, 4969.602431805447, 4991.32617096691, 5103.106956282636, 5116.079105760961, 5123.692578445305, 5133.178691409356, 5688.061469857468, 5705.885882282331, 5707.68607726579, 5712.82861514151, 5715.2855893013375, 5741.971290143522, 5756.079027355645, 5765.135229715576, 5803.0, 5913.763449462016, 5526.778928842841, 5536.86157846049, 5540.445955404468, 5551.861203469905, 5595.552355811526, 5899.911501327483, 5951.077582974646, 5996.040633498619, 5996.16053752475, 6011.5190784737115, 5463.836084917459, 5549.750261238254, 5619.072852979212, 5795.681237531892, 5825.475707186373, 5894.941050589439, 5896.439838215363, 5898.911516327258, 5903.321118071391, 5917.31950825652, 5841.802430501737, 5862.70686465675, 5867.559933005042, 5870.471657550841, 5878.059510478303, 5587.1339942309005, 5715.028445164332, 5719.942800572025, 5836.0, 5885.058390657474, 4532.0709254602725, 4538.931916021263, 4542.727436353764, 4545.727256364628, 4574.977125114419, 4020.0552870075508, 4035.1122752994293, 4037.8990525237105, 4082.0203151243486, 4089.5705950875326, 5217.399999000164, 5340.786368545292, 5403.297571315782, 5462.0987537056735, 5526.336839579276, 5817.127430885316, 5871.089981052944, 5879.588428810028, 5881.05903055509, 5884.735186916599, 5222.843314700565, 5525.143602741581, 5535.640183388067, 5548.278723765965, 5549.056660367747, 4881.341018962466, 5025.095482813129, 5090.974545127251, 5096.311997880239, 5142.023015627019, 5984.521238301013, 6007.759689612367, 6020.307664618537, 6023.096535519707, 6025.276966763929, 5523.8895222096135, 5619.0, 5824.679642619697, 5839.35767065614, 5843.824685259449, 5702.515286200553, 5982.0, 6076.088586712029, 6077.580865039038, 6134.294556126102, 5705.743241554057, 5752.07966725322, 5754.827355179351, 5756.107803290497, 5759.424057594375, 5495.505404513613, 5559.527440167631, 5835.328937172279, 5853.268341457318, 5857.0, 5489.58828087887, 5546.944530554642, 5636.633618814928, 5651.4348565143155, 5706.28671416073, 5672.971635141798, 5677.829665110054, 5704.429557044263, 5711.600187986801, 5713.171590119493, 5143.588512918883, 5332.68003919766, 5579.637323574117, 5599.132134519156, 5603.719814009365, 5690.516306113861, 5780.190773291659, 5839.970800145972, 5841.1238314253205, 5841.50347220491, 5512.090505066619, 5537.307836520435, 5537.8061767837935, 5551.444855514416, 5557.888842223214], "unit": "ms", "name": "dojo.html", "value": 5341.078074630716}, {"lowerIsBetter": false, "replicates": [572.2503520388289, 585.6720236667469, 598.6408155106936, 618.5855476830523, 625.159160928551, 31365.84317078414, 31910.68089319106, 31922.201944951386, 31970.360592788187, 39626.0, 42288.788556057174, 42842.35736463955, 42900.0, 42912.57087429125, 42981.785091074504, 8459.323254139685, 8740.781480462976, 8754.124587541226, 8758.343124265673, 8760.649574017063, 7778.8833167502535, 7956.960215198916, 7960.4825686330205, 7964.840703185932, 8587.699430519933, 10404.115650169706, 10583.73540661482, 10648.574057037747, 10649.0, 10664.573417063308, 10243.38539687621, 10354.689359319233, 10360.430176340336, 10362.27464077515, 10380.636677716282, 7855.0, 8050.959745201237, 8055.879161812578, 10155.187584993182, 10295.691129266133, 30272.394552108835, 43030.56969430328, 43757.56242437599, 43981.34027989583, 44055.33916991248, 3243.221626809572, 3330.400527904976, 3337.8497967591275, 3346.866125354983, 3717.7955212463303, 29094.563581546292, 29265.0, 29575.70424295751, 29704.0, 29912.55131173034, 3863.459115723801, 3892.5134358205223, 3904.958164709376, 3931.5282166140246, 4001.0597509585223, 243096.0, 244731.0, 244899.0, 244902.0, 245557.0, 55526.16710749342, 55871.44128558744, 56011.43988560144, 56018.719906400205, 56026.43973560212, 59144.0, 59397.0, 59446.108308375406, 59447.702761486784, 59470.405295947356, 14692.485762998458, 14924.104553726811, 15018.0, 15022.924885375503, 15043.849561504463, 12096.33470159132, 12206.267623942415, 12213.633590992284, 12221.69445763863, 12224.511019559119, 18947.14842331026, 19107.808921910604, 19202.13590388436, 19349.709754353695, 19408.12663430149, 7836.529808211526, 8071.5157090573575, 8107.878381824277, 8197.34421246299, 8235.95882020586, 954.8185844689486, 965.2133511188359, 1015.6496008876926, 1018.4856647393019, 1029.361795686679, 25428.982840686534, 25500.48999020046, 25518.23445296644, 25541.361465963128, 25543.744562554137, 1045.7751583409604, 1046.9268999275803, 1074.505727365405, 1105.6848798092506, 1137.6700756780606, 13944.372503237379, 13967.161970281815, 14204.28978551089, 14215.218163001135, 15171.924140379448, 309537.0, 313039.0, 313132.0, 313548.0, 313669.0, 43810.0, 44444.77777611091, 58839.41160588339, 60466.69766651138, 60612.39387606156, 1394.463131694305, 1398.356755892301, 1408.8520705325793, 1409.7180563887264, 1413.3639862062114, 28525.0, 28669.0, 29919.700802992564, 30625.23436914095, 32628.0, 48548.27177592268, 49532.75233623809, 49584.0, 49631.503684962685, 49713.751431242614, 298880.0, 300000.0, 301639.0, 302452.0, 302613.0, 32498.0, 32622.34755304928, 33130.0, 33143.33713325768, 33166.834165829016, 42608.0, 42771.14457710766, 43400.348994765736, 43604.12791744084, 43716.56283437125, 36244.63755362518, 36493.635063649024, 36971.0, 37363.252734945694, 37532.0, 35716.0, 36448.205917325016, 37310.44034339433, 37324.0, 37366.813165934, 35998.4600231002, 36224.45663315105, 36238.27523449569, 40251.19497609973, 41593.37609935793, 32364.676353236166, 33100.83449582737, 33225.50161747624, 33357.16607084842, 33769.324613508084, 25668.871655641604, 25723.742762572136, 25874.35314117162, 25932.8703356482, 26296.8685156573], "unit": "ms", "name": "ext.html", "value": 18661.881208686562}, {"lowerIsBetter": false, "replicates": [338.7070184290588, 341.9025577710353, 361.73593276907854, 362.26279521174416, 363.353231248378, 13625.795613065788, 13971.231582262997, 16176.110313932744, 16667.833321666796, 17511.29954801808, 13435.596932092029, 14381.496647617336, 14658.340374683139, 14675.266236688176, 16327.673446531062, 5198.844034678966, 5326.493983071621, 5327.733613319338, 5333.519983201511, 5334.91997620036, 4014.9397759033636, 4127.195196936584, 5361.571074314065, 5393.946060539383, 5763.135529670562, 5805.187273781653, 5829.358770535237, 5844.707764611774, 5858.677772722499, 5867.383924687911, 5676.432356764331, 5815.912761308584, 5818.563607729416, 5822.155787410802, 5832.562557808182, 5200.8179713710215, 5230.816921407771, 5234.528892399664, 5235.6073294502685, 5238.319018527605, 19502.414927552196, 19852.60294794096, 19875.701864472045, 20025.8998705007, 20607.381778546667, 1918.8464922806143, 2264.2527965771255, 2330.685357476753, 2342.367560758603, 2557.629143774161, 8660.047394786568, 8787.384883058188, 8876.556172191365, 8911.376203665746, 9023.819523609556, 1774.4942691332908, 1782.0911335219132, 1801.639672065592, 1806.3948577226645, 1828.4606041217874, 52735.20897186545, 55073.0, 59427.70286148541, 61778.99999999955, 62549.06176407357, 8956.552172391355, 9355.204807591295, 9815.901840981498, 9934.602615895283, 10085.848712269322, 13118.672033199056, 13168.60494185176, 13255.469781208836, 13258.071934964653, 13262.53581124656, 7771.7668469944865, 7795.14253432122, 7834.294913457816, 7873.645685944031, 7900.44696871225, 7497.400207983348, 7529.284717951786, 7563.205863384375, 7577.734779282699, 7638.197989211053, 4276.294411422143, 4351.412559304454, 4355.847545335898, 4377.978110109493, 4401.141777353385, 1620.8541231289241, 1639.7294446416208, 1643.7973116024584, 1644.4244514420097, 1736.4877361178483, 841.486693117198, 970.5583959298502, 997.4814092162516, 1022.1805186767566, 1068.326954018963, 14886.915831783559, 15652.68694626101, 15761.763573546406, 16502.17489125539, 16682.833171668128, 937.723371605378, 952.9850708995036, 965.3001573859036, 1020.7754294055296, 1049.3284298049255, 9177.770555736026, 9200.171984521425, 9566.61733530651, 9766.609335626637, 9785.217182625373, 510300.0, 516164.0, 521068.0, 521534.0, 539103.0, 77270.0, 77744.0, 78661.60669196732, 78789.0, 79890.60054699806, 1183.6922400175845, 1191.6484637032097, 1193.5046955513537, 1194.498310709504, 1212.4059210986552, 15080.170590617621, 15178.772318415453, 15211.467598633995, 15220.771688424462, 15537.533873983799, 34628.78799242015, 34893.47659785156, 35216.0, 35689.0, 37399.0, 45019.32471012872, 45389.31916021196, 47153.0, 47267.29099063448, 47980.0, 9191.816163676824, 9584.37701549397, 9692.22462203008, 9700.175485083846, 9715.805683886425, 15190.46833360827, 15284.388624454898, 15373.461928832437, 15655.13896735645, 15694.058356498646, 11610.0, 11753.764924701629, 12134.817977730518, 13140.60578182656, 14045.929770351082, 13916.791248131489, 14024.298785060502, 14025.438982440588, 14041.578752637437, 14046.1572305662, 11644.184907056424, 11855.34795586251, 13605.115667481581, 14114.082584631966, 14500.419983200554, 8168.7957801055445, 8300.792480188044, 9035.638574456949, 9419.717408477756, 9465.432074075577, 9778.951105244429, 9818.214542836698, 13675.316234188052, 14012.089214201043, 14130.576082717535], "unit": "ms", "name": "jquery.html", "value": 8667.856682683818}, {"lowerIsBetter": false, "replicates": [1317.3347459532947, 1494.1184701026395, 1532.126687787961, 1689.662067586483, 1799.8290162434573, 6459.838504037396, 7062.540934839239, 7368.300011498899, 7386.298301661333, 7397.519161254515, 7408.888866667005, 7544.886826697604, 7545.962270188655, 7550.886736698954, 7576.431767617423, 1941.3884626342729, 1972.142118178597, 1976.7529058867642, 2252.2905284835224, 2258.7063681721434, 2269.716285464317, 2318.5942460069505, 2323.779240972105, 2323.9419014524688, 2324.337563794319, 2223.0996446439217, 2241.7309922809286, 2280.9771902281013, 2281.064763446987, 2281.2813963601498, 1940.8544359173047, 1986.9403917882487, 2207.3377986604114, 2257.3792207143, 2262.17430637816, 2264.230161745015, 2268.160780511221, 2279.4985103277254, 2288.290629904735, 2288.942776430586, 7497.062867141607, 7589.696412143508, 7596.0, 7596.240375962415, 7599.050118735158, 4598.2642777155825, 4673.205555055649, 4683.110209060268, 4705.835295764632, 4713.575778179946, 3141.8271995040263, 3142.1987393214813, 3149.7637677174303, 3176.9841150794327, 3253.056613582058, 1024.6615135145898, 1050.6532844161409, 1053.9736506587396, 1066.4347895615335, 1096.4572536594437, 10192.74518137045, 10283.280170388076, 10289.485525723683, 10301.27891047627, 10331.07020368163, 4064.7154699171365, 4170.35359519275, 4171.20747058058, 4188.8324467020975, 4232.957670423318, 8500.489970601784, 8517.318614510825, 8517.403781735218, 8526.147385261424, 8535.402521823537, 4630.282306242538, 4658.091672123904, 4660.347551342815, 4667.95332046682, 4673.065386922629, 4260.595243451867, 4271.551487093872, 4321.654267658642, 4331.436913201263, 4332.696711230246, 3442.3631628148873, 3498.632643572439, 3502.194495266103, 3506.1059429845227, 3538.168530395355, 1088.3469918049107, 1100.4442756408123, 1103.5034234594305, 1103.939283339424, 1110.4170310587006, 1311.9147255428365, 1315.5987423835631, 1355.8169647097516, 1369.1579678497653, 1369.589123263037, 6387.457066149336, 6689.5986240825705, 7217.566945983258, 7247.347738703542, 7277.017602623578, 615.5414216408805, 622.7415622516695, 624.6345887655772, 632.0677001422864, 632.7785275153659, 5214.0875346814155, 5227.451117632594, 5233.319668443078, 5247.868803279948, 5270.130428479258, 8544.700935467354, 9157.954210228907, 9161.496117713456, 9171.403858749294, 9173.908260917438, 8936.642534298686, 9042.276617870555, 9057.637694492147, 9087.454752714726, 9126.634934602675, 6700.932990670129, 6859.691313890787, 6929.272426395257, 6929.272426395358, 7080.0, 6973.19808222061, 6973.6164510952385, 7038.049863268393, 7041.3310735480045, 7056.611886346095, 7432.145303290191, 7564.962694690056, 7761.72833950809, 7778.105517865518, 7785.532868027936, 7963.920360796318, 7972.322352599862, 8036.87944680842, 8111.351091912515, 8125.3905957052275, 6300.936990630035, 6535.640539770168, 6583.769568065095, 6605.372489613479, 6615.073889655356, 7175.103112110986, 7305.707771689073, 7319.707211711685, 7321.853562928605, 7323.523970941872, 6571.769988050395, 6677.43241824431, 6698.0, 6839.0, 6888.793336199922, 6649.102371179829, 6747.055412242388, 6798.082258894986, 6825.146856642919, 6887.931120688729, 6362.650054246876, 6528.510361722795, 6906.827329316808, 6935.445164386938, 6961.269066747918, 6062.120992456157, 6066.363031881765, 6070.484008859296, 6074.787382441594, 6085.4523092921845, 6077.361877003028, 6120.969395152996, 6122.051082082285, 6124.38756124384, 6149.200603921461], "unit": "ms", "name": "mootools.html", "value": 4294.304696959475}, {"lowerIsBetter": false, "replicates": [342.2914566846627, 345.1165017555059, 351.2763706764065, 352.86591095383744, 353.27049642488277, 5880.470757631812, 5985.730642121104, 6002.639841609506, 6015.0, 6015.849603759908, 5918.378570250115, 5928.762849486026, 5938.792142275021, 5941.64350138992, 5947.851303717409, 4543.068670922469, 4568.5431456854385, 4617.191991401516, 4621.006483606024, 4622.214223581982, 4610.377599024139, 4611.884702882421, 4616.169089563878, 4620.468646105707, 4623.653226008046, 4551.908961820762, 4562.749048802315, 4563.634909207255, 4565.0, 4572.885677858047, 4374.781260936957, 4471.128130014648, 4471.955280447187, 4546.568076032805, 4566.269396896497, 3991.600839916014, 4007.198560287954, 4009.0979529605897, 4025.0943537704075, 4034.9596504034876, 5452.345718513804, 5615.129654903456, 5627.2684551008515, 5634.3520495143175, 5638.548916086703, 5197.922031169535, 5234.790608375661, 5313.867153321159, 5315.415304316522, 5318.335208098988, 6250.093736408241, 6269.185005949243, 6275.905861412082, 6275.937240627581, 6279.843003924892, 4385.7149285296355, 4410.977945110286, 4411.955880441186, 4424.601785839258, 4424.623906967911, 6326.936730632727, 6500.707468163944, 6503.739850405978, 6506.804795856132, 6530.902036469457, 4549.363089167519, 4558.88602784933, 4559.247724125482, 4561.29299958507, 4567.086582683477, 4609.423822022247, 4615.330777037311, 4615.515370886076, 4622.537746225351, 4623.42207224097, 5293.0, 5297.364316282071, 5299.417064122943, 5301.0723123453245, 5311.044012077863, 6167.229096362955, 6339.873202535923, 6343.048542718537, 6343.524235682342, 6344.904826427607, 1624.5694890853933, 1643.9013659180487, 1645.9588510287338, 1647.6622292430263, 1648.307710761459, 1113.5935383584933, 1120.7310245541012, 1122.1639878290562, 1134.3307448605362, 1148.7300484386162, 6158.076288556752, 6158.291796443466, 6160.537959653044, 6169.321374648785, 6169.660668663177, 6518.511111666644, 6541.836454088686, 6544.70548825304, 6558.409743123142, 6566.770163044271, 6036.275646922398, 6145.170401995673, 6149.2620885494025, 6153.8461538461, 6163.38366163389, 3518.9970858305774, 3535.5227044348685, 3547.396942519726, 3550.5029295898594, 3556.5732112146707, 6715.865682686319, 6757.831054223684, 6765.019072234499, 6769.593824370553, 6772.153480814898, 6528.477721782246, 6647.036179753909, 6663.36698013688, 6671.699773510204, 6685.431738302202, 6801.625910574966, 6813.897791533131, 6817.761378351734, 6831.419329356961, 6838.623875686885, 6606.702698378681, 6607.603543787388, 6610.9669451652435, 6611.537192396486, 6613.933860661332, 6378.77674281398, 6511.479081673549, 6522.315156908646, 6528.183977002875, 6530.249021362414, 6788.083608712958, 6820.658967051529, 6832.829179270558, 6838.692258848463, 6853.7943861684225, 4582.679212455096, 4585.220512512948, 4585.266357382803, 4588.2888152336445, 4589.862304130881, 6417.486601071996, 6579.572327798678, 6580.374864387875, 6590.1103351046995, 6595.109660196004, 6415.358464153734, 6417.486601071996, 6426.196725409324, 6428.8071357859335, 6431.067495213074, 6407.167068281094, 6414.775482858077, 6415.903761443676, 6416.711247993945, 6416.839579010375, 6185.134081228542, 6269.780557680459, 6295.087212354091, 6297.307296197323, 6301.180846489927, 6430.099786030053, 6435.292117866938, 6444.615492267891, 6446.871062578816, 6464.256610489854, 6202.627842329294, 6275.843103922439, 6284.183056202664, 6285.654289014148, 6300.054991751274], "unit": "ms", "name": "prototype.html", "value": 4812.396549114551}, {"lowerIsBetter": false, "replicates": [754.5548126605304, 758.2947858491603, 792.6433105102701, 812.4312980913359, 817.6320655704927, 12170.148089633718, 12410.875891241085, 12449.626511204679, 12450.81323780144, 12453.564125255618, 14431.206283654396, 14597.0, 14607.78088328676, 14636.0, 14651.633709157277, 687.5530904911823, 697.2051860878603, 828.0229329391316, 842.6629348260699, 859.9355048371366, 828.7638023163399, 832.5587438657521, 832.7127141136298, 838.053000109875, 838.1116017021945, 884.8672699095155, 888.866669999502, 892.3173772064376, 893.9821203575924, 894.7539426657656, 721.476929226312, 731.2395109086543, 856.7772379181396, 872.8734333521668, 874.2525141004413, 645.177398816508, 645.9806205813833, 647.2491909385137, 648.3710800523485, 649.04590252329, 12298.877011229863, 12408.689782755413, 12458.688532786662, 12477.50089996399, 12488.313142777144, 11480.081593472501, 12093.395330233454, 12681.302528360937, 12752.681182970407, 12753.362331883462, 10091.243156763272, 10270.794584108351, 10371.948140259326, 10376.740581485448, 10630.30902991303, 3597.730170237243, 3647.3799454092605, 3650.908727281813, 3696.3346597612417, 3791.393377059685, 44103.558964410266, 44855.327170092474, 50705.746471267405, 51169.23246151311, 51288.74355628198, 9315.301352398597, 9749.853752193723, 9756.121949024622, 9859.802803943881, 10122.089011988955, 17797.377091801725, 17828.732569011474, 17835.0, 17845.643087138185, 17885.642287154184, 7246.782596522113, 7349.0078839356, 7393.260673932564, 7445.627718614048, 7546.0, 4045.59544045599, 4232.153569286155, 4269.957300426956, 4296.419983302277, 4304.5910638489295, 3190.9042728718177, 3203.2312245061016, 3208.5508028875975, 3221.1624977506017, 3225.59680039995, 522.3914140026826, 546.5791340667664, 555.3419198250086, 564.881374911265, 565.9207710920474, 590.8729623130962, 604.2507290959179, 694.8818700820873, 708.2598684374841, 716.2014353995257, 8190.385721070944, 8195.139510351446, 8259.876101858477, 8357.916420835836, 8455.534945577929, 584.3834754334218, 635.9599236452427, 658.2397331082607, 684.3430306905327, 713.4363852556422, 3738.4953031340965, 4088.836446542105, 4136.855210067633, 4258.61672449481, 4379.5182529921685, 58361.0, 58822.0, 59468.10797838036, 59532.70233648804, 59704.402955970756, 49870.0, 49999.0, 51062.74468627633, 51231.0, 51923.48076519262, 44861.327080093826, 46410.303845442344, 47032.294515582296, 47688.99999999931, 47772.28341574879, 8217.260446559838, 8264.586770661563, 8680.218780309802, 8720.607572659119, 8755.518446485505, 35671.28657426889, 35741.10647233736, 36198.27603447864, 36753.816230918674, 37002.259954801295, 42571.14857702891, 42598.14803703847, 43947.56052439435, 46035.76982115068, 46185.307220392395, 10353.327033742782, 10388.272820902464, 10414.583416663248, 10465.110465610509, 10749.516271767632, 11564.363959982282, 11567.652970410902, 11623.0, 11677.824832627348, 11846.940765296118, 10777.89222107769, 10966.506507207036, 11032.669019929415, 11172.88827111751, 11373.158028772532, 10553.63062292816, 10764.838527422251, 10830.945845270724, 11064.944675276894, 11104.444777760918, 8173.965918549439, 9436.339456238273, 9807.9019209807, 11014.889851101387, 11081.501332439899, 8762.737117886474, 9407.341486095907, 9428.528573571431, 9455.716328510156, 9758.316917815684, 9004.90995090067, 9185.816283674156, 9303.72088837336, 9342.579583918876, 9534.666286679932], "unit": "ms", "name": "yui.html", "value": 6243.07898590268}], "name": "dromaeo_css", "value": 6937.42330613239}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 112.0, 105.0, 110.0, 112.0, 113.0, 98.0, 109.0, 108.0, 105.0], "unit": "ms", "name": "ai-astar", "value": 108.1}, {"lowerIsBetter": true, "replicates": [148.0, 123.0, 163.0, 167.0, 127.0, 149.0, 158.0, 123.0, 164.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.6}, {"lowerIsBetter": true, "replicates": [160.0, 162.0, 161.0, 161.0, 163.0, 161.0, 162.0, 161.0, 163.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 161.6}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 88.0, 97.0, 73.0, 71.0, 71.0, 102.0, 91.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 80.7}, {"lowerIsBetter": true, "replicates": [92.0, 94.0, 146.0, 92.0, 94.0, 95.0, 156.0, 93.0, 94.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 108.9}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 110.0, 109.0, 110.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [145.0, 147.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [58.0, 56.0, 57.0, 57.0, 56.0, 57.0, 57.0, 56.0, 57.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.6}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 70.0, 72.0, 72.0, 71.0, 71.0, 71.0, 71.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 71.0}, {"lowerIsBetter": true, "replicates": [91.0, 83.0, 92.0, 84.0, 85.0, 86.0, 86.0, 86.0, 92.0, 89.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 87.4}, {"lowerIsBetter": true, "replicates": [118.0, 124.0, 116.0, 112.0, 121.0, 123.0, 126.0, 126.0, 122.0, 129.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 121.7}, {"lowerIsBetter": true, "replicates": [141.0, 147.0, 144.0, 146.0, 146.0, 145.0, 145.0, 147.0, 146.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 144.2}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 68.0, 66.0, 65.0, 67.0, 67.0, 64.0, 65.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.6}], "name": "kraken", "value": 1501.6000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.0150893882237], "unit": "score", "name": "Richards", "value": 215.23629400000002}, {"lowerIsBetter": false, "replicates": [194.1370607649], "unit": "score", "name": "DeltaBlue", "value": 340.5738179999999}, {"lowerIsBetter": false, "replicates": [283.12570781426956], "unit": "score", "name": "Encrypt", "value": 940.1512919999999}, {"lowerIsBetter": false, "replicates": [5229.166666666667], "unit": "score", "name": "Decrypt", "value": 50.90313944223106}, {"lowerIsBetter": false, "replicates": [1127.3957158962796], "unit": "score", "name": "RayTrace", "value": 656.370243}, {"lowerIsBetter": false, "replicates": [778.988326848249], "unit": "score", "name": "Earley", "value": 855.5494055944059}, {"lowerIsBetter": false, "replicates": [12268.292682926829], "unit": "score", "name": "Boyer", "value": 54.32402186878729}, {"lowerIsBetter": false, "replicates": [25100.0], "unit": "score", "name": "RegExp", "value": 36.294223107569735}, {"lowerIsBetter": false, "replicates": [680.7065217391304], "unit": "score", "name": "Splay", "value": 119.71532135728543}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20759.131027198793}]} 04:16:47 INFO - RETURN: dromaeo_css: 6937.42 04:16:47 INFO - RETURN: kraken: 1501.60 04:16:47 INFO - RETURN: v8_7: 20759.13 04:16:47 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "20759.13"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1501.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6937.42"}}} 04:16:47 INFO - Return code: 0 04:16:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 04:16:47 INFO - # TBPL SUCCESS # 04:16:47 INFO - Running post-action listener: _resource_record_post_action 04:16:47 INFO - Running post-run listener: _resource_record_post_run 04:16:48 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 0; Write bytes: 507097088; Read time: 0; Write time: 411500 04:16:48 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 169320448; Read time: 0; Write time: 214540 04:16:48 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 0; Write bytes: 266772480; Read time: 0; Write time: 141220 04:16:48 INFO - Running post-run listener: _upload_blobber_files 04:16:48 INFO - Blob upload gear active. 04:16:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:16:48 INFO - Copying logs to upload dir... 04:16:48 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1255.646809 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2015-11-26 04:16:47.717647) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 04:16:47.721758) ========= 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=6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009979 build_url: 'https://queue.taskcluster.net/v1/task/ZXW5IbiSSOqycTytqzbjlQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 04:16:47.781699) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 04:16:47.782346) ========= 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=6587686cb2da90a5f91b3170000001be-1448538951.202767-1034056950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004482 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 04:16:47.835158) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 04:16:47.835759) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 04:16:47.836323) ========= ========= Total master_lag: 0.27 =========