builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1447988045.49 results: success (0) buildid: 20151119163047 builduid: cc6e0095a1ce4717ba6218942eaa8552 revision: cc325db44f6f8a58604d60b746c140e73f3d8216 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.487250) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.487761) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.488068) ========= 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=0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009099 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.521768) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.522046) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.550647) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.550957) ========= 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=0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004609 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.600696) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.601061) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.601444) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.601763) ========= 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=0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-19 18:54:07-- 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% 213M=0s 2015-11-19 18:54:07 (213 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135507 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.754670) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.754975) ========= 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=0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020149 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.805533) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:05.805888) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cc325db44f6f8a58604d60b746c140e73f3d8216 --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 cc325db44f6f8a58604d60b746c140e73f3d8216 --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=0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-19 18:54:07,404 truncating revision to first 12 chars 2015-11-19 18:54:07,404 Setting DEBUG logging. 2015-11-19 18:54:07,404 attempt 1/10 2015-11-19 18:54:07,404 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cc325db44f6f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-19 18:54:07,660 unpacking tar archive at: mozilla-central-cc325db44f6f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.576632 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:06.395163) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:06.395491) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:06.409141) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:06.409475) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 18:54:06.409987) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2015-11-19 18:54:06.410281) ========= /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=0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:54:08 INFO - MultiFileLogger online at 20151119 18:54:08 in /builds/slave/test-pgo 18:54:08 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 18:54:08 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:54:08 INFO - {'append_to_log': False, 18:54:08 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:54:08 INFO - 'blob_upload_branch': 'Firefox', 18:54:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:54:08 INFO - 'branch': 'Firefox', 18:54:08 INFO - 'buildbot_json_path': 'buildprops.json', 18:54:08 INFO - 'config_files': ('talos/linux_config.py',), 18:54:08 INFO - 'default_actions': ('clobber', 18:54:08 INFO - 'read-buildbot-config', 18:54:08 INFO - 'download-and-extract', 18:54:08 INFO - 'populate-webroot', 18:54:08 INFO - 'create-virtualenv', 18:54:08 INFO - 'install', 18:54:08 INFO - 'run-tests'), 18:54:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:54:08 INFO - 'download_minidump_stackwalk': True, 18:54:08 INFO - 'download_symbols': 'ondemand', 18:54:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:54:08 INFO - 'tooltool.py': '/tools/tooltool.py', 18:54:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:54:08 INFO - '/tools/misc-python/virtualenv.py')}, 18:54:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:54:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:54:08 INFO - 'installer_path': 'installer.exe', 18:54:08 INFO - 'log_level': 'info', 18:54:08 INFO - 'log_name': 'talos', 18:54:08 INFO - 'log_to_console': True, 18:54:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:54:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:54:08 INFO - 'opt_config_files': (), 18:54:08 INFO - 'pip_index': False, 18:54:08 INFO - 'sps_profile': False, 18:54:08 INFO - 'sps_profile_interval': 0, 18:54:08 INFO - 'suite': 'dromaeojs', 18:54:08 INFO - 'system_bits': '64', 18:54:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:54:08 INFO - 'title': 'talos-linux64-ix-056', 18:54:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:54:08 INFO - 'use_talos_json': True, 18:54:08 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:54:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:54:08 INFO - 'work_dir': 'build'} 18:54:08 INFO - ##### 18:54:08 INFO - ##### Running clobber step. 18:54:08 INFO - ##### 18:54:08 INFO - Running pre-action listener: _resource_record_pre_action 18:54:08 INFO - Running main action method: clobber 18:54:08 INFO - rmtree: /builds/slave/test-pgo/build 18:54:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:54:09 INFO - Running post-action listener: _resource_record_post_action 18:54:09 INFO - ##### 18:54:09 INFO - ##### Running read-buildbot-config step. 18:54:09 INFO - ##### 18:54:09 INFO - Running pre-action listener: _resource_record_pre_action 18:54:09 INFO - Running main action method: read_buildbot_config 18:54:09 INFO - Using buildbot properties: 18:54:09 INFO - { 18:54:09 INFO - "properties": { 18:54:09 INFO - "buildnumber": 264, 18:54:09 INFO - "product": "firefox", 18:54:09 INFO - "script_repo_revision": "production", 18:54:09 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:54:09 INFO - "repository": "", 18:54:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 18:54:09 INFO - "buildid": "20151119163047", 18:54:09 INFO - "slavename": "talos-linux64-ix-056", 18:54:09 INFO - "pgo_build": "True", 18:54:09 INFO - "basedir": "/builds/slave/test-pgo", 18:54:09 INFO - "project": "", 18:54:09 INFO - "platform": "ubuntu64_hw", 18:54:09 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:54:09 INFO - "slavebuilddir": "test", 18:54:09 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:54:09 INFO - "branch": "mozilla-central", 18:54:09 INFO - "repo_path": "mozilla-central", 18:54:09 INFO - "stage_platform": "linux64-pgo", 18:54:09 INFO - "builduid": "cc6e0095a1ce4717ba6218942eaa8552", 18:54:09 INFO - "revision": "cc325db44f6f8a58604d60b746c140e73f3d8216" 18:54:09 INFO - }, 18:54:09 INFO - "sourcestamp": { 18:54:09 INFO - "repository": "", 18:54:09 INFO - "hasPatch": false, 18:54:09 INFO - "project": "", 18:54:09 INFO - "branch": "mozilla-central-linux64-pgo-talos", 18:54:09 INFO - "changes": [ 18:54:09 INFO - { 18:54:09 INFO - "category": null, 18:54:09 INFO - "files": [ 18:54:09 INFO - { 18:54:09 INFO - "url": null, 18:54:09 INFO - "name": "https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:54:09 INFO - } 18:54:09 INFO - ], 18:54:09 INFO - "repository": "", 18:54:09 INFO - "rev": "cc325db44f6f8a58604d60b746c140e73f3d8216", 18:54:09 INFO - "who": "sendchange", 18:54:09 INFO - "when": 1447987969, 18:54:09 INFO - "number": 6686513, 18:54:09 INFO - "comments": "", 18:54:09 INFO - "project": "", 18:54:09 INFO - "at": "Thu 19 Nov 2015 18:52:49", 18:54:09 INFO - "branch": "mozilla-central-linux64-pgo-talos", 18:54:09 INFO - "revlink": "", 18:54:09 INFO - "properties": [ 18:54:09 INFO - [ 18:54:09 INFO - "buildid", 18:54:09 INFO - "20151119163047", 18:54:09 INFO - "Change" 18:54:09 INFO - ], 18:54:09 INFO - [ 18:54:09 INFO - "builduid", 18:54:09 INFO - "cc6e0095a1ce4717ba6218942eaa8552", 18:54:09 INFO - "Change" 18:54:09 INFO - ], 18:54:09 INFO - [ 18:54:09 INFO - "pgo_build", 18:54:09 INFO - "True", 18:54:09 INFO - "Change" 18:54:09 INFO - ] 18:54:09 INFO - ], 18:54:09 INFO - "revision": "cc325db44f6f8a58604d60b746c140e73f3d8216" 18:54:09 INFO - } 18:54:09 INFO - ], 18:54:09 INFO - "revision": "cc325db44f6f8a58604d60b746c140e73f3d8216" 18:54:09 INFO - } 18:54:09 INFO - } 18:54:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:54:09 INFO - Running post-action listener: _resource_record_post_action 18:54:09 INFO - ##### 18:54:09 INFO - ##### Running download-and-extract step. 18:54:09 INFO - ##### 18:54:09 INFO - Running pre-action listener: _resource_record_pre_action 18:54:09 INFO - Running main action method: download_and_extract 18:54:09 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:54: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')]} 18:54:09 INFO - https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:54:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/test_packages.json 18:54:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/test_packages.json 18:54:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:54:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:54:24 INFO - Downloaded 1302 bytes. 18:54:24 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:54:24 INFO - Using the following test package requirements: 18:54:24 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:54:24 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:54:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:54:24 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:54:24 INFO - u'jsshell-linux-x86_64.zip'], 18:54:24 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:54:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:54:24 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:54:24 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:54:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:54:24 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:54:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:54:24 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:54:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:54:24 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:54:24 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:54:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:54:24 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:54:24 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')]} 18:54:24 INFO - https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:54:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:54:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:54:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/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 18:54:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/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 18:54:36 INFO - Downloaded 22520897 bytes. 18:54:36 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 18:54:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:54:36 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 18:54:37 INFO - Return code: 0 18:54:37 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 18:54: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')]} 18:54:37 INFO - https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:54:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:54:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:54:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/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 18:54:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/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 18:54:39 INFO - Downloaded 22520897 bytes. 18:54:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:54:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:54:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:54:40 INFO - Return code: 0 18:54: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')]} 18:54:40 INFO - https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:54:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:54:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:54:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/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 18:54:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/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 18:54:41 INFO - Downloaded 11130997 bytes. 18:54:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:54:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:54:41 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:54:41 INFO - Return code: 0 18:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:54:41 INFO - https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:54:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:54:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:54:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/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 18:54:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/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 18:54:50 INFO - Downloaded 57345520 bytes. 18:54:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:54:50 INFO - mkdir: /builds/slave/test-pgo/properties 18:54:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:54:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:54:50 INFO - Contents: 18:54:50 INFO - build_url:https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:54:50 INFO - Running post-action listener: _resource_record_post_action 18:54:50 INFO - Running post-action listener: set_extra_try_arguments 18:54:50 INFO - ##### 18:54:50 INFO - ##### Running populate-webroot step. 18:54:50 INFO - ##### 18:54:50 INFO - Running pre-action listener: _resource_record_pre_action 18:54:50 INFO - Running main action method: populate_webroot 18:54:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:54:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:54:50 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:54:50 INFO - '../fennec_ids.txt'], 18:54:50 INFO - 'tests': ['tcheck2']}, 18:54:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:54:50 INFO - '--tppagecycles', 18:54:50 INFO - '7'], 18:54:50 INFO - 'tests': ['tsvgm']}}, 18:54:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:54:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:54:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:54:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:54:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:54:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:54:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:54:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:50 INFO - 'talos_options': ['--e10s'], 18:54:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:54:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:50 INFO - 'tests': ['damp', 'tps']}, 18:54:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:50 INFO - 'talos_options': ['--e10s'], 18:54:50 INFO - 'tests': ['damp', 'tps']}, 18:54:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:54:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:54:50 INFO - 'other': {'tests': ['a11yr', 18:54:50 INFO - 'ts_paint', 18:54:50 INFO - 'tpaint', 18:54:50 INFO - 'sessionrestore', 18:54:50 INFO - 'sessionrestore_no_auto_restore']}, 18:54:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:54:50 INFO - 'tests': ['a11yr', 18:54:50 INFO - 'ts_paint', 18:54:50 INFO - 'tpaint', 18:54:50 INFO - 'sessionrestore', 18:54:50 INFO - 'sessionrestore_no_auto_restore']}, 18:54:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:54:50 INFO - 'tests': ['a11yr', 18:54:50 INFO - 'ts_paint', 18:54:50 INFO - 'tpaint', 18:54:50 INFO - 'sessionrestore', 18:54:50 INFO - 'sessionrestore_no_auto_restore']}, 18:54:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:54:50 INFO - 'tests': ['a11yr', 18:54:50 INFO - 'ts_paint', 18:54:50 INFO - 'tpaint', 18:54:50 INFO - 'sessionrestore', 18:54:50 INFO - 'sessionrestore_no_auto_restore']}, 18:54:50 INFO - 'other_l64': {'tests': ['a11yr', 18:54:50 INFO - 'ts_paint', 18:54:50 INFO - 'tpaint', 18:54:50 INFO - 'sessionrestore', 18:54:50 INFO - 'sessionrestore_no_auto_restore']}, 18:54:50 INFO - 'other_nol64': {'tests': ['a11yr', 18:54:50 INFO - 'ts_paint', 18:54:50 INFO - 'tpaint', 18:54:50 INFO - 'sessionrestore', 18:54:50 INFO - 'sessionrestore_no_auto_restore']}, 18:54:50 INFO - 'svgr': {'tests': ['tsvgx', 18:54:50 INFO - 'tsvgr_opacity', 18:54:50 INFO - 'tart', 18:54:50 INFO - 'tscrollx', 18:54:50 INFO - 'cart']}, 18:54:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:54:50 INFO - 'tests': ['tsvgx', 18:54:50 INFO - 'tsvgr_opacity', 18:54:50 INFO - 'tart', 18:54:50 INFO - 'tscrollx', 18:54:50 INFO - 'cart']}, 18:54:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:50 INFO - 'tests': ['tp5o']}, 18:54:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:50 INFO - 'talos_options': ['--e10s'], 18:54:50 INFO - 'tests': ['tp5o']}, 18:54:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:50 INFO - 'talos_options': ['--xperf_path', 18:54:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:54:50 INFO - 'tests': ['tp5n']}, 18:54:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:50 INFO - 'talos_options': ['--e10s', 18:54:50 INFO - '--xperf_path', 18:54:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:54:50 INFO - 'tests': ['tp5n']}}, 18:54:50 INFO - 'talos.zip': {'path': '', 18:54:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:54:50 INFO - Running post-action listener: _resource_record_post_action 18:54:50 INFO - ##### 18:54:50 INFO - ##### Running create-virtualenv step. 18:54:50 INFO - ##### 18:54:50 INFO - Running pre-action listener: _resource_record_pre_action 18:54:50 INFO - Running main action method: create_virtualenv 18:54:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:54:50 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 18:54:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:54:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:54:50 INFO - Using real prefix '/usr' 18:54:50 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:54:50 INFO - Installing distribute.............................................................................................................................................................................................done. 18:54:52 INFO - Installing pip.................done. 18:54:52 INFO - Return code: 0 18:54:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:54: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')]} 18:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54: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')]} 18:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:52 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 0x7f1984a42c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80d70>, '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 0x1f81b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81fb0>, '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': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', '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 18:54:52 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 18:54:52 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 18:54:52 INFO - Using env: {'DISPLAY': ':0', 18:54:52 INFO - 'HOME': '/home/cltbld', 18:54:52 INFO - 'LANG': 'en_US.UTF-8', 18:54:52 INFO - 'LANGUAGE': 'en_US:en', 18:54:52 INFO - 'LOGNAME': 'cltbld', 18:54:52 INFO - 'MAIL': '/var/mail/cltbld', 18:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:52 INFO - 'MOZ_NO_REMOTE': '1', 18:54:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:54:52 INFO - 'NO_EM_RESTART': '1', 18:54:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:54:52 INFO - 'PWD': '/builds/slave/test-pgo', 18:54:52 INFO - 'SHELL': '/bin/bash', 18:54:52 INFO - 'SHLVL': '1', 18:54:52 INFO - 'TERM': 'linux', 18:54:52 INFO - 'TMOUT': '86400', 18:54:52 INFO - 'USER': 'cltbld', 18:54:52 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', 18:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:52 INFO - '_': '/tools/buildbot/bin/python'} 18:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:53 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 18:54:53 INFO - Cleaning up... 18:54:53 INFO - Return code: 0 18:54:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:54: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')]} 18:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54: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')]} 18:54:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:53 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 0x7f1984a42c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80d70>, '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 0x1f81b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81fb0>, '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': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', '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 18:54:53 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 18:54:53 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 18:54:53 INFO - Using env: {'DISPLAY': ':0', 18:54:53 INFO - 'HOME': '/home/cltbld', 18:54:53 INFO - 'LANG': 'en_US.UTF-8', 18:54:53 INFO - 'LANGUAGE': 'en_US:en', 18:54:53 INFO - 'LOGNAME': 'cltbld', 18:54:53 INFO - 'MAIL': '/var/mail/cltbld', 18:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:53 INFO - 'MOZ_NO_REMOTE': '1', 18:54:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:54:53 INFO - 'NO_EM_RESTART': '1', 18:54:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:54:53 INFO - 'PWD': '/builds/slave/test-pgo', 18:54:53 INFO - 'SHELL': '/bin/bash', 18:54:53 INFO - 'SHLVL': '1', 18:54:53 INFO - 'TERM': 'linux', 18:54:53 INFO - 'TMOUT': '86400', 18:54:53 INFO - 'USER': 'cltbld', 18:54:53 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', 18:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:53 INFO - '_': '/tools/buildbot/bin/python'} 18:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:53 INFO - Downloading/unpacking psutil>=0.7.1 18:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:54:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:54:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:54:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:54:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:54:54 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:54:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:54:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:54:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:54:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:54:55 INFO - Installing collected packages: psutil 18:54:55 INFO - Running setup.py install for psutil 18:54:55 INFO - building 'psutil._psutil_linux' extension 18:54:55 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 18:54:55 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 18:54:55 INFO - building 'psutil._psutil_posix' extension 18:54:55 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 18:54:55 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 18:54:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:54:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:54:55 INFO - Successfully installed psutil 18:54:55 INFO - Cleaning up... 18:54:55 INFO - Return code: 0 18:54:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:54:55 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')]} 18:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:55 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')]} 18:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:55 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 0x7f1984a42c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80d70>, '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 0x1f81b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81fb0>, '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': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', '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 18:54:55 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 18:54:55 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 18:54:55 INFO - Using env: {'DISPLAY': ':0', 18:54:55 INFO - 'HOME': '/home/cltbld', 18:54:55 INFO - 'LANG': 'en_US.UTF-8', 18:54:55 INFO - 'LANGUAGE': 'en_US:en', 18:54:55 INFO - 'LOGNAME': 'cltbld', 18:54:55 INFO - 'MAIL': '/var/mail/cltbld', 18:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:55 INFO - 'MOZ_NO_REMOTE': '1', 18:54:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:54:55 INFO - 'NO_EM_RESTART': '1', 18:54:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:54:55 INFO - 'PWD': '/builds/slave/test-pgo', 18:54:55 INFO - 'SHELL': '/bin/bash', 18:54:55 INFO - 'SHLVL': '1', 18:54:55 INFO - 'TERM': 'linux', 18:54:55 INFO - 'TMOUT': '86400', 18:54:55 INFO - 'USER': 'cltbld', 18:54:55 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', 18:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:55 INFO - '_': '/tools/buildbot/bin/python'} 18:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:54:55 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 18:54:55 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 18:54:55 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 18:54:55 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 18:54:55 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 18:54:55 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 18:54:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:54:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:54:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:54:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:54:57 INFO - Installing collected packages: mozsystemmonitor 18:54:57 INFO - Running setup.py install for mozsystemmonitor 18:54:57 INFO - Successfully installed mozsystemmonitor 18:54:57 INFO - Cleaning up... 18:54:57 INFO - Return code: 0 18:54:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f1984a42c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80d70>, '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 0x1f81b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81fb0>, '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': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', '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 18:54:57 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 18:54:57 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 18:54:57 INFO - Using env: {'DISPLAY': ':0', 18:54:57 INFO - 'HOME': '/home/cltbld', 18:54:57 INFO - 'LANG': 'en_US.UTF-8', 18:54:57 INFO - 'LANGUAGE': 'en_US:en', 18:54:57 INFO - 'LOGNAME': 'cltbld', 18:54:57 INFO - 'MAIL': '/var/mail/cltbld', 18:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:57 INFO - 'MOZ_NO_REMOTE': '1', 18:54:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:54:57 INFO - 'NO_EM_RESTART': '1', 18:54:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:54:57 INFO - 'PWD': '/builds/slave/test-pgo', 18:54:57 INFO - 'SHELL': '/bin/bash', 18:54:57 INFO - 'SHLVL': '1', 18:54:57 INFO - 'TERM': 'linux', 18:54:57 INFO - 'TMOUT': '86400', 18:54:57 INFO - 'USER': 'cltbld', 18:54:57 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', 18:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:57 INFO - '_': '/tools/buildbot/bin/python'} 18:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:57 INFO - Downloading/unpacking blobuploader==1.2.4 18:54:57 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 18:54:57 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 18:54:57 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 18:54:57 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 18:54:57 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 18:54:57 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 18:54:59 INFO - Downloading blobuploader-1.2.4.tar.gz 18:54:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:54:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:54:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:54:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:54:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:54:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:54:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:54:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:54:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:55:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:55:00 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 18:55:00 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 18:55:00 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 18:55:00 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 18:55:00 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 18:55:00 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 18:55:00 INFO - Downloading docopt-0.6.1.tar.gz 18:55:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:55:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:55:00 INFO - Installing collected packages: blobuploader, requests, docopt 18:55:00 INFO - Running setup.py install for blobuploader 18:55:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:55:00 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:55:00 INFO - Running setup.py install for requests 18:55:00 INFO - Running setup.py install for docopt 18:55:00 INFO - Successfully installed blobuploader requests docopt 18:55:00 INFO - Cleaning up... 18:55:00 INFO - Return code: 0 18:55:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:55: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')]} 18:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55: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')]} 18:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:00 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 0x7f1984a42c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80d70>, '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 0x1f81b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81fb0>, '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': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', '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 18:55:00 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 18:55:00 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 18:55:00 INFO - Using env: {'DISPLAY': ':0', 18:55:00 INFO - 'HOME': '/home/cltbld', 18:55:00 INFO - 'LANG': 'en_US.UTF-8', 18:55:00 INFO - 'LANGUAGE': 'en_US:en', 18:55:00 INFO - 'LOGNAME': 'cltbld', 18:55:00 INFO - 'MAIL': '/var/mail/cltbld', 18:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:00 INFO - 'MOZ_NO_REMOTE': '1', 18:55:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:55:00 INFO - 'NO_EM_RESTART': '1', 18:55:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:55:00 INFO - 'PWD': '/builds/slave/test-pgo', 18:55:00 INFO - 'SHELL': '/bin/bash', 18:55:00 INFO - 'SHLVL': '1', 18:55:00 INFO - 'TERM': 'linux', 18:55:00 INFO - 'TMOUT': '86400', 18:55:00 INFO - 'USER': 'cltbld', 18:55:00 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', 18:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:00 INFO - '_': '/tools/buildbot/bin/python'} 18:55:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:55:01 INFO - Running setup.py (path:/tmp/pip-GEdBSI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:55:01 INFO - Running setup.py (path:/tmp/pip-g_4qjm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:55:01 INFO - Running setup.py (path:/tmp/pip-kLA3Dx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:55:01 INFO - Running setup.py (path:/tmp/pip-VWQIsL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:55:01 INFO - Running setup.py (path:/tmp/pip-iim2Qk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:55:01 INFO - Running setup.py (path:/tmp/pip-mpf3pL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:55:01 INFO - Running setup.py (path:/tmp/pip-mRCmVL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:55:01 INFO - Running setup.py (path:/tmp/pip-1C8OKj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:55:01 INFO - Running setup.py (path:/tmp/pip-tZPU59-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:55:01 INFO - Running setup.py (path:/tmp/pip-WlII72-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:55:01 INFO - Running setup.py (path:/tmp/pip-6mw9pL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:55:01 INFO - Running setup.py (path:/tmp/pip-RGQ1Y2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:55:01 INFO - Running setup.py (path:/tmp/pip-wYcQgh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:55:02 INFO - Running setup.py (path:/tmp/pip-IlumVX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:55:02 INFO - Running setup.py (path:/tmp/pip-TNVCja-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:55:02 INFO - Running setup.py (path:/tmp/pip-BfEtaj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:55:02 INFO - Running setup.py (path:/tmp/pip-gzRvM2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:55:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:55:02 INFO - Running setup.py install for manifestparser 18:55:02 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:55:02 INFO - Running setup.py install for mozcrash 18:55:02 INFO - Running setup.py install for mozdebug 18:55:02 INFO - Running setup.py install for mozdevice 18:55:02 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:55:02 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:55:02 INFO - Running setup.py install for mozfile 18:55:02 INFO - Running setup.py install for mozhttpd 18:55:02 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:55:02 INFO - Running setup.py install for mozinfo 18:55:03 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Running setup.py install for mozInstall 18:55:03 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Running setup.py install for mozleak 18:55:03 INFO - Running setup.py install for mozlog 18:55:03 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Running setup.py install for moznetwork 18:55:03 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Running setup.py install for mozprocess 18:55:03 INFO - Running setup.py install for mozprofile 18:55:03 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Running setup.py install for mozrunner 18:55:03 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:55:03 INFO - Running setup.py install for mozscreenshot 18:55:03 INFO - Running setup.py install for moztest 18:55:04 INFO - Running setup.py install for mozversion 18:55:04 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:55:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:55:04 INFO - Cleaning up... 18:55:04 INFO - Return code: 0 18:55:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:55:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:55:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:55:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f1984a42c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80d70>, '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 0x1f81b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81fb0>, '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': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', '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 18:55:04 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 18:55:04 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 18:55:04 INFO - Using env: {'DISPLAY': ':0', 18:55:04 INFO - 'HOME': '/home/cltbld', 18:55:04 INFO - 'LANG': 'en_US.UTF-8', 18:55:04 INFO - 'LANGUAGE': 'en_US:en', 18:55:04 INFO - 'LOGNAME': 'cltbld', 18:55:04 INFO - 'MAIL': '/var/mail/cltbld', 18:55:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:04 INFO - 'MOZ_NO_REMOTE': '1', 18:55:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:55:04 INFO - 'NO_EM_RESTART': '1', 18:55:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:55:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:55:04 INFO - 'PWD': '/builds/slave/test-pgo', 18:55:04 INFO - 'SHELL': '/bin/bash', 18:55:04 INFO - 'SHLVL': '1', 18:55:04 INFO - 'TERM': 'linux', 18:55:04 INFO - 'TMOUT': '86400', 18:55:04 INFO - 'USER': 'cltbld', 18:55:04 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', 18:55:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:04 INFO - '_': '/tools/buildbot/bin/python'} 18:55:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:55:04 INFO - Running setup.py (path:/tmp/pip-lzaBPa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:55:04 INFO - Running setup.py (path:/tmp/pip-RLhbLm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:55:04 INFO - Running setup.py (path:/tmp/pip-7TpxGT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:55:04 INFO - Running setup.py (path:/tmp/pip-z2dQpN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:55:04 INFO - Running setup.py (path:/tmp/pip-miy67V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:55:04 INFO - Running setup.py (path:/tmp/pip-6H3t2w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:55:04 INFO - Running setup.py (path:/tmp/pip-DXDsls-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:55:04 INFO - Running setup.py (path:/tmp/pip-0tpI7A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:55:04 INFO - Running setup.py (path:/tmp/pip-2fXXz0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:55:05 INFO - Running setup.py (path:/tmp/pip-wpKubR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:55:05 INFO - Running setup.py (path:/tmp/pip-_PRc42-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:55:05 INFO - Running setup.py (path:/tmp/pip-Mi2WX1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:55:05 INFO - Running setup.py (path:/tmp/pip-RGkWbZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:55:05 INFO - Running setup.py (path:/tmp/pip-vj60QC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:55:05 INFO - Running setup.py (path:/tmp/pip-1dk2y6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:55:05 INFO - Running setup.py (path:/tmp/pip-2ezZoN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:55:05 INFO - Running setup.py (path:/tmp/pip-gXfMXD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:55:05 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)) 18:55:05 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)) 18:55:05 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)) 18:55:05 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)) 18:55:05 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)) 18:55:05 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)) 18:55:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:55: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 18:55: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 18:55: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 18:55: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 18:55: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 18:55: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 18:55:07 INFO - Downloading blessings-1.5.1.tar.gz 18:55:07 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 18:55:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:55:07 INFO - Installing collected packages: blessings 18:55:07 INFO - Running setup.py install for blessings 18:55:07 INFO - Successfully installed blessings 18:55:07 INFO - Cleaning up... 18:55:07 INFO - Return code: 0 18:55:07 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:55:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:55:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:55:07 INFO - Reading from file tmpfile_stdout 18:55:07 INFO - Current package versions: 18:55:07 INFO - argparse == 1.2.1 18:55:07 INFO - blessings == 1.5.1 18:55:07 INFO - blobuploader == 1.2.4 18:55:07 INFO - docopt == 0.6.1 18:55:07 INFO - manifestparser == 1.1 18:55:07 INFO - mozInstall == 1.12 18:55:07 INFO - mozcrash == 0.16 18:55:07 INFO - mozdebug == 0.1 18:55:07 INFO - mozdevice == 0.47 18:55:07 INFO - mozfile == 1.2 18:55:07 INFO - mozhttpd == 0.7 18:55:07 INFO - mozinfo == 0.9 18:55:07 INFO - mozleak == 0.1 18:55:07 INFO - mozlog == 3.0 18:55:07 INFO - moznetwork == 0.27 18:55:07 INFO - mozprocess == 0.22 18:55:07 INFO - mozprofile == 0.27 18:55:07 INFO - mozrunner == 6.11 18:55:07 INFO - mozscreenshot == 0.1 18:55:07 INFO - mozsystemmonitor == 0.0 18:55:07 INFO - moztest == 0.7 18:55:07 INFO - mozversion == 1.4 18:55:07 INFO - psutil == 3.1.1 18:55:07 INFO - requests == 1.2.3 18:55:07 INFO - wsgiref == 0.1.2 18:55:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f1984a42c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80d70>, '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 0x1f81b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81fb0>, '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': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', '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 18:55:07 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 18:55:07 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 18:55:07 INFO - Using env: {'DISPLAY': ':0', 18:55:07 INFO - 'HOME': '/home/cltbld', 18:55:07 INFO - 'LANG': 'en_US.UTF-8', 18:55:07 INFO - 'LANGUAGE': 'en_US:en', 18:55:07 INFO - 'LOGNAME': 'cltbld', 18:55:07 INFO - 'MAIL': '/var/mail/cltbld', 18:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:07 INFO - 'MOZ_NO_REMOTE': '1', 18:55:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:55:07 INFO - 'NO_EM_RESTART': '1', 18:55:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:55:07 INFO - 'PWD': '/builds/slave/test-pgo', 18:55:07 INFO - 'SHELL': '/bin/bash', 18:55:07 INFO - 'SHLVL': '1', 18:55:07 INFO - 'TERM': 'linux', 18:55:07 INFO - 'TMOUT': '86400', 18:55:07 INFO - 'USER': 'cltbld', 18:55:07 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', 18:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:07 INFO - '_': '/tools/buildbot/bin/python'} 18:55:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:07 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)) 18:55:07 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)) 18:55:07 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)) 18:55:07 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)) 18:55:07 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)) 18:55:07 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)) 18:55:07 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)) 18:55:07 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)) 18:55:07 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)) 18:55:07 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)) 18:55:07 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)) 18:55:07 INFO - Cleaning up... 18:55:07 INFO - Return code: 0 18:55:07 INFO - Running post-action listener: _resource_record_post_action 18:55:07 INFO - Running post-action listener: _start_resource_monitoring 18:55:07 INFO - Starting resource monitoring. 18:55:07 INFO - ##### 18:55:07 INFO - ##### Running install step. 18:55:07 INFO - ##### 18:55:07 INFO - Running pre-action listener: _resource_record_pre_action 18:55:07 INFO - Running main action method: install 18:55:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:55:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:55:08 INFO - Reading from file tmpfile_stdout 18:55:08 INFO - Detecting whether we're running mozinstall >=1.0... 18:55:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:55:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:55:08 INFO - Reading from file tmpfile_stdout 18:55:08 INFO - Output received: 18:55:08 INFO - Usage: mozinstall [options] installer 18:55:08 INFO - Options: 18:55:08 INFO - -h, --help show this help message and exit 18:55:08 INFO - -d DEST, --destination=DEST 18:55:08 INFO - Directory to install application into. [default: 18:55:08 INFO - "/builds/slave/test-pgo"] 18:55:08 INFO - --app=APP Application being installed. [default: firefox] 18:55:08 INFO - mkdir: /builds/slave/test-pgo/build/application 18:55:08 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'] 18:55:08 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 18:55:22 INFO - Reading from file tmpfile_stdout 18:55:22 INFO - Output received: 18:55:22 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:55:22 INFO - Running post-action listener: _resource_record_post_action 18:55:22 INFO - ##### 18:55:22 INFO - ##### Running run-tests step. 18:55:22 INFO - ##### 18:55:22 INFO - Running pre-action listener: _resource_record_pre_action 18:55:22 INFO - Running main action method: run_tests 18:55:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:55:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:55:22 INFO - Python 2.7.3 18:55:22 INFO - Return code: 0 18:55:22 INFO - grabbing minidump binary from tooltool 18:55:22 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')]} 18:55:22 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 0x1f80d70>, '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 0x1f81b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:55:22 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 18:55:22 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 18:55:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:55:22 INFO - Return code: 0 18:55:22 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:55:22 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:55:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:55:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:55:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:55:22 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/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:55:22 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/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost 18:55:22 INFO - Using env: {'DISPLAY': ':0', 18:55:22 INFO - 'HOME': '/home/cltbld', 18:55:22 INFO - 'LANG': 'en_US.UTF-8', 18:55:22 INFO - 'LANGUAGE': 'en_US:en', 18:55:22 INFO - 'LOGNAME': 'cltbld', 18:55:22 INFO - 'MAIL': '/var/mail/cltbld', 18:55:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:55:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:22 INFO - 'MOZ_NO_REMOTE': '1', 18:55:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:55:22 INFO - 'NO_EM_RESTART': '1', 18:55:22 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:55:22 INFO - 'PWD': '/builds/slave/test-pgo', 18:55:22 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:55:22 INFO - 'SHELL': '/bin/bash', 18:55:22 INFO - 'SHLVL': '1', 18:55:22 INFO - 'TERM': 'linux', 18:55:22 INFO - 'TMOUT': '86400', 18:55:22 INFO - 'USER': 'cltbld', 18:55:22 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297', 18:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:22 INFO - '_': '/tools/buildbot/bin/python'} 18:55:22 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/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] with output_timeout 3600 18:55:22 INFO - mozversion INFO | application_buildid: 20151119163047 18:55:22 INFO - mozversion INFO | application_changeset: cc325db44f6f8a58604d60b746c140e73f3d8216 18:55:22 INFO - mozversion INFO | application_display_name: Nightly 18:55:22 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:55:22 INFO - mozversion INFO | application_name: Firefox 18:55:22 INFO - mozversion INFO | application_remotingname: firefox 18:55:22 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 18:55:22 INFO - mozversion INFO | application_vendor: Mozilla 18:55:22 INFO - mozversion INFO | application_version: 45.0a1 18:55:22 INFO - mozversion INFO | platform_buildid: 20151119163047 18:55:22 INFO - mozversion INFO | platform_changeset: cc325db44f6f8a58604d60b746c140e73f3d8216 18:55:22 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 18:55:22 INFO - mozversion INFO | platform_version: 45.0a1 18:55:22 INFO - 2015-11-19 18:55:22,922 DEBUG : using testdate: 1447988122 18:55:22 INFO - 2015-11-19 18:55:22,922 DEBUG : actual date: 1447988122 18:55:23 INFO - 2015-11-19 18:55:23,009 INFO : starting webserver on 'localhost:39511' 18:55:23 INFO - 2015-11-19 18:55:23,010 INFO : Starting test suite talos-linux64-ix-056 18:55:23 INFO - 2015-11-19 18:55:23,010 INFO : Starting test dromaeo_css 18:55:23 INFO - 2015-11-19 18:55:23,011 DEBUG : operating with platform_type : linux_ 18:55:23 INFO - 2015-11-19 18:55:23,011 INFO : Initialising browser for dromaeo_css test... 18:55:23 INFO - 2015-11-19 18:55:23,021 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUSbYIt/profile http://localhost:39511/getInfo.html 18:55:28 INFO - 2015-11-19 18:55:28,558 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:55:28 INFO - 2015-11-19 18:55:28,558 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:55:28 INFO - 2015-11-19 18:55:28,558 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:55:28 INFO - 2015-11-19 18:55:28,558 DEBUG : BROWSER_OUTPUT: __metrics 18:55:29 INFO - 2015-11-19 18:55:29,326 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:55:30 INFO - 2015-11-19 18:55:30,742 INFO : Browser initialized. 18:55:30 INFO - 2015-11-19 18:55:30,743 INFO : Running cycle 1/1 for dromaeo_css test... 18:55:30 INFO - 2015-11-19 18:55:30,743 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUSbYIt/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:55:42 INFO - 2015-11-19 18:55:42,483 DEBUG : BROWSER_OUTPUT: RSS: Main: 142462976 18:55:42 INFO - 2015-11-19 18:55:42,483 DEBUG : BROWSER_OUTPUT: 18:56:34 INFO - 2015-11-19 18:56:34,189 DEBUG : BROWSER_OUTPUT: 1447988194185 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] 18:58:43 INFO - 2015-11-19 18:58:43,085 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39511/tests/dromaeo/cssquery-ext.html) 18:58:44 INFO - 2015-11-19 18:58:44,250 DEBUG : BROWSER_OUTPUT: RSS: Main: 266047488 18:58:44 INFO - 2015-11-19 18:58:44,251 DEBUG : BROWSER_OUTPUT: 19:01:46 INFO - 2015-11-19 19:01:46,628 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/dromaeo/cssquery-ext.html (next: http://localhost:39511/tests/dromaeo/cssquery-jquery.html) 19:01:47 INFO - 2015-11-19 19:01:47,195 DEBUG : BROWSER_OUTPUT: RSS: Main: 221646848 19:01:47 INFO - 2015-11-19 19:01:47,195 DEBUG : BROWSER_OUTPUT: 19:04:49 INFO - 2015-11-19 19:04:49,106 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39511/tests/dromaeo/cssquery-mootools.html) 19:04:49 INFO - 2015-11-19 19:04:49,672 DEBUG : BROWSER_OUTPUT: RSS: Main: 215785472 19:04:49 INFO - 2015-11-19 19:04:49,672 DEBUG : BROWSER_OUTPUT: 19:07:51 INFO - 2015-11-19 19:07:51,566 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39511/tests/dromaeo/cssquery-prototype.html) 19:07:52 INFO - 2015-11-19 19:07:52,131 DEBUG : BROWSER_OUTPUT: RSS: Main: 200011776 19:07:52 INFO - 2015-11-19 19:07:52,132 DEBUG : BROWSER_OUTPUT: 19:10:54 INFO - 2015-11-19 19:10:54,569 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39511/tests/dromaeo/cssquery-yui.html) 19:10:55 INFO - 2015-11-19 19:10:55,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 203378688 19:10:55 INFO - 2015-11-19 19:10:55,155 DEBUG : BROWSER_OUTPUT: 19:13:57 INFO - 2015-11-19 19:13:57,654 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/dromaeo/cssquery-yui.html (next: http://localhost:39511/tests/dromaeo/cssquery-dojo.html) 19:13:57 INFO - 2015-11-19 19:13:57,912 DEBUG : BROWSER_OUTPUT: RSS: Main: 264994816 19:13:57 INFO - 2015-11-19 19:13:57,912 DEBUG : BROWSER_OUTPUT: 19:13:57 INFO - 2015-11-19 19:13:57,912 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:13:57 INFO - 2015-11-19 19:13:57,912 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:13:57 INFO - 2015-11-19 19:13:57,912 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:13:57 INFO - 2015-11-19 19:13:57,912 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:13:57 INFO - 2015-11-19 19:13:57,912 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3195.217171792911;3237.4981877808946;3270.967290327096;3283.786553873998;3302.0754188827127;5305.283786688796;5405.729713514318;5412.485813847683;5424.810131645393;5435.701036442994;5339;5415.241866138734;5443.374011988622;5453.127499600065;5457.5633949284065;4911.361523002005;4919.852404427855;4941.258811178316;4945.035718034985;5006.299118123466;4852.902941941159;5007.14878470659;5083.6441449098575;5097.617678674096;5150.9183071554835;4823.541763532458;4906.067847109038;4920.458749537548;4928.6303527235605;4957.281194226835;4907.067657145131;4918.065567542156;4918.163912134945;4925.827596034122;4964.205727083686;4901.950980490185;4909.189983652693;4914.410270767495;4936.432310284327;4982.750862456863;5077.187649976023;5173.198154286091;5198.324217851693;5321.281626980385;5352.464753524655;5835.854103647401;5848.181254624356;5855.3851845556455;5858.355580886099;5862.032764593837;5750.108733146369;5879.353271140171;5892.292924849003;5893.823185304448;5899.793507227269;5387.676739395688;5441.347038355423;5445.83662490122;5449.427810079964;5503.972480137573;5870.855183239313;5883;5921.970390148108;6017.969910150421;6023.789167379121;5665.830025099253;5785.652860828363;5789.131630255411;5789.768409263666;5799.88400231993;5781.53747700183;5787.594868359174;5866.6186697864505;5870.119482077723;5923.644581325134;5801.245838041028;5888.499477544457;5889.764409423661;5893.14549390336;5896.587238893322;5677.914831277534;5816.854578635483;5897.793577224862;5900.911486327708;5905;4485.394471746337;4488.371627972087;4491.528389519053;4516.593506584423;4543.522930092358;3982.7012974027066;3999.6600288975183;4001.819918103634;4005.679545636342;4050.1697152083752;5398.406175320712;5503.559715222773;5516.503514683698;5598.944010559842;5603.7478313475995;5773.278340207474;5809.68046757424;5852.648841069465;5918.822435326947;5921.259842519685;5498;5574.665520068727;5579.246801681802;5606.21512988182;5649.322081350264;5094.465081166498;5137.255098010767;5140.897182056338;5141.9742901286;5179.792808287626;5922.674252916131;6044.667543285162;6063.969680151571;6115.602485838406;6125.050617154348;5927.881442371215;5931.228940237742;5936.703164841654;5941.643501389931;5975.16347711312;6067.089936509559;6113.5414843887775;6132.63204207749;6136.570440069152;6138.5396095292135;5681.545476361964;5735.827925162252;5746.367899530965;5844.590878638454;5877.23595932526;5874.324452687995;5878.706064696834;5879;5884.882302353843;5897.9115313271195;5758.712064396847;5760.596758226884;5765.94234057654;5766.077427611562;5769.653820770767;5634.380218176079;5717.285339332583;5753.798617048551;5762;5793.420657934173;5576.442355764553;5589.413111623221;5606.85982850432;5608.46719561641;5625.943740562706;5678.460546248265;5719.370869204467;5763.827085187451;5771.5671324649975;5808.244928159313;5377.220303056113;5586.804461843816;5618.52242559387;5644.435556444323;5675.347335056521 19:13:57 INFO - 2015-11-19 19:13:57,913 DEBUG : BROWSER_OUTPUT: |1;ext.html;561.9740834292376;591.4233622218337;595.1043679262709;598.7215944585766;603.333316685063;30302.696973030263;38999.41500877486;39319.41020884689;39340.803295983555;39457.21085578287;41359.17281654365;41835.16329673405;41934;41946;41982.37026444606;7564.054493188351;7648.694052237904;8699.086595907449;8843.115688431137;8877.911220887772;10045.849312260321;10173.949130254376;10244.180465562737;10254.641087561937;10255.282130250886;9909.207263418944;10063.396196228214;10077.647282345119;10080.395176289447;10081.143102836231;7883.802904927394;8045.115037345888;8052.637631306547;8061.879071813927;10227;10368.79262414755;10703.83944240837;10736.979986901231;10737.892621073768;10744.14046876248;38670.39279304513;39008.60991390106;39025.60974390248;39065.60934390676;39137.21725565473;3056.679048699899;3137.905862824119;3150.2754366495833;3218.951715724266;3617.6201498842506;29791.255218619706;30006;30357.24106897323;30485.39029219403;31193;3920.2355540669582;3938.8030598469963;3964.603539646041;4034.1931613677384;4101.507819061701;249691;249918.7504062468;250509.21180911505;251174;251658;54656.72671636696;55161.72419137959;55689.16466253009;57228.14157787636;58283;51797.223041654404;51838.740806295726;51942.74028629833;52182;52265.08011899123;14922.179280139479;15029.173395463136;15041.172735499653;15120.621984450478;15137;12053.63839084829;12082.516699331929;12090.818637720442;12126.151169418232;12224.755504889852;18336.90831545834;18521.35175268859;18538.629227415375;18547.16537755804;18550.628987420176;7420.406367490587;7452.701891924262;7658.290151556753;7668.194839541879;7676.923230767732;955.861400096982;962.6053318139669;991.306085739985;1010.2625083688948;1039.028508344685;27430.5885411719;27452.862735686194;27600;27884.721152788214;28040.43919121606;973.1290495006929;974.551706215149;1043.7912417516527;1050.9312806604732;1117.234694234452;13439.865601344058;14642.341094650767;14731.558053258419;14899.255037248095;14928.402863885323;297840;299341;300138;300266;300626;49122;49168.50831491711;49272;49296.75351623219;49301.506984930405;1296.3906963727034;1315.2437348524445;1317.157019507516;1319.9934000329936;1348.4740951029107;26396.208113756617;27160.91288461145;27343.86328068347;27499.31251718723;27576.58635120515;40205;43648.34527482027;43915.34126988034;44055.559444405146;44075.11849763051;193836;275305;283829;283867;283890;26813.86593067022;27353;27362.5895611562;27368;27368.452630947668;39124;39851.80074099611;40329.193416130925;40685.389719153645;41093.58906410898;33909.95520705466;34213.65786342204;34571.30857382889;34751.21990240825;35126;33609.15977100592;34525.26147581416;34834.65165348314;34901;34913.65086349104;33640.3271934555;34427.311453770286;34444.48333275053;34473.482897756054;34496;28885.711142889144;31298.687013129576;31920;31937;32022;23054.769452305933;23726.40683982845;24374.078060837848;24488.75511244865;24603.753962460145 19:13:57 INFO - 2015-11-19 19:13:57,913 DEBUG : BROWSER_OUTPUT: |2;jquery.html;356.8322888242525;357.46915829992463;359.60084306419867;359.76255671256973;361.4596178712825;14096.506622268224;14275.643108922282;16503;16726.916365418172;17320.393786217486;16131.83868161318;16537.173141342915;16679.24943377548;17236.913815430904;17299.91350043248;5424.647397919122;5562.972185139069;5566.86082847928;5573.888522229553;5579.13523403873;4026.396040593905;4160.687948403866;5537.390887002426;5570.136628822539;5947.078202878561;6206.503479721611;6219.844503887393;6262.937370626304;6290.056491526285;6293.181886354768;6012;6094.96952515239;6104.877902441971;6108.725107370156;6114.388561143895;5375.11310633745;5378.435264297271;5379.300690910196;5380.381256155553;5407.351117865842;19808.40574782759;19813.603727925503;20193.293234736862;20565.89717051405;20972;2076.9896150519303;2157.331227319536;2162.1459523488293;2202.119152339077;2317.7566355532595;12787;12831.486740530368;13070.346482675828;13339.399727012307;13352.599422017354;1718.6820438218845;1733.5926057376507;1736.3749050341867;1738.0379017903956;1846.1323178106268;56045;57387.71306143443;57748;58135;59224.40775592232;9623.903760962441;9779.266555008402;9903.702888913343;9907;9942;12715.109942304134;12724.681882953;12738.936305318413;12910.321658184375;12928.870711292766;7413.814654633569;7555.395568354518;7582;7664.233576642291;7728.536287822636;7322.1945585985495;7421.109466864011;7422.740204092831;7425.443091768139;7459.962700186465;5882.470577648032;5943.227380440515;5945.494632956247;5955.970220148958;6001.879962400728;1657.2459530913407;1721.3716993297355;1732.644807814395;1741.277369891481;1774.1838754173225;902.4044130873723;913.8903331600251;1024.3137098144261;1066.9786604268027;1067.9199060070525;15963.60090997711;16707.997520148587;16926.40757573479;17146.314147434212;17524.912375438296;799.428408687791;838.576518857985;907.4555266839975;909.4679612426804;910.7768596693852;9322.627094916263;9908.20734341251;9913.851292230622;10145.08694217524;10261.9486902565;507442;511746;543020;548003;559137;74408.62795686095;74850;77613.61193193997;77926.44147117026;79089;1141.57191053355;1161.2103769436694;1163.8545181852437;1178.5757127434117;1179.1922533064878;15061.77407338869;15272;15468.5359439217;15476.767848482057;15701.685966280838;33746.15634609154;34168.14579635529;34475.827620862736;34675.47986780251;35478.75824346136;44053;44351.556484436034;45477.772611138054;47000.29499557441;47719.522804772896;8953.731388058368;9731;9733.756656083655;9734.513274336396;9748.415095094317;16402.425915092914;17048.33842895134;17110.74333884968;17277.222524986657;17801.376951806626;14400.42398304056;14473.4934277304;14578.271086445848;14586.34361453716;14629.195394253418;14118.2940852959;14268.357923893658;14366.425342986582;14368.784468232776;14401.135931844123;11944.76110477803;13979.44082236699;14404;14821.777673334693;14921.477748278758;8005.679772809255;8247.917520824714;8291.08798032228;8722.215000649718;8989.865152022594;13821.516246931309;13863.930680346533;14041;14230.501932432315;14404 19:13:57 INFO - 2015-11-19 19:13:57,914 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1528.847115288472;1613.8063432388115;1634.7793047938524;1708.2483707168844;1764.1884733022816;6711.966440167798;7051.612161331132;7349.853002939938;7356.926430735691;7360.300771426712;7190.388816950565;7314.5976971266555;7322.63386830659;7332.706691732337;7413.073365829271;1940.43727319078;1983.1373352591597;1984.7519060117486;2235.9888200558976;2261.2989973108306;2246.5731511012855;2301.631738921773;2306.5963456395148;2307.630779075348;2311.399036250579;2095.905684244213;2129.861558998668;2132.115172203534;2132.605467988419;2132.76539580646;1928.1997970842083;1971.5071232191951;2148.495103650641;2165.6859755335545;2217.3015500117413;2186.814120799734;2228.2869481765842;2229.9888500557395;2233.832462565314;2235.3294011796397;7557.130929943072;7605.353544948686;7619.733309334202;7632.580208088553;7640.5797681127515;4494.662900282492;4554.567316104964;4568;4580.312953057068;4590.770461476946;3061.8928337508305;3077.353755711303;3101.581286526313;3106.673799251091;3178.952315715266;1060.4929051530994;1072.6889202131451;1094.9178811589163;1158.206628459508;1162.3548930343657;9900.702978910573;10064.094231519199;10077.59689612422;10090.243231757575;10091.798164036678;3963.801809909493;3977.9204415911518;4000.079981604247;4060.634542891154;4062.106336605942;8055.63749631268;8089.150639182801;8097.919020809834;8098.190180981855;8100.756977290691;4511.503734589192;4534.841280555164;4545.431821022372;4548.1813273610405;4555.180067587799;4237.618614324726;4238.936415953764;4241.575842415796;4243.448351714257;4251;3387.6951074403423;3416.2313479467416;3450.6721861423125;3472.3576138414473;3478.930421391558;1063.648995831374;1073.0825144501496;1073.5276478349501;1081.864766904137;1084.6583326252205;1310.9803352949714;1325.9734805304033;1333.0735139078247;1347.1916849890183;1351.2838195756265;6741.629210393377;6989.89515157273;7350.889736653954;7368.8894666580045;7378.667959941816;594.1829983772313;595.3540408656656;599.235974132984;614.0451597765517;614.7540983606503;5556.499915007668;5613.46672066153;5640.407757185519;5640.717964101778;5658.971705141448;8844.646214151362;9031.683891063782;9093.136152065543;9102.362834601514;9139.680111196076;8939.597718102701;8997.460152390877;9031.36780425377;9039;9044.909550904538;6878.896816547755;7101.538400003983;7114;7115.395191408788;7187.5687458752645;6835.350641689031;6847.897281540681;6891.655417229018;6896.241413444617;6897.068895699016;7831.608419579112;7989.161138080418;8059.274665280153;8077;8196.83606327882;8214.753557393287;8326.50040997542;8343.99872015362;8345.082040975609;8347.95826020866;6304.117423560797;6486.0919471275;6490.286068532363;6491.805245842632;6516.0551719999385;7334.119905611361;7389.445791565761;7395.852082958419;7396.704131834882;7404.740834070782;6838.281980391987;6914.965425172842;6931.202911665223;7066.646667666493;7092.893606595802;6726.932730672631;6737.157855268091;6779.72881084751;6834.487413443912;6868.072810170563;6176.598521096114;6342.175517182921;6497.772577959749;6866.34769696878;6894.13823272091;6185.257769067741;6360;6360.045993101072;6364.204474440695;6370.980643097082;6420.6147631142285;6433.292337842927;6435.935640643534;6470.38531339522;6494.0258961156205 19:13:57 INFO - 2015-11-19 19:13:57,914 DEBUG : BROWSER_OUTPUT: |4;prototype.html;311.4098782806582;329.20496999745615;333.5397151930335;338.6004514672685;338.7831787655899;5972.9402705972925;6033.7284822183;6044.123602077707;6045.972184728601;6079.8176054718315;5950.672713000783;5960.612560183585;5967.791127310545;5982.820515384535;5988.131720900477;4636.6058884994645;4639.860804175863;4643.210654188796;4643.512431194718;4647.163510568096;4406.162829062484;4568;4592.931106033412;4636.768161591924;4659.697119687226;4554.043650833312;4560.407147070892;4564.224081906067;4574.656900732459;4575.450945886499;4295.226859165349;4566.54334566544;4572.35986961824;4579.335996280554;4584.472785629662;3777.7544459610313;3843.846246150151;3934.7245692801516;3946.1910308387;3953.5058117735284;5601.215829783833;5609.298837645294;5624.21261023457;5630.436956304377;5645.068563686987;5016.122178618727;5155.020546096267;5168.870778230575;5264.315638966948;5273.736313184364;6116.694165291718;6189.597676151036;6263.843403914893;6332.366763323676;6343.270523889765;4368.47578290604;4372.2130016597;4382.6055654991205;4388.363687265361;4398.186335527937;6462.25684046336;6526.445252153572;6603.471722262256;6616;6622.701978410983;4462.464504259509;4470.731756094645;4495.078508905666;4496;4497.123061003138;4543;4547.954520454819;4552.362669226311;4554.3851580036935;4555.06621142665;5275.683458992396;5283.784729512234;5288.92066619001;5293.417724050351;5295.841124766263;6252.218472690914;6430.131932189094;6439.774607888701;6447.194100737408;6467.32093130224;1581.9762703559456;1582.5647946814718;1585.8017747781528;1596.0184486540927;1599.4881637876001;1056.3609016545033;1071.9410432426291;1072.1851392941264;1073.1843798713035;1087.8912108789057;6194.442500174916;6195.256569211723;6206.782762603287;6207.96896015517;6212.099245609361;6606.489533977058;6608.735650574019;6611.23970743361;6612;6615.900761488581;6066.636001839815;6193.318734939153;6193.938060619426;6199.690015499207;6203.7828675996125;3541.982290088533;3576.2847430514003;3584.6594573515476;3585.8924232273075;3587.1390866191928;6844.8973265401055;6859.9314006860295;6869.7595584154315;6885.483588730765;6887.827804304832;6473.126127972758;6780.38976492118;6782.593044417252;6786.049953006583;6786.796396108125;6401.871962560723;6736.191657001191;6746.021826835179;6832.282610325944;6854.931450685529;6565.507586931095;6570.507211959027;6572.849751293409;6577.901331479936;6581.80254592363;6509.283978762238;6550.967245163743;6581.638009909501;6606.570572912745;6619.801405957829;6756.391924726799;6780.966095169492;6897.793066208022;6898.999645051339;6917.654117294014;4544.00031992961;4544.931826022546;4546.227141385906;4550.3629491871825;4551;6639.269680335063;6642.701078451578;6644.83387915306;6649.833754155991;6653.700583473658;6308.211473565804;6453.032045193258;6472.482201423969;6478.870422591427;6479.222493300834;6443.065755465525;6455.160829092188;6457.644829534421;6459.321771213955;6463.806085817433;6354.110424540476;6355.459785918249;6359.996601070648;6368.872622547616;6369.1401660777055;6317.6841157941;6460.870782584416;6473.029045643191;6473.449756770728;6480.513961453004;6304.243490780952;6314.368563143833;6316.778912738032;6320.399562041599;6325.683715814283 19:13:57 INFO - 2015-11-19 19:13:57,915 DEBUG : BROWSER_OUTPUT: |5;yui.html;829.1832544943234;832.3549249331769;837.7277384849925;837.7486753973808;839.7984483723908;13595.728085438286;13728.519501817438;13754.312284385791;13756.312184390765;13778.104423212497;13061.67345816355;14680.486182983597;14906.627334316647;14940.47708330206;14941.252937353116;820.9343252539783;827.9751607451786;831.8627426474625;832.196929962586;832.8958880139983;689.135135405066;692.92031416387;805.899262592176;817.1950628630794;824.2458150792019;845.0873057098319;850.4642075492432;857.3269983063296;857.9099194584572;858.0347109501811;715.5921124958776;722.5953466058971;845.3870943565896;846.9491830490159;847.1740053447901;613.9662318572476;629.0312918106112;629.4429429954478;629.6285191736897;630.5460068750492;13597.388117534734;13684.93157534216;13715.657108572266;13736.793948090786;13758.65603359914;11990.640280791591;12419.379031048411;12461.501539938392;12590.24458532491;13344.466221351135;10051.095401413835;10239.84640230397;10282.383057016528;10290.177014952184;10466.267361284714;3590.551181102362;3632.3643362411735;3684.392075307571;3730.1047748540427;3752.9061773455883;47737.52262477366;48580.514194857955;48626;48724.75637621789;48869.266960995614;10301.330413523247;10308.12380947628;10340.638077667396;10419.218558607983;10461.738456538495;18443.72334414985;18444.81555184431;18473.1687074082;18486.722699159523;18494.630107397777;7404.629768511661;7496.475246732785;7612.885806712904;7764.301212890867;7958.84082318362;4071.91856162881;4089.1412803311637;4146.295129827937;4146.979265103715;4225.176090662353;3003.8498075096595;3028.6365636123805;3030.696930306996;3062.7396671282745;3067.8466076696077;552.2875490617139;558.2324304081887;567.5799908068;569.9287589051369;578.5169383564687;584.8128598848413;598.3029770317619;605.1995313941078;622.2284367384501;634.1629049654412;7803.297703206738;7989.640466179036;8033.678652853937;8067.273945344947;8178.052797045784;587.3128439725531;621.2141640824361;636.4335741190345;638.0397501759821;666.3503084492581;3629.6188900165102;3819.779144045845;3850.5764365919727;3878.2825177342283;3882.941755873664;50423.243651345256;55305.72347138319;55657;55883.441165588636;55913.72043139758;50087.24869126966;50574;50716.23925641119;51027.48972510302;51081;47175.292370614465;47554;47672.523274768195;47786.522134778905;48299.75850120727;8084.83830323402;8289.709860154866;8352.540610266493;8451.83096338082;8462.957685211535;33645.83177084099;34243;34431.827840860635;34452.31095378028;34556.82721586376;41227;41452.79273603613;46747.29879051885;47088.29367559558;47110.05779884315;9947.801043979225;10292.897071029194;10515.894841051491;10951.357296405602;11157.944210278896;10670.19973502006;10990;11051.889481105407;11169.72075698114;11180.832287515856;10062.798744025225;10585.576576936835;10590.947045264724;10842.945785271024;10869;10323.277370584288;10819.350838949687;10888.836667449836;10891.237613366988;10951.726206844574;10302.639407620698;10353.171746260432;10537.683869483928;10673.679789606324;10822.615869934281;9130.178283954476;9191.310651701015;9254.305927055364;9456.858147127661;9480.715578532656;7942.6028698566;8305;8337.333013359039;8415.747527574182;8836.293096552132 19:13:57 INFO - 2015-11-19 19:13:57,915 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:13:57 INFO - 2015-11-19 19:13:57,916 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:13:57 INFO - 2015-11-19 19:13:57,916 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,946 19:13:57 INFO - 2015-11-19 19:13:57,916 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:13:57 INFO - 2015-11-19 19:13:57,916 DEBUG : BROWSER_OUTPUT: __startTimestamp1447989237905__endTimestamp 19:13:57 INFO - 2015-11-19 19:13:57,916 DEBUG : BROWSER_OUTPUT: 19:13:57 INFO - 2015-11-19 19:13:57,916 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:13:57 INFO - 2015-11-19 19:13:57,916 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:13:57 INFO - 2015-11-19 19:13:57,917 DEBUG : BROWSER_OUTPUT: 19:13:57 INFO - 2015-11-19 19:13:57,917 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5433.05 Median:5634.38 stddev:612.02 (10.9%) stddev-sans-first:588.18 19:13:57 INFO - 2015-11-19 19:13:57,917 DEBUG : BROWSER_OUTPUT: Values: 3195.2 3237.5 3271.0 3283.8 3302.1 5305.3 5405.7 5412.5 5424.8 5435.7 5339.0 5415.2 5443.4 5453.1 5457.6 4911.4 4919.9 4941.3 4945.0 5006.3 4852.9 5007.1 5083.6 5097.6 5150.9 4823.5 4906.1 4920.5 4928.6 4957.3 4907.1 4918.1 4918.2 4925.8 4964.2 4902.0 4909.2 4914.4 4936.4 4982.8 5077.2 5173.2 5198.3 5321.3 5352.5 5835.9 5848.2 5855.4 5858.4 5862.0 5750.1 5879.4 5892.3 5893.8 5899.8 5387.7 5441.3 5445.8 5449.4 5504.0 5870.9 5883.0 5922.0 6018.0 6023.8 5665.8 5785.7 5789.1 5789.8 5799.9 5781.5 5787.6 5866.6 5870.1 5923.6 5801.2 5888.5 5889.8 5893.1 5896.6 5677.9 5816.9 5897.8 5900.9 5905.0 4485.4 4488.4 4491.5 4516.6 4543.5 3982.7 3999.7 4001.8 4005.7 4050.2 5398.4 5503.6 5516.5 5598.9 5603.7 5773.3 5809.7 5852.6 5918.8 5921.3 5498.0 5574.7 5579.2 5606.2 5649.3 5094.5 5137.3 5140.9 5142.0 5179.8 5922.7 6044.7 6064.0 6115.6 6125.1 5927.9 5931.2 5936.7 5941.6 5975.2 6067.1 6113.5 6132.6 6136.6 6138.5 5681.5 5735.8 5746.4 5844.6 5877.2 5874.3 5878.7 5879.0 5884.9 5897.9 5758.7 5760.6 5765.9 5766.1 5769.7 5634.4 5717.3 5753.8 5762.0 5793.4 5576.4 5589.4 5606.9 5608.5 5625.9 5678.5 5719.4 5763.8 5771.6 5808.2 5377.2 5586.8 5618.5 5644.4 5675.3 19:13:57 INFO - 2015-11-19 19:13:57,917 DEBUG : BROWSER_OUTPUT: 19:13:57 INFO - 2015-11-19 19:13:57,917 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43645.13 Median:17535.14 stddev:71153.36 (405.8%) stddev-sans-first:71279.43 19:13:57 INFO - 2015-11-19 19:13:57,917 DEBUG : BROWSER_OUTPUT: Values: 562.0 591.4 595.1 598.7 603.3 30302.7 38999.4 39319.4 39340.8 39457.2 41359.2 41835.2 41934.0 41946.0 41982.4 7564.1 7648.7 8699.1 8843.1 8877.9 10045.8 10173.9 10244.2 10254.6 10255.3 9909.2 10063.4 10077.6 10080.4 10081.1 7883.8 8045.1 8052.6 8061.9 10227.0 10368.8 10703.8 10737.0 10737.9 10744.1 38670.4 39008.6 39025.6 39065.6 39137.2 3056.7 3137.9 3150.3 3219.0 3617.6 29791.3 30006.0 30357.2 30485.4 31193.0 3920.2 3938.8 3964.6 4034.2 4101.5 249691.0 249918.8 250509.2 251174.0 251658.0 54656.7 55161.7 55689.2 57228.1 58283.0 51797.2 51838.7 51942.7 52182.0 52265.1 14922.2 15029.2 15041.2 15120.6 15137.0 12053.6 12082.5 12090.8 12126.2 12224.8 18336.9 18521.4 18538.6 18547.2 18550.6 7420.4 7452.7 7658.3 7668.2 7676.9 955.9 962.6 991.3 1010.3 1039.0 27430.6 27452.9 27600.0 27884.7 28040.4 973.1 974.6 1043.8 1050.9 1117.2 13439.9 14642.3 14731.6 14899.3 14928.4 297840.0 299341.0 300138.0 300266.0 300626.0 49122.0 49168.5 49272.0 49296.8 49301.5 1296.4 1315.2 1317.2 1320.0 1348.5 26396.2 27160.9 27343.9 27499.3 27576.6 40205.0 43648.3 43915.3 44055.6 44075.1 193836.0 275305.0 283829.0 283867.0 283890.0 26813.9 27353.0 27362.6 27368.0 27368.5 39124.0 39851.8 40329.2 40685.4 41093.6 33910.0 34213.7 34571.3 34751.2 35126.0 33609.2 34525.3 34834.7 34901.0 34913.7 33640.3 34427.3 34444.5 34473.5 34496.0 28885.7 31298.7 31920.0 31937.0 32022.0 23054.8 23726.4 24374.1 24488.8 24603.8 19:13:57 INFO - 2015-11-19 19:13:57,917 DEBUG : BROWSER_OUTPUT: 19:13:57 INFO - 2015-11-19 19:13:57,917 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28602.44 Median:357.15 stddev:87179.21 (24409.6%) stddev-sans-first:87397.97 19:13:57 INFO - 2015-11-19 19:13:57,919 DEBUG : BROWSER_OUTPUT: Values: 356.8 357.5 359.6 359.8 361.5 14096.5 14275.6 16503.0 16726.9 17320.4 16131.8 16537.2 16679.2 17236.9 17299.9 5424.6 5563.0 5566.9 5573.9 5579.1 4026.4 4160.7 5537.4 5570.1 5947.1 6206.5 6219.8 6262.9 6290.1 6293.2 6012.0 6095.0 6104.9 6108.7 6114.4 5375.1 5378.4 5379.3 5380.4 5407.4 19808.4 19813.6 20193.3 20565.9 20972.0 2077.0 2157.3 2162.1 2202.1 2317.8 12787.0 12831.5 13070.3 13339.4 13352.6 1718.7 1733.6 1736.4 1738.0 1846.1 56045.0 57387.7 57748.0 58135.0 59224.4 9623.9 9779.3 9903.7 9907.0 9942.0 12715.1 12724.7 12738.9 12910.3 12928.9 7413.8 7555.4 7582.0 7664.2 7728.5 7322.2 7421.1 7422.7 7425.4 7460.0 5882.5 5943.2 5945.5 5956.0 6001.9 1657.2 1721.4 1732.6 1741.3 1774.2 902.4 913.9 1024.3 1067.0 1067.9 15963.6 16708.0 16926.4 17146.3 17524.9 799.4 838.6 907.5 909.5 910.8 9322.6 9908.2 9913.9 10145.1 10261.9 507442.0 511746.0 543020.0 548003.0 559137.0 74408.6 74850.0 77613.6 77926.4 79089.0 1141.6 1161.2 1163.9 1178.6 1179.2 15061.8 15272.0 15468.5 15476.8 15701.7 33746.2 34168.1 34475.8 34675.5 35478.8 44053.0 44351.6 45477.8 47000.3 47719.5 8953.7 9731.0 9733.8 9734.5 9748.4 16402.4 17048.3 17110.7 17277.2 17801.4 14400.4 14473.5 14578.3 14586.3 14629.2 14118.3 14268.4 14366.4 14368.8 14401.1 11944.8 13979.4 14404.0 14821.8 14921.5 8005.7 8247.9 8291.1 8722.2 8989.9 13821.5 13863.9 14041.0 14230.5 14404.0 19:13:57 INFO - 2015-11-19 19:13:57,919 DEBUG : BROWSER_OUTPUT: 19:13:57 INFO - 2015-11-19 19:13:57,919 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5243.89 Median:6244.69 stddev:2692.64 (43.1%) stddev-sans-first:2685.71 19:13:57 INFO - 2015-11-19 19:13:57,919 DEBUG : BROWSER_OUTPUT: Values: 1528.8 1613.8 1634.8 1708.2 1764.2 6712.0 7051.6 7349.9 7356.9 7360.3 7190.4 7314.6 7322.6 7332.7 7413.1 1940.4 1983.1 1984.8 2236.0 2261.3 2246.6 2301.6 2306.6 2307.6 2311.4 2095.9 2129.9 2132.1 2132.6 2132.8 1928.2 1971.5 2148.5 2165.7 2217.3 2186.8 2228.3 2230.0 2233.8 2235.3 7557.1 7605.4 7619.7 7632.6 7640.6 4494.7 4554.6 4568.0 4580.3 4590.8 3061.9 3077.4 3101.6 3106.7 3179.0 1060.5 1072.7 1094.9 1158.2 1162.4 9900.7 10064.1 10077.6 10090.2 10091.8 3963.8 3977.9 4000.1 4060.6 4062.1 8055.6 8089.2 8097.9 8098.2 8100.8 4511.5 4534.8 4545.4 4548.2 4555.2 4237.6 4238.9 4241.6 4243.4 4251.0 3387.7 3416.2 3450.7 3472.4 3478.9 1063.6 1073.1 1073.5 1081.9 1084.7 1311.0 1326.0 1333.1 1347.2 1351.3 6741.6 6989.9 7350.9 7368.9 7378.7 594.2 595.4 599.2 614.0 614.8 5556.5 5613.5 5640.4 5640.7 5659.0 8844.6 9031.7 9093.1 9102.4 9139.7 8939.6 8997.5 9031.4 9039.0 9044.9 6878.9 7101.5 7114.0 7115.4 7187.6 6835.4 6847.9 6891.7 6896.2 6897.1 7831.6 7989.2 8059.3 8077.0 8196.8 8214.8 8326.5 8344.0 8345.1 8348.0 6304.1 6486.1 6490.3 6491.8 6516.1 7334.1 7389.4 7395.9 7396.7 7404.7 6838.3 6915.0 6931.2 7066.6 7092.9 6726.9 6737.2 6779.7 6834.5 6868.1 6176.6 6342.2 6497.8 6866.3 6894.1 6185.3 6360.0 6360.0 6364.2 6371.0 6420.6 6433.3 6435.9 6470.4 6494.0 19:13:57 INFO - 2015-11-19 19:13:57,919 DEBUG : BROWSER_OUTPUT: 19:13:57 INFO - 2015-11-19 19:13:57,919 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5332.88 Median:6098.26 stddev:1622.93 (26.6%) stddev-sans-first:1583.12 19:13:57 INFO - 2015-11-19 19:13:57,919 DEBUG : BROWSER_OUTPUT: Values: 311.4 329.2 333.5 338.6 338.8 5972.9 6033.7 6044.1 6046.0 6079.8 5950.7 5960.6 5967.8 5982.8 5988.1 4636.6 4639.9 4643.2 4643.5 4647.2 4406.2 4568.0 4592.9 4636.8 4659.7 4554.0 4560.4 4564.2 4574.7 4575.5 4295.2 4566.5 4572.4 4579.3 4584.5 3777.8 3843.8 3934.7 3946.2 3953.5 5601.2 5609.3 5624.2 5630.4 5645.1 5016.1 5155.0 5168.9 5264.3 5273.7 6116.7 6189.6 6263.8 6332.4 6343.3 4368.5 4372.2 4382.6 4388.4 4398.2 6462.3 6526.4 6603.5 6616.0 6622.7 4462.5 4470.7 4495.1 4496.0 4497.1 4543.0 4548.0 4552.4 4554.4 4555.1 5275.7 5283.8 5288.9 5293.4 5295.8 6252.2 6430.1 6439.8 6447.2 6467.3 1582.0 1582.6 1585.8 1596.0 1599.5 1056.4 1071.9 1072.2 1073.2 1087.9 6194.4 6195.3 6206.8 6208.0 6212.1 6606.5 6608.7 6611.2 6612.0 6615.9 6066.6 6193.3 6193.9 6199.7 6203.8 3542.0 3576.3 3584.7 3585.9 3587.1 6844.9 6859.9 6869.8 6885.5 6887.8 6473.1 6780.4 6782.6 6786.0 6786.8 6401.9 6736.2 6746.0 6832.3 6854.9 6565.5 6570.5 6572.8 6577.9 6581.8 6509.3 6551.0 6581.6 6606.6 6619.8 6756.4 6781.0 6897.8 6899.0 6917.7 4544.0 4544.9 4546.2 4550.4 4551.0 6639.3 6642.7 6644.8 6649.8 6653.7 6308.2 6453.0 6472.5 6478.9 6479.2 6443.1 6455.2 6457.6 6459.3 6463.8 6354.1 6355.5 6360.0 6368.9 6369.1 6317.7 6460.9 6473.0 6473.4 6480.5 6304.2 6314.4 6316.8 6320.4 6325.7 19:13:57 INFO - 2015-11-19 19:13:57,920 DEBUG : BROWSER_OUTPUT: 19:13:57 INFO - 2015-11-19 19:13:57,920 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13623.44 Median:47613.26 stddev:15827.92 (33.2%) stddev-sans-first:15843.16 19:13:57 INFO - 2015-11-19 19:13:57,921 DEBUG : BROWSER_OUTPUT: Values: 829.2 832.4 837.7 837.7 839.8 13595.7 13728.5 13754.3 13756.3 13778.1 13061.7 14680.5 14906.6 14940.5 14941.3 820.9 828.0 831.9 832.2 832.9 689.1 692.9 805.9 817.2 824.2 845.1 850.5 857.3 857.9 858.0 715.6 722.6 845.4 846.9 847.2 614.0 629.0 629.4 629.6 630.5 13597.4 13684.9 13715.7 13736.8 13758.7 11990.6 12419.4 12461.5 12590.2 13344.5 10051.1 10239.8 10282.4 10290.2 10466.3 3590.6 3632.4 3684.4 3730.1 3752.9 47737.5 48580.5 48626.0 48724.8 48869.3 10301.3 10308.1 10340.6 10419.2 10461.7 18443.7 18444.8 18473.2 18486.7 18494.6 7404.6 7496.5 7612.9 7764.3 7958.8 4071.9 4089.1 4146.3 4147.0 4225.2 3003.8 3028.6 3030.7 3062.7 3067.8 552.3 558.2 567.6 569.9 578.5 584.8 598.3 605.2 622.2 634.2 7803.3 7989.6 8033.7 8067.3 8178.1 587.3 621.2 636.4 638.0 666.4 3629.6 3819.8 3850.6 3878.3 3882.9 50423.2 55305.7 55657.0 55883.4 55913.7 50087.2 50574.0 50716.2 51027.5 51081.0 47175.3 47554.0 47672.5 47786.5 48299.8 8084.8 8289.7 8352.5 8451.8 8463.0 33645.8 34243.0 34431.8 34452.3 34556.8 41227.0 41452.8 46747.3 47088.3 47110.1 9947.8 10292.9 10515.9 10951.4 11157.9 10670.2 10990.0 11051.9 11169.7 11180.8 10062.8 10585.6 10590.9 10842.9 10869.0 10323.3 10819.4 10888.8 10891.2 10951.7 10302.6 10353.2 10537.7 10673.7 10822.6 9130.2 9191.3 9254.3 9456.9 9480.7 7942.6 8305.0 8337.3 8415.7 8836.3 19:13:57 INFO - 2015-11-19 19:13:57,921 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:13:57 INFO - 2015-11-19 19:13:57,921 DEBUG : BROWSER_OUTPUT: 19:13:58 INFO - 2015-11-19 19:13:58,685 INFO : Browser exited with error code: 0 19:13:58 INFO - 2015-11-19 19:13:58,701 INFO : Completed test dromaeo_css (00:18:35) 19:13:58 INFO - 2015-11-19 19:13:58,701 INFO : Starting test kraken 19:13:58 INFO - 2015-11-19 19:13:58,701 DEBUG : operating with platform_type : linux_ 19:13:58 INFO - 2015-11-19 19:13:58,702 INFO : Initialising browser for kraken test... 19:13:58 INFO - 2015-11-19 19:13:58,708 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXWAZ69/profile http://localhost:39511/getInfo.html 19:14:02 INFO - 2015-11-19 19:14:02,970 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:14:02 INFO - 2015-11-19 19:14:02,970 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:14:02 INFO - 2015-11-19 19:14:02,970 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:14:02 INFO - 2015-11-19 19:14:02,970 DEBUG : BROWSER_OUTPUT: __metrics 19:14:03 INFO - 2015-11-19 19:14:03,075 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:14:04 INFO - 2015-11-19 19:14:04,528 INFO : Browser initialized. 19:14:04 INFO - 2015-11-19 19:14:04,528 INFO : Running cycle 1/1 for kraken test... 19:14:04 INFO - 2015-11-19 19:14:04,528 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXWAZ69/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:14:16 INFO - 2015-11-19 19:14:16,415 DEBUG : BROWSER_OUTPUT: RSS: Main: 141934592 19:14:16 INFO - 2015-11-19 19:14:16,415 DEBUG : BROWSER_OUTPUT: 19:14:18 INFO - 2015-11-19 19:14:18,882 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39511/tests/kraken/driver.html?testName=audio-beat-detection) 19:14:19 INFO - 2015-11-19 19:14:19,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 177799168 19:14:19 INFO - 2015-11-19 19:14:19,469 DEBUG : BROWSER_OUTPUT: 19:14:22 INFO - 2015-11-19 19:14:22,099 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39511/tests/kraken/driver.html?testName=audio-dft) 19:14:22 INFO - 2015-11-19 19:14:22,679 DEBUG : BROWSER_OUTPUT: RSS: Main: 167526400 19:14:22 INFO - 2015-11-19 19:14:22,680 DEBUG : BROWSER_OUTPUT: 19:14:25 INFO - 2015-11-19 19:14:25,620 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39511/tests/kraken/driver.html?testName=audio-fft) 19:14:26 INFO - 2015-11-19 19:14:26,173 DEBUG : BROWSER_OUTPUT: RSS: Main: 151109632 19:14:26 INFO - 2015-11-19 19:14:26,173 DEBUG : BROWSER_OUTPUT: 19:14:28 INFO - 2015-11-19 19:14:28,150 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39511/tests/kraken/driver.html?testName=audio-oscillator) 19:14:28 INFO - 2015-11-19 19:14:28,716 DEBUG : BROWSER_OUTPUT: RSS: Main: 153612288 19:14:28 INFO - 2015-11-19 19:14:28,716 DEBUG : BROWSER_OUTPUT: 19:14:30 INFO - 2015-11-19 19:14:30,910 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39511/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:14:31 INFO - 2015-11-19 19:14:31,471 DEBUG : BROWSER_OUTPUT: RSS: Main: 155324416 19:14:31 INFO - 2015-11-19 19:14:31,471 DEBUG : BROWSER_OUTPUT: 19:14:34 INFO - 2015-11-19 19:14:34,369 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39511/tests/kraken/driver.html?testName=imaging-darkroom) 19:14:34 INFO - 2015-11-19 19:14:34,951 DEBUG : BROWSER_OUTPUT: RSS: Main: 156344320 19:14:34 INFO - 2015-11-19 19:14:34,951 DEBUG : BROWSER_OUTPUT: 19:14:38 INFO - 2015-11-19 19:14:38,382 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39511/tests/kraken/driver.html?testName=imaging-desaturate) 19:14:38 INFO - 2015-11-19 19:14:38,951 DEBUG : BROWSER_OUTPUT: RSS: Main: 156581888 19:14:38 INFO - 2015-11-19 19:14:38,952 DEBUG : BROWSER_OUTPUT: 19:14:41 INFO - 2015-11-19 19:14:41,690 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39511/tests/kraken/driver.html?testName=json-parse-financial) 19:14:42 INFO - 2015-11-19 19:14:42,277 DEBUG : BROWSER_OUTPUT: RSS: Main: 155176960 19:14:42 INFO - 2015-11-19 19:14:42,278 DEBUG : BROWSER_OUTPUT: 19:14:43 INFO - 2015-11-19 19:14:43,874 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39511/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:14:44 INFO - 2015-11-19 19:14:44,439 DEBUG : BROWSER_OUTPUT: RSS: Main: 170434560 19:14:44 INFO - 2015-11-19 19:14:44,439 DEBUG : BROWSER_OUTPUT: 19:14:46 INFO - 2015-11-19 19:14:46,259 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39511/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:14:46 INFO - 2015-11-19 19:14:46,860 DEBUG : BROWSER_OUTPUT: RSS: Main: 164167680 19:14:46 INFO - 2015-11-19 19:14:46,860 DEBUG : BROWSER_OUTPUT: 19:14:48 INFO - 2015-11-19 19:14:48,940 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39511/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:14:49 INFO - 2015-11-19 19:14:49,513 DEBUG : BROWSER_OUTPUT: RSS: Main: 173965312 19:14:49 INFO - 2015-11-19 19:14:49,513 DEBUG : BROWSER_OUTPUT: 19:14:51 INFO - 2015-11-19 19:14:51,949 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39511/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:14:52 INFO - 2015-11-19 19:14:52,532 DEBUG : BROWSER_OUTPUT: RSS: Main: 181837824 19:14:52 INFO - 2015-11-19 19:14:52,532 DEBUG : BROWSER_OUTPUT: 19:14:55 INFO - 2015-11-19 19:14:55,149 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39511/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:14:55 INFO - 2015-11-19 19:14:55,722 DEBUG : BROWSER_OUTPUT: RSS: Main: 179798016 19:14:55 INFO - 2015-11-19 19:14:55,722 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,400 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39511/tests/kraken/driver.html?testName=ai-astar) 19:14:57 INFO - 2015-11-19 19:14:57,658 DEBUG : BROWSER_OUTPUT: RSS: Main: 238338048 19:14:57 INFO - 2015-11-19 19:14:57,658 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,658 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:14:57 INFO - 2015-11-19 19:14:57,658 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:14:57 INFO - 2015-11-19 19:14:57,659 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:14:57 INFO - 2015-11-19 19:14:57,659 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:14:57 INFO - 2015-11-19 19:14:57,659 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;111;104;109;111;111;100;109;109;108 19:14:57 INFO - 2015-11-19 19:14:57,659 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;137;149;169;130;152;152;127;168;126 19:14:57 INFO - 2015-11-19 19:14:57,659 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;162;164;163;161;162;161;163;165;163 19:14:57 INFO - 2015-11-19 19:14:57,659 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;74;88;94;73;73;73;93;93;72 19:14:57 INFO - 2015-11-19 19:14:57,659 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;92;135;91;91;93;134;99;91;137 19:14:57 INFO - 2015-11-19 19:14:57,660 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;109;109;109;108;109;109 19:14:57 INFO - 2015-11-19 19:14:57,660 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;147;146;146;146;145;145;145;145 19:14:57 INFO - 2015-11-19 19:14:57,660 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;95;96;95;95;95;96;95 19:14:57 INFO - 2015-11-19 19:14:57,660 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;66;63;64;63;63;66;61;71;60;61 19:14:57 INFO - 2015-11-19 19:14:57,660 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;68;70;69;69;71;68;70;70;70 19:14:57 INFO - 2015-11-19 19:14:57,660 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;89;86;85;86;86;85;86;84;93;85 19:14:57 INFO - 2015-11-19 19:14:57,661 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;117;115;121;124;123;119;120;126;132 19:14:57 INFO - 2015-11-19 19:14:57,661 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;152;142;157;154;159;147;165;152;150;157 19:14:57 INFO - 2015-11-19 19:14:57,661 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;71;69;70;70;70;68;68;69;70 19:14:57 INFO - 2015-11-19 19:14:57,661 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:14:57 INFO - 2015-11-19 19:14:57,661 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:14:57 INFO - 2015-11-19 19:14:57,661 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,945 19:14:57 INFO - 2015-11-19 19:14:57,661 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:14:57 INFO - 2015-11-19 19:14:57,661 DEBUG : BROWSER_OUTPUT: __startTimestamp1447989297652__endTimestamp 19:14:57 INFO - 2015-11-19 19:14:57,661 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,661 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: Number of tests: 14 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.20 Median:109.50 stddev:3.55 (3.2%) stddev-sans-first:3.71 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 104.0 109.0 111.0 111.0 100.0 109.0 109.0 108.0 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:146.10 Median:151.50 stddev:15.69 (10.4%) stddev-sans-first:16.55 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: Values: 151.0 137.0 149.0 169.0 130.0 152.0 152.0 127.0 168.0 126.0 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.25 (0.8%) stddev-sans-first:1.32 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: Values: 163.0 162.0 164.0 163.0 161.0 162.0 161.0 163.0 165.0 163.0 19:14:57 INFO - 2015-11-19 19:14:57,662 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,663 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.60 Median:81.00 stddev:9.95 (12.3%) stddev-sans-first:10.16 19:14:57 INFO - 2015-11-19 19:14:57,663 DEBUG : BROWSER_OUTPUT: Values: 73.0 74.0 88.0 94.0 73.0 73.0 73.0 93.0 93.0 72.0 19:14:57 INFO - 2015-11-19 19:14:57,663 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,663 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.30 Median:91.00 stddev:20.89 (23.0%) stddev-sans-first:21.41 19:14:57 INFO - 2015-11-19 19:14:57,663 DEBUG : BROWSER_OUTPUT: Values: 90.0 92.0 135.0 91.0 91.0 93.0 134.0 99.0 91.0 137.0 19:14:57 INFO - 2015-11-19 19:14:57,663 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,663 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 19:14:57 INFO - 2015-11-19 19:14:57,663 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 109.0 109.0 109.0 108.0 109.0 109.0 19:14:57 INFO - 2015-11-19 19:14:57,663 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,663 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 19:14:57 INFO - 2015-11-19 19:14:57,664 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 147.0 146.0 146.0 146.0 145.0 145.0 145.0 145.0 19:14:57 INFO - 2015-11-19 19:14:57,664 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,664 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 19:14:57 INFO - 2015-11-19 19:14:57,664 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 19:14:57 INFO - 2015-11-19 19:14:57,664 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,664 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:63.80 Median:63.50 stddev:3.22 (5.1%) stddev-sans-first:3.32 19:14:57 INFO - 2015-11-19 19:14:57,664 DEBUG : BROWSER_OUTPUT: Values: 66.0 63.0 64.0 63.0 63.0 66.0 61.0 71.0 60.0 61.0 19:14:57 INFO - 2015-11-19 19:14:57,664 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:70.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: Values: 69.0 68.0 70.0 69.0 69.0 71.0 68.0 70.0 70.0 70.0 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:86.00 stddev:2.64 (3.1%) stddev-sans-first:2.64 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: Values: 89.0 86.0 85.0 86.0 86.0 85.0 86.0 84.0 93.0 85.0 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.30 Median:122.00 stddev:5.17 (4.2%) stddev-sans-first:5.11 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: Values: 116.0 117.0 115.0 121.0 124.0 123.0 119.0 120.0 126.0 132.0 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.50 Median:155.50 stddev:6.49 (4.2%) stddev-sans-first:6.86 19:14:57 INFO - 2015-11-19 19:14:57,665 DEBUG : BROWSER_OUTPUT: Values: 152.0 142.0 157.0 154.0 159.0 147.0 165.0 152.0 150.0 157.0 19:14:57 INFO - 2015-11-19 19:14:57,666 DEBUG : BROWSER_OUTPUT: 19:14:57 INFO - 2015-11-19 19:14:57,666 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:1.07 (1.5%) stddev-sans-first:1.01 19:14:57 INFO - 2015-11-19 19:14:57,666 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 69.0 70.0 70.0 70.0 68.0 68.0 69.0 70.0 19:14:57 INFO - 2015-11-19 19:14:57,666 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:14:57 INFO - 2015-11-19 19:14:57,666 DEBUG : BROWSER_OUTPUT: 19:14:58 INFO - 2015-11-19 19:14:58,585 INFO : Browser exited with error code: 0 19:14:58 INFO - 2015-11-19 19:14:58,636 INFO : Completed test kraken (00:00:59) 19:14:58 INFO - 2015-11-19 19:14:58,636 INFO : Starting test v8_7 19:14:58 INFO - 2015-11-19 19:14:58,636 DEBUG : operating with platform_type : linux_ 19:14:58 INFO - 2015-11-19 19:14:58,637 INFO : Initialising browser for v8_7 test... 19:14:58 INFO - 2015-11-19 19:14:58,643 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpklBAk2/profile http://localhost:39511/getInfo.html 19:15:02 INFO - 2015-11-19 19:15:02,805 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:15:02 INFO - 2015-11-19 19:15:02,806 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:15:02 INFO - 2015-11-19 19:15:02,806 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:15:02 INFO - 2015-11-19 19:15:02,806 DEBUG : BROWSER_OUTPUT: __metrics 19:15:02 INFO - 2015-11-19 19:15:02,975 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:15:04 INFO - 2015-11-19 19:15:04,363 INFO : Browser initialized. 19:15:04 INFO - 2015-11-19 19:15:04,364 INFO : Running cycle 1/1 for v8_7 test... 19:15:04 INFO - 2015-11-19 19:15:04,364 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpklBAk2/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:15:16 INFO - 2015-11-19 19:15:16,060 DEBUG : BROWSER_OUTPUT: RSS: Main: 141561856 19:15:16 INFO - 2015-11-19 19:15:16,060 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,547 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39511/tests/v8_7/run.html (next: http://localhost:39511/tests/v8_7/run.html) 19:15:37 INFO - 2015-11-19 19:15:37,797 DEBUG : BROWSER_OUTPUT: RSS: Main: 622284800 19:15:37 INFO - 2015-11-19 19:15:37,797 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,798 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:15:37 INFO - 2015-11-19 19:15:37,799 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:15:37 INFO - 2015-11-19 19:15:37,799 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:15:37 INFO - 2015-11-19 19:15:37,799 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:15:37 INFO - 2015-11-19 19:15:37,799 DEBUG : BROWSER_OUTPUT: |0;Richards;163.18537859007833 19:15:37 INFO - 2015-11-19 19:15:37,799 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;195.1219512195122 19:15:37 INFO - 2015-11-19 19:15:37,799 DEBUG : BROWSER_OUTPUT: |2;Encrypt;286.94404591104734 19:15:37 INFO - 2015-11-19 19:15:37,799 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5086.294416243655 19:15:37 INFO - 2015-11-19 19:15:37,799 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1132.5028312570782 19:15:37 INFO - 2015-11-19 19:15:37,800 DEBUG : BROWSER_OUTPUT: |5;Earley;788.6435331230284 19:15:37 INFO - 2015-11-19 19:15:37,800 DEBUG : BROWSER_OUTPUT: |6;Boyer;12195.121951219513 19:15:37 INFO - 2015-11-19 19:15:37,800 DEBUG : BROWSER_OUTPUT: |7;RegExp;25550 19:15:37 INFO - 2015-11-19 19:15:37,800 DEBUG : BROWSER_OUTPUT: |8;Splay;646.1932181701856 19:15:37 INFO - 2015-11-19 19:15:37,800 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 19:15:37 INFO - 2015-11-19 19:15:37,800 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:15:37 INFO - 2015-11-19 19:15:37,800 DEBUG : BROWSER_OUTPUT: __startTimestamp1447989337798__endTimestamp 19:15:37 INFO - 2015-11-19 19:15:37,801 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,801 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:15:37 INFO - 2015-11-19 19:15:37,801 DEBUG : BROWSER_OUTPUT: Number of tests: 10 19:15:37 INFO - 2015-11-19 19:15:37,801 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,801 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.19 Median:163.19 stddev:0.00 (0.0%) 19:15:37 INFO - 2015-11-19 19:15:37,801 DEBUG : BROWSER_OUTPUT: Values: 163.2 19:15:37 INFO - 2015-11-19 19:15:37,801 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,801 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:195.12 Median:195.12 stddev:0.00 (0.0%) 19:15:37 INFO - 2015-11-19 19:15:37,801 DEBUG : BROWSER_OUTPUT: Values: 195.1 19:15:37 INFO - 2015-11-19 19:15:37,802 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,802 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:286.94 Median:286.94 stddev:0.00 (0.0%) 19:15:37 INFO - 2015-11-19 19:15:37,802 DEBUG : BROWSER_OUTPUT: Values: 286.9 19:15:37 INFO - 2015-11-19 19:15:37,802 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,802 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5086.29 Median:5086.29 stddev:0.00 (0.0%) 19:15:37 INFO - 2015-11-19 19:15:37,802 DEBUG : BROWSER_OUTPUT: Values: 5086.3 19:15:37 INFO - 2015-11-19 19:15:37,802 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,802 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1132.50 Median:1132.50 stddev:0.00 (0.0%) 19:15:37 INFO - 2015-11-19 19:15:37,802 DEBUG : BROWSER_OUTPUT: Values: 1132.5 19:15:37 INFO - 2015-11-19 19:15:37,802 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,803 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:788.64 Median:788.64 stddev:0.00 (0.0%) 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: Values: 788.6 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12195.12 Median:12195.12 stddev:0.00 (0.0%) 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: Values: 12195.1 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25550.00 Median:25550.00 stddev:0.00 (0.0%) 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: Values: 25550.0 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:646.19 Median:646.19 stddev:0.00 (0.0%) 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: Values: 646.2 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: Values: 4830.9 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:15:37 INFO - 2015-11-19 19:15:37,804 DEBUG : BROWSER_OUTPUT: 19:15:38 INFO - 2015-11-19 19:15:38,689 INFO : Browser exited with error code: 0 19:15:38 INFO - 2015-11-19 19:15:38,703 INFO : Completed test v8_7 (00:00:40) 19:15:38 INFO - 2015-11-19 19:15:38,742 INFO : Completed test suite (00:20:15) 19:15:38 INFO - 2015-11-19 19:15:38,742 DEBUG : Working with test: dromaeo_css 19:15:38 INFO - 2015-11-19 19:15:38,743 DEBUG : Generating results file: dromaeo_css 19:15:38 INFO - 2015-11-19 19:15:38,744 DEBUG : Working with test: kraken 19:15:38 INFO - 2015-11-19 19:15:38,744 DEBUG : Generating results file: kraken 19:15:38 INFO - 2015-11-19 19:15:38,744 INFO : javascript benchmark 19:15:38 INFO - 2015-11-19 19:15:38,744 DEBUG : Working with test: v8_7 19:15:38 INFO - 2015-11-19 19:15:38,744 DEBUG : Generating results file: v8_7 19:15:38 INFO - 2015-11-19 19:15:38,745 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:15:38 INFO - 2015-11-19 19:15:38,797 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 19:15:38 INFO - 2015-11-19 19:15:38,797 DEBUG : process_Request line: dromaeo_css 6939.94 graph.html#tests=[[72,1,35]] 19:15:38 INFO - 2015-11-19 19:15:38,797 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:15:38 INFO - 2015-11-19 19:15:38,842 DEBUG : process_Request line: kraken 1517.10 graph.html#tests=[[232,1,35]] 19:15:38 INFO - 2015-11-19 19:15:38,842 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:15:38 INFO - 2015-11-19 19:15:38,875 DEBUG : process_Request line: v8_7 20836.83 graph.html#tests=[[230,1,35]] 19:15:38 INFO - 2015-11-19 19:15:38,877 INFO : javascript benchmark 19:15:38 INFO - 2015-11-19 19:15:38,880 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3195.217171792911, 3237.4981877808946, 3270.967290327096, 3283.786553873998, 3302.0754188827127, 5305.283786688796, 5405.729713514318, 5412.485813847683, 5424.810131645393, 5435.701036442994, 5339.0, 5415.241866138734, 5443.374011988622, 5453.127499600065, 5457.5633949284065, 4911.361523002005, 4919.852404427855, 4941.258811178316, 4945.035718034985, 5006.299118123466, 4852.902941941159, 5007.14878470659, 5083.6441449098575, 5097.617678674096, 5150.9183071554835, 4823.541763532458, 4906.067847109038, 4920.458749537548, 4928.6303527235605, 4957.281194226835, 4907.067657145131, 4918.065567542156, 4918.163912134945, 4925.827596034122, 4964.205727083686, 4901.950980490185, 4909.189983652693, 4914.410270767495, 4936.432310284327, 4982.750862456863, 5077.187649976023, 5173.198154286091, 5198.324217851693, 5321.281626980385, 5352.464753524655, 5835.854103647401, 5848.181254624356, 5855.3851845556455, 5858.355580886099, 5862.032764593837, 5750.108733146369, 5879.353271140171, 5892.292924849003, 5893.823185304448, 5899.793507227269, 5387.676739395688, 5441.347038355423, 5445.83662490122, 5449.427810079964, 5503.972480137573, 5870.855183239313, 5883.0, 5921.970390148108, 6017.969910150421, 6023.789167379121, 5665.830025099253, 5785.652860828363, 5789.131630255411, 5789.768409263666, 5799.88400231993, 5781.53747700183, 5787.594868359174, 5866.6186697864505, 5870.119482077723, 5923.644581325134, 5801.245838041028, 5888.499477544457, 5889.764409423661, 5893.14549390336, 5896.587238893322, 5677.914831277534, 5816.854578635483, 5897.793577224862, 5900.911486327708, 5905.0, 4485.394471746337, 4488.371627972087, 4491.528389519053, 4516.593506584423, 4543.522930092358, 3982.7012974027066, 3999.6600288975183, 4001.819918103634, 4005.679545636342, 4050.1697152083752, 5398.406175320712, 5503.559715222773, 5516.503514683698, 5598.944010559842, 5603.7478313475995, 5773.278340207474, 5809.68046757424, 5852.648841069465, 5918.822435326947, 5921.259842519685, 5498.0, 5574.665520068727, 5579.246801681802, 5606.21512988182, 5649.322081350264, 5094.465081166498, 5137.255098010767, 5140.897182056338, 5141.9742901286, 5179.792808287626, 5922.674252916131, 6044.667543285162, 6063.969680151571, 6115.602485838406, 6125.050617154348, 5927.881442371215, 5931.228940237742, 5936.703164841654, 5941.643501389931, 5975.16347711312, 6067.089936509559, 6113.5414843887775, 6132.63204207749, 6136.570440069152, 6138.5396095292135, 5681.545476361964, 5735.827925162252, 5746.367899530965, 5844.590878638454, 5877.23595932526, 5874.324452687995, 5878.706064696834, 5879.0, 5884.882302353843, 5897.9115313271195, 5758.712064396847, 5760.596758226884, 5765.94234057654, 5766.077427611562, 5769.653820770767, 5634.380218176079, 5717.285339332583, 5753.798617048551, 5762.0, 5793.420657934173, 5576.442355764553, 5589.413111623221, 5606.85982850432, 5608.46719561641, 5625.943740562706, 5678.460546248265, 5719.370869204467, 5763.827085187451, 5771.5671324649975, 5808.244928159313, 5377.220303056113, 5586.804461843816, 5618.52242559387, 5644.435556444323, 5675.347335056521], "unit": "ms", "name": "dojo.html", "value": 5392.841198906787}, {"lowerIsBetter": false, "replicates": [561.9740834292376, 591.4233622218337, 595.1043679262709, 598.7215944585766, 603.333316685063, 30302.696973030263, 38999.41500877486, 39319.41020884689, 39340.803295983555, 39457.21085578287, 41359.17281654365, 41835.16329673405, 41934.0, 41946.0, 41982.37026444606, 7564.054493188351, 7648.694052237904, 8699.086595907449, 8843.115688431137, 8877.911220887772, 10045.849312260321, 10173.949130254376, 10244.180465562737, 10254.641087561937, 10255.282130250886, 9909.207263418944, 10063.396196228214, 10077.647282345119, 10080.395176289447, 10081.143102836231, 7883.802904927394, 8045.115037345888, 8052.637631306547, 8061.879071813927, 10227.0, 10368.79262414755, 10703.83944240837, 10736.979986901231, 10737.892621073768, 10744.14046876248, 38670.39279304513, 39008.60991390106, 39025.60974390248, 39065.60934390676, 39137.21725565473, 3056.679048699899, 3137.905862824119, 3150.2754366495833, 3218.951715724266, 3617.6201498842506, 29791.255218619706, 30006.0, 30357.24106897323, 30485.39029219403, 31193.0, 3920.2355540669582, 3938.8030598469963, 3964.603539646041, 4034.1931613677384, 4101.507819061701, 249691.0, 249918.7504062468, 250509.21180911505, 251174.0, 251658.0, 54656.72671636696, 55161.72419137959, 55689.16466253009, 57228.14157787636, 58283.0, 51797.223041654404, 51838.740806295726, 51942.74028629833, 52182.0, 52265.08011899123, 14922.179280139479, 15029.173395463136, 15041.172735499653, 15120.621984450478, 15137.0, 12053.63839084829, 12082.516699331929, 12090.818637720442, 12126.151169418232, 12224.755504889852, 18336.90831545834, 18521.35175268859, 18538.629227415375, 18547.16537755804, 18550.628987420176, 7420.406367490587, 7452.701891924262, 7658.290151556753, 7668.194839541879, 7676.923230767732, 955.861400096982, 962.6053318139669, 991.306085739985, 1010.2625083688948, 1039.028508344685, 27430.5885411719, 27452.862735686194, 27600.0, 27884.721152788214, 28040.43919121606, 973.1290495006929, 974.551706215149, 1043.7912417516527, 1050.9312806604732, 1117.234694234452, 13439.865601344058, 14642.341094650767, 14731.558053258419, 14899.255037248095, 14928.402863885323, 297840.0, 299341.0, 300138.0, 300266.0, 300626.0, 49122.0, 49168.50831491711, 49272.0, 49296.75351623219, 49301.506984930405, 1296.3906963727034, 1315.2437348524445, 1317.157019507516, 1319.9934000329936, 1348.4740951029107, 26396.208113756617, 27160.91288461145, 27343.86328068347, 27499.31251718723, 27576.58635120515, 40205.0, 43648.34527482027, 43915.34126988034, 44055.559444405146, 44075.11849763051, 193836.0, 275305.0, 283829.0, 283867.0, 283890.0, 26813.86593067022, 27353.0, 27362.5895611562, 27368.0, 27368.452630947668, 39124.0, 39851.80074099611, 40329.193416130925, 40685.389719153645, 41093.58906410898, 33909.95520705466, 34213.65786342204, 34571.30857382889, 34751.21990240825, 35126.0, 33609.15977100592, 34525.26147581416, 34834.65165348314, 34901.0, 34913.65086349104, 33640.3271934555, 34427.311453770286, 34444.48333275053, 34473.482897756054, 34496.0, 28885.711142889144, 31298.687013129576, 31920.0, 31937.0, 32022.0, 23054.769452305933, 23726.40683982845, 24374.078060837848, 24488.75511244865, 24603.753962460145], "unit": "ms", "name": "ext.html", "value": 18049.22173598154}, {"lowerIsBetter": false, "replicates": [356.8322888242525, 357.46915829992463, 359.60084306419867, 359.76255671256973, 361.4596178712825, 14096.506622268224, 14275.643108922282, 16503.0, 16726.916365418172, 17320.393786217486, 16131.83868161318, 16537.173141342915, 16679.24943377548, 17236.913815430904, 17299.91350043248, 5424.647397919122, 5562.972185139069, 5566.86082847928, 5573.888522229553, 5579.13523403873, 4026.396040593905, 4160.687948403866, 5537.390887002426, 5570.136628822539, 5947.078202878561, 6206.503479721611, 6219.844503887393, 6262.937370626304, 6290.056491526285, 6293.181886354768, 6012.0, 6094.96952515239, 6104.877902441971, 6108.725107370156, 6114.388561143895, 5375.11310633745, 5378.435264297271, 5379.300690910196, 5380.381256155553, 5407.351117865842, 19808.40574782759, 19813.603727925503, 20193.293234736862, 20565.89717051405, 20972.0, 2076.9896150519303, 2157.331227319536, 2162.1459523488293, 2202.119152339077, 2317.7566355532595, 12787.0, 12831.486740530368, 13070.346482675828, 13339.399727012307, 13352.599422017354, 1718.6820438218845, 1733.5926057376507, 1736.3749050341867, 1738.0379017903956, 1846.1323178106268, 56045.0, 57387.71306143443, 57748.0, 58135.0, 59224.40775592232, 9623.903760962441, 9779.266555008402, 9903.702888913343, 9907.0, 9942.0, 12715.109942304134, 12724.681882953, 12738.936305318413, 12910.321658184375, 12928.870711292766, 7413.814654633569, 7555.395568354518, 7582.0, 7664.233576642291, 7728.536287822636, 7322.1945585985495, 7421.109466864011, 7422.740204092831, 7425.443091768139, 7459.962700186465, 5882.470577648032, 5943.227380440515, 5945.494632956247, 5955.970220148958, 6001.879962400728, 1657.2459530913407, 1721.3716993297355, 1732.644807814395, 1741.277369891481, 1774.1838754173225, 902.4044130873723, 913.8903331600251, 1024.3137098144261, 1066.9786604268027, 1067.9199060070525, 15963.60090997711, 16707.997520148587, 16926.40757573479, 17146.314147434212, 17524.912375438296, 799.428408687791, 838.576518857985, 907.4555266839975, 909.4679612426804, 910.7768596693852, 9322.627094916263, 9908.20734341251, 9913.851292230622, 10145.08694217524, 10261.9486902565, 507442.0, 511746.0, 543020.0, 548003.0, 559137.0, 74408.62795686095, 74850.0, 77613.61193193997, 77926.44147117026, 79089.0, 1141.57191053355, 1161.2103769436694, 1163.8545181852437, 1178.5757127434117, 1179.1922533064878, 15061.77407338869, 15272.0, 15468.5359439217, 15476.767848482057, 15701.685966280838, 33746.15634609154, 34168.14579635529, 34475.827620862736, 34675.47986780251, 35478.75824346136, 44053.0, 44351.556484436034, 45477.772611138054, 47000.29499557441, 47719.522804772896, 8953.731388058368, 9731.0, 9733.756656083655, 9734.513274336396, 9748.415095094317, 16402.425915092914, 17048.33842895134, 17110.74333884968, 17277.222524986657, 17801.376951806626, 14400.42398304056, 14473.4934277304, 14578.271086445848, 14586.34361453716, 14629.195394253418, 14118.2940852959, 14268.357923893658, 14366.425342986582, 14368.784468232776, 14401.135931844123, 11944.76110477803, 13979.44082236699, 14404.0, 14821.777673334693, 14921.477748278758, 8005.679772809255, 8247.917520824714, 8291.08798032228, 8722.215000649718, 8989.865152022594, 13821.516246931309, 13863.930680346533, 14041.0, 14230.501932432315, 14404.0], "unit": "ms", "name": "jquery.html", "value": 8997.851213944426}, {"lowerIsBetter": false, "replicates": [1528.847115288472, 1613.8063432388115, 1634.7793047938524, 1708.2483707168844, 1764.1884733022816, 6711.966440167798, 7051.612161331132, 7349.853002939938, 7356.926430735691, 7360.300771426712, 7190.388816950565, 7314.5976971266555, 7322.63386830659, 7332.706691732337, 7413.073365829271, 1940.43727319078, 1983.1373352591597, 1984.7519060117486, 2235.9888200558976, 2261.2989973108306, 2246.5731511012855, 2301.631738921773, 2306.5963456395148, 2307.630779075348, 2311.399036250579, 2095.905684244213, 2129.861558998668, 2132.115172203534, 2132.605467988419, 2132.76539580646, 1928.1997970842083, 1971.5071232191951, 2148.495103650641, 2165.6859755335545, 2217.3015500117413, 2186.814120799734, 2228.2869481765842, 2229.9888500557395, 2233.832462565314, 2235.3294011796397, 7557.130929943072, 7605.353544948686, 7619.733309334202, 7632.580208088553, 7640.5797681127515, 4494.662900282492, 4554.567316104964, 4568.0, 4580.312953057068, 4590.770461476946, 3061.8928337508305, 3077.353755711303, 3101.581286526313, 3106.673799251091, 3178.952315715266, 1060.4929051530994, 1072.6889202131451, 1094.9178811589163, 1158.206628459508, 1162.3548930343657, 9900.702978910573, 10064.094231519199, 10077.59689612422, 10090.243231757575, 10091.798164036678, 3963.801809909493, 3977.9204415911518, 4000.079981604247, 4060.634542891154, 4062.106336605942, 8055.63749631268, 8089.150639182801, 8097.919020809834, 8098.190180981855, 8100.756977290691, 4511.503734589192, 4534.841280555164, 4545.431821022372, 4548.1813273610405, 4555.180067587799, 4237.618614324726, 4238.936415953764, 4241.575842415796, 4243.448351714257, 4251.0, 3387.6951074403423, 3416.2313479467416, 3450.6721861423125, 3472.3576138414473, 3478.930421391558, 1063.648995831374, 1073.0825144501496, 1073.5276478349501, 1081.864766904137, 1084.6583326252205, 1310.9803352949714, 1325.9734805304033, 1333.0735139078247, 1347.1916849890183, 1351.2838195756265, 6741.629210393377, 6989.89515157273, 7350.889736653954, 7368.8894666580045, 7378.667959941816, 594.1829983772313, 595.3540408656656, 599.235974132984, 614.0451597765517, 614.7540983606503, 5556.499915007668, 5613.46672066153, 5640.407757185519, 5640.717964101778, 5658.971705141448, 8844.646214151362, 9031.683891063782, 9093.136152065543, 9102.362834601514, 9139.680111196076, 8939.597718102701, 8997.460152390877, 9031.36780425377, 9039.0, 9044.909550904538, 6878.896816547755, 7101.538400003983, 7114.0, 7115.395191408788, 7187.5687458752645, 6835.350641689031, 6847.897281540681, 6891.655417229018, 6896.241413444617, 6897.068895699016, 7831.608419579112, 7989.161138080418, 8059.274665280153, 8077.0, 8196.83606327882, 8214.753557393287, 8326.50040997542, 8343.99872015362, 8345.082040975609, 8347.95826020866, 6304.117423560797, 6486.0919471275, 6490.286068532363, 6491.805245842632, 6516.0551719999385, 7334.119905611361, 7389.445791565761, 7395.852082958419, 7396.704131834882, 7404.740834070782, 6838.281980391987, 6914.965425172842, 6931.202911665223, 7066.646667666493, 7092.893606595802, 6726.932730672631, 6737.157855268091, 6779.72881084751, 6834.487413443912, 6868.072810170563, 6176.598521096114, 6342.175517182921, 6497.772577959749, 6866.34769696878, 6894.13823272091, 6185.257769067741, 6360.0, 6360.045993101072, 6364.204474440695, 6370.980643097082, 6420.6147631142285, 6433.292337842927, 6435.935640643534, 6470.38531339522, 6494.0258961156205], "unit": "ms", "name": "mootools.html", "value": 4290.183125560169}, {"lowerIsBetter": false, "replicates": [311.4098782806582, 329.20496999745615, 333.5397151930335, 338.6004514672685, 338.7831787655899, 5972.9402705972925, 6033.7284822183, 6044.123602077707, 6045.972184728601, 6079.8176054718315, 5950.672713000783, 5960.612560183585, 5967.791127310545, 5982.820515384535, 5988.131720900477, 4636.6058884994645, 4639.860804175863, 4643.210654188796, 4643.512431194718, 4647.163510568096, 4406.162829062484, 4568.0, 4592.931106033412, 4636.768161591924, 4659.697119687226, 4554.043650833312, 4560.407147070892, 4564.224081906067, 4574.656900732459, 4575.450945886499, 4295.226859165349, 4566.54334566544, 4572.35986961824, 4579.335996280554, 4584.472785629662, 3777.7544459610313, 3843.846246150151, 3934.7245692801516, 3946.1910308387, 3953.5058117735284, 5601.215829783833, 5609.298837645294, 5624.21261023457, 5630.436956304377, 5645.068563686987, 5016.122178618727, 5155.020546096267, 5168.870778230575, 5264.315638966948, 5273.736313184364, 6116.694165291718, 6189.597676151036, 6263.843403914893, 6332.366763323676, 6343.270523889765, 4368.47578290604, 4372.2130016597, 4382.6055654991205, 4388.363687265361, 4398.186335527937, 6462.25684046336, 6526.445252153572, 6603.471722262256, 6616.0, 6622.701978410983, 4462.464504259509, 4470.731756094645, 4495.078508905666, 4496.0, 4497.123061003138, 4543.0, 4547.954520454819, 4552.362669226311, 4554.3851580036935, 4555.06621142665, 5275.683458992396, 5283.784729512234, 5288.92066619001, 5293.417724050351, 5295.841124766263, 6252.218472690914, 6430.131932189094, 6439.774607888701, 6447.194100737408, 6467.32093130224, 1581.9762703559456, 1582.5647946814718, 1585.8017747781528, 1596.0184486540927, 1599.4881637876001, 1056.3609016545033, 1071.9410432426291, 1072.1851392941264, 1073.1843798713035, 1087.8912108789057, 6194.442500174916, 6195.256569211723, 6206.782762603287, 6207.96896015517, 6212.099245609361, 6606.489533977058, 6608.735650574019, 6611.23970743361, 6612.0, 6615.900761488581, 6066.636001839815, 6193.318734939153, 6193.938060619426, 6199.690015499207, 6203.7828675996125, 3541.982290088533, 3576.2847430514003, 3584.6594573515476, 3585.8924232273075, 3587.1390866191928, 6844.8973265401055, 6859.9314006860295, 6869.7595584154315, 6885.483588730765, 6887.827804304832, 6473.126127972758, 6780.38976492118, 6782.593044417252, 6786.049953006583, 6786.796396108125, 6401.871962560723, 6736.191657001191, 6746.021826835179, 6832.282610325944, 6854.931450685529, 6565.507586931095, 6570.507211959027, 6572.849751293409, 6577.901331479936, 6581.80254592363, 6509.283978762238, 6550.967245163743, 6581.638009909501, 6606.570572912745, 6619.801405957829, 6756.391924726799, 6780.966095169492, 6897.793066208022, 6898.999645051339, 6917.654117294014, 4544.00031992961, 4544.931826022546, 4546.227141385906, 4550.3629491871825, 4551.0, 6639.269680335063, 6642.701078451578, 6644.83387915306, 6649.833754155991, 6653.700583473658, 6308.211473565804, 6453.032045193258, 6472.482201423969, 6478.870422591427, 6479.222493300834, 6443.065755465525, 6455.160829092188, 6457.644829534421, 6459.321771213955, 6463.806085817433, 6354.110424540476, 6355.459785918249, 6359.996601070648, 6368.872622547616, 6369.1401660777055, 6317.6841157941, 6460.870782584416, 6473.029045643191, 6473.449756770728, 6480.513961453004, 6304.243490780952, 6314.368563143833, 6316.778912738032, 6320.399562041599, 6325.683715814283], "unit": "ms", "name": "prototype.html", "value": 4801.533805501261}, {"lowerIsBetter": false, "replicates": [829.1832544943234, 832.3549249331769, 837.7277384849925, 837.7486753973808, 839.7984483723908, 13595.728085438286, 13728.519501817438, 13754.312284385791, 13756.312184390765, 13778.104423212497, 13061.67345816355, 14680.486182983597, 14906.627334316647, 14940.47708330206, 14941.252937353116, 820.9343252539783, 827.9751607451786, 831.8627426474625, 832.196929962586, 832.8958880139983, 689.135135405066, 692.92031416387, 805.899262592176, 817.1950628630794, 824.2458150792019, 845.0873057098319, 850.4642075492432, 857.3269983063296, 857.9099194584572, 858.0347109501811, 715.5921124958776, 722.5953466058971, 845.3870943565896, 846.9491830490159, 847.1740053447901, 613.9662318572476, 629.0312918106112, 629.4429429954478, 629.6285191736897, 630.5460068750492, 13597.388117534734, 13684.93157534216, 13715.657108572266, 13736.793948090786, 13758.65603359914, 11990.640280791591, 12419.379031048411, 12461.501539938392, 12590.24458532491, 13344.466221351135, 10051.095401413835, 10239.84640230397, 10282.383057016528, 10290.177014952184, 10466.267361284714, 3590.551181102362, 3632.3643362411735, 3684.392075307571, 3730.1047748540427, 3752.9061773455883, 47737.52262477366, 48580.514194857955, 48626.0, 48724.75637621789, 48869.266960995614, 10301.330413523247, 10308.12380947628, 10340.638077667396, 10419.218558607983, 10461.738456538495, 18443.72334414985, 18444.81555184431, 18473.1687074082, 18486.722699159523, 18494.630107397777, 7404.629768511661, 7496.475246732785, 7612.885806712904, 7764.301212890867, 7958.84082318362, 4071.91856162881, 4089.1412803311637, 4146.295129827937, 4146.979265103715, 4225.176090662353, 3003.8498075096595, 3028.6365636123805, 3030.696930306996, 3062.7396671282745, 3067.8466076696077, 552.2875490617139, 558.2324304081887, 567.5799908068, 569.9287589051369, 578.5169383564687, 584.8128598848413, 598.3029770317619, 605.1995313941078, 622.2284367384501, 634.1629049654412, 7803.297703206738, 7989.640466179036, 8033.678652853937, 8067.273945344947, 8178.052797045784, 587.3128439725531, 621.2141640824361, 636.4335741190345, 638.0397501759821, 666.3503084492581, 3629.6188900165102, 3819.779144045845, 3850.5764365919727, 3878.2825177342283, 3882.941755873664, 50423.243651345256, 55305.72347138319, 55657.0, 55883.441165588636, 55913.72043139758, 50087.24869126966, 50574.0, 50716.23925641119, 51027.48972510302, 51081.0, 47175.292370614465, 47554.0, 47672.523274768195, 47786.522134778905, 48299.75850120727, 8084.83830323402, 8289.709860154866, 8352.540610266493, 8451.83096338082, 8462.957685211535, 33645.83177084099, 34243.0, 34431.827840860635, 34452.31095378028, 34556.82721586376, 41227.0, 41452.79273603613, 46747.29879051885, 47088.29367559558, 47110.05779884315, 9947.801043979225, 10292.897071029194, 10515.894841051491, 10951.357296405602, 11157.944210278896, 10670.19973502006, 10990.0, 11051.889481105407, 11169.72075698114, 11180.832287515856, 10062.798744025225, 10585.576576936835, 10590.947045264724, 10842.945785271024, 10869.0, 10323.277370584288, 10819.350838949687, 10888.836667449836, 10891.237613366988, 10951.726206844574, 10302.639407620698, 10353.171746260432, 10537.683869483928, 10673.679789606324, 10822.615869934281, 9130.178283954476, 9191.310651701015, 9254.305927055364, 9456.858147127661, 9480.715578532656, 7942.6028698566, 8305.0, 8337.333013359039, 8415.747527574182, 8836.293096552132], "unit": "ms", "name": "yui.html", "value": 6191.909555057441}], "name": "dromaeo_css", "value": 6939.94064934535}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 104.0, 109.0, 111.0, 111.0, 100.0, 109.0, 109.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 108.2}, {"lowerIsBetter": true, "replicates": [151.0, 137.0, 149.0, 169.0, 130.0, 152.0, 152.0, 127.0, 168.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 146.1}, {"lowerIsBetter": true, "replicates": [163.0, 162.0, 164.0, 163.0, 161.0, 162.0, 161.0, 163.0, 165.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 162.7}, {"lowerIsBetter": true, "replicates": [73.0, 74.0, 88.0, 94.0, 73.0, 73.0, 73.0, 93.0, 93.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.6}, {"lowerIsBetter": true, "replicates": [90.0, 92.0, 135.0, 91.0, 91.0, 93.0, 134.0, 99.0, 91.0, 137.0], "unit": "ms", "name": "audio-oscillator", "value": 105.3}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 108.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [66.0, 63.0, 64.0, 63.0, 63.0, 66.0, 61.0, 71.0, 60.0, 61.0], "unit": "ms", "name": "json-parse-financial", "value": 63.8}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 70.0, 69.0, 69.0, 71.0, 68.0, 70.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.4}, {"lowerIsBetter": true, "replicates": [89.0, 86.0, 85.0, 86.0, 86.0, 85.0, 86.0, 84.0, 93.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.5}, {"lowerIsBetter": true, "replicates": [116.0, 117.0, 115.0, 121.0, 124.0, 123.0, 119.0, 120.0, 126.0, 132.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 121.3}, {"lowerIsBetter": true, "replicates": [152.0, 142.0, 157.0, 154.0, 159.0, 147.0, 165.0, 152.0, 150.0, 157.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 153.5}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 69.0, 70.0, 70.0, 70.0, 68.0, 68.0, 69.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 69.6}], "name": "kraken", "value": 1517.0999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.18537859007833], "unit": "score", "name": "Richards", "value": 216.33065600000006}, {"lowerIsBetter": false, "replicates": [195.1219512195122], "unit": "score", "name": "DeltaBlue", "value": 338.85475000000014}, {"lowerIsBetter": false, "replicates": [286.94404591104734], "unit": "score", "name": "Encrypt", "value": 927.640785}, {"lowerIsBetter": false, "replicates": [5086.294416243655], "unit": "score", "name": "Decrypt", "value": 52.33299101796402}, {"lowerIsBetter": false, "replicates": [1132.5028312570782], "unit": "score", "name": "RayTrace", "value": 653.410287}, {"lowerIsBetter": false, "replicates": [788.6435331230284], "unit": "score", "name": "Earley", "value": 845.075084}, {"lowerIsBetter": false, "replicates": [12195.121951219513], "unit": "score", "name": "Boyer", "value": 54.64996600000001}, {"lowerIsBetter": false, "replicates": [25550.0], "unit": "score", "name": "RegExp", "value": 35.65499021526421}, {"lowerIsBetter": false, "replicates": [646.1932181701856], "unit": "score", "name": "Splay", "value": 126.1093396039604}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20836.82509803916}]} 19:15:38 INFO - RETURN: dromaeo_css: 6939.94 19:15:38 INFO - RETURN: kraken: 1517.10 19:15:38 INFO - RETURN: v8_7: 20836.83 19:15:38 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "20836.83"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1517.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6939.94"}}} 19:15:38 INFO - Return code: 0 19:15:38 INFO - # TBPL SUCCESS # 19:15:38 INFO - Running post-action listener: _resource_record_post_action 19:15:38 INFO - Running post-run listener: _resource_record_post_run 19:15:40 INFO - Total resource usage - Wall time: 1230s; CPU: 13.0%; Read bytes: 8192; Write bytes: 506421248; Read time: 408; Write time: 648640 19:15:40 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 164261888; Read time: 0; Write time: 248420 19:15:40 INFO - run-tests - Wall time: 1216s; CPU: 13.0%; Read bytes: 8192; Write bytes: 342118400; Read time: 408; Write time: 400196 19:15:40 INFO - Running post-run listener: _upload_blobber_files 19:15:40 INFO - Blob upload gear active. 19:15:40 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:15:40 INFO - Copying logs to upload dir... 19:15:40 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1292.098290 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2015-11-19 19:15:38.663406) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 19:15:38.668270) ========= 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=0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009961 build_url: 'https://queue.taskcluster.net/v1/task/9VoIBK9UQ5-wrtyBkZQtmw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 19:15:38.702674) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 19:15:38.706428) ========= 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=0ae9de487587ba4b94dd3100000001b5-1447978162.176033-128764297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004533 ========= master_lag: 1.32 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 19:15:40.028284) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 19:15:40.028730) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 19:15:40.029438) ========= ========= Total master_lag: 1.63 =========