builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1447739975.34 results: success (0) buildid: 20151116193328 builduid: 2bf1d3f0823744b2be45a28d55a661b6 revision: a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.336500) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.336971) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.337340) ========= 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=447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006510 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.383096) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.383411) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.447057) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.447337) ========= 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=447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004675 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.496699) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.497004) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.497358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.497631) ========= 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=447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-16 21:59:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.4M=0s 2015-11-16 21:59:36 (47.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.191576 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.702057) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.702361) ========= 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=447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013338 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.753836) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:35.754195) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb --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=447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-16 21:59:36,224 truncating revision to first 12 chars 2015-11-16 21:59:36,224 Setting DEBUG logging. 2015-11-16 21:59:36,224 attempt 1/10 2015-11-16 21:59:36,224 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a2f83cbe53ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-16 21:59:36,465 unpacking tar archive at: mozilla-central-a2f83cbe53ac/testing/mozharness/ program finished with exit code 0 elapsedTime=0.537459 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:36.306349) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:36.306620) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:36.321976) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:36.322254) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-16 21:59:36.322639) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2015-11-16 21:59:36.322938) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:59:36 INFO - MultiFileLogger online at 20151116 21:59:36 in /builds/slave/test-pgo 21:59:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:59:36 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:59:36 INFO - {'append_to_log': False, 21:59:36 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:59:36 INFO - 'blob_upload_branch': 'Firefox', 21:59:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:59:36 INFO - 'branch': 'Firefox', 21:59:36 INFO - 'buildbot_json_path': 'buildprops.json', 21:59:36 INFO - 'config_files': ('talos/linux_config.py',), 21:59:36 INFO - 'default_actions': ('clobber', 21:59:36 INFO - 'read-buildbot-config', 21:59:36 INFO - 'download-and-extract', 21:59:36 INFO - 'populate-webroot', 21:59:36 INFO - 'create-virtualenv', 21:59:36 INFO - 'install', 21:59:36 INFO - 'run-tests'), 21:59:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:59:36 INFO - 'download_minidump_stackwalk': True, 21:59:36 INFO - 'download_symbols': 'ondemand', 21:59:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:59:36 INFO - 'tooltool.py': '/tools/tooltool.py', 21:59:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:59:36 INFO - '/tools/misc-python/virtualenv.py')}, 21:59:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:59:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:59:36 INFO - 'installer_path': 'installer.exe', 21:59:36 INFO - 'log_level': 'info', 21:59:36 INFO - 'log_name': 'talos', 21:59:36 INFO - 'log_to_console': True, 21:59:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:59:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:59:36 INFO - 'opt_config_files': (), 21:59:36 INFO - 'pip_index': False, 21:59:36 INFO - 'sps_profile': False, 21:59:36 INFO - 'sps_profile_interval': 0, 21:59:36 INFO - 'suite': 'dromaeojs', 21:59:36 INFO - 'system_bits': '64', 21:59:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:59:36 INFO - 'title': 'talos-linux64-ix-027', 21:59:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:59:36 INFO - 'use_talos_json': True, 21:59:36 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:59:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:59:36 INFO - 'work_dir': 'build'} 21:59:36 INFO - ##### 21:59:36 INFO - ##### Running clobber step. 21:59:36 INFO - ##### 21:59:36 INFO - Running pre-action listener: _resource_record_pre_action 21:59:36 INFO - Running main action method: clobber 21:59:36 INFO - rmtree: /builds/slave/test-pgo/build 21:59:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:59:37 INFO - Running post-action listener: _resource_record_post_action 21:59:37 INFO - ##### 21:59:37 INFO - ##### Running read-buildbot-config step. 21:59:37 INFO - ##### 21:59:37 INFO - Running pre-action listener: _resource_record_pre_action 21:59:37 INFO - Running main action method: read_buildbot_config 21:59:37 INFO - Using buildbot properties: 21:59:37 INFO - { 21:59:37 INFO - "properties": { 21:59:37 INFO - "buildnumber": 560, 21:59:37 INFO - "product": "firefox", 21:59:37 INFO - "script_repo_revision": "production", 21:59:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:59:37 INFO - "repository": "", 21:59:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:59:37 INFO - "buildid": "20151116193328", 21:59:37 INFO - "slavename": "talos-linux64-ix-027", 21:59:37 INFO - "pgo_build": "True", 21:59:37 INFO - "basedir": "/builds/slave/test-pgo", 21:59:37 INFO - "project": "", 21:59:37 INFO - "platform": "ubuntu64_hw", 21:59:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:59:37 INFO - "slavebuilddir": "test", 21:59:37 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:59:37 INFO - "branch": "mozilla-central", 21:59:37 INFO - "repo_path": "mozilla-central", 21:59:37 INFO - "stage_platform": "linux64-pgo", 21:59:37 INFO - "builduid": "2bf1d3f0823744b2be45a28d55a661b6", 21:59:37 INFO - "revision": "a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb" 21:59:37 INFO - }, 21:59:37 INFO - "sourcestamp": { 21:59:37 INFO - "repository": "", 21:59:37 INFO - "hasPatch": false, 21:59:37 INFO - "project": "", 21:59:37 INFO - "branch": "mozilla-central-linux64-pgo-talos", 21:59:37 INFO - "changes": [ 21:59:37 INFO - { 21:59:37 INFO - "category": null, 21:59:37 INFO - "files": [ 21:59:37 INFO - { 21:59:37 INFO - "url": null, 21:59:37 INFO - "name": "https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 21:59:37 INFO - } 21:59:37 INFO - ], 21:59:37 INFO - "repository": "", 21:59:37 INFO - "rev": "a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb", 21:59:37 INFO - "who": "sendchange", 21:59:37 INFO - "when": 1447739942, 21:59:37 INFO - "number": 6670185, 21:59:37 INFO - "comments": "", 21:59:37 INFO - "project": "", 21:59:37 INFO - "at": "Mon 16 Nov 2015 21:59:02", 21:59:37 INFO - "branch": "mozilla-central-linux64-pgo-talos", 21:59:37 INFO - "revlink": "", 21:59:37 INFO - "properties": [ 21:59:37 INFO - [ 21:59:37 INFO - "buildid", 21:59:37 INFO - "20151116193328", 21:59:37 INFO - "Change" 21:59:37 INFO - ], 21:59:37 INFO - [ 21:59:37 INFO - "builduid", 21:59:37 INFO - "2bf1d3f0823744b2be45a28d55a661b6", 21:59:37 INFO - "Change" 21:59:37 INFO - ], 21:59:37 INFO - [ 21:59:37 INFO - "pgo_build", 21:59:37 INFO - "True", 21:59:37 INFO - "Change" 21:59:37 INFO - ] 21:59:37 INFO - ], 21:59:37 INFO - "revision": "a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb" 21:59:37 INFO - } 21:59:37 INFO - ], 21:59:37 INFO - "revision": "a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb" 21:59:37 INFO - } 21:59:37 INFO - } 21:59:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 21:59:37 INFO - Running post-action listener: _resource_record_post_action 21:59:37 INFO - ##### 21:59:37 INFO - ##### Running download-and-extract step. 21:59:37 INFO - ##### 21:59:37 INFO - Running pre-action listener: _resource_record_pre_action 21:59:37 INFO - Running main action method: download_and_extract 21:59:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:37 INFO - https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:59:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/test_packages.json 21:59:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/test_packages.json 21:59:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:59:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:59:40 INFO - Downloaded 1302 bytes. 21:59:40 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:59:40 INFO - Using the following test package requirements: 21:59:40 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:59:40 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:59:40 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:40 INFO - u'jsshell-linux-x86_64.zip'], 21:59:40 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:59:40 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:59:40 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:59:40 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:59:40 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:59:40 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:59:40 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:59:40 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:40 INFO - https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:59:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:59:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:59:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:59:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:59:49 INFO - Downloaded 22463228 bytes. 21:59:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 21:59:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:59:49 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 21:59:50 INFO - Return code: 0 21:59:50 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:50 INFO - https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:59:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:59:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:59:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:59:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:59:51 INFO - Downloaded 22463228 bytes. 21:59:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 21:59:51 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:59:51 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 21:59:52 INFO - Return code: 0 21:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:52 INFO - https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:59:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:59:53 INFO - Downloaded 11131693 bytes. 21:59:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 21:59:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:53 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 21:59:53 INFO - Return code: 0 21:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:53 INFO - https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:59:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:59:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:59:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:59:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:59:55 INFO - Downloaded 57164913 bytes. 21:59:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:59:55 INFO - mkdir: /builds/slave/test-pgo/properties 21:59:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:59:55 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:59:55 INFO - Contents: 21:59:55 INFO - build_url:https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:59:55 INFO - Running post-action listener: _resource_record_post_action 21:59:55 INFO - Running post-action listener: set_extra_try_arguments 21:59:55 INFO - ##### 21:59:55 INFO - ##### Running populate-webroot step. 21:59:55 INFO - ##### 21:59:55 INFO - Running pre-action listener: _resource_record_pre_action 21:59:55 INFO - Running main action method: populate_webroot 21:59:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:59:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:59:55 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 21:59:55 INFO - '../fennec_ids.txt'], 21:59:55 INFO - 'tests': ['tcheck2']}, 21:59:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:59:55 INFO - '--tppagecycles', 21:59:55 INFO - '7'], 21:59:55 INFO - 'tests': ['tsvgm']}}, 21:59:55 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:59:55 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:59:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:59:55 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:59:55 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:59:55 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:59:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:55 INFO - 'talos_options': ['--e10s'], 21:59:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:55 INFO - 'tests': ['damp', 'tps']}, 21:59:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:55 INFO - 'talos_options': ['--e10s'], 21:59:55 INFO - 'tests': ['damp', 'tps']}, 21:59:55 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:59:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:59:55 INFO - 'other': {'tests': ['a11yr', 21:59:55 INFO - 'ts_paint', 21:59:55 INFO - 'tpaint', 21:59:55 INFO - 'sessionrestore', 21:59:55 INFO - 'sessionrestore_no_auto_restore']}, 21:59:55 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:59:55 INFO - 'tests': ['a11yr', 21:59:55 INFO - 'ts_paint', 21:59:55 INFO - 'tpaint', 21:59:55 INFO - 'sessionrestore', 21:59:55 INFO - 'sessionrestore_no_auto_restore']}, 21:59:55 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:59:55 INFO - 'tests': ['a11yr', 21:59:55 INFO - 'ts_paint', 21:59:55 INFO - 'tpaint', 21:59:55 INFO - 'sessionrestore', 21:59:55 INFO - 'sessionrestore_no_auto_restore']}, 21:59:55 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:59:55 INFO - 'tests': ['a11yr', 21:59:55 INFO - 'ts_paint', 21:59:55 INFO - 'tpaint', 21:59:55 INFO - 'sessionrestore', 21:59:55 INFO - 'sessionrestore_no_auto_restore']}, 21:59:55 INFO - 'other_l64': {'tests': ['a11yr', 21:59:55 INFO - 'ts_paint', 21:59:55 INFO - 'tpaint', 21:59:55 INFO - 'sessionrestore', 21:59:55 INFO - 'sessionrestore_no_auto_restore']}, 21:59:55 INFO - 'other_nol64': {'tests': ['a11yr', 21:59:55 INFO - 'ts_paint', 21:59:55 INFO - 'tpaint', 21:59:55 INFO - 'sessionrestore', 21:59:55 INFO - 'sessionrestore_no_auto_restore']}, 21:59:55 INFO - 'svgr': {'tests': ['tsvgx', 21:59:55 INFO - 'tsvgr_opacity', 21:59:55 INFO - 'tart', 21:59:55 INFO - 'tscrollx', 21:59:55 INFO - 'cart']}, 21:59:55 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:59:55 INFO - 'tests': ['tsvgx', 21:59:55 INFO - 'tsvgr_opacity', 21:59:55 INFO - 'tart', 21:59:55 INFO - 'tscrollx', 21:59:55 INFO - 'cart']}, 21:59:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:55 INFO - 'tests': ['tp5o']}, 21:59:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:55 INFO - 'talos_options': ['--e10s'], 21:59:55 INFO - 'tests': ['tp5o']}, 21:59:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:55 INFO - 'talos_options': ['--xperf_path', 21:59:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:55 INFO - 'tests': ['tp5n']}, 21:59:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:55 INFO - 'talos_options': ['--e10s', 21:59:55 INFO - '--xperf_path', 21:59:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:55 INFO - 'tests': ['tp5n']}}, 21:59:55 INFO - 'talos.zip': {'path': '', 21:59:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:59:55 INFO - Running post-action listener: _resource_record_post_action 21:59:55 INFO - ##### 21:59:55 INFO - ##### Running create-virtualenv step. 21:59:55 INFO - ##### 21:59:55 INFO - Running pre-action listener: _resource_record_pre_action 21:59:55 INFO - Running main action method: create_virtualenv 21:59:55 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:59:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 21:59:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:59:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:59:55 INFO - Using real prefix '/usr' 21:59:55 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:59:56 INFO - Installing distribute.............................................................................................................................................................................................done. 21:59:58 INFO - Installing pip.................done. 21:59:58 INFO - Return code: 0 21:59:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:58 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 0x7f850bf78c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b196c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bda3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c638c0>, '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 0x2c10420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c108b0>, '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': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 21:59:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:59:58 INFO - Using env: {'DISPLAY': ':0', 21:59:58 INFO - 'HOME': '/home/cltbld', 21:59:58 INFO - 'LANG': 'en_US.UTF-8', 21:59:58 INFO - 'LANGUAGE': 'en_US:en', 21:59:58 INFO - 'LOGNAME': 'cltbld', 21:59:58 INFO - 'MAIL': '/var/mail/cltbld', 21:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:58 INFO - 'MOZ_NO_REMOTE': '1', 21:59:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:58 INFO - 'NO_EM_RESTART': '1', 21:59:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:58 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:58 INFO - 'SHELL': '/bin/bash', 21:59:58 INFO - 'SHLVL': '1', 21:59:58 INFO - 'TERM': 'linux', 21:59:58 INFO - 'TMOUT': '86400', 21:59:58 INFO - 'USER': 'cltbld', 21:59:58 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', 21:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:58 INFO - '_': '/tools/buildbot/bin/python'} 21:59:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:59:58 INFO - Cleaning up... 21:59:58 INFO - Return code: 0 21:59:58 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:58 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 0x7f850bf78c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b196c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bda3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c638c0>, '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 0x2c10420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c108b0>, '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': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 21:59:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 21:59:58 INFO - Using env: {'DISPLAY': ':0', 21:59:58 INFO - 'HOME': '/home/cltbld', 21:59:58 INFO - 'LANG': 'en_US.UTF-8', 21:59:58 INFO - 'LANGUAGE': 'en_US:en', 21:59:58 INFO - 'LOGNAME': 'cltbld', 21:59:58 INFO - 'MAIL': '/var/mail/cltbld', 21:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:58 INFO - 'MOZ_NO_REMOTE': '1', 21:59:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:58 INFO - 'NO_EM_RESTART': '1', 21:59:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:58 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:58 INFO - 'SHELL': '/bin/bash', 21:59:58 INFO - 'SHLVL': '1', 21:59:58 INFO - 'TERM': 'linux', 21:59:58 INFO - 'TMOUT': '86400', 21:59:58 INFO - 'USER': 'cltbld', 21:59:58 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', 21:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:58 INFO - '_': '/tools/buildbot/bin/python'} 21:59:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:58 INFO - Downloading/unpacking psutil>=0.7.1 21:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:58 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 22:00:00 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 22:00:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:00:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:00:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:00 INFO - Installing collected packages: psutil 22:00:00 INFO - Running setup.py install for psutil 22:00:00 INFO - building 'psutil._psutil_linux' extension 22:00:00 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 22:00:00 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 22:00:00 INFO - building 'psutil._psutil_posix' extension 22:00:00 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 22:00:00 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 22:00:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:00 INFO - Successfully installed psutil 22:00:00 INFO - Cleaning up... 22:00:00 INFO - Return code: 0 22:00:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:00 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 0x7f850bf78c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b196c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bda3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c638c0>, '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 0x2c10420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c108b0>, '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': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', '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 22:00:00 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 22:00:00 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 22:00:00 INFO - Using env: {'DISPLAY': ':0', 22:00:00 INFO - 'HOME': '/home/cltbld', 22:00:00 INFO - 'LANG': 'en_US.UTF-8', 22:00:00 INFO - 'LANGUAGE': 'en_US:en', 22:00:00 INFO - 'LOGNAME': 'cltbld', 22:00:00 INFO - 'MAIL': '/var/mail/cltbld', 22:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:00 INFO - 'MOZ_NO_REMOTE': '1', 22:00:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:00 INFO - 'NO_EM_RESTART': '1', 22:00:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:00 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:00 INFO - 'SHELL': '/bin/bash', 22:00:00 INFO - 'SHLVL': '1', 22:00:00 INFO - 'TERM': 'linux', 22:00:00 INFO - 'TMOUT': '86400', 22:00:00 INFO - 'USER': 'cltbld', 22:00:00 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', 22:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:00 INFO - '_': '/tools/buildbot/bin/python'} 22:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:00:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:00:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:00:02 INFO - Installing collected packages: mozsystemmonitor 22:00:02 INFO - Running setup.py install for mozsystemmonitor 22:00:03 INFO - Successfully installed mozsystemmonitor 22:00:03 INFO - Cleaning up... 22:00:03 INFO - Return code: 0 22:00:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:00:03 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')]} 22:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:03 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')]} 22:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:03 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 0x7f850bf78c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b196c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bda3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c638c0>, '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 0x2c10420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c108b0>, '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': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', '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 22:00:03 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 22:00:03 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 22:00:03 INFO - Using env: {'DISPLAY': ':0', 22:00:03 INFO - 'HOME': '/home/cltbld', 22:00:03 INFO - 'LANG': 'en_US.UTF-8', 22:00:03 INFO - 'LANGUAGE': 'en_US:en', 22:00:03 INFO - 'LOGNAME': 'cltbld', 22:00:03 INFO - 'MAIL': '/var/mail/cltbld', 22:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:03 INFO - 'MOZ_NO_REMOTE': '1', 22:00:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:03 INFO - 'NO_EM_RESTART': '1', 22:00:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:03 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:03 INFO - 'SHELL': '/bin/bash', 22:00:03 INFO - 'SHLVL': '1', 22:00:03 INFO - 'TERM': 'linux', 22:00:03 INFO - 'TMOUT': '86400', 22:00:03 INFO - 'USER': 'cltbld', 22:00:03 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', 22:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:03 INFO - '_': '/tools/buildbot/bin/python'} 22:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:03 INFO - Downloading/unpacking blobuploader==1.2.4 22:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:04 INFO - Downloading blobuploader-1.2.4.tar.gz 22:00:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 22:00:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:00:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:00:04 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 22:00:04 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 22:00:04 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 22:00:04 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 22:00:04 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 22:00:04 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 22:00:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 22:00:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:00:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:05 INFO - Downloading docopt-0.6.1.tar.gz 22:00:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 22:00:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:00:05 INFO - Installing collected packages: blobuploader, requests, docopt 22:00:05 INFO - Running setup.py install for blobuploader 22:00:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:00:05 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:00:05 INFO - Running setup.py install for requests 22:00:06 INFO - Running setup.py install for docopt 22:00:06 INFO - Successfully installed blobuploader requests docopt 22:00:06 INFO - Cleaning up... 22:00:06 INFO - Return code: 0 22:00:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:00:06 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')]} 22:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:06 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')]} 22:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:06 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 0x7f850bf78c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b196c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bda3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c638c0>, '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 0x2c10420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c108b0>, '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': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', '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 22:00:06 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 22:00:06 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 22:00:06 INFO - Using env: {'DISPLAY': ':0', 22:00:06 INFO - 'HOME': '/home/cltbld', 22:00:06 INFO - 'LANG': 'en_US.UTF-8', 22:00:06 INFO - 'LANGUAGE': 'en_US:en', 22:00:06 INFO - 'LOGNAME': 'cltbld', 22:00:06 INFO - 'MAIL': '/var/mail/cltbld', 22:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:06 INFO - 'MOZ_NO_REMOTE': '1', 22:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:06 INFO - 'NO_EM_RESTART': '1', 22:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:06 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:06 INFO - 'SHELL': '/bin/bash', 22:00:06 INFO - 'SHLVL': '1', 22:00:06 INFO - 'TERM': 'linux', 22:00:06 INFO - 'TMOUT': '86400', 22:00:06 INFO - 'USER': 'cltbld', 22:00:06 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', 22:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:06 INFO - '_': '/tools/buildbot/bin/python'} 22:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:00:06 INFO - Running setup.py (path:/tmp/pip-ooIcBe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:00:06 INFO - Running setup.py (path:/tmp/pip-MiWzTw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:00:06 INFO - Running setup.py (path:/tmp/pip-GHj_a_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:00:06 INFO - Running setup.py (path:/tmp/pip-wn6V90-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:00:06 INFO - Running setup.py (path:/tmp/pip-vi2YGR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:00:06 INFO - Running setup.py (path:/tmp/pip-kJDaLB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:00:06 INFO - Running setup.py (path:/tmp/pip-rB0u_r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:00:06 INFO - Running setup.py (path:/tmp/pip-a6WWYf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:00:06 INFO - Running setup.py (path:/tmp/pip-sDVW2s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:00:07 INFO - Running setup.py (path:/tmp/pip-UFfk46-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:00:07 INFO - Running setup.py (path:/tmp/pip-h8ktr_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:00:07 INFO - Running setup.py (path:/tmp/pip-KxPSCJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:00:07 INFO - Running setup.py (path:/tmp/pip-n2Bwa4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:00:07 INFO - Running setup.py (path:/tmp/pip-bmOqtt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:00:07 INFO - Running setup.py (path:/tmp/pip-ZURnZg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:00:07 INFO - Running setup.py (path:/tmp/pip-EuMpd9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:00:07 INFO - Running setup.py (path:/tmp/pip-JRIWX5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:00:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:00:07 INFO - Running setup.py install for manifestparser 22:00:07 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:00:07 INFO - Running setup.py install for mozcrash 22:00:07 INFO - Running setup.py install for mozdebug 22:00:07 INFO - Running setup.py install for mozdevice 22:00:08 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Running setup.py install for mozfile 22:00:08 INFO - Running setup.py install for mozhttpd 22:00:08 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Running setup.py install for mozinfo 22:00:08 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Running setup.py install for mozInstall 22:00:08 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Running setup.py install for mozleak 22:00:08 INFO - Running setup.py install for mozlog 22:00:08 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Running setup.py install for moznetwork 22:00:08 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Running setup.py install for mozprocess 22:00:08 INFO - Running setup.py install for mozprofile 22:00:08 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:00:08 INFO - Running setup.py install for mozrunner 22:00:09 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:00:09 INFO - Running setup.py install for mozscreenshot 22:00:09 INFO - Running setup.py install for moztest 22:00:09 INFO - Running setup.py install for mozversion 22:00:09 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:00:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:00:09 INFO - Cleaning up... 22:00:09 INFO - Return code: 0 22:00:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f850bf78c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b196c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bda3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c638c0>, '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 0x2c10420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c108b0>, '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': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', '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 22:00:09 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 22:00:09 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 22:00:09 INFO - Using env: {'DISPLAY': ':0', 22:00:09 INFO - 'HOME': '/home/cltbld', 22:00:09 INFO - 'LANG': 'en_US.UTF-8', 22:00:09 INFO - 'LANGUAGE': 'en_US:en', 22:00:09 INFO - 'LOGNAME': 'cltbld', 22:00:09 INFO - 'MAIL': '/var/mail/cltbld', 22:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:09 INFO - 'MOZ_NO_REMOTE': '1', 22:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:09 INFO - 'NO_EM_RESTART': '1', 22:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:09 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:09 INFO - 'SHELL': '/bin/bash', 22:00:09 INFO - 'SHLVL': '1', 22:00:09 INFO - 'TERM': 'linux', 22:00:09 INFO - 'TMOUT': '86400', 22:00:09 INFO - 'USER': 'cltbld', 22:00:09 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', 22:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:09 INFO - '_': '/tools/buildbot/bin/python'} 22:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:00:09 INFO - Running setup.py (path:/tmp/pip-Ch9LvK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:00:09 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)) 22:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:00:09 INFO - Running setup.py (path:/tmp/pip-LdTu4E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:00:09 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)) 22:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:00:09 INFO - Running setup.py (path:/tmp/pip-atQKrX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:00:09 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)) 22:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:00:09 INFO - Running setup.py (path:/tmp/pip-xpat8b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:00:09 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)) 22:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:00:09 INFO - Running setup.py (path:/tmp/pip-ug1fbf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:00:10 INFO - Running setup.py (path:/tmp/pip-us6wBu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:00:10 INFO - Running setup.py (path:/tmp/pip-Wh_ORd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:00:10 INFO - Running setup.py (path:/tmp/pip-Rl7NFl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:00:10 INFO - Running setup.py (path:/tmp/pip-aZI66X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:00:10 INFO - Running setup.py (path:/tmp/pip-Q7mZ4T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:00:10 INFO - Running setup.py (path:/tmp/pip-RhNUFo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:00:10 INFO - Running setup.py (path:/tmp/pip-Ad85Zc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:00:10 INFO - Running setup.py (path:/tmp/pip-ivyMND-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:00:10 INFO - Running setup.py (path:/tmp/pip-ZBacDN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:00:10 INFO - Running setup.py (path:/tmp/pip-bWScDh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:00:10 INFO - Running setup.py (path:/tmp/pip-k3WMuA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:00:10 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)) 22:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:00:10 INFO - Running setup.py (path:/tmp/pip-bdyNoJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:00:10 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)) 22:00:10 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)) 22:00:10 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)) 22:00:10 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)) 22:00:10 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)) 22:00:10 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)) 22:00:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:12 INFO - Downloading blessings-1.5.1.tar.gz 22:00:12 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 22:00:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:00:12 INFO - Installing collected packages: blessings 22:00:12 INFO - Running setup.py install for blessings 22:00:12 INFO - Successfully installed blessings 22:00:12 INFO - Cleaning up... 22:00:12 INFO - Return code: 0 22:00:12 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:00:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:00:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:00:12 INFO - Reading from file tmpfile_stdout 22:00:12 INFO - Current package versions: 22:00:12 INFO - argparse == 1.2.1 22:00:12 INFO - blessings == 1.5.1 22:00:12 INFO - blobuploader == 1.2.4 22:00:12 INFO - docopt == 0.6.1 22:00:12 INFO - manifestparser == 1.1 22:00:12 INFO - mozInstall == 1.12 22:00:12 INFO - mozcrash == 0.16 22:00:12 INFO - mozdebug == 0.1 22:00:12 INFO - mozdevice == 0.47 22:00:12 INFO - mozfile == 1.2 22:00:12 INFO - mozhttpd == 0.7 22:00:12 INFO - mozinfo == 0.9 22:00:12 INFO - mozleak == 0.1 22:00:12 INFO - mozlog == 3.0 22:00:12 INFO - moznetwork == 0.27 22:00:12 INFO - mozprocess == 0.22 22:00:12 INFO - mozprofile == 0.27 22:00:12 INFO - mozrunner == 6.11 22:00:12 INFO - mozscreenshot == 0.1 22:00:12 INFO - mozsystemmonitor == 0.0 22:00:12 INFO - moztest == 0.7 22:00:12 INFO - mozversion == 1.4 22:00:12 INFO - psutil == 3.1.1 22:00:12 INFO - requests == 1.2.3 22:00:12 INFO - wsgiref == 0.1.2 22:00:12 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:12 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 0x7f850bf78c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b196c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bda3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c638c0>, '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 0x2c10420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c108b0>, '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': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', '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 22:00:12 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 22:00:12 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 22:00:12 INFO - Using env: {'DISPLAY': ':0', 22:00:12 INFO - 'HOME': '/home/cltbld', 22:00:12 INFO - 'LANG': 'en_US.UTF-8', 22:00:12 INFO - 'LANGUAGE': 'en_US:en', 22:00:12 INFO - 'LOGNAME': 'cltbld', 22:00:12 INFO - 'MAIL': '/var/mail/cltbld', 22:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:12 INFO - 'MOZ_NO_REMOTE': '1', 22:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:12 INFO - 'NO_EM_RESTART': '1', 22:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:12 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:12 INFO - 'SHELL': '/bin/bash', 22:00:12 INFO - 'SHLVL': '1', 22:00:12 INFO - 'TERM': 'linux', 22:00:12 INFO - 'TMOUT': '86400', 22:00:12 INFO - 'USER': 'cltbld', 22:00:12 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', 22:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:12 INFO - '_': '/tools/buildbot/bin/python'} 22:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:13 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)) 22:00:13 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)) 22:00:13 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)) 22:00:13 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)) 22:00:13 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)) 22:00:13 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)) 22:00:13 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)) 22:00:13 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)) 22:00:13 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)) 22:00:13 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)) 22:00:13 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)) 22:00:13 INFO - Cleaning up... 22:00:13 INFO - Return code: 0 22:00:13 INFO - Running post-action listener: _resource_record_post_action 22:00:13 INFO - Running post-action listener: _start_resource_monitoring 22:00:13 INFO - Starting resource monitoring. 22:00:13 INFO - ##### 22:00:13 INFO - ##### Running install step. 22:00:13 INFO - ##### 22:00:13 INFO - Running pre-action listener: _resource_record_pre_action 22:00:13 INFO - Running main action method: install 22:00:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:00:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:00:13 INFO - Reading from file tmpfile_stdout 22:00:13 INFO - Detecting whether we're running mozinstall >=1.0... 22:00:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:00:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:00:13 INFO - Reading from file tmpfile_stdout 22:00:13 INFO - Output received: 22:00:13 INFO - Usage: mozinstall [options] installer 22:00:13 INFO - Options: 22:00:13 INFO - -h, --help show this help message and exit 22:00:13 INFO - -d DEST, --destination=DEST 22:00:13 INFO - Directory to install application into. [default: 22:00:13 INFO - "/builds/slave/test-pgo"] 22:00:13 INFO - --app=APP Application being installed. [default: firefox] 22:00:13 INFO - mkdir: /builds/slave/test-pgo/build/application 22:00:13 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'] 22:00:13 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 22:00:27 INFO - Reading from file tmpfile_stdout 22:00:27 INFO - Output received: 22:00:27 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:00:27 INFO - Running post-action listener: _resource_record_post_action 22:00:27 INFO - ##### 22:00:27 INFO - ##### Running run-tests step. 22:00:27 INFO - ##### 22:00:27 INFO - Running pre-action listener: _resource_record_pre_action 22:00:27 INFO - Running main action method: run_tests 22:00:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:00:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:00:27 INFO - Python 2.7.3 22:00:27 INFO - Return code: 0 22:00:27 INFO - grabbing minidump binary from tooltool 22:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:27 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 0x2c638c0>, '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 0x2c10420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c108b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:00:27 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 22:00:27 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 22:00:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:00:27 INFO - Return code: 0 22:00:27 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:00:27 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:00:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:00:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:00:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:00:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 22:00:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost 22:00:27 INFO - Using env: {'DISPLAY': ':0', 22:00:27 INFO - 'HOME': '/home/cltbld', 22:00:27 INFO - 'LANG': 'en_US.UTF-8', 22:00:27 INFO - 'LANGUAGE': 'en_US:en', 22:00:27 INFO - 'LOGNAME': 'cltbld', 22:00:27 INFO - 'MAIL': '/var/mail/cltbld', 22:00:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:00:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:27 INFO - 'MOZ_NO_REMOTE': '1', 22:00:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:27 INFO - 'NO_EM_RESTART': '1', 22:00:27 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:27 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:27 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:00:27 INFO - 'SHELL': '/bin/bash', 22:00:27 INFO - 'SHLVL': '1', 22:00:27 INFO - 'TERM': 'linux', 22:00:27 INFO - 'TMOUT': '86400', 22:00:27 INFO - 'USER': 'cltbld', 22:00:27 INFO - 'XDG_SESSION_COOKIE': '447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248', 22:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:27 INFO - '_': '/tools/buildbot/bin/python'} 22:00:27 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost'] with output_timeout 3600 22:00:28 INFO - mozversion INFO | application_buildid: 20151116193328 22:00:28 INFO - mozversion INFO | application_changeset: a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb 22:00:28 INFO - mozversion INFO | application_display_name: Nightly 22:00:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:00:28 INFO - mozversion INFO | application_name: Firefox 22:00:28 INFO - mozversion INFO | application_remotingname: firefox 22:00:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 22:00:28 INFO - mozversion INFO | application_vendor: Mozilla 22:00:28 INFO - mozversion INFO | application_version: 45.0a1 22:00:28 INFO - mozversion INFO | platform_buildid: 20151116193328 22:00:28 INFO - mozversion INFO | platform_changeset: a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb 22:00:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 22:00:28 INFO - mozversion INFO | platform_version: 45.0a1 22:00:28 INFO - 2015-11-16 22:00:28,186 DEBUG : using testdate: 1447740028 22:00:28 INFO - 2015-11-16 22:00:28,186 DEBUG : actual date: 1447740028 22:00:28 INFO - 2015-11-16 22:00:28,195 INFO : starting webserver on 'localhost:39503' 22:00:28 INFO - 2015-11-16 22:00:28,196 INFO : Starting test suite talos-linux64-ix-027 22:00:28 INFO - 2015-11-16 22:00:28,196 INFO : Starting test dromaeo_css 22:00:28 INFO - 2015-11-16 22:00:28,197 DEBUG : operating with platform_type : linux_ 22:00:28 INFO - 2015-11-16 22:00:28,197 INFO : Initialising browser for dromaeo_css test... 22:00:28 INFO - 2015-11-16 22:00:28,211 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp5tJFuz/profile http://localhost:39503/getInfo.html 22:00:32 INFO - 2015-11-16 22:00:32,624 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:00:32 INFO - 2015-11-16 22:00:32,625 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:00:32 INFO - 2015-11-16 22:00:32,625 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:00:32 INFO - 2015-11-16 22:00:32,625 DEBUG : BROWSER_OUTPUT: __metrics 22:00:32 INFO - 2015-11-16 22:00:32,963 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:00:34 INFO - 2015-11-16 22:00:34,182 INFO : Browser initialized. 22:00:34 INFO - 2015-11-16 22:00:34,182 INFO : Running cycle 1/1 for dromaeo_css test... 22:00:34 INFO - 2015-11-16 22:00:34,183 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp5tJFuz/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:00:45 INFO - 2015-11-16 22:00:45,944 DEBUG : BROWSER_OUTPUT: RSS: Main: 141897728 22:00:45 INFO - 2015-11-16 22:00:45,944 DEBUG : BROWSER_OUTPUT: 22:01:37 INFO - 2015-11-16 22:01:37,491 DEBUG : BROWSER_OUTPUT: 1447740097490 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] 22:03:45 INFO - 2015-11-16 22:03:45,645 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39503/tests/dromaeo/cssquery-ext.html) 22:03:46 INFO - 2015-11-16 22:03:46,616 DEBUG : BROWSER_OUTPUT: RSS: Main: 223657984 22:03:46 INFO - 2015-11-16 22:03:46,616 DEBUG : BROWSER_OUTPUT: 22:06:48 INFO - 2015-11-16 22:06:48,964 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/dromaeo/cssquery-ext.html (next: http://localhost:39503/tests/dromaeo/cssquery-jquery.html) 22:06:49 INFO - 2015-11-16 22:06:49,525 DEBUG : BROWSER_OUTPUT: RSS: Main: 229556224 22:06:49 INFO - 2015-11-16 22:06:49,525 DEBUG : BROWSER_OUTPUT: 22:09:51 INFO - 2015-11-16 22:09:51,407 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39503/tests/dromaeo/cssquery-mootools.html) 22:09:51 INFO - 2015-11-16 22:09:51,980 DEBUG : BROWSER_OUTPUT: RSS: Main: 220991488 22:09:51 INFO - 2015-11-16 22:09:51,981 DEBUG : BROWSER_OUTPUT: 22:12:53 INFO - 2015-11-16 22:12:53,853 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39503/tests/dromaeo/cssquery-prototype.html) 22:12:54 INFO - 2015-11-16 22:12:54,418 DEBUG : BROWSER_OUTPUT: RSS: Main: 204169216 22:12:54 INFO - 2015-11-16 22:12:54,418 DEBUG : BROWSER_OUTPUT: 22:15:56 INFO - 2015-11-16 22:15:56,913 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39503/tests/dromaeo/cssquery-yui.html) 22:15:57 INFO - 2015-11-16 22:15:57,474 DEBUG : BROWSER_OUTPUT: RSS: Main: 204333056 22:15:57 INFO - 2015-11-16 22:15:57,474 DEBUG : BROWSER_OUTPUT: 22:18:59 INFO - 2015-11-16 22:18:59,811 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/dromaeo/cssquery-yui.html (next: http://localhost:39503/tests/dromaeo/cssquery-dojo.html) 22:19:00 INFO - 2015-11-16 22:19:00,069 DEBUG : BROWSER_OUTPUT: RSS: Main: 252424192 22:19:00 INFO - 2015-11-16 22:19:00,069 DEBUG : BROWSER_OUTPUT: 22:19:00 INFO - 2015-11-16 22:19:00,070 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:19:00 INFO - 2015-11-16 22:19:00,070 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:19:00 INFO - 2015-11-16 22:19:00,070 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:19:00 INFO - 2015-11-16 22:19:00,070 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:19:00 INFO - 2015-11-16 22:19:00,070 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3129.7183253507183;3159.447096758067;3191.266008817972;3201.695838895304;3220.0822765511834;4999.07517109335;5120.231965205221;5228.607854410915;5300.443453437387;5317.734113294339;5241.344831896013;5255.211718242265;5306.973465132679;5364.356277246736;5380.9192862107;4785.976070119645;4809.326694262789;4837.709737415748;4906.386701662293;4978.153713868651;4970.105381031413;4990.126727822635;5026.597872170218;5049.242613607951;5075.441701412841;4663.183942809995;4751.881202969937;4759.976200118977;4843.346148269974;4856.975715121437;4836.250381190921;4842.44311904132;4855.63582731293;4865;4889.022195560902;4820.686655367425;4833.081714474274;4833.178359678863;4846.418429788413;4895.241237608177;5050.621203409759;5090.338256026693;5116.897662046738;5142.871428214287;5178.093833579109;5659.207710920475;5660.29246344207;5672.06410942194;5677.886442271173;5681.147827825818;5416.51251387377;5697.31632204134;5699.572532060112;5699.658020518741;5708.571857110692;5301.443348448396;5340.839774806723;5385.353757549119;5414.8917021659345;5433.1035379162395;5782.392848750821;5794.82615521535;5813.331466881363;5815.41845815415;5864.296284445893;5488.643238189585;5606.439356064361;5620.91568626471;5627.409122042127;5630.5495560355075;5590.440955904377;5681.545476361881;5769.365369809318;5809.157672137517;5811.476967072984;5736.598438109376;5738.254026976467;5739.7704091836695;5743.167240750068;5750.1949727038245;5441.59188060897;5730.914036289459;5733;5736.196932429463;5739.483446489836;4356.368326592689;4372.912541749147;4407.911841763147;4411.735295882258;4443.37792709021;3885.0675837798726;3889.4749208857006;3892.2993861105274;3892.6885849132;3932.193900250442;5194.532492075656;5291;5350.27771250884;5378.354597448253;5399.774802704327;5605.607607467438;5678.063119585222;5678.68767217807;5694.800681976111;5727.5131613812455;5361.276227709287;5375.139977603565;5379.731013449312;5380.112281473513;5380.757865896044;4724.244120940633;4807.351007613927;4937.382827146606;4972.229304457815;5022.372203474566;5967.045272756425;5967.731452084667;5973.940260597426;5977.163197152402;5994.100884867305;5796.913046304225;5818.981678206213;5819.70901454934;5821.476067153976;5824.737886795019;5682.545396368363;5693.231413759089;5837.1244313351635;5855.765769369348;5933.347331793586;5534.335879694462;5537.36320323168;5540.501354878081;5543.417941116125;5578.86052848682;5597.04850175463;5600.85997850057;5603.075492543685;5603.131514615241;5618.101103823368;5256.053910296183;5362.624616276823;5460.535854452416;5519.089350257163;5587.69267690281;5590.636608620427;5629.662220266797;5633.267675202197;5636.379998200113;5638.9436105638415;5267.051930652519;5529.170624406372;5546.750396232099;5551.389347171727;5553;5507;5585.972070139624;5716.228309178207;5716.42835716425;5759.337676167294;5393.83818485446;5406.945930540802;5407.83776486706;5432.103702889137;5485.835424937259 22:19:00 INFO - 2015-11-16 22:19:00,070 DEBUG : BROWSER_OUTPUT: |1;ext.html;596.394659420688;598.5899658733767;599.3616798110013;601.9969900150498;602.773959765088;30933.845330773373;39582.40626390603;40107.59892401075;40194.799026004905;40244.597554024454;41859;42390.36414453786;42458.36312455316;42566.36150457746;42571;7521.05986751656;8725.738227853175;8833.89576302962;8847.115288471166;8917.375783695144;7590.6963721451075;10497.632582859602;10769;10771;10772.5690972361;10404.167666586693;10463.895361046407;10481.737956551071;10496.370217786956;10508.527116279747;7596.278353556403;7781.727639532589;10364.740881477948;10392.168626509861;10462.058414742634;7534.623268836591;10510.684679459628;10775.4612269387;10776.461176941122;10784.892151078468;38793.80603096967;39243;39260.80369598162;39339.606603933884;39685.60314396848;3231.8545665445113;3264.0860559043504;3287.4575695010294;3626.492291079251;4097.078157414588;29264.41471170586;29503.852480737674;29611.259718507208;29712.257193570116;29878.10365689011;3340.4822252550894;3354.3123659649714;3398.762086653935;3453.0849324928913;3539.2744487380023;270898;271797;272593;272697;275008;50472.747636261585;51376;56968;57493.13760293599;57810.42189578134;57802;57820.71089644525;57902;58155.709221453624;58230.7088464555;15700.450484232997;15794.763078553831;15819.446319378767;15838.92080539613;15887.52337429879;12131.939340303243;12198.756024879453;12200.145989780807;12203.755924881452;12208;18893.149808258662;18897.338593149172;18950.336738214373;19035.333763318216;19044.904775476032;7376.3730082942475;7508.587027713528;7565.205653406313;7645.3527418898;7767.844643107106;952.6189524190379;966.5457235099492;1014.9797004059877;1017.9134773544183;1114.2367478277406;24193.274201773977;24715.87642061778;26650.333741656614;26800;26986.865065674545;1003.4380746781826;1013.0274936061463;1042.9113525350278;1122.180808010141;1124.0894875151155;13642.59072227815;13727.656808579666;13952.093113947562;14006.229657368738;14015.929920350332;317486;320756;321437;322478;322795;50890.236646450336;51089;51111;51117.74441127845;51216;1300.466808608466;1306.3272414706435;1310.7247478029722;1317.6639956811143;1338.417788262109;22795.65806512937;27533.724662753117;28093.297667557657;28141.718582813908;28471.28821779389;42725.786371067945;43351.34972975471;43435;43437;43455.348169776844;271430;271690;273117;274365;275056;27197.728022720312;27614.861925691046;30808.38383232278;31756.841215793775;32425.675743242267;40506.79746601248;40727.79636101801;41193;41990.58009419867;42158.1568368637;34790.47814282737;34966.65033349634;35048.47427288542;35540;36008.63991360053;31066.844665776527;33681.3263734719;34987.47518787169;35389.64610353864;35645.28709425745;32105.839470802497;32832;34376.312473750884;34800.65199347974;34957.47563786596;31486.212844679067;32279.193020174684;32495.675043249266;32580.51129233111;32640.83679581587;24327.742645036695;24613.63079553844;24691.753082468946;24977.50044999126;24998.875005624854 22:19:00 INFO - 2015-11-16 22:19:00,070 DEBUG : BROWSER_OUTPUT: |2;jquery.html;350.09849637184254;351.8029903254176;352.17766515187037;354.1288430461066;355.07148805872635;14007.229602371866;14444;16131.91934040331;16898.49304520865;17578.91210543947;16044.35822567096;16467.752983705253;17002.234899429524;17361.131943402812;17604.735928961076;5112.846614601559;5268.446813084628;5272.1300985337375;5275.393329767068;5275.736213189344;3981.223661386031;4076.429299898017;5238.3975842778;5678.233438485793;5841.240638716982;5838.503727183194;5851.4148585141575;5858.589898707092;5888.087346461305;5907.409259074079;5658.91511627326;5767;5774.538036957033;5780.479756821865;5785.681787501686;5047.419546752134;5053.0399224147295;5070.138076527;5070.188769796815;5070.645054846163;20766.273180438613;21231.787682123137;21334.46663833401;21347.67978480324;21392.14431422741;2094.6229678657837;2121.5332626822074;2254.740704818934;2274.783895529922;2602.596597527386;12946.287954162517;13134.671633209151;13135.86864131356;13136.540221092217;13580.320983950764;1766.055160489132;1786.1337251433044;1813.6463389639025;1820.5084627150727;1852.51834523025;59238.8532802704;61491.692541537006;61650;61694.69152654253;62389;9027.36808423404;9311.348205625676;9894.60421583143;9910.900890990997;9969.55137018836;12726.363681816058;12743.107982441326;12754.170978886343;12789.488420463078;12804.359782010863;5464.9180262296095;5638.464345887134;5670;5754.74103665336;7181.533200341961;5922.703864806743;5926.940730592725;5975.37258587842;5980.85047873798;5982.611130276518;3277.721393681516;3279.409706252897;3300.1914530940076;3337.1823903143886;3340.048086295394;1142.9542818287175;1147.460693474066;1154.6651471073294;1183.4733543573027;1197.8382918306093;457.30033049434417;459.9402077729941;461.2389557230529;474.7127987567541;481.41748484333647;11721.4139293035;12117.697637449033;12402;12432.813507797391;12469.563565275172;494.8614387971374;503.69778133119837;508.2198824803908;522.8274669359179;523.743365750787;6199.291452522311;6650.501212408992;6793.218779840283;6795.2185498667295;6830.692618832164;381544;383823;387322;388657;513381;59297.70351148303;63340.68329658322;73878.63060684662;79967.20032799598;80751;482.60185347088793;498.38449514849134;501.0930216308493;501.6313009937693;503.2250334484925;9792.657256995972;10084.899151008396;10186.847197291898;10197.54111064989;10333.018363255478;27484.58773118445;32399.676003239667;33501.83249083739;34078;35794.92615221548;45845.77077114593;46610;47363;48082.27876581924;48189;6333.398327158912;6333.8733225336155;6490.221173459215;6497;6497.18785151856;11009.449527523751;11036.724081898017;11112.444377780917;11510.36692981894;11750.823737643772;9842.803143937224;9852.852207217042;9908.900910990798;9916.454594997344;10289.74275643115;9695.175910047443;9813.509324533601;10003.499825008575;10252.58989640406;10276.434796086287;9972.60109595608;10396.532156053147;10442.530086146293;10506.21203409732;10541.472926353805;6339.049142628643;6404.103425520524;6409.070684750778;6411;6414.743410263537;9818.361806482555;9822.950885245815;10138.49307534635;10145.18838492904;10166 22:19:00 INFO - 2015-11-16 22:19:00,071 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1291.689994401344;1683.3182561062767;1719.4927496388564;1743.5118166913269;1752.1326943163133;6555.77054803082;7221.638918054096;7356.74251401201;7371.115466144063;7374.446916481258;7471.514351567144;7599.468037237396;7604.467687261894;7604.543727376361;7611.391088712903;1974.8123928226796;2005.8997050147507;2152.569486102778;2174.293354659734;2244.046280330861;2257.909683612662;2261.8642881427086;2262.875541845198;2264.388615073929;2266.648669456237;2181.2583721534675;2220.322801545528;2223;2223.2329846203115;2226.209695558078;1923.9134238959211;1989.7512810898638;2197.8681279123225;2233.251860626692;2235.854669446481;2243.2821497120926;2259.8531095478743;2288.954220915589;2290.267114523353;2309.6881920940796;7406.518576292524;7412.110546734372;7464.626768661599;7469.589172595505;7481.738139165103;4581.129585378767;4674.579287864109;4706.270528068155;4706.270528068155;4706.694064885772;2773.126465163467;2842.332051967786;2919.6350456192977;2964.496035673919;2967.4361871244396;862.8015556421996;863.7538301584018;911.5806728904712;919.9264058875274;1851.6759567075708;9964.352317099365;9972.252081093875;10026.899731002743;10058.195344372505;10084.747881302952;3974.6025397460603;4000.3799411091327;4074;4163.770992595376;4173.478315210598;8580.099089595627;8586.570671466401;8598.828023439497;8602.569871506399;8614.569271536398;4549.931751023737;4567.223571992769;4587.4036375271;4592.380028696083;4597.057603191338;4158.625723684883;4183.163367326486;4196.8111434984885;4197.034682023152;4202.117555313418;3395.966040339614;3425.3663072331697;3456.723462123024;3478.6869181773764;3479.6868281854754;1068.551208492435;1070.9143268538498;1073.6725298783947;1077.407425915759;1090.7327704712395;932.1191474057086;1280.2510531339271;1285.3252042677482;1326.1446367093172;1327.9004074694437;6018.095546845023;7267.345938865527;7465.962670186615;7532.058492688414;7534.962325188449;704.5068452083563;717.4547344018479;723.3453724379445;730.3317464520011;733.5818583407513;5557.661612130918;5711.428857114255;5716.942830571724;5733.885322293614;5766.336871259775;8447.746567602982;8607.956960215159;8616.439931404439;8623;8635.525046122399;8236.135205803424;8429.199226073513;8503.447275927045;8506.78733031679;8527.488350698977;6723.890558057963;6782.423494002966;6822.11312529368;6840;6855.245922948372;6573.901391479036;6574.868502630016;6599.835004124935;6601.141851559267;6603;7950.165232650488;7997.880031799644;8109.7161599343735;8235.423520353517;8297.25324720778;7924.4056695749205;8000;8027.357811374959;8136.877946830684;8138.186181381814;6767.796966091025;6947.201071876799;6960.651967401711;6961.8955715665315;6963.825904352431;7301.233370496021;7304.926950730637;7306.269373062863;7314.743983960536;7476.140243872024;6938.306169383223;7007.719691212498;7057.258987806412;7120.964395177991;7185.604791736505;6944.4444444445335;6997.26528714498;7008.579485230903;7077;7128.786136415916;6028.3971602839365;6131.785387511416;6571.145751052524;6663.100481434944;6691.029800678972;6256.968715156395;6440;6487.156669632917;6503.219613646393;6505.869882602226;6473.158489396348;6577.408033277028;6626.602403855784;6627.039079333566;6633.867322653617 22:19:00 INFO - 2015-11-16 22:19:00,072 DEBUG : BROWSER_OUTPUT: |4;prototype.html;325.86965213914436;328.2302999466252;330.67593758117044;338.28452822280883;339.8997295797739;5832.533397328214;5981.371955944628;5994.8201553953395;6011.248593925759;6022.54830887683;5747;5874.970625146868;5898.439648233411;5900.1739756434135;5901.111644273834;4550.408446901906;4555.498895121534;4556.270996640538;4557.567031132037;4578.816847326103;4392.428984232045;4475.104979004196;4478.440194975628;4553.089382123589;4634.675572709912;4565.292379681155;4577.244754615484;4593.862184134481;4594.563516465931;4607.700499467541;4559.996800703856;4562.680612357136;4576.954230457686;4578.10726908253;4586.449626044896;4003.539592946819;4009.1380353224195;4012.6789856811383;4025.677945764332;4028.47629808126;5495.945040549584;5655.632383895034;5675.6878371689545;5696.145578163268;5705.62913410627;5278;5285.471452854722;5288.862894477706;5290.259363689087;5291.047611429941;6379;6390;6390.81769872575;6392.169018027673;6395.456386207178;4343.06624075822;4346;4346.739195648272;4356.477222733292;4361.869143925687;6361.586496877734;6546;6595.109660195908;6607.30623284553;6607.372299631527;4448.154850578379;4474.463064432224;4476.977615111904;4483.977580112079;4494.685372023927;4524.932126018112;4530.003399252159;4533.795979180944;4534.229181039232;4536.070105628338;5169.71566563835;5221.530062294411;5267.499587539178;5279.076161671692;5282.577393808486;6368.904466433008;6379.330170332141;6390.744370225232;6391.96804015977;6397.840053998687;1574.181425658651;1598.968020639604;1599.704054749875;1599.9200039997954;1600.927958241882;1019.8062368150028;1040.812653722322;1042.83314669654;1054.8786889507805;1067.2422579968154;6183.474404675563;6196.287426945959;6196.938030619726;6198.2871969723155;6200.472959798468;6384.138141350858;6492.772752953624;6506.41442270198;6617.933820661828;6622.337766223339;6216.129741836146;6231.813045608639;6257.2491301044165;6260.718267677966;6262.749490020439;3579.069441945113;3594.101474631342;3604.387254166798;3605.5673319201337;3613.56637203532;6669.166354205724;6819.215790184093;6819.829504262433;6820.727170913207;6826.692798823966;6817.11377520929;6823.522353435311;6830.829229269308;6837;6838.093810130675;6684.632345220963;6704.932950670431;6726.091977583062;6733.966330168416;6734.023566582818;6546.574472659262;6626.701798418986;6651.933480665325;6661.700223489857;6667.366600172976;6586.571872828251;6589.110470086477;6593;6594.934050659624;6597.934020659732;6816.1820581528555;6821.283765204582;6822.454203663596;6831.760888368884;6832.350926661959;4332.696711230184;4461.085477477044;4464.955350446584;4503.311258278146;4505.549445055468;6582.572132811543;6584;6590.901136483052;6596.571222870498;6598.307177746267;6422.3577642235405;6426.485881129405;6438.774642887476;6443.420092191725;6445.677716114269;6258.624482531062;6414.166158399378;6422.293547709842;6475.579087359307;6481.125048118443;6355.777547785805;6358.713857876314;6359.395857393541;6362.650054247061;6363.140975968185;6505.121808555785;6510.056041873996;6517.837054073686;6524.771632992823;6526.771562995273;6101.542384321105;6193.163922870355;6214.937850621436;6340.71466784005;6353.0152826312 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: |5;yui.html;739.6301849075461;829.8921140251766;839.6767244610822;842.578710644678;889.5418859287469;10382.377057376554;10468.162546996242;10473.057424831764;10481.161507079434;10536.525856336464;13299;13327.067105302633;13328.533501327456;13335.933320333408;13343.733125337487;682.6006786030179;691.2430888177438;779.111812533712;808.3129340060931;818.9836203275961;817.4931542443693;821.8808272800441;822.3462347433805;823.2672921100195;823.3043078598574;811.8944537210155;849.3417601358934;853.1340689200435;861.2378045429778;861.4745005546612;723.236984980845;728.1626129950573;843.4770442325736;855.5038077914794;856.2251162697769;619.1672200889795;621.1273161208509;621.2886245249209;621.4407033669688;626.2266101364811;12872.935635321857;12963;12997.350132493431;12999.090063695545;13002.609921702364;9834.655787047419;9893.060159284856;10125.493725313778;10232.232582556264;10352.948235258775;9643.167798118335;10174.236932230038;10216.387016779017;10225.693229203136;10376;3281.49136883781;3325.9501107483406;3345.3978283908887;3416.385050690875;3418.8632454701783;46452.303215452135;46569.76715116403;46687.29969050467;46713.299300510516;46883.765581172214;7606.353459955966;7799.181085986003;7945.523268603903;8043.477173983672;8108.918910810934;12367.752644947052;12394.194377365442;12396.75206495865;12397.442115104843;12409.565665201675;6643.1031810704935;6763.7632682855865;6856.314368563204;6984.4063254623925;6990;3857.189990102054;3915.3735402335487;3928.194720082376;3948.624880636335;3960.861369852099;2735.3161709572605;2748.051922086877;2751.3671855473353;2753.5731961545994;2753.931151721183;425.40868193211463;432.0882937002921;437.02543328377897;438.59210933831207;446.5757530346184;491.9581835544019;506.9771860266297;515.996387027236;555.0536335547881;558.1822629847293;8372.455790373606;8753.781155471164;8786;8886.107012811539;8975.596098175598;563.8703098287417;653.4217217762315;663.7013343995221;684;700.1737949219868;3947.703922205846;4134.090500089976;4161.750294982311;4193.97903010483;4269.978650106729;56808.715956419954;57313;57521;57639;57647.71176144092;48107.93286615668;48223.75888120537;48586;48944;50050.74974625104;48000.7599961998;48033.27950080752;48430.5156948426;49089.50910490921;49123.50876491261;8372.581370931308;8538.658453661785;8838;8851.291896648381;9016;36379.45430818593;36724.63275367212;36762.448563271035;37565.4365184517;37604.247915041;41805;41910.161796763285;44463.11073778571;46100.30849537192;46557.76721116373;8517;8629.69796057135;8728.127187281221;8922.420042697204;8945.418547794372;12479.251244925334;12773.361331933553;12991.870081299066;13117.540886068942;13218.669533261746;11631.6510504685;11817.586384476503;12285.754284914074;12379.133460657667;12431.93784031074;11390.715232119263;11431.256968297033;11458.94270528642;11908.940455297668;11952.223105498115;7177.7487787927175;11667.824982625438;11674.533018679158;11707.356095414494;12142.453589588313;9079.319051071328;10227.84658230142;10352.378857268588;10539.841902371318;10667.519961601902;9258.861117083114;9369.718908432758;9557.569909354235;9784.559694813894;9874.50627468638 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,723 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: __startTimestamp1447741140063__endTimestamp 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: 22:19:00 INFO - 2015-11-16 22:19:00,074 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5283.50 Median:5433.10 stddev:586.25 (10.8%) stddev-sans-first:563.18 22:19:00 INFO - 2015-11-16 22:19:00,075 DEBUG : BROWSER_OUTPUT: Values: 3129.7 3159.4 3191.3 3201.7 3220.1 4999.1 5120.2 5228.6 5300.4 5317.7 5241.3 5255.2 5307.0 5364.4 5380.9 4786.0 4809.3 4837.7 4906.4 4978.2 4970.1 4990.1 5026.6 5049.2 5075.4 4663.2 4751.9 4760.0 4843.3 4857.0 4836.3 4842.4 4855.6 4865.0 4889.0 4820.7 4833.1 4833.2 4846.4 4895.2 5050.6 5090.3 5116.9 5142.9 5178.1 5659.2 5660.3 5672.1 5677.9 5681.1 5416.5 5697.3 5699.6 5699.7 5708.6 5301.4 5340.8 5385.4 5414.9 5433.1 5782.4 5794.8 5813.3 5815.4 5864.3 5488.6 5606.4 5620.9 5627.4 5630.5 5590.4 5681.5 5769.4 5809.2 5811.5 5736.6 5738.3 5739.8 5743.2 5750.2 5441.6 5730.9 5733.0 5736.2 5739.5 4356.4 4372.9 4407.9 4411.7 4443.4 3885.1 3889.5 3892.3 3892.7 3932.2 5194.5 5291.0 5350.3 5378.4 5399.8 5605.6 5678.1 5678.7 5694.8 5727.5 5361.3 5375.1 5379.7 5380.1 5380.8 4724.2 4807.4 4937.4 4972.2 5022.4 5967.0 5967.7 5973.9 5977.2 5994.1 5796.9 5819.0 5819.7 5821.5 5824.7 5682.5 5693.2 5837.1 5855.8 5933.3 5534.3 5537.4 5540.5 5543.4 5578.9 5597.0 5600.9 5603.1 5603.1 5618.1 5256.1 5362.6 5460.5 5519.1 5587.7 5590.6 5629.7 5633.3 5636.4 5638.9 5267.1 5529.2 5546.8 5551.4 5553.0 5507.0 5586.0 5716.2 5716.4 5759.3 5393.8 5406.9 5407.8 5432.1 5485.8 22:19:00 INFO - 2015-11-16 22:19:00,075 DEBUG : BROWSER_OUTPUT: 22:19:00 INFO - 2015-11-16 22:19:00,075 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45432.47 Median:177378.60 stddev:75659.84 (42.7%) stddev-sans-first:75797.19 22:19:00 INFO - 2015-11-16 22:19:00,075 DEBUG : BROWSER_OUTPUT: Values: 596.4 598.6 599.4 602.0 602.8 30933.8 39582.4 40107.6 40194.8 40244.6 41859.0 42390.4 42458.4 42566.4 42571.0 7521.1 8725.7 8833.9 8847.1 8917.4 7590.7 10497.6 10769.0 10771.0 10772.6 10404.2 10463.9 10481.7 10496.4 10508.5 7596.3 7781.7 10364.7 10392.2 10462.1 7534.6 10510.7 10775.5 10776.5 10784.9 38793.8 39243.0 39260.8 39339.6 39685.6 3231.9 3264.1 3287.5 3626.5 4097.1 29264.4 29503.9 29611.3 29712.3 29878.1 3340.5 3354.3 3398.8 3453.1 3539.3 270898.0 271797.0 272593.0 272697.0 275008.0 50472.7 51376.0 56968.0 57493.1 57810.4 57802.0 57820.7 57902.0 58155.7 58230.7 15700.5 15794.8 15819.4 15838.9 15887.5 12131.9 12198.8 12200.1 12203.8 12208.0 18893.1 18897.3 18950.3 19035.3 19044.9 7376.4 7508.6 7565.2 7645.4 7767.8 952.6 966.5 1015.0 1017.9 1114.2 24193.3 24715.9 26650.3 26800.0 26986.9 1003.4 1013.0 1042.9 1122.2 1124.1 13642.6 13727.7 13952.1 14006.2 14015.9 317486.0 320756.0 321437.0 322478.0 322795.0 50890.2 51089.0 51111.0 51117.7 51216.0 1300.5 1306.3 1310.7 1317.7 1338.4 22795.7 27533.7 28093.3 28141.7 28471.3 42725.8 43351.3 43435.0 43437.0 43455.3 271430.0 271690.0 273117.0 274365.0 275056.0 27197.7 27614.9 30808.4 31756.8 32425.7 40506.8 40727.8 41193.0 41990.6 42158.2 34790.5 34966.7 35048.5 35540.0 36008.6 31066.8 33681.3 34987.5 35389.6 35645.3 32105.8 32832.0 34376.3 34800.7 34957.5 31486.2 32279.2 32495.7 32580.5 32640.8 24327.7 24613.6 24691.8 24977.5 24998.9 22:19:00 INFO - 2015-11-16 22:19:00,075 DEBUG : BROWSER_OUTPUT: 22:19:00 INFO - 2015-11-16 22:19:00,075 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:23769.11 Median:46986.50 stddev:68090.29 (144.9%) stddev-sans-first:68258.60 22:19:00 INFO - 2015-11-16 22:19:00,075 DEBUG : BROWSER_OUTPUT: Values: 350.1 351.8 352.2 354.1 355.1 14007.2 14444.0 16131.9 16898.5 17578.9 16044.4 16467.8 17002.2 17361.1 17604.7 5112.8 5268.4 5272.1 5275.4 5275.7 3981.2 4076.4 5238.4 5678.2 5841.2 5838.5 5851.4 5858.6 5888.1 5907.4 5658.9 5767.0 5774.5 5780.5 5785.7 5047.4 5053.0 5070.1 5070.2 5070.6 20766.3 21231.8 21334.5 21347.7 21392.1 2094.6 2121.5 2254.7 2274.8 2602.6 12946.3 13134.7 13135.9 13136.5 13580.3 1766.1 1786.1 1813.6 1820.5 1852.5 59238.9 61491.7 61650.0 61694.7 62389.0 9027.4 9311.3 9894.6 9910.9 9969.6 12726.4 12743.1 12754.2 12789.5 12804.4 5464.9 5638.5 5670.0 5754.7 7181.5 5922.7 5926.9 5975.4 5980.9 5982.6 3277.7 3279.4 3300.2 3337.2 3340.0 1143.0 1147.5 1154.7 1183.5 1197.8 457.3 459.9 461.2 474.7 481.4 11721.4 12117.7 12402.0 12432.8 12469.6 494.9 503.7 508.2 522.8 523.7 6199.3 6650.5 6793.2 6795.2 6830.7 381544.0 383823.0 387322.0 388657.0 513381.0 59297.7 63340.7 73878.6 79967.2 80751.0 482.6 498.4 501.1 501.6 503.2 9792.7 10084.9 10186.8 10197.5 10333.0 27484.6 32399.7 33501.8 34078.0 35794.9 45845.8 46610.0 47363.0 48082.3 48189.0 6333.4 6333.9 6490.2 6497.0 6497.2 11009.4 11036.7 11112.4 11510.4 11750.8 9842.8 9852.9 9908.9 9916.5 10289.7 9695.2 9813.5 10003.5 10252.6 10276.4 9972.6 10396.5 10442.5 10506.2 10541.5 6339.0 6404.1 6409.1 6411.0 6414.7 9818.4 9823.0 10138.5 10145.2 10166.0 22:19:00 INFO - 2015-11-16 22:19:00,075 DEBUG : BROWSER_OUTPUT: 22:19:00 INFO - 2015-11-16 22:19:00,075 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5240.36 Median:6576.14 stddev:2672.08 (40.6%) stddev-sans-first:2663.08 22:19:00 INFO - 2015-11-16 22:19:00,076 DEBUG : BROWSER_OUTPUT: Values: 1291.7 1683.3 1719.5 1743.5 1752.1 6555.8 7221.6 7356.7 7371.1 7374.4 7471.5 7599.5 7604.5 7604.5 7611.4 1974.8 2005.9 2152.6 2174.3 2244.0 2257.9 2261.9 2262.9 2264.4 2266.6 2181.3 2220.3 2223.0 2223.2 2226.2 1923.9 1989.8 2197.9 2233.3 2235.9 2243.3 2259.9 2289.0 2290.3 2309.7 7406.5 7412.1 7464.6 7469.6 7481.7 4581.1 4674.6 4706.3 4706.3 4706.7 2773.1 2842.3 2919.6 2964.5 2967.4 862.8 863.8 911.6 919.9 1851.7 9964.4 9972.3 10026.9 10058.2 10084.7 3974.6 4000.4 4074.0 4163.8 4173.5 8580.1 8586.6 8598.8 8602.6 8614.6 4549.9 4567.2 4587.4 4592.4 4597.1 4158.6 4183.2 4196.8 4197.0 4202.1 3396.0 3425.4 3456.7 3478.7 3479.7 1068.6 1070.9 1073.7 1077.4 1090.7 932.1 1280.3 1285.3 1326.1 1327.9 6018.1 7267.3 7466.0 7532.1 7535.0 704.5 717.5 723.3 730.3 733.6 5557.7 5711.4 5716.9 5733.9 5766.3 8447.7 8608.0 8616.4 8623.0 8635.5 8236.1 8429.2 8503.4 8506.8 8527.5 6723.9 6782.4 6822.1 6840.0 6855.2 6573.9 6574.9 6599.8 6601.1 6603.0 7950.2 7997.9 8109.7 8235.4 8297.3 7924.4 8000.0 8027.4 8136.9 8138.2 6767.8 6947.2 6960.7 6961.9 6963.8 7301.2 7304.9 7306.3 7314.7 7476.1 6938.3 7007.7 7057.3 7121.0 7185.6 6944.4 6997.3 7008.6 7077.0 7128.8 6028.4 6131.8 6571.1 6663.1 6691.0 6257.0 6440.0 6487.2 6503.2 6505.9 6473.2 6577.4 6626.6 6627.0 6633.9 22:19:00 INFO - 2015-11-16 22:19:00,077 DEBUG : BROWSER_OUTPUT: 22:19:00 INFO - 2015-11-16 22:19:00,077 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5329.42 Median:6142.51 stddev:1622.07 (26.4%) stddev-sans-first:1582.55 22:19:00 INFO - 2015-11-16 22:19:00,077 DEBUG : BROWSER_OUTPUT: Values: 325.9 328.2 330.7 338.3 339.9 5832.5 5981.4 5994.8 6011.2 6022.5 5747.0 5875.0 5898.4 5900.2 5901.1 4550.4 4555.5 4556.3 4557.6 4578.8 4392.4 4475.1 4478.4 4553.1 4634.7 4565.3 4577.2 4593.9 4594.6 4607.7 4560.0 4562.7 4577.0 4578.1 4586.4 4003.5 4009.1 4012.7 4025.7 4028.5 5495.9 5655.6 5675.7 5696.1 5705.6 5278.0 5285.5 5288.9 5290.3 5291.0 6379.0 6390.0 6390.8 6392.2 6395.5 4343.1 4346.0 4346.7 4356.5 4361.9 6361.6 6546.0 6595.1 6607.3 6607.4 4448.2 4474.5 4477.0 4484.0 4494.7 4524.9 4530.0 4533.8 4534.2 4536.1 5169.7 5221.5 5267.5 5279.1 5282.6 6368.9 6379.3 6390.7 6392.0 6397.8 1574.2 1599.0 1599.7 1599.9 1600.9 1019.8 1040.8 1042.8 1054.9 1067.2 6183.5 6196.3 6196.9 6198.3 6200.5 6384.1 6492.8 6506.4 6617.9 6622.3 6216.1 6231.8 6257.2 6260.7 6262.7 3579.1 3594.1 3604.4 3605.6 3613.6 6669.2 6819.2 6819.8 6820.7 6826.7 6817.1 6823.5 6830.8 6837.0 6838.1 6684.6 6704.9 6726.1 6734.0 6734.0 6546.6 6626.7 6651.9 6661.7 6667.4 6586.6 6589.1 6593.0 6594.9 6597.9 6816.2 6821.3 6822.5 6831.8 6832.4 4332.7 4461.1 4465.0 4503.3 4505.5 6582.6 6584.0 6590.9 6596.6 6598.3 6422.4 6426.5 6438.8 6443.4 6445.7 6258.6 6414.2 6422.3 6475.6 6481.1 6355.8 6358.7 6359.4 6362.7 6363.1 6505.1 6510.1 6517.8 6524.8 6526.8 6101.5 6193.2 6214.9 6340.7 6353.0 22:19:00 INFO - 2015-11-16 22:19:00,077 DEBUG : BROWSER_OUTPUT: 22:19:00 INFO - 2015-11-16 22:19:00,077 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13350.43 Median:48017.02 stddev:15933.66 (33.2%) stddev-sans-first:15950.21 22:19:00 INFO - 2015-11-16 22:19:00,078 DEBUG : BROWSER_OUTPUT: Values: 739.6 829.9 839.7 842.6 889.5 10382.4 10468.2 10473.1 10481.2 10536.5 13299.0 13327.1 13328.5 13335.9 13343.7 682.6 691.2 779.1 808.3 819.0 817.5 821.9 822.3 823.3 823.3 811.9 849.3 853.1 861.2 861.5 723.2 728.2 843.5 855.5 856.2 619.2 621.1 621.3 621.4 626.2 12872.9 12963.0 12997.4 12999.1 13002.6 9834.7 9893.1 10125.5 10232.2 10352.9 9643.2 10174.2 10216.4 10225.7 10376.0 3281.5 3326.0 3345.4 3416.4 3418.9 46452.3 46569.8 46687.3 46713.3 46883.8 7606.4 7799.2 7945.5 8043.5 8108.9 12367.8 12394.2 12396.8 12397.4 12409.6 6643.1 6763.8 6856.3 6984.4 6990.0 3857.2 3915.4 3928.2 3948.6 3960.9 2735.3 2748.1 2751.4 2753.6 2753.9 425.4 432.1 437.0 438.6 446.6 492.0 507.0 516.0 555.1 558.2 8372.5 8753.8 8786.0 8886.1 8975.6 563.9 653.4 663.7 684.0 700.2 3947.7 4134.1 4161.8 4194.0 4270.0 56808.7 57313.0 57521.0 57639.0 57647.7 48107.9 48223.8 48586.0 48944.0 50050.7 48000.8 48033.3 48430.5 49089.5 49123.5 8372.6 8538.7 8838.0 8851.3 9016.0 36379.5 36724.6 36762.4 37565.4 37604.2 41805.0 41910.2 44463.1 46100.3 46557.8 8517.0 8629.7 8728.1 8922.4 8945.4 12479.3 12773.4 12991.9 13117.5 13218.7 11631.7 11817.6 12285.8 12379.1 12431.9 11390.7 11431.3 11458.9 11908.9 11952.2 7177.7 11667.8 11674.5 11707.4 12142.5 9079.3 10227.8 10352.4 10539.8 10667.5 9258.9 9369.7 9557.6 9784.6 9874.5 22:19:00 INFO - 2015-11-16 22:19:00,078 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:19:00 INFO - 2015-11-16 22:19:00,078 DEBUG : BROWSER_OUTPUT: 22:19:00 INFO - 2015-11-16 22:19:00,785 INFO : Browser exited with error code: 0 22:19:00 INFO - 2015-11-16 22:19:00,806 INFO : Completed test dromaeo_css (00:18:32) 22:19:00 INFO - 2015-11-16 22:19:00,806 INFO : Starting test kraken 22:19:00 INFO - 2015-11-16 22:19:00,807 DEBUG : operating with platform_type : linux_ 22:19:00 INFO - 2015-11-16 22:19:00,807 INFO : Initialising browser for kraken test... 22:19:00 INFO - 2015-11-16 22:19:00,813 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_s7z_x/profile http://localhost:39503/getInfo.html 22:19:04 INFO - 2015-11-16 22:19:04,773 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:19:04 INFO - 2015-11-16 22:19:04,774 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:19:04 INFO - 2015-11-16 22:19:04,774 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:19:04 INFO - 2015-11-16 22:19:04,774 DEBUG : BROWSER_OUTPUT: __metrics 22:19:04 INFO - 2015-11-16 22:19:04,931 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:19:06 INFO - 2015-11-16 22:19:06,182 INFO : Browser initialized. 22:19:06 INFO - 2015-11-16 22:19:06,183 INFO : Running cycle 1/1 for kraken test... 22:19:06 INFO - 2015-11-16 22:19:06,183 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_s7z_x/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:19:17 INFO - 2015-11-16 22:19:17,935 DEBUG : BROWSER_OUTPUT: RSS: Main: 141176832 22:19:17 INFO - 2015-11-16 22:19:17,935 DEBUG : BROWSER_OUTPUT: 22:19:20 INFO - 2015-11-16 22:19:20,390 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39503/tests/kraken/driver.html?testName=audio-beat-detection) 22:19:20 INFO - 2015-11-16 22:19:20,964 DEBUG : BROWSER_OUTPUT: RSS: Main: 181039104 22:19:20 INFO - 2015-11-16 22:19:20,964 DEBUG : BROWSER_OUTPUT: 22:19:23 INFO - 2015-11-16 22:19:23,545 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39503/tests/kraken/driver.html?testName=audio-dft) 22:19:24 INFO - 2015-11-16 22:19:24,118 DEBUG : BROWSER_OUTPUT: RSS: Main: 170614784 22:19:24 INFO - 2015-11-16 22:19:24,119 DEBUG : BROWSER_OUTPUT: 22:19:26 INFO - 2015-11-16 22:19:26,992 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39503/tests/kraken/driver.html?testName=audio-fft) 22:19:27 INFO - 2015-11-16 22:19:27,553 DEBUG : BROWSER_OUTPUT: RSS: Main: 151851008 22:19:27 INFO - 2015-11-16 22:19:27,553 DEBUG : BROWSER_OUTPUT: 22:19:29 INFO - 2015-11-16 22:19:29,512 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39503/tests/kraken/driver.html?testName=audio-oscillator) 22:19:30 INFO - 2015-11-16 22:19:30,085 DEBUG : BROWSER_OUTPUT: RSS: Main: 153800704 22:19:30 INFO - 2015-11-16 22:19:30,085 DEBUG : BROWSER_OUTPUT: 22:19:32 INFO - 2015-11-16 22:19:32,258 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39503/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:19:32 INFO - 2015-11-16 22:19:32,819 DEBUG : BROWSER_OUTPUT: RSS: Main: 153808896 22:19:32 INFO - 2015-11-16 22:19:32,819 DEBUG : BROWSER_OUTPUT: 22:19:35 INFO - 2015-11-16 22:19:35,718 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39503/tests/kraken/driver.html?testName=imaging-darkroom) 22:19:36 INFO - 2015-11-16 22:19:36,284 DEBUG : BROWSER_OUTPUT: RSS: Main: 155426816 22:19:36 INFO - 2015-11-16 22:19:36,284 DEBUG : BROWSER_OUTPUT: 22:19:39 INFO - 2015-11-16 22:19:39,654 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39503/tests/kraken/driver.html?testName=imaging-desaturate) 22:19:40 INFO - 2015-11-16 22:19:40,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 156393472 22:19:40 INFO - 2015-11-16 22:19:40,221 DEBUG : BROWSER_OUTPUT: 22:19:42 INFO - 2015-11-16 22:19:42,963 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39503/tests/kraken/driver.html?testName=json-parse-financial) 22:19:43 INFO - 2015-11-16 22:19:43,537 DEBUG : BROWSER_OUTPUT: RSS: Main: 155783168 22:19:43 INFO - 2015-11-16 22:19:43,537 DEBUG : BROWSER_OUTPUT: 22:19:45 INFO - 2015-11-16 22:19:45,086 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39503/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:19:45 INFO - 2015-11-16 22:19:45,655 DEBUG : BROWSER_OUTPUT: RSS: Main: 166707200 22:19:45 INFO - 2015-11-16 22:19:45,655 DEBUG : BROWSER_OUTPUT: 22:19:47 INFO - 2015-11-16 22:19:47,477 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39503/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:19:48 INFO - 2015-11-16 22:19:48,071 DEBUG : BROWSER_OUTPUT: RSS: Main: 167047168 22:19:48 INFO - 2015-11-16 22:19:48,071 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-11-16 22:19:50,176 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39503/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:19:50 INFO - 2015-11-16 22:19:50,749 DEBUG : BROWSER_OUTPUT: RSS: Main: 173654016 22:19:50 INFO - 2015-11-16 22:19:50,750 DEBUG : BROWSER_OUTPUT: 22:19:53 INFO - 2015-11-16 22:19:53,150 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39503/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:19:53 INFO - 2015-11-16 22:19:53,731 DEBUG : BROWSER_OUTPUT: RSS: Main: 181878784 22:19:53 INFO - 2015-11-16 22:19:53,731 DEBUG : BROWSER_OUTPUT: 22:19:56 INFO - 2015-11-16 22:19:56,306 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39503/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:19:56 INFO - 2015-11-16 22:19:56,879 DEBUG : BROWSER_OUTPUT: RSS: Main: 179875840 22:19:56 INFO - 2015-11-16 22:19:56,880 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,533 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39503/tests/kraken/driver.html?testName=ai-astar) 22:19:58 INFO - 2015-11-16 22:19:58,783 DEBUG : BROWSER_OUTPUT: RSS: Main: 238510080 22:19:58 INFO - 2015-11-16 22:19:58,783 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,784 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:19:58 INFO - 2015-11-16 22:19:58,784 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:19:58 INFO - 2015-11-16 22:19:58,784 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:19:58 INFO - 2015-11-16 22:19:58,784 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;110;103;109;113;112;99;109;109;109 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;123;153;163;125;149;155;129;169;124 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;161;160;162;163;161;160;164;162;161 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;81;92;72;72;72;109;93;72 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;90;140;90;90;90;140;90;90;132 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;109;109;110;110;110;109;110 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;147;146;147;146;148;146;146;146;145 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;96;96;96;95;95;95;95 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;58;58;58;57;57;58;65;55;55 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;70;68;70;70;69;70;69;71 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;84;86;95;86;86;85;86;98;85 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;122;126;120;118;122;119;124;119;117;119 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;155;151;147;148;142;158;148;148;144 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;66;65;70;66;67;66;68;66;66 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:19:58 INFO - 2015-11-16 22:19:58,785 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:19:58 INFO - 2015-11-16 22:19:58,786 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,929 22:19:58 INFO - 2015-11-16 22:19:58,786 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:19:58 INFO - 2015-11-16 22:19:58,786 DEBUG : BROWSER_OUTPUT: __startTimestamp1447741198783__endTimestamp 22:19:58 INFO - 2015-11-16 22:19:58,786 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,789 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:19:58 INFO - 2015-11-16 22:19:58,789 DEBUG : BROWSER_OUTPUT: Number of tests: 14 22:19:58 INFO - 2015-11-16 22:19:58,789 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,789 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.00 Median:109.50 stddev:4.16 (3.8%) stddev-sans-first:4.40 22:19:58 INFO - 2015-11-16 22:19:58,789 DEBUG : BROWSER_OUTPUT: Values: 107.0 110.0 103.0 109.0 113.0 112.0 99.0 109.0 109.0 109.0 22:19:58 INFO - 2015-11-16 22:19:58,789 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,789 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.10 Median:152.00 stddev:17.28 (11.4%) stddev-sans-first:18.15 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: Values: 151.0 123.0 153.0 163.0 125.0 149.0 155.0 129.0 169.0 124.0 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: Values: 162.0 161.0 160.0 162.0 163.0 161.0 160.0 164.0 162.0 161.0 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.70 Median:72.00 stddev:13.06 (18.1%) stddev-sans-first:13.46 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 81.0 92.0 72.0 72.0 72.0 109.0 93.0 72.0 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.30 Median:90.00 stddev:22.90 (25.4%) stddev-sans-first:23.78 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: Values: 91.0 90.0 140.0 90.0 90.0 90.0 140.0 90.0 90.0 132.0 22:19:58 INFO - 2015-11-16 22:19:58,790 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,791 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 22:19:58 INFO - 2015-11-16 22:19:58,791 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 109.0 109.0 110.0 110.0 110.0 109.0 110.0 22:19:58 INFO - 2015-11-16 22:19:58,791 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,791 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 22:19:58 INFO - 2015-11-16 22:19:58,791 DEBUG : BROWSER_OUTPUT: Values: 146.0 147.0 146.0 147.0 146.0 148.0 146.0 146.0 146.0 145.0 22:19:58 INFO - 2015-11-16 22:19:58,791 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,791 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 22:19:58 INFO - 2015-11-16 22:19:58,791 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 22:19:58 INFO - 2015-11-16 22:19:58,791 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.00 stddev:2.77 (4.8%) stddev-sans-first:2.93 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: Values: 58.0 58.0 58.0 58.0 57.0 57.0 58.0 65.0 55.0 55.0 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:70.00 stddev:0.97 (1.4%) stddev-sans-first:0.88 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 70.0 68.0 70.0 70.0 69.0 70.0 69.0 71.0 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:86.00 stddev:4.74 (5.5%) stddev-sans-first:4.99 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: Values: 86.0 84.0 86.0 95.0 86.0 86.0 85.0 86.0 98.0 85.0 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.60 Median:121.00 stddev:2.84 (2.3%) stddev-sans-first:2.96 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: Values: 122.0 126.0 120.0 118.0 122.0 119.0 124.0 119.0 117.0 119.0 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.20 Median:148.00 stddev:5.37 (3.6%) stddev-sans-first:5.02 22:19:58 INFO - 2015-11-16 22:19:58,792 DEBUG : BROWSER_OUTPUT: Values: 141.0 155.0 151.0 147.0 148.0 142.0 158.0 148.0 148.0 144.0 22:19:58 INFO - 2015-11-16 22:19:58,793 DEBUG : BROWSER_OUTPUT: 22:19:58 INFO - 2015-11-16 22:19:58,793 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.00 stddev:1.51 (2.3%) stddev-sans-first:1.50 22:19:58 INFO - 2015-11-16 22:19:58,793 DEBUG : BROWSER_OUTPUT: Values: 65.0 66.0 65.0 70.0 66.0 67.0 66.0 68.0 66.0 66.0 22:19:58 INFO - 2015-11-16 22:19:58,793 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:19:58 INFO - 2015-11-16 22:19:58,793 DEBUG : BROWSER_OUTPUT: 22:19:59 INFO - 2015-11-16 22:19:59,614 INFO : Browser exited with error code: 0 22:19:59 INFO - 2015-11-16 22:19:59,664 INFO : Completed test kraken (00:00:58) 22:19:59 INFO - 2015-11-16 22:19:59,664 INFO : Starting test v8_7 22:19:59 INFO - 2015-11-16 22:19:59,665 DEBUG : operating with platform_type : linux_ 22:19:59 INFO - 2015-11-16 22:19:59,665 INFO : Initialising browser for v8_7 test... 22:19:59 INFO - 2015-11-16 22:19:59,671 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkerRx5/profile http://localhost:39503/getInfo.html 22:20:03 INFO - 2015-11-16 22:20:03,639 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:20:03 INFO - 2015-11-16 22:20:03,639 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:20:03 INFO - 2015-11-16 22:20:03,639 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:20:03 INFO - 2015-11-16 22:20:03,640 DEBUG : BROWSER_OUTPUT: __metrics 22:20:03 INFO - 2015-11-16 22:20:03,748 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:20:05 INFO - 2015-11-16 22:20:05,040 INFO : Browser initialized. 22:20:05 INFO - 2015-11-16 22:20:05,041 INFO : Running cycle 1/1 for v8_7 test... 22:20:05 INFO - 2015-11-16 22:20:05,041 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkerRx5/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:20:16 INFO - 2015-11-16 22:20:16,796 DEBUG : BROWSER_OUTPUT: RSS: Main: 141565952 22:20:16 INFO - 2015-11-16 22:20:16,796 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,174 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39503/tests/v8_7/run.html (next: http://localhost:39503/tests/v8_7/run.html) 22:20:38 INFO - 2015-11-16 22:20:38,432 DEBUG : BROWSER_OUTPUT: RSS: Main: 578428928 22:20:38 INFO - 2015-11-16 22:20:38,432 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,432 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:20:38 INFO - 2015-11-16 22:20:38,432 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:20:38 INFO - 2015-11-16 22:20:38,432 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:20:38 INFO - 2015-11-16 22:20:38,433 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:20:38 INFO - 2015-11-16 22:20:38,433 DEBUG : BROWSER_OUTPUT: |0;Richards;166.36167027116952 22:20:38 INFO - 2015-11-16 22:20:38,433 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.35938971557732 22:20:38 INFO - 2015-11-16 22:20:38,433 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.04557033682426 22:20:38 INFO - 2015-11-16 22:20:38,433 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5045.226130653266 22:20:38 INFO - 2015-11-16 22:20:38,433 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1111.111111111111 22:20:38 INFO - 2015-11-16 22:20:38,433 DEBUG : BROWSER_OUTPUT: |5;Earley;800.1598721023181 22:20:38 INFO - 2015-11-16 22:20:38,434 DEBUG : BROWSER_OUTPUT: |6;Boyer;12481.481481481482 22:20:38 INFO - 2015-11-16 22:20:38,434 DEBUG : BROWSER_OUTPUT: |7;RegExp;25948.71794871795 22:20:38 INFO - 2015-11-16 22:20:38,434 DEBUG : BROWSER_OUTPUT: |8;Splay;638.9776357827476 22:20:38 INFO - 2015-11-16 22:20:38,434 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 22:20:38 INFO - 2015-11-16 22:20:38,434 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:20:38 INFO - 2015-11-16 22:20:38,434 DEBUG : BROWSER_OUTPUT: __startTimestamp1447741238426__endTimestamp 22:20:38 INFO - 2015-11-16 22:20:38,434 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,434 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: Number of tests: 10 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.36 Median:166.36 stddev:0.00 (0.0%) 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: Values: 166.4 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.36 Median:188.36 stddev:0.00 (0.0%) 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: Values: 188.4 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.05 Median:283.05 stddev:0.00 (0.0%) 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: Values: 283.0 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5045.23 Median:5045.23 stddev:0.00 (0.0%) 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: Values: 5045.2 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1111.11 Median:1111.11 stddev:0.00 (0.0%) 22:20:38 INFO - 2015-11-16 22:20:38,435 DEBUG : BROWSER_OUTPUT: Values: 1111.1 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:800.16 Median:800.16 stddev:0.00 (0.0%) 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: Values: 800.2 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12481.48 Median:12481.48 stddev:0.00 (0.0%) 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: Values: 12481.5 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25948.72 Median:25948.72 stddev:0.00 (0.0%) 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: Values: 25948.7 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:638.98 Median:638.98 stddev:0.00 (0.0%) 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: Values: 639.0 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: Values: 4822.1 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:20:38 INFO - 2015-11-16 22:20:38,436 DEBUG : BROWSER_OUTPUT: 22:20:39 INFO - 2015-11-16 22:20:39,254 INFO : Browser exited with error code: 0 22:20:39 INFO - 2015-11-16 22:20:39,266 INFO : Completed test v8_7 (00:00:39) 22:20:39 INFO - 2015-11-16 22:20:39,452 INFO : Completed test suite (00:20:11) 22:20:39 INFO - 2015-11-16 22:20:39,452 DEBUG : Working with test: dromaeo_css 22:20:39 INFO - 2015-11-16 22:20:39,452 DEBUG : Generating results file: dromaeo_css 22:20:39 INFO - 2015-11-16 22:20:39,453 DEBUG : Working with test: kraken 22:20:39 INFO - 2015-11-16 22:20:39,453 DEBUG : Generating results file: kraken 22:20:39 INFO - 2015-11-16 22:20:39,453 INFO : javascript benchmark 22:20:39 INFO - 2015-11-16 22:20:39,454 DEBUG : Working with test: v8_7 22:20:39 INFO - 2015-11-16 22:20:39,454 DEBUG : Generating results file: v8_7 22:20:39 INFO - 2015-11-16 22:20:39,454 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:20:39 INFO - 2015-11-16 22:20:39,524 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 22:20:39 INFO - 2015-11-16 22:20:39,525 DEBUG : process_Request line: dromaeo_css 6644.55 graph.html#tests=[[72,1,35]] 22:20:39 INFO - 2015-11-16 22:20:39,525 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:20:39 INFO - 2015-11-16 22:20:39,633 DEBUG : process_Request line: kraken 1500.10 graph.html#tests=[[232,1,35]] 22:20:39 INFO - 2015-11-16 22:20:39,633 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:20:39 INFO - 2015-11-16 22:20:39,756 DEBUG : process_Request line: v8_7 20873.03 graph.html#tests=[[230,1,35]] 22:20:39 INFO - 2015-11-16 22:20:39,757 INFO : javascript benchmark 22:20:39 INFO - 2015-11-16 22:20:39,761 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"jquery.html": [350.09849637184254, 351.8029903254176, 352.17766515187037, 354.1288430461066, 355.07148805872635, 14007.229602371866, 14444.0, 16131.91934040331, 16898.49304520865, 17578.91210543947, 16044.35822567096, 16467.752983705253, 17002.234899429524, 17361.131943402812, 17604.735928961076, 5112.846614601559, 5268.446813084628, 5272.1300985337375, 5275.393329767068, 5275.736213189344, 3981.223661386031, 4076.429299898017, 5238.3975842778, 5678.233438485793, 5841.240638716982, 5838.503727183194, 5851.4148585141575, 5858.589898707092, 5888.087346461305, 5907.409259074079, 5658.91511627326, 5767.0, 5774.538036957033, 5780.479756821865, 5785.681787501686, 5047.419546752134, 5053.0399224147295, 5070.138076527, 5070.188769796815, 5070.645054846163, 20766.273180438613, 21231.787682123137, 21334.46663833401, 21347.67978480324, 21392.14431422741, 2094.6229678657837, 2121.5332626822074, 2254.740704818934, 2274.783895529922, 2602.596597527386, 12946.287954162517, 13134.671633209151, 13135.86864131356, 13136.540221092217, 13580.320983950764, 1766.055160489132, 1786.1337251433044, 1813.6463389639025, 1820.5084627150727, 1852.51834523025, 59238.8532802704, 61491.692541537006, 61650.0, 61694.69152654253, 62389.0, 9027.36808423404, 9311.348205625676, 9894.60421583143, 9910.900890990997, 9969.55137018836, 12726.363681816058, 12743.107982441326, 12754.170978886343, 12789.488420463078, 12804.359782010863, 5464.9180262296095, 5638.464345887134, 5670.0, 5754.74103665336, 7181.533200341961, 5922.703864806743, 5926.940730592725, 5975.37258587842, 5980.85047873798, 5982.611130276518, 3277.721393681516, 3279.409706252897, 3300.1914530940076, 3337.1823903143886, 3340.048086295394, 1142.9542818287175, 1147.460693474066, 1154.6651471073294, 1183.4733543573027, 1197.8382918306093, 457.30033049434417, 459.9402077729941, 461.2389557230529, 474.7127987567541, 481.41748484333647, 11721.4139293035, 12117.697637449033, 12402.0, 12432.813507797391, 12469.563565275172, 494.8614387971374, 503.69778133119837, 508.2198824803908, 522.8274669359179, 523.743365750787, 6199.291452522311, 6650.501212408992, 6793.218779840283, 6795.2185498667295, 6830.692618832164, 381544.0, 383823.0, 387322.0, 388657.0, 513381.0, 59297.70351148303, 63340.68329658322, 73878.63060684662, 79967.20032799598, 80751.0, 482.60185347088793, 498.38449514849134, 501.0930216308493, 501.6313009937693, 503.2250334484925, 9792.657256995972, 10084.899151008396, 10186.847197291898, 10197.54111064989, 10333.018363255478, 27484.58773118445, 32399.676003239667, 33501.83249083739, 34078.0, 35794.92615221548, 45845.77077114593, 46610.0, 47363.0, 48082.27876581924, 48189.0, 6333.398327158912, 6333.8733225336155, 6490.221173459215, 6497.0, 6497.18785151856, 11009.449527523751, 11036.724081898017, 11112.444377780917, 11510.36692981894, 11750.823737643772, 9842.803143937224, 9852.852207217042, 9908.900910990798, 9916.454594997344, 10289.74275643115, 9695.175910047443, 9813.509324533601, 10003.499825008575, 10252.58989640406, 10276.434796086287, 9972.60109595608, 10396.532156053147, 10442.530086146293, 10506.21203409732, 10541.472926353805, 6339.049142628643, 6404.103425520524, 6409.070684750778, 6411.0, 6414.743410263537, 9818.361806482555, 9822.950885245815, 10138.49307534635, 10145.18838492904, 10166.0], "dojo.html": [3129.7183253507183, 3159.447096758067, 3191.266008817972, 3201.695838895304, 3220.0822765511834, 4999.07517109335, 5120.231965205221, 5228.607854410915, 5300.443453437387, 5317.734113294339, 5241.344831896013, 5255.211718242265, 5306.973465132679, 5364.356277246736, 5380.9192862107, 4785.976070119645, 4809.326694262789, 4837.709737415748, 4906.386701662293, 4978.153713868651, 4970.105381031413, 4990.126727822635, 5026.597872170218, 5049.242613607951, 5075.441701412841, 4663.183942809995, 4751.881202969937, 4759.976200118977, 4843.346148269974, 4856.975715121437, 4836.250381190921, 4842.44311904132, 4855.63582731293, 4865.0, 4889.022195560902, 4820.686655367425, 4833.081714474274, 4833.178359678863, 4846.418429788413, 4895.241237608177, 5050.621203409759, 5090.338256026693, 5116.897662046738, 5142.871428214287, 5178.093833579109, 5659.207710920475, 5660.29246344207, 5672.06410942194, 5677.886442271173, 5681.147827825818, 5416.51251387377, 5697.31632204134, 5699.572532060112, 5699.658020518741, 5708.571857110692, 5301.443348448396, 5340.839774806723, 5385.353757549119, 5414.8917021659345, 5433.1035379162395, 5782.392848750821, 5794.82615521535, 5813.331466881363, 5815.41845815415, 5864.296284445893, 5488.643238189585, 5606.439356064361, 5620.91568626471, 5627.409122042127, 5630.5495560355075, 5590.440955904377, 5681.545476361881, 5769.365369809318, 5809.157672137517, 5811.476967072984, 5736.598438109376, 5738.254026976467, 5739.7704091836695, 5743.167240750068, 5750.1949727038245, 5441.59188060897, 5730.914036289459, 5733.0, 5736.196932429463, 5739.483446489836, 4356.368326592689, 4372.912541749147, 4407.911841763147, 4411.735295882258, 4443.37792709021, 3885.0675837798726, 3889.4749208857006, 3892.2993861105274, 3892.6885849132, 3932.193900250442, 5194.532492075656, 5291.0, 5350.27771250884, 5378.354597448253, 5399.774802704327, 5605.607607467438, 5678.063119585222, 5678.68767217807, 5694.800681976111, 5727.5131613812455, 5361.276227709287, 5375.139977603565, 5379.731013449312, 5380.112281473513, 5380.757865896044, 4724.244120940633, 4807.351007613927, 4937.382827146606, 4972.229304457815, 5022.372203474566, 5967.045272756425, 5967.731452084667, 5973.940260597426, 5977.163197152402, 5994.100884867305, 5796.913046304225, 5818.981678206213, 5819.70901454934, 5821.476067153976, 5824.737886795019, 5682.545396368363, 5693.231413759089, 5837.1244313351635, 5855.765769369348, 5933.347331793586, 5534.335879694462, 5537.36320323168, 5540.501354878081, 5543.417941116125, 5578.86052848682, 5597.04850175463, 5600.85997850057, 5603.075492543685, 5603.131514615241, 5618.101103823368, 5256.053910296183, 5362.624616276823, 5460.535854452416, 5519.089350257163, 5587.69267690281, 5590.636608620427, 5629.662220266797, 5633.267675202197, 5636.379998200113, 5638.9436105638415, 5267.051930652519, 5529.170624406372, 5546.750396232099, 5551.389347171727, 5553.0, 5507.0, 5585.972070139624, 5716.228309178207, 5716.42835716425, 5759.337676167294, 5393.83818485446, 5406.945930540802, 5407.83776486706, 5432.103702889137, 5485.835424937259], "mootools.html": [1291.689994401344, 1683.3182561062767, 1719.4927496388564, 1743.5118166913269, 1752.1326943163133, 6555.77054803082, 7221.638918054096, 7356.74251401201, 7371.115466144063, 7374.446916481258, 7471.514351567144, 7599.468037237396, 7604.467687261894, 7604.543727376361, 7611.391088712903, 1974.8123928226796, 2005.8997050147507, 2152.569486102778, 2174.293354659734, 2244.046280330861, 2257.909683612662, 2261.8642881427086, 2262.875541845198, 2264.388615073929, 2266.648669456237, 2181.2583721534675, 2220.322801545528, 2223.0, 2223.2329846203115, 2226.209695558078, 1923.9134238959211, 1989.7512810898638, 2197.8681279123225, 2233.251860626692, 2235.854669446481, 2243.2821497120926, 2259.8531095478743, 2288.954220915589, 2290.267114523353, 2309.6881920940796, 7406.518576292524, 7412.110546734372, 7464.626768661599, 7469.589172595505, 7481.738139165103, 4581.129585378767, 4674.579287864109, 4706.270528068155, 4706.270528068155, 4706.694064885772, 2773.126465163467, 2842.332051967786, 2919.6350456192977, 2964.496035673919, 2967.4361871244396, 862.8015556421996, 863.7538301584018, 911.5806728904712, 919.9264058875274, 1851.6759567075708, 9964.352317099365, 9972.252081093875, 10026.899731002743, 10058.195344372505, 10084.747881302952, 3974.6025397460603, 4000.3799411091327, 4074.0, 4163.770992595376, 4173.478315210598, 8580.099089595627, 8586.570671466401, 8598.828023439497, 8602.569871506399, 8614.569271536398, 4549.931751023737, 4567.223571992769, 4587.4036375271, 4592.380028696083, 4597.057603191338, 4158.625723684883, 4183.163367326486, 4196.8111434984885, 4197.034682023152, 4202.117555313418, 3395.966040339614, 3425.3663072331697, 3456.723462123024, 3478.6869181773764, 3479.6868281854754, 1068.551208492435, 1070.9143268538498, 1073.6725298783947, 1077.407425915759, 1090.7327704712395, 932.1191474057086, 1280.2510531339271, 1285.3252042677482, 1326.1446367093172, 1327.9004074694437, 6018.095546845023, 7267.345938865527, 7465.962670186615, 7532.058492688414, 7534.962325188449, 704.5068452083563, 717.4547344018479, 723.3453724379445, 730.3317464520011, 733.5818583407513, 5557.661612130918, 5711.428857114255, 5716.942830571724, 5733.885322293614, 5766.336871259775, 8447.746567602982, 8607.956960215159, 8616.439931404439, 8623.0, 8635.525046122399, 8236.135205803424, 8429.199226073513, 8503.447275927045, 8506.78733031679, 8527.488350698977, 6723.890558057963, 6782.423494002966, 6822.11312529368, 6840.0, 6855.245922948372, 6573.901391479036, 6574.868502630016, 6599.835004124935, 6601.141851559267, 6603.0, 7950.165232650488, 7997.880031799644, 8109.7161599343735, 8235.423520353517, 8297.25324720778, 7924.4056695749205, 8000.0, 8027.357811374959, 8136.877946830684, 8138.186181381814, 6767.796966091025, 6947.201071876799, 6960.651967401711, 6961.8955715665315, 6963.825904352431, 7301.233370496021, 7304.926950730637, 7306.269373062863, 7314.743983960536, 7476.140243872024, 6938.306169383223, 7007.719691212498, 7057.258987806412, 7120.964395177991, 7185.604791736505, 6944.4444444445335, 6997.26528714498, 7008.579485230903, 7077.0, 7128.786136415916, 6028.3971602839365, 6131.785387511416, 6571.145751052524, 6663.100481434944, 6691.029800678972, 6256.968715156395, 6440.0, 6487.156669632917, 6503.219613646393, 6505.869882602226, 6473.158489396348, 6577.408033277028, 6626.602403855784, 6627.039079333566, 6633.867322653617], "ext.html": [596.394659420688, 598.5899658733767, 599.3616798110013, 601.9969900150498, 602.773959765088, 30933.845330773373, 39582.40626390603, 40107.59892401075, 40194.799026004905, 40244.597554024454, 41859.0, 42390.36414453786, 42458.36312455316, 42566.36150457746, 42571.0, 7521.05986751656, 8725.738227853175, 8833.89576302962, 8847.115288471166, 8917.375783695144, 7590.6963721451075, 10497.632582859602, 10769.0, 10771.0, 10772.5690972361, 10404.167666586693, 10463.895361046407, 10481.737956551071, 10496.370217786956, 10508.527116279747, 7596.278353556403, 7781.727639532589, 10364.740881477948, 10392.168626509861, 10462.058414742634, 7534.623268836591, 10510.684679459628, 10775.4612269387, 10776.461176941122, 10784.892151078468, 38793.80603096967, 39243.0, 39260.80369598162, 39339.606603933884, 39685.60314396848, 3231.8545665445113, 3264.0860559043504, 3287.4575695010294, 3626.492291079251, 4097.078157414588, 29264.41471170586, 29503.852480737674, 29611.259718507208, 29712.257193570116, 29878.10365689011, 3340.4822252550894, 3354.3123659649714, 3398.762086653935, 3453.0849324928913, 3539.2744487380023, 270898.0, 271797.0, 272593.0, 272697.0, 275008.0, 50472.747636261585, 51376.0, 56968.0, 57493.13760293599, 57810.42189578134, 57802.0, 57820.71089644525, 57902.0, 58155.709221453624, 58230.7088464555, 15700.450484232997, 15794.763078553831, 15819.446319378767, 15838.92080539613, 15887.52337429879, 12131.939340303243, 12198.756024879453, 12200.145989780807, 12203.755924881452, 12208.0, 18893.149808258662, 18897.338593149172, 18950.336738214373, 19035.333763318216, 19044.904775476032, 7376.3730082942475, 7508.587027713528, 7565.205653406313, 7645.3527418898, 7767.844643107106, 952.6189524190379, 966.5457235099492, 1014.9797004059877, 1017.9134773544183, 1114.2367478277406, 24193.274201773977, 24715.87642061778, 26650.333741656614, 26800.0, 26986.865065674545, 1003.4380746781826, 1013.0274936061463, 1042.9113525350278, 1122.180808010141, 1124.0894875151155, 13642.59072227815, 13727.656808579666, 13952.093113947562, 14006.229657368738, 14015.929920350332, 317486.0, 320756.0, 321437.0, 322478.0, 322795.0, 50890.236646450336, 51089.0, 51111.0, 51117.74441127845, 51216.0, 1300.466808608466, 1306.3272414706435, 1310.7247478029722, 1317.6639956811143, 1338.417788262109, 22795.65806512937, 27533.724662753117, 28093.297667557657, 28141.718582813908, 28471.28821779389, 42725.786371067945, 43351.34972975471, 43435.0, 43437.0, 43455.348169776844, 271430.0, 271690.0, 273117.0, 274365.0, 275056.0, 27197.728022720312, 27614.861925691046, 30808.38383232278, 31756.841215793775, 32425.675743242267, 40506.79746601248, 40727.79636101801, 41193.0, 41990.58009419867, 42158.1568368637, 34790.47814282737, 34966.65033349634, 35048.47427288542, 35540.0, 36008.63991360053, 31066.844665776527, 33681.3263734719, 34987.47518787169, 35389.64610353864, 35645.28709425745, 32105.839470802497, 32832.0, 34376.312473750884, 34800.65199347974, 34957.47563786596, 31486.212844679067, 32279.193020174684, 32495.675043249266, 32580.51129233111, 32640.83679581587, 24327.742645036695, 24613.63079553844, 24691.753082468946, 24977.50044999126, 24998.875005624854], "prototype.html": [325.86965213914436, 328.2302999466252, 330.67593758117044, 338.28452822280883, 339.8997295797739, 5832.533397328214, 5981.371955944628, 5994.8201553953395, 6011.248593925759, 6022.54830887683, 5747.0, 5874.970625146868, 5898.439648233411, 5900.1739756434135, 5901.111644273834, 4550.408446901906, 4555.498895121534, 4556.270996640538, 4557.567031132037, 4578.816847326103, 4392.428984232045, 4475.104979004196, 4478.440194975628, 4553.089382123589, 4634.675572709912, 4565.292379681155, 4577.244754615484, 4593.862184134481, 4594.563516465931, 4607.700499467541, 4559.996800703856, 4562.680612357136, 4576.954230457686, 4578.10726908253, 4586.449626044896, 4003.539592946819, 4009.1380353224195, 4012.6789856811383, 4025.677945764332, 4028.47629808126, 5495.945040549584, 5655.632383895034, 5675.6878371689545, 5696.145578163268, 5705.62913410627, 5278.0, 5285.471452854722, 5288.862894477706, 5290.259363689087, 5291.047611429941, 6379.0, 6390.0, 6390.81769872575, 6392.169018027673, 6395.456386207178, 4343.06624075822, 4346.0, 4346.739195648272, 4356.477222733292, 4361.869143925687, 6361.586496877734, 6546.0, 6595.109660195908, 6607.30623284553, 6607.372299631527, 4448.154850578379, 4474.463064432224, 4476.977615111904, 4483.977580112079, 4494.685372023927, 4524.932126018112, 4530.003399252159, 4533.795979180944, 4534.229181039232, 4536.070105628338, 5169.71566563835, 5221.530062294411, 5267.499587539178, 5279.076161671692, 5282.577393808486, 6368.904466433008, 6379.330170332141, 6390.744370225232, 6391.96804015977, 6397.840053998687, 1574.181425658651, 1598.968020639604, 1599.704054749875, 1599.9200039997954, 1600.927958241882, 1019.8062368150028, 1040.812653722322, 1042.83314669654, 1054.8786889507805, 1067.2422579968154, 6183.474404675563, 6196.287426945959, 6196.938030619726, 6198.2871969723155, 6200.472959798468, 6384.138141350858, 6492.772752953624, 6506.41442270198, 6617.933820661828, 6622.337766223339, 6216.129741836146, 6231.813045608639, 6257.2491301044165, 6260.718267677966, 6262.749490020439, 3579.069441945113, 3594.101474631342, 3604.387254166798, 3605.5673319201337, 3613.56637203532, 6669.166354205724, 6819.215790184093, 6819.829504262433, 6820.727170913207, 6826.692798823966, 6817.11377520929, 6823.522353435311, 6830.829229269308, 6837.0, 6838.093810130675, 6684.632345220963, 6704.932950670431, 6726.091977583062, 6733.966330168416, 6734.023566582818, 6546.574472659262, 6626.701798418986, 6651.933480665325, 6661.700223489857, 6667.366600172976, 6586.571872828251, 6589.110470086477, 6593.0, 6594.934050659624, 6597.934020659732, 6816.1820581528555, 6821.283765204582, 6822.454203663596, 6831.760888368884, 6832.350926661959, 4332.696711230184, 4461.085477477044, 4464.955350446584, 4503.311258278146, 4505.549445055468, 6582.572132811543, 6584.0, 6590.901136483052, 6596.571222870498, 6598.307177746267, 6422.3577642235405, 6426.485881129405, 6438.774642887476, 6443.420092191725, 6445.677716114269, 6258.624482531062, 6414.166158399378, 6422.293547709842, 6475.579087359307, 6481.125048118443, 6355.777547785805, 6358.713857876314, 6359.395857393541, 6362.650054247061, 6363.140975968185, 6505.121808555785, 6510.056041873996, 6517.837054073686, 6524.771632992823, 6526.771562995273, 6101.542384321105, 6193.163922870355, 6214.937850621436, 6340.71466784005, 6353.0152826312], "yui.html": [739.6301849075461, 829.8921140251766, 839.6767244610822, 842.578710644678, 889.5418859287469, 10382.377057376554, 10468.162546996242, 10473.057424831764, 10481.161507079434, 10536.525856336464, 13299.0, 13327.067105302633, 13328.533501327456, 13335.933320333408, 13343.733125337487, 682.6006786030179, 691.2430888177438, 779.111812533712, 808.3129340060931, 818.9836203275961, 817.4931542443693, 821.8808272800441, 822.3462347433805, 823.2672921100195, 823.3043078598574, 811.8944537210155, 849.3417601358934, 853.1340689200435, 861.2378045429778, 861.4745005546612, 723.236984980845, 728.1626129950573, 843.4770442325736, 855.5038077914794, 856.2251162697769, 619.1672200889795, 621.1273161208509, 621.2886245249209, 621.4407033669688, 626.2266101364811, 12872.935635321857, 12963.0, 12997.350132493431, 12999.090063695545, 13002.609921702364, 9834.655787047419, 9893.060159284856, 10125.493725313778, 10232.232582556264, 10352.948235258775, 9643.167798118335, 10174.236932230038, 10216.387016779017, 10225.693229203136, 10376.0, 3281.49136883781, 3325.9501107483406, 3345.3978283908887, 3416.385050690875, 3418.8632454701783, 46452.303215452135, 46569.76715116403, 46687.29969050467, 46713.299300510516, 46883.765581172214, 7606.353459955966, 7799.181085986003, 7945.523268603903, 8043.477173983672, 8108.918910810934, 12367.752644947052, 12394.194377365442, 12396.75206495865, 12397.442115104843, 12409.565665201675, 6643.1031810704935, 6763.7632682855865, 6856.314368563204, 6984.4063254623925, 6990.0, 3857.189990102054, 3915.3735402335487, 3928.194720082376, 3948.624880636335, 3960.861369852099, 2735.3161709572605, 2748.051922086877, 2751.3671855473353, 2753.5731961545994, 2753.931151721183, 425.40868193211463, 432.0882937002921, 437.02543328377897, 438.59210933831207, 446.5757530346184, 491.9581835544019, 506.9771860266297, 515.996387027236, 555.0536335547881, 558.1822629847293, 8372.455790373606, 8753.781155471164, 8786.0, 8886.107012811539, 8975.596098175598, 563.8703098287417, 653.4217217762315, 663.7013343995221, 684.0, 700.1737949219868, 3947.703922205846, 4134.090500089976, 4161.750294982311, 4193.97903010483, 4269.978650106729, 56808.715956419954, 57313.0, 57521.0, 57639.0, 57647.71176144092, 48107.93286615668, 48223.75888120537, 48586.0, 48944.0, 50050.74974625104, 48000.7599961998, 48033.27950080752, 48430.5156948426, 49089.50910490921, 49123.50876491261, 8372.581370931308, 8538.658453661785, 8838.0, 8851.291896648381, 9016.0, 36379.45430818593, 36724.63275367212, 36762.448563271035, 37565.4365184517, 37604.247915041, 41805.0, 41910.161796763285, 44463.11073778571, 46100.30849537192, 46557.76721116373, 8517.0, 8629.69796057135, 8728.127187281221, 8922.420042697204, 8945.418547794372, 12479.251244925334, 12773.361331933553, 12991.870081299066, 13117.540886068942, 13218.669533261746, 11631.6510504685, 11817.586384476503, 12285.754284914074, 12379.133460657667, 12431.93784031074, 11390.715232119263, 11431.256968297033, 11458.94270528642, 11908.940455297668, 11952.223105498115, 7177.7487787927175, 11667.824982625438, 11674.533018679158, 11707.356095414494, 12142.453589588313, 9079.319051071328, 10227.84658230142, 10352.378857268588, 10539.841902371318, 10667.519961601902, 9258.861117083114, 9369.718908432758, 9557.569909354235, 9784.559694813894, 9874.50627468638]}, "summary": {"lowerIsBetter": false, "suite": 6644.552736683261, "subtests": {"jquery.html": {"lowerIsBetter": false, "filtered": 7274.458681520378, "unit": "ms", "value": 7274.458681520378}, "dojo.html": {"lowerIsBetter": false, "filtered": 5245.670990947735, "unit": "ms", "value": 5245.670990947735}, "mootools.html": {"lowerIsBetter": false, "filtered": 4303.188500788739, "unit": "ms", "value": 4303.188500788739}, "ext.html": {"lowerIsBetter": false, "filtered": 18325.630219830346, "unit": "ms", "value": 18325.630219830346}, "prototype.html": {"lowerIsBetter": false, "filtered": 4797.549209983893, "unit": "ms", "value": 4797.549209983893}, "yui.html": {"lowerIsBetter": false, "filtered": 5960.541749872844, "unit": "ms", "value": 5960.541749872844}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-027"}, "testrun": {"date": 1447740028, "suite": "dromaeo_css", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151116193328", "branch": "Firefox", "revision": "a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb"}}, {"talos_counters": {}, "results": {"audio-dft": [162.0, 161.0, 160.0, 162.0, 163.0, 161.0, 160.0, 164.0, 162.0, 161.0], "ai-astar": [107.0, 110.0, 103.0, 109.0, 113.0, 112.0, 99.0, 109.0, 109.0, 109.0], "imaging-gaussian-blur": [109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 110.0, 110.0, 109.0, 110.0], "audio-beat-detection": [151.0, 123.0, 153.0, 163.0, 125.0, 149.0, 155.0, 129.0, 169.0, 124.0], "json-stringify-tinderbox": [68.0, 69.0, 70.0, 68.0, 70.0, 70.0, 69.0, 70.0, 69.0, 71.0], "stanford-crypto-pbkdf2": [141.0, 155.0, 151.0, 147.0, 148.0, 142.0, 158.0, 148.0, 148.0, 144.0], "json-parse-financial": [58.0, 58.0, 58.0, 58.0, 57.0, 57.0, 58.0, 65.0, 55.0, 55.0], "stanford-crypto-sha256-iterative": [65.0, 66.0, 65.0, 70.0, 66.0, 67.0, 66.0, 68.0, 66.0, 66.0], "audio-fft": [72.0, 72.0, 81.0, 92.0, 72.0, 72.0, 72.0, 109.0, 93.0, 72.0], "imaging-darkroom": [146.0, 147.0, 146.0, 147.0, 146.0, 148.0, 146.0, 146.0, 146.0, 145.0], "stanford-crypto-ccm": [122.0, 126.0, 120.0, 118.0, 122.0, 119.0, 124.0, 119.0, 117.0, 119.0], "audio-oscillator": [91.0, 90.0, 140.0, 90.0, 90.0, 90.0, 140.0, 90.0, 90.0, 132.0], "stanford-crypto-aes": [86.0, 84.0, 86.0, 95.0, 86.0, 86.0, 85.0, 86.0, 98.0, 85.0], "imaging-desaturate": [95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0]}, "summary": {"lowerIsBetter": true, "suite": 1500.1, "subtests": {"audio-fft": {"lowerIsBetter": true, "filtered": 80.7, "unit": "ms", "value": 80.7}, "stanford-crypto-pbkdf2": {"lowerIsBetter": true, "filtered": 148.2, "unit": "ms", "value": 148.2}, "audio-beat-detection": {"lowerIsBetter": true, "filtered": 144.1, "unit": "ms", "value": 144.1}, "stanford-crypto-ccm": {"lowerIsBetter": true, "filtered": 120.6, "unit": "ms", "value": 120.6}, "imaging-darkroom": {"lowerIsBetter": true, "filtered": 146.3, "unit": "ms", "value": 146.3}, "json-parse-financial": {"lowerIsBetter": true, "filtered": 57.9, "unit": "ms", "value": 57.9}, "audio-oscillator": {"lowerIsBetter": true, "filtered": 104.3, "unit": "ms", "value": 104.3}, "ai-astar": {"lowerIsBetter": true, "filtered": 108.0, "unit": "ms", "value": 108.0}, "audio-dft": {"lowerIsBetter": true, "filtered": 161.6, "unit": "ms", "value": 161.6}, "stanford-crypto-sha256-iterative": {"lowerIsBetter": true, "filtered": 66.5, "unit": "ms", "value": 66.5}, "json-stringify-tinderbox": {"lowerIsBetter": true, "filtered": 69.4, "unit": "ms", "value": 69.4}, "imaging-gaussian-blur": {"lowerIsBetter": true, "filtered": 109.5, "unit": "ms", "value": 109.5}, "stanford-crypto-aes": {"lowerIsBetter": true, "filtered": 87.7, "unit": "ms", "value": 87.7}, "imaging-desaturate": {"lowerIsBetter": true, "filtered": 95.3, "unit": "ms", "value": 95.3}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-027"}, "testrun": {"date": 1447740028, "suite": "kraken", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151116193328", "branch": "Firefox", "revision": "a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb"}}, {"talos_counters": {}, "results": {"DeltaBlue": [188.35938971557732], "Encrypt": [283.04557033682426], "NavierStokes": [4822.115384615385], "Decrypt": [5045.226130653266], "Splay": [638.9776357827476], "Richards": [166.36167027116952], "RegExp": [25948.71794871795], "Earley": [800.1598721023181], "Boyer": [12481.481481481482], "RayTrace": [1111.111111111111]}, "summary": {"lowerIsBetter": false, "suite": 20873.027102831777, "subtests": {"DeltaBlue": {"lowerIsBetter": false, "filtered": 351.0204619999999, "unit": "score", "value": 351.0204619999999}, "Encrypt": {"lowerIsBetter": false, "filtered": 940.4174729999997, "unit": "score", "value": 940.4174729999997}, "NavierStokes": {"lowerIsBetter": false, "filtered": 307.7487537387835, "unit": "score", "value": 307.7487537387835}, "Decrypt": {"lowerIsBetter": false, "filtered": 52.75898306772911, "unit": "score", "value": 52.75898306772911}, "Splay": {"lowerIsBetter": false, "filtered": 127.53341499999998, "unit": "score", "value": 127.53341499999998}, "RegExp": {"lowerIsBetter": false, "filtered": 35.107129446640336, "unit": "score", "value": 35.107129446640336}, "Richards": {"lowerIsBetter": false, "filtered": 212.20032200000009, "unit": "score", "value": 212.20032200000009}, "Earley": {"lowerIsBetter": false, "filtered": 832.9123006993004, "unit": "score", "value": 832.9123006993004}, "Boyer": {"lowerIsBetter": false, "filtered": 53.39614540059344, "unit": "score", "value": 53.39614540059344}, "RayTrace": {"lowerIsBetter": false, "filtered": 665.9901, "unit": "score", "value": 665.9901}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-027"}, "testrun": {"date": 1447740028, "suite": "v8_7", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151116193328", "branch": "Firefox", "revision": "a2f83cbe53ac4009afa4cb2b0b8f549289b23eeb"}}] 22:20:39 INFO - RETURN: dromaeo_css: 6644.55 22:20:39 INFO - RETURN: kraken: 1500.10 22:20:39 INFO - RETURN: v8_7: 20873.03 22:20:39 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "20873.03"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1500.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6644.55"}}} 22:20:39 INFO - Return code: 0 22:20:39 INFO - # TBPL SUCCESS # 22:20:39 INFO - Running post-action listener: _resource_record_post_action 22:20:39 INFO - Running post-run listener: _resource_record_post_run 22:20:40 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 4096; Write bytes: 507232256; Read time: 52; Write time: 456652 22:20:40 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 167559168; Read time: 0; Write time: 163140 22:20:40 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 4096; Write bytes: 269410304; Read time: 52; Write time: 156536 22:20:40 INFO - Running post-run listener: _upload_blobber_files 22:20:40 INFO - Blob upload gear active. 22:20:40 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 22:20:40 INFO - Copying logs to upload dir... 22:20:40 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1264.201287 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2015-11-16 22:20:40.555128) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-16 22:20:40.560320) ========= 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=447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010052 build_url: 'https://queue.taskcluster.net/v1/task/2pWcTd4wR_6oVDE4vEx2_g/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-16 22:20:40.609805) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 22:20:40.613170) ========= 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=447a3abc0420b6a47dd38cba000001bf-1447738722.564062-182760248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004673 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 22:20:40.662601) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-16 22:20:40.665976) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-16 22:20:40.666345) ========= ========= Total master_lag: 0.26 =========