builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1448430684.05 results: success (0) buildid: 20151124203134 builduid: 257f911fc0334c1d84167f9edd9d1fff revision: aef73dcc9acc1b4434254e1e6bde1bfd36268b48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.047121) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.047703) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.048056) ========= 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=ccb4611811c763f2fad71465000001de-1448429890.172968-462874076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006717 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.069593) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.069947) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.084459) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.084892) ========= 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=ccb4611811c763f2fad71465000001de-1448429890.172968-462874076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004645 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.140164) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.140651) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.141057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.141389) ========= 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=ccb4611811c763f2fad71465000001de-1448429890.172968-462874076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 21:51:25-- 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% 64.8M=0s 2015-11-24 21:51:25 (64.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.169601 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.340480) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.340802) ========= 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=ccb4611811c763f2fad71465000001de-1448429890.172968-462874076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016422 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:24.394124) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-24 21:51:24.394490) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev aef73dcc9acc1b4434254e1e6bde1bfd36268b48 --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 aef73dcc9acc1b4434254e1e6bde1bfd36268b48 --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=ccb4611811c763f2fad71465000001de-1448429890.172968-462874076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 21:51:25,483 truncating revision to first 12 chars 2015-11-24 21:51:25,483 Setting DEBUG logging. 2015-11-24 21:51:25,483 attempt 1/10 2015-11-24 21:51:25,483 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/aef73dcc9acc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 21:51:26,540 unpacking tar archive at: fx-team-aef73dcc9acc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.346096 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-24 21:51:25.759290) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:25.760154) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:25.772170) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:25.772859) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 21:51:25.773603) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2015-11-24 21:51:25.774349) ========= /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=ccb4611811c763f2fad71465000001de-1448429890.172968-462874076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:51:26 INFO - MultiFileLogger online at 20151124 21:51:26 in /builds/slave/test-pgo 21:51:26 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 21:51:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:51:26 INFO - {'append_to_log': False, 21:51:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:51:26 INFO - 'blob_upload_branch': 'Fx-Team', 21:51:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:51:26 INFO - 'branch': 'Fx-Team', 21:51:26 INFO - 'buildbot_json_path': 'buildprops.json', 21:51:26 INFO - 'config_files': ('talos/linux_config.py',), 21:51:26 INFO - 'default_actions': ('clobber', 21:51:26 INFO - 'read-buildbot-config', 21:51:26 INFO - 'download-and-extract', 21:51:26 INFO - 'populate-webroot', 21:51:26 INFO - 'create-virtualenv', 21:51:26 INFO - 'install', 21:51:26 INFO - 'run-tests'), 21:51:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:51:26 INFO - 'download_minidump_stackwalk': True, 21:51:26 INFO - 'download_symbols': 'ondemand', 21:51:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:51:26 INFO - 'tooltool.py': '/tools/tooltool.py', 21:51:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:51:26 INFO - '/tools/misc-python/virtualenv.py')}, 21:51:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:51:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:51:26 INFO - 'installer_path': 'installer.exe', 21:51:26 INFO - 'log_level': 'info', 21:51:26 INFO - 'log_name': 'talos', 21:51:26 INFO - 'log_to_console': True, 21:51:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:51:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:51:26 INFO - 'opt_config_files': (), 21:51:26 INFO - 'pip_index': False, 21:51:26 INFO - 'sps_profile': False, 21:51:26 INFO - 'sps_profile_interval': 0, 21:51:26 INFO - 'suite': 'dromaeojs', 21:51:26 INFO - 'system_bits': '64', 21:51:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:51:26 INFO - 'title': 'talos-linux64-ix-021', 21:51:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:51:26 INFO - 'use_talos_json': True, 21:51:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:51:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:51:26 INFO - 'work_dir': 'build'} 21:51:26 INFO - ##### 21:51:26 INFO - ##### Running clobber step. 21:51:26 INFO - ##### 21:51:26 INFO - Running pre-action listener: _resource_record_pre_action 21:51:26 INFO - Running main action method: clobber 21:51:26 INFO - rmtree: /builds/slave/test-pgo/build 21:51:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:51:27 INFO - Running post-action listener: _resource_record_post_action 21:51:27 INFO - ##### 21:51:27 INFO - ##### Running read-buildbot-config step. 21:51:27 INFO - ##### 21:51:27 INFO - Running pre-action listener: _resource_record_pre_action 21:51:27 INFO - Running main action method: read_buildbot_config 21:51:27 INFO - Using buildbot properties: 21:51:27 INFO - { 21:51:27 INFO - "properties": { 21:51:27 INFO - "buildnumber": 514, 21:51:27 INFO - "product": "firefox", 21:51:27 INFO - "script_repo_revision": "production", 21:51:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:51:27 INFO - "repository": "", 21:51:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 21:51:27 INFO - "buildid": "20151124203134", 21:51:27 INFO - "slavename": "talos-linux64-ix-021", 21:51:27 INFO - "pgo_build": "True", 21:51:27 INFO - "basedir": "/builds/slave/test-pgo", 21:51:27 INFO - "project": "", 21:51:27 INFO - "platform": "ubuntu64_hw", 21:51:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:51:27 INFO - "slavebuilddir": "test", 21:51:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 21:51:27 INFO - "branch": "fx-team", 21:51:27 INFO - "repo_path": "integration/fx-team", 21:51:27 INFO - "stage_platform": "linux64-pgo", 21:51:27 INFO - "builduid": "257f911fc0334c1d84167f9edd9d1fff", 21:51:27 INFO - "revision": "aef73dcc9acc1b4434254e1e6bde1bfd36268b48" 21:51:27 INFO - }, 21:51:27 INFO - "sourcestamp": { 21:51:27 INFO - "repository": "", 21:51:27 INFO - "hasPatch": false, 21:51:27 INFO - "project": "", 21:51:27 INFO - "branch": "fx-team-linux64-pgo-talos", 21:51:27 INFO - "changes": [ 21:51:27 INFO - { 21:51:27 INFO - "category": null, 21:51:27 INFO - "files": [ 21:51:27 INFO - { 21:51:27 INFO - "url": null, 21:51:27 INFO - "name": "https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 21:51:27 INFO - } 21:51:27 INFO - ], 21:51:27 INFO - "repository": "", 21:51:27 INFO - "rev": "aef73dcc9acc1b4434254e1e6bde1bfd36268b48", 21:51:27 INFO - "who": "sendchange", 21:51:27 INFO - "when": 1448430593, 21:51:27 INFO - "number": 6703863, 21:51:27 INFO - "comments": "", 21:51:27 INFO - "project": "", 21:51:27 INFO - "at": "Tue 24 Nov 2015 21:49:53", 21:51:27 INFO - "branch": "fx-team-linux64-pgo-talos", 21:51:27 INFO - "revlink": "", 21:51:27 INFO - "properties": [ 21:51:27 INFO - [ 21:51:27 INFO - "buildid", 21:51:27 INFO - "20151124203134", 21:51:27 INFO - "Change" 21:51:27 INFO - ], 21:51:27 INFO - [ 21:51:27 INFO - "builduid", 21:51:27 INFO - "257f911fc0334c1d84167f9edd9d1fff", 21:51:27 INFO - "Change" 21:51:27 INFO - ], 21:51:27 INFO - [ 21:51:27 INFO - "pgo_build", 21:51:27 INFO - "True", 21:51:27 INFO - "Change" 21:51:27 INFO - ] 21:51:27 INFO - ], 21:51:27 INFO - "revision": "aef73dcc9acc1b4434254e1e6bde1bfd36268b48" 21:51:27 INFO - } 21:51:27 INFO - ], 21:51:27 INFO - "revision": "aef73dcc9acc1b4434254e1e6bde1bfd36268b48" 21:51:27 INFO - } 21:51:27 INFO - } 21:51:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 21:51:27 INFO - Running post-action listener: _resource_record_post_action 21:51:27 INFO - ##### 21:51:27 INFO - ##### Running download-and-extract step. 21:51:27 INFO - ##### 21:51:27 INFO - Running pre-action listener: _resource_record_pre_action 21:51:27 INFO - Running main action method: download_and_extract 21:51:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:51:27 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')]} 21:51:27 INFO - https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:51:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/test_packages.json 21:51:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/test_packages.json 21:51:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:51:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:51:30 INFO - Downloaded 1302 bytes. 21:51:30 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:51:30 INFO - Using the following test package requirements: 21:51:30 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:51:30 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:51:30 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:51:30 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:51:30 INFO - u'jsshell-linux-x86_64.zip'], 21:51:30 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:51:30 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:51:30 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:51:30 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:51:30 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:51:30 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:51:30 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:51:30 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:51:30 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:51:30 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:51:30 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:51:30 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:51:30 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:51:30 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')]} 21:51:30 INFO - https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:51:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:51:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:51:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/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 21:51:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/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 21:51:32 INFO - Downloaded 22530736 bytes. 21:51:32 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 21:51:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:51:32 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 21:51:33 INFO - Return code: 0 21:51:33 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 21:51:33 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')]} 21:51:33 INFO - https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:51:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:51:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:51:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/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 21:51:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/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 21:51:39 INFO - Downloaded 22530736 bytes. 21:51:39 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 21:51:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:51:39 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 21:51:40 INFO - Return code: 0 21:51:40 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')]} 21:51:40 INFO - https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:51:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:51:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:51:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/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 21:51:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/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 21:51:41 INFO - Downloaded 11131717 bytes. 21:51:41 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 21:51:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:51:41 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 21:51:42 INFO - Return code: 0 21:51:42 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')]} 21:51:42 INFO - https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:51:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:51:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:51:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/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 21:51:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ceMDea84QOaSPwXR9Oim3w/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 21:51:43 INFO - Downloaded 58393227 bytes. 21:51:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:51:43 INFO - mkdir: /builds/slave/test-pgo/properties 21:51:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:51:43 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:51:43 INFO - Contents: 21:51:43 INFO - build_url:https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:51:43 INFO - Running post-action listener: _resource_record_post_action 21:51:43 INFO - Running post-action listener: set_extra_try_arguments 21:51:43 INFO - ##### 21:51:43 INFO - ##### Running populate-webroot step. 21:51:43 INFO - ##### 21:51:43 INFO - Running pre-action listener: _resource_record_pre_action 21:51:43 INFO - Running main action method: populate_webroot 21:51:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:51:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:51:43 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 21:51:43 INFO - '../fennec_ids.txt'], 21:51:43 INFO - 'tests': ['tcheck2']}, 21:51:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:51:43 INFO - '--tppagecycles', 21:51:43 INFO - '7'], 21:51:43 INFO - 'tests': ['tsvgm']}}, 21:51:43 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:51:43 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:51:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:51:43 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:51:43 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:51:43 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:51:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:51:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:43 INFO - 'talos_options': ['--e10s'], 21:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:51:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:43 INFO - 'tests': ['damp', 'tps']}, 21:51:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:43 INFO - 'talos_options': ['--e10s'], 21:51:43 INFO - 'tests': ['damp', 'tps']}, 21:51:43 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:51:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:51:43 INFO - 'other': {'tests': ['a11yr', 21:51:43 INFO - 'ts_paint', 21:51:43 INFO - 'tpaint', 21:51:43 INFO - 'sessionrestore', 21:51:43 INFO - 'sessionrestore_no_auto_restore']}, 21:51:43 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:51:43 INFO - 'tests': ['a11yr', 21:51:43 INFO - 'ts_paint', 21:51:43 INFO - 'tpaint', 21:51:43 INFO - 'sessionrestore', 21:51:43 INFO - 'sessionrestore_no_auto_restore']}, 21:51:43 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:51:43 INFO - 'tests': ['a11yr', 21:51:43 INFO - 'ts_paint', 21:51:43 INFO - 'tpaint', 21:51:43 INFO - 'sessionrestore', 21:51:43 INFO - 'sessionrestore_no_auto_restore']}, 21:51:43 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:51:43 INFO - 'tests': ['a11yr', 21:51:43 INFO - 'ts_paint', 21:51:43 INFO - 'tpaint', 21:51:43 INFO - 'sessionrestore', 21:51:43 INFO - 'sessionrestore_no_auto_restore']}, 21:51:43 INFO - 'other_l64': {'tests': ['a11yr', 21:51:43 INFO - 'ts_paint', 21:51:43 INFO - 'tpaint', 21:51:43 INFO - 'sessionrestore', 21:51:43 INFO - 'sessionrestore_no_auto_restore']}, 21:51:43 INFO - 'other_nol64': {'tests': ['a11yr', 21:51:43 INFO - 'ts_paint', 21:51:43 INFO - 'tpaint', 21:51:43 INFO - 'sessionrestore', 21:51:43 INFO - 'sessionrestore_no_auto_restore']}, 21:51:43 INFO - 'svgr': {'tests': ['tsvgx', 21:51:43 INFO - 'tsvgr_opacity', 21:51:43 INFO - 'tart', 21:51:43 INFO - 'tscrollx', 21:51:43 INFO - 'cart']}, 21:51:43 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:51:43 INFO - 'tests': ['tsvgx', 21:51:43 INFO - 'tsvgr_opacity', 21:51:43 INFO - 'tart', 21:51:43 INFO - 'tscrollx', 21:51:43 INFO - 'cart']}, 21:51:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:43 INFO - 'tests': ['tp5o']}, 21:51:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:43 INFO - 'talos_options': ['--e10s'], 21:51:43 INFO - 'tests': ['tp5o']}, 21:51:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:43 INFO - 'talos_options': ['--xperf_path', 21:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:51:43 INFO - 'tests': ['tp5n']}, 21:51:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:43 INFO - 'talos_options': ['--e10s', 21:51:43 INFO - '--xperf_path', 21:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:51:43 INFO - 'tests': ['tp5n']}}, 21:51:43 INFO - 'talos.zip': {'path': '', 21:51:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:51:43 INFO - Running post-action listener: _resource_record_post_action 21:51:43 INFO - ##### 21:51:43 INFO - ##### Running create-virtualenv step. 21:51:43 INFO - ##### 21:51:43 INFO - Running pre-action listener: _resource_record_pre_action 21:51:43 INFO - Running main action method: create_virtualenv 21:51:43 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:51:43 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 21:51:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:51:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:51:43 INFO - Using real prefix '/usr' 21:51:43 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:51:44 INFO - Installing distribute.............................................................................................................................................................................................done. 21:51:46 INFO - Installing pip.................done. 21:51:46 INFO - Return code: 0 21:51:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:51:46 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')]} 21:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:46 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')]} 21:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:46 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 0x1793030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f640>, '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 0x1a50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a509d0>, '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': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', '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 21:51:46 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 21:51:46 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 21:51:46 INFO - Using env: {'DISPLAY': ':0', 21:51:46 INFO - 'HOME': '/home/cltbld', 21:51:46 INFO - 'LANG': 'en_US.UTF-8', 21:51:46 INFO - 'LANGUAGE': 'en_US:en', 21:51:46 INFO - 'LOGNAME': 'cltbld', 21:51:46 INFO - 'MAIL': '/var/mail/cltbld', 21:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:46 INFO - 'MOZ_NO_REMOTE': '1', 21:51:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:51:46 INFO - 'NO_EM_RESTART': '1', 21:51:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:51:46 INFO - 'PWD': '/builds/slave/test-pgo', 21:51:46 INFO - 'SHELL': '/bin/bash', 21:51:46 INFO - 'SHLVL': '1', 21:51:46 INFO - 'TERM': 'linux', 21:51:46 INFO - 'TMOUT': '86400', 21:51:46 INFO - 'USER': 'cltbld', 21:51:46 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', 21:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:46 INFO - '_': '/tools/buildbot/bin/python'} 21:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:46 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 21:51:46 INFO - Cleaning up... 21:51:46 INFO - Return code: 0 21:51:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:51:46 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')]} 21:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:46 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')]} 21:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:46 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 0x1793030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f640>, '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 0x1a50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a509d0>, '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': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', '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 21:51:46 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 21:51:46 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 21:51:46 INFO - Using env: {'DISPLAY': ':0', 21:51:46 INFO - 'HOME': '/home/cltbld', 21:51:46 INFO - 'LANG': 'en_US.UTF-8', 21:51:46 INFO - 'LANGUAGE': 'en_US:en', 21:51:46 INFO - 'LOGNAME': 'cltbld', 21:51:46 INFO - 'MAIL': '/var/mail/cltbld', 21:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:46 INFO - 'MOZ_NO_REMOTE': '1', 21:51:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:51:46 INFO - 'NO_EM_RESTART': '1', 21:51:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:51:46 INFO - 'PWD': '/builds/slave/test-pgo', 21:51:46 INFO - 'SHELL': '/bin/bash', 21:51:46 INFO - 'SHLVL': '1', 21:51:46 INFO - 'TERM': 'linux', 21:51:46 INFO - 'TMOUT': '86400', 21:51:46 INFO - 'USER': 'cltbld', 21:51:46 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', 21:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:46 INFO - '_': '/tools/buildbot/bin/python'} 21:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:46 INFO - Downloading/unpacking psutil>=0.7.1 21:51:46 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 21:51:46 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 21:51:46 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 21:51:46 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 21:51:46 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 21:51:46 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 21:51:48 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 21:51:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 21:51:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:51:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:51:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:51:48 INFO - Installing collected packages: psutil 21:51:48 INFO - Running setup.py install for psutil 21:51:48 INFO - building 'psutil._psutil_linux' extension 21:51:48 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 21:51:48 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 21:51:49 INFO - building 'psutil._psutil_posix' extension 21:51:49 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 21:51:49 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 21:51:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:51:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:51:49 INFO - Successfully installed psutil 21:51:49 INFO - Cleaning up... 21:51:49 INFO - Return code: 0 21:51:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:51:49 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')]} 21:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:49 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')]} 21:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:49 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 0x1793030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f640>, '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 0x1a50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a509d0>, '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': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', '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 21:51:49 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 21:51:49 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 21:51:49 INFO - Using env: {'DISPLAY': ':0', 21:51:49 INFO - 'HOME': '/home/cltbld', 21:51:49 INFO - 'LANG': 'en_US.UTF-8', 21:51:49 INFO - 'LANGUAGE': 'en_US:en', 21:51:49 INFO - 'LOGNAME': 'cltbld', 21:51:49 INFO - 'MAIL': '/var/mail/cltbld', 21:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:49 INFO - 'MOZ_NO_REMOTE': '1', 21:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:51:49 INFO - 'NO_EM_RESTART': '1', 21:51:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:51:49 INFO - 'PWD': '/builds/slave/test-pgo', 21:51:49 INFO - 'SHELL': '/bin/bash', 21:51:49 INFO - 'SHLVL': '1', 21:51:49 INFO - 'TERM': 'linux', 21:51:49 INFO - 'TMOUT': '86400', 21:51:49 INFO - 'USER': 'cltbld', 21:51:49 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', 21:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:49 INFO - '_': '/tools/buildbot/bin/python'} 21:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:51:49 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 21:51:49 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 21:51:49 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 21:51:49 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 21:51:49 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 21:51:49 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 21:51:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:51:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 21:51:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:51:51 INFO - Installing collected packages: mozsystemmonitor 21:51:51 INFO - Running setup.py install for mozsystemmonitor 21:51:51 INFO - Successfully installed mozsystemmonitor 21:51:51 INFO - Cleaning up... 21:51:51 INFO - Return code: 0 21:51:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1793030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f640>, '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 0x1a50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a509d0>, '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': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', '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 21:51:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 21:51:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:51:51 INFO - Using env: {'DISPLAY': ':0', 21:51:51 INFO - 'HOME': '/home/cltbld', 21:51:51 INFO - 'LANG': 'en_US.UTF-8', 21:51:51 INFO - 'LANGUAGE': 'en_US:en', 21:51:51 INFO - 'LOGNAME': 'cltbld', 21:51:51 INFO - 'MAIL': '/var/mail/cltbld', 21:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:51 INFO - 'MOZ_NO_REMOTE': '1', 21:51:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:51:51 INFO - 'NO_EM_RESTART': '1', 21:51:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:51:51 INFO - 'PWD': '/builds/slave/test-pgo', 21:51:51 INFO - 'SHELL': '/bin/bash', 21:51:51 INFO - 'SHLVL': '1', 21:51:51 INFO - 'TERM': 'linux', 21:51:51 INFO - 'TMOUT': '86400', 21:51:51 INFO - 'USER': 'cltbld', 21:51:51 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', 21:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:51 INFO - '_': '/tools/buildbot/bin/python'} 21:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:51 INFO - Downloading/unpacking blobuploader==1.2.4 21:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:51:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:51:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:51:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:51:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:51:53 INFO - Downloading blobuploader-1.2.4.tar.gz 21:51:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 21:51:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:51:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:51:53 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 21:51:53 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 21:51:53 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 21:51:53 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 21:51:53 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 21:51:53 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 21:51:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 21:51:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:51:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:51:53 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 21:51:53 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 21:51:53 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 21:51:53 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 21:51:53 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 21:51:53 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 21:51:53 INFO - Downloading docopt-0.6.1.tar.gz 21:51:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 21:51:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:51:54 INFO - Installing collected packages: blobuploader, requests, docopt 21:51:54 INFO - Running setup.py install for blobuploader 21:51:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:51:54 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:51:54 INFO - Running setup.py install for requests 21:51:54 INFO - Running setup.py install for docopt 21:51:54 INFO - Successfully installed blobuploader requests docopt 21:51:54 INFO - Cleaning up... 21:51:54 INFO - Return code: 0 21:51:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:51: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')]} 21:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51: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')]} 21:51:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:54 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 0x1793030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f640>, '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 0x1a50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a509d0>, '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': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', '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 21:51:54 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 21:51:54 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 21:51:54 INFO - Using env: {'DISPLAY': ':0', 21:51:54 INFO - 'HOME': '/home/cltbld', 21:51:54 INFO - 'LANG': 'en_US.UTF-8', 21:51:54 INFO - 'LANGUAGE': 'en_US:en', 21:51:54 INFO - 'LOGNAME': 'cltbld', 21:51:54 INFO - 'MAIL': '/var/mail/cltbld', 21:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:54 INFO - 'MOZ_NO_REMOTE': '1', 21:51:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:51:54 INFO - 'NO_EM_RESTART': '1', 21:51:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:51:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:51:54 INFO - 'PWD': '/builds/slave/test-pgo', 21:51:54 INFO - 'SHELL': '/bin/bash', 21:51:54 INFO - 'SHLVL': '1', 21:51:54 INFO - 'TERM': 'linux', 21:51:54 INFO - 'TMOUT': '86400', 21:51:54 INFO - 'USER': 'cltbld', 21:51:54 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', 21:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:54 INFO - '_': '/tools/buildbot/bin/python'} 21:51:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:51:54 INFO - Running setup.py (path:/tmp/pip-tttl1b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:51:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:51:54 INFO - Running setup.py (path:/tmp/pip-vDHytJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:51:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:51:54 INFO - Running setup.py (path:/tmp/pip-GN6BoA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:51:55 INFO - Running setup.py (path:/tmp/pip-ye1YE2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:51:55 INFO - Running setup.py (path:/tmp/pip-CxggXr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:51:55 INFO - Running setup.py (path:/tmp/pip-6npPth-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:51:55 INFO - Running setup.py (path:/tmp/pip-up5zMa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:51:55 INFO - Running setup.py (path:/tmp/pip-QK0I4f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:51:55 INFO - Running setup.py (path:/tmp/pip-qQBac6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:51:55 INFO - Running setup.py (path:/tmp/pip-VO_Wra-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:51:55 INFO - Running setup.py (path:/tmp/pip-VLHIg_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:51:55 INFO - Running setup.py (path:/tmp/pip-QOAEkK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:51:55 INFO - Running setup.py (path:/tmp/pip-yRDNJh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:51:55 INFO - Running setup.py (path:/tmp/pip-mv5CRW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:51:55 INFO - Running setup.py (path:/tmp/pip-h5jyiU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:51:55 INFO - Running setup.py (path:/tmp/pip-gqEdm6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:51:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:51:55 INFO - Running setup.py (path:/tmp/pip-WXazLV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:51:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:51:56 INFO - Running setup.py install for manifestparser 21:51:56 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:51:56 INFO - Running setup.py install for mozcrash 21:51:56 INFO - Running setup.py install for mozdebug 21:51:56 INFO - Running setup.py install for mozdevice 21:51:56 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:51:56 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:51:56 INFO - Running setup.py install for mozfile 21:51:56 INFO - Running setup.py install for mozhttpd 21:51:56 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:51:56 INFO - Running setup.py install for mozinfo 21:51:56 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:51:56 INFO - Running setup.py install for mozInstall 21:51:56 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:51:56 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:51:56 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:51:56 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:51:56 INFO - Running setup.py install for mozleak 21:51:56 INFO - Running setup.py install for mozlog 21:51:57 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:51:57 INFO - Running setup.py install for moznetwork 21:51:57 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:51:57 INFO - Running setup.py install for mozprocess 21:51:57 INFO - Running setup.py install for mozprofile 21:51:57 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:51:57 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:51:57 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:51:57 INFO - Running setup.py install for mozrunner 21:51:57 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:51:57 INFO - Running setup.py install for mozscreenshot 21:51:57 INFO - Running setup.py install for moztest 21:51:57 INFO - Running setup.py install for mozversion 21:51:57 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:51:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:51:57 INFO - Cleaning up... 21:51:57 INFO - Return code: 0 21:51:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:51:57 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')]} 21:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:57 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')]} 21:51:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:57 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 0x1793030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f640>, '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 0x1a50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a509d0>, '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': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', '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 21:51:57 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 21:51:57 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 21:51:57 INFO - Using env: {'DISPLAY': ':0', 21:51:57 INFO - 'HOME': '/home/cltbld', 21:51:57 INFO - 'LANG': 'en_US.UTF-8', 21:51:57 INFO - 'LANGUAGE': 'en_US:en', 21:51:57 INFO - 'LOGNAME': 'cltbld', 21:51:57 INFO - 'MAIL': '/var/mail/cltbld', 21:51:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:57 INFO - 'MOZ_NO_REMOTE': '1', 21:51:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:51:57 INFO - 'NO_EM_RESTART': '1', 21:51:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:51:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:51:57 INFO - 'PWD': '/builds/slave/test-pgo', 21:51:57 INFO - 'SHELL': '/bin/bash', 21:51:57 INFO - 'SHLVL': '1', 21:51:57 INFO - 'TERM': 'linux', 21:51:57 INFO - 'TMOUT': '86400', 21:51:57 INFO - 'USER': 'cltbld', 21:51:57 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', 21:51:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:57 INFO - '_': '/tools/buildbot/bin/python'} 21:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:51:58 INFO - Running setup.py (path:/tmp/pip-kZueZ1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:51:58 INFO - Running setup.py (path:/tmp/pip-slBpzl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:51:58 INFO - Running setup.py (path:/tmp/pip-pyT8_f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:51:58 INFO - Running setup.py (path:/tmp/pip-YjI2hK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:51:58 INFO - Running setup.py (path:/tmp/pip-EbENie-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:51:58 INFO - Running setup.py (path:/tmp/pip-MzH5hj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:51:58 INFO - Running setup.py (path:/tmp/pip-YuV_4k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:51:58 INFO - Running setup.py (path:/tmp/pip-6BYBKr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:51:58 INFO - Running setup.py (path:/tmp/pip-2ijRsu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:51:58 INFO - Running setup.py (path:/tmp/pip-fJK_m0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:51:58 INFO - Running setup.py (path:/tmp/pip-zE5atc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:51:58 INFO - Running setup.py (path:/tmp/pip-kuo7Yp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:51:58 INFO - Running setup.py (path:/tmp/pip-66BVNM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:51:58 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)) 21:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:51:59 INFO - Running setup.py (path:/tmp/pip-CnaD42-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:51:59 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)) 21:51:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:51:59 INFO - Running setup.py (path:/tmp/pip-f8CWxO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:51:59 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)) 21:51:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:51:59 INFO - Running setup.py (path:/tmp/pip-4wmGpK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:51:59 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)) 21:51:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:51:59 INFO - Running setup.py (path:/tmp/pip-Ouy2_S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:51:59 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)) 21:51:59 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)) 21:51:59 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)) 21:51:59 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)) 21:51:59 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)) 21:51:59 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)) 21:51:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:51:59 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 21:51:59 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 21:51:59 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 21:51:59 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 21:51:59 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 21:51:59 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 21:52:00 INFO - Downloading blessings-1.5.1.tar.gz 21:52:00 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 21:52:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:52:01 INFO - Installing collected packages: blessings 21:52:01 INFO - Running setup.py install for blessings 21:52:01 INFO - Successfully installed blessings 21:52:01 INFO - Cleaning up... 21:52:01 INFO - Return code: 0 21:52:01 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:52:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:52:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:52:01 INFO - Reading from file tmpfile_stdout 21:52:01 INFO - Current package versions: 21:52:01 INFO - argparse == 1.2.1 21:52:01 INFO - blessings == 1.5.1 21:52:01 INFO - blobuploader == 1.2.4 21:52:01 INFO - docopt == 0.6.1 21:52:01 INFO - manifestparser == 1.1 21:52:01 INFO - mozInstall == 1.12 21:52:01 INFO - mozcrash == 0.16 21:52:01 INFO - mozdebug == 0.1 21:52:01 INFO - mozdevice == 0.47 21:52:01 INFO - mozfile == 1.2 21:52:01 INFO - mozhttpd == 0.7 21:52:01 INFO - mozinfo == 0.9 21:52:01 INFO - mozleak == 0.1 21:52:01 INFO - mozlog == 3.1 21:52:01 INFO - moznetwork == 0.27 21:52:01 INFO - mozprocess == 0.22 21:52:01 INFO - mozprofile == 0.27 21:52:01 INFO - mozrunner == 6.11 21:52:01 INFO - mozscreenshot == 0.1 21:52:01 INFO - mozsystemmonitor == 0.0 21:52:01 INFO - moztest == 0.7 21:52:01 INFO - mozversion == 1.4 21:52:01 INFO - psutil == 3.1.1 21:52:01 INFO - requests == 1.2.3 21:52:01 INFO - wsgiref == 0.1.2 21:52:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:01 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 0x1793030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f640>, '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 0x1a50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a509d0>, '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': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', '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 21:52:01 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 21:52:01 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 21:52:01 INFO - Using env: {'DISPLAY': ':0', 21:52:01 INFO - 'HOME': '/home/cltbld', 21:52:01 INFO - 'LANG': 'en_US.UTF-8', 21:52:01 INFO - 'LANGUAGE': 'en_US:en', 21:52:01 INFO - 'LOGNAME': 'cltbld', 21:52:01 INFO - 'MAIL': '/var/mail/cltbld', 21:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:01 INFO - 'MOZ_NO_REMOTE': '1', 21:52:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:52:01 INFO - 'NO_EM_RESTART': '1', 21:52:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:52:01 INFO - 'PWD': '/builds/slave/test-pgo', 21:52:01 INFO - 'SHELL': '/bin/bash', 21:52:01 INFO - 'SHLVL': '1', 21:52:01 INFO - 'TERM': 'linux', 21:52:01 INFO - 'TMOUT': '86400', 21:52:01 INFO - 'USER': 'cltbld', 21:52:01 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', 21:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:01 INFO - '_': '/tools/buildbot/bin/python'} 21:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:01 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)) 21:52:01 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)) 21:52:01 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)) 21:52:01 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)) 21:52:01 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)) 21:52:01 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)) 21:52:01 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)) 21:52:01 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)) 21:52:01 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)) 21:52:01 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)) 21:52:01 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)) 21:52:01 INFO - Cleaning up... 21:52:01 INFO - Return code: 0 21:52:01 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 21:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:01 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 0x1793030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f640>, '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 0x1a50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a509d0>, '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': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', '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 21:52:01 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 21:52:01 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 21:52:01 INFO - Using env: {'DISPLAY': ':0', 21:52:01 INFO - 'HOME': '/home/cltbld', 21:52:01 INFO - 'LANG': 'en_US.UTF-8', 21:52:01 INFO - 'LANGUAGE': 'en_US:en', 21:52:01 INFO - 'LOGNAME': 'cltbld', 21:52:01 INFO - 'MAIL': '/var/mail/cltbld', 21:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:01 INFO - 'MOZ_NO_REMOTE': '1', 21:52:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:52:01 INFO - 'NO_EM_RESTART': '1', 21:52:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:52:01 INFO - 'PWD': '/builds/slave/test-pgo', 21:52:01 INFO - 'SHELL': '/bin/bash', 21:52:01 INFO - 'SHLVL': '1', 21:52:01 INFO - 'TERM': 'linux', 21:52:01 INFO - 'TMOUT': '86400', 21:52:01 INFO - 'USER': 'cltbld', 21:52:01 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', 21:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:01 INFO - '_': '/tools/buildbot/bin/python'} 21:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:01 INFO - Downloading/unpacking jsonschema 21:52:01 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 21:52:01 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 21:52:01 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 21:52:01 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 21:52:01 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 21:52:01 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 21:52:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:52:03 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 21:52:03 INFO - Downloading/unpacking functools32 (from jsonschema) 21:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:03 INFO - Downloading functools32-3.2.3-2.tar.gz 21:52:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 21:52:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 21:52:03 INFO - warning: no files found matching '*.txt' 21:52:03 INFO - no previously-included directories found matching 'build' 21:52:03 INFO - no previously-included directories found matching 'dist' 21:52:03 INFO - no previously-included directories found matching '.git*' 21:52:03 INFO - Installing collected packages: jsonschema, functools32 21:52:03 INFO - Running setup.py install for functools32 21:52:03 INFO - warning: no files found matching '*.txt' 21:52:03 INFO - no previously-included directories found matching 'build' 21:52:03 INFO - no previously-included directories found matching 'dist' 21:52:03 INFO - no previously-included directories found matching '.git*' 21:52:03 INFO - Successfully installed jsonschema functools32 21:52:03 INFO - Cleaning up... 21:52:03 INFO - Return code: 0 21:52:03 INFO - Running post-action listener: _resource_record_post_action 21:52:03 INFO - Running post-action listener: _start_resource_monitoring 21:52:04 INFO - Starting resource monitoring. 21:52:04 INFO - ##### 21:52:04 INFO - ##### Running install step. 21:52:04 INFO - ##### 21:52:04 INFO - Running pre-action listener: _resource_record_pre_action 21:52:04 INFO - Running main action method: install 21:52:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:52:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:52:04 INFO - Reading from file tmpfile_stdout 21:52:04 INFO - Detecting whether we're running mozinstall >=1.0... 21:52:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:52:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:52:04 INFO - Reading from file tmpfile_stdout 21:52:04 INFO - Output received: 21:52:04 INFO - Usage: mozinstall [options] installer 21:52:04 INFO - Options: 21:52:04 INFO - -h, --help show this help message and exit 21:52:04 INFO - -d DEST, --destination=DEST 21:52:04 INFO - Directory to install application into. [default: 21:52:04 INFO - "/builds/slave/test-pgo"] 21:52:04 INFO - --app=APP Application being installed. [default: firefox] 21:52:04 INFO - mkdir: /builds/slave/test-pgo/build/application 21:52:04 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'] 21:52:04 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 21:52:18 INFO - Reading from file tmpfile_stdout 21:52:18 INFO - Output received: 21:52:18 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 21:52:18 INFO - Running post-action listener: _resource_record_post_action 21:52:18 INFO - ##### 21:52:18 INFO - ##### Running run-tests step. 21:52:18 INFO - ##### 21:52:18 INFO - Running pre-action listener: _resource_record_pre_action 21:52:18 INFO - Running main action method: run_tests 21:52:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 21:52:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 21:52:18 INFO - Python 2.7.3 21:52:18 INFO - Return code: 0 21:52:18 INFO - grabbing minidump binary from tooltool 21:52:18 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')]} 21:52:18 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 0x1a4f640>, '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 0x1a50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a509d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 21:52:18 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 21:52:18 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 21:52:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:52:18 INFO - Return code: 0 21:52:18 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 21:52:18 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 21:52:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 21:52:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 21:52:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 21:52:18 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/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 21:52:18 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/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost 21:52:18 INFO - Using env: {'DISPLAY': ':0', 21:52:18 INFO - 'HOME': '/home/cltbld', 21:52:18 INFO - 'LANG': 'en_US.UTF-8', 21:52:18 INFO - 'LANGUAGE': 'en_US:en', 21:52:18 INFO - 'LOGNAME': 'cltbld', 21:52:18 INFO - 'MAIL': '/var/mail/cltbld', 21:52:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:52:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 21:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:18 INFO - 'MOZ_NO_REMOTE': '1', 21:52:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:52:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:52:18 INFO - 'NO_EM_RESTART': '1', 21:52:18 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:52:18 INFO - 'PWD': '/builds/slave/test-pgo', 21:52:18 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 21:52:18 INFO - 'SHELL': '/bin/bash', 21:52:18 INFO - 'SHLVL': '1', 21:52:18 INFO - 'TERM': 'linux', 21:52:18 INFO - 'TMOUT': '86400', 21:52:18 INFO - 'USER': 'cltbld', 21:52:18 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1448429890.172968-462874076', 21:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:18 INFO - '_': '/tools/buildbot/bin/python'} 21:52:18 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/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost'] with output_timeout 3600 21:52:19 INFO - mozversion INFO | application_buildid: 20151124203134 21:52:19 INFO - mozversion INFO | application_changeset: aef73dcc9acc1b4434254e1e6bde1bfd36268b48 21:52:19 INFO - mozversion INFO | application_display_name: Nightly 21:52:19 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:52:19 INFO - mozversion INFO | application_name: Firefox 21:52:19 INFO - mozversion INFO | application_remotingname: firefox 21:52:19 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 21:52:19 INFO - mozversion INFO | application_vendor: Mozilla 21:52:19 INFO - mozversion INFO | application_version: 45.0a1 21:52:19 INFO - mozversion INFO | platform_buildid: 20151124203134 21:52:19 INFO - mozversion INFO | platform_changeset: aef73dcc9acc1b4434254e1e6bde1bfd36268b48 21:52:19 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 21:52:19 INFO - mozversion INFO | platform_version: 45.0a1 21:52:19 INFO - 2015-11-24 21:52:19,172 DEBUG : using testdate: 1448430739 21:52:19 INFO - 2015-11-24 21:52:19,172 DEBUG : actual date: 1448430739 21:52:19 INFO - 2015-11-24 21:52:19,219 INFO : starting webserver on 'localhost:51007' 21:52:19 INFO - 2015-11-24 21:52:19,220 INFO : Starting test suite talos-linux64-ix-021 21:52:19 INFO - 2015-11-24 21:52:19,220 INFO : Starting test dromaeo_css 21:52:19 INFO - 2015-11-24 21:52:19,221 DEBUG : operating with platform_type : linux_ 21:52:19 INFO - 2015-11-24 21:52:19,221 INFO : Initialising browser for dromaeo_css test... 21:52:19 INFO - 2015-11-24 21:52:19,228 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzKDhh9/profile http://localhost:51007/getInfo.html 21:52:27 INFO - 2015-11-24 21:52:27,332 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:52:27 INFO - 2015-11-24 21:52:27,332 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:52:27 INFO - 2015-11-24 21:52:27,332 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 21:52:27 INFO - 2015-11-24 21:52:27,332 DEBUG : BROWSER_OUTPUT: __metrics 21:52:27 INFO - 2015-11-24 21:52:27,514 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] 21:52:29 INFO - 2015-11-24 21:52:29,498 INFO : Browser initialized. 21:52:29 INFO - 2015-11-24 21:52:29,498 INFO : Running cycle 1/1 for dromaeo_css test... 21:52:29 INFO - 2015-11-24 21:52:29,498 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzKDhh9/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:52:41 INFO - 2015-11-24 21:52:41,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 139853824 21:52:41 INFO - 2015-11-24 21:52:41,221 DEBUG : BROWSER_OUTPUT: 21:53:31 INFO - 2015-11-24 21:53:31,929 DEBUG : BROWSER_OUTPUT: 1448430811928 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] 21:55:42 INFO - 2015-11-24 21:55:42,360 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51007/tests/dromaeo/cssquery-ext.html) 21:55:43 INFO - 2015-11-24 21:55:43,608 DEBUG : BROWSER_OUTPUT: RSS: Main: 270544896 21:55:43 INFO - 2015-11-24 21:55:43,608 DEBUG : BROWSER_OUTPUT: 21:58:46 INFO - 2015-11-24 21:58:46,089 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/dromaeo/cssquery-ext.html (next: http://localhost:51007/tests/dromaeo/cssquery-jquery.html) 21:58:46 INFO - 2015-11-24 21:58:46,658 DEBUG : BROWSER_OUTPUT: RSS: Main: 224411648 21:58:46 INFO - 2015-11-24 21:58:46,658 DEBUG : BROWSER_OUTPUT: 22:01:48 INFO - 2015-11-24 22:01:48,688 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51007/tests/dromaeo/cssquery-mootools.html) 22:01:49 INFO - 2015-11-24 22:01:49,262 DEBUG : BROWSER_OUTPUT: RSS: Main: 216285184 22:01:49 INFO - 2015-11-24 22:01:49,262 DEBUG : BROWSER_OUTPUT: 22:04:51 INFO - 2015-11-24 22:04:51,175 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51007/tests/dromaeo/cssquery-prototype.html) 22:04:51 INFO - 2015-11-24 22:04:51,737 DEBUG : BROWSER_OUTPUT: RSS: Main: 202153984 22:04:51 INFO - 2015-11-24 22:04:51,737 DEBUG : BROWSER_OUTPUT: 22:07:54 INFO - 2015-11-24 22:07:54,219 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51007/tests/dromaeo/cssquery-yui.html) 22:07:54 INFO - 2015-11-24 22:07:54,800 DEBUG : BROWSER_OUTPUT: RSS: Main: 203505664 22:07:54 INFO - 2015-11-24 22:07:54,801 DEBUG : BROWSER_OUTPUT: 22:10:57 INFO - 2015-11-24 22:10:57,334 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/dromaeo/cssquery-yui.html (next: http://localhost:51007/tests/dromaeo/cssquery-dojo.html) 22:10:57 INFO - 2015-11-24 22:10:57,584 DEBUG : BROWSER_OUTPUT: RSS: Main: 278130688 22:10:57 INFO - 2015-11-24 22:10:57,585 DEBUG : BROWSER_OUTPUT: 22:10:57 INFO - 2015-11-24 22:10:57,585 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:10:57 INFO - 2015-11-24 22:10:57,585 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:10:57 INFO - 2015-11-24 22:10:57,585 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:10:57 INFO - 2015-11-24 22:10:57,586 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:10:57 INFO - 2015-11-24 22:10:57,586 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3218.6781321867807;3220.9355812883728;3254.0400581828358;3267.3628642414737;3329.1510664780476;5270.789168433258;5351.518363347298;5387.434319396466;5454.863628409292;5479.972600136999;5488.91766623501;5508.228847961289;5568.49883510484;5668.064769313059;5686.260786097811;4807.519248075199;5026.698398096108;5043.067032598971;5070.949290507103;5192.948070519303;5190.143626301656;5249.396319423258;5250.4487028861995;5271.341082364705;5273.709945952971;4947.084789313988;5031.471695471996;5034.421041580192;5145.125328694112;5173.068847607429;5100.458283257956;5101.821436249714;5104.132297509395;5106.361704786902;5127.769250383742;5031.547160755549;5053.8231161909525;5080.720560369178;5084.186530155157;5091.134507133796;5154.845354639329;5199;5296.894062118775;5297.205419187114;5335.253064570963;5976.8505787355225;6005.609635373687;6016.458518733335;6019.946509360888;6028.608140470856;5877.706114694248;6035.7887473938645;6036.577439579231;6045.425684559959;6049.818505444844;5559.972200139055;5579.776808927638;5596.992541342557;5644.350899646511;5658.660480371191;5952.345242023374;6019.187409699634;6023.45788879003;6024.246969128859;6051.8487037823525;5784.855378615484;5789.652620842763;5799.217105690762;5801.419858014165;5853.560982926298;5786.971065144648;5813.680247586339;5850.502707269845;5882.029465138205;5948.345681974979;5893.174955506232;5925.4074592541265;5944.64332140073;5954.315253745788;5959.851003724855;5946.197263369476;5959.7914073008105;5967.5524335675;5975.103734439869;5976.402359764076;4434.467863856358;4514.052049069666;4536.092781443725;4612.3542704021465;4616.746078965622;4109;4133.152703695735;4144.896377590524;4151.4395556600075;4176.644985176234;5738.454846789548;5747.770089196385;5758.62568933018;5763.4812866842185;5819.447152520504;5842.737076831553;5897.823065307962;5900.350961394243;5970.940290597038;6002.519798416116;5613.607047506635;5640.943590564042;5684.54523638108;5689.061304884731;5701.344345400249;5198.142306519458;5225.843224703265;5229.555487783581;5245.763940622681;5276.65701729394;5879.147523609138;5983.611065280743;5987;6040.486558642477;6117.989078219788;6093.329733729375;6094.969525152346;6095.664738439428;6100.1459795629535;6102.206713127265;6201.658908760061;6252.562320637512;6253.062040693932;6262.06069089622;6267.843303917256;5686.374498805211;5815.069588865761;5877.471027607536;5883.882322353444;5925.970370148121;5965.8806823862415;5969.940300597113;5977.551683623833;5992.850178745566;6007.939920600738;5626.212330273846;5811.4479124483105;5830.154627578892;5838.649681019152;5848.4151584841175;5666.235058267246;5693.572982026281;5709.22925405065;5789.2763404574425;5835.503982161564;5745.1382292656435;5761.884762304647;5765.221695071112;5765.8558536036935;5769.336526299529;5718.313802343745;5833.387494313036;5897;5902.055671092605;5902.2327097477055;5702.7718891243885;5753.913691294718;5755.165501002248;5829.271341082364;5882.941170588239 22:10:57 INFO - 2015-11-24 22:10:57,587 DEBUG : BROWSER_OUTPUT: |1;ext.html;541.6219609901;582.257621532546;586.1091141464973;617.4165413684068;621.4562258024229;31619.20951976199;40897;40918.386224206624;41062.58937410625;41072.973175670624;44340.33489497652;44617.77691111548;44803.1039379213;44847.327290090674;44858.551414485846;7752.26353496417;7850.764477065669;8788.78028049299;8942.41874278173;8962.148595883413;10595.152387808996;10843.783124337508;10852.728681782939;10860.837087443695;10870.347779133237;10467.84298235527;10579.73550661232;10585.894141058607;10593.470326483684;10598.576056957712;7968.44220904534;8122.756317310431;8134.349252059821;10297.124744396735;10614.840777388345;10266.897331026668;10326.690199294033;10361.948190259076;10366.17070634355;10403.271770976035;40973.13188076836;41131.794341028406;41288.38067428991;41491.377629335584;41501;3449.931001379958;3968.9876248695277;4100.138970816132;4131.173765246933;4206.579342065769;33779;34404.82797586021;34496.655033449846;34544.48183277253;34791;3829.7893615851394;3863.150106976461;3967.761934283952;3977.880663580097;4007;264427;265964;266037;267264;268408.6579567109;62061.37938620646;62231.6888415555;62235;62359.6882015587;62431;67064.32935670679;67200;67253.66373168102;67265.26449458278;67350.32649673539;15717.44988925405;15967.36130554788;15978.201089945456;16005.439809606607;16011.359545618045;12188.390580470941;12259.448324825404;12260.509579616715;12272.631821045383;12274.815877761841;20109.39671809848;20935.895320523603;21141.26005589797;21160.470988225417;21169.894150529148;6990.580565166107;7968.195144365509;8138.186181381814;8250;8268.503889766633;917.7797328641078;1030.6907927621683;1077.2028698763015;1113.7271368514766;1126.3298337489182;29234.707652923626;29725.851370743007;29837;33301.16747081342;33931;1011.3780025284544;1057.6774083904484;1119.764849381639;1126.693155588351;1140.1733743036398;16771.664566708598;17565.560860978578;17593.47219583389;17714.291428342724;17733.46799596014;386491;389518;390491;392506;393317;55295.44704552903;55835.162472562944;55954;56013;56053;1352.2765320553456;1371.2526672963259;1390.2075816784336;1391.9443222271198;1393.6655202751267;29709.554356684235;30375.696243037288;30592.84703576468;30744.538831917987;30916.22709432282;47247.76376118098;47554.76222618865;47818.760906196636;47913.281300781215;47920.520794791606;264022;285216;285823;286310;288090;26861.328466788487;33386;33864.83067584646;34149.60952733285;34188;42341.576584233764;42880.142397151256;43166.78416608022;43548.34677479777;44029;35181;35358.46962295516;35789.46315805213;36360.63639363572;37017;30860.87149954257;36342.63657363393;36713.08217294589;39478.802605986784;40507.1898562033;36646.26707465889;37328;37530.437043444916;37531.43702844405;37593.43609845909;33069.83465082659;33993.88415877227;34316.65683343135;34732.305353892276;34829;25763.39757160529;26072.608910866733;26103.347416313984;26422.471550568498;26554.336141596614 22:10:57 INFO - 2015-11-24 22:10:57,591 DEBUG : BROWSER_OUTPUT: |2;jquery.html;350.0827831082564;354.12707675579696;354.2136457065314;354.2383874669461;354.70736642270134;13758.656033599165;14101.858981410209;16806.327746890125;16932.238049287782;17140.65718685627;15763.60590985226;16130.596735081628;16985.91507042466;17161.656766864657;17290.13549322532;5273.156294992802;5432.864178395532;5435.157550579666;5442.91835622466;5443.727813609324;3892.5912779158207;3981;5345.599080069011;5669.546436285108;5867.031939729939;5913.349531551526;5926.199963005004;5938.46553810157;5948.196993405878;5951.613145145551;5803.73883175256;5941.851453713692;5946.821595352125;5948.613340132899;5952.077427998667;5177.896442071175;5189.792408303663;5207.297014902978;5209.557187639055;5239.685618862843;12081.637550873487;12480.750384992249;12481.438335274934;12488.75022499554;19419.3203237886;2041.428400047974;2139.358192542231;2184.6613774864923;2231.5983123037845;2311.722593288799;8530.573471326408;9433.669821556281;11856.288622682581;12538.435770390355;13046.217226966412;1774.8313910178513;1805.9367922122794;1828.6159906419668;1835.614520950602;1850.7223916412645;57640.423595763925;60533.09200362042;61522;61668;62243.37756622421;9558.091981261769;9698.127168554864;9771.755706107262;9810.950945245228;9813;12522.499100035897;12530.060245481625;12576.559820406243;12620.17968832023;12650.177738446971;7446.441516886255;7510.511816731895;7618.5809780462605;7672.347850432664;7682.615869206629;7417.703291868373;7476.476646634792;7485.513441626277;7584.962075189589;7647.464677472523;4089.2026054919006;4149.33610622299;4156.189543039139;4185.623293903503;4186.790660467025;1680.6554656295675;1719.6216832296875;1749.1866282178848;1794.5872449336464;1803.053396966603;910.2854259406334;926.0554234680588;941.30343545777;986.7335819328885;1006.8942761010135;15813.920930395274;16432.260548275357;17436.738448923275;17495.125243737763;17665.49216582947;891.362675686874;893.4862454088925;907.5008745190119;942.599395257013;1030.8196065688473;9124.012847345359;9416.576254068583;9468.715938521855;9484.099010593982;9600.087991640783;507733;512650;514682;517303;518382;75117;78238.6088069556;82213;82218;82487.5875620618;1123.4102096399292;1126.3973774031017;1128.4978184707727;1155.058627218814;1160.7794519041524;14948;15013.699726005638;15058.849411505746;15807.604809879846;15812.60468488297;34353.828230858686;35427.82286088553;35537.79938628298;35875.64124358723;36039;46768;47293.527064728914;47817.76091119522;48017.5198248027;50289.49710502848;8978.102189781232;9151.954240228757;9161.175494205554;9209.079092090737;9223.123803238681;10798.73003174927;13548.119372240773;14896.32966516531;15544.689106218038;16619.085950272853;11894.167408281337;13195.60413187606;14168.645783855074;14216.786748198992;14309.499167529502;9760.804783904425;9828.950855245677;10094.79810403773;10138.949305253427;10307.484625768531;8943.731688049369;10110.191184705352;10425.322354046963;10457.895421045996;10485.318454300446;13078.411471483996;14923.104613723212;15111.395544178109;15563.45527906563;15600.297986590404;15667.373305068126;15752.68494630078;15915.204239787732;16000.359985600446;16292.67414651724 22:10:57 INFO - 2015-11-24 22:10:57,591 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1329.7407005633904;1691.5432833135053;1734.3322820714025;1756.736489526571;1780.5904641932354;6801.183857937049;7238.457115716323;7500;7687.269709377614;7687.423443241752;7592.772216833504;7685.116211635664;7716.768496945087;7734.149243583215;7738.032745906762;1942.9611407771836;1989.333573252962;2282.554901794155;2321.2339927823778;2322.744498105207;2255.9436014099615;2309.722833260003;2310.5263420998654;2312.5143719818775;2313.4794671199015;2205.0959106766227;2214.070090561968;2288.645259984705;2291.3927809130596;2300.5973954557885;1887.59416725404;1895.573495963411;2154.687570302305;2229.6544035674497;2267.501149747053;2249.4488850231796;2260.993857733309;2265.88670566471;2266.7733226677365;2267.2971378872603;7721.305082542598;7778.261065198797;7780.844383112362;7787.688492460352;7805.531668099876;4627.699199552018;4697.201475749131;4700.976495117537;4718.103560323528;4739.549742774431;3145.685431456859;3161.4309424303615;3172.349668317989;3215.1479857837694;3220.6618305077864;970.315927271272;987.3680844259679;992.0823238504427;1012.8784545854545;1025.912797412221;10663.933606639273;10725.03474687282;10727.624533141303;10755.569777208902;10798.352098874093;4179.373094035858;4217.135487225111;4297.05464797744;4325.956740432555;4329.15581461612;9105.499197544199;9118.498482583389;9132.406393584395;9173.44959302444;9186.081391860895;4658.34783130362;4705.035467729107;4708;4710.317004034396;4710.764461776897;4187.225363307798;4237.427947227146;4242.978785106055;4247.341662042389;4248.702590818613;3655.8354874030733;3678.70570354371;3700.2414505026404;3735.831887565066;3740.6259374062374;1159.1074872348245;1162.2798258577352;1162.6222925832915;1170.0697945133638;1174.8179032249843;1043.1811028342865;1068.7755571329951;1068.9251752377415;1212.5998420521207;1348.3527906604688;6946.687399067054;7091.078159839188;7359.374453171434;7630.198829122974;7654.923450765533;609.2871340531589;615.8952977993752;616.4144063140035;623.8159742875863;627.0719335383658;5763.682997435181;5784.768609255667;5804.680742559115;5820.82537523875;5830.650160990354;9336.15974562279;9473.715788526355;9488.478133702713;9496.762580935532;9511.762205944768;9207.95396023029;9368.39105458143;9375.015623359586;9496.050394960586;9516.286278529138;7289.125304963332;7310.451716121206;7348.63256837156;7351.926480735338;7365.852682946311;6930.5148639594745;7001.684924178322;7015.193252775996;7038.2609825969585;7047.119110111236;8140.430169888051;8361.832763344577;8425.87361189595;8431.19903609156;8508.74473765788;8420.326373889951;8438.367122465717;8438.957805210935;8635.309175265838;8642.697505587274;6761.729530818712;6811.4550835934;6813;6822.454203663794;6829.385355318045;7536.057992750906;7648.273414025659;7693.653785579774;7695.807604809925;7708.267714567107;7146.035285236427;7203.603801790951;7214.74748383804;7245.347918687344;7348.154962179417;6978.860422791414;7059.152901651834;7059.964700176466;7155.391791697556;7192.568445893263;6143.047827586731;6220.409061139185;7159.140903091662;7178.389836863925;7193.856122877409;6369.426751592379;6531.150950376421;6560.704768285534;6599.5050371223315;6602.570832895846;6573.638449885111;6615.933840661532;6657.101291325609;6670.799876003728;6671.5997040177745 22:10:57 INFO - 2015-11-24 22:10:57,594 DEBUG : BROWSER_OUTPUT: |4;prototype.html;317.33993293948583;326.295202362896;335.7666421836823;343.16268305334967;344.1705489769654;5875.265591801025;5903.4686878180955;6013.338532761392;6028.065649824273;6034.487068599175;5892;5892.823215303548;5898;5901.4688678018965;5917.526597872171;4616.792244349012;4618.930716039254;4619.03000369921;4629.490756016836;4636.837710680127;4411.382406463098;4539.659525535581;4562.84030058948;4629.791659375336;4639.907201855961;4502.707324023928;4509.25597276449;4522.457305123374;4528.071745292224;4543.363929049936;4327.156204540116;4520.9095818083615;4521.706089104214;4524.959259370363;4528.796204170803;3851.364524853396;3904.2191561687778;3949.8420063197436;3957.3866050762176;3964.6431821136234;5621.381648018714;5635.971820140873;5638.097904335286;5643.153526970947;5651.434856514356;5004.074246264417;5124.769385377667;5167.896642067175;5262.23697563855;5264.710440925747;6230.87538249237;6261.27995280544;6267.843303917393;6271.6864156792435;6272.027835685476;4348.456442944632;4354.629856462205;4354.782260886943;4355.695101342907;4362.323839804835;6325.557210995232;6472.5145614078665;6547.116139321226;6549.93450065498;6557.868842623168;4448.799804008828;4460.531644177315;4469.262571675637;4476.574725401082;4476.686631935733;4509.819607215741;4522.29904364824;4527.660425468103;4531.77341132942;4532.0256144929;5249.763760630781;5255.3168088148295;5257.553107985787;5263.921041184385;5264.078786212397;6197.938020619827;6356.904646430307;6360.109584658151;6360.268569114519;6364.745410183634;1579.447193482295;1584.1445619365643;1584.302906721062;1585.6194513316952;1585.8572728454494;1031.231732359397;1036.1555332404066;1050.406520316019;1051.5110473629645;1059.1685526861377;6091.786787462417;6114.602550834182;6145.985912324416;6151.108089327112;6156.8768624627255;6494.318096599884;6500.544961852719;6559.212894452697;6560.737570497126;6565.606063636197;6008.128821320884;6102.786402475981;6105.419985101408;6111.786087486917;6141.662208578571;3479.3911065563425;3519.560054993126;3533.063738109403;3533.9293214135573;3546.4325707886615;6710.664466776641;6716.496262780311;6719.193696756421;6736.2253340865445;6736.46108311334;6451.903221451682;6663.267040625527;6669.299723529057;6674.16572928384;6675.532712710064;6283.868903597305;6639.933600664028;6786.626735529593;6819.283975182534;6823.658817059128;6584;6597.472202223907;6599.703013364506;6603.80188594343;6608.273089960005;6526.053722210348;6533.738650454119;6534.085228068169;6537.411632953057;6537.8692426152165;6647.833804154935;6785.117934668461;6794.422474089758;6797.456203503807;6799.048133261446;4454.7772611368655;4457.910841783211;4479.126570318756;4489.820407183676;4501.459824821042;6583.769568065095;6592.274849766618;6592.868142637216;6595.736170553124;6609.4712423007;6257.217847769029;6395.42441180296;6408.58344207625;6413.198350206224;6419.839504012437;6381.266154392119;6401.8719625608155;6402.263739669997;6403.327650596807;6408.871822563616;6231.189945307081;6240.28236752761;6240.375962403723;6262.499000079892;6264.715733274759;6274.215723034621;6403.295637479967;6438.484921206386;6447.290798012121;6450.870982580416;6295.905561416674;6297.275813281348;6298.968505157445;6300.086487459384;6302.338254483397 22:10:57 INFO - 2015-11-24 22:10:57,596 DEBUG : BROWSER_OUTPUT: |5;yui.html;669.8425869920571;682.4199430484086;735.8675438421084;807.1968391450507;856.6372032381681;13415.932920335397;13652.976026797993;13667.863321366784;13674.999999999975;13680;13709.657258568515;13760.93119534401;13787.655308617264;13820.585382438518;13822.170669759797;666.7899611622348;677.8136012596551;815.5881279953616;825.8389614025267;826.1036775099025;826.9669213231464;830.5182993863575;832.1013305629905;833.4166083741375;834.6828205281985;714.7677004973378;722.1118024829462;871.877937088808;878.1701292278799;879.5953861223812;851.5529347092764;867.3061550759398;868.1665601023026;868.192580899763;869.5782545465444;631.823089534931;634.9587276827037;638.2628064585411;639.0127253393518;639.1307821362942;13749.725005499835;13859;13889.79165312521;13946.442142314396;14003.719925601532;11923.284602923852;12372.257664540157;12554.68613284666;12565.748685026248;12668;10116.797664046751;10330.7417314567;10342.896571034344;10620.256582039261;10727.409992450412;3572.160542272564;3639.3085313790293;3653.872114476007;3680.1351682354625;3687.1519550503535;47363;47583.5241647586;47678.5232147681;48081.51918480805;48165.75917120392;8966.193042626195;9255.953720231355;9308.767280818034;9352;9396.436213827192;16524.504264872074;16634.75047874283;16686.082265475514;16686.58283542921;16688.499345019427;7390.889136662954;7564.848703025908;7567.545947243183;7773.106092799288;7858.685652573833;4353.651707863363;4395.252807022814;4442.666799990014;4450.332450132441;4468.642508599304;3121.360121175154;3189.537517060013;3191.984040079785;3195.0255172172247;3196.2488815128427;541.3801197628693;560.7168379968172;572.3932631410718;595.3718826637938;621.2079598511843;632.5540493951753;637.8564822914807;682.7917485166972;684.4729558240127;692.0001196541998;7717.1896950819355;7798.25916537928;8016.118226995026;8031.277185053374;8184.754457366289;592.4135106244787;602.4848754315085;620.3634553149117;697.3514631392853;706.3713295167305;3646.3072016317346;3713.814309284525;3862.4592557042038;3893.53277606689;4081.69387295954;57657;57970;58384.70807645935;58458;58506.12240816306;51457.48542514527;51924.22113668222;51925.22112168321;51998.48001519936;52086;46672.299915501295;47239.76380118146;47478.5252147481;47690.76154619205;48212.27681584779;7754.418418618739;7889.816527520688;7989.52062876206;8051;8066.637001334837;32762.83618581892;34239.82880085584;34878.82560587181;35320.29359412849;35831.46252806158;40552.797236013634;45667.314990274506;46324.7683761579;46936;47080.7645961768;10071.848922266025;10222.386656800616;10456.790864182522;10693.679189624323;10700.625478108228;11221.719457013314;11282;11315.490802914053;11330.093592512414;11666.591669291534;10531.736706582396;10666.8399974002;11123.221374503708;11131.72170695739;11253.043491303331;10398.740031499274;10590.258681892501;10592;10771.34768061145;10886.78226435451;10146.441945693057;10262.538185781508;10311.175105991653;10313.385795555801;10385.03213178274;9239.307051970995;9313.720588382359;9381.765455863659;9601.682973782732;9609;8253.298469630148;8542.91457085446;9166.908330916605;9188.264938805014;9208.723738287863 22:10:57 INFO - 2015-11-24 22:10:57,596 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:10:57 INFO - 2015-11-24 22:10:57,596 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:10:57 INFO - 2015-11-24 22:10:57,596 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1017 22:10:57 INFO - 2015-11-24 22:10:57,596 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:10:57 INFO - 2015-11-24 22:10:57,597 DEBUG : BROWSER_OUTPUT: __startTimestamp1448431857583__endTimestamp 22:10:57 INFO - 2015-11-24 22:10:57,597 DEBUG : BROWSER_OUTPUT: 22:10:57 INFO - 2015-11-24 22:10:57,597 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:10:57 INFO - 2015-11-24 22:10:57,597 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:10:57 INFO - 2015-11-24 22:10:57,597 DEBUG : BROWSER_OUTPUT: 22:10:57 INFO - 2015-11-24 22:10:57,597 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5532.65 Median:5758.63 stddev:615.79 (10.7%) stddev-sans-first:590.31 22:10:57 INFO - 2015-11-24 22:10:57,597 DEBUG : BROWSER_OUTPUT: Values: 3218.7 3220.9 3254.0 3267.4 3329.2 5270.8 5351.5 5387.4 5454.9 5480.0 5488.9 5508.2 5568.5 5668.1 5686.3 4807.5 5026.7 5043.1 5070.9 5192.9 5190.1 5249.4 5250.4 5271.3 5273.7 4947.1 5031.5 5034.4 5145.1 5173.1 5100.5 5101.8 5104.1 5106.4 5127.8 5031.5 5053.8 5080.7 5084.2 5091.1 5154.8 5199.0 5296.9 5297.2 5335.3 5976.9 6005.6 6016.5 6019.9 6028.6 5877.7 6035.8 6036.6 6045.4 6049.8 5560.0 5579.8 5597.0 5644.4 5658.7 5952.3 6019.2 6023.5 6024.2 6051.8 5784.9 5789.7 5799.2 5801.4 5853.6 5787.0 5813.7 5850.5 5882.0 5948.3 5893.2 5925.4 5944.6 5954.3 5959.9 5946.2 5959.8 5967.6 5975.1 5976.4 4434.5 4514.1 4536.1 4612.4 4616.7 4109.0 4133.2 4144.9 4151.4 4176.6 5738.5 5747.8 5758.6 5763.5 5819.4 5842.7 5897.8 5900.4 5970.9 6002.5 5613.6 5640.9 5684.5 5689.1 5701.3 5198.1 5225.8 5229.6 5245.8 5276.7 5879.1 5983.6 5987.0 6040.5 6118.0 6093.3 6095.0 6095.7 6100.1 6102.2 6201.7 6252.6 6253.1 6262.1 6267.8 5686.4 5815.1 5877.5 5883.9 5926.0 5965.9 5969.9 5977.6 5992.9 6007.9 5626.2 5811.4 5830.2 5838.6 5848.4 5666.2 5693.6 5709.2 5789.3 5835.5 5745.1 5761.9 5765.2 5765.9 5769.3 5718.3 5833.4 5897.0 5902.1 5902.2 5702.8 5753.9 5755.2 5829.3 5882.9 22:10:57 INFO - 2015-11-24 22:10:57,597 DEBUG : BROWSER_OUTPUT: 22:10:57 INFO - 2015-11-24 22:10:57,599 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49298.82 Median:34638.39 stddev:83263.27 (240.4%) stddev-sans-first:83416.37 22:10:57 INFO - 2015-11-24 22:10:57,599 DEBUG : BROWSER_OUTPUT: Values: 541.6 582.3 586.1 617.4 621.5 31619.2 40897.0 40918.4 41062.6 41073.0 44340.3 44617.8 44803.1 44847.3 44858.6 7752.3 7850.8 8788.8 8942.4 8962.1 10595.2 10843.8 10852.7 10860.8 10870.3 10467.8 10579.7 10585.9 10593.5 10598.6 7968.4 8122.8 8134.3 10297.1 10614.8 10266.9 10326.7 10361.9 10366.2 10403.3 40973.1 41131.8 41288.4 41491.4 41501.0 3449.9 3969.0 4100.1 4131.2 4206.6 33779.0 34404.8 34496.7 34544.5 34791.0 3829.8 3863.2 3967.8 3977.9 4007.0 264427.0 265964.0 266037.0 267264.0 268408.7 62061.4 62231.7 62235.0 62359.7 62431.0 67064.3 67200.0 67253.7 67265.3 67350.3 15717.4 15967.4 15978.2 16005.4 16011.4 12188.4 12259.4 12260.5 12272.6 12274.8 20109.4 20935.9 21141.3 21160.5 21169.9 6990.6 7968.2 8138.2 8250.0 8268.5 917.8 1030.7 1077.2 1113.7 1126.3 29234.7 29725.9 29837.0 33301.2 33931.0 1011.4 1057.7 1119.8 1126.7 1140.2 16771.7 17565.6 17593.5 17714.3 17733.5 386491.0 389518.0 390491.0 392506.0 393317.0 55295.4 55835.2 55954.0 56013.0 56053.0 1352.3 1371.3 1390.2 1391.9 1393.7 29709.6 30375.7 30592.8 30744.5 30916.2 47247.8 47554.8 47818.8 47913.3 47920.5 264022.0 285216.0 285823.0 286310.0 288090.0 26861.3 33386.0 33864.8 34149.6 34188.0 42341.6 42880.1 43166.8 43548.3 44029.0 35181.0 35358.5 35789.5 36360.6 37017.0 30860.9 36342.6 36713.1 39478.8 40507.2 36646.3 37328.0 37530.4 37531.4 37593.4 33069.8 33993.9 34316.7 34732.3 34829.0 25763.4 26072.6 26103.3 26422.5 26554.3 22:10:57 INFO - 2015-11-24 22:10:57,599 DEBUG : BROWSER_OUTPUT: 22:10:57 INFO - 2015-11-24 22:10:57,599 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27872.34 Median:35957.32 stddev:84105.48 (233.9%) stddev-sans-first:84316.03 22:10:57 INFO - 2015-11-24 22:10:57,601 DEBUG : BROWSER_OUTPUT: Values: 350.1 354.1 354.2 354.2 354.7 13758.7 14101.9 16806.3 16932.2 17140.7 15763.6 16130.6 16985.9 17161.7 17290.1 5273.2 5432.9 5435.2 5442.9 5443.7 3892.6 3981.0 5345.6 5669.5 5867.0 5913.3 5926.2 5938.5 5948.2 5951.6 5803.7 5941.9 5946.8 5948.6 5952.1 5177.9 5189.8 5207.3 5209.6 5239.7 12081.6 12480.8 12481.4 12488.8 19419.3 2041.4 2139.4 2184.7 2231.6 2311.7 8530.6 9433.7 11856.3 12538.4 13046.2 1774.8 1805.9 1828.6 1835.6 1850.7 57640.4 60533.1 61522.0 61668.0 62243.4 9558.1 9698.1 9771.8 9811.0 9813.0 12522.5 12530.1 12576.6 12620.2 12650.2 7446.4 7510.5 7618.6 7672.3 7682.6 7417.7 7476.5 7485.5 7585.0 7647.5 4089.2 4149.3 4156.2 4185.6 4186.8 1680.7 1719.6 1749.2 1794.6 1803.1 910.3 926.1 941.3 986.7 1006.9 15813.9 16432.3 17436.7 17495.1 17665.5 891.4 893.5 907.5 942.6 1030.8 9124.0 9416.6 9468.7 9484.1 9600.1 507733.0 512650.0 514682.0 517303.0 518382.0 75117.0 78238.6 82213.0 82218.0 82487.6 1123.4 1126.4 1128.5 1155.1 1160.8 14948.0 15013.7 15058.8 15807.6 15812.6 34353.8 35427.8 35537.8 35875.6 36039.0 46768.0 47293.5 47817.8 48017.5 50289.5 8978.1 9152.0 9161.2 9209.1 9223.1 10798.7 13548.1 14896.3 15544.7 16619.1 11894.2 13195.6 14168.6 14216.8 14309.5 9760.8 9829.0 10094.8 10138.9 10307.5 8943.7 10110.2 10425.3 10457.9 10485.3 13078.4 14923.1 15111.4 15563.5 15600.3 15667.4 15752.7 15915.2 16000.4 16292.7 22:10:57 INFO - 2015-11-24 22:10:57,601 DEBUG : BROWSER_OUTPUT: 22:10:57 INFO - 2015-11-24 22:10:57,601 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5448.21 Median:6545.93 stddev:2853.08 (43.6%) stddev-sans-first:2844.29 22:10:57 INFO - 2015-11-24 22:10:57,601 DEBUG : BROWSER_OUTPUT: Values: 1329.7 1691.5 1734.3 1756.7 1780.6 6801.2 7238.5 7500.0 7687.3 7687.4 7592.8 7685.1 7716.8 7734.1 7738.0 1943.0 1989.3 2282.6 2321.2 2322.7 2255.9 2309.7 2310.5 2312.5 2313.5 2205.1 2214.1 2288.6 2291.4 2300.6 1887.6 1895.6 2154.7 2229.7 2267.5 2249.4 2261.0 2265.9 2266.8 2267.3 7721.3 7778.3 7780.8 7787.7 7805.5 4627.7 4697.2 4701.0 4718.1 4739.5 3145.7 3161.4 3172.3 3215.1 3220.7 970.3 987.4 992.1 1012.9 1025.9 10663.9 10725.0 10727.6 10755.6 10798.4 4179.4 4217.1 4297.1 4326.0 4329.2 9105.5 9118.5 9132.4 9173.4 9186.1 4658.3 4705.0 4708.0 4710.3 4710.8 4187.2 4237.4 4243.0 4247.3 4248.7 3655.8 3678.7 3700.2 3735.8 3740.6 1159.1 1162.3 1162.6 1170.1 1174.8 1043.2 1068.8 1068.9 1212.6 1348.4 6946.7 7091.1 7359.4 7630.2 7654.9 609.3 615.9 616.4 623.8 627.1 5763.7 5784.8 5804.7 5820.8 5830.7 9336.2 9473.7 9488.5 9496.8 9511.8 9208.0 9368.4 9375.0 9496.1 9516.3 7289.1 7310.5 7348.6 7351.9 7365.9 6930.5 7001.7 7015.2 7038.3 7047.1 8140.4 8361.8 8425.9 8431.2 8508.7 8420.3 8438.4 8439.0 8635.3 8642.7 6761.7 6811.5 6813.0 6822.5 6829.4 7536.1 7648.3 7693.7 7695.8 7708.3 7146.0 7203.6 7214.7 7245.3 7348.2 6978.9 7059.2 7060.0 7155.4 7192.6 6143.0 6220.4 7159.1 7178.4 7193.9 6369.4 6531.2 6560.7 6599.5 6602.6 6573.6 6615.9 6657.1 6670.8 6671.6 22:10:57 INFO - 2015-11-24 22:10:57,601 DEBUG : BROWSER_OUTPUT: 22:10:57 INFO - 2015-11-24 22:10:57,601 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5289.88 Median:6063.14 stddev:1605.27 (26.5%) stddev-sans-first:1565.78 22:10:57 INFO - 2015-11-24 22:10:57,601 DEBUG : BROWSER_OUTPUT: Values: 317.3 326.3 335.8 343.2 344.2 5875.3 5903.5 6013.3 6028.1 6034.5 5892.0 5892.8 5898.0 5901.5 5917.5 4616.8 4618.9 4619.0 4629.5 4636.8 4411.4 4539.7 4562.8 4629.8 4639.9 4502.7 4509.3 4522.5 4528.1 4543.4 4327.2 4520.9 4521.7 4525.0 4528.8 3851.4 3904.2 3949.8 3957.4 3964.6 5621.4 5636.0 5638.1 5643.2 5651.4 5004.1 5124.8 5167.9 5262.2 5264.7 6230.9 6261.3 6267.8 6271.7 6272.0 4348.5 4354.6 4354.8 4355.7 4362.3 6325.6 6472.5 6547.1 6549.9 6557.9 4448.8 4460.5 4469.3 4476.6 4476.7 4509.8 4522.3 4527.7 4531.8 4532.0 5249.8 5255.3 5257.6 5263.9 5264.1 6197.9 6356.9 6360.1 6360.3 6364.7 1579.4 1584.1 1584.3 1585.6 1585.9 1031.2 1036.2 1050.4 1051.5 1059.2 6091.8 6114.6 6146.0 6151.1 6156.9 6494.3 6500.5 6559.2 6560.7 6565.6 6008.1 6102.8 6105.4 6111.8 6141.7 3479.4 3519.6 3533.1 3533.9 3546.4 6710.7 6716.5 6719.2 6736.2 6736.5 6451.9 6663.3 6669.3 6674.2 6675.5 6283.9 6639.9 6786.6 6819.3 6823.7 6584.0 6597.5 6599.7 6603.8 6608.3 6526.1 6533.7 6534.1 6537.4 6537.9 6647.8 6785.1 6794.4 6797.5 6799.0 4454.8 4457.9 4479.1 4489.8 4501.5 6583.8 6592.3 6592.9 6595.7 6609.5 6257.2 6395.4 6408.6 6413.2 6419.8 6381.3 6401.9 6402.3 6403.3 6408.9 6231.2 6240.3 6240.4 6262.5 6264.7 6274.2 6403.3 6438.5 6447.3 6450.9 6295.9 6297.3 6299.0 6300.1 6302.3 22:10:57 INFO - 2015-11-24 22:10:57,601 DEBUG : BROWSER_OUTPUT: 22:10:57 INFO - 2015-11-24 22:10:57,601 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13677.49 Median:47684.64 stddev:16137.13 (33.8%) stddev-sans-first:16152.83 22:10:57 INFO - 2015-11-24 22:10:57,602 DEBUG : BROWSER_OUTPUT: Values: 669.8 682.4 735.9 807.2 856.6 13415.9 13653.0 13667.9 13675.0 13680.0 13709.7 13760.9 13787.7 13820.6 13822.2 666.8 677.8 815.6 825.8 826.1 827.0 830.5 832.1 833.4 834.7 714.8 722.1 871.9 878.2 879.6 851.6 867.3 868.2 868.2 869.6 631.8 635.0 638.3 639.0 639.1 13749.7 13859.0 13889.8 13946.4 14003.7 11923.3 12372.3 12554.7 12565.7 12668.0 10116.8 10330.7 10342.9 10620.3 10727.4 3572.2 3639.3 3653.9 3680.1 3687.2 47363.0 47583.5 47678.5 48081.5 48165.8 8966.2 9256.0 9308.8 9352.0 9396.4 16524.5 16634.8 16686.1 16686.6 16688.5 7390.9 7564.8 7567.5 7773.1 7858.7 4353.7 4395.3 4442.7 4450.3 4468.6 3121.4 3189.5 3192.0 3195.0 3196.2 541.4 560.7 572.4 595.4 621.2 632.6 637.9 682.8 684.5 692.0 7717.2 7798.3 8016.1 8031.3 8184.8 592.4 602.5 620.4 697.4 706.4 3646.3 3713.8 3862.5 3893.5 4081.7 57657.0 57970.0 58384.7 58458.0 58506.1 51457.5 51924.2 51925.2 51998.5 52086.0 46672.3 47239.8 47478.5 47690.8 48212.3 7754.4 7889.8 7989.5 8051.0 8066.6 32762.8 34239.8 34878.8 35320.3 35831.5 40552.8 45667.3 46324.8 46936.0 47080.8 10071.8 10222.4 10456.8 10693.7 10700.6 11221.7 11282.0 11315.5 11330.1 11666.6 10531.7 10666.8 11123.2 11131.7 11253.0 10398.7 10590.3 10592.0 10771.3 10886.8 10146.4 10262.5 10311.2 10313.4 10385.0 9239.3 9313.7 9381.8 9601.7 9609.0 8253.3 8542.9 9166.9 9188.3 9208.7 22:10:57 INFO - 2015-11-24 22:10:57,603 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:10:57 INFO - 2015-11-24 22:10:57,603 DEBUG : BROWSER_OUTPUT: 22:10:58 INFO - 2015-11-24 22:10:58,401 INFO : Browser exited with error code: 0 22:10:58 INFO - 2015-11-24 22:10:58,422 INFO : Completed test dromaeo_css (00:18:39) 22:10:58 INFO - 2015-11-24 22:10:58,422 INFO : Starting test kraken 22:10:58 INFO - 2015-11-24 22:10:58,423 DEBUG : operating with platform_type : linux_ 22:10:58 INFO - 2015-11-24 22:10:58,423 INFO : Initialising browser for kraken test... 22:10:58 INFO - 2015-11-24 22:10:58,429 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_LYEkh/profile http://localhost:51007/getInfo.html 22:11:03 INFO - 2015-11-24 22:11:03,054 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:11:03 INFO - 2015-11-24 22:11:03,054 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:11:03 INFO - 2015-11-24 22:11:03,054 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:11:03 INFO - 2015-11-24 22:11:03,054 DEBUG : BROWSER_OUTPUT: __metrics 22:11:03 INFO - 2015-11-24 22:11:03,162 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] 22:11:04 INFO - 2015-11-24 22:11:04,648 INFO : Browser initialized. 22:11:04 INFO - 2015-11-24 22:11:04,648 INFO : Running cycle 1/1 for kraken test... 22:11:04 INFO - 2015-11-24 22:11:04,649 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_LYEkh/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:11:16 INFO - 2015-11-24 22:11:16,392 DEBUG : BROWSER_OUTPUT: RSS: Main: 139448320 22:11:16 INFO - 2015-11-24 22:11:16,393 DEBUG : BROWSER_OUTPUT: 22:11:18 INFO - 2015-11-24 22:11:18,815 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51007/tests/kraken/driver.html?testName=audio-beat-detection) 22:11:19 INFO - 2015-11-24 22:11:19,397 DEBUG : BROWSER_OUTPUT: RSS: Main: 176508928 22:11:19 INFO - 2015-11-24 22:11:19,397 DEBUG : BROWSER_OUTPUT: 22:11:21 INFO - 2015-11-24 22:11:21,987 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51007/tests/kraken/driver.html?testName=audio-dft) 22:11:22 INFO - 2015-11-24 22:11:22,554 DEBUG : BROWSER_OUTPUT: RSS: Main: 166359040 22:11:22 INFO - 2015-11-24 22:11:22,555 DEBUG : BROWSER_OUTPUT: 22:11:25 INFO - 2015-11-24 22:11:25,385 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51007/tests/kraken/driver.html?testName=audio-fft) 22:11:25 INFO - 2015-11-24 22:11:25,954 DEBUG : BROWSER_OUTPUT: RSS: Main: 150216704 22:11:25 INFO - 2015-11-24 22:11:25,955 DEBUG : BROWSER_OUTPUT: 22:11:27 INFO - 2015-11-24 22:11:27,926 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51007/tests/kraken/driver.html?testName=audio-oscillator) 22:11:28 INFO - 2015-11-24 22:11:28,492 DEBUG : BROWSER_OUTPUT: RSS: Main: 153673728 22:11:28 INFO - 2015-11-24 22:11:28,492 DEBUG : BROWSER_OUTPUT: 22:11:30 INFO - 2015-11-24 22:11:30,665 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51007/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:11:31 INFO - 2015-11-24 22:11:31,226 DEBUG : BROWSER_OUTPUT: RSS: Main: 154984448 22:11:31 INFO - 2015-11-24 22:11:31,226 DEBUG : BROWSER_OUTPUT: 22:11:34 INFO - 2015-11-24 22:11:34,146 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51007/tests/kraken/driver.html?testName=imaging-darkroom) 22:11:34 INFO - 2015-11-24 22:11:34,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 153980928 22:11:34 INFO - 2015-11-24 22:11:34,714 DEBUG : BROWSER_OUTPUT: 22:11:38 INFO - 2015-11-24 22:11:38,115 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51007/tests/kraken/driver.html?testName=imaging-desaturate) 22:11:38 INFO - 2015-11-24 22:11:38,696 DEBUG : BROWSER_OUTPUT: RSS: Main: 156168192 22:11:38 INFO - 2015-11-24 22:11:38,697 DEBUG : BROWSER_OUTPUT: 22:11:41 INFO - 2015-11-24 22:11:41,417 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51007/tests/kraken/driver.html?testName=json-parse-financial) 22:11:41 INFO - 2015-11-24 22:11:41,986 DEBUG : BROWSER_OUTPUT: RSS: Main: 155807744 22:11:41 INFO - 2015-11-24 22:11:41,986 DEBUG : BROWSER_OUTPUT: 22:11:43 INFO - 2015-11-24 22:11:43,547 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51007/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:11:44 INFO - 2015-11-24 22:11:44,108 DEBUG : BROWSER_OUTPUT: RSS: Main: 163119104 22:11:44 INFO - 2015-11-24 22:11:44,108 DEBUG : BROWSER_OUTPUT: 22:11:45 INFO - 2015-11-24 22:11:45,928 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51007/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:11:46 INFO - 2015-11-24 22:11:46,522 DEBUG : BROWSER_OUTPUT: RSS: Main: 162111488 22:11:46 INFO - 2015-11-24 22:11:46,522 DEBUG : BROWSER_OUTPUT: 22:11:48 INFO - 2015-11-24 22:11:48,579 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51007/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:11:49 INFO - 2015-11-24 22:11:49,153 DEBUG : BROWSER_OUTPUT: RSS: Main: 169394176 22:11:49 INFO - 2015-11-24 22:11:49,153 DEBUG : BROWSER_OUTPUT: 22:11:51 INFO - 2015-11-24 22:11:51,555 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:11:52 INFO - 2015-11-24 22:11:52,141 DEBUG : BROWSER_OUTPUT: RSS: Main: 177491968 22:11:52 INFO - 2015-11-24 22:11:52,141 DEBUG : BROWSER_OUTPUT: 22:11:54 INFO - 2015-11-24 22:11:54,660 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:11:55 INFO - 2015-11-24 22:11:55,241 DEBUG : BROWSER_OUTPUT: RSS: Main: 175644672 22:11:55 INFO - 2015-11-24 22:11:55,242 DEBUG : BROWSER_OUTPUT: 22:11:56 INFO - 2015-11-24 22:11:56,911 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51007/tests/kraken/driver.html?testName=ai-astar) 22:11:57 INFO - 2015-11-24 22:11:57,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 234868736 22:11:57 INFO - 2015-11-24 22:11:57,155 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,159 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:11:57 INFO - 2015-11-24 22:11:57,159 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:11:57 INFO - 2015-11-24 22:11:57,159 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:11:57 INFO - 2015-11-24 22:11:57,159 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:11:57 INFO - 2015-11-24 22:11:57,159 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;109;102;109;109;109;97;109;109;98 22:11:57 INFO - 2015-11-24 22:11:57,160 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;130;157;162;131;152;147;132;162;123 22:11:57 INFO - 2015-11-24 22:11:57,160 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;160;160;158;160;160;162;161;162 22:11:57 INFO - 2015-11-24 22:11:57,160 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;90;91;72;71;72;120;93;72 22:11:57 INFO - 2015-11-24 22:11:57,160 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;138;92;92;93;138;93;94;136 22:11:57 INFO - 2015-11-24 22:11:57,160 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;110;110;110;109;111;109 22:11:57 INFO - 2015-11-24 22:11:57,160 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;145;145;145;147;145;146;145;145 22:11:57 INFO - 2015-11-24 22:11:57,161 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;96;96;95;95;95;95 22:11:57 INFO - 2015-11-24 22:11:57,161 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;60;59;58;59;56;57;58;60;55 22:11:57 INFO - 2015-11-24 22:11:57,161 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;71;70;70;68;70;70;70;70;70 22:11:57 INFO - 2015-11-24 22:11:57,161 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;84;86;84;84;84;84;85;86;84 22:11:57 INFO - 2015-11-24 22:11:57,161 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;115;123;124;125;116;125;117;120;117 22:11:57 INFO - 2015-11-24 22:11:57,161 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;139;150;140;149;148;161;142;148;142 22:11:57 INFO - 2015-11-24 22:11:57,161 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;68;69;67;66;66;66;67;66;66 22:11:57 INFO - 2015-11-24 22:11:57,162 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:11:57 INFO - 2015-11-24 22:11:57,162 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:11:57 INFO - 2015-11-24 22:11:57,162 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,930 22:11:57 INFO - 2015-11-24 22:11:57,162 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:11:57 INFO - 2015-11-24 22:11:57,162 DEBUG : BROWSER_OUTPUT: __startTimestamp1448431917157__endTimestamp 22:11:57 INFO - 2015-11-24 22:11:57,162 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: Number of tests: 14 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:105.80 Median:109.00 stddev:4.89 (4.5%) stddev-sans-first:5.17 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: Values: 107.0 109.0 102.0 109.0 109.0 109.0 97.0 109.0 109.0 98.0 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.80 Median:152.00 stddev:14.52 (9.6%) stddev-sans-first:15.17 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: Values: 152.0 130.0 157.0 162.0 131.0 152.0 147.0 132.0 162.0 123.0 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.17 (0.7%) stddev-sans-first:1.24 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 160.0 160.0 158.0 160.0 160.0 162.0 161.0 162.0 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:82.40 Median:72.00 stddev:16.16 (22.4%) stddev-sans-first:16.70 22:11:57 INFO - 2015-11-24 22:11:57,166 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 90.0 91.0 72.0 71.0 72.0 120.0 93.0 72.0 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.30 Median:93.00 stddev:21.43 (23.0%) stddev-sans-first:22.27 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 138.0 92.0 92.0 93.0 138.0 93.0 94.0 136.0 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:109.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 110.0 110.0 110.0 109.0 111.0 109.0 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 145.0 145.0 145.0 147.0 145.0 146.0 145.0 145.0 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.80 Median:58.50 stddev:1.75 (3.0%) stddev-sans-first:1.73 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: Values: 56.0 60.0 59.0 58.0 59.0 56.0 57.0 58.0 60.0 55.0 22:11:57 INFO - 2015-11-24 22:11:57,167 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:0.79 (1.1%) stddev-sans-first:0.78 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: Values: 69.0 71.0 70.0 70.0 68.0 70.0 70.0 70.0 70.0 70.0 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:84.50 stddev:0.84 (1.0%) stddev-sans-first:0.88 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: Values: 85.0 84.0 86.0 84.0 84.0 84.0 84.0 85.0 86.0 84.0 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.90 Median:121.50 stddev:3.98 (3.3%) stddev-sans-first:4.09 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: Values: 117.0 115.0 123.0 124.0 125.0 116.0 125.0 117.0 120.0 117.0 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.80 Median:148.00 stddev:6.86 (4.6%) stddev-sans-first:6.82 22:11:57 INFO - 2015-11-24 22:11:57,168 DEBUG : BROWSER_OUTPUT: Values: 139.0 139.0 150.0 140.0 149.0 148.0 161.0 142.0 148.0 142.0 22:11:57 INFO - 2015-11-24 22:11:57,169 DEBUG : BROWSER_OUTPUT: 22:11:57 INFO - 2015-11-24 22:11:57,169 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:66.50 stddev:1.06 (1.6%) stddev-sans-first:1.09 22:11:57 INFO - 2015-11-24 22:11:57,169 DEBUG : BROWSER_OUTPUT: Values: 66.0 68.0 69.0 67.0 66.0 66.0 66.0 67.0 66.0 66.0 22:11:57 INFO - 2015-11-24 22:11:57,169 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:11:57 INFO - 2015-11-24 22:11:57,169 DEBUG : BROWSER_OUTPUT: 22:11:58 INFO - 2015-11-24 22:11:58,537 INFO : Browser exited with error code: 0 22:11:58 INFO - 2015-11-24 22:11:58,586 INFO : Completed test kraken (00:01:00) 22:11:58 INFO - 2015-11-24 22:11:58,587 INFO : Starting test v8_7 22:11:58 INFO - 2015-11-24 22:11:58,587 DEBUG : operating with platform_type : linux_ 22:11:58 INFO - 2015-11-24 22:11:58,587 INFO : Initialising browser for v8_7 test... 22:11:58 INFO - 2015-11-24 22:11:58,593 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbZVqBG/profile http://localhost:51007/getInfo.html 22:12:03 INFO - 2015-11-24 22:12:03,419 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:12:03 INFO - 2015-11-24 22:12:03,419 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:12:03 INFO - 2015-11-24 22:12:03,419 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:12:03 INFO - 2015-11-24 22:12:03,419 DEBUG : BROWSER_OUTPUT: __metrics 22:12:03 INFO - 2015-11-24 22:12:03,564 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] 22:12:05 INFO - 2015-11-24 22:12:05,163 INFO : Browser initialized. 22:12:05 INFO - 2015-11-24 22:12:05,164 INFO : Running cycle 1/1 for v8_7 test... 22:12:05 INFO - 2015-11-24 22:12:05,164 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbZVqBG/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:12:16 INFO - 2015-11-24 22:12:16,914 DEBUG : BROWSER_OUTPUT: RSS: Main: 139677696 22:12:16 INFO - 2015-11-24 22:12:16,915 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,367 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51007/tests/v8_7/run.html (next: http://localhost:51007/tests/v8_7/run.html) 22:12:38 INFO - 2015-11-24 22:12:38,626 DEBUG : BROWSER_OUTPUT: RSS: Main: 631877632 22:12:38 INFO - 2015-11-24 22:12:38,626 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,626 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:12:38 INFO - 2015-11-24 22:12:38,626 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:12:38 INFO - 2015-11-24 22:12:38,626 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:12:38 INFO - 2015-11-24 22:12:38,626 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:12:38 INFO - 2015-11-24 22:12:38,626 DEBUG : BROWSER_OUTPUT: |0;Richards;164.66326362588507 22:12:38 INFO - 2015-11-24 22:12:38,627 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.09937888198758 22:12:38 INFO - 2015-11-24 22:12:38,627 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.28611898017 22:12:38 INFO - 2015-11-24 22:12:38,627 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5202.072538860104 22:12:38 INFO - 2015-11-24 22:12:38,627 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1984.126984126984 22:12:38 INFO - 2015-11-24 22:12:38,627 DEBUG : BROWSER_OUTPUT: |5;Earley;821.6926869350863 22:12:38 INFO - 2015-11-24 22:12:38,627 DEBUG : BROWSER_OUTPUT: |6;Boyer;12084.33734939759 22:12:38 INFO - 2015-11-24 22:12:38,627 DEBUG : BROWSER_OUTPUT: |7;RegExp;25150 22:12:38 INFO - 2015-11-24 22:12:38,627 DEBUG : BROWSER_OUTPUT: |8;Splay;657.3780873970868 22:12:38 INFO - 2015-11-24 22:12:38,628 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 22:12:38 INFO - 2015-11-24 22:12:38,628 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:12:38 INFO - 2015-11-24 22:12:38,628 DEBUG : BROWSER_OUTPUT: __startTimestamp1448431958618__endTimestamp 22:12:38 INFO - 2015-11-24 22:12:38,628 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,628 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:12:38 INFO - 2015-11-24 22:12:38,628 DEBUG : BROWSER_OUTPUT: Number of tests: 10 22:12:38 INFO - 2015-11-24 22:12:38,628 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,628 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.66 Median:164.66 stddev:0.00 (0.0%) 22:12:38 INFO - 2015-11-24 22:12:38,629 DEBUG : BROWSER_OUTPUT: Values: 164.7 22:12:38 INFO - 2015-11-24 22:12:38,629 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,629 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.10 Median:194.10 stddev:0.00 (0.0%) 22:12:38 INFO - 2015-11-24 22:12:38,629 DEBUG : BROWSER_OUTPUT: Values: 194.1 22:12:38 INFO - 2015-11-24 22:12:38,629 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,629 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.29 Median:283.29 stddev:0.00 (0.0%) 22:12:38 INFO - 2015-11-24 22:12:38,629 DEBUG : BROWSER_OUTPUT: Values: 283.3 22:12:38 INFO - 2015-11-24 22:12:38,629 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,630 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 22:12:38 INFO - 2015-11-24 22:12:38,630 DEBUG : BROWSER_OUTPUT: Values: 5202.1 22:12:38 INFO - 2015-11-24 22:12:38,630 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,630 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1984.13 Median:1984.13 stddev:0.00 (0.0%) 22:12:38 INFO - 2015-11-24 22:12:38,630 DEBUG : BROWSER_OUTPUT: Values: 1984.1 22:12:38 INFO - 2015-11-24 22:12:38,630 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,630 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:821.69 Median:821.69 stddev:0.00 (0.0%) 22:12:38 INFO - 2015-11-24 22:12:38,630 DEBUG : BROWSER_OUTPUT: Values: 821.7 22:12:38 INFO - 2015-11-24 22:12:38,631 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,631 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12084.34 Median:12084.34 stddev:0.00 (0.0%) 22:12:38 INFO - 2015-11-24 22:12:38,631 DEBUG : BROWSER_OUTPUT: Values: 12084.3 22:12:38 INFO - 2015-11-24 22:12:38,631 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,631 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25150.00 Median:25150.00 stddev:0.00 (0.0%) 22:12:38 INFO - 2015-11-24 22:12:38,631 DEBUG : BROWSER_OUTPUT: Values: 25150.0 22:12:38 INFO - 2015-11-24 22:12:38,631 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,631 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:657.38 Median:657.38 stddev:0.00 (0.0%) 22:12:38 INFO - 2015-11-24 22:12:38,632 DEBUG : BROWSER_OUTPUT: Values: 657.4 22:12:38 INFO - 2015-11-24 22:12:38,632 DEBUG : BROWSER_OUTPUT: 22:12:38 INFO - 2015-11-24 22:12:38,632 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 22:12:38 INFO - 2015-11-24 22:12:38,632 DEBUG : BROWSER_OUTPUT: Values: 4826.9 22:12:38 INFO - 2015-11-24 22:12:38,632 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:12:38 INFO - 2015-11-24 22:12:38,632 DEBUG : BROWSER_OUTPUT: 22:12:39 INFO - 2015-11-24 22:12:39,438 INFO : Browser exited with error code: 0 22:12:39 INFO - 2015-11-24 22:12:39,454 INFO : Completed test v8_7 (00:00:40) 22:12:39 INFO - 2015-11-24 22:12:39,598 INFO : Completed test suite (00:20:20) 22:12:39 INFO - 2015-11-24 22:12:39,599 DEBUG : Working with test: dromaeo_css 22:12:39 INFO - 2015-11-24 22:12:39,599 DEBUG : Generating results file: dromaeo_css 22:12:39 INFO - 2015-11-24 22:12:39,600 DEBUG : Working with test: kraken 22:12:39 INFO - 2015-11-24 22:12:39,600 DEBUG : Generating results file: kraken 22:12:39 INFO - 2015-11-24 22:12:39,601 INFO : javascript benchmark 22:12:39 INFO - 2015-11-24 22:12:39,601 DEBUG : Working with test: v8_7 22:12:39 INFO - 2015-11-24 22:12:39,601 DEBUG : Generating results file: v8_7 22:12:39 INFO - 2015-11-24 22:12:39,601 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:12:39 INFO - 2015-11-24 22:12:39,658 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 22:12:39 INFO - 2015-11-24 22:12:39,658 DEBUG : process_Request line: dromaeo_css 7044.08 graph.html#tests=[[72,64,35]] 22:12:39 INFO - 2015-11-24 22:12:39,659 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:12:39 INFO - 2015-11-24 22:12:39,696 DEBUG : process_Request line: kraken 1494.40 graph.html#tests=[[232,64,35]] 22:12:39 INFO - 2015-11-24 22:12:39,696 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:12:39 INFO - 2015-11-24 22:12:39,724 DEBUG : process_Request line: v8_7 19606.74 graph.html#tests=[[230,64,35]] 22:12:39 INFO - 2015-11-24 22:12:39,729 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3218.6781321867807, 3220.9355812883728, 3254.0400581828358, 3267.3628642414737, 3329.1510664780476, 5270.789168433258, 5351.518363347298, 5387.434319396466, 5454.863628409292, 5479.972600136999, 5488.91766623501, 5508.228847961289, 5568.49883510484, 5668.064769313059, 5686.260786097811, 4807.519248075199, 5026.698398096108, 5043.067032598971, 5070.949290507103, 5192.948070519303, 5190.143626301656, 5249.396319423258, 5250.4487028861995, 5271.341082364705, 5273.709945952971, 4947.084789313988, 5031.471695471996, 5034.421041580192, 5145.125328694112, 5173.068847607429, 5100.458283257956, 5101.821436249714, 5104.132297509395, 5106.361704786902, 5127.769250383742, 5031.547160755549, 5053.8231161909525, 5080.720560369178, 5084.186530155157, 5091.134507133796, 5154.845354639329, 5199.0, 5296.894062118775, 5297.205419187114, 5335.253064570963, 5976.8505787355225, 6005.609635373687, 6016.458518733335, 6019.946509360888, 6028.608140470856, 5877.706114694248, 6035.7887473938645, 6036.577439579231, 6045.425684559959, 6049.818505444844, 5559.972200139055, 5579.776808927638, 5596.992541342557, 5644.350899646511, 5658.660480371191, 5952.345242023374, 6019.187409699634, 6023.45788879003, 6024.246969128859, 6051.8487037823525, 5784.855378615484, 5789.652620842763, 5799.217105690762, 5801.419858014165, 5853.560982926298, 5786.971065144648, 5813.680247586339, 5850.502707269845, 5882.029465138205, 5948.345681974979, 5893.174955506232, 5925.4074592541265, 5944.64332140073, 5954.315253745788, 5959.851003724855, 5946.197263369476, 5959.7914073008105, 5967.5524335675, 5975.103734439869, 5976.402359764076, 4434.467863856358, 4514.052049069666, 4536.092781443725, 4612.3542704021465, 4616.746078965622, 4109.0, 4133.152703695735, 4144.896377590524, 4151.4395556600075, 4176.644985176234, 5738.454846789548, 5747.770089196385, 5758.62568933018, 5763.4812866842185, 5819.447152520504, 5842.737076831553, 5897.823065307962, 5900.350961394243, 5970.940290597038, 6002.519798416116, 5613.607047506635, 5640.943590564042, 5684.54523638108, 5689.061304884731, 5701.344345400249, 5198.142306519458, 5225.843224703265, 5229.555487783581, 5245.763940622681, 5276.65701729394, 5879.147523609138, 5983.611065280743, 5987.0, 6040.486558642477, 6117.989078219788, 6093.329733729375, 6094.969525152346, 6095.664738439428, 6100.1459795629535, 6102.206713127265, 6201.658908760061, 6252.562320637512, 6253.062040693932, 6262.06069089622, 6267.843303917256, 5686.374498805211, 5815.069588865761, 5877.471027607536, 5883.882322353444, 5925.970370148121, 5965.8806823862415, 5969.940300597113, 5977.551683623833, 5992.850178745566, 6007.939920600738, 5626.212330273846, 5811.4479124483105, 5830.154627578892, 5838.649681019152, 5848.4151584841175, 5666.235058267246, 5693.572982026281, 5709.22925405065, 5789.2763404574425, 5835.503982161564, 5745.1382292656435, 5761.884762304647, 5765.221695071112, 5765.8558536036935, 5769.336526299529, 5718.313802343745, 5833.387494313036, 5897.0, 5902.055671092605, 5902.2327097477055, 5702.7718891243885, 5753.913691294718, 5755.165501002248, 5829.271341082364, 5882.941170588239], "unit": "ms", "name": "dojo.html", "value": 5492.153858447819}, {"lowerIsBetter": false, "replicates": [541.6219609901, 582.257621532546, 586.1091141464973, 617.4165413684068, 621.4562258024229, 31619.20951976199, 40897.0, 40918.386224206624, 41062.58937410625, 41072.973175670624, 44340.33489497652, 44617.77691111548, 44803.1039379213, 44847.327290090674, 44858.551414485846, 7752.26353496417, 7850.764477065669, 8788.78028049299, 8942.41874278173, 8962.148595883413, 10595.152387808996, 10843.783124337508, 10852.728681782939, 10860.837087443695, 10870.347779133237, 10467.84298235527, 10579.73550661232, 10585.894141058607, 10593.470326483684, 10598.576056957712, 7968.44220904534, 8122.756317310431, 8134.349252059821, 10297.124744396735, 10614.840777388345, 10266.897331026668, 10326.690199294033, 10361.948190259076, 10366.17070634355, 10403.271770976035, 40973.13188076836, 41131.794341028406, 41288.38067428991, 41491.377629335584, 41501.0, 3449.931001379958, 3968.9876248695277, 4100.138970816132, 4131.173765246933, 4206.579342065769, 33779.0, 34404.82797586021, 34496.655033449846, 34544.48183277253, 34791.0, 3829.7893615851394, 3863.150106976461, 3967.761934283952, 3977.880663580097, 4007.0, 264427.0, 265964.0, 266037.0, 267264.0, 268408.6579567109, 62061.37938620646, 62231.6888415555, 62235.0, 62359.6882015587, 62431.0, 67064.32935670679, 67200.0, 67253.66373168102, 67265.26449458278, 67350.32649673539, 15717.44988925405, 15967.36130554788, 15978.201089945456, 16005.439809606607, 16011.359545618045, 12188.390580470941, 12259.448324825404, 12260.509579616715, 12272.631821045383, 12274.815877761841, 20109.39671809848, 20935.895320523603, 21141.26005589797, 21160.470988225417, 21169.894150529148, 6990.580565166107, 7968.195144365509, 8138.186181381814, 8250.0, 8268.503889766633, 917.7797328641078, 1030.6907927621683, 1077.2028698763015, 1113.7271368514766, 1126.3298337489182, 29234.707652923626, 29725.851370743007, 29837.0, 33301.16747081342, 33931.0, 1011.3780025284544, 1057.6774083904484, 1119.764849381639, 1126.693155588351, 1140.1733743036398, 16771.664566708598, 17565.560860978578, 17593.47219583389, 17714.291428342724, 17733.46799596014, 386491.0, 389518.0, 390491.0, 392506.0, 393317.0, 55295.44704552903, 55835.162472562944, 55954.0, 56013.0, 56053.0, 1352.2765320553456, 1371.2526672963259, 1390.2075816784336, 1391.9443222271198, 1393.6655202751267, 29709.554356684235, 30375.696243037288, 30592.84703576468, 30744.538831917987, 30916.22709432282, 47247.76376118098, 47554.76222618865, 47818.760906196636, 47913.281300781215, 47920.520794791606, 264022.0, 285216.0, 285823.0, 286310.0, 288090.0, 26861.328466788487, 33386.0, 33864.83067584646, 34149.60952733285, 34188.0, 42341.576584233764, 42880.142397151256, 43166.78416608022, 43548.34677479777, 44029.0, 35181.0, 35358.46962295516, 35789.46315805213, 36360.63639363572, 37017.0, 30860.87149954257, 36342.63657363393, 36713.08217294589, 39478.802605986784, 40507.1898562033, 36646.26707465889, 37328.0, 37530.437043444916, 37531.43702844405, 37593.43609845909, 33069.83465082659, 33993.88415877227, 34316.65683343135, 34732.305353892276, 34829.0, 25763.39757160529, 26072.608910866733, 26103.347416313984, 26422.471550568498, 26554.336141596614], "unit": "ms", "name": "ext.html", "value": 19639.590727026458}, {"lowerIsBetter": false, "replicates": [350.0827831082564, 354.12707675579696, 354.2136457065314, 354.2383874669461, 354.70736642270134, 13758.656033599165, 14101.858981410209, 16806.327746890125, 16932.238049287782, 17140.65718685627, 15763.60590985226, 16130.596735081628, 16985.91507042466, 17161.656766864657, 17290.13549322532, 5273.156294992802, 5432.864178395532, 5435.157550579666, 5442.91835622466, 5443.727813609324, 3892.5912779158207, 3981.0, 5345.599080069011, 5669.546436285108, 5867.031939729939, 5913.349531551526, 5926.199963005004, 5938.46553810157, 5948.196993405878, 5951.613145145551, 5803.73883175256, 5941.851453713692, 5946.821595352125, 5948.613340132899, 5952.077427998667, 5177.896442071175, 5189.792408303663, 5207.297014902978, 5209.557187639055, 5239.685618862843, 12081.637550873487, 12480.750384992249, 12481.438335274934, 12488.75022499554, 19419.3203237886, 2041.428400047974, 2139.358192542231, 2184.6613774864923, 2231.5983123037845, 2311.722593288799, 8530.573471326408, 9433.669821556281, 11856.288622682581, 12538.435770390355, 13046.217226966412, 1774.8313910178513, 1805.9367922122794, 1828.6159906419668, 1835.614520950602, 1850.7223916412645, 57640.423595763925, 60533.09200362042, 61522.0, 61668.0, 62243.37756622421, 9558.091981261769, 9698.127168554864, 9771.755706107262, 9810.950945245228, 9813.0, 12522.499100035897, 12530.060245481625, 12576.559820406243, 12620.17968832023, 12650.177738446971, 7446.441516886255, 7510.511816731895, 7618.5809780462605, 7672.347850432664, 7682.615869206629, 7417.703291868373, 7476.476646634792, 7485.513441626277, 7584.962075189589, 7647.464677472523, 4089.2026054919006, 4149.33610622299, 4156.189543039139, 4185.623293903503, 4186.790660467025, 1680.6554656295675, 1719.6216832296875, 1749.1866282178848, 1794.5872449336464, 1803.053396966603, 910.2854259406334, 926.0554234680588, 941.30343545777, 986.7335819328885, 1006.8942761010135, 15813.920930395274, 16432.260548275357, 17436.738448923275, 17495.125243737763, 17665.49216582947, 891.362675686874, 893.4862454088925, 907.5008745190119, 942.599395257013, 1030.8196065688473, 9124.012847345359, 9416.576254068583, 9468.715938521855, 9484.099010593982, 9600.087991640783, 507733.0, 512650.0, 514682.0, 517303.0, 518382.0, 75117.0, 78238.6088069556, 82213.0, 82218.0, 82487.5875620618, 1123.4102096399292, 1126.3973774031017, 1128.4978184707727, 1155.058627218814, 1160.7794519041524, 14948.0, 15013.699726005638, 15058.849411505746, 15807.604809879846, 15812.60468488297, 34353.828230858686, 35427.82286088553, 35537.79938628298, 35875.64124358723, 36039.0, 46768.0, 47293.527064728914, 47817.76091119522, 48017.5198248027, 50289.49710502848, 8978.102189781232, 9151.954240228757, 9161.175494205554, 9209.079092090737, 9223.123803238681, 10798.73003174927, 13548.119372240773, 14896.32966516531, 15544.689106218038, 16619.085950272853, 11894.167408281337, 13195.60413187606, 14168.645783855074, 14216.786748198992, 14309.499167529502, 9760.804783904425, 9828.950855245677, 10094.79810403773, 10138.949305253427, 10307.484625768531, 8943.731688049369, 10110.191184705352, 10425.322354046963, 10457.895421045996, 10485.318454300446, 13078.411471483996, 14923.104613723212, 15111.395544178109, 15563.45527906563, 15600.297986590404, 15667.373305068126, 15752.68494630078, 15915.204239787732, 16000.359985600446, 16292.67414651724], "unit": "ms", "name": "jquery.html", "value": 8686.852557409296}, {"lowerIsBetter": false, "replicates": [1329.7407005633904, 1691.5432833135053, 1734.3322820714025, 1756.736489526571, 1780.5904641932354, 6801.183857937049, 7238.457115716323, 7500.0, 7687.269709377614, 7687.423443241752, 7592.772216833504, 7685.116211635664, 7716.768496945087, 7734.149243583215, 7738.032745906762, 1942.9611407771836, 1989.333573252962, 2282.554901794155, 2321.2339927823778, 2322.744498105207, 2255.9436014099615, 2309.722833260003, 2310.5263420998654, 2312.5143719818775, 2313.4794671199015, 2205.0959106766227, 2214.070090561968, 2288.645259984705, 2291.3927809130596, 2300.5973954557885, 1887.59416725404, 1895.573495963411, 2154.687570302305, 2229.6544035674497, 2267.501149747053, 2249.4488850231796, 2260.993857733309, 2265.88670566471, 2266.7733226677365, 2267.2971378872603, 7721.305082542598, 7778.261065198797, 7780.844383112362, 7787.688492460352, 7805.531668099876, 4627.699199552018, 4697.201475749131, 4700.976495117537, 4718.103560323528, 4739.549742774431, 3145.685431456859, 3161.4309424303615, 3172.349668317989, 3215.1479857837694, 3220.6618305077864, 970.315927271272, 987.3680844259679, 992.0823238504427, 1012.8784545854545, 1025.912797412221, 10663.933606639273, 10725.03474687282, 10727.624533141303, 10755.569777208902, 10798.352098874093, 4179.373094035858, 4217.135487225111, 4297.05464797744, 4325.956740432555, 4329.15581461612, 9105.499197544199, 9118.498482583389, 9132.406393584395, 9173.44959302444, 9186.081391860895, 4658.34783130362, 4705.035467729107, 4708.0, 4710.317004034396, 4710.764461776897, 4187.225363307798, 4237.427947227146, 4242.978785106055, 4247.341662042389, 4248.702590818613, 3655.8354874030733, 3678.70570354371, 3700.2414505026404, 3735.831887565066, 3740.6259374062374, 1159.1074872348245, 1162.2798258577352, 1162.6222925832915, 1170.0697945133638, 1174.8179032249843, 1043.1811028342865, 1068.7755571329951, 1068.9251752377415, 1212.5998420521207, 1348.3527906604688, 6946.687399067054, 7091.078159839188, 7359.374453171434, 7630.198829122974, 7654.923450765533, 609.2871340531589, 615.8952977993752, 616.4144063140035, 623.8159742875863, 627.0719335383658, 5763.682997435181, 5784.768609255667, 5804.680742559115, 5820.82537523875, 5830.650160990354, 9336.15974562279, 9473.715788526355, 9488.478133702713, 9496.762580935532, 9511.762205944768, 9207.95396023029, 9368.39105458143, 9375.015623359586, 9496.050394960586, 9516.286278529138, 7289.125304963332, 7310.451716121206, 7348.63256837156, 7351.926480735338, 7365.852682946311, 6930.5148639594745, 7001.684924178322, 7015.193252775996, 7038.2609825969585, 7047.119110111236, 8140.430169888051, 8361.832763344577, 8425.87361189595, 8431.19903609156, 8508.74473765788, 8420.326373889951, 8438.367122465717, 8438.957805210935, 8635.309175265838, 8642.697505587274, 6761.729530818712, 6811.4550835934, 6813.0, 6822.454203663794, 6829.385355318045, 7536.057992750906, 7648.273414025659, 7693.653785579774, 7695.807604809925, 7708.267714567107, 7146.035285236427, 7203.603801790951, 7214.74748383804, 7245.347918687344, 7348.154962179417, 6978.860422791414, 7059.152901651834, 7059.964700176466, 7155.391791697556, 7192.568445893263, 6143.047827586731, 6220.409061139185, 7159.140903091662, 7178.389836863925, 7193.856122877409, 6369.426751592379, 6531.150950376421, 6560.704768285534, 6599.5050371223315, 6602.570832895846, 6573.638449885111, 6615.933840661532, 6657.101291325609, 6670.799876003728, 6671.5997040177745], "unit": "ms", "name": "mootools.html", "value": 4413.063108092588}, {"lowerIsBetter": false, "replicates": [317.33993293948583, 326.295202362896, 335.7666421836823, 343.16268305334967, 344.1705489769654, 5875.265591801025, 5903.4686878180955, 6013.338532761392, 6028.065649824273, 6034.487068599175, 5892.0, 5892.823215303548, 5898.0, 5901.4688678018965, 5917.526597872171, 4616.792244349012, 4618.930716039254, 4619.03000369921, 4629.490756016836, 4636.837710680127, 4411.382406463098, 4539.659525535581, 4562.84030058948, 4629.791659375336, 4639.907201855961, 4502.707324023928, 4509.25597276449, 4522.457305123374, 4528.071745292224, 4543.363929049936, 4327.156204540116, 4520.9095818083615, 4521.706089104214, 4524.959259370363, 4528.796204170803, 3851.364524853396, 3904.2191561687778, 3949.8420063197436, 3957.3866050762176, 3964.6431821136234, 5621.381648018714, 5635.971820140873, 5638.097904335286, 5643.153526970947, 5651.434856514356, 5004.074246264417, 5124.769385377667, 5167.896642067175, 5262.23697563855, 5264.710440925747, 6230.87538249237, 6261.27995280544, 6267.843303917393, 6271.6864156792435, 6272.027835685476, 4348.456442944632, 4354.629856462205, 4354.782260886943, 4355.695101342907, 4362.323839804835, 6325.557210995232, 6472.5145614078665, 6547.116139321226, 6549.93450065498, 6557.868842623168, 4448.799804008828, 4460.531644177315, 4469.262571675637, 4476.574725401082, 4476.686631935733, 4509.819607215741, 4522.29904364824, 4527.660425468103, 4531.77341132942, 4532.0256144929, 5249.763760630781, 5255.3168088148295, 5257.553107985787, 5263.921041184385, 5264.078786212397, 6197.938020619827, 6356.904646430307, 6360.109584658151, 6360.268569114519, 6364.745410183634, 1579.447193482295, 1584.1445619365643, 1584.302906721062, 1585.6194513316952, 1585.8572728454494, 1031.231732359397, 1036.1555332404066, 1050.406520316019, 1051.5110473629645, 1059.1685526861377, 6091.786787462417, 6114.602550834182, 6145.985912324416, 6151.108089327112, 6156.8768624627255, 6494.318096599884, 6500.544961852719, 6559.212894452697, 6560.737570497126, 6565.606063636197, 6008.128821320884, 6102.786402475981, 6105.419985101408, 6111.786087486917, 6141.662208578571, 3479.3911065563425, 3519.560054993126, 3533.063738109403, 3533.9293214135573, 3546.4325707886615, 6710.664466776641, 6716.496262780311, 6719.193696756421, 6736.2253340865445, 6736.46108311334, 6451.903221451682, 6663.267040625527, 6669.299723529057, 6674.16572928384, 6675.532712710064, 6283.868903597305, 6639.933600664028, 6786.626735529593, 6819.283975182534, 6823.658817059128, 6584.0, 6597.472202223907, 6599.703013364506, 6603.80188594343, 6608.273089960005, 6526.053722210348, 6533.738650454119, 6534.085228068169, 6537.411632953057, 6537.8692426152165, 6647.833804154935, 6785.117934668461, 6794.422474089758, 6797.456203503807, 6799.048133261446, 4454.7772611368655, 4457.910841783211, 4479.126570318756, 4489.820407183676, 4501.459824821042, 6583.769568065095, 6592.274849766618, 6592.868142637216, 6595.736170553124, 6609.4712423007, 6257.217847769029, 6395.42441180296, 6408.58344207625, 6413.198350206224, 6419.839504012437, 6381.266154392119, 6401.8719625608155, 6402.263739669997, 6403.327650596807, 6408.871822563616, 6231.189945307081, 6240.28236752761, 6240.375962403723, 6262.499000079892, 6264.715733274759, 6274.215723034621, 6403.295637479967, 6438.484921206386, 6447.290798012121, 6450.870982580416, 6295.905561416674, 6297.275813281348, 6298.968505157445, 6300.086487459384, 6302.338254483397], "unit": "ms", "name": "prototype.html", "value": 4764.801190906763}, {"lowerIsBetter": false, "replicates": [669.8425869920571, 682.4199430484086, 735.8675438421084, 807.1968391450507, 856.6372032381681, 13415.932920335397, 13652.976026797993, 13667.863321366784, 13674.999999999975, 13680.0, 13709.657258568515, 13760.93119534401, 13787.655308617264, 13820.585382438518, 13822.170669759797, 666.7899611622348, 677.8136012596551, 815.5881279953616, 825.8389614025267, 826.1036775099025, 826.9669213231464, 830.5182993863575, 832.1013305629905, 833.4166083741375, 834.6828205281985, 714.7677004973378, 722.1118024829462, 871.877937088808, 878.1701292278799, 879.5953861223812, 851.5529347092764, 867.3061550759398, 868.1665601023026, 868.192580899763, 869.5782545465444, 631.823089534931, 634.9587276827037, 638.2628064585411, 639.0127253393518, 639.1307821362942, 13749.725005499835, 13859.0, 13889.79165312521, 13946.442142314396, 14003.719925601532, 11923.284602923852, 12372.257664540157, 12554.68613284666, 12565.748685026248, 12668.0, 10116.797664046751, 10330.7417314567, 10342.896571034344, 10620.256582039261, 10727.409992450412, 3572.160542272564, 3639.3085313790293, 3653.872114476007, 3680.1351682354625, 3687.1519550503535, 47363.0, 47583.5241647586, 47678.5232147681, 48081.51918480805, 48165.75917120392, 8966.193042626195, 9255.953720231355, 9308.767280818034, 9352.0, 9396.436213827192, 16524.504264872074, 16634.75047874283, 16686.082265475514, 16686.58283542921, 16688.499345019427, 7390.889136662954, 7564.848703025908, 7567.545947243183, 7773.106092799288, 7858.685652573833, 4353.651707863363, 4395.252807022814, 4442.666799990014, 4450.332450132441, 4468.642508599304, 3121.360121175154, 3189.537517060013, 3191.984040079785, 3195.0255172172247, 3196.2488815128427, 541.3801197628693, 560.7168379968172, 572.3932631410718, 595.3718826637938, 621.2079598511843, 632.5540493951753, 637.8564822914807, 682.7917485166972, 684.4729558240127, 692.0001196541998, 7717.1896950819355, 7798.25916537928, 8016.118226995026, 8031.277185053374, 8184.754457366289, 592.4135106244787, 602.4848754315085, 620.3634553149117, 697.3514631392853, 706.3713295167305, 3646.3072016317346, 3713.814309284525, 3862.4592557042038, 3893.53277606689, 4081.69387295954, 57657.0, 57970.0, 58384.70807645935, 58458.0, 58506.12240816306, 51457.48542514527, 51924.22113668222, 51925.22112168321, 51998.48001519936, 52086.0, 46672.299915501295, 47239.76380118146, 47478.5252147481, 47690.76154619205, 48212.27681584779, 7754.418418618739, 7889.816527520688, 7989.52062876206, 8051.0, 8066.637001334837, 32762.83618581892, 34239.82880085584, 34878.82560587181, 35320.29359412849, 35831.46252806158, 40552.797236013634, 45667.314990274506, 46324.7683761579, 46936.0, 47080.7645961768, 10071.848922266025, 10222.386656800616, 10456.790864182522, 10693.679189624323, 10700.625478108228, 11221.719457013314, 11282.0, 11315.490802914053, 11330.093592512414, 11666.591669291534, 10531.736706582396, 10666.8399974002, 11123.221374503708, 11131.72170695739, 11253.043491303331, 10398.740031499274, 10590.258681892501, 10592.0, 10771.34768061145, 10886.78226435451, 10146.441945693057, 10262.538185781508, 10311.175105991653, 10313.385795555801, 10385.03213178274, 9239.307051970995, 9313.720588382359, 9381.765455863659, 9601.682973782732, 9609.0, 8253.298469630148, 8542.91457085446, 9166.908330916605, 9188.264938805014, 9208.723738287863], "unit": "ms", "name": "yui.html", "value": 6199.856790844444}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 109.0, 102.0, 109.0, 109.0, 109.0, 97.0, 109.0, 109.0, 98.0], "unit": "ms", "name": "ai-astar", "value": 105.8}, {"lowerIsBetter": true, "replicates": [152.0, 130.0, 157.0, 162.0, 131.0, 152.0, 147.0, 132.0, 162.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.8}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 160.0, 160.0, 158.0, 160.0, 160.0, 162.0, 161.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 90.0, 91.0, 72.0, 71.0, 72.0, 120.0, 93.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 82.4}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 138.0, 92.0, 92.0, 93.0, 138.0, 93.0, 94.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 106.3}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0, 109.0, 111.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 145.0, 145.0, 145.0, 147.0, 145.0, 146.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [56.0, 60.0, 59.0, 58.0, 59.0, 56.0, 57.0, 58.0, 60.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 57.8}, {"lowerIsBetter": true, "replicates": [69.0, 71.0, 70.0, 70.0, 68.0, 70.0, 70.0, 70.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.8}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 86.0, 84.0, 84.0, 84.0, 84.0, 85.0, 86.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.6}, {"lowerIsBetter": true, "replicates": [117.0, 115.0, 123.0, 124.0, 125.0, 116.0, 125.0, 117.0, 120.0, 117.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.9}, {"lowerIsBetter": true, "replicates": [139.0, 139.0, 150.0, 140.0, 149.0, 148.0, 161.0, 142.0, 148.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 145.8}, {"lowerIsBetter": true, "replicates": [66.0, 68.0, 69.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.7}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.66326362588507], "unit": "score", "name": "Richards", "value": 214.38904599999995}, {"lowerIsBetter": false, "replicates": [194.09937888198758], "unit": "score", "name": "DeltaBlue", "value": 340.63993600000003}, {"lowerIsBetter": false, "replicates": [283.28611898017], "unit": "score", "name": "Encrypt", "value": 939.6189299999998}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1984.126984126984], "unit": "score", "name": "RayTrace", "value": 372.9544560000001}, {"lowerIsBetter": false, "replicates": [821.6926869350863], "unit": "score", "name": "Earley", "value": 811.0854709999999}, {"lowerIsBetter": false, "replicates": [12084.33734939759], "unit": "score", "name": "Boyer", "value": 55.150976071784655}, {"lowerIsBetter": false, "replicates": [25150.0], "unit": "score", "name": "RegExp", "value": 36.22206759443341}, {"lowerIsBetter": false, "replicates": [657.3780873970868], "unit": "score", "name": "Splay", "value": 123.96366955684005}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7"}]} 22:12:39 INFO - RETURN: dromaeo_css: 7044.08 22:12:39 INFO - RETURN: kraken: 1494.40 22:12:39 INFO - RETURN: v8_7: 19606.74 22:12:39 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "19606.74"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1494.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "7044.08"}}} 22:12:39 INFO - Return code: 0 22:12:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:12:39 INFO - # TBPL SUCCESS # 22:12:39 INFO - Running post-action listener: _resource_record_post_action 22:12:39 INFO - Running post-run listener: _resource_record_post_run 22:12:40 INFO - Total resource usage - Wall time: 1235s; CPU: 13.0%; Read bytes: 4096; Write bytes: 518144000; Read time: 860; Write time: 1441760 22:12:40 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 0; Write bytes: 149860352; Read time: 0; Write time: 294200 22:12:40 INFO - run-tests - Wall time: 1221s; CPU: 13.0%; Read bytes: 4096; Write bytes: 335310848; Read time: 860; Write time: 1007236 22:12:40 INFO - Running post-run listener: _upload_blobber_files 22:12:40 INFO - Blob upload gear active. 22:12:40 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 22:12:40 INFO - Copying logs to upload dir... 22:12:40 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1274.170292 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2015-11-24 22:12:39.970628) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 22:12:39.975492) ========= 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=ccb4611811c763f2fad71465000001de-1448429890.172968-462874076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010042 build_url: 'https://queue.taskcluster.net/v1/task/ceMDea84QOaSPwXR9Oim3w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 22:12:40.027543) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 22:12:40.027908) ========= 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=ccb4611811c763f2fad71465000001de-1448429890.172968-462874076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004538 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 22:12:40.078135) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 22:12:40.078485) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 22:12:40.078812) ========= ========= Total master_lag: 0.26 =========