builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1448314829.92 results: success (0) buildid: 20151123113010 builduid: bad73f6f97da4a3ebb1e5cda27231615 revision: a5cf6e488f2d4266e5437a18dc1e3930570558c4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.922747) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.923206) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.923499) ========= 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=5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006294 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.940722) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.941017) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.949058) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.949342) ========= 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=5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004030 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.996890) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.997204) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.997556) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:29.997822) ========= 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=5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-23 13:40:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.5M=0s 2015-11-23 13:40:31 (43.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105052 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:30.115028) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:30.115347) ========= 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=5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022323 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:30.164932) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 13:40:30.165282) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a5cf6e488f2d4266e5437a18dc1e3930570558c4 --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 a5cf6e488f2d4266e5437a18dc1e3930570558c4 --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=5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-23 13:40:31,486 truncating revision to first 12 chars 2015-11-23 13:40:31,486 Setting DEBUG logging. 2015-11-23 13:40:31,486 attempt 1/10 2015-11-23 13:40:31,486 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a5cf6e488f2d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 13:40:32,767 unpacking tar archive at: fx-team-a5cf6e488f2d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.549226 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 13:40:31.727099) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:31.727414) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:31.734828) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:31.735211) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 13:40:31.735636) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2015-11-23 13:40:31.735933) ========= /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=5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:40:33 INFO - MultiFileLogger online at 20151123 13:40:33 in /builds/slave/test-pgo 13:40:33 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 13:40:33 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 13:40:33 INFO - {'append_to_log': False, 13:40:33 INFO - 'base_work_dir': '/builds/slave/test-pgo', 13:40:33 INFO - 'blob_upload_branch': 'Fx-Team', 13:40:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 13:40:33 INFO - 'branch': 'Fx-Team', 13:40:33 INFO - 'buildbot_json_path': 'buildprops.json', 13:40:33 INFO - 'config_files': ('talos/linux_config.py',), 13:40:33 INFO - 'default_actions': ('clobber', 13:40:33 INFO - 'read-buildbot-config', 13:40:33 INFO - 'download-and-extract', 13:40:33 INFO - 'populate-webroot', 13:40:33 INFO - 'create-virtualenv', 13:40:33 INFO - 'install', 13:40:33 INFO - 'run-tests'), 13:40:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:40:33 INFO - 'download_minidump_stackwalk': True, 13:40:33 INFO - 'download_symbols': 'ondemand', 13:40:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:40:33 INFO - 'tooltool.py': '/tools/tooltool.py', 13:40:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:40:33 INFO - '/tools/misc-python/virtualenv.py')}, 13:40:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:40:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:40:33 INFO - 'installer_path': 'installer.exe', 13:40:33 INFO - 'log_level': 'info', 13:40:33 INFO - 'log_name': 'talos', 13:40:33 INFO - 'log_to_console': True, 13:40:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:40:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:40:33 INFO - 'opt_config_files': (), 13:40:33 INFO - 'pip_index': False, 13:40:33 INFO - 'sps_profile': False, 13:40:33 INFO - 'sps_profile_interval': 0, 13:40:33 INFO - 'suite': 'dromaeojs', 13:40:33 INFO - 'system_bits': '64', 13:40:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:40:33 INFO - 'title': 'talos-linux64-ix-061', 13:40:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:40:33 INFO - 'use_talos_json': True, 13:40:33 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 13:40:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:40:33 INFO - 'work_dir': 'build'} 13:40:33 INFO - ##### 13:40:33 INFO - ##### Running clobber step. 13:40:33 INFO - ##### 13:40:33 INFO - Running pre-action listener: _resource_record_pre_action 13:40:33 INFO - Running main action method: clobber 13:40:33 INFO - rmtree: /builds/slave/test-pgo/build 13:40:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 13:40:33 INFO - Running post-action listener: _resource_record_post_action 13:40:33 INFO - ##### 13:40:33 INFO - ##### Running read-buildbot-config step. 13:40:33 INFO - ##### 13:40:33 INFO - Running pre-action listener: _resource_record_pre_action 13:40:33 INFO - Running main action method: read_buildbot_config 13:40:33 INFO - Using buildbot properties: 13:40:33 INFO - { 13:40:33 INFO - "properties": { 13:40:33 INFO - "buildnumber": 509, 13:40:33 INFO - "product": "firefox", 13:40:33 INFO - "script_repo_revision": "production", 13:40:33 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 13:40:33 INFO - "repository": "", 13:40:33 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 13:40:33 INFO - "buildid": "20151123113010", 13:40:33 INFO - "slavename": "talos-linux64-ix-061", 13:40:33 INFO - "pgo_build": "True", 13:40:33 INFO - "basedir": "/builds/slave/test-pgo", 13:40:33 INFO - "project": "", 13:40:33 INFO - "platform": "ubuntu64_hw", 13:40:33 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:40:33 INFO - "slavebuilddir": "test", 13:40:33 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 13:40:33 INFO - "branch": "fx-team", 13:40:33 INFO - "repo_path": "integration/fx-team", 13:40:33 INFO - "stage_platform": "linux64-pgo", 13:40:33 INFO - "builduid": "bad73f6f97da4a3ebb1e5cda27231615", 13:40:33 INFO - "revision": "a5cf6e488f2d4266e5437a18dc1e3930570558c4" 13:40:33 INFO - }, 13:40:33 INFO - "sourcestamp": { 13:40:33 INFO - "repository": "", 13:40:33 INFO - "hasPatch": false, 13:40:33 INFO - "project": "", 13:40:33 INFO - "branch": "fx-team-linux64-pgo-talos", 13:40:33 INFO - "changes": [ 13:40:33 INFO - { 13:40:33 INFO - "category": null, 13:40:33 INFO - "files": [ 13:40:33 INFO - { 13:40:33 INFO - "url": null, 13:40:33 INFO - "name": "https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 13:40:33 INFO - } 13:40:33 INFO - ], 13:40:33 INFO - "repository": "", 13:40:33 INFO - "rev": "a5cf6e488f2d4266e5437a18dc1e3930570558c4", 13:40:33 INFO - "who": "sendchange", 13:40:33 INFO - "when": 1448311123, 13:40:33 INFO - "number": 6696657, 13:40:33 INFO - "comments": "", 13:40:33 INFO - "project": "", 13:40:33 INFO - "at": "Mon 23 Nov 2015 12:38:43", 13:40:33 INFO - "branch": "fx-team-linux64-pgo-talos", 13:40:33 INFO - "revlink": "", 13:40:33 INFO - "properties": [ 13:40:33 INFO - [ 13:40:33 INFO - "buildid", 13:40:33 INFO - "20151123113010", 13:40:33 INFO - "Change" 13:40:33 INFO - ], 13:40:33 INFO - [ 13:40:33 INFO - "builduid", 13:40:33 INFO - "bad73f6f97da4a3ebb1e5cda27231615", 13:40:33 INFO - "Change" 13:40:33 INFO - ], 13:40:33 INFO - [ 13:40:33 INFO - "pgo_build", 13:40:33 INFO - "True", 13:40:33 INFO - "Change" 13:40:33 INFO - ] 13:40:33 INFO - ], 13:40:33 INFO - "revision": "a5cf6e488f2d4266e5437a18dc1e3930570558c4" 13:40:33 INFO - } 13:40:33 INFO - ], 13:40:33 INFO - "revision": "a5cf6e488f2d4266e5437a18dc1e3930570558c4" 13:40:33 INFO - } 13:40:33 INFO - } 13:40:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 13:40:33 INFO - Running post-action listener: _resource_record_post_action 13:40:33 INFO - ##### 13:40:33 INFO - ##### Running download-and-extract step. 13:40:33 INFO - ##### 13:40:33 INFO - Running pre-action listener: _resource_record_pre_action 13:40:33 INFO - Running main action method: download_and_extract 13:40:33 INFO - mkdir: /builds/slave/test-pgo/build/tests 13:40: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')]} 13:40:33 INFO - https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 13:40:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/test_packages.json 13:40:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/test_packages.json 13:40:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 13:40:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 13:40:34 INFO - Downloaded 1302 bytes. 13:40:34 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 13:40:34 INFO - Using the following test package requirements: 13:40:34 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 13:40:34 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:40:34 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:34 INFO - u'jsshell-linux-x86_64.zip'], 13:40:34 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:40:34 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 13:40:34 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:40:34 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:40:34 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:40:34 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 13:40:34 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 13:40:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:40:34 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:40:34 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')]} 13:40:34 INFO - https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:40:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 13:40:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 13:40:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/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 13:40:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/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 13:40:35 INFO - Downloaded 22526557 bytes. 13:40:35 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 13:40:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 13:40:35 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 13:40:36 INFO - Return code: 0 13:40:36 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 13:40:36 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')]} 13:40:36 INFO - https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:40:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 13:40:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 13:40:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/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 13:40:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/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 13:40:37 INFO - Downloaded 22526557 bytes. 13:40:37 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 13:40:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 13:40:37 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 13:40:38 INFO - Return code: 0 13:40:38 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')]} 13:40:38 INFO - https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:40:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 13:40:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 13:40:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/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 13:40:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/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 13:40:39 INFO - Downloaded 11130997 bytes. 13:40:39 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 13:40:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 13:40:39 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 13:40:39 INFO - Return code: 0 13:40:39 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')]} 13:40:39 INFO - https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:40:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 13:40:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 13:40:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/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 13:40:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcdffnyZREi70AfhbR-HKg/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 13:40:41 INFO - Downloaded 57601548 bytes. 13:40:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 13:40:41 INFO - mkdir: /builds/slave/test-pgo/properties 13:40:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 13:40:41 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 13:40:41 INFO - Contents: 13:40:41 INFO - build_url:https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 13:40:41 INFO - Running post-action listener: _resource_record_post_action 13:40:41 INFO - Running post-action listener: set_extra_try_arguments 13:40:41 INFO - ##### 13:40:41 INFO - ##### Running populate-webroot step. 13:40:41 INFO - ##### 13:40:41 INFO - Running pre-action listener: _resource_record_pre_action 13:40:41 INFO - Running main action method: populate_webroot 13:40:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:40:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:40:41 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 13:40:41 INFO - '../fennec_ids.txt'], 13:40:41 INFO - 'tests': ['tcheck2']}, 13:40:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:40:41 INFO - '--tppagecycles', 13:40:41 INFO - '7'], 13:40:41 INFO - 'tests': ['tsvgm']}}, 13:40:41 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 13:40:41 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 13:40:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:40:41 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 13:40:41 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 13:40:41 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 13:40:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:40:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:41 INFO - 'talos_options': ['--e10s'], 13:40:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:40:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:41 INFO - 'tests': ['damp', 'tps']}, 13:40:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:41 INFO - 'talos_options': ['--e10s'], 13:40:41 INFO - 'tests': ['damp', 'tps']}, 13:40:41 INFO - 'g3': {'tests': ['dromaeo_dom']}, 13:40:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:40:41 INFO - 'other': {'tests': ['a11yr', 13:40:41 INFO - 'ts_paint', 13:40:41 INFO - 'tpaint', 13:40:41 INFO - 'sessionrestore', 13:40:41 INFO - 'sessionrestore_no_auto_restore']}, 13:40:41 INFO - 'other-e10s': {'talos_options': ['--e10s'], 13:40:41 INFO - 'tests': ['a11yr', 13:40:41 INFO - 'ts_paint', 13:40:41 INFO - 'tpaint', 13:40:41 INFO - 'sessionrestore', 13:40:41 INFO - 'sessionrestore_no_auto_restore']}, 13:40:41 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 13:40:41 INFO - 'tests': ['a11yr', 13:40:41 INFO - 'ts_paint', 13:40:41 INFO - 'tpaint', 13:40:41 INFO - 'sessionrestore', 13:40:41 INFO - 'sessionrestore_no_auto_restore']}, 13:40:41 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 13:40:41 INFO - 'tests': ['a11yr', 13:40:41 INFO - 'ts_paint', 13:40:41 INFO - 'tpaint', 13:40:41 INFO - 'sessionrestore', 13:40:41 INFO - 'sessionrestore_no_auto_restore']}, 13:40:41 INFO - 'other_l64': {'tests': ['a11yr', 13:40:41 INFO - 'ts_paint', 13:40:41 INFO - 'tpaint', 13:40:41 INFO - 'sessionrestore', 13:40:41 INFO - 'sessionrestore_no_auto_restore']}, 13:40:41 INFO - 'other_nol64': {'tests': ['a11yr', 13:40:41 INFO - 'ts_paint', 13:40:41 INFO - 'tpaint', 13:40:41 INFO - 'sessionrestore', 13:40:41 INFO - 'sessionrestore_no_auto_restore']}, 13:40:41 INFO - 'svgr': {'tests': ['tsvgx', 13:40:41 INFO - 'tsvgr_opacity', 13:40:41 INFO - 'tart', 13:40:41 INFO - 'tscrollx', 13:40:41 INFO - 'cart']}, 13:40:41 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 13:40:41 INFO - 'tests': ['tsvgx', 13:40:41 INFO - 'tsvgr_opacity', 13:40:41 INFO - 'tart', 13:40:41 INFO - 'tscrollx', 13:40:41 INFO - 'cart']}, 13:40:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:41 INFO - 'tests': ['tp5o']}, 13:40:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:41 INFO - 'talos_options': ['--e10s'], 13:40:41 INFO - 'tests': ['tp5o']}, 13:40:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:41 INFO - 'talos_options': ['--xperf_path', 13:40:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:40:41 INFO - 'tests': ['tp5n']}, 13:40:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:41 INFO - 'talos_options': ['--e10s', 13:40:41 INFO - '--xperf_path', 13:40:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:40:41 INFO - 'tests': ['tp5n']}}, 13:40:41 INFO - 'talos.zip': {'path': '', 13:40:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:40:41 INFO - Running post-action listener: _resource_record_post_action 13:40:41 INFO - ##### 13:40:41 INFO - ##### Running create-virtualenv step. 13:40:41 INFO - ##### 13:40:41 INFO - Running pre-action listener: _resource_record_pre_action 13:40:41 INFO - Running main action method: create_virtualenv 13:40:41 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 13:40:41 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 13:40:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 13:40:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:40:41 INFO - Using real prefix '/usr' 13:40:41 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 13:40:41 INFO - Installing distribute.............................................................................................................................................................................................done. 13:40:43 INFO - Installing pip.................done. 13:40:43 INFO - Return code: 0 13:40:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 13:40:43 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')]} 13:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:43 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')]} 13:40:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:43 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 0x7f32675ccc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebfa90>, '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 0xf11f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf122e0>, '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': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', '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 13:40:43 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 13:40:43 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 13:40:43 INFO - Using env: {'DISPLAY': ':0', 13:40:43 INFO - 'HOME': '/home/cltbld', 13:40:43 INFO - 'LANG': 'en_US.UTF-8', 13:40:43 INFO - 'LANGUAGE': 'en_US:en', 13:40:43 INFO - 'LOGNAME': 'cltbld', 13:40:43 INFO - 'MAIL': '/var/mail/cltbld', 13:40:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:43 INFO - 'MOZ_NO_REMOTE': '1', 13:40:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:43 INFO - 'NO_EM_RESTART': '1', 13:40:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:40:43 INFO - 'PWD': '/builds/slave/test-pgo', 13:40:43 INFO - 'SHELL': '/bin/bash', 13:40:43 INFO - 'SHLVL': '1', 13:40:43 INFO - 'TERM': 'linux', 13:40:43 INFO - 'TMOUT': '86400', 13:40:43 INFO - 'USER': 'cltbld', 13:40:43 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', 13:40:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:43 INFO - '_': '/tools/buildbot/bin/python'} 13:40:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:43 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 13:40:43 INFO - Cleaning up... 13:40:43 INFO - Return code: 0 13:40:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 13:40:43 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')]} 13:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:43 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')]} 13:40:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:43 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 0x7f32675ccc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebfa90>, '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 0xf11f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf122e0>, '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': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', '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 13:40:43 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 13:40:43 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 13:40:43 INFO - Using env: {'DISPLAY': ':0', 13:40:43 INFO - 'HOME': '/home/cltbld', 13:40:43 INFO - 'LANG': 'en_US.UTF-8', 13:40:43 INFO - 'LANGUAGE': 'en_US:en', 13:40:43 INFO - 'LOGNAME': 'cltbld', 13:40:43 INFO - 'MAIL': '/var/mail/cltbld', 13:40:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:43 INFO - 'MOZ_NO_REMOTE': '1', 13:40:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:43 INFO - 'NO_EM_RESTART': '1', 13:40:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:40:43 INFO - 'PWD': '/builds/slave/test-pgo', 13:40:43 INFO - 'SHELL': '/bin/bash', 13:40:43 INFO - 'SHLVL': '1', 13:40:43 INFO - 'TERM': 'linux', 13:40:43 INFO - 'TMOUT': '86400', 13:40:43 INFO - 'USER': 'cltbld', 13:40:43 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', 13:40:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:43 INFO - '_': '/tools/buildbot/bin/python'} 13:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:44 INFO - Downloading/unpacking psutil>=0.7.1 13:40:44 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 13:40:44 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 13:40:44 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 13:40:44 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 13:40:44 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 13:40:44 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 13:40:45 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 13:40:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 13:40:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 13:40:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:40:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:40:45 INFO - Installing collected packages: psutil 13:40:45 INFO - Running setup.py install for psutil 13:40:45 INFO - building 'psutil._psutil_linux' extension 13:40:45 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 13:40:46 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 13:40:46 INFO - building 'psutil._psutil_posix' extension 13:40:46 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 13:40:46 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 13:40:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:40:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:40:46 INFO - Successfully installed psutil 13:40:46 INFO - Cleaning up... 13:40:46 INFO - Return code: 0 13:40:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 13:40: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')]} 13:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40: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')]} 13:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40: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', '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 0x7f32675ccc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebfa90>, '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 0xf11f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf122e0>, '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': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', '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 13:40: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 13:40: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 mozsystemmonitor==0.0.0 13:40:46 INFO - Using env: {'DISPLAY': ':0', 13:40:46 INFO - 'HOME': '/home/cltbld', 13:40:46 INFO - 'LANG': 'en_US.UTF-8', 13:40:46 INFO - 'LANGUAGE': 'en_US:en', 13:40:46 INFO - 'LOGNAME': 'cltbld', 13:40:46 INFO - 'MAIL': '/var/mail/cltbld', 13:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:46 INFO - 'MOZ_NO_REMOTE': '1', 13:40:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:46 INFO - 'NO_EM_RESTART': '1', 13:40:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:40:46 INFO - 'PWD': '/builds/slave/test-pgo', 13:40:46 INFO - 'SHELL': '/bin/bash', 13:40:46 INFO - 'SHLVL': '1', 13:40:46 INFO - 'TERM': 'linux', 13:40:46 INFO - 'TMOUT': '86400', 13:40:46 INFO - 'USER': 'cltbld', 13:40:46 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', 13:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:46 INFO - '_': '/tools/buildbot/bin/python'} 13:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:46 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:40: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 13:40: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 13:40: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 13:40: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 13:40: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 13:40: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 13:40:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:40:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 13:40:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:40:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:40:48 INFO - Installing collected packages: mozsystemmonitor 13:40:48 INFO - Running setup.py install for mozsystemmonitor 13:40:48 INFO - Successfully installed mozsystemmonitor 13:40:48 INFO - Cleaning up... 13:40:48 INFO - Return code: 0 13:40:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 13:40:48 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')]} 13:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:48 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')]} 13:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:48 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 0x7f32675ccc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebfa90>, '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 0xf11f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf122e0>, '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': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', '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 13:40:48 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 13:40:48 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 13:40:48 INFO - Using env: {'DISPLAY': ':0', 13:40:48 INFO - 'HOME': '/home/cltbld', 13:40:48 INFO - 'LANG': 'en_US.UTF-8', 13:40:48 INFO - 'LANGUAGE': 'en_US:en', 13:40:48 INFO - 'LOGNAME': 'cltbld', 13:40:48 INFO - 'MAIL': '/var/mail/cltbld', 13:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:48 INFO - 'MOZ_NO_REMOTE': '1', 13:40:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:48 INFO - 'NO_EM_RESTART': '1', 13:40:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:40:48 INFO - 'PWD': '/builds/slave/test-pgo', 13:40:48 INFO - 'SHELL': '/bin/bash', 13:40:48 INFO - 'SHLVL': '1', 13:40:48 INFO - 'TERM': 'linux', 13:40:48 INFO - 'TMOUT': '86400', 13:40:48 INFO - 'USER': 'cltbld', 13:40:48 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', 13:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:48 INFO - '_': '/tools/buildbot/bin/python'} 13:40:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:48 INFO - Downloading/unpacking blobuploader==1.2.4 13:40:48 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 13:40:48 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 13:40:48 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 13:40:48 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 13:40:48 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 13:40:48 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 13:40:50 INFO - Downloading blobuploader-1.2.4.tar.gz 13:40:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 13:40:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:40:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:40:50 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 13:40:50 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 13:40:50 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 13:40:50 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 13:40:50 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 13:40:50 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 13:40:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 13:40:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 13:40:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:40:50 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 13:40:50 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 13:40:50 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 13:40:50 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 13:40:50 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 13:40:50 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 13:40:50 INFO - Downloading docopt-0.6.1.tar.gz 13:40:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 13:40:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 13:40:51 INFO - Installing collected packages: blobuploader, requests, docopt 13:40:51 INFO - Running setup.py install for blobuploader 13:40:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:40:51 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 13:40:51 INFO - Running setup.py install for requests 13:40:51 INFO - Running setup.py install for docopt 13:40:51 INFO - Successfully installed blobuploader requests docopt 13:40:51 INFO - Cleaning up... 13:40:51 INFO - Return code: 0 13:40:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:40: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')]} 13:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40: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')]} 13:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:51 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 0x7f32675ccc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebfa90>, '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 0xf11f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf122e0>, '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': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', '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 13:40:51 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 13:40:51 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 13:40:51 INFO - Using env: {'DISPLAY': ':0', 13:40:51 INFO - 'HOME': '/home/cltbld', 13:40:51 INFO - 'LANG': 'en_US.UTF-8', 13:40:51 INFO - 'LANGUAGE': 'en_US:en', 13:40:51 INFO - 'LOGNAME': 'cltbld', 13:40:51 INFO - 'MAIL': '/var/mail/cltbld', 13:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:51 INFO - 'MOZ_NO_REMOTE': '1', 13:40:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:51 INFO - 'NO_EM_RESTART': '1', 13:40:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:40:51 INFO - 'PWD': '/builds/slave/test-pgo', 13:40:51 INFO - 'SHELL': '/bin/bash', 13:40:51 INFO - 'SHLVL': '1', 13:40:51 INFO - 'TERM': 'linux', 13:40:51 INFO - 'TMOUT': '86400', 13:40:51 INFO - 'USER': 'cltbld', 13:40:51 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', 13:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:51 INFO - '_': '/tools/buildbot/bin/python'} 13:40:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:40:51 INFO - Running setup.py (path:/tmp/pip-UOx3lF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:40:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:40:51 INFO - Running setup.py (path:/tmp/pip-sK_cMZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:40:52 INFO - Running setup.py (path:/tmp/pip-3yG5WF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:40:52 INFO - Running setup.py (path:/tmp/pip-bpyZcV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 13:40:52 INFO - Running setup.py (path:/tmp/pip-yLReNL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:40:52 INFO - Running setup.py (path:/tmp/pip-0CLeS8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:40:52 INFO - Running setup.py (path:/tmp/pip-3BSLgJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:40:52 INFO - Running setup.py (path:/tmp/pip-WxeROQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 13:40:52 INFO - Running setup.py (path:/tmp/pip-XHIikS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 13:40:52 INFO - Running setup.py (path:/tmp/pip-zy0q6Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:40:52 INFO - Running setup.py (path:/tmp/pip-TljP8R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:40:52 INFO - Running setup.py (path:/tmp/pip-12sphh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:40:52 INFO - Running setup.py (path:/tmp/pip-olHGpB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:40:52 INFO - Running setup.py (path:/tmp/pip-owEIcI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:40:52 INFO - Running setup.py (path:/tmp/pip-ZwtrvU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:40:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 13:40:52 INFO - Running setup.py (path:/tmp/pip-6Li_kX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 13:40:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 13:40:53 INFO - Running setup.py (path:/tmp/pip-FbhBlt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 13:40:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:40:53 INFO - Running setup.py install for manifestparser 13:40:53 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 13:40:53 INFO - Running setup.py install for mozcrash 13:40:53 INFO - Running setup.py install for mozdebug 13:40:53 INFO - Running setup.py install for mozdevice 13:40:53 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 13:40:53 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 13:40:53 INFO - Running setup.py install for mozfile 13:40:53 INFO - Running setup.py install for mozhttpd 13:40:53 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 13:40:53 INFO - Running setup.py install for mozinfo 13:40:53 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 13:40:53 INFO - Running setup.py install for mozInstall 13:40:53 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 13:40:53 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 13:40:53 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 13:40:53 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 13:40:53 INFO - Running setup.py install for mozleak 13:40:54 INFO - Running setup.py install for mozlog 13:40:54 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 13:40:54 INFO - Running setup.py install for moznetwork 13:40:54 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 13:40:54 INFO - Running setup.py install for mozprocess 13:40:54 INFO - Running setup.py install for mozprofile 13:40:54 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 13:40:54 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 13:40:54 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 13:40:54 INFO - Running setup.py install for mozrunner 13:40:54 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 13:40:54 INFO - Running setup.py install for mozscreenshot 13:40:54 INFO - Running setup.py install for moztest 13:40:54 INFO - Running setup.py install for mozversion 13:40:54 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 13:40:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:40:54 INFO - Cleaning up... 13:40:54 INFO - Return code: 0 13:40:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:40: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')]} 13:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40: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')]} 13:40:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f32675ccc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebfa90>, '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 0xf11f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf122e0>, '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': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', '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 13:40:54 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 13:40:54 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 13:40:54 INFO - Using env: {'DISPLAY': ':0', 13:40:54 INFO - 'HOME': '/home/cltbld', 13:40:54 INFO - 'LANG': 'en_US.UTF-8', 13:40:54 INFO - 'LANGUAGE': 'en_US:en', 13:40:54 INFO - 'LOGNAME': 'cltbld', 13:40:54 INFO - 'MAIL': '/var/mail/cltbld', 13:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:54 INFO - 'MOZ_NO_REMOTE': '1', 13:40:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:54 INFO - 'NO_EM_RESTART': '1', 13:40:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:40:54 INFO - 'PWD': '/builds/slave/test-pgo', 13:40:54 INFO - 'SHELL': '/bin/bash', 13:40:54 INFO - 'SHLVL': '1', 13:40:54 INFO - 'TERM': 'linux', 13:40:54 INFO - 'TMOUT': '86400', 13:40:54 INFO - 'USER': 'cltbld', 13:40:54 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', 13:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:54 INFO - '_': '/tools/buildbot/bin/python'} 13:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:40:55 INFO - Running setup.py (path:/tmp/pip-yVcC_V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:40:55 INFO - Running setup.py (path:/tmp/pip-QW5dP4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:40:55 INFO - Running setup.py (path:/tmp/pip-owpqt2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:40:55 INFO - Running setup.py (path:/tmp/pip-7aJbaV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 13:40:55 INFO - Running setup.py (path:/tmp/pip-9k3kL_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:40:55 INFO - Running setup.py (path:/tmp/pip-l7JR_a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:40:55 INFO - Running setup.py (path:/tmp/pip-IX6ZXa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:40:55 INFO - Running setup.py (path:/tmp/pip-S_MZwS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 13:40:55 INFO - Running setup.py (path:/tmp/pip-J2WrSk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 13:40:55 INFO - Running setup.py (path:/tmp/pip-iK6mhE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:40:55 INFO - Running setup.py (path:/tmp/pip-PqKRdV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:40:55 INFO - Running setup.py (path:/tmp/pip-LFYmot-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:40:55 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)) 13:40:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:40:56 INFO - Running setup.py (path:/tmp/pip-kA2bcX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:40:56 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)) 13:40:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:40:56 INFO - Running setup.py (path:/tmp/pip-_qO5yq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:40:56 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)) 13:40:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:40:56 INFO - Running setup.py (path:/tmp/pip-cdlDIh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:40:56 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)) 13:40:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 13:40:56 INFO - Running setup.py (path:/tmp/pip-H6EkNU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 13:40:56 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)) 13:40:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 13:40:56 INFO - Running setup.py (path:/tmp/pip-_N39cd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 13:40:56 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)) 13:40:56 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)) 13:40:56 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)) 13:40:56 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)) 13:40:56 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)) 13:40:56 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)) 13:40:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 13:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:59 INFO - Downloading blessings-1.5.1.tar.gz 13:40:59 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 13:40:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 13:40:59 INFO - Installing collected packages: blessings 13:40:59 INFO - Running setup.py install for blessings 13:40:59 INFO - Successfully installed blessings 13:40:59 INFO - Cleaning up... 13:40:59 INFO - Return code: 0 13:40:59 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 13:40:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 13:40:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 13:40:59 INFO - Reading from file tmpfile_stdout 13:40:59 INFO - Current package versions: 13:40:59 INFO - argparse == 1.2.1 13:40:59 INFO - blessings == 1.5.1 13:40:59 INFO - blobuploader == 1.2.4 13:40:59 INFO - docopt == 0.6.1 13:40:59 INFO - manifestparser == 1.1 13:40:59 INFO - mozInstall == 1.12 13:40:59 INFO - mozcrash == 0.16 13:40:59 INFO - mozdebug == 0.1 13:40:59 INFO - mozdevice == 0.47 13:40:59 INFO - mozfile == 1.2 13:40:59 INFO - mozhttpd == 0.7 13:40:59 INFO - mozinfo == 0.9 13:40:59 INFO - mozleak == 0.1 13:40:59 INFO - mozlog == 3.1 13:40:59 INFO - moznetwork == 0.27 13:40:59 INFO - mozprocess == 0.22 13:40:59 INFO - mozprofile == 0.27 13:40:59 INFO - mozrunner == 6.11 13:40:59 INFO - mozscreenshot == 0.1 13:40:59 INFO - mozsystemmonitor == 0.0 13:40:59 INFO - moztest == 0.7 13:40:59 INFO - mozversion == 1.4 13:40:59 INFO - psutil == 3.1.1 13:40:59 INFO - requests == 1.2.3 13:40:59 INFO - wsgiref == 0.1.2 13:40:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:40:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:40:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:59 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 0x7f32675ccc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebfa90>, '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 0xf11f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf122e0>, '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': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', '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 13:40:59 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 13:40:59 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 13:40:59 INFO - Using env: {'DISPLAY': ':0', 13:40:59 INFO - 'HOME': '/home/cltbld', 13:40:59 INFO - 'LANG': 'en_US.UTF-8', 13:40:59 INFO - 'LANGUAGE': 'en_US:en', 13:40:59 INFO - 'LOGNAME': 'cltbld', 13:40:59 INFO - 'MAIL': '/var/mail/cltbld', 13:40:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:59 INFO - 'MOZ_NO_REMOTE': '1', 13:40:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:59 INFO - 'NO_EM_RESTART': '1', 13:40:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:40:59 INFO - 'PWD': '/builds/slave/test-pgo', 13:40:59 INFO - 'SHELL': '/bin/bash', 13:40:59 INFO - 'SHLVL': '1', 13:40:59 INFO - 'TERM': 'linux', 13:40:59 INFO - 'TMOUT': '86400', 13:40:59 INFO - 'USER': 'cltbld', 13:40:59 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', 13:40:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:59 INFO - '_': '/tools/buildbot/bin/python'} 13:40:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:59 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)) 13:40:59 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)) 13:40:59 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)) 13:40:59 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)) 13:40:59 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)) 13:40:59 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)) 13:40:59 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)) 13:40:59 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)) 13:40: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.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 13:40: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 mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 13:40:59 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)) 13:40:59 INFO - Cleaning up... 13:40:59 INFO - Return code: 0 13:40:59 INFO - Running post-action listener: _resource_record_post_action 13:40:59 INFO - Running post-action listener: _start_resource_monitoring 13:40:59 INFO - Starting resource monitoring. 13:40:59 INFO - ##### 13:40:59 INFO - ##### Running install step. 13:40:59 INFO - ##### 13:40:59 INFO - Running pre-action listener: _resource_record_pre_action 13:40:59 INFO - Running main action method: install 13:40:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 13:40:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 13:40:59 INFO - Reading from file tmpfile_stdout 13:40:59 INFO - Detecting whether we're running mozinstall >=1.0... 13:40:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 13:40:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 13:41:00 INFO - Reading from file tmpfile_stdout 13:41:00 INFO - Output received: 13:41:00 INFO - Usage: mozinstall [options] installer 13:41:00 INFO - Options: 13:41:00 INFO - -h, --help show this help message and exit 13:41:00 INFO - -d DEST, --destination=DEST 13:41:00 INFO - Directory to install application into. [default: 13:41:00 INFO - "/builds/slave/test-pgo"] 13:41:00 INFO - --app=APP Application being installed. [default: firefox] 13:41:00 INFO - mkdir: /builds/slave/test-pgo/build/application 13:41:00 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'] 13:41:00 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 13:41:14 INFO - Reading from file tmpfile_stdout 13:41:14 INFO - Output received: 13:41:14 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 13:41:14 INFO - Running post-action listener: _resource_record_post_action 13:41:14 INFO - ##### 13:41:14 INFO - ##### Running run-tests step. 13:41:14 INFO - ##### 13:41:14 INFO - Running pre-action listener: _resource_record_pre_action 13:41:14 INFO - Running main action method: run_tests 13:41:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 13:41:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 13:41:14 INFO - Python 2.7.3 13:41:14 INFO - Return code: 0 13:41:14 INFO - grabbing minidump binary from tooltool 13:41:14 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')]} 13:41:14 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 0xebfa90>, '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 0xf11f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf122e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 13:41:14 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 13:41:14 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 13:41:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:41:14 INFO - Return code: 0 13:41:14 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 13:41:14 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 13:41:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 13:41:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 13:41:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 13:41:14 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/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 13:41:14 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/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost 13:41:14 INFO - Using env: {'DISPLAY': ':0', 13:41:14 INFO - 'HOME': '/home/cltbld', 13:41:14 INFO - 'LANG': 'en_US.UTF-8', 13:41:14 INFO - 'LANGUAGE': 'en_US:en', 13:41:14 INFO - 'LOGNAME': 'cltbld', 13:41:14 INFO - 'MAIL': '/var/mail/cltbld', 13:41:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 13:41:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 13:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:14 INFO - 'MOZ_NO_REMOTE': '1', 13:41:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 13:41:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:14 INFO - 'NO_EM_RESTART': '1', 13:41:14 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:41:14 INFO - 'PWD': '/builds/slave/test-pgo', 13:41:14 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 13:41:14 INFO - 'SHELL': '/bin/bash', 13:41:14 INFO - 'SHLVL': '1', 13:41:14 INFO - 'TERM': 'linux', 13:41:14 INFO - 'TMOUT': '86400', 13:41:14 INFO - 'USER': 'cltbld', 13:41:14 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409', 13:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:14 INFO - '_': '/tools/buildbot/bin/python'} 13:41:14 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/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost'] with output_timeout 3600 13:41:14 INFO - mozversion INFO | application_buildid: 20151123113010 13:41:14 INFO - mozversion INFO | application_changeset: a5cf6e488f2d4266e5437a18dc1e3930570558c4 13:41:14 INFO - mozversion INFO | application_display_name: Nightly 13:41:14 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:41:14 INFO - mozversion INFO | application_name: Firefox 13:41:14 INFO - mozversion INFO | application_remotingname: firefox 13:41:14 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 13:41:14 INFO - mozversion INFO | application_vendor: Mozilla 13:41:14 INFO - mozversion INFO | application_version: 45.0a1 13:41:14 INFO - mozversion INFO | platform_buildid: 20151123113010 13:41:14 INFO - mozversion INFO | platform_changeset: a5cf6e488f2d4266e5437a18dc1e3930570558c4 13:41:14 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 13:41:14 INFO - mozversion INFO | platform_version: 45.0a1 13:41:14 INFO - 2015-11-23 13:41:14,832 DEBUG : using testdate: 1448314874 13:41:14 INFO - 2015-11-23 13:41:14,832 DEBUG : actual date: 1448314874 13:41:14 INFO - 2015-11-23 13:41:14,910 INFO : starting webserver on 'localhost:59165' 13:41:14 INFO - 2015-11-23 13:41:14,911 INFO : Starting test suite talos-linux64-ix-061 13:41:14 INFO - 2015-11-23 13:41:14,912 INFO : Starting test dromaeo_css 13:41:14 INFO - 2015-11-23 13:41:14,912 DEBUG : operating with platform_type : linux_ 13:41:14 INFO - 2015-11-23 13:41:14,912 INFO : Initialising browser for dromaeo_css test... 13:41:14 INFO - 2015-11-23 13:41:14,927 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpX_qTsH/profile http://localhost:59165/getInfo.html 13:41:18 INFO - 2015-11-23 13:41:18,789 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:41:18 INFO - 2015-11-23 13:41:18,789 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:41:18 INFO - 2015-11-23 13:41:18,789 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:41:18 INFO - 2015-11-23 13:41:18,789 DEBUG : BROWSER_OUTPUT: __metrics 13:41:18 INFO - 2015-11-23 13:41:18,926 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:41:20 INFO - 2015-11-23 13:41:20,145 INFO : Browser initialized. 13:41:20 INFO - 2015-11-23 13:41:20,146 INFO : Running cycle 1/1 for dromaeo_css test... 13:41:20 INFO - 2015-11-23 13:41:20,146 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpX_qTsH/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:41:31 INFO - 2015-11-23 13:41:31,975 DEBUG : BROWSER_OUTPUT: RSS: Main: 139939840 13:41:31 INFO - 2015-11-23 13:41:31,975 DEBUG : BROWSER_OUTPUT: 13:42:23 INFO - 2015-11-23 13:42:23,362 DEBUG : BROWSER_OUTPUT: 1448314943358 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] 13:44:31 INFO - 2015-11-23 13:44:31,983 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59165/tests/dromaeo/cssquery-ext.html) 13:44:33 INFO - 2015-11-23 13:44:33,544 DEBUG : BROWSER_OUTPUT: RSS: Main: 311832576 13:44:33 INFO - 2015-11-23 13:44:33,545 DEBUG : BROWSER_OUTPUT: 13:47:36 INFO - 2015-11-23 13:47:36,013 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/dromaeo/cssquery-ext.html (next: http://localhost:59165/tests/dromaeo/cssquery-jquery.html) 13:47:36 INFO - 2015-11-23 13:47:36,585 DEBUG : BROWSER_OUTPUT: RSS: Main: 220340224 13:47:36 INFO - 2015-11-23 13:47:36,586 DEBUG : BROWSER_OUTPUT: 13:50:38 INFO - 2015-11-23 13:50:38,435 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59165/tests/dromaeo/cssquery-mootools.html) 13:50:39 INFO - 2015-11-23 13:50:39,009 DEBUG : BROWSER_OUTPUT: RSS: Main: 218140672 13:50:39 INFO - 2015-11-23 13:50:39,009 DEBUG : BROWSER_OUTPUT: 13:53:40 INFO - 2015-11-23 13:53:40,827 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59165/tests/dromaeo/cssquery-prototype.html) 13:53:41 INFO - 2015-11-23 13:53:41,400 DEBUG : BROWSER_OUTPUT: RSS: Main: 202579968 13:53:41 INFO - 2015-11-23 13:53:41,401 DEBUG : BROWSER_OUTPUT: 13:56:43 INFO - 2015-11-23 13:56:43,792 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59165/tests/dromaeo/cssquery-yui.html) 13:56:44 INFO - 2015-11-23 13:56:44,381 DEBUG : BROWSER_OUTPUT: RSS: Main: 204136448 13:56:44 INFO - 2015-11-23 13:56:44,381 DEBUG : BROWSER_OUTPUT: 13:59:46 INFO - 2015-11-23 13:59:46,937 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/dromaeo/cssquery-yui.html (next: http://localhost:59165/tests/dromaeo/cssquery-dojo.html) 13:59:47 INFO - 2015-11-23 13:59:47,186 DEBUG : BROWSER_OUTPUT: RSS: Main: 280842240 13:59:47 INFO - 2015-11-23 13:59:47,187 DEBUG : BROWSER_OUTPUT: 13:59:47 INFO - 2015-11-23 13:59:47,187 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:59:47 INFO - 2015-11-23 13:59:47,187 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:59:47 INFO - 2015-11-23 13:59:47,187 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:59:47 INFO - 2015-11-23 13:59:47,187 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:59:47 INFO - 2015-11-23 13:59:47,188 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3191.632962209346;3267.1995361136524;3296.8681252749893;3304.5208444775494;3336.332733453309;5125.66683165594;5238.476152384759;5360.169173778066;5396.109641909085;5411.404745477999;5410.56715462763;5437.429069947658;5497.670139791615;5498.3127109111365;5500.697461639608;4936.06214819185;4958.553730164284;4960.032793605248;4972.080165169437;5000.374953130859;4847.636427267969;5080.949190508103;5083.949160508385;5106.5659418949435;5162.915787684591;4903.803847846099;4970.776315065831;5042.571381432583;5075.6447048706605;5081.491850814926;4966.056449274626;4974.676646018033;4975.427825800043;4985.52637499437;5022.17134172861;4793.065352256312;4943.8516844494725;4965.131102057162;4972.229304457815;5024.748762561857;5158.690478571298;5180.43015268324;5189.247559103909;5198.662086964335;5198.766055527472;5822.708864556797;5875.823725288248;5973.731182096816;5974.193483879708;5984.371640977722;5951.137085122633;5951.137085122633;5951.940480595182;5965.850853728648;5996.610220335664;5464.863378415492;5524.558035357162;5534.501894829445;5536.806211782568;5547.306586676666;5822.82531524055;5847.181394604759;5942.37605051472;5945.524358051345;6008.639481631116;5875.353711091777;5879.118132280192;5908.704564771744;5930.05119180929;5933.525317974551;5955.374685657944;5958.493528050164;5958.612690175211;5959.791407300723;5967.224260846062;5679.74441150141;5790.218320526759;5927;5930.169776231331;5939.970300148558;5838.824835254949;5851.093080572518;5923.703814809329;5933.021051526536;5974.4622983930785;4526.683132180781;4539.16025535277;4548.4541854977615;4560.1335746208115;4563.543645635476;3939.1727737175515;4023.376376661622;4070.063885306396;4076.980754811297;4106.815193316308;5537.723113844291;5568.888622227532;5600.27196464457;5602.775888964396;5671.886562268732;5856.648601083949;5864.149698293809;5893.5874488785375;5899.115132730039;5904.852378690567;5439.48324909133;5517.586181036439;5548.528375088082;5593.161025846156;5595.580331475156;5055.545000949932;5095.464976177448;5099.082165210298;5110.233464980209;5117.923231151536;6106.9083963739695;6141.938580614226;6190.380961903774;6192.287886893065;6194.318624951252;5712.742926568231;5837.94162058374;5841.649501029951;5987.491063259672;6014;6061.6666083365835;6122.377674734244;6123.387661233841;6130.765048853685;6133.26400831903;5813.331466881194;5869.35437101927;5871.412858714094;5872.119182122716;5875.236219291465;5661.348944871393;5775.884482310246;5791.247137872049;5938.524918006636;5940.227770389822;5582.162675598693;5599.636023658449;5631.465010824129;5638.407967163389;5640.464155905182;5611.214429979725;5612.102063669957;5612.803551875665;5619.353774316006;5625.971870140623;5546.168074788814;5605.047141985954;5620.662760234399;5622.156676498558;5661.377248502744;5762.1068734346245;5775.0471172257785;5814.622049566765;5822.796202132905;5870.442307980735;5431.4840090191365;5548.0845660465575;5697.886042279214;5701.971490142523;5704.60067795267 13:59:47 INFO - 2015-11-23 13:59:47,192 DEBUG : BROWSER_OUTPUT: |1;ext.html;570.5180672126463;601.527800676469;602.9125776762372;610.5420934299276;614.3702704727655;31306.686933130663;32228.516572251407;41744.58255417445;42166;42213.366799497955;44353.11293774131;44663.553364466345;45047;45075.54924450763;45139.54860451395;7350.522216055966;7605.8478830423355;8683.56582170892;8821.25019373354;8854.424462409956;10456.738581535446;10678.733031674192;10693.518791654395;10714.142868570534;10714.357138571671;10361.533730982086;10428.63499777508;10451.11165550929;10481.790364192711;10487.37075775456;7688.961555192244;7878.212178782133;7884.369250459949;10320.277580569364;10577.471126443725;10192.369220924616;10327.84508232377;10331.79336413275;10340.121089707383;10344.482775861177;40584.188316233805;41025;41040.179196416204;41054.178916421806;41098.58901410978;3280.6555311692405;3320.568326117589;3338.6327503974544;3367.3602015616952;3401.302732939766;30645.620947057436;30704.732076930206;30953.53569696456;31230.84384578085;31610.525842112387;3928.2339943710704;3930.5872883347124;3999.7600143991453;4054.4729185205733;4066.2883995301;267435;267563;268283.6585817078;269134;269810;56471.435285647436;57458.42541574531;58078.41921580815;58356.708216458646;58816.11775823366;60588.394116058276;60596;60954.69522652358;61002.38997609967;61072.69463652653;13564.796528052088;13662.521811736542;13673.453061877612;13674.17954922688;13679.384427700777;10893.945530272298;10954.726131846768;10966.122710183166;10987.560497580167;11043.503042363112;19103.951046686056;19199;19268.903655481634;19293.421197364103;19457.124429400712;7237.167725711611;7484.401247900155;7584.62076896153;7603.695852165962;7614.58119803405;943.9952800236092;968.3221744778683;977.5747549815853;1013.159077965287;1154.878737732526;27429;27872.860635696692;28183.013594524095;28252.71747282501;28301.71698282991;1063.2344711807486;1096.6490722968733;1097.1277834121893;1099.054812860939;1116.4083035990939;13434.529791457253;13867.930660346836;13913.791293130611;13979.650508737363;14003.439862405594;311212;314861;315562;316058;316578;52239;52569.47430525722;52660;52718.47281527136;52890;1142.9771404571861;1307.718840449308;1309.9499283621103;1319.6568892088126;1332.0409305300168;20927.686084709047;29278;29543.556846646887;29808.701912980592;29811.70188298176;50976;51387.743061284455;51654;51667;51764.48235517597;221114;222386;222990;282462;286779;32142;32509.862154824466;32566.83716581402;32854.83572582122;32902.506462402605;38703;39091.218175636895;39755.40366894557;40071.599284007956;40205.39691904682;34141.829290853384;36078.8196059018;36812.44781328336;37142.8142859284;37220.06949826276;36175.457368138974;36348;36814.44778328274;37112.81443592765;37571.436428454144;33002.339953201285;33480.497792532646;33907;33952.83023584866;34011.4898276531;29372.559411608414;31008.844955775076;31100.533491997187;31428.37143257168;31485.527717083805;23992.520149597258;24055.142656387594;24085.27744167678;24345.14791982272;25718 13:59:47 INFO - 2015-11-23 13:59:47,192 DEBUG : BROWSER_OUTPUT: |2;jquery.html;349.6852832450796;349.88803582853484;353.3709996206752;354.59753180140535;355.5431270817;14112;14266;16369.508914732562;17057.744133837987;18143.092845357743;15043.398264069425;16254;16276.674466510663;16317.510474685749;17379;5234.476552344773;5356.866078348034;5361;5361.169018802092;5367.973160134214;3912;4008.7193896427116;5268.367795864501;5308.92036619451;5675.063614503602;5889.087191485305;5920.378360272174;5923.318818335903;5933.5549833762425;5973.820785376424;5721.971390143043;5860.882782344351;5862.237909071836;5871.3541510433815;5885.146653735227;5206.661566998171;5213.2701421800975;5215.608829337777;5215.8174463893965;5217.113090774539;20328.390148295573;20429.69355459669;20678.379648610568;20940.16239350424;20945.266915657878;2150.1184514349043;2162.048698572639;2257.1761307122947;2327.3599760066118;2381.940451488709;13202.735945281136;13396.598102056954;13452;13594.252316122609;13662.316884155853;1881.285111657575;1922.2983610981892;1933.236371633211;1988.4829944214464;2002.5093852006205;58337.416625833626;59503.99999999956;61914;65007.3499265006;65713.3428665712;9663.13031827139;9782.364146330608;9835.852462213072;9851.113399794052;9889.159421449114;12769.872301277053;12778.361081945866;12807.167534110253;12826.67933301675;12847.550335738391;7286.417086633057;7368.410527157815;7398.223186565441;7451.515651482637;7462.067241594801;6866.588004719633;6984.755533556301;7046.260142684944;7192.496525243183;7285.890711639329;4210.621044106045;4211.620954114083;4352.086061926968;4352.804123814436;4368.825246990148;1695.533728224748;1739.1565090930887;1743.119724539125;1757.868159887988;1788.6333301673126;875.6059773102002;959.7984423271051;987.4420952162005;1009.9748755013667;1018.923580731448;15520.301586428637;16375.67248655044;16474.75287870683;17015.23431445588;17073.48779536591;823.180934969709;873.7204094689765;918.4856480371013;950.5104870991445;957.4016239850093;7431.739889103856;9421.481818499911;9964.451955142391;10207.438590877424;10313.48432578368;506416;506569;519653;528874;532709;80955.59522202352;82247.5887620558;82945.58527207325;83220.58389708013;84481.57759211164;1096.8957948994832;1124.404065845087;1143.5654451308617;1156.901663358607;1160.1936654025371;15071.246437677853;15585.922070389575;15608.687826243638;15791.921040394724;15813.446529371417;35218.82390588031;35550;36100;37201.25597488089;37221.883343499736;47918.28122578094;48828.2675759871;50951.490485096154;51580.74209628928;52003.47996519986;8970.41692289999;9082.18260356571;9102.08979102111;9140.451572905647;9239.907600924174;12898.548550800677;17431.302747889942;17832;17861.28554857843;17919.73120403169;14300.785488217893;14353.497627582985;14770.113793172444;14782.408703651734;14894.925525372737;14350.282485875456;14414.207218603078;14414.85585144135;14552;14900.850991489946;14734;14812.407503699731;15113.395464181309;15197.924010379877;15256.160911149993;7867.645955931882;9137.908620913706;9592.08875156859;9774.85337719949;9779.168770654289;14517;14740.96813223064;15086.54740357791;15131.54605361841;15214 13:59:47 INFO - 2015-11-23 13:59:47,193 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1282.9871701282996;1452.949146779863;1458.1615571046643;1712.2551690014845;1777.386801553464;6328.2406111266655;7028.226895041541;7177;7186.029885965406;7190.065291512109;7297.817554561138;7417.666205020773;7422;7435.219301973296;7436.2563743625615;1897.7627796525435;1926.6917293233087;1927.7493925789697;2063.5872825434935;2066.8243199328076;2200.471886747185;2238.205437069833;2243.170027089979;2243.4839986803045;2246.0454306919573;2173.7608863025052;2175.912963481459;2262.6266666000092;2263.207877242968;2266.7846554577372;1920.5198700324918;1953.7460130183056;2166.490874644457;2210.7789221077924;2259.062489067035;2209.281983355416;2235.88820558973;2246.3485589179118;2249.741279752833;2250.4036430345973;7566.772996810049;7626.122995855492;7627.69489220436;7633.618319084024;7633.847323053508;4551;4558.977205113986;4576.244919588297;4584.312353147021;4646.303054541812;2924.546695262238;2924.6197994260824;2972.628421447319;2991.4764916139807;3023.108183085995;949.5916755795006;949.6106596295555;955.8183945050488;994.0854413939194;994.6469003503797;10140.89859101407;10151.543180556893;10163.542640581192;10177.287589868713;10184.236182286284;4004.5594984551676;4126.834926602963;4155.314373128461;4228.048689045002;4228.725132866415;8818.779530511789;8828.8675669865;8831.911680883237;8834.116588341114;8839.46963182211;4528.932066019012;4553.931691024637;4554.817807287672;4560.749158796231;4618.445786505641;4128.731632443882;4163.875083747493;4164.56272091426;4166.270902592034;4182.728122672017;3471.5486986691567;3506.1234691327168;3524.647535246455;3528.3119791640383;3534.6465353464446;1157.7337212441016;1160.2400427719856;1169.6140273709825;1176.4705882352976;1180.0913296761446;1290.9870901290867;1339.7387509435566;1349.39951721483;1364.2701154882288;1379.165604809096;7148.571085734872;7183;7187.9640601796655;7198.460115491359;7205.135383753983;493.4153028661048;495.3040977426721;502.9899402011939;503.5694481218579;503.6197670758554;5574.665520068809;5681.0910254359105;5707.058335374617;5724.084146536534;5726.484616384545;8776.385653004356;8857.424267422726;8979.416337938013;9004.549772511347;9160.22138118254;9045.638174472948;9063;9063.81872362549;9067.68263110788;9081.954590227007;6824.522283440111;6931.6880740366805;7017.122859642544;7029.4376449883885;7033.613151276627;7051.189113252041;7102.4673149512955;7106.7157313706875;7109.928900710926;7111.111111111111;7767.844643106993;7989.161138080651;7996.960015199887;8079.272865442138;8096.919030809617;7943.4042446817875;8096.878546821685;8142.267195952392;8143.104258531438;8252.21603947624;6797.456203503807;6807.659617019228;6837.589744615339;6838.521303508706;6859.348361905611;7348.853022939618;7371.557706537625;7378.778636640909;7379.778606641809;7383.963080184565;6802.795916122524;6816.318368163144;6816.556923799937;6831.487638427038;6870.038194652652;5883.176355310346;5883.441073098049;6380.968095159495;6781.491388146008;6867;6608.867822643424;6645.036469711768;6664.200295964515;6666;6666.566673166229;6209.223847019122;6231.719572619334;6247.219097612799;6247.812565623039;6254.124422580751;6287.937120628735;6485.351464853666;6504;6530.640814755234;6536.215654121345 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: |4;prototype.html;310.6955183919759;318.4888254351765;320.985555649996;321.50809261829403;323.2113642711783;5973.551983601231;6050.697465126748;6067.302260240073;6074.240719910005;6075.51395888329;5980.880382392361;5987.191729116568;5990.071538911465;5991.310999235089;5991.910121348184;4371.300591905296;4476.753778542179;4493.6405087593075;4588.587027167546;4617.960958784263;4555.271156614942;4590;4594.287885377755;4610.861674149781;4618.884527886796;4560.133574620828;4560.156371071363;4566.474855391622;4572.154151481986;4573.176828170928;4313.331433627793;4430.069685366077;4459.197344477992;4518.954810451886;4525.637948964075;3938.2714197873483;3948.8617898373705;3953.2686453006286;3954.8813535593977;3955.0903292242947;5682;5698.088305871082;5731.656100633975;5732.283464566929;5749.655020698771;5220.634555581111;5344;5349.3045904032615;5352.437994010612;5352.652077614943;6344.873102537924;6349.746010159588;6351.047342898602;6353.777617783401;6356.237251529846;4435.647127626105;4437.134758722051;4437.556244375569;4438;4439.64482841372;6554.180727409074;6564.376384243489;6566.047923051131;6569.178852643419;6600.2409722882;4470.374147619336;4550.362949187116;4555.772211389417;4573.794179261941;4582.243929751554;4626.190416677068;4626.653001024936;4629.027904140168;4629.0973260214605;4629.490756016836;5307.442718514578;5314.8937021260135;5317.893642127213;5323.760430780624;5325.547328477045;6418.679066046679;6420.1974753155855;6423.96788016057;6427.839304017437;6428.485721142298;1553.7824704541374;1576.1961399686243;1588.7060893734697;1592.936282548685;1595.6330044089925;1083.631565267797;1093.4478088565224;1099.69758316462;1108.4457771114444;1114.3146964616703;6211.720472578654;6217;6227;6229.190205273376;6233.875322493615;6553.967230163818;6603.900941485882;6606.834829129214;6618.933810661928;6626.56927299724;6085.496732417979;6215.409536094064;6215.9378406216265;6222.844428889314;6222.875542489215;3439.724822014283;3511.631278715749;3531.7174626029855;3615.1504396466808;3625.746197766131;6898.379145876896;6908.378245957888;6912.723491060301;6915.51591388598;6925.065116209348;6815;6822.454203663794;6822.624755638389;6829.658517074126;6832.214295355998;6692.966535167293;6835.726570937206;6874;6919.723211071601;6944.236134025355;6673.699683514156;6674.933250667431;6678.065070889982;6680.098186744727;6684.933150668626;6450.677466126769;6585.901211481736;6607.834804129935;6612.404883560503;6612.570182938093;6768.932310676831;6773.661316934035;6774.288699686462;6775.525713200226;6779.830504237236;4417.602415782595;4465.771912724027;4468.8212471502075;4468.865934021985;4471.932921006252;6650.767223147167;6651.301613330531;6652.201735791542;6656.400923916871;6661.367170118831;6338.90491642635;6443.484521238383;6458.22501299847;6464.773732919325;6487.610743355413;6393.2967373587935;6437.485001199799;6445.291017988111;6449.871002580016;6458.483321334376;6194.938050619436;6288.213973253343;6302.338254483397;6302.464290535356;6309.432151106423;6480.25477070143;6482.805515834532;6483.124778154889;6491.870162596627;6493;6334.113224148531;6334.968325158345;6341.175647165839;6345.841353966003;6360.4911607072245 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: |5;yui.html;747.7320445271955;779.0817540242646;803.3210589867176;831.3681601982494;860.780500972252;11588.478518466669;11961.58134465294;11978.880211197886;11981.580644677439;11992.640220793379;12475.812862807043;12543.18469299495;12547.121701480877;12586;12588.496460141583;782.9804254893617;816.5998660656295;823.8475881961826;841.4446465332882;842.1999100854182;810.712197170005;811.2293321344716;812.3095368936387;813.3940214540175;813.4631143445328;860.371928492201;863.8401895649293;864.5288317866778;865.969691060813;867.6442658510013;848.3594885861136;852.526847599582;853.2278288149234;854.6068808348167;854.8504011797942;608.9421504957022;627.1721327847243;628.4124343738619;630.1555915073777;630.3161070238797;13001.674958126028;13113.541026064042;13150.276734779583;13223.603291901161;13242.66893327665;11933.761324773543;12141.089418293575;12177.939110304393;12447.3776311185;12511.311877846714;9427.905720942772;9507.096825801538;9570.712878613653;9756.365836220622;10212.642557510451;3393.4231180699094;3429.1255729788745;3461.6884480396884;3546.7871927684164;3546.8758593449365;40897.79551102255;41711;41938.37092443616;42406.36390454115;50478;9129.817403652023;9136;9150.908490915139;9181.449113053104;9194.49430281341;15927.283272252776;15995.440159594358;16115.838841611669;16116.75824862628;18171.72742408865;7228.241034691282;7285.708571657075;7288.380487658608;7370.963145184347;7458.776236712907;4012.117334186474;4045.1505183911117;4079;4079.97960010204;4119.670426365884;3114.7508199343997;3142.6228852537383;3144.9213769655944;3150.1337132288804;3154.0695494828624;563.1890078287254;593.3413910559276;594.93753155918;605.4308949587373;622.5237693164732;641.8106658535743;642.8167972127922;667.3226674924955;670.6043434373743;699.4299645788666;8786.560671966376;9014.23379012778;9292.72121836346;9412.482313472825;9807.705768826947;656.4157899469476;664.4750646988824;664.6112024465644;676.2662511175331;684.1824020295799;3861.362875125573;4015.457913181683;4051.3517837145914;4069.1658210066867;4153.273177193979;56619.71690141523;57091.14363284554;57146.714266428404;57428.71285643629;57470.137947930816;49608.50391496111;50756.492435075175;50811.745941270055;50854;50977;36957;42663.36004959928;44565;44673.55326446694;45134.54865451369;8026.59867006659;8423.831523369376;8493.447925884797;8517.701880434155;8618.655253789779;35375.469367958984;35779.46330805092;36648;36736;37208.44187337246;41311.586884130775;45695;45870.31194532018;46376;46799.53200468088;9979.900200997898;11099.119172788434;11107.666769996913;11141.83287250708;11442.084633229488;12445.937770311091;12488.75022499563;12559.874401256237;13100.148490348098;13160.80258796138;11517.942410287895;11543.249688770204;11561.768764624492;11597.18819682614;11956.58151964677;11646;11893.345865977108;11918.582849600223;12231.082668800054;12320.445579948744;11572.710682233012;11734.191932742951;11992.460339284578;12344.691382715504;12359.75280494403;9334;9504.714858554255;9525.237980961369;9721.4653194075;9847.606095756377;8711.747039043532;8988.730338089868;9490.335676502578;9733.367331123454;9860.753481163028 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: __start_cc_report 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1359 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: __end_cc_report 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: __startTimestamp1448315987186__endTimestamp 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: Number of tests: 6 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: 13:59:47 INFO - 2015-11-23 13:59:47,195 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5453.49 Median:5612.80 stddev:613.29 (10.9%) stddev-sans-first:588.95 13:59:47 INFO - 2015-11-23 13:59:47,197 DEBUG : BROWSER_OUTPUT: Values: 3191.6 3267.2 3296.9 3304.5 3336.3 5125.7 5238.5 5360.2 5396.1 5411.4 5410.6 5437.4 5497.7 5498.3 5500.7 4936.1 4958.6 4960.0 4972.1 5000.4 4847.6 5080.9 5083.9 5106.6 5162.9 4903.8 4970.8 5042.6 5075.6 5081.5 4966.1 4974.7 4975.4 4985.5 5022.2 4793.1 4943.9 4965.1 4972.2 5024.7 5158.7 5180.4 5189.2 5198.7 5198.8 5822.7 5875.8 5973.7 5974.2 5984.4 5951.1 5951.1 5951.9 5965.9 5996.6 5464.9 5524.6 5534.5 5536.8 5547.3 5822.8 5847.2 5942.4 5945.5 6008.6 5875.4 5879.1 5908.7 5930.1 5933.5 5955.4 5958.5 5958.6 5959.8 5967.2 5679.7 5790.2 5927.0 5930.2 5940.0 5838.8 5851.1 5923.7 5933.0 5974.5 4526.7 4539.2 4548.5 4560.1 4563.5 3939.2 4023.4 4070.1 4077.0 4106.8 5537.7 5568.9 5600.3 5602.8 5671.9 5856.6 5864.1 5893.6 5899.1 5904.9 5439.5 5517.6 5548.5 5593.2 5595.6 5055.5 5095.5 5099.1 5110.2 5117.9 6106.9 6141.9 6190.4 6192.3 6194.3 5712.7 5837.9 5841.6 5987.5 6014.0 6061.7 6122.4 6123.4 6130.8 6133.3 5813.3 5869.4 5871.4 5872.1 5875.2 5661.3 5775.9 5791.2 5938.5 5940.2 5582.2 5599.6 5631.5 5638.4 5640.5 5611.2 5612.1 5612.8 5619.4 5626.0 5546.2 5605.0 5620.7 5622.2 5661.4 5762.1 5775.0 5814.6 5822.8 5870.4 5431.5 5548.1 5697.9 5702.0 5704.6 13:59:47 INFO - 2015-11-23 13:59:47,197 DEBUG : BROWSER_OUTPUT: 13:59:47 INFO - 2015-11-23 13:59:47,197 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45089.20 Median:32185.26 stddev:72776.16 (226.1%) stddev-sans-first:72903.55 13:59:47 INFO - 2015-11-23 13:59:47,197 DEBUG : BROWSER_OUTPUT: Values: 570.5 601.5 602.9 610.5 614.4 31306.7 32228.5 41744.6 42166.0 42213.4 44353.1 44663.6 45047.0 45075.5 45139.5 7350.5 7605.8 8683.6 8821.3 8854.4 10456.7 10678.7 10693.5 10714.1 10714.4 10361.5 10428.6 10451.1 10481.8 10487.4 7689.0 7878.2 7884.4 10320.3 10577.5 10192.4 10327.8 10331.8 10340.1 10344.5 40584.2 41025.0 41040.2 41054.2 41098.6 3280.7 3320.6 3338.6 3367.4 3401.3 30645.6 30704.7 30953.5 31230.8 31610.5 3928.2 3930.6 3999.8 4054.5 4066.3 267435.0 267563.0 268283.7 269134.0 269810.0 56471.4 57458.4 58078.4 58356.7 58816.1 60588.4 60596.0 60954.7 61002.4 61072.7 13564.8 13662.5 13673.5 13674.2 13679.4 10893.9 10954.7 10966.1 10987.6 11043.5 19104.0 19199.0 19268.9 19293.4 19457.1 7237.2 7484.4 7584.6 7603.7 7614.6 944.0 968.3 977.6 1013.2 1154.9 27429.0 27872.9 28183.0 28252.7 28301.7 1063.2 1096.6 1097.1 1099.1 1116.4 13434.5 13867.9 13913.8 13979.7 14003.4 311212.0 314861.0 315562.0 316058.0 316578.0 52239.0 52569.5 52660.0 52718.5 52890.0 1143.0 1307.7 1309.9 1319.7 1332.0 20927.7 29278.0 29543.6 29808.7 29811.7 50976.0 51387.7 51654.0 51667.0 51764.5 221114.0 222386.0 222990.0 282462.0 286779.0 32142.0 32509.9 32566.8 32854.8 32902.5 38703.0 39091.2 39755.4 40071.6 40205.4 34141.8 36078.8 36812.4 37142.8 37220.1 36175.5 36348.0 36814.4 37112.8 37571.4 33002.3 33480.5 33907.0 33952.8 34011.5 29372.6 31008.8 31100.5 31428.4 31485.5 23992.5 24055.1 24085.3 24345.1 25718.0 13:59:47 INFO - 2015-11-23 13:59:47,197 DEBUG : BROWSER_OUTPUT: 13:59:47 INFO - 2015-11-23 13:59:47,197 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28616.74 Median:35825.00 stddev:84894.04 (237.0%) stddev-sans-first:85105.65 13:59:47 INFO - 2015-11-23 13:59:47,197 DEBUG : BROWSER_OUTPUT: Values: 349.7 349.9 353.4 354.6 355.5 14112.0 14266.0 16369.5 17057.7 18143.1 15043.4 16254.0 16276.7 16317.5 17379.0 5234.5 5356.9 5361.0 5361.2 5368.0 3912.0 4008.7 5268.4 5308.9 5675.1 5889.1 5920.4 5923.3 5933.6 5973.8 5722.0 5860.9 5862.2 5871.4 5885.1 5206.7 5213.3 5215.6 5215.8 5217.1 20328.4 20429.7 20678.4 20940.2 20945.3 2150.1 2162.0 2257.2 2327.4 2381.9 13202.7 13396.6 13452.0 13594.3 13662.3 1881.3 1922.3 1933.2 1988.5 2002.5 58337.4 59504.0 61914.0 65007.3 65713.3 9663.1 9782.4 9835.9 9851.1 9889.2 12769.9 12778.4 12807.2 12826.7 12847.6 7286.4 7368.4 7398.2 7451.5 7462.1 6866.6 6984.8 7046.3 7192.5 7285.9 4210.6 4211.6 4352.1 4352.8 4368.8 1695.5 1739.2 1743.1 1757.9 1788.6 875.6 959.8 987.4 1010.0 1018.9 15520.3 16375.7 16474.8 17015.2 17073.5 823.2 873.7 918.5 950.5 957.4 7431.7 9421.5 9964.5 10207.4 10313.5 506416.0 506569.0 519653.0 528874.0 532709.0 80955.6 82247.6 82945.6 83220.6 84481.6 1096.9 1124.4 1143.6 1156.9 1160.2 15071.2 15585.9 15608.7 15791.9 15813.4 35218.8 35550.0 36100.0 37201.3 37221.9 47918.3 48828.3 50951.5 51580.7 52003.5 8970.4 9082.2 9102.1 9140.5 9239.9 12898.5 17431.3 17832.0 17861.3 17919.7 14300.8 14353.5 14770.1 14782.4 14894.9 14350.3 14414.2 14414.9 14552.0 14900.9 14734.0 14812.4 15113.4 15197.9 15256.2 7867.6 9137.9 9592.1 9774.9 9779.2 14517.0 14741.0 15086.5 15131.5 15214.0 13:59:47 INFO - 2015-11-23 13:59:47,197 DEBUG : BROWSER_OUTPUT: 13:59:47 INFO - 2015-11-23 13:59:47,197 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5250.77 Median:6250.97 stddev:2726.24 (43.6%) stddev-sans-first:2717.57 13:59:47 INFO - 2015-11-23 13:59:47,198 DEBUG : BROWSER_OUTPUT: Values: 1283.0 1452.9 1458.2 1712.3 1777.4 6328.2 7028.2 7177.0 7186.0 7190.1 7297.8 7417.7 7422.0 7435.2 7436.3 1897.8 1926.7 1927.7 2063.6 2066.8 2200.5 2238.2 2243.2 2243.5 2246.0 2173.8 2175.9 2262.6 2263.2 2266.8 1920.5 1953.7 2166.5 2210.8 2259.1 2209.3 2235.9 2246.3 2249.7 2250.4 7566.8 7626.1 7627.7 7633.6 7633.8 4551.0 4559.0 4576.2 4584.3 4646.3 2924.5 2924.6 2972.6 2991.5 3023.1 949.6 949.6 955.8 994.1 994.6 10140.9 10151.5 10163.5 10177.3 10184.2 4004.6 4126.8 4155.3 4228.0 4228.7 8818.8 8828.9 8831.9 8834.1 8839.5 4528.9 4553.9 4554.8 4560.7 4618.4 4128.7 4163.9 4164.6 4166.3 4182.7 3471.5 3506.1 3524.6 3528.3 3534.6 1157.7 1160.2 1169.6 1176.5 1180.1 1291.0 1339.7 1349.4 1364.3 1379.2 7148.6 7183.0 7188.0 7198.5 7205.1 493.4 495.3 503.0 503.6 503.6 5574.7 5681.1 5707.1 5724.1 5726.5 8776.4 8857.4 8979.4 9004.5 9160.2 9045.6 9063.0 9063.8 9067.7 9082.0 6824.5 6931.7 7017.1 7029.4 7033.6 7051.2 7102.5 7106.7 7109.9 7111.1 7767.8 7989.2 7997.0 8079.3 8096.9 7943.4 8096.9 8142.3 8143.1 8252.2 6797.5 6807.7 6837.6 6838.5 6859.3 7348.9 7371.6 7378.8 7379.8 7384.0 6802.8 6816.3 6816.6 6831.5 6870.0 5883.2 5883.4 6381.0 6781.5 6867.0 6608.9 6645.0 6664.2 6666.0 6666.6 6209.2 6231.7 6247.2 6247.8 6254.1 6287.9 6485.4 6504.0 6530.6 6536.2 13:59:47 INFO - 2015-11-23 13:59:47,198 DEBUG : BROWSER_OUTPUT: 13:59:47 INFO - 2015-11-23 13:59:47,199 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5352.83 Median:6140.22 stddev:1632.70 (26.6%) stddev-sans-first:1592.82 13:59:47 INFO - 2015-11-23 13:59:47,199 DEBUG : BROWSER_OUTPUT: Values: 310.7 318.5 321.0 321.5 323.2 5973.6 6050.7 6067.3 6074.2 6075.5 5980.9 5987.2 5990.1 5991.3 5991.9 4371.3 4476.8 4493.6 4588.6 4618.0 4555.3 4590.0 4594.3 4610.9 4618.9 4560.1 4560.2 4566.5 4572.2 4573.2 4313.3 4430.1 4459.2 4519.0 4525.6 3938.3 3948.9 3953.3 3954.9 3955.1 5682.0 5698.1 5731.7 5732.3 5749.7 5220.6 5344.0 5349.3 5352.4 5352.7 6344.9 6349.7 6351.0 6353.8 6356.2 4435.6 4437.1 4437.6 4438.0 4439.6 6554.2 6564.4 6566.0 6569.2 6600.2 4470.4 4550.4 4555.8 4573.8 4582.2 4626.2 4626.7 4629.0 4629.1 4629.5 5307.4 5314.9 5317.9 5323.8 5325.5 6418.7 6420.2 6424.0 6427.8 6428.5 1553.8 1576.2 1588.7 1592.9 1595.6 1083.6 1093.4 1099.7 1108.4 1114.3 6211.7 6217.0 6227.0 6229.2 6233.9 6554.0 6603.9 6606.8 6618.9 6626.6 6085.5 6215.4 6215.9 6222.8 6222.9 3439.7 3511.6 3531.7 3615.2 3625.7 6898.4 6908.4 6912.7 6915.5 6925.1 6815.0 6822.5 6822.6 6829.7 6832.2 6693.0 6835.7 6874.0 6919.7 6944.2 6673.7 6674.9 6678.1 6680.1 6684.9 6450.7 6585.9 6607.8 6612.4 6612.6 6768.9 6773.7 6774.3 6775.5 6779.8 4417.6 4465.8 4468.8 4468.9 4471.9 6650.8 6651.3 6652.2 6656.4 6661.4 6338.9 6443.5 6458.2 6464.8 6487.6 6393.3 6437.5 6445.3 6449.9 6458.5 6194.9 6288.2 6302.3 6302.5 6309.4 6480.3 6482.8 6483.1 6491.9 6493.0 6334.1 6335.0 6341.2 6345.8 6360.5 13:59:47 INFO - 2015-11-23 13:59:47,199 DEBUG : BROWSER_OUTPUT: 13:59:47 INFO - 2015-11-23 13:59:47,199 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13485.28 Median:50617.25 stddev:15519.69 (30.7%) stddev-sans-first:15533.75 13:59:47 INFO - 2015-11-23 13:59:47,199 DEBUG : BROWSER_OUTPUT: Values: 747.7 779.1 803.3 831.4 860.8 11588.5 11961.6 11978.9 11981.6 11992.6 12475.8 12543.2 12547.1 12586.0 12588.5 783.0 816.6 823.8 841.4 842.2 810.7 811.2 812.3 813.4 813.5 860.4 863.8 864.5 866.0 867.6 848.4 852.5 853.2 854.6 854.9 608.9 627.2 628.4 630.2 630.3 13001.7 13113.5 13150.3 13223.6 13242.7 11933.8 12141.1 12177.9 12447.4 12511.3 9427.9 9507.1 9570.7 9756.4 10212.6 3393.4 3429.1 3461.7 3546.8 3546.9 40897.8 41711.0 41938.4 42406.4 50478.0 9129.8 9136.0 9150.9 9181.4 9194.5 15927.3 15995.4 16115.8 16116.8 18171.7 7228.2 7285.7 7288.4 7371.0 7458.8 4012.1 4045.2 4079.0 4080.0 4119.7 3114.8 3142.6 3144.9 3150.1 3154.1 563.2 593.3 594.9 605.4 622.5 641.8 642.8 667.3 670.6 699.4 8786.6 9014.2 9292.7 9412.5 9807.7 656.4 664.5 664.6 676.3 684.2 3861.4 4015.5 4051.4 4069.2 4153.3 56619.7 57091.1 57146.7 57428.7 57470.1 49608.5 50756.5 50811.7 50854.0 50977.0 36957.0 42663.4 44565.0 44673.6 45134.5 8026.6 8423.8 8493.4 8517.7 8618.7 35375.5 35779.5 36648.0 36736.0 37208.4 41311.6 45695.0 45870.3 46376.0 46799.5 9979.9 11099.1 11107.7 11141.8 11442.1 12445.9 12488.8 12559.9 13100.1 13160.8 11517.9 11543.2 11561.8 11597.2 11956.6 11646.0 11893.3 11918.6 12231.1 12320.4 11572.7 11734.2 11992.5 12344.7 12359.8 9334.0 9504.7 9525.2 9721.5 9847.6 8711.7 8988.7 9490.3 9733.4 9860.8 13:59:47 INFO - 2015-11-23 13:59:47,199 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:59:47 INFO - 2015-11-23 13:59:47,199 DEBUG : BROWSER_OUTPUT: 13:59:47 INFO - 2015-11-23 13:59:47,893 INFO : Browser exited with error code: 0 13:59:47 INFO - 2015-11-23 13:59:47,910 INFO : Completed test dromaeo_css (00:18:32) 13:59:47 INFO - 2015-11-23 13:59:47,910 INFO : Starting test kraken 13:59:47 INFO - 2015-11-23 13:59:47,910 DEBUG : operating with platform_type : linux_ 13:59:47 INFO - 2015-11-23 13:59:47,910 INFO : Initialising browser for kraken test... 13:59:47 INFO - 2015-11-23 13:59:47,916 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmK7yen/profile http://localhost:59165/getInfo.html 13:59:51 INFO - 2015-11-23 13:59:51,634 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:59:51 INFO - 2015-11-23 13:59:51,634 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:59:51 INFO - 2015-11-23 13:59:51,634 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:59:51 INFO - 2015-11-23 13:59:51,634 DEBUG : BROWSER_OUTPUT: __metrics 13:59:51 INFO - 2015-11-23 13:59:51,771 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:59:52 INFO - 2015-11-23 13:59:52,921 INFO : Browser initialized. 13:59:52 INFO - 2015-11-23 13:59:52,922 INFO : Running cycle 1/1 for kraken test... 13:59:52 INFO - 2015-11-23 13:59:52,922 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmK7yen/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:00:04 INFO - 2015-11-23 14:00:04,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 140095488 14:00:04 INFO - 2015-11-23 14:00:04,631 DEBUG : BROWSER_OUTPUT: 14:00:07 INFO - 2015-11-23 14:00:07,106 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59165/tests/kraken/driver.html?testName=audio-beat-detection) 14:00:07 INFO - 2015-11-23 14:00:07,688 DEBUG : BROWSER_OUTPUT: RSS: Main: 179032064 14:00:07 INFO - 2015-11-23 14:00:07,688 DEBUG : BROWSER_OUTPUT: 14:00:10 INFO - 2015-11-23 14:00:10,304 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59165/tests/kraken/driver.html?testName=audio-dft) 14:00:10 INFO - 2015-11-23 14:00:10,885 DEBUG : BROWSER_OUTPUT: RSS: Main: 170659840 14:00:10 INFO - 2015-11-23 14:00:10,885 DEBUG : BROWSER_OUTPUT: 14:00:13 INFO - 2015-11-23 14:00:13,709 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59165/tests/kraken/driver.html?testName=audio-fft) 14:00:14 INFO - 2015-11-23 14:00:14,269 DEBUG : BROWSER_OUTPUT: RSS: Main: 151769088 14:00:14 INFO - 2015-11-23 14:00:14,270 DEBUG : BROWSER_OUTPUT: 14:00:16 INFO - 2015-11-23 14:00:16,221 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59165/tests/kraken/driver.html?testName=audio-oscillator) 14:00:16 INFO - 2015-11-23 14:00:16,782 DEBUG : BROWSER_OUTPUT: RSS: Main: 153853952 14:00:16 INFO - 2015-11-23 14:00:16,783 DEBUG : BROWSER_OUTPUT: 14:00:18 INFO - 2015-11-23 14:00:18,957 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59165/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:00:19 INFO - 2015-11-23 14:00:19,509 DEBUG : BROWSER_OUTPUT: RSS: Main: 155381760 14:00:19 INFO - 2015-11-23 14:00:19,510 DEBUG : BROWSER_OUTPUT: 14:00:22 INFO - 2015-11-23 14:00:22,430 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59165/tests/kraken/driver.html?testName=imaging-darkroom) 14:00:22 INFO - 2015-11-23 14:00:22,999 DEBUG : BROWSER_OUTPUT: RSS: Main: 155226112 14:00:23 INFO - 2015-11-23 14:00:22,999 DEBUG : BROWSER_OUTPUT: 14:00:26 INFO - 2015-11-23 14:00:26,349 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59165/tests/kraken/driver.html?testName=imaging-desaturate) 14:00:26 INFO - 2015-11-23 14:00:26,930 DEBUG : BROWSER_OUTPUT: RSS: Main: 156712960 14:00:26 INFO - 2015-11-23 14:00:26,930 DEBUG : BROWSER_OUTPUT: 14:00:29 INFO - 2015-11-23 14:00:29,688 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59165/tests/kraken/driver.html?testName=json-parse-financial) 14:00:30 INFO - 2015-11-23 14:00:30,269 DEBUG : BROWSER_OUTPUT: RSS: Main: 156385280 14:00:30 INFO - 2015-11-23 14:00:30,269 DEBUG : BROWSER_OUTPUT: 14:00:31 INFO - 2015-11-23 14:00:31,857 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59165/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:00:32 INFO - 2015-11-23 14:00:32,417 DEBUG : BROWSER_OUTPUT: RSS: Main: 169877504 14:00:32 INFO - 2015-11-23 14:00:32,417 DEBUG : BROWSER_OUTPUT: 14:00:34 INFO - 2015-11-23 14:00:34,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59165/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:00:34 INFO - 2015-11-23 14:00:34,814 DEBUG : BROWSER_OUTPUT: RSS: Main: 167751680 14:00:34 INFO - 2015-11-23 14:00:34,814 DEBUG : BROWSER_OUTPUT: 14:00:36 INFO - 2015-11-23 14:00:36,865 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59165/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:00:37 INFO - 2015-11-23 14:00:37,446 DEBUG : BROWSER_OUTPUT: RSS: Main: 173121536 14:00:37 INFO - 2015-11-23 14:00:37,446 DEBUG : BROWSER_OUTPUT: 14:00:39 INFO - 2015-11-23 14:00:39,881 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59165/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:00:40 INFO - 2015-11-23 14:00:40,455 DEBUG : BROWSER_OUTPUT: RSS: Main: 180383744 14:00:40 INFO - 2015-11-23 14:00:40,455 DEBUG : BROWSER_OUTPUT: 14:00:43 INFO - 2015-11-23 14:00:43,043 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59165/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:00:43 INFO - 2015-11-23 14:00:43,617 DEBUG : BROWSER_OUTPUT: RSS: Main: 179892224 14:00:43 INFO - 2015-11-23 14:00:43,617 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,285 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59165/tests/kraken/driver.html?testName=ai-astar) 14:00:45 INFO - 2015-11-23 14:00:45,531 DEBUG : BROWSER_OUTPUT: RSS: Main: 239284224 14:00:45 INFO - 2015-11-23 14:00:45,531 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,532 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:00:45 INFO - 2015-11-23 14:00:45,532 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:00:45 INFO - 2015-11-23 14:00:45,532 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:00:45 INFO - 2015-11-23 14:00:45,532 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:00:45 INFO - 2015-11-23 14:00:45,532 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;113;104;109;111;111;99;110;110;101 14:00:45 INFO - 2015-11-23 14:00:45,532 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;124;154;162;126;152;149;125;174;127 14:00:45 INFO - 2015-11-23 14:00:45,532 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;160;162;161;163;160;160;164;161;160 14:00:45 INFO - 2015-11-23 14:00:45,533 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;86;94;71;71;72;95;93;71 14:00:45 INFO - 2015-11-23 14:00:45,533 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;94;150;92;94;94;135;93;94;136 14:00:45 INFO - 2015-11-23 14:00:45,533 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;110;109;109;109;109;109;109 14:00:45 INFO - 2015-11-23 14:00:45,533 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;145;145;146;145;145;147;145;145 14:00:45 INFO - 2015-11-23 14:00:45,533 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;97;96;96;95;96;96;95;96;95 14:00:45 INFO - 2015-11-23 14:00:45,533 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;65;62;62;63;64;65;65;63;60;60 14:00:45 INFO - 2015-11-23 14:00:45,533 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;68;69;68;68;68;68;68;68;68 14:00:45 INFO - 2015-11-23 14:00:45,533 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;85;85;85;84;84;84;85;85;85 14:00:45 INFO - 2015-11-23 14:00:45,533 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;123;131;117;133;121;129;115;116;124 14:00:45 INFO - 2015-11-23 14:00:45,534 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;149;159;163;150;152;141;154;149;150;137 14:00:45 INFO - 2015-11-23 14:00:45,534 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;68;66;66;71;66;68;69;67;66 14:00:45 INFO - 2015-11-23 14:00:45,534 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:00:45 INFO - 2015-11-23 14:00:45,534 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:00:45 INFO - 2015-11-23 14:00:45,534 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,933 14:00:45 INFO - 2015-11-23 14:00:45,534 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:00:45 INFO - 2015-11-23 14:00:45,534 DEBUG : BROWSER_OUTPUT: __startTimestamp1448316045531__endTimestamp 14:00:45 INFO - 2015-11-23 14:00:45,535 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,538 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:00:45 INFO - 2015-11-23 14:00:45,538 DEBUG : BROWSER_OUTPUT: Number of tests: 14 14:00:45 INFO - 2015-11-23 14:00:45,538 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,538 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.80 Median:110.50 stddev:4.73 (4.3%) stddev-sans-first:4.95 14:00:45 INFO - 2015-11-23 14:00:45,538 DEBUG : BROWSER_OUTPUT: Values: 110.0 113.0 104.0 109.0 111.0 111.0 99.0 110.0 110.0 101.0 14:00:45 INFO - 2015-11-23 14:00:45,538 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,538 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.90 Median:150.50 stddev:17.61 (11.7%) stddev-sans-first:18.66 14:00:45 INFO - 2015-11-23 14:00:45,538 DEBUG : BROWSER_OUTPUT: Values: 146.0 124.0 154.0 162.0 126.0 152.0 149.0 125.0 174.0 127.0 14:00:45 INFO - 2015-11-23 14:00:45,538 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:1.42 (0.9%) stddev-sans-first:1.48 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: Values: 162.0 160.0 162.0 161.0 163.0 160.0 160.0 164.0 161.0 160.0 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.70 Median:79.00 stddev:10.85 (13.7%) stddev-sans-first:11.15 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 86.0 94.0 71.0 71.0 72.0 95.0 93.0 71.0 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.60 Median:94.00 stddev:22.94 (24.4%) stddev-sans-first:23.80 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: Values: 94.0 94.0 150.0 92.0 94.0 94.0 135.0 93.0 94.0 136.0 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 14:00:45 INFO - 2015-11-23 14:00:45,539 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 145.0 145.0 146.0 145.0 145.0 147.0 145.0 145.0 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: Values: 97.0 97.0 96.0 96.0 95.0 96.0 96.0 95.0 96.0 95.0 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:62.90 Median:63.50 stddev:1.91 (3.0%) stddev-sans-first:1.87 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: Values: 65.0 62.0 62.0 63.0 64.0 65.0 65.0 63.0 60.0 60.0 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 69.0 68.0 68.0 68.0 68.0 68.0 68.0 68.0 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.00 stddev:0.52 (0.6%) stddev-sans-first:0.50 14:00:45 INFO - 2015-11-23 14:00:45,540 DEBUG : BROWSER_OUTPUT: Values: 84.0 85.0 85.0 85.0 84.0 84.0 84.0 85.0 85.0 85.0 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:123.50 stddev:6.31 (5.1%) stddev-sans-first:6.65 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: Values: 121.0 123.0 131.0 117.0 133.0 121.0 129.0 115.0 116.0 124.0 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.40 Median:151.00 stddev:7.60 (5.0%) stddev-sans-first:8.05 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: Values: 149.0 159.0 163.0 150.0 152.0 141.0 154.0 149.0 150.0 137.0 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.70 (2.5%) stddev-sans-first:1.74 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: Values: 66.0 68.0 66.0 66.0 71.0 66.0 68.0 69.0 67.0 66.0 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:00:45 INFO - 2015-11-23 14:00:45,541 DEBUG : BROWSER_OUTPUT: 14:00:46 INFO - 2015-11-23 14:00:46,429 INFO : Browser exited with error code: 0 14:00:46 INFO - 2015-11-23 14:00:46,478 INFO : Completed test kraken (00:00:58) 14:00:46 INFO - 2015-11-23 14:00:46,479 INFO : Starting test v8_7 14:00:46 INFO - 2015-11-23 14:00:46,479 DEBUG : operating with platform_type : linux_ 14:00:46 INFO - 2015-11-23 14:00:46,479 INFO : Initialising browser for v8_7 test... 14:00:46 INFO - 2015-11-23 14:00:46,485 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpv1lhZZ/profile http://localhost:59165/getInfo.html 14:00:50 INFO - 2015-11-23 14:00:50,190 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:00:50 INFO - 2015-11-23 14:00:50,190 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:00:50 INFO - 2015-11-23 14:00:50,190 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:00:50 INFO - 2015-11-23 14:00:50,190 DEBUG : BROWSER_OUTPUT: __metrics 14:00:50 INFO - 2015-11-23 14:00:50,339 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:00:51 INFO - 2015-11-23 14:00:51,491 INFO : Browser initialized. 14:00:51 INFO - 2015-11-23 14:00:51,491 INFO : Running cycle 1/1 for v8_7 test... 14:00:51 INFO - 2015-11-23 14:00:51,491 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpv1lhZZ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:01:03 INFO - 2015-11-23 14:01:03,310 DEBUG : BROWSER_OUTPUT: RSS: Main: 140005376 14:01:03 INFO - 2015-11-23 14:01:03,311 DEBUG : BROWSER_OUTPUT: 14:01:24 INFO - 2015-11-23 14:01:24,804 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59165/tests/v8_7/run.html (next: http://localhost:59165/tests/v8_7/run.html) 14:01:25 INFO - 2015-11-23 14:01:25,062 DEBUG : BROWSER_OUTPUT: RSS: Main: 610045952 14:01:25 INFO - 2015-11-23 14:01:25,062 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,063 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:01:25 INFO - 2015-11-23 14:01:25,063 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:01:25 INFO - 2015-11-23 14:01:25,063 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:01:25 INFO - 2015-11-23 14:01:25,063 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:01:25 INFO - 2015-11-23 14:01:25,063 DEBUG : BROWSER_OUTPUT: |0;Richards;186.84603886397608 14:01:25 INFO - 2015-11-23 14:01:25,063 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;189.10741301059002 14:01:25 INFO - 2015-11-23 14:01:25,063 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.42624789680315 14:01:25 INFO - 2015-11-23 14:01:25,064 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4970.297029702971 14:01:25 INFO - 2015-11-23 14:01:25,064 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1938.1044487427466 14:01:25 INFO - 2015-11-23 14:01:25,064 DEBUG : BROWSER_OUTPUT: |5;Earley;789.8894154818325 14:01:25 INFO - 2015-11-23 14:01:25,064 DEBUG : BROWSER_OUTPUT: |6;Boyer;12095.238095238095 14:01:25 INFO - 2015-11-23 14:01:25,064 DEBUG : BROWSER_OUTPUT: |7;RegExp;26315.78947368421 14:01:25 INFO - 2015-11-23 14:01:25,064 DEBUG : BROWSER_OUTPUT: |8;Splay;602.0469596628537 14:01:25 INFO - 2015-11-23 14:01:25,064 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 14:01:25 INFO - 2015-11-23 14:01:25,065 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:01:25 INFO - 2015-11-23 14:01:25,065 DEBUG : BROWSER_OUTPUT: __startTimestamp1448316085055__endTimestamp 14:01:25 INFO - 2015-11-23 14:01:25,065 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,065 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:01:25 INFO - 2015-11-23 14:01:25,065 DEBUG : BROWSER_OUTPUT: Number of tests: 10 14:01:25 INFO - 2015-11-23 14:01:25,065 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,065 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:186.85 Median:186.85 stddev:0.00 (0.0%) 14:01:25 INFO - 2015-11-23 14:01:25,066 DEBUG : BROWSER_OUTPUT: Values: 186.8 14:01:25 INFO - 2015-11-23 14:01:25,066 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,066 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:189.11 Median:189.11 stddev:0.00 (0.0%) 14:01:25 INFO - 2015-11-23 14:01:25,066 DEBUG : BROWSER_OUTPUT: Values: 189.1 14:01:25 INFO - 2015-11-23 14:01:25,066 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,066 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.43 Median:280.43 stddev:0.00 (0.0%) 14:01:25 INFO - 2015-11-23 14:01:25,066 DEBUG : BROWSER_OUTPUT: Values: 280.4 14:01:25 INFO - 2015-11-23 14:01:25,066 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,067 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4970.30 Median:4970.30 stddev:0.00 (0.0%) 14:01:25 INFO - 2015-11-23 14:01:25,067 DEBUG : BROWSER_OUTPUT: Values: 4970.3 14:01:25 INFO - 2015-11-23 14:01:25,067 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,067 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1938.10 Median:1938.10 stddev:0.00 (0.0%) 14:01:25 INFO - 2015-11-23 14:01:25,067 DEBUG : BROWSER_OUTPUT: Values: 1938.1 14:01:25 INFO - 2015-11-23 14:01:25,067 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,067 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:789.89 Median:789.89 stddev:0.00 (0.0%) 14:01:25 INFO - 2015-11-23 14:01:25,068 DEBUG : BROWSER_OUTPUT: Values: 789.9 14:01:25 INFO - 2015-11-23 14:01:25,068 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,068 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12095.24 Median:12095.24 stddev:0.00 (0.0%) 14:01:25 INFO - 2015-11-23 14:01:25,068 DEBUG : BROWSER_OUTPUT: Values: 12095.2 14:01:25 INFO - 2015-11-23 14:01:25,068 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,068 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26315.79 Median:26315.79 stddev:0.00 (0.0%) 14:01:25 INFO - 2015-11-23 14:01:25,068 DEBUG : BROWSER_OUTPUT: Values: 26315.8 14:01:25 INFO - 2015-11-23 14:01:25,068 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,069 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:602.05 Median:602.05 stddev:0.00 (0.0%) 14:01:25 INFO - 2015-11-23 14:01:25,069 DEBUG : BROWSER_OUTPUT: Values: 602.0 14:01:25 INFO - 2015-11-23 14:01:25,069 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,069 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 14:01:25 INFO - 2015-11-23 14:01:25,069 DEBUG : BROWSER_OUTPUT: Values: 4830.9 14:01:25 INFO - 2015-11-23 14:01:25,069 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:01:25 INFO - 2015-11-23 14:01:25,069 DEBUG : BROWSER_OUTPUT: 14:01:25 INFO - 2015-11-23 14:01:25,901 INFO : Browser exited with error code: 0 14:01:25 INFO - 2015-11-23 14:01:25,920 INFO : Completed test v8_7 (00:00:39) 14:01:25 INFO - 2015-11-23 14:01:25,985 INFO : Completed test suite (00:20:11) 14:01:25 INFO - 2015-11-23 14:01:25,985 DEBUG : Working with test: dromaeo_css 14:01:25 INFO - 2015-11-23 14:01:25,985 DEBUG : Generating results file: dromaeo_css 14:01:25 INFO - 2015-11-23 14:01:25,986 DEBUG : Working with test: kraken 14:01:25 INFO - 2015-11-23 14:01:25,987 DEBUG : Generating results file: kraken 14:01:25 INFO - 2015-11-23 14:01:25,987 INFO : javascript benchmark 14:01:25 INFO - 2015-11-23 14:01:25,987 DEBUG : Working with test: v8_7 14:01:25 INFO - 2015-11-23 14:01:25,987 DEBUG : Generating results file: v8_7 14:01:25 INFO - 2015-11-23 14:01:25,988 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:01:26 INFO - 2015-11-23 14:01:26,043 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 14:01:26 INFO - 2015-11-23 14:01:26,043 DEBUG : process_Request line: dromaeo_css 6975.73 graph.html#tests=[[72,64,35]] 14:01:26 INFO - 2015-11-23 14:01:26,043 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:01:26 INFO - 2015-11-23 14:01:26,076 DEBUG : process_Request line: kraken 1507.00 graph.html#tests=[[232,64,35]] 14:01:26 INFO - 2015-11-23 14:01:26,076 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:01:26 INFO - 2015-11-23 14:01:26,109 DEBUG : process_Request line: v8_7 19724.12 graph.html#tests=[[230,64,35]] 14:01:26 INFO - 2015-11-23 14:01:26,110 INFO : javascript benchmark 14:01:26 INFO - 2015-11-23 14:01:26,114 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3191.632962209346, 3267.1995361136524, 3296.8681252749893, 3304.5208444775494, 3336.332733453309, 5125.66683165594, 5238.476152384759, 5360.169173778066, 5396.109641909085, 5411.404745477999, 5410.56715462763, 5437.429069947658, 5497.670139791615, 5498.3127109111365, 5500.697461639608, 4936.06214819185, 4958.553730164284, 4960.032793605248, 4972.080165169437, 5000.374953130859, 4847.636427267969, 5080.949190508103, 5083.949160508385, 5106.5659418949435, 5162.915787684591, 4903.803847846099, 4970.776315065831, 5042.571381432583, 5075.6447048706605, 5081.491850814926, 4966.056449274626, 4974.676646018033, 4975.427825800043, 4985.52637499437, 5022.17134172861, 4793.065352256312, 4943.8516844494725, 4965.131102057162, 4972.229304457815, 5024.748762561857, 5158.690478571298, 5180.43015268324, 5189.247559103909, 5198.662086964335, 5198.766055527472, 5822.708864556797, 5875.823725288248, 5973.731182096816, 5974.193483879708, 5984.371640977722, 5951.137085122633, 5951.137085122633, 5951.940480595182, 5965.850853728648, 5996.610220335664, 5464.863378415492, 5524.558035357162, 5534.501894829445, 5536.806211782568, 5547.306586676666, 5822.82531524055, 5847.181394604759, 5942.37605051472, 5945.524358051345, 6008.639481631116, 5875.353711091777, 5879.118132280192, 5908.704564771744, 5930.05119180929, 5933.525317974551, 5955.374685657944, 5958.493528050164, 5958.612690175211, 5959.791407300723, 5967.224260846062, 5679.74441150141, 5790.218320526759, 5927.0, 5930.169776231331, 5939.970300148558, 5838.824835254949, 5851.093080572518, 5923.703814809329, 5933.021051526536, 5974.4622983930785, 4526.683132180781, 4539.16025535277, 4548.4541854977615, 4560.1335746208115, 4563.543645635476, 3939.1727737175515, 4023.376376661622, 4070.063885306396, 4076.980754811297, 4106.815193316308, 5537.723113844291, 5568.888622227532, 5600.27196464457, 5602.775888964396, 5671.886562268732, 5856.648601083949, 5864.149698293809, 5893.5874488785375, 5899.115132730039, 5904.852378690567, 5439.48324909133, 5517.586181036439, 5548.528375088082, 5593.161025846156, 5595.580331475156, 5055.545000949932, 5095.464976177448, 5099.082165210298, 5110.233464980209, 5117.923231151536, 6106.9083963739695, 6141.938580614226, 6190.380961903774, 6192.287886893065, 6194.318624951252, 5712.742926568231, 5837.94162058374, 5841.649501029951, 5987.491063259672, 6014.0, 6061.6666083365835, 6122.377674734244, 6123.387661233841, 6130.765048853685, 6133.26400831903, 5813.331466881194, 5869.35437101927, 5871.412858714094, 5872.119182122716, 5875.236219291465, 5661.348944871393, 5775.884482310246, 5791.247137872049, 5938.524918006636, 5940.227770389822, 5582.162675598693, 5599.636023658449, 5631.465010824129, 5638.407967163389, 5640.464155905182, 5611.214429979725, 5612.102063669957, 5612.803551875665, 5619.353774316006, 5625.971870140623, 5546.168074788814, 5605.047141985954, 5620.662760234399, 5622.156676498558, 5661.377248502744, 5762.1068734346245, 5775.0471172257785, 5814.622049566765, 5822.796202132905, 5870.442307980735, 5431.4840090191365, 5548.0845660465575, 5697.886042279214, 5701.971490142523, 5704.60067795267], "unit": "ms", "name": "dojo.html", "value": 5413.512276172902}, {"lowerIsBetter": false, "replicates": [570.5180672126463, 601.527800676469, 602.9125776762372, 610.5420934299276, 614.3702704727655, 31306.686933130663, 32228.516572251407, 41744.58255417445, 42166.0, 42213.366799497955, 44353.11293774131, 44663.553364466345, 45047.0, 45075.54924450763, 45139.54860451395, 7350.522216055966, 7605.8478830423355, 8683.56582170892, 8821.25019373354, 8854.424462409956, 10456.738581535446, 10678.733031674192, 10693.518791654395, 10714.142868570534, 10714.357138571671, 10361.533730982086, 10428.63499777508, 10451.11165550929, 10481.790364192711, 10487.37075775456, 7688.961555192244, 7878.212178782133, 7884.369250459949, 10320.277580569364, 10577.471126443725, 10192.369220924616, 10327.84508232377, 10331.79336413275, 10340.121089707383, 10344.482775861177, 40584.188316233805, 41025.0, 41040.179196416204, 41054.178916421806, 41098.58901410978, 3280.6555311692405, 3320.568326117589, 3338.6327503974544, 3367.3602015616952, 3401.302732939766, 30645.620947057436, 30704.732076930206, 30953.53569696456, 31230.84384578085, 31610.525842112387, 3928.2339943710704, 3930.5872883347124, 3999.7600143991453, 4054.4729185205733, 4066.2883995301, 267435.0, 267563.0, 268283.6585817078, 269134.0, 269810.0, 56471.435285647436, 57458.42541574531, 58078.41921580815, 58356.708216458646, 58816.11775823366, 60588.394116058276, 60596.0, 60954.69522652358, 61002.38997609967, 61072.69463652653, 13564.796528052088, 13662.521811736542, 13673.453061877612, 13674.17954922688, 13679.384427700777, 10893.945530272298, 10954.726131846768, 10966.122710183166, 10987.560497580167, 11043.503042363112, 19103.951046686056, 19199.0, 19268.903655481634, 19293.421197364103, 19457.124429400712, 7237.167725711611, 7484.401247900155, 7584.62076896153, 7603.695852165962, 7614.58119803405, 943.9952800236092, 968.3221744778683, 977.5747549815853, 1013.159077965287, 1154.878737732526, 27429.0, 27872.860635696692, 28183.013594524095, 28252.71747282501, 28301.71698282991, 1063.2344711807486, 1096.6490722968733, 1097.1277834121893, 1099.054812860939, 1116.4083035990939, 13434.529791457253, 13867.930660346836, 13913.791293130611, 13979.650508737363, 14003.439862405594, 311212.0, 314861.0, 315562.0, 316058.0, 316578.0, 52239.0, 52569.47430525722, 52660.0, 52718.47281527136, 52890.0, 1142.9771404571861, 1307.718840449308, 1309.9499283621103, 1319.6568892088126, 1332.0409305300168, 20927.686084709047, 29278.0, 29543.556846646887, 29808.701912980592, 29811.70188298176, 50976.0, 51387.743061284455, 51654.0, 51667.0, 51764.48235517597, 221114.0, 222386.0, 222990.0, 282462.0, 286779.0, 32142.0, 32509.862154824466, 32566.83716581402, 32854.83572582122, 32902.506462402605, 38703.0, 39091.218175636895, 39755.40366894557, 40071.599284007956, 40205.39691904682, 34141.829290853384, 36078.8196059018, 36812.44781328336, 37142.8142859284, 37220.06949826276, 36175.457368138974, 36348.0, 36814.44778328274, 37112.81443592765, 37571.436428454144, 33002.339953201285, 33480.497792532646, 33907.0, 33952.83023584866, 34011.4898276531, 29372.559411608414, 31008.844955775076, 31100.533491997187, 31428.37143257168, 31485.527717083805, 23992.520149597258, 24055.142656387594, 24085.27744167678, 24345.14791982272, 25718.0], "unit": "ms", "name": "ext.html", "value": 18509.81256846351}, {"lowerIsBetter": false, "replicates": [349.6852832450796, 349.88803582853484, 353.3709996206752, 354.59753180140535, 355.5431270817, 14112.0, 14266.0, 16369.508914732562, 17057.744133837987, 18143.092845357743, 15043.398264069425, 16254.0, 16276.674466510663, 16317.510474685749, 17379.0, 5234.476552344773, 5356.866078348034, 5361.0, 5361.169018802092, 5367.973160134214, 3912.0, 4008.7193896427116, 5268.367795864501, 5308.92036619451, 5675.063614503602, 5889.087191485305, 5920.378360272174, 5923.318818335903, 5933.5549833762425, 5973.820785376424, 5721.971390143043, 5860.882782344351, 5862.237909071836, 5871.3541510433815, 5885.146653735227, 5206.661566998171, 5213.2701421800975, 5215.608829337777, 5215.8174463893965, 5217.113090774539, 20328.390148295573, 20429.69355459669, 20678.379648610568, 20940.16239350424, 20945.266915657878, 2150.1184514349043, 2162.048698572639, 2257.1761307122947, 2327.3599760066118, 2381.940451488709, 13202.735945281136, 13396.598102056954, 13452.0, 13594.252316122609, 13662.316884155853, 1881.285111657575, 1922.2983610981892, 1933.236371633211, 1988.4829944214464, 2002.5093852006205, 58337.416625833626, 59503.99999999956, 61914.0, 65007.3499265006, 65713.3428665712, 9663.13031827139, 9782.364146330608, 9835.852462213072, 9851.113399794052, 9889.159421449114, 12769.872301277053, 12778.361081945866, 12807.167534110253, 12826.67933301675, 12847.550335738391, 7286.417086633057, 7368.410527157815, 7398.223186565441, 7451.515651482637, 7462.067241594801, 6866.588004719633, 6984.755533556301, 7046.260142684944, 7192.496525243183, 7285.890711639329, 4210.621044106045, 4211.620954114083, 4352.086061926968, 4352.804123814436, 4368.825246990148, 1695.533728224748, 1739.1565090930887, 1743.119724539125, 1757.868159887988, 1788.6333301673126, 875.6059773102002, 959.7984423271051, 987.4420952162005, 1009.9748755013667, 1018.923580731448, 15520.301586428637, 16375.67248655044, 16474.75287870683, 17015.23431445588, 17073.48779536591, 823.180934969709, 873.7204094689765, 918.4856480371013, 950.5104870991445, 957.4016239850093, 7431.739889103856, 9421.481818499911, 9964.451955142391, 10207.438590877424, 10313.48432578368, 506416.0, 506569.0, 519653.0, 528874.0, 532709.0, 80955.59522202352, 82247.5887620558, 82945.58527207325, 83220.58389708013, 84481.57759211164, 1096.8957948994832, 1124.404065845087, 1143.5654451308617, 1156.901663358607, 1160.1936654025371, 15071.246437677853, 15585.922070389575, 15608.687826243638, 15791.921040394724, 15813.446529371417, 35218.82390588031, 35550.0, 36100.0, 37201.25597488089, 37221.883343499736, 47918.28122578094, 48828.2675759871, 50951.490485096154, 51580.74209628928, 52003.47996519986, 8970.41692289999, 9082.18260356571, 9102.08979102111, 9140.451572905647, 9239.907600924174, 12898.548550800677, 17431.302747889942, 17832.0, 17861.28554857843, 17919.73120403169, 14300.785488217893, 14353.497627582985, 14770.113793172444, 14782.408703651734, 14894.925525372737, 14350.282485875456, 14414.207218603078, 14414.85585144135, 14552.0, 14900.850991489946, 14734.0, 14812.407503699731, 15113.395464181309, 15197.924010379877, 15256.160911149993, 7867.645955931882, 9137.908620913706, 9592.08875156859, 9774.85337719949, 9779.168770654289, 14517.0, 14740.96813223064, 15086.54740357791, 15131.54605361841, 15214.0], "unit": "ms", "name": "jquery.html", "value": 8976.153089851647}, {"lowerIsBetter": false, "replicates": [1282.9871701282996, 1452.949146779863, 1458.1615571046643, 1712.2551690014845, 1777.386801553464, 6328.2406111266655, 7028.226895041541, 7177.0, 7186.029885965406, 7190.065291512109, 7297.817554561138, 7417.666205020773, 7422.0, 7435.219301973296, 7436.2563743625615, 1897.7627796525435, 1926.6917293233087, 1927.7493925789697, 2063.5872825434935, 2066.8243199328076, 2200.471886747185, 2238.205437069833, 2243.170027089979, 2243.4839986803045, 2246.0454306919573, 2173.7608863025052, 2175.912963481459, 2262.6266666000092, 2263.207877242968, 2266.7846554577372, 1920.5198700324918, 1953.7460130183056, 2166.490874644457, 2210.7789221077924, 2259.062489067035, 2209.281983355416, 2235.88820558973, 2246.3485589179118, 2249.741279752833, 2250.4036430345973, 7566.772996810049, 7626.122995855492, 7627.69489220436, 7633.618319084024, 7633.847323053508, 4551.0, 4558.977205113986, 4576.244919588297, 4584.312353147021, 4646.303054541812, 2924.546695262238, 2924.6197994260824, 2972.628421447319, 2991.4764916139807, 3023.108183085995, 949.5916755795006, 949.6106596295555, 955.8183945050488, 994.0854413939194, 994.6469003503797, 10140.89859101407, 10151.543180556893, 10163.542640581192, 10177.287589868713, 10184.236182286284, 4004.5594984551676, 4126.834926602963, 4155.314373128461, 4228.048689045002, 4228.725132866415, 8818.779530511789, 8828.8675669865, 8831.911680883237, 8834.116588341114, 8839.46963182211, 4528.932066019012, 4553.931691024637, 4554.817807287672, 4560.749158796231, 4618.445786505641, 4128.731632443882, 4163.875083747493, 4164.56272091426, 4166.270902592034, 4182.728122672017, 3471.5486986691567, 3506.1234691327168, 3524.647535246455, 3528.3119791640383, 3534.6465353464446, 1157.7337212441016, 1160.2400427719856, 1169.6140273709825, 1176.4705882352976, 1180.0913296761446, 1290.9870901290867, 1339.7387509435566, 1349.39951721483, 1364.2701154882288, 1379.165604809096, 7148.571085734872, 7183.0, 7187.9640601796655, 7198.460115491359, 7205.135383753983, 493.4153028661048, 495.3040977426721, 502.9899402011939, 503.5694481218579, 503.6197670758554, 5574.665520068809, 5681.0910254359105, 5707.058335374617, 5724.084146536534, 5726.484616384545, 8776.385653004356, 8857.424267422726, 8979.416337938013, 9004.549772511347, 9160.22138118254, 9045.638174472948, 9063.0, 9063.81872362549, 9067.68263110788, 9081.954590227007, 6824.522283440111, 6931.6880740366805, 7017.122859642544, 7029.4376449883885, 7033.613151276627, 7051.189113252041, 7102.4673149512955, 7106.7157313706875, 7109.928900710926, 7111.111111111111, 7767.844643106993, 7989.161138080651, 7996.960015199887, 8079.272865442138, 8096.919030809617, 7943.4042446817875, 8096.878546821685, 8142.267195952392, 8143.104258531438, 8252.21603947624, 6797.456203503807, 6807.659617019228, 6837.589744615339, 6838.521303508706, 6859.348361905611, 7348.853022939618, 7371.557706537625, 7378.778636640909, 7379.778606641809, 7383.963080184565, 6802.795916122524, 6816.318368163144, 6816.556923799937, 6831.487638427038, 6870.038194652652, 5883.176355310346, 5883.441073098049, 6380.968095159495, 6781.491388146008, 6867.0, 6608.867822643424, 6645.036469711768, 6664.200295964515, 6666.0, 6666.566673166229, 6209.223847019122, 6231.719572619334, 6247.219097612799, 6247.812565623039, 6254.124422580751, 6287.937120628735, 6485.351464853666, 6504.0, 6530.640814755234, 6536.215654121345], "unit": "ms", "name": "mootools.html", "value": 4258.523003008834}, {"lowerIsBetter": false, "replicates": [310.6955183919759, 318.4888254351765, 320.985555649996, 321.50809261829403, 323.2113642711783, 5973.551983601231, 6050.697465126748, 6067.302260240073, 6074.240719910005, 6075.51395888329, 5980.880382392361, 5987.191729116568, 5990.071538911465, 5991.310999235089, 5991.910121348184, 4371.300591905296, 4476.753778542179, 4493.6405087593075, 4588.587027167546, 4617.960958784263, 4555.271156614942, 4590.0, 4594.287885377755, 4610.861674149781, 4618.884527886796, 4560.133574620828, 4560.156371071363, 4566.474855391622, 4572.154151481986, 4573.176828170928, 4313.331433627793, 4430.069685366077, 4459.197344477992, 4518.954810451886, 4525.637948964075, 3938.2714197873483, 3948.8617898373705, 3953.2686453006286, 3954.8813535593977, 3955.0903292242947, 5682.0, 5698.088305871082, 5731.656100633975, 5732.283464566929, 5749.655020698771, 5220.634555581111, 5344.0, 5349.3045904032615, 5352.437994010612, 5352.652077614943, 6344.873102537924, 6349.746010159588, 6351.047342898602, 6353.777617783401, 6356.237251529846, 4435.647127626105, 4437.134758722051, 4437.556244375569, 4438.0, 4439.64482841372, 6554.180727409074, 6564.376384243489, 6566.047923051131, 6569.178852643419, 6600.2409722882, 4470.374147619336, 4550.362949187116, 4555.772211389417, 4573.794179261941, 4582.243929751554, 4626.190416677068, 4626.653001024936, 4629.027904140168, 4629.0973260214605, 4629.490756016836, 5307.442718514578, 5314.8937021260135, 5317.893642127213, 5323.760430780624, 5325.547328477045, 6418.679066046679, 6420.1974753155855, 6423.96788016057, 6427.839304017437, 6428.485721142298, 1553.7824704541374, 1576.1961399686243, 1588.7060893734697, 1592.936282548685, 1595.6330044089925, 1083.631565267797, 1093.4478088565224, 1099.69758316462, 1108.4457771114444, 1114.3146964616703, 6211.720472578654, 6217.0, 6227.0, 6229.190205273376, 6233.875322493615, 6553.967230163818, 6603.900941485882, 6606.834829129214, 6618.933810661928, 6626.56927299724, 6085.496732417979, 6215.409536094064, 6215.9378406216265, 6222.844428889314, 6222.875542489215, 3439.724822014283, 3511.631278715749, 3531.7174626029855, 3615.1504396466808, 3625.746197766131, 6898.379145876896, 6908.378245957888, 6912.723491060301, 6915.51591388598, 6925.065116209348, 6815.0, 6822.454203663794, 6822.624755638389, 6829.658517074126, 6832.214295355998, 6692.966535167293, 6835.726570937206, 6874.0, 6919.723211071601, 6944.236134025355, 6673.699683514156, 6674.933250667431, 6678.065070889982, 6680.098186744727, 6684.933150668626, 6450.677466126769, 6585.901211481736, 6607.834804129935, 6612.404883560503, 6612.570182938093, 6768.932310676831, 6773.661316934035, 6774.288699686462, 6775.525713200226, 6779.830504237236, 4417.602415782595, 4465.771912724027, 4468.8212471502075, 4468.865934021985, 4471.932921006252, 6650.767223147167, 6651.301613330531, 6652.201735791542, 6656.400923916871, 6661.367170118831, 6338.90491642635, 6443.484521238383, 6458.22501299847, 6464.773732919325, 6487.610743355413, 6393.2967373587935, 6437.485001199799, 6445.291017988111, 6449.871002580016, 6458.483321334376, 6194.938050619436, 6288.213973253343, 6302.338254483397, 6302.464290535356, 6309.432151106423, 6480.25477070143, 6482.805515834532, 6483.124778154889, 6491.870162596627, 6493.0, 6334.113224148531, 6334.968325158345, 6341.175647165839, 6345.841353966003, 6360.4911607072245], "unit": "ms", "name": "prototype.html", "value": 4814.905388203985}, {"lowerIsBetter": false, "replicates": [747.7320445271955, 779.0817540242646, 803.3210589867176, 831.3681601982494, 860.780500972252, 11588.478518466669, 11961.58134465294, 11978.880211197886, 11981.580644677439, 11992.640220793379, 12475.812862807043, 12543.18469299495, 12547.121701480877, 12586.0, 12588.496460141583, 782.9804254893617, 816.5998660656295, 823.8475881961826, 841.4446465332882, 842.1999100854182, 810.712197170005, 811.2293321344716, 812.3095368936387, 813.3940214540175, 813.4631143445328, 860.371928492201, 863.8401895649293, 864.5288317866778, 865.969691060813, 867.6442658510013, 848.3594885861136, 852.526847599582, 853.2278288149234, 854.6068808348167, 854.8504011797942, 608.9421504957022, 627.1721327847243, 628.4124343738619, 630.1555915073777, 630.3161070238797, 13001.674958126028, 13113.541026064042, 13150.276734779583, 13223.603291901161, 13242.66893327665, 11933.761324773543, 12141.089418293575, 12177.939110304393, 12447.3776311185, 12511.311877846714, 9427.905720942772, 9507.096825801538, 9570.712878613653, 9756.365836220622, 10212.642557510451, 3393.4231180699094, 3429.1255729788745, 3461.6884480396884, 3546.7871927684164, 3546.8758593449365, 40897.79551102255, 41711.0, 41938.37092443616, 42406.36390454115, 50478.0, 9129.817403652023, 9136.0, 9150.908490915139, 9181.449113053104, 9194.49430281341, 15927.283272252776, 15995.440159594358, 16115.838841611669, 16116.75824862628, 18171.72742408865, 7228.241034691282, 7285.708571657075, 7288.380487658608, 7370.963145184347, 7458.776236712907, 4012.117334186474, 4045.1505183911117, 4079.0, 4079.97960010204, 4119.670426365884, 3114.7508199343997, 3142.6228852537383, 3144.9213769655944, 3150.1337132288804, 3154.0695494828624, 563.1890078287254, 593.3413910559276, 594.93753155918, 605.4308949587373, 622.5237693164732, 641.8106658535743, 642.8167972127922, 667.3226674924955, 670.6043434373743, 699.4299645788666, 8786.560671966376, 9014.23379012778, 9292.72121836346, 9412.482313472825, 9807.705768826947, 656.4157899469476, 664.4750646988824, 664.6112024465644, 676.2662511175331, 684.1824020295799, 3861.362875125573, 4015.457913181683, 4051.3517837145914, 4069.1658210066867, 4153.273177193979, 56619.71690141523, 57091.14363284554, 57146.714266428404, 57428.71285643629, 57470.137947930816, 49608.50391496111, 50756.492435075175, 50811.745941270055, 50854.0, 50977.0, 36957.0, 42663.36004959928, 44565.0, 44673.55326446694, 45134.54865451369, 8026.59867006659, 8423.831523369376, 8493.447925884797, 8517.701880434155, 8618.655253789779, 35375.469367958984, 35779.46330805092, 36648.0, 36736.0, 37208.44187337246, 41311.586884130775, 45695.0, 45870.31194532018, 46376.0, 46799.53200468088, 9979.900200997898, 11099.119172788434, 11107.666769996913, 11141.83287250708, 11442.084633229488, 12445.937770311091, 12488.75022499563, 12559.874401256237, 13100.148490348098, 13160.80258796138, 11517.942410287895, 11543.249688770204, 11561.768764624492, 11597.18819682614, 11956.58151964677, 11646.0, 11893.345865977108, 11918.582849600223, 12231.082668800054, 12320.445579948744, 11572.710682233012, 11734.191932742951, 11992.460339284578, 12344.691382715504, 12359.75280494403, 9334.0, 9504.714858554255, 9525.237980961369, 9721.4653194075, 9847.606095756377, 8711.747039043532, 8988.730338089868, 9490.335676502578, 9733.367331123454, 9860.753481163028], "unit": "ms", "name": "yui.html", "value": 6247.128947430841}], "name": "dromaeo_css", "value": 6975.732300246831}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 113.0, 104.0, 109.0, 111.0, 111.0, 99.0, 110.0, 110.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 107.8}, {"lowerIsBetter": true, "replicates": [146.0, 124.0, 154.0, 162.0, 126.0, 152.0, 149.0, 125.0, 174.0, 127.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.9}, {"lowerIsBetter": true, "replicates": [162.0, 160.0, 162.0, 161.0, 163.0, 160.0, 160.0, 164.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 161.3}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 86.0, 94.0, 71.0, 71.0, 72.0, 95.0, 93.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 79.7}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 150.0, 92.0, 94.0, 94.0, 135.0, 93.0, 94.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 107.6}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 147.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [65.0, 62.0, 62.0, 63.0, 64.0, 65.0, 65.0, 63.0, 60.0, 60.0], "unit": "ms", "name": "json-parse-financial", "value": 62.9}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.1}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 85.0, 85.0, 84.0, 84.0, 84.0, 85.0, 85.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.6}, {"lowerIsBetter": true, "replicates": [121.0, 123.0, 131.0, 117.0, 133.0, 121.0, 129.0, 115.0, 116.0, 124.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 123.0}, {"lowerIsBetter": true, "replicates": [149.0, 159.0, 163.0, 150.0, 152.0, 141.0, 154.0, 149.0, 150.0, 137.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 150.4}, {"lowerIsBetter": true, "replicates": [66.0, 68.0, 66.0, 66.0, 71.0, 66.0, 68.0, 69.0, 67.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.3}], "name": "kraken", "value": 1506.9999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [186.84603886397608], "unit": "score", "name": "Richards", "value": 188.93630400000006}, {"lowerIsBetter": false, "replicates": [189.10741301059002], "unit": "score", "name": "DeltaBlue", "value": 349.631984}, {"lowerIsBetter": false, "replicates": [280.42624789680315], "unit": "score", "name": "Encrypt", "value": 949.2014460000001}, {"lowerIsBetter": false, "replicates": [4970.297029702971], "unit": "score", "name": "Decrypt", "value": 53.55434462151397}, {"lowerIsBetter": false, "replicates": [1938.1044487427466], "unit": "score", "name": "RayTrace", "value": 381.81069161676635}, {"lowerIsBetter": false, "replicates": [789.8894154818325], "unit": "score", "name": "Earley", "value": 843.7421580000001}, {"lowerIsBetter": false, "replicates": [12095.238095238095], "unit": "score", "name": "Boyer", "value": 55.101271653543314}, {"lowerIsBetter": false, "replicates": [26315.78947368421], "unit": "score", "name": "RegExp", "value": 34.61742999999998}, {"lowerIsBetter": false, "replicates": [602.0469596628537], "unit": "score", "name": "Splay", "value": 135.35655099999997}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19724.12277129574}]} 14:01:26 INFO - RETURN: dromaeo_css: 6975.73 14:01:26 INFO - RETURN: kraken: 1507.00 14:01:26 INFO - RETURN: v8_7: 19724.12 14:01:26 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "19724.12"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1507.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6975.73"}}} 14:01:26 INFO - Return code: 0 14:01:26 INFO - # TBPL SUCCESS # 14:01:26 INFO - Running post-action listener: _resource_record_post_action 14:01:26 INFO - Running post-run listener: _resource_record_post_run 14:01:27 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 0; Write bytes: 513040384; Read time: 0; Write time: 298668 14:01:27 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 141602816; Read time: 0; Write time: 86528 14:01:27 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 0; Write bytes: 263741440; Read time: 0; Write time: 117988 14:01:27 INFO - Running post-run listener: _upload_blobber_files 14:01:27 INFO - Blob upload gear active. 14:01:27 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 14:01:27 INFO - Copying logs to upload dir... 14:01:27 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1254.275347 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2015-11-23 14:01:26.024478) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 14:01:26.028562) ========= 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=5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009897 build_url: 'https://queue.taskcluster.net/v1/task/IcdffnyZREi70AfhbR-HKg/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-23 14:01:26.081038) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 14:01:26.081431) ========= 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=5804b9f07a53938c557deee7000001c5-1448314830.597223-1269778409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 14:01:26.132915) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 14:01:26.133299) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 14:01:26.133651) ========= ========= Total master_lag: 0.21 =========