builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-054 starttime: 1447952331.68 results: success (0) buildid: 20151119073136 builduid: 37d38f06c3b14979a6238f32f936229b revision: a523d4c7efe2f43dd6b25a176c07b729918d550f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.677159) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.677799) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.678176) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006474 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.697013) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.697347) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.706207) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.706565) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004197 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.756515) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.757156) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.757935) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.758283) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-19 08:58:52-- 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% 227M=0s 2015-11-19 08:58:52 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100343 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.871172) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.871525) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020960 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.921836) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:51.922206) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a523d4c7efe2f43dd6b25a176c07b729918d550f --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 a523d4c7efe2f43dd6b25a176c07b729918d550f --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=752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-19 08:58:52,370 truncating revision to first 12 chars 2015-11-19 08:58:52,370 Setting DEBUG logging. 2015-11-19 08:58:52,370 attempt 1/10 2015-11-19 08:58:52,370 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a523d4c7efe2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-19 08:58:52,622 unpacking tar archive at: mozilla-central-a523d4c7efe2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.544461 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:52.480080) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:52.480405) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:52.734734) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:52.735180) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 08:58:52.735656) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2015-11-19 08:58:52.735967) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:58:53 INFO - MultiFileLogger online at 20151119 08:58:53 in /builds/slave/test-pgo 08:58:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 08:58:53 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:58:53 INFO - {'append_to_log': False, 08:58:53 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:58:53 INFO - 'blob_upload_branch': 'Firefox', 08:58:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:58:53 INFO - 'branch': 'Firefox', 08:58:53 INFO - 'buildbot_json_path': 'buildprops.json', 08:58:53 INFO - 'config_files': ('talos/linux_config.py',), 08:58:53 INFO - 'default_actions': ('clobber', 08:58:53 INFO - 'read-buildbot-config', 08:58:53 INFO - 'download-and-extract', 08:58:53 INFO - 'populate-webroot', 08:58:53 INFO - 'create-virtualenv', 08:58:53 INFO - 'install', 08:58:53 INFO - 'run-tests'), 08:58:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:58:53 INFO - 'download_minidump_stackwalk': True, 08:58:53 INFO - 'download_symbols': 'ondemand', 08:58:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:58:53 INFO - 'tooltool.py': '/tools/tooltool.py', 08:58:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:58:53 INFO - '/tools/misc-python/virtualenv.py')}, 08:58:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:58:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:58:53 INFO - 'installer_path': 'installer.exe', 08:58:53 INFO - 'log_level': 'info', 08:58:53 INFO - 'log_name': 'talos', 08:58:53 INFO - 'log_to_console': True, 08:58:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:58:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:58:53 INFO - 'opt_config_files': (), 08:58:53 INFO - 'pip_index': False, 08:58:53 INFO - 'sps_profile': False, 08:58:53 INFO - 'sps_profile_interval': 0, 08:58:53 INFO - 'suite': 'dromaeojs-e10s', 08:58:53 INFO - 'system_bits': '64', 08:58:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:58:53 INFO - 'title': 'talos-linux64-ix-054', 08:58:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:58:53 INFO - 'use_talos_json': True, 08:58:53 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:58:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:58:53 INFO - 'work_dir': 'build'} 08:58:53 INFO - ##### 08:58:53 INFO - ##### Running clobber step. 08:58:53 INFO - ##### 08:58:53 INFO - Running pre-action listener: _resource_record_pre_action 08:58:53 INFO - Running main action method: clobber 08:58:53 INFO - rmtree: /builds/slave/test-pgo/build 08:58:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:58:54 INFO - Running post-action listener: _resource_record_post_action 08:58:54 INFO - ##### 08:58:54 INFO - ##### Running read-buildbot-config step. 08:58:54 INFO - ##### 08:58:54 INFO - Running pre-action listener: _resource_record_pre_action 08:58:54 INFO - Running main action method: read_buildbot_config 08:58:54 INFO - Using buildbot properties: 08:58:54 INFO - { 08:58:54 INFO - "properties": { 08:58:54 INFO - "buildnumber": 247, 08:58:54 INFO - "product": "firefox", 08:58:54 INFO - "script_repo_revision": "production", 08:58:54 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:58:54 INFO - "repository": "", 08:58:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 08:58:54 INFO - "buildid": "20151119073136", 08:58:54 INFO - "slavename": "talos-linux64-ix-054", 08:58:54 INFO - "pgo_build": "True", 08:58:54 INFO - "basedir": "/builds/slave/test-pgo", 08:58:54 INFO - "project": "", 08:58:54 INFO - "platform": "ubuntu64_hw", 08:58:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:58:54 INFO - "slavebuilddir": "test", 08:58:54 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 08:58:54 INFO - "branch": "mozilla-central", 08:58:54 INFO - "repo_path": "mozilla-central", 08:58:54 INFO - "stage_platform": "linux64-pgo", 08:58:54 INFO - "builduid": "37d38f06c3b14979a6238f32f936229b", 08:58:54 INFO - "revision": "a523d4c7efe2f43dd6b25a176c07b729918d550f" 08:58:54 INFO - }, 08:58:54 INFO - "sourcestamp": { 08:58:54 INFO - "repository": "", 08:58:54 INFO - "hasPatch": false, 08:58:54 INFO - "project": "", 08:58:54 INFO - "branch": "mozilla-central-linux64-pgo-talos", 08:58:54 INFO - "changes": [ 08:58:54 INFO - { 08:58:54 INFO - "category": null, 08:58:54 INFO - "files": [ 08:58:54 INFO - { 08:58:54 INFO - "url": null, 08:58:54 INFO - "name": "https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 08:58:54 INFO - } 08:58:54 INFO - ], 08:58:54 INFO - "repository": "", 08:58:54 INFO - "rev": "a523d4c7efe2f43dd6b25a176c07b729918d550f", 08:58:54 INFO - "who": "sendchange", 08:58:54 INFO - "when": 1447952077, 08:58:54 INFO - "number": 6683661, 08:58:54 INFO - "comments": "", 08:58:54 INFO - "project": "", 08:58:54 INFO - "at": "Thu 19 Nov 2015 08:54:37", 08:58:54 INFO - "branch": "mozilla-central-linux64-pgo-talos", 08:58:54 INFO - "revlink": "", 08:58:54 INFO - "properties": [ 08:58:54 INFO - [ 08:58:54 INFO - "buildid", 08:58:54 INFO - "20151119073136", 08:58:54 INFO - "Change" 08:58:54 INFO - ], 08:58:54 INFO - [ 08:58:54 INFO - "builduid", 08:58:54 INFO - "37d38f06c3b14979a6238f32f936229b", 08:58:54 INFO - "Change" 08:58:54 INFO - ], 08:58:54 INFO - [ 08:58:54 INFO - "pgo_build", 08:58:54 INFO - "True", 08:58:54 INFO - "Change" 08:58:54 INFO - ] 08:58:54 INFO - ], 08:58:54 INFO - "revision": "a523d4c7efe2f43dd6b25a176c07b729918d550f" 08:58:54 INFO - } 08:58:54 INFO - ], 08:58:54 INFO - "revision": "a523d4c7efe2f43dd6b25a176c07b729918d550f" 08:58:54 INFO - } 08:58:54 INFO - } 08:58:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 08:58:54 INFO - Running post-action listener: _resource_record_post_action 08:58:54 INFO - ##### 08:58:54 INFO - ##### Running download-and-extract step. 08:58:54 INFO - ##### 08:58:54 INFO - Running pre-action listener: _resource_record_pre_action 08:58:54 INFO - Running main action method: download_and_extract 08:58:54 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:58:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:58:54 INFO - https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 08:58:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/test_packages.json 08:58:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/test_packages.json 08:58:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:58:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:58:54 INFO - Downloaded 1302 bytes. 08:58:54 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:58:54 INFO - Using the following test package requirements: 08:58:54 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 08:58:54 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 08:58:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:58:54 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 08:58:54 INFO - u'jsshell-linux-x86_64.zip'], 08:58:54 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 08:58:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:58:54 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 08:58:54 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 08:58:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:58:54 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 08:58:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:58:54 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 08:58:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:58:54 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 08:58:54 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 08:58:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:58:54 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:58:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:58:54 INFO - https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:58:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 08:58:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 08:58:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/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 08:58:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/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 08:58:55 INFO - Downloaded 22520893 bytes. 08:58:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 08:58:55 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 08:58:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 08:58:57 INFO - Return code: 0 08:58:57 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 08:58: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')]} 08:58:57 INFO - https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:58:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 08:58:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 08:58:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/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 08:58:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/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 08:58:58 INFO - Downloaded 22520893 bytes. 08:58:58 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 08:58:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 08:58:58 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 08:58:59 INFO - Return code: 0 08:58:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:58:59 INFO - https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:58:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 08:58:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 08:58:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/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 08:58:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/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 08:59:00 INFO - Downloaded 11130997 bytes. 08:59:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 08:59:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 08:59:00 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 08:59:00 INFO - Return code: 0 08:59: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')]} 08:59:00 INFO - https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:59:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 08:59:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 08:59:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/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 08:59:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gnIvwFcKT1SsSyF9VrugQA/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 08:59:02 INFO - Downloaded 57339951 bytes. 08:59:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 08:59:02 INFO - mkdir: /builds/slave/test-pgo/properties 08:59:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:59:02 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:59:02 INFO - Contents: 08:59:02 INFO - build_url:https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 08:59:02 INFO - Running post-action listener: _resource_record_post_action 08:59:02 INFO - Running post-action listener: set_extra_try_arguments 08:59:02 INFO - ##### 08:59:02 INFO - ##### Running populate-webroot step. 08:59:02 INFO - ##### 08:59:02 INFO - Running pre-action listener: _resource_record_pre_action 08:59:02 INFO - Running main action method: populate_webroot 08:59:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:59:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:59:02 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 08:59:02 INFO - '../fennec_ids.txt'], 08:59:02 INFO - 'tests': ['tcheck2']}, 08:59:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:59:02 INFO - '--tppagecycles', 08:59:02 INFO - '7'], 08:59:02 INFO - 'tests': ['tsvgm']}}, 08:59:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:59:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:59:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:59:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:59:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:59:02 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:59:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:59:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:02 INFO - 'talos_options': ['--e10s'], 08:59:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:59:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:02 INFO - 'tests': ['damp', 'tps']}, 08:59:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:02 INFO - 'talos_options': ['--e10s'], 08:59:02 INFO - 'tests': ['damp', 'tps']}, 08:59:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:59:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:59:02 INFO - 'other': {'tests': ['a11yr', 08:59:02 INFO - 'ts_paint', 08:59:02 INFO - 'tpaint', 08:59:02 INFO - 'sessionrestore', 08:59:02 INFO - 'sessionrestore_no_auto_restore']}, 08:59:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:59:02 INFO - 'tests': ['a11yr', 08:59:02 INFO - 'ts_paint', 08:59:02 INFO - 'tpaint', 08:59:02 INFO - 'sessionrestore', 08:59:02 INFO - 'sessionrestore_no_auto_restore']}, 08:59:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:59:02 INFO - 'tests': ['a11yr', 08:59:02 INFO - 'ts_paint', 08:59:02 INFO - 'tpaint', 08:59:02 INFO - 'sessionrestore', 08:59:02 INFO - 'sessionrestore_no_auto_restore']}, 08:59:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:59:02 INFO - 'tests': ['a11yr', 08:59:02 INFO - 'ts_paint', 08:59:02 INFO - 'tpaint', 08:59:02 INFO - 'sessionrestore', 08:59:02 INFO - 'sessionrestore_no_auto_restore']}, 08:59:02 INFO - 'other_l64': {'tests': ['a11yr', 08:59:02 INFO - 'ts_paint', 08:59:02 INFO - 'tpaint', 08:59:02 INFO - 'sessionrestore', 08:59:02 INFO - 'sessionrestore_no_auto_restore']}, 08:59:02 INFO - 'other_nol64': {'tests': ['a11yr', 08:59:02 INFO - 'ts_paint', 08:59:02 INFO - 'tpaint', 08:59:02 INFO - 'sessionrestore', 08:59:02 INFO - 'sessionrestore_no_auto_restore']}, 08:59:02 INFO - 'svgr': {'tests': ['tsvgx', 08:59:02 INFO - 'tsvgr_opacity', 08:59:02 INFO - 'tart', 08:59:02 INFO - 'tscrollx', 08:59:02 INFO - 'cart']}, 08:59:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:59:02 INFO - 'tests': ['tsvgx', 08:59:02 INFO - 'tsvgr_opacity', 08:59:02 INFO - 'tart', 08:59:02 INFO - 'tscrollx', 08:59:02 INFO - 'cart']}, 08:59:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:02 INFO - 'tests': ['tp5o']}, 08:59:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:02 INFO - 'talos_options': ['--e10s'], 08:59:02 INFO - 'tests': ['tp5o']}, 08:59:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:02 INFO - 'talos_options': ['--xperf_path', 08:59:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:59:02 INFO - 'tests': ['tp5n']}, 08:59:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:02 INFO - 'talos_options': ['--e10s', 08:59:02 INFO - '--xperf_path', 08:59:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:59:02 INFO - 'tests': ['tp5n']}}, 08:59:02 INFO - 'talos.zip': {'path': '', 08:59:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:59:02 INFO - Running post-action listener: _resource_record_post_action 08:59:02 INFO - ##### 08:59:02 INFO - ##### Running create-virtualenv step. 08:59:02 INFO - ##### 08:59:02 INFO - Running pre-action listener: _resource_record_pre_action 08:59:02 INFO - Running main action method: create_virtualenv 08:59:02 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:59:02 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 08:59:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:59:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:59:02 INFO - Using real prefix '/usr' 08:59:02 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:59:02 INFO - Installing distribute.............................................................................................................................................................................................done. 08:59:04 INFO - Installing pip.................done. 08:59:04 INFO - Return code: 0 08:59:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:59: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')]} 08:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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')]} 08:59:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9e1c65c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf726c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a90>, '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 0x10bbf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', '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 08:59:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 08:59:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:59:04 INFO - Using env: {'DISPLAY': ':0', 08:59:04 INFO - 'HOME': '/home/cltbld', 08:59:04 INFO - 'LANG': 'en_US.UTF-8', 08:59:04 INFO - 'LANGUAGE': 'en_US:en', 08:59:04 INFO - 'LOGNAME': 'cltbld', 08:59:04 INFO - 'MAIL': '/var/mail/cltbld', 08:59:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:04 INFO - 'MOZ_NO_REMOTE': '1', 08:59:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:04 INFO - 'NO_EM_RESTART': '1', 08:59:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:59:04 INFO - 'PWD': '/builds/slave/test-pgo', 08:59:04 INFO - 'SHELL': '/bin/bash', 08:59:04 INFO - 'SHLVL': '1', 08:59:04 INFO - 'TERM': 'linux', 08:59:04 INFO - 'TMOUT': '86400', 08:59:04 INFO - 'USER': 'cltbld', 08:59:04 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', 08:59:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:04 INFO - '_': '/tools/buildbot/bin/python'} 08:59:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:59:04 INFO - Cleaning up... 08:59:04 INFO - Return code: 0 08:59:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:59: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')]} 08:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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')]} 08:59:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9e1c65c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf726c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a90>, '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 0x10bbf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', '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 08:59:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 08:59:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:59:04 INFO - Using env: {'DISPLAY': ':0', 08:59:04 INFO - 'HOME': '/home/cltbld', 08:59:04 INFO - 'LANG': 'en_US.UTF-8', 08:59:04 INFO - 'LANGUAGE': 'en_US:en', 08:59:04 INFO - 'LOGNAME': 'cltbld', 08:59:04 INFO - 'MAIL': '/var/mail/cltbld', 08:59:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:04 INFO - 'MOZ_NO_REMOTE': '1', 08:59:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:04 INFO - 'NO_EM_RESTART': '1', 08:59:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:59:04 INFO - 'PWD': '/builds/slave/test-pgo', 08:59:04 INFO - 'SHELL': '/bin/bash', 08:59:04 INFO - 'SHLVL': '1', 08:59:04 INFO - 'TERM': 'linux', 08:59:04 INFO - 'TMOUT': '86400', 08:59:04 INFO - 'USER': 'cltbld', 08:59:04 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', 08:59:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:04 INFO - '_': '/tools/buildbot/bin/python'} 08:59:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:05 INFO - Downloading/unpacking psutil>=0.7.1 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59:06 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 08:59:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 08:59:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:59:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:59:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:59:06 INFO - Installing collected packages: psutil 08:59:06 INFO - Running setup.py install for psutil 08:59:06 INFO - building 'psutil._psutil_linux' extension 08:59:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:59:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:59:07 INFO - building 'psutil._psutil_posix' extension 08:59:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:59:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:59:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:59:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:59:07 INFO - Successfully installed psutil 08:59:07 INFO - Cleaning up... 08:59:07 INFO - Return code: 0 08:59:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:59: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')]} 08:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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')]} 08:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9e1c65c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf726c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a90>, '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 0x10bbf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', '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 08:59:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 08:59:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:59:07 INFO - Using env: {'DISPLAY': ':0', 08:59:07 INFO - 'HOME': '/home/cltbld', 08:59:07 INFO - 'LANG': 'en_US.UTF-8', 08:59:07 INFO - 'LANGUAGE': 'en_US:en', 08:59:07 INFO - 'LOGNAME': 'cltbld', 08:59:07 INFO - 'MAIL': '/var/mail/cltbld', 08:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:07 INFO - 'MOZ_NO_REMOTE': '1', 08:59:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:07 INFO - 'NO_EM_RESTART': '1', 08:59:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:59:07 INFO - 'PWD': '/builds/slave/test-pgo', 08:59:07 INFO - 'SHELL': '/bin/bash', 08:59:07 INFO - 'SHLVL': '1', 08:59:07 INFO - 'TERM': 'linux', 08:59:07 INFO - 'TMOUT': '86400', 08:59:07 INFO - 'USER': 'cltbld', 08:59:07 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', 08:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:07 INFO - '_': '/tools/buildbot/bin/python'} 08:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:59:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 08:59:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:59:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:59:09 INFO - Installing collected packages: mozsystemmonitor 08:59:09 INFO - Running setup.py install for mozsystemmonitor 08:59:09 INFO - Successfully installed mozsystemmonitor 08:59:09 INFO - Cleaning up... 08:59:09 INFO - Return code: 0 08:59:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:59: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')]} 08:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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')]} 08:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9e1c65c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf726c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a90>, '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 0x10bbf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', '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 08:59:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 08:59:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:59:09 INFO - Using env: {'DISPLAY': ':0', 08:59:09 INFO - 'HOME': '/home/cltbld', 08:59:09 INFO - 'LANG': 'en_US.UTF-8', 08:59:09 INFO - 'LANGUAGE': 'en_US:en', 08:59:09 INFO - 'LOGNAME': 'cltbld', 08:59:09 INFO - 'MAIL': '/var/mail/cltbld', 08:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:09 INFO - 'MOZ_NO_REMOTE': '1', 08:59:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:09 INFO - 'NO_EM_RESTART': '1', 08:59:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:59:09 INFO - 'PWD': '/builds/slave/test-pgo', 08:59:09 INFO - 'SHELL': '/bin/bash', 08:59:09 INFO - 'SHLVL': '1', 08:59:09 INFO - 'TERM': 'linux', 08:59:09 INFO - 'TMOUT': '86400', 08:59:09 INFO - 'USER': 'cltbld', 08:59:09 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', 08:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:09 INFO - '_': '/tools/buildbot/bin/python'} 08:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:09 INFO - Downloading/unpacking blobuploader==1.2.4 08:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:11 INFO - Downloading blobuploader-1.2.4.tar.gz 08:59:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 08:59:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:59:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 08:59:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:59:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:11 INFO - Downloading docopt-0.6.1.tar.gz 08:59:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 08:59:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:59:12 INFO - Installing collected packages: blobuploader, requests, docopt 08:59:12 INFO - Running setup.py install for blobuploader 08:59:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:59:12 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:59:12 INFO - Running setup.py install for requests 08:59:12 INFO - Running setup.py install for docopt 08:59:12 INFO - Successfully installed blobuploader requests docopt 08:59:12 INFO - Cleaning up... 08:59:12 INFO - Return code: 0 08:59:12 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9e1c65c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf726c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a90>, '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 0x10bbf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', '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 08:59:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 08:59:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:59:12 INFO - Using env: {'DISPLAY': ':0', 08:59:12 INFO - 'HOME': '/home/cltbld', 08:59:12 INFO - 'LANG': 'en_US.UTF-8', 08:59:12 INFO - 'LANGUAGE': 'en_US:en', 08:59:12 INFO - 'LOGNAME': 'cltbld', 08:59:12 INFO - 'MAIL': '/var/mail/cltbld', 08:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:12 INFO - 'MOZ_NO_REMOTE': '1', 08:59:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:12 INFO - 'NO_EM_RESTART': '1', 08:59:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:59:12 INFO - 'PWD': '/builds/slave/test-pgo', 08:59:12 INFO - 'SHELL': '/bin/bash', 08:59:12 INFO - 'SHLVL': '1', 08:59:12 INFO - 'TERM': 'linux', 08:59:12 INFO - 'TMOUT': '86400', 08:59:12 INFO - 'USER': 'cltbld', 08:59:12 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', 08:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:12 INFO - '_': '/tools/buildbot/bin/python'} 08:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:59:12 INFO - Running setup.py (path:/tmp/pip-0an17D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:59:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:59:12 INFO - Running setup.py (path:/tmp/pip-Udqcgh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:59:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:59:12 INFO - Running setup.py (path:/tmp/pip-kTYekA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:59:13 INFO - Running setup.py (path:/tmp/pip-b1YET8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:59:13 INFO - Running setup.py (path:/tmp/pip-d4ZDaI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:59:13 INFO - Running setup.py (path:/tmp/pip-9Mns10-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:59:13 INFO - Running setup.py (path:/tmp/pip-mxMcip-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:59:13 INFO - Running setup.py (path:/tmp/pip-Z67c7I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:59:13 INFO - Running setup.py (path:/tmp/pip-tPZdqF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:59:13 INFO - Running setup.py (path:/tmp/pip-ceMgQG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:59:13 INFO - Running setup.py (path:/tmp/pip-meYVvj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:59:13 INFO - Running setup.py (path:/tmp/pip-oB05iB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:59:13 INFO - Running setup.py (path:/tmp/pip-JTetpL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:59:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:59:14 INFO - Running setup.py (path:/tmp/pip-6JyhcA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:59:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:59:14 INFO - Running setup.py (path:/tmp/pip-A7vvDk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:59:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:59:14 INFO - Running setup.py (path:/tmp/pip-po9XpR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:59:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:59:14 INFO - Running setup.py (path:/tmp/pip-X0tTAr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:59:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:59:14 INFO - Running setup.py install for manifestparser 08:59:14 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:59:14 INFO - Running setup.py install for mozcrash 08:59:14 INFO - Running setup.py install for mozdebug 08:59:14 INFO - Running setup.py install for mozdevice 08:59:14 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:59:14 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:59:14 INFO - Running setup.py install for mozfile 08:59:14 INFO - Running setup.py install for mozhttpd 08:59:14 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:59:14 INFO - Running setup.py install for mozinfo 08:59:14 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Running setup.py install for mozInstall 08:59:15 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Running setup.py install for mozleak 08:59:15 INFO - Running setup.py install for mozlog 08:59:15 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Running setup.py install for moznetwork 08:59:15 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Running setup.py install for mozprocess 08:59:15 INFO - Running setup.py install for mozprofile 08:59:15 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Running setup.py install for mozrunner 08:59:15 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:59:15 INFO - Running setup.py install for mozscreenshot 08:59:15 INFO - Running setup.py install for moztest 08:59:15 INFO - Running setup.py install for mozversion 08:59:16 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:59:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:59:16 INFO - Cleaning up... 08:59:16 INFO - Return code: 0 08:59:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:59:16 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')]} 08:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:16 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')]} 08:59:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:16 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 0x7fb9e1c65c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf726c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a90>, '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 0x10bbf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', '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 08:59:16 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 08:59:16 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 08:59:16 INFO - Using env: {'DISPLAY': ':0', 08:59:16 INFO - 'HOME': '/home/cltbld', 08:59:16 INFO - 'LANG': 'en_US.UTF-8', 08:59:16 INFO - 'LANGUAGE': 'en_US:en', 08:59:16 INFO - 'LOGNAME': 'cltbld', 08:59:16 INFO - 'MAIL': '/var/mail/cltbld', 08:59:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:16 INFO - 'MOZ_NO_REMOTE': '1', 08:59:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:16 INFO - 'NO_EM_RESTART': '1', 08:59:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:59:16 INFO - 'PWD': '/builds/slave/test-pgo', 08:59:16 INFO - 'SHELL': '/bin/bash', 08:59:16 INFO - 'SHLVL': '1', 08:59:16 INFO - 'TERM': 'linux', 08:59:16 INFO - 'TMOUT': '86400', 08:59:16 INFO - 'USER': 'cltbld', 08:59:16 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', 08:59:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:16 INFO - '_': '/tools/buildbot/bin/python'} 08:59:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:59:16 INFO - Running setup.py (path:/tmp/pip-oPMq4g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 08:59:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:59:16 INFO - Running setup.py (path:/tmp/pip-Fxvqbh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:59:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:59:16 INFO - Running setup.py (path:/tmp/pip-5RhAS3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 08:59:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:59:16 INFO - Running setup.py (path:/tmp/pip-Gc9Nzg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:59:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:59:16 INFO - Running setup.py (path:/tmp/pip-KiI9z_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 08:59:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:59:16 INFO - Running setup.py (path:/tmp/pip-pQOZxl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 08:59:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:59:16 INFO - Running setup.py (path:/tmp/pip-m6LF6g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 08:59:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:59:16 INFO - Running setup.py (path:/tmp/pip-rYOmcN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 08:59:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:59:16 INFO - Running setup.py (path:/tmp/pip-qWt6Eq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 08:59:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:59:16 INFO - Running setup.py (path:/tmp/pip-IsshAt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:59:17 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)) 08:59:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:59:17 INFO - Running setup.py (path:/tmp/pip-vb9qiD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:59:17 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)) 08:59:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:59:17 INFO - Running setup.py (path:/tmp/pip-FuZxKe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 08:59:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:59:17 INFO - Running setup.py (path:/tmp/pip-gHIz9m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 08:59:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:59:17 INFO - Running setup.py (path:/tmp/pip-ynf3RJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 08:59:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:59:17 INFO - Running setup.py (path:/tmp/pip-YD59t9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 08:59:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:59:17 INFO - Running setup.py (path:/tmp/pip-SxsV8t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 08:59:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:59:17 INFO - Running setup.py (path:/tmp/pip-Ww4040-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:59:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:19 INFO - Downloading blessings-1.5.1.tar.gz 08:59:19 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 08:59:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:59:19 INFO - Installing collected packages: blessings 08:59:19 INFO - Running setup.py install for blessings 08:59:19 INFO - Successfully installed blessings 08:59:19 INFO - Cleaning up... 08:59:19 INFO - Return code: 0 08:59:19 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:59:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:59:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:59:19 INFO - Reading from file tmpfile_stdout 08:59:19 INFO - Current package versions: 08:59:19 INFO - argparse == 1.2.1 08:59:19 INFO - blessings == 1.5.1 08:59:19 INFO - blobuploader == 1.2.4 08:59:19 INFO - docopt == 0.6.1 08:59:19 INFO - manifestparser == 1.1 08:59:19 INFO - mozInstall == 1.12 08:59:19 INFO - mozcrash == 0.16 08:59:19 INFO - mozdebug == 0.1 08:59:19 INFO - mozdevice == 0.47 08:59:19 INFO - mozfile == 1.2 08:59:19 INFO - mozhttpd == 0.7 08:59:19 INFO - mozinfo == 0.9 08:59:19 INFO - mozleak == 0.1 08:59:19 INFO - mozlog == 3.0 08:59:19 INFO - moznetwork == 0.27 08:59:19 INFO - mozprocess == 0.22 08:59:19 INFO - mozprofile == 0.27 08:59:19 INFO - mozrunner == 6.11 08:59:19 INFO - mozscreenshot == 0.1 08:59:19 INFO - mozsystemmonitor == 0.0 08:59:19 INFO - moztest == 0.7 08:59:19 INFO - mozversion == 1.4 08:59:19 INFO - psutil == 3.1.1 08:59:19 INFO - requests == 1.2.3 08:59:19 INFO - wsgiref == 0.1.2 08:59:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9e1c65c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf726c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a90>, '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 0x10bbf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', '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 08:59:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 08:59:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:59:19 INFO - Using env: {'DISPLAY': ':0', 08:59:19 INFO - 'HOME': '/home/cltbld', 08:59:19 INFO - 'LANG': 'en_US.UTF-8', 08:59:19 INFO - 'LANGUAGE': 'en_US:en', 08:59:19 INFO - 'LOGNAME': 'cltbld', 08:59:19 INFO - 'MAIL': '/var/mail/cltbld', 08:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:19 INFO - 'MOZ_NO_REMOTE': '1', 08:59:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:19 INFO - 'NO_EM_RESTART': '1', 08:59:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:59:19 INFO - 'PWD': '/builds/slave/test-pgo', 08:59:19 INFO - 'SHELL': '/bin/bash', 08:59:19 INFO - 'SHLVL': '1', 08:59:19 INFO - 'TERM': 'linux', 08:59:19 INFO - 'TMOUT': '86400', 08:59:19 INFO - 'USER': 'cltbld', 08:59:19 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', 08:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:19 INFO - '_': '/tools/buildbot/bin/python'} 08:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 08:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:59:19 INFO - Cleaning up... 08:59:19 INFO - Return code: 0 08:59:19 INFO - Running post-action listener: _resource_record_post_action 08:59:19 INFO - Running post-action listener: _start_resource_monitoring 08:59:19 INFO - Starting resource monitoring. 08:59:19 INFO - ##### 08:59:19 INFO - ##### Running install step. 08:59:19 INFO - ##### 08:59:19 INFO - Running pre-action listener: _resource_record_pre_action 08:59:19 INFO - Running main action method: install 08:59:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:59:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:59:20 INFO - Reading from file tmpfile_stdout 08:59:20 INFO - Detecting whether we're running mozinstall >=1.0... 08:59:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:59:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:59:20 INFO - Reading from file tmpfile_stdout 08:59:20 INFO - Output received: 08:59:20 INFO - Usage: mozinstall [options] installer 08:59:20 INFO - Options: 08:59:20 INFO - -h, --help show this help message and exit 08:59:20 INFO - -d DEST, --destination=DEST 08:59:20 INFO - Directory to install application into. [default: 08:59:20 INFO - "/builds/slave/test-pgo"] 08:59:20 INFO - --app=APP Application being installed. [default: firefox] 08:59:20 INFO - mkdir: /builds/slave/test-pgo/build/application 08:59:20 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'] 08:59:20 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 08:59:34 INFO - Reading from file tmpfile_stdout 08:59:34 INFO - Output received: 08:59:34 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:59:34 INFO - Running post-action listener: _resource_record_post_action 08:59:34 INFO - ##### 08:59:34 INFO - ##### Running run-tests step. 08:59:34 INFO - ##### 08:59:34 INFO - Running pre-action listener: _resource_record_pre_action 08:59:34 INFO - Running main action method: run_tests 08:59:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:59:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:59:34 INFO - Python 2.7.3 08:59:34 INFO - Return code: 0 08:59:34 INFO - grabbing minidump binary from tooltool 08:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:34 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 0x1069a90>, '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 0x10bbf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:59:34 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 08:59:34 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 08:59:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:59:34 INFO - Return code: 0 08:59:34 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:59:34 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:59:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:59:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:59:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:59:34 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 08:59:34 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-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost 08:59:34 INFO - Using env: {'DISPLAY': ':0', 08:59:34 INFO - 'HOME': '/home/cltbld', 08:59:34 INFO - 'LANG': 'en_US.UTF-8', 08:59:34 INFO - 'LANGUAGE': 'en_US:en', 08:59:34 INFO - 'LOGNAME': 'cltbld', 08:59:34 INFO - 'MAIL': '/var/mail/cltbld', 08:59:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:59:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:34 INFO - 'MOZ_NO_REMOTE': '1', 08:59:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:59:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:34 INFO - 'NO_EM_RESTART': '1', 08:59:34 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:59:34 INFO - 'PWD': '/builds/slave/test-pgo', 08:59:34 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:59:34 INFO - 'SHELL': '/bin/bash', 08:59:34 INFO - 'SHLVL': '1', 08:59:34 INFO - 'TERM': 'linux', 08:59:34 INFO - 'TMOUT': '86400', 08:59:34 INFO - 'USER': 'cltbld', 08:59:34 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377', 08:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:34 INFO - '_': '/tools/buildbot/bin/python'} 08:59:34 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost'] with output_timeout 3600 08:59:34 INFO - mozversion INFO | application_buildid: 20151119073136 08:59:34 INFO - mozversion INFO | application_changeset: a523d4c7efe2f43dd6b25a176c07b729918d550f 08:59:34 INFO - mozversion INFO | application_display_name: Nightly 08:59:34 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:59:34 INFO - mozversion INFO | application_name: Firefox 08:59:34 INFO - mozversion INFO | application_remotingname: firefox 08:59:34 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 08:59:34 INFO - mozversion INFO | application_vendor: Mozilla 08:59:34 INFO - mozversion INFO | application_version: 45.0a1 08:59:34 INFO - mozversion INFO | platform_buildid: 20151119073136 08:59:34 INFO - mozversion INFO | platform_changeset: a523d4c7efe2f43dd6b25a176c07b729918d550f 08:59:34 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 08:59:34 INFO - mozversion INFO | platform_version: 45.0a1 08:59:34 INFO - 2015-11-19 08:59:34,854 DEBUG : using testdate: 1447952374 08:59:34 INFO - 2015-11-19 08:59:34,854 DEBUG : actual date: 1447952374 08:59:34 INFO - 2015-11-19 08:59:34,901 INFO : starting webserver on 'localhost:52798' 08:59:34 INFO - 2015-11-19 08:59:34,902 INFO : Starting test suite talos-linux64-ix-054.e 08:59:34 INFO - 2015-11-19 08:59:34,902 INFO : Starting test dromaeo_css 08:59:34 INFO - 2015-11-19 08:59:34,902 DEBUG : operating with platform_type : linux_ 08:59:34 INFO - 2015-11-19 08:59:34,903 INFO : Initialising browser for dromaeo_css test... 08:59:34 INFO - 2015-11-19 08:59:34,916 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppefWPA/profile http://localhost:52798/getInfo.html 08:59:41 INFO - 2015-11-19 08:59:41,292 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:59:41 INFO - 2015-11-19 08:59:41,293 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:59:41 INFO - 2015-11-19 08:59:41,293 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:59:41 INFO - 2015-11-19 08:59:41,293 DEBUG : BROWSER_OUTPUT: __metrics 08:59:42 INFO - 2015-11-19 08:59:42,361 INFO : Browser initialized. 08:59:42 INFO - 2015-11-19 08:59:42,362 INFO : Running cycle 1/1 for dromaeo_css test... 08:59:42 INFO - 2015-11-19 08:59:42,362 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppefWPA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:59:54 INFO - 2015-11-19 08:59:54,196 DEBUG : BROWSER_OUTPUT: RSS: Main: 141946880 08:59:54 INFO - 2015-11-19 08:59:54,196 DEBUG : BROWSER_OUTPUT: 09:00:43 INFO - 2015-11-19 09:00:43,835 DEBUG : BROWSER_OUTPUT: 1447952443833 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] 09:02:52 INFO - 2015-11-19 09:02:52,693 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52798/tests/dromaeo/cssquery-ext.html) 09:02:53 INFO - 2015-11-19 09:02:53,898 DEBUG : BROWSER_OUTPUT: RSS: Main: 147836928 09:02:53 INFO - 2015-11-19 09:02:53,898 DEBUG : BROWSER_OUTPUT: 09:05:55 INFO - 2015-11-19 09:05:55,810 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/dromaeo/cssquery-ext.html (next: http://localhost:52798/tests/dromaeo/cssquery-jquery.html) 09:05:56 INFO - 2015-11-19 09:05:56,383 DEBUG : BROWSER_OUTPUT: RSS: Main: 142192640 09:05:56 INFO - 2015-11-19 09:05:56,383 DEBUG : BROWSER_OUTPUT: 09:08:58 INFO - 2015-11-19 09:08:58,060 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52798/tests/dromaeo/cssquery-mootools.html) 09:08:58 INFO - 2015-11-19 09:08:58,646 DEBUG : BROWSER_OUTPUT: RSS: Main: 143818752 09:08:58 INFO - 2015-11-19 09:08:58,646 DEBUG : BROWSER_OUTPUT: 09:12:00 INFO - 2015-11-19 09:12:00,286 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52798/tests/dromaeo/cssquery-prototype.html) 09:12:00 INFO - 2015-11-19 09:12:00,873 DEBUG : BROWSER_OUTPUT: RSS: Main: 141471744 09:12:00 INFO - 2015-11-19 09:12:00,874 DEBUG : BROWSER_OUTPUT: 09:15:03 INFO - 2015-11-19 09:15:03,186 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52798/tests/dromaeo/cssquery-yui.html) 09:15:03 INFO - 2015-11-19 09:15:03,787 DEBUG : BROWSER_OUTPUT: RSS: Main: 142098432 09:15:03 INFO - 2015-11-19 09:15:03,787 DEBUG : BROWSER_OUTPUT: 09:18:06 INFO - 2015-11-19 09:18:06,060 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/dromaeo/cssquery-yui.html (next: http://localhost:52798/tests/dromaeo/cssquery-dojo.html) 09:18:06 INFO - 2015-11-19 09:18:06,310 DEBUG : BROWSER_OUTPUT: RSS: Main: 144359424 09:18:06 INFO - 2015-11-19 09:18:06,310 DEBUG : BROWSER_OUTPUT: 09:18:06 INFO - 2015-11-19 09:18:06,310 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:18:06 INFO - 2015-11-19 09:18:06,311 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:18:06 INFO - 2015-11-19 09:18:06,311 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:18:06 INFO - 2015-11-19 09:18:06,311 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:18:06 INFO - 2015-11-19 09:18:06,311 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3187.8406079696015;3229.1927018245437;3241.7082462578364;3271.067745692478;3277.3772983133204;5162.8193013244545;5374.543163831074;5377.731113444327;5406.810761623344;5419.593530485219;5335.306410166682;5344.038073146832;5356.785728570862;5414.756335964881;5418.485243901833;4592.7014744041535;4894.119058569448;5009.6242781791325;5015.774290156934;5029.748512574375;4811.374521312225;4914.238293064581;4956;4970.751462426882;5016.322796422491;4833.226683730604;4848.127337079324;4948.331975183341;4960.23116416956;4966.2798894160505;4904.313396124546;4920.286558449005;4929.605631549467;4947.529984651488;4996.925046124311;4780.617550595944;4861.246506791453;4918.631102667314;4925.975370123162;4998.500149985009;5073.1122053640465;5139.203423469368;5152.613553983429;5169.1212493875755;5256.290400795881;5744.741486633112;5746.25298711169;5749.59752817303;5749.965006298865;5752.827415177551;5662.801801936913;5776.220210271644;5777.133429985536;5825.997667208304;5892.734826932756;5371;5408.0806262935785;5424.887897980904;5465.262189604432;5471.097268950618;5768.019436695688;5777.393373695785;5778.624389414675;5905.675187864623;5950.047992321208;5543.750531226105;5589.74846131925;5602.271704678447;5608.579356548275;5611.887762244733;5579.246801681721;5676;5713.5714821388565;5744.454276843693;5763.510101641324;5719.399463056402;5719.571032172604;5721.199032135504;5741.770329186869;5768.9711551442815;5668.433156684381;5670.262865827499;5671.801486947937;5725.770969161187;5734.455226753452;4279.87160385189;4377;4391.297392417262;4464.308032255006;4491.88770280739;3991.1219531702977;3995.4805875236616;4006.1787333596544;4007.0383108053857;4041.454403655469;5531.446855314436;5535.806246781343;5539.612227144142;5548.750306236229;5594.524465420403;5511.255980442669;5608.298962629671;5730.111832665943;5794.652320860761;5860.677662728591;5468.343798744176;5480.177973304037;5495.697736624444;5511.669299842022;5550.69471179089;4877.731724755173;4954.232094025432;4955.083309587738;4957.876053098701;5005.924911126336;5845.181674565564;5850;5911.527077833849;5929.673867937218;5972;5801.883962320814;5847.239858818326;5851.268591426072;5853.882922341614;5854.795082172103;5795.449432303924;6008.429199226067;6013.548983826318;6027.095935609694;6028.638281703112;5622.465865742748;5688.175214593944;5720.74256658443;5750.884982300414;5795.159701843125;5794.942050579609;5806.854828629318;5822.5633077518505;5825.70871456417;5840.357560668408;5481.588880834033;5596.664200148004;5611.102223644198;5632.042552766122;5652.745626446902;5406.135018397038;5440.782368705208;5466.3986961435;5484.122540393518;5614.915776263441;5492.313460817398;5535.8892822144135;5551.666899985852;5568.888622227614;5576.470235327637;5555.638883472561;5577.581681373832;5660.773569057192;5689.743961521659;5751.45361190703;5477.178423236546;5597.356304025089;5638.2670252866865;5644.407337229696;5658.462446067617 09:18:06 INFO - 2015-11-19 09:18:06,311 DEBUG : BROWSER_OUTPUT: |1;ext.html;546.890621875625;597.5846786483395;606.0878378041048;611.3244864424813;615.8922188616991;40210;40380.394294085614;40901.70532094132;40962.59037409625;41162.79418602907;39761;40106.598934010726;40166.59833401665;41069.38395924064;41197.38203926943;8700.303975681945;8862.955685221565;8866.423682460652;8894;8914.108589141066;8601.569921503931;8671.566421678923;8726.956365218164;8760.518171500566;8769.649214031462;8656.61045252962;8692.956535217316;8753.606087726035;8755.693550725726;8774.254188393994;8700.695475658353;8784.165504277084;8796.912030879674;8803.33974951878;8805.6037478313;8632.179942905414;8685.522296273703;8762.904636920384;8775.341849361259;8786.956065219696;39609;39790.40314395286;40071;40295.59704402948;40329.596704033174;3199.2161920329436;3224.6452890182063;3229.580154579913;3268.5587445694764;3320.9833950830334;29240.268993275127;29389;29439.558406623917;29473.85263073671;29651.55522667162;3380.154961259685;3449.6550344965553;3472.982635086834;3544.5392099027217;3581.5344005279408;268356;268528;270513;271088;271481;41220.58779412227;56953.430465695645;57071;57106.7144664274;57174.714126429935;36304.45543316852;36765.632343676756;36904.630953690656;37235.62764372376;37296.321665525116;14521;14569.198694071716;14625.92687036558;14669.266536673336;14669.706605867823;11774.587889423829;11811.52753889852;11843.822342664867;11854.407279635983;11858.525658973716;13806.792898106336;13896.444142234197;14083.71832563343;14167.64580885486;14307.427702891768;5771.278590176228;5849;5855.736491857877;5876.177335173079;5885.117232415087;993.2451336983944;1044.8119338519139;1063.4363787192801;1081.507913899174;1123.4663534820895;28848.42303153967;28849.27876803055;32941;33568.83215583907;33740.831295843855;1029.27950434696;1039.8024375368657;1062.2617280989793;1081.6268387406333;1150.511032811052;14676.853231467761;14799;15011.624709382135;15124.924375378272;15224.46714364992;385986;387265;389795;390650;390777;51970.740146299024;52631;52716.20925686118;52930.20604690933;52990;1367.453018792471;1371.1772936238178;1373.5810577773875;1374.8144000559796;1397.7274431485762;26872.865635671697;28497.857510712314;28735.425291493633;28896.133116006553;29054;45064.49355064467;45384.54615453803;45621;45886.77056614829;45991;295094;296313;299777;301157;301180.4940975281;31701.20746981344;45957.77021114873;46174.30738538992;46338.073238534365;46935;44398;44834.10331793281;45048.324275136554;45379.773101134284;45860.08279834318;36783.63216367802;37423.25153496969;37547.62452375441;37679.24641507209;38892.80553597214;37513.624863751014;38039;38255.617443825206;38607.22785544329;41805.163896721286;36591.08522286964;37118.072048199014;37275.44086838754;37453.81273093726;37619.81190094032;31200;32246.516302255954;32894.67105328916;33621;33642.49536256909;25698;25887.352816178995;25976.8701156493;25980.8700956494;26292.21123366302 09:18:06 INFO - 2015-11-19 09:18:06,311 DEBUG : BROWSER_OUTPUT: |2;jquery.html;353.55805243445695;359.70504186567024;361.2089523942566;361.40548797228575;362.2772568725392;12029.639110826667;12407.131500794949;16488.505344839657;16906;17925.91037044816;11352.262102963323;11357.545698172064;11454.370009649467;11597.188196826204;11812.82280765787;4358.193734159175;4485.080558485519;4486.573775491323;4486.910261794762;4498.190325741365;4527.253003254459;4724.97637511812;4866.31871537985;4894.143524883149;4894.706317620937;4604.608608268301;4706.505816889228;4737.952620473786;4817.879553011167;4857.611391088704;4685.83599574015;4723.149833030053;4743.1225223333795;4761.928571071437;4766.523347665223;4188.958110418887;4282.42187304713;4302.41917341158;4306.44016277885;4311.353297005474;12938.935305323508;12939.094263401566;13200.273984930922;13581.524646637321;13702.314884255848;1854;1934.6711059119843;1951.2097600471832;1988.5625162464235;2051.107768120872;9794.853077203848;9808.803823923552;9817.5582098805;9874.111329980336;10144.746381340452;1647.4810434713147;1659.585103724069;1694.5848267174497;1735.201807168704;1742.8280529436431;61394;61571;61690.38309616891;61854;63006;6807.421369183576;6974.163100427981;7010.158780946217;7014.19336776267;7014.263502332284;8166.265036146775;8174.468659537111;8191.95904020488;8220.219079187467;8258;5485.396606373296;5519.972400137974;5527.143292789624;5551.611387202938;5551.9722401387735;5643.294588176478;5650.350209725934;5656.236408084938;5671.716414179357;5679;3214.9357012860078;3217.5978002749657;3226.532152837872;3229.3379857129703;3234;1134.0247387246961;1141.8058929981923;1143.3540049871797;1152.8962393384636;1196.4017991004498;389.4703203643043;393.6889857012985;399.2115571745837;401.46605015329544;412.99587004130177;9792.412455252708;10525.9473702631;10530.63142790014;10613.25707200504;10658.467076646137;408.15104582468393;420.9284421648327;426.76101383225495;430.03671775223273;432.13788491958746;5545.889082218333;5731;5734.39788822176;5794.449527294901;5821.09772985188;536386;536439;537189;547191;551392;68057;74357.6282118586;74566.6271668649;75298.24701752912;78640.21359786329;513.8252993982064;528.8545649946295;529.1348644965432;530.376807251478;531.7102179312207;10023;10445;10455.372677639367;10467.738306542398;10615.628453004108;24745.62881556742;28481;32964.835175823966;33181.50227746634;35964.28071438609;43748.78125609351;44255.77872110619;47255.763721181174;48448;49095.50904491052;6488.643124628191;6591.769288074895;6699.832504187434;6704.128463299739;6727.394534491919;11803.23278986863;11910.642680719593;11948.223365481215;12017.519299227934;12103.455344509694;10213.438260895722;10249.282550221413;10472.057514823398;10592.940705929346;10758.677239682822;9845.113939745457;9903.27659893535;10024.298299118991;10298.485075746034;10316.690499285034;10367.63713270032;10627.149828013586;10676.252662313564;10723.839142412715;10786.784264314512;6692.029655699994;6820.931790682228;6850.794476165723;6854.177498700188;6855.5543889647015;10151.238657100599;10423.061924426534;10491.160707143257;10513.632022879468;10651.840222396515 09:18:06 INFO - 2015-11-19 09:18:06,313 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1453.048253394026;1691.5432833135053;1735.4273089880342;1749.7987731410883;1783.1619139004667;6446;6626.569272997252;7497.662605182766;7654.349380302681;7678.539287642745;7201.4238860891;7385.113786345632;7414.406847452205;7482.850342993137;7507.512011719248;2241.574100920824;2289.9656505152434;2294.4837411582425;2294.9541009179807;2304.83866129371;2291.3355127013147;2321.558903808271;2339.6139636959965;2340.8127349811975;2341.203990643181;2231.0518029837335;2245.752967173618;2269.500709843832;2274.3290729234914;2278.031836469502;2237.742659594151;2259.4238469190414;2274.590573696734;2289.198780426854;2293.850899691523;2301.9526115617355;2324.4886125052462;2336.1122773346187;2342.3909783456425;2355.787979081891;7441.888371674429;7488.3260506554125;7508.624568771595;7518.887216691754;7545.924540754577;4450.043240703232;4505.797239124215;4574.702644328108;4599.425071866017;4615.446146462413;3128.7809853310278;3138;3140.4975203967256;3148.6693897140926;3156.589643346373;1005.7938122684832;1011.858339832424;1023.5854478936043;1031.7884833609091;1073.7154654016692;10107.595696172144;10121;10200.13298869597;10230.795384092351;10233.385996840214;4181.560936101726;4188.120494696087;4258.318669012943;4277.721948073366;4305.225059489259;9023.909760902437;9031.187193152648;9050.4117232381;9054.502002389932;9058.456492610465;4657.5575320344515;4665;4705.12955103307;4723.7638118093955;4731.053789242165;4255.76593287366;4291.184674911757;4297.957020429818;4306.784660766949;4316.201502722007;3548.929021419557;3570.8750193743613;3572.392693242103;3582.802945837952;3627.0025742920907;1130.6042884990188;1143.5254369436757;1146.529922731676;1148.9827652585218;1178.7760325538293;1018.9388636681823;1045.0490054050777;1052.6210564196883;1204.27141579345;1369.1784929042694;6933.3066693331275;7075.115610548682;7083.972823940499;7088.539244949062;7098.822529436806;460.29344955493156;462.9027904140101;465.9836905708284;468.14797069333486;469.5984932882405;5465.781368745285;5512.310961129859;5535.806246781343;5577.721113944286;5691.630044047124;8834.072422395684;8850.513221772735;8922.464652120894;8929.10708929102;9073.092690731006;8305.044919834176;8741.737747867446;8787.340949428819;8839.116088391109;8891.155340242667;7051.753188638476;7054.576725396492;7084.716611335592;7109.715611375591;7151.53515021522;6889.965550172217;6919.792406227822;6992.720291188295;7015.859682806214;7021.719131234693;7629.427792915664;7813.41399395059;7835.05979282467;7892.0924093729955;7898.565578893216;7962.203779621991;8007.919920800718;8059.194080592129;8132.105468398353;8190.83618327642;6623.337666233531;6657.73369065232;6669.933300666931;6670.232923213893;6689.732410703712;7249.202587715453;7254.45591580623;7340.339369456775;7342.5227360221415;7356.485046046725;6745.730170793114;6862.794116176523;6870.656467176521;6918.481113916376;6943.444524438134;6544.901826472702;6573.638449885302;6621.370969757865;6626.734930602722;6686.531942764154;6830.351116644109;6838.213605435439;6847.452203823782;6852.931470685229;6867;6252.905741495311;6274.372562743689;6317.747290108344;6320.209973753281;6327.90508142369;5951.315598706205;5952.464278214981;6030.879382412415;6050.818475445743;6081.087836824335 09:18:06 INFO - 2015-11-19 09:18:06,316 DEBUG : BROWSER_OUTPUT: |4;prototype.html;315.142811552577;316.1006935269159;321.1809884793775;323.9271163988103;324.67045948362403;6218.502519798417;6295.244570651528;6387.936120638792;6395.2325720913495;6466.708998095085;6194.7212375443105;6292.961661325887;6326.525510586702;6328.3671632836695;6336.017917222826;4557.931631025537;4681.74250416227;4704.247320428732;4708.788104535295;4720.480747117815;4657.976710116462;4681.227597446417;4696.107739529495;4704.976475117637;4708.7645617719145;4618.937644341803;4633.09654617348;4648.697834640738;4659.254519276917;4670.439547254335;4673.556012178855;4674.509176536466;4676.7193968361835;4677.134730074913;4680.7191568506;3898.8245528951265;3997.920041599152;4011.0574015106426;4021.034951611621;4022.3363145081025;5927.932972065027;5930.55520835939;5935.080062590306;5999.13012613173;6006.429389208019;5432.91850622241;5436.048691478975;5442.2380866678695;5445.455454454563;5451.04606693827;6625.436837868739;6631.568948018364;6632.43624291931;6633.2703402625675;6641.966790166019;4304.870853874389;4391.780410979471;4396.20868243716;4424.7123936944;4440.000999775057;6861.210960739479;6897.068895699117;6936.687849046754;6939.757108501179;6956.060931774196;4494.685372023992;4496.662750293741;4506.571875671806;4508.18852606534;4519.864404067883;4488.304312831517;4573.885652858638;4576.061907308994;4579.6794224403975;4581.037982023746;5403.378611459654;5408.242846001563;5409.323834520685;5412.91880621791;5424.213489044067;6732.057511948331;6732.562383445158;6743.662816859138;6754.797356079325;6755.49333799967;1611.8629916457232;1628.0557276779439;1629.7310943694395;1630.1767607358208;1631.3963833381724;1050.632278702448;1052.8315469524991;1053.0206907576028;1061.9150467962545;1080.821664425361;6465.806025819233;6476.190476190476;6477.449416799531;6517.478601711851;6595.340465953463;6994.860102797916;7019.0875186226485;7038.120234970629;7048.259932706992;7060;6542.607443553402;6546.279909209983;6547.1161393211305;6551.868962620721;6585.539012269095;3750.868719594801;3784.2053168834304;3821.9235615288094;3822.560405553397;3824.101336185994;7248.927510724825;7270.163931147986;7284.417246620258;7296.051513303237;7326.816829579197;7167.534110282815;7172.426205903515;7184.137903451619;7207.783766487014;7222.169450513258;7131.857362852714;7279.672414741243;7293.124825021137;7296.525725827911;7326.157491888501;6984.860302794017;6986.336298051677;6988.4059854910865;6999.860002800017;7001.194862590867;6903.1716194055025;6941.895871561829;6957.130358705162;6961.756338528127;6962.338577835098;7154.785356439315;7287.526310789782;7309.086364204474;7325.706971721072;7326.3406293433845;4533.342665313577;4568.0407114505;4579.908401832011;4584.15193189261;4584.862454126382;6936.826579335557;6989.965050174716;7029.156501219886;7030.2969703029285;7038.225795162426;6645.800625981228;6751.628660423724;6753.3246675332075;6753.459723222229;6808.251092379735;6754.2570317266045;6760.966195168992;6761.695723692544;6768.390844823989;6775.390214880685;6527;6589.0775291460195;6639.203295604559;6644.302348253364;6653.933460665331;6584.242812076673;6703.363180497845;6774.119364482585;6789;6834.487413443912;6548.869022619616;6565.934340656723;6568.9014664781025;6616.66916654175;6633.170853643294 09:18:06 INFO - 2015-11-19 09:18:06,316 DEBUG : BROWSER_OUTPUT: |5;yui.html;733.9082614673166;814.9470284431511;848.3298194426402;864.0279685353977;869.8434281829276;13645.727085458286;13717.588472345833;13899.235542045184;13947.581572552826;13960.371783269751;13230.27233502157;13264;13268.33658317085;13301;13310.667233319173;789.6999140326668;807.3944541593804;809.4576633655436;809.522381794741;811.1685522339619;795.5743677132735;803.1727320859524;805.5086397297644;806.4838503357827;806.8587997100514;852.2925971443718;852.6078004118082;852.8336974290016;853.3173461231021;853.3343991686495;840.3319361107934;841.8190089130836;841.9915800842005;842.7935155886817;848.2577744473585;612.5038718637918;616.3897741236188;621.139721485741;622.9688515574248;624.2072567838865;13440;13456.058075934689;13493.730125397535;13557.796633050511;13590.456381744718;10065.748356291078;10096.394216347042;10160.59357625694;10388.428636424993;10526.684199474028;9184.908150918473;9238.149971265073;9300.8604870927;9383.296252781069;9444.150026497648;3110.377924415126;3114.2986366867044;3116.579261799651;3159.510275907238;3223.3069889973767;44594.33108503375;45184.322235166495;45213.77393113046;45229.54770452286;45365;7232.92767072333;7293.416526677853;7315.268473152642;7337.003824671852;7449.366803821736;11904.583339583247;11912.821307680391;11949.163558550817;11959.40202989847;11976;6031.758729650853;6043.425874541911;6110.511159107261;6243.531735119885;6299.622022678563;3819.0834199792407;3987.5813039631003;4054.3107671695884;4061.1268577256033;4117.155983023473;2668.21287720123;2671.1852884869913;2673.625692403065;2683.141394753699;2692.111603170949;412.9050318426779;421.6710965446956;421.6879509163197;426.28597099857615;427.6749670250632;447.1638036871007;528.4292963599304;572.7656899381839;645.8837409266283;663.0650782396893;8642.30861531076;8814.955925220462;8926.152015558511;8931.240844528287;9079.977447248004;696.3976160621027;708.373089815517;711.6904146696204;714.370995343653;718.9568625882464;3920.4707364506;3966;4375.212461756851;4472.1502914446155;4567.1550763108935;56276;56385.43614563802;56396.71801641048;56985.715071424376;57484.425155748744;50712.49287507078;51272.74363628233;51643.64316319427;52182.21726674103;53714.731426342616;50050.24924626134;50572.747136264086;50808.49191508112;50866.491335086175;51080.74459627678;8827.117288271122;9058.230050445785;9309;9380.822135014192;9411.152996230372;34939.30121397608;35423;35853;35935.82032089823;36655.08362290964;40950.59049409587;43720.78139609282;44972.10055798931;44984.32523512084;45412.77293613643;10263.819660739111;10782.838257425989;10878.40168790724;11186.440677966231;11615.477303521193;12059.75880482403;12195.817062743889;12227.266364018187;12731.363431828186;12985.155964862253;11462.484188211383;11650.941745291218;11839.881601183877;12162.087843411957;12206.816897746363;11693.590724324607;11926.940365298118;12035.879641203475;12279.324637145042;12407.44166512491;11587.768244634892;11759.294442333487;11900;11924.284542927453;11958.46186921608;9958.15355694774;10117.797644047225;10246.282760206714;10263.897361026295;10301.896981030093;9161.175494205554;9659.565319560495;10211.948940255252;10217.33587316822;10421.270511064153 09:18:06 INFO - 2015-11-19 09:18:06,317 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:18:06 INFO - 2015-11-19 09:18:06,317 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:18:06 INFO - 2015-11-19 09:18:06,317 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,281 09:18:06 INFO - 2015-11-19 09:18:06,317 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:18:06 INFO - 2015-11-19 09:18:06,317 DEBUG : BROWSER_OUTPUT: __startTimestamp1447953486307__endTimestamp 09:18:06 INFO - 2015-11-19 09:18:06,317 DEBUG : BROWSER_OUTPUT: 09:18:06 INFO - 2015-11-19 09:18:06,318 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:18:06 INFO - 2015-11-19 09:18:06,318 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:18:06 INFO - 2015-11-19 09:18:06,318 DEBUG : BROWSER_OUTPUT: 09:18:06 INFO - 2015-11-19 09:18:06,318 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5347.34 Median:5548.75 stddev:584.12 (10.5%) stddev-sans-first:560.80 09:18:06 INFO - 2015-11-19 09:18:06,319 DEBUG : BROWSER_OUTPUT: Values: 3187.8 3229.2 3241.7 3271.1 3277.4 5162.8 5374.5 5377.7 5406.8 5419.6 5335.3 5344.0 5356.8 5414.8 5418.5 4592.7 4894.1 5009.6 5015.8 5029.7 4811.4 4914.2 4956.0 4970.8 5016.3 4833.2 4848.1 4948.3 4960.2 4966.3 4904.3 4920.3 4929.6 4947.5 4996.9 4780.6 4861.2 4918.6 4926.0 4998.5 5073.1 5139.2 5152.6 5169.1 5256.3 5744.7 5746.3 5749.6 5750.0 5752.8 5662.8 5776.2 5777.1 5826.0 5892.7 5371.0 5408.1 5424.9 5465.3 5471.1 5768.0 5777.4 5778.6 5905.7 5950.0 5543.8 5589.7 5602.3 5608.6 5611.9 5579.2 5676.0 5713.6 5744.5 5763.5 5719.4 5719.6 5721.2 5741.8 5769.0 5668.4 5670.3 5671.8 5725.8 5734.5 4279.9 4377.0 4391.3 4464.3 4491.9 3991.1 3995.5 4006.2 4007.0 4041.5 5531.4 5535.8 5539.6 5548.8 5594.5 5511.3 5608.3 5730.1 5794.7 5860.7 5468.3 5480.2 5495.7 5511.7 5550.7 4877.7 4954.2 4955.1 4957.9 5005.9 5845.2 5850.0 5911.5 5929.7 5972.0 5801.9 5847.2 5851.3 5853.9 5854.8 5795.4 6008.4 6013.5 6027.1 6028.6 5622.5 5688.2 5720.7 5750.9 5795.2 5794.9 5806.9 5822.6 5825.7 5840.4 5481.6 5596.7 5611.1 5632.0 5652.7 5406.1 5440.8 5466.4 5484.1 5614.9 5492.3 5535.9 5551.7 5568.9 5576.5 5555.6 5577.6 5660.8 5689.7 5751.5 5477.2 5597.4 5638.3 5644.4 5658.5 09:18:06 INFO - 2015-11-19 09:18:06,319 DEBUG : BROWSER_OUTPUT: 09:18:06 INFO - 2015-11-19 09:18:06,319 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48170.77 Median:37483.72 stddev:84860.19 (226.4%) stddev-sans-first:85022.91 09:18:06 INFO - 2015-11-19 09:18:06,320 DEBUG : BROWSER_OUTPUT: Values: 546.9 597.6 606.1 611.3 615.9 40210.0 40380.4 40901.7 40962.6 41162.8 39761.0 40106.6 40166.6 41069.4 41197.4 8700.3 8863.0 8866.4 8894.0 8914.1 8601.6 8671.6 8727.0 8760.5 8769.6 8656.6 8693.0 8753.6 8755.7 8774.3 8700.7 8784.2 8796.9 8803.3 8805.6 8632.2 8685.5 8762.9 8775.3 8787.0 39609.0 39790.4 40071.0 40295.6 40329.6 3199.2 3224.6 3229.6 3268.6 3321.0 29240.3 29389.0 29439.6 29473.9 29651.6 3380.2 3449.7 3473.0 3544.5 3581.5 268356.0 268528.0 270513.0 271088.0 271481.0 41220.6 56953.4 57071.0 57106.7 57174.7 36304.5 36765.6 36904.6 37235.6 37296.3 14521.0 14569.2 14625.9 14669.3 14669.7 11774.6 11811.5 11843.8 11854.4 11858.5 13806.8 13896.4 14083.7 14167.6 14307.4 5771.3 5849.0 5855.7 5876.2 5885.1 993.2 1044.8 1063.4 1081.5 1123.5 28848.4 28849.3 32941.0 33568.8 33740.8 1029.3 1039.8 1062.3 1081.6 1150.5 14676.9 14799.0 15011.6 15124.9 15224.5 385986.0 387265.0 389795.0 390650.0 390777.0 51970.7 52631.0 52716.2 52930.2 52990.0 1367.5 1371.2 1373.6 1374.8 1397.7 26872.9 28497.9 28735.4 28896.1 29054.0 45064.5 45384.5 45621.0 45886.8 45991.0 295094.0 296313.0 299777.0 301157.0 301180.5 31701.2 45957.8 46174.3 46338.1 46935.0 44398.0 44834.1 45048.3 45379.8 45860.1 36783.6 37423.3 37547.6 37679.2 38892.8 37513.6 38039.0 38255.6 38607.2 41805.2 36591.1 37118.1 37275.4 37453.8 37619.8 31200.0 32246.5 32894.7 33621.0 33642.5 25698.0 25887.4 25976.9 25980.9 26292.2 09:18:06 INFO - 2015-11-19 09:18:06,320 DEBUG : BROWSER_OUTPUT: 09:18:06 INFO - 2015-11-19 09:18:06,320 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26609.45 Median:4308.90 stddev:88811.69 (2061.1%) stddev-sans-first:89038.94 09:18:06 INFO - 2015-11-19 09:18:06,320 DEBUG : BROWSER_OUTPUT: Values: 353.6 359.7 361.2 361.4 362.3 12029.6 12407.1 16488.5 16906.0 17925.9 11352.3 11357.5 11454.4 11597.2 11812.8 4358.2 4485.1 4486.6 4486.9 4498.2 4527.3 4725.0 4866.3 4894.1 4894.7 4604.6 4706.5 4738.0 4817.9 4857.6 4685.8 4723.1 4743.1 4761.9 4766.5 4189.0 4282.4 4302.4 4306.4 4311.4 12938.9 12939.1 13200.3 13581.5 13702.3 1854.0 1934.7 1951.2 1988.6 2051.1 9794.9 9808.8 9817.6 9874.1 10144.7 1647.5 1659.6 1694.6 1735.2 1742.8 61394.0 61571.0 61690.4 61854.0 63006.0 6807.4 6974.2 7010.2 7014.2 7014.3 8166.3 8174.5 8192.0 8220.2 8258.0 5485.4 5520.0 5527.1 5551.6 5552.0 5643.3 5650.4 5656.2 5671.7 5679.0 3214.9 3217.6 3226.5 3229.3 3234.0 1134.0 1141.8 1143.4 1152.9 1196.4 389.5 393.7 399.2 401.5 413.0 9792.4 10525.9 10530.6 10613.3 10658.5 408.2 420.9 426.8 430.0 432.1 5545.9 5731.0 5734.4 5794.4 5821.1 536386.0 536439.0 537189.0 547191.0 551392.0 68057.0 74357.6 74566.6 75298.2 78640.2 513.8 528.9 529.1 530.4 531.7 10023.0 10445.0 10455.4 10467.7 10615.6 24745.6 28481.0 32964.8 33181.5 35964.3 43748.8 44255.8 47255.8 48448.0 49095.5 6488.6 6591.8 6699.8 6704.1 6727.4 11803.2 11910.6 11948.2 12017.5 12103.5 10213.4 10249.3 10472.1 10592.9 10758.7 9845.1 9903.3 10024.3 10298.5 10316.7 10367.6 10627.1 10676.3 10723.8 10786.8 6692.0 6820.9 6850.8 6854.2 6855.6 10151.2 10423.1 10491.2 10513.6 10651.8 09:18:06 INFO - 2015-11-19 09:18:06,320 DEBUG : BROWSER_OUTPUT: 09:18:06 INFO - 2015-11-19 09:18:06,320 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5265.13 Median:5951.89 stddev:2684.15 (45.1%) stddev-sans-first:2676.39 09:18:06 INFO - 2015-11-19 09:18:06,321 DEBUG : BROWSER_OUTPUT: Values: 1453.0 1691.5 1735.4 1749.8 1783.2 6446.0 6626.6 7497.7 7654.3 7678.5 7201.4 7385.1 7414.4 7482.9 7507.5 2241.6 2290.0 2294.5 2295.0 2304.8 2291.3 2321.6 2339.6 2340.8 2341.2 2231.1 2245.8 2269.5 2274.3 2278.0 2237.7 2259.4 2274.6 2289.2 2293.9 2302.0 2324.5 2336.1 2342.4 2355.8 7441.9 7488.3 7508.6 7518.9 7545.9 4450.0 4505.8 4574.7 4599.4 4615.4 3128.8 3138.0 3140.5 3148.7 3156.6 1005.8 1011.9 1023.6 1031.8 1073.7 10107.6 10121.0 10200.1 10230.8 10233.4 4181.6 4188.1 4258.3 4277.7 4305.2 9023.9 9031.2 9050.4 9054.5 9058.5 4657.6 4665.0 4705.1 4723.8 4731.1 4255.8 4291.2 4298.0 4306.8 4316.2 3548.9 3570.9 3572.4 3582.8 3627.0 1130.6 1143.5 1146.5 1149.0 1178.8 1018.9 1045.0 1052.6 1204.3 1369.2 6933.3 7075.1 7084.0 7088.5 7098.8 460.3 462.9 466.0 468.1 469.6 5465.8 5512.3 5535.8 5577.7 5691.6 8834.1 8850.5 8922.5 8929.1 9073.1 8305.0 8741.7 8787.3 8839.1 8891.2 7051.8 7054.6 7084.7 7109.7 7151.5 6890.0 6919.8 6992.7 7015.9 7021.7 7629.4 7813.4 7835.1 7892.1 7898.6 7962.2 8007.9 8059.2 8132.1 8190.8 6623.3 6657.7 6669.9 6670.2 6689.7 7249.2 7254.5 7340.3 7342.5 7356.5 6745.7 6862.8 6870.7 6918.5 6943.4 6544.9 6573.6 6621.4 6626.7 6686.5 6830.4 6838.2 6847.5 6852.9 6867.0 6252.9 6274.4 6317.7 6320.2 6327.9 5951.3 5952.5 6030.9 6050.8 6081.1 09:18:06 INFO - 2015-11-19 09:18:06,321 DEBUG : BROWSER_OUTPUT: 09:18:06 INFO - 2015-11-19 09:18:06,322 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5576.83 Median:6471.45 stddev:1754.23 (27.1%) stddev-sans-first:1714.12 09:18:06 INFO - 2015-11-19 09:18:06,322 DEBUG : BROWSER_OUTPUT: Values: 315.1 316.1 321.2 323.9 324.7 6218.5 6295.2 6387.9 6395.2 6466.7 6194.7 6293.0 6326.5 6328.4 6336.0 4557.9 4681.7 4704.2 4708.8 4720.5 4658.0 4681.2 4696.1 4705.0 4708.8 4618.9 4633.1 4648.7 4659.3 4670.4 4673.6 4674.5 4676.7 4677.1 4680.7 3898.8 3997.9 4011.1 4021.0 4022.3 5927.9 5930.6 5935.1 5999.1 6006.4 5432.9 5436.0 5442.2 5445.5 5451.0 6625.4 6631.6 6632.4 6633.3 6642.0 4304.9 4391.8 4396.2 4424.7 4440.0 6861.2 6897.1 6936.7 6939.8 6956.1 4494.7 4496.7 4506.6 4508.2 4519.9 4488.3 4573.9 4576.1 4579.7 4581.0 5403.4 5408.2 5409.3 5412.9 5424.2 6732.1 6732.6 6743.7 6754.8 6755.5 1611.9 1628.1 1629.7 1630.2 1631.4 1050.6 1052.8 1053.0 1061.9 1080.8 6465.8 6476.2 6477.4 6517.5 6595.3 6994.9 7019.1 7038.1 7048.3 7060.0 6542.6 6546.3 6547.1 6551.9 6585.5 3750.9 3784.2 3821.9 3822.6 3824.1 7248.9 7270.2 7284.4 7296.1 7326.8 7167.5 7172.4 7184.1 7207.8 7222.2 7131.9 7279.7 7293.1 7296.5 7326.2 6984.9 6986.3 6988.4 6999.9 7001.2 6903.2 6941.9 6957.1 6961.8 6962.3 7154.8 7287.5 7309.1 7325.7 7326.3 4533.3 4568.0 4579.9 4584.2 4584.9 6936.8 6990.0 7029.2 7030.3 7038.2 6645.8 6751.6 6753.3 6753.5 6808.3 6754.3 6761.0 6761.7 6768.4 6775.4 6527.0 6589.1 6639.2 6644.3 6653.9 6584.2 6703.4 6774.1 6789.0 6834.5 6548.9 6565.9 6568.9 6616.7 6633.2 09:18:06 INFO - 2015-11-19 09:18:06,322 DEBUG : BROWSER_OUTPUT: 09:18:06 INFO - 2015-11-19 09:18:06,322 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13545.45 Median:44978.21 stddev:16054.53 (35.7%) stddev-sans-first:16070.74 09:18:06 INFO - 2015-11-19 09:18:06,323 DEBUG : BROWSER_OUTPUT: Values: 733.9 814.9 848.3 864.0 869.8 13645.7 13717.6 13899.2 13947.6 13960.4 13230.3 13264.0 13268.3 13301.0 13310.7 789.7 807.4 809.5 809.5 811.2 795.6 803.2 805.5 806.5 806.9 852.3 852.6 852.8 853.3 853.3 840.3 841.8 842.0 842.8 848.3 612.5 616.4 621.1 623.0 624.2 13440.0 13456.1 13493.7 13557.8 13590.5 10065.7 10096.4 10160.6 10388.4 10526.7 9184.9 9238.1 9300.9 9383.3 9444.2 3110.4 3114.3 3116.6 3159.5 3223.3 44594.3 45184.3 45213.8 45229.5 45365.0 7232.9 7293.4 7315.3 7337.0 7449.4 11904.6 11912.8 11949.2 11959.4 11976.0 6031.8 6043.4 6110.5 6243.5 6299.6 3819.1 3987.6 4054.3 4061.1 4117.2 2668.2 2671.2 2673.6 2683.1 2692.1 412.9 421.7 421.7 426.3 427.7 447.2 528.4 572.8 645.9 663.1 8642.3 8815.0 8926.2 8931.2 9080.0 696.4 708.4 711.7 714.4 719.0 3920.5 3966.0 4375.2 4472.2 4567.2 56276.0 56385.4 56396.7 56985.7 57484.4 50712.5 51272.7 51643.6 52182.2 53714.7 50050.2 50572.7 50808.5 50866.5 51080.7 8827.1 9058.2 9309.0 9380.8 9411.2 34939.3 35423.0 35853.0 35935.8 36655.1 40950.6 43720.8 44972.1 44984.3 45412.8 10263.8 10782.8 10878.4 11186.4 11615.5 12059.8 12195.8 12227.3 12731.4 12985.2 11462.5 11650.9 11839.9 12162.1 12206.8 11693.6 11926.9 12035.9 12279.3 12407.4 11587.8 11759.3 11900.0 11924.3 11958.5 9958.2 10117.8 10246.3 10263.9 10301.9 9161.2 9659.6 10211.9 10217.3 10421.3 09:18:06 INFO - 2015-11-19 09:18:06,323 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:18:06 INFO - 2015-11-19 09:18:06,323 DEBUG : BROWSER_OUTPUT: 09:18:06 INFO - 2015-11-19 09:18:06,985 INFO : Browser exited with error code: 0 09:18:07 INFO - 2015-11-19 09:18:07,009 INFO : Completed test dromaeo_css (00:18:32) 09:18:07 INFO - 2015-11-19 09:18:07,009 INFO : Starting test kraken 09:18:07 INFO - 2015-11-19 09:18:07,009 DEBUG : operating with platform_type : linux_ 09:18:07 INFO - 2015-11-19 09:18:07,009 INFO : Initialising browser for kraken test... 09:18:07 INFO - 2015-11-19 09:18:07,015 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiY8NqK/profile http://localhost:52798/getInfo.html 09:18:11 INFO - 2015-11-19 09:18:11,824 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:18:11 INFO - 2015-11-19 09:18:11,824 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:18:11 INFO - 2015-11-19 09:18:11,824 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:18:11 INFO - 2015-11-19 09:18:11,824 DEBUG : BROWSER_OUTPUT: __metrics 09:18:12 INFO - 2015-11-19 09:18:12,889 INFO : Browser initialized. 09:18:12 INFO - 2015-11-19 09:18:12,890 INFO : Running cycle 1/1 for kraken test... 09:18:12 INFO - 2015-11-19 09:18:12,890 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiY8NqK/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:18:24 INFO - 2015-11-19 09:18:24,657 DEBUG : BROWSER_OUTPUT: RSS: Main: 141873152 09:18:24 INFO - 2015-11-19 09:18:24,658 DEBUG : BROWSER_OUTPUT: 09:18:27 INFO - 2015-11-19 09:18:27,117 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52798/tests/kraken/driver.html?testName=audio-beat-detection) 09:18:27 INFO - 2015-11-19 09:18:27,723 DEBUG : BROWSER_OUTPUT: RSS: Main: 141463552 09:18:27 INFO - 2015-11-19 09:18:27,723 DEBUG : BROWSER_OUTPUT: 09:18:30 INFO - 2015-11-19 09:18:30,392 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52798/tests/kraken/driver.html?testName=audio-dft) 09:18:30 INFO - 2015-11-19 09:18:30,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 141369344 09:18:30 INFO - 2015-11-19 09:18:30,994 DEBUG : BROWSER_OUTPUT: 09:18:33 INFO - 2015-11-19 09:18:33,859 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52798/tests/kraken/driver.html?testName=audio-fft) 09:18:34 INFO - 2015-11-19 09:18:34,440 DEBUG : BROWSER_OUTPUT: RSS: Main: 137326592 09:18:34 INFO - 2015-11-19 09:18:34,440 DEBUG : BROWSER_OUTPUT: 09:18:36 INFO - 2015-11-19 09:18:36,412 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52798/tests/kraken/driver.html?testName=audio-oscillator) 09:18:36 INFO - 2015-11-19 09:18:36,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 135913472 09:18:36 INFO - 2015-11-19 09:18:36,994 DEBUG : BROWSER_OUTPUT: 09:18:39 INFO - 2015-11-19 09:18:39,147 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52798/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:18:39 INFO - 2015-11-19 09:18:39,721 DEBUG : BROWSER_OUTPUT: RSS: Main: 137170944 09:18:39 INFO - 2015-11-19 09:18:39,721 DEBUG : BROWSER_OUTPUT: 09:18:42 INFO - 2015-11-19 09:18:42,720 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52798/tests/kraken/driver.html?testName=imaging-darkroom) 09:18:43 INFO - 2015-11-19 09:18:43,307 DEBUG : BROWSER_OUTPUT: RSS: Main: 137576448 09:18:43 INFO - 2015-11-19 09:18:43,307 DEBUG : BROWSER_OUTPUT: 09:18:46 INFO - 2015-11-19 09:18:46,729 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52798/tests/kraken/driver.html?testName=imaging-desaturate) 09:18:47 INFO - 2015-11-19 09:18:47,316 DEBUG : BROWSER_OUTPUT: RSS: Main: 138596352 09:18:47 INFO - 2015-11-19 09:18:47,316 DEBUG : BROWSER_OUTPUT: 09:18:50 INFO - 2015-11-19 09:18:50,122 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52798/tests/kraken/driver.html?testName=json-parse-financial) 09:18:50 INFO - 2015-11-19 09:18:50,709 DEBUG : BROWSER_OUTPUT: RSS: Main: 139522048 09:18:50 INFO - 2015-11-19 09:18:50,710 DEBUG : BROWSER_OUTPUT: 09:18:52 INFO - 2015-11-19 09:18:52,318 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52798/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:18:52 INFO - 2015-11-19 09:18:52,900 DEBUG : BROWSER_OUTPUT: RSS: Main: 138854400 09:18:52 INFO - 2015-11-19 09:18:52,900 DEBUG : BROWSER_OUTPUT: 09:18:54 INFO - 2015-11-19 09:18:54,738 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52798/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:18:55 INFO - 2015-11-19 09:18:55,360 DEBUG : BROWSER_OUTPUT: RSS: Main: 139767808 09:18:55 INFO - 2015-11-19 09:18:55,360 DEBUG : BROWSER_OUTPUT: 09:18:57 INFO - 2015-11-19 09:18:57,432 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52798/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:18:58 INFO - 2015-11-19 09:18:58,013 DEBUG : BROWSER_OUTPUT: RSS: Main: 139935744 09:18:58 INFO - 2015-11-19 09:18:58,013 DEBUG : BROWSER_OUTPUT: 09:19:00 INFO - 2015-11-19 09:19:00,515 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52798/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:19:01 INFO - 2015-11-19 09:19:01,116 DEBUG : BROWSER_OUTPUT: RSS: Main: 140173312 09:19:01 INFO - 2015-11-19 09:19:01,116 DEBUG : BROWSER_OUTPUT: 09:19:03 INFO - 2015-11-19 09:19:03,718 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52798/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:19:04 INFO - 2015-11-19 09:19:04,311 DEBUG : BROWSER_OUTPUT: RSS: Main: 139608064 09:19:04 INFO - 2015-11-19 09:19:04,312 DEBUG : BROWSER_OUTPUT: 09:19:05 INFO - 2015-11-19 09:19:05,985 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52798/tests/kraken/driver.html?testName=ai-astar) 09:19:06 INFO - 2015-11-19 09:19:06,235 DEBUG : BROWSER_OUTPUT: RSS: Main: 139374592 09:19:06 INFO - 2015-11-19 09:19:06,235 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,237 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:19:06 INFO - 2015-11-19 09:19:06,237 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:19:06 INFO - 2015-11-19 09:19:06,237 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:19:06 INFO - 2015-11-19 09:19:06,237 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:19:06 INFO - 2015-11-19 09:19:06,237 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;106;111;109;110;124;111;110;110;110 09:19:06 INFO - 2015-11-19 09:19:06,237 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;168;135;150;165;128;152;152;127;176;125 09:19:06 INFO - 2015-11-19 09:19:06,237 DEBUG : BROWSER_OUTPUT: |2;audio-dft;168;163;161;162;162;162;163;162;168;162 09:19:06 INFO - 2015-11-19 09:19:06,238 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;82;73;74;72;148;72;73;72;73 09:19:06 INFO - 2015-11-19 09:19:06,238 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;92;136;90;91;91;130;90;92;135 09:19:06 INFO - 2015-11-19 09:19:06,238 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;110;110;110;110;110;111;110 09:19:06 INFO - 2015-11-19 09:19:06,238 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;149;146;147;146;147;146;146;147;147;146 09:19:06 INFO - 2015-11-19 09:19:06,238 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;96;97;96;96;96;96 09:19:06 INFO - 2015-11-19 09:19:06,238 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;65;64;63;64;63;65;62;63;61;63 09:19:06 INFO - 2015-11-19 09:19:06,238 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;73;71;74;70;71;69;69;69;69 09:19:06 INFO - 2015-11-19 09:19:06,238 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;86;85;86;87;86;86;87;85;86 09:19:06 INFO - 2015-11-19 09:19:06,239 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;122;120;136;127;128;125;132;119;126 09:19:06 INFO - 2015-11-19 09:19:06,239 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;149;155;155;153;155;143;155;154;161;144 09:19:06 INFO - 2015-11-19 09:19:06,239 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;69;68;68;69;67;68;68;68;67 09:19:06 INFO - 2015-11-19 09:19:06,239 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:19:06 INFO - 2015-11-19 09:19:06,239 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:19:06 INFO - 2015-11-19 09:19:06,239 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,719 09:19:06 INFO - 2015-11-19 09:19:06,239 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:19:06 INFO - 2015-11-19 09:19:06,240 DEBUG : BROWSER_OUTPUT: __startTimestamp1447953546235__endTimestamp 09:19:06 INFO - 2015-11-19 09:19:06,240 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,247 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:19:06 INFO - 2015-11-19 09:19:06,248 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:19:06 INFO - 2015-11-19 09:19:06,248 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,248 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.20 Median:110.50 stddev:4.73 (4.3%) stddev-sans-first:5.02 09:19:06 INFO - 2015-11-19 09:19:06,248 DEBUG : BROWSER_OUTPUT: Values: 111.0 106.0 111.0 109.0 110.0 124.0 111.0 110.0 110.0 110.0 09:19:06 INFO - 2015-11-19 09:19:06,248 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,248 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:147.80 Median:152.00 stddev:18.40 (12.1%) stddev-sans-first:18.01 09:19:06 INFO - 2015-11-19 09:19:06,248 DEBUG : BROWSER_OUTPUT: Values: 168.0 135.0 150.0 165.0 128.0 152.0 152.0 127.0 176.0 125.0 09:19:06 INFO - 2015-11-19 09:19:06,249 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,249 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.30 Median:162.50 stddev:2.54 (1.6%) stddev-sans-first:2.05 09:19:06 INFO - 2015-11-19 09:19:06,249 DEBUG : BROWSER_OUTPUT: Values: 168.0 163.0 161.0 162.0 162.0 162.0 163.0 162.0 168.0 162.0 09:19:06 INFO - 2015-11-19 09:19:06,249 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,249 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.10 Median:73.00 stddev:23.70 (32.5%) stddev-sans-first:24.91 09:19:06 INFO - 2015-11-19 09:19:06,249 DEBUG : BROWSER_OUTPUT: Values: 72.0 82.0 73.0 74.0 72.0 148.0 72.0 73.0 72.0 73.0 09:19:06 INFO - 2015-11-19 09:19:06,249 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,249 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.80 Median:91.00 stddev:20.68 (22.7%) stddev-sans-first:21.41 09:19:06 INFO - 2015-11-19 09:19:06,249 DEBUG : BROWSER_OUTPUT: Values: 91.0 92.0 136.0 90.0 91.0 91.0 130.0 90.0 92.0 135.0 09:19:06 INFO - 2015-11-19 09:19:06,250 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,250 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.20 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:19:06 INFO - 2015-11-19 09:19:06,250 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 110.0 110.0 110.0 110.0 110.0 111.0 110.0 09:19:06 INFO - 2015-11-19 09:19:06,250 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,250 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.95 (0.6%) stddev-sans-first:0.53 09:19:06 INFO - 2015-11-19 09:19:06,250 DEBUG : BROWSER_OUTPUT: Values: 149.0 146.0 147.0 146.0 147.0 146.0 146.0 147.0 147.0 146.0 09:19:06 INFO - 2015-11-19 09:19:06,251 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,251 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:19:06 INFO - 2015-11-19 09:19:06,251 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 96.0 97.0 96.0 96.0 96.0 96.0 09:19:06 INFO - 2015-11-19 09:19:06,251 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,251 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.50 stddev:1.25 (2.0%) stddev-sans-first:1.17 09:19:06 INFO - 2015-11-19 09:19:06,251 DEBUG : BROWSER_OUTPUT: Values: 65.0 64.0 63.0 64.0 63.0 65.0 62.0 63.0 61.0 63.0 09:19:06 INFO - 2015-11-19 09:19:06,251 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,252 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.50 stddev:1.84 (2.6%) stddev-sans-first:1.88 09:19:06 INFO - 2015-11-19 09:19:06,252 DEBUG : BROWSER_OUTPUT: Values: 69.0 73.0 71.0 74.0 70.0 71.0 69.0 69.0 69.0 69.0 09:19:06 INFO - 2015-11-19 09:19:06,252 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,252 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:86.00 stddev:1.16 (1.3%) stddev-sans-first:0.71 09:19:06 INFO - 2015-11-19 09:19:06,252 DEBUG : BROWSER_OUTPUT: Values: 83.0 86.0 85.0 86.0 87.0 86.0 86.0 87.0 85.0 86.0 09:19:06 INFO - 2015-11-19 09:19:06,252 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,252 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:126.50 stddev:5.21 (4.1%) stddev-sans-first:5.51 09:19:06 INFO - 2015-11-19 09:19:06,252 DEBUG : BROWSER_OUTPUT: Values: 125.0 122.0 120.0 136.0 127.0 128.0 125.0 132.0 119.0 126.0 09:19:06 INFO - 2015-11-19 09:19:06,252 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,253 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:155.00 stddev:5.52 (3.6%) stddev-sans-first:5.72 09:19:06 INFO - 2015-11-19 09:19:06,253 DEBUG : BROWSER_OUTPUT: Values: 149.0 155.0 155.0 153.0 155.0 143.0 155.0 154.0 161.0 144.0 09:19:06 INFO - 2015-11-19 09:19:06,253 DEBUG : BROWSER_OUTPUT: 09:19:06 INFO - 2015-11-19 09:19:06,253 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 09:19:06 INFO - 2015-11-19 09:19:06,253 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 68.0 69.0 67.0 68.0 68.0 68.0 67.0 09:19:06 INFO - 2015-11-19 09:19:06,253 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:19:06 INFO - 2015-11-19 09:19:06,253 DEBUG : BROWSER_OUTPUT: 09:19:07 INFO - 2015-11-19 09:19:07,129 INFO : Browser exited with error code: 0 09:19:07 INFO - 2015-11-19 09:19:07,182 INFO : Completed test kraken (00:01:00) 09:19:07 INFO - 2015-11-19 09:19:07,183 INFO : Starting test v8_7 09:19:07 INFO - 2015-11-19 09:19:07,183 DEBUG : operating with platform_type : linux_ 09:19:07 INFO - 2015-11-19 09:19:07,183 INFO : Initialising browser for v8_7 test... 09:19:07 INFO - 2015-11-19 09:19:07,189 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWuGrkd/profile http://localhost:52798/getInfo.html 09:19:12 INFO - 2015-11-19 09:19:12,093 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:19:12 INFO - 2015-11-19 09:19:12,094 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:19:12 INFO - 2015-11-19 09:19:12,094 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:19:12 INFO - 2015-11-19 09:19:12,094 DEBUG : BROWSER_OUTPUT: __metrics 09:19:13 INFO - 2015-11-19 09:19:13,149 INFO : Browser initialized. 09:19:13 INFO - 2015-11-19 09:19:13,150 INFO : Running cycle 1/1 for v8_7 test... 09:19:13 INFO - 2015-11-19 09:19:13,150 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWuGrkd/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:19:24 INFO - 2015-11-19 09:19:24,941 DEBUG : BROWSER_OUTPUT: RSS: Main: 141688832 09:19:24 INFO - 2015-11-19 09:19:24,941 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,349 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52798/tests/v8_7/run.html (next: http://localhost:52798/tests/v8_7/run.html) 09:19:46 INFO - 2015-11-19 09:19:46,607 DEBUG : BROWSER_OUTPUT: RSS: Main: 138231808 09:19:46 INFO - 2015-11-19 09:19:46,608 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,608 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:19:46 INFO - 2015-11-19 09:19:46,608 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:19:46 INFO - 2015-11-19 09:19:46,608 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:19:46 INFO - 2015-11-19 09:19:46,608 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:19:46 INFO - 2015-11-19 09:19:46,608 DEBUG : BROWSER_OUTPUT: |0;Richards;165.50810989738497 09:19:46 INFO - 2015-11-19 09:19:46,609 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.23671497584542 09:19:46 INFO - 2015-11-19 09:19:46,609 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.7684449489217 09:19:46 INFO - 2015-11-19 09:19:46,609 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5035.175879396985 09:19:46 INFO - 2015-11-19 09:19:46,609 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1066.0980810234541 09:19:46 INFO - 2015-11-19 09:19:46,609 DEBUG : BROWSER_OUTPUT: |5;Earley;718.9072609633357 09:19:46 INFO - 2015-11-19 09:19:46,609 DEBUG : BROWSER_OUTPUT: |6;Boyer;12240.963855421687 09:19:46 INFO - 2015-11-19 09:19:46,609 DEBUG : BROWSER_OUTPUT: |7;RegExp;25048.780487804877 09:19:46 INFO - 2015-11-19 09:19:46,609 DEBUG : BROWSER_OUTPUT: |8;Splay;642.1725239616613 09:19:46 INFO - 2015-11-19 09:19:46,610 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4845.410628019324 09:19:46 INFO - 2015-11-19 09:19:46,610 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:19:46 INFO - 2015-11-19 09:19:46,610 DEBUG : BROWSER_OUTPUT: __startTimestamp1447953586601__endTimestamp 09:19:46 INFO - 2015-11-19 09:19:46,610 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,610 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:19:46 INFO - 2015-11-19 09:19:46,610 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:19:46 INFO - 2015-11-19 09:19:46,610 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,611 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.51 Median:165.51 stddev:0.00 (0.0%) 09:19:46 INFO - 2015-11-19 09:19:46,611 DEBUG : BROWSER_OUTPUT: Values: 165.5 09:19:46 INFO - 2015-11-19 09:19:46,611 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,611 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.24 Median:193.24 stddev:0.00 (0.0%) 09:19:46 INFO - 2015-11-19 09:19:46,611 DEBUG : BROWSER_OUTPUT: Values: 193.2 09:19:46 INFO - 2015-11-19 09:19:46,611 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,611 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.77 Median:283.77 stddev:0.00 (0.0%) 09:19:46 INFO - 2015-11-19 09:19:46,612 DEBUG : BROWSER_OUTPUT: Values: 283.8 09:19:46 INFO - 2015-11-19 09:19:46,612 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,612 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5035.18 Median:5035.18 stddev:0.00 (0.0%) 09:19:46 INFO - 2015-11-19 09:19:46,612 DEBUG : BROWSER_OUTPUT: Values: 5035.2 09:19:46 INFO - 2015-11-19 09:19:46,612 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,612 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1066.10 Median:1066.10 stddev:0.00 (0.0%) 09:19:46 INFO - 2015-11-19 09:19:46,612 DEBUG : BROWSER_OUTPUT: Values: 1066.1 09:19:46 INFO - 2015-11-19 09:19:46,612 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:718.91 Median:718.91 stddev:0.00 (0.0%) 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: Values: 718.9 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12240.96 Median:12240.96 stddev:0.00 (0.0%) 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: Values: 12241.0 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25048.78 Median:25048.78 stddev:0.00 (0.0%) 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: Values: 25048.8 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:642.17 Median:642.17 stddev:0.00 (0.0%) 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: Values: 642.2 09:19:46 INFO - 2015-11-19 09:19:46,613 DEBUG : BROWSER_OUTPUT: 09:19:46 INFO - 2015-11-19 09:19:46,614 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 09:19:46 INFO - 2015-11-19 09:19:46,614 DEBUG : BROWSER_OUTPUT: Values: 4845.4 09:19:46 INFO - 2015-11-19 09:19:46,614 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:19:46 INFO - 2015-11-19 09:19:46,614 DEBUG : BROWSER_OUTPUT: 09:19:47 INFO - 2015-11-19 09:19:47,490 INFO : Browser exited with error code: 0 09:19:47 INFO - 2015-11-19 09:19:47,506 INFO : Completed test v8_7 (00:00:40) 09:19:47 INFO - 2015-11-19 09:19:47,641 INFO : Completed test suite (00:20:12) 09:19:47 INFO - 2015-11-19 09:19:47,641 DEBUG : Working with test: dromaeo_css 09:19:47 INFO - 2015-11-19 09:19:47,641 DEBUG : Generating results file: dromaeo_css 09:19:47 INFO - 2015-11-19 09:19:47,642 DEBUG : Working with test: kraken 09:19:47 INFO - 2015-11-19 09:19:47,642 DEBUG : Generating results file: kraken 09:19:47 INFO - 2015-11-19 09:19:47,643 INFO : javascript benchmark 09:19:47 INFO - 2015-11-19 09:19:47,643 DEBUG : Working with test: v8_7 09:19:47 INFO - 2015-11-19 09:19:47,643 DEBUG : Generating results file: v8_7 09:19:47 INFO - 2015-11-19 09:19:47,643 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:19:47 INFO - 2015-11-19 09:19:47,694 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 09:19:47 INFO - 2015-11-19 09:19:47,694 DEBUG : process_Request line: dromaeo_css 6619.92 graph.html#tests=[[72,1,43]] 09:19:47 INFO - 2015-11-19 09:19:47,694 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:19:47 INFO - 2015-11-19 09:19:47,722 DEBUG : process_Request line: kraken 1526.00 graph.html#tests=[[232,1,43]] 09:19:47 INFO - 2015-11-19 09:19:47,723 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:19:47 INFO - 2015-11-19 09:19:47,754 DEBUG : process_Request line: v8_7 21234.70 graph.html#tests=[[230,1,43]] 09:19:47 INFO - 2015-11-19 09:19:47,755 INFO : javascript benchmark 09:19:47 INFO - 2015-11-19 09:19:47,759 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3187.8406079696015, 3229.1927018245437, 3241.7082462578364, 3271.067745692478, 3277.3772983133204, 5162.8193013244545, 5374.543163831074, 5377.731113444327, 5406.810761623344, 5419.593530485219, 5335.306410166682, 5344.038073146832, 5356.785728570862, 5414.756335964881, 5418.485243901833, 4592.7014744041535, 4894.119058569448, 5009.6242781791325, 5015.774290156934, 5029.748512574375, 4811.374521312225, 4914.238293064581, 4956.0, 4970.751462426882, 5016.322796422491, 4833.226683730604, 4848.127337079324, 4948.331975183341, 4960.23116416956, 4966.2798894160505, 4904.313396124546, 4920.286558449005, 4929.605631549467, 4947.529984651488, 4996.925046124311, 4780.617550595944, 4861.246506791453, 4918.631102667314, 4925.975370123162, 4998.500149985009, 5073.1122053640465, 5139.203423469368, 5152.613553983429, 5169.1212493875755, 5256.290400795881, 5744.741486633112, 5746.25298711169, 5749.59752817303, 5749.965006298865, 5752.827415177551, 5662.801801936913, 5776.220210271644, 5777.133429985536, 5825.997667208304, 5892.734826932756, 5371.0, 5408.0806262935785, 5424.887897980904, 5465.262189604432, 5471.097268950618, 5768.019436695688, 5777.393373695785, 5778.624389414675, 5905.675187864623, 5950.047992321208, 5543.750531226105, 5589.74846131925, 5602.271704678447, 5608.579356548275, 5611.887762244733, 5579.246801681721, 5676.0, 5713.5714821388565, 5744.454276843693, 5763.510101641324, 5719.399463056402, 5719.571032172604, 5721.199032135504, 5741.770329186869, 5768.9711551442815, 5668.433156684381, 5670.262865827499, 5671.801486947937, 5725.770969161187, 5734.455226753452, 4279.87160385189, 4377.0, 4391.297392417262, 4464.308032255006, 4491.88770280739, 3991.1219531702977, 3995.4805875236616, 4006.1787333596544, 4007.0383108053857, 4041.454403655469, 5531.446855314436, 5535.806246781343, 5539.612227144142, 5548.750306236229, 5594.524465420403, 5511.255980442669, 5608.298962629671, 5730.111832665943, 5794.652320860761, 5860.677662728591, 5468.343798744176, 5480.177973304037, 5495.697736624444, 5511.669299842022, 5550.69471179089, 4877.731724755173, 4954.232094025432, 4955.083309587738, 4957.876053098701, 5005.924911126336, 5845.181674565564, 5850.0, 5911.527077833849, 5929.673867937218, 5972.0, 5801.883962320814, 5847.239858818326, 5851.268591426072, 5853.882922341614, 5854.795082172103, 5795.449432303924, 6008.429199226067, 6013.548983826318, 6027.095935609694, 6028.638281703112, 5622.465865742748, 5688.175214593944, 5720.74256658443, 5750.884982300414, 5795.159701843125, 5794.942050579609, 5806.854828629318, 5822.5633077518505, 5825.70871456417, 5840.357560668408, 5481.588880834033, 5596.664200148004, 5611.102223644198, 5632.042552766122, 5652.745626446902, 5406.135018397038, 5440.782368705208, 5466.3986961435, 5484.122540393518, 5614.915776263441, 5492.313460817398, 5535.8892822144135, 5551.666899985852, 5568.888622227614, 5576.470235327637, 5555.638883472561, 5577.581681373832, 5660.773569057192, 5689.743961521659, 5751.45361190703, 5477.178423236546, 5597.356304025089, 5638.2670252866865, 5644.407337229696, 5658.462446067617], "unit": "ms", "name": "dojo.html", "value": 5310.256308943245}, {"lowerIsBetter": false, "replicates": [546.890621875625, 597.5846786483395, 606.0878378041048, 611.3244864424813, 615.8922188616991, 40210.0, 40380.394294085614, 40901.70532094132, 40962.59037409625, 41162.79418602907, 39761.0, 40106.598934010726, 40166.59833401665, 41069.38395924064, 41197.38203926943, 8700.303975681945, 8862.955685221565, 8866.423682460652, 8894.0, 8914.108589141066, 8601.569921503931, 8671.566421678923, 8726.956365218164, 8760.518171500566, 8769.649214031462, 8656.61045252962, 8692.956535217316, 8753.606087726035, 8755.693550725726, 8774.254188393994, 8700.695475658353, 8784.165504277084, 8796.912030879674, 8803.33974951878, 8805.6037478313, 8632.179942905414, 8685.522296273703, 8762.904636920384, 8775.341849361259, 8786.956065219696, 39609.0, 39790.40314395286, 40071.0, 40295.59704402948, 40329.596704033174, 3199.2161920329436, 3224.6452890182063, 3229.580154579913, 3268.5587445694764, 3320.9833950830334, 29240.268993275127, 29389.0, 29439.558406623917, 29473.85263073671, 29651.55522667162, 3380.154961259685, 3449.6550344965553, 3472.982635086834, 3544.5392099027217, 3581.5344005279408, 268356.0, 268528.0, 270513.0, 271088.0, 271481.0, 41220.58779412227, 56953.430465695645, 57071.0, 57106.7144664274, 57174.714126429935, 36304.45543316852, 36765.632343676756, 36904.630953690656, 37235.62764372376, 37296.321665525116, 14521.0, 14569.198694071716, 14625.92687036558, 14669.266536673336, 14669.706605867823, 11774.587889423829, 11811.52753889852, 11843.822342664867, 11854.407279635983, 11858.525658973716, 13806.792898106336, 13896.444142234197, 14083.71832563343, 14167.64580885486, 14307.427702891768, 5771.278590176228, 5849.0, 5855.736491857877, 5876.177335173079, 5885.117232415087, 993.2451336983944, 1044.8119338519139, 1063.4363787192801, 1081.507913899174, 1123.4663534820895, 28848.42303153967, 28849.27876803055, 32941.0, 33568.83215583907, 33740.831295843855, 1029.27950434696, 1039.8024375368657, 1062.2617280989793, 1081.6268387406333, 1150.511032811052, 14676.853231467761, 14799.0, 15011.624709382135, 15124.924375378272, 15224.46714364992, 385986.0, 387265.0, 389795.0, 390650.0, 390777.0, 51970.740146299024, 52631.0, 52716.20925686118, 52930.20604690933, 52990.0, 1367.453018792471, 1371.1772936238178, 1373.5810577773875, 1374.8144000559796, 1397.7274431485762, 26872.865635671697, 28497.857510712314, 28735.425291493633, 28896.133116006553, 29054.0, 45064.49355064467, 45384.54615453803, 45621.0, 45886.77056614829, 45991.0, 295094.0, 296313.0, 299777.0, 301157.0, 301180.4940975281, 31701.20746981344, 45957.77021114873, 46174.30738538992, 46338.073238534365, 46935.0, 44398.0, 44834.10331793281, 45048.324275136554, 45379.773101134284, 45860.08279834318, 36783.63216367802, 37423.25153496969, 37547.62452375441, 37679.24641507209, 38892.80553597214, 37513.624863751014, 38039.0, 38255.617443825206, 38607.22785544329, 41805.163896721286, 36591.08522286964, 37118.072048199014, 37275.44086838754, 37453.81273093726, 37619.81190094032, 31200.0, 32246.516302255954, 32894.67105328916, 33621.0, 33642.49536256909, 25698.0, 25887.352816178995, 25976.8701156493, 25980.8700956494, 26292.21123366302], "unit": "ms", "name": "ext.html", "value": 18289.10318687353}, {"lowerIsBetter": false, "replicates": [353.55805243445695, 359.70504186567024, 361.2089523942566, 361.40548797228575, 362.2772568725392, 12029.639110826667, 12407.131500794949, 16488.505344839657, 16906.0, 17925.91037044816, 11352.262102963323, 11357.545698172064, 11454.370009649467, 11597.188196826204, 11812.82280765787, 4358.193734159175, 4485.080558485519, 4486.573775491323, 4486.910261794762, 4498.190325741365, 4527.253003254459, 4724.97637511812, 4866.31871537985, 4894.143524883149, 4894.706317620937, 4604.608608268301, 4706.505816889228, 4737.952620473786, 4817.879553011167, 4857.611391088704, 4685.83599574015, 4723.149833030053, 4743.1225223333795, 4761.928571071437, 4766.523347665223, 4188.958110418887, 4282.42187304713, 4302.41917341158, 4306.44016277885, 4311.353297005474, 12938.935305323508, 12939.094263401566, 13200.273984930922, 13581.524646637321, 13702.314884255848, 1854.0, 1934.6711059119843, 1951.2097600471832, 1988.5625162464235, 2051.107768120872, 9794.853077203848, 9808.803823923552, 9817.5582098805, 9874.111329980336, 10144.746381340452, 1647.4810434713147, 1659.585103724069, 1694.5848267174497, 1735.201807168704, 1742.8280529436431, 61394.0, 61571.0, 61690.38309616891, 61854.0, 63006.0, 6807.421369183576, 6974.163100427981, 7010.158780946217, 7014.19336776267, 7014.263502332284, 8166.265036146775, 8174.468659537111, 8191.95904020488, 8220.219079187467, 8258.0, 5485.396606373296, 5519.972400137974, 5527.143292789624, 5551.611387202938, 5551.9722401387735, 5643.294588176478, 5650.350209725934, 5656.236408084938, 5671.716414179357, 5679.0, 3214.9357012860078, 3217.5978002749657, 3226.532152837872, 3229.3379857129703, 3234.0, 1134.0247387246961, 1141.8058929981923, 1143.3540049871797, 1152.8962393384636, 1196.4017991004498, 389.4703203643043, 393.6889857012985, 399.2115571745837, 401.46605015329544, 412.99587004130177, 9792.412455252708, 10525.9473702631, 10530.63142790014, 10613.25707200504, 10658.467076646137, 408.15104582468393, 420.9284421648327, 426.76101383225495, 430.03671775223273, 432.13788491958746, 5545.889082218333, 5731.0, 5734.39788822176, 5794.449527294901, 5821.09772985188, 536386.0, 536439.0, 537189.0, 547191.0, 551392.0, 68057.0, 74357.6282118586, 74566.6271668649, 75298.24701752912, 78640.21359786329, 513.8252993982064, 528.8545649946295, 529.1348644965432, 530.376807251478, 531.7102179312207, 10023.0, 10445.0, 10455.372677639367, 10467.738306542398, 10615.628453004108, 24745.62881556742, 28481.0, 32964.835175823966, 33181.50227746634, 35964.28071438609, 43748.78125609351, 44255.77872110619, 47255.763721181174, 48448.0, 49095.50904491052, 6488.643124628191, 6591.769288074895, 6699.832504187434, 6704.128463299739, 6727.394534491919, 11803.23278986863, 11910.642680719593, 11948.223365481215, 12017.519299227934, 12103.455344509694, 10213.438260895722, 10249.282550221413, 10472.057514823398, 10592.940705929346, 10758.677239682822, 9845.113939745457, 9903.27659893535, 10024.298299118991, 10298.485075746034, 10316.690499285034, 10367.63713270032, 10627.149828013586, 10676.252662313564, 10723.839142412715, 10786.784264314512, 6692.029655699994, 6820.931790682228, 6850.794476165723, 6854.177498700188, 6855.5543889647015, 10151.238657100599, 10423.061924426534, 10491.160707143257, 10513.632022879468, 10651.840222396515], "unit": "ms", "name": "jquery.html", "value": 6673.311581135941}, {"lowerIsBetter": false, "replicates": [1453.048253394026, 1691.5432833135053, 1735.4273089880342, 1749.7987731410883, 1783.1619139004667, 6446.0, 6626.569272997252, 7497.662605182766, 7654.349380302681, 7678.539287642745, 7201.4238860891, 7385.113786345632, 7414.406847452205, 7482.850342993137, 7507.512011719248, 2241.574100920824, 2289.9656505152434, 2294.4837411582425, 2294.9541009179807, 2304.83866129371, 2291.3355127013147, 2321.558903808271, 2339.6139636959965, 2340.8127349811975, 2341.203990643181, 2231.0518029837335, 2245.752967173618, 2269.500709843832, 2274.3290729234914, 2278.031836469502, 2237.742659594151, 2259.4238469190414, 2274.590573696734, 2289.198780426854, 2293.850899691523, 2301.9526115617355, 2324.4886125052462, 2336.1122773346187, 2342.3909783456425, 2355.787979081891, 7441.888371674429, 7488.3260506554125, 7508.624568771595, 7518.887216691754, 7545.924540754577, 4450.043240703232, 4505.797239124215, 4574.702644328108, 4599.425071866017, 4615.446146462413, 3128.7809853310278, 3138.0, 3140.4975203967256, 3148.6693897140926, 3156.589643346373, 1005.7938122684832, 1011.858339832424, 1023.5854478936043, 1031.7884833609091, 1073.7154654016692, 10107.595696172144, 10121.0, 10200.13298869597, 10230.795384092351, 10233.385996840214, 4181.560936101726, 4188.120494696087, 4258.318669012943, 4277.721948073366, 4305.225059489259, 9023.909760902437, 9031.187193152648, 9050.4117232381, 9054.502002389932, 9058.456492610465, 4657.5575320344515, 4665.0, 4705.12955103307, 4723.7638118093955, 4731.053789242165, 4255.76593287366, 4291.184674911757, 4297.957020429818, 4306.784660766949, 4316.201502722007, 3548.929021419557, 3570.8750193743613, 3572.392693242103, 3582.802945837952, 3627.0025742920907, 1130.6042884990188, 1143.5254369436757, 1146.529922731676, 1148.9827652585218, 1178.7760325538293, 1018.9388636681823, 1045.0490054050777, 1052.6210564196883, 1204.27141579345, 1369.1784929042694, 6933.3066693331275, 7075.115610548682, 7083.972823940499, 7088.539244949062, 7098.822529436806, 460.29344955493156, 462.9027904140101, 465.9836905708284, 468.14797069333486, 469.5984932882405, 5465.781368745285, 5512.310961129859, 5535.806246781343, 5577.721113944286, 5691.630044047124, 8834.072422395684, 8850.513221772735, 8922.464652120894, 8929.10708929102, 9073.092690731006, 8305.044919834176, 8741.737747867446, 8787.340949428819, 8839.116088391109, 8891.155340242667, 7051.753188638476, 7054.576725396492, 7084.716611335592, 7109.715611375591, 7151.53515021522, 6889.965550172217, 6919.792406227822, 6992.720291188295, 7015.859682806214, 7021.719131234693, 7629.427792915664, 7813.41399395059, 7835.05979282467, 7892.0924093729955, 7898.565578893216, 7962.203779621991, 8007.919920800718, 8059.194080592129, 8132.105468398353, 8190.83618327642, 6623.337666233531, 6657.73369065232, 6669.933300666931, 6670.232923213893, 6689.732410703712, 7249.202587715453, 7254.45591580623, 7340.339369456775, 7342.5227360221415, 7356.485046046725, 6745.730170793114, 6862.794116176523, 6870.656467176521, 6918.481113916376, 6943.444524438134, 6544.901826472702, 6573.638449885302, 6621.370969757865, 6626.734930602722, 6686.531942764154, 6830.351116644109, 6838.213605435439, 6847.452203823782, 6852.931470685229, 6867.0, 6252.905741495311, 6274.372562743689, 6317.747290108344, 6320.209973753281, 6327.90508142369, 5951.315598706205, 5952.464278214981, 6030.879382412415, 6050.818475445743, 6081.087836824335], "unit": "ms", "name": "mootools.html", "value": 4290.421627095327}, {"lowerIsBetter": false, "replicates": [315.142811552577, 316.1006935269159, 321.1809884793775, 323.9271163988103, 324.67045948362403, 6218.502519798417, 6295.244570651528, 6387.936120638792, 6395.2325720913495, 6466.708998095085, 6194.7212375443105, 6292.961661325887, 6326.525510586702, 6328.3671632836695, 6336.017917222826, 4557.931631025537, 4681.74250416227, 4704.247320428732, 4708.788104535295, 4720.480747117815, 4657.976710116462, 4681.227597446417, 4696.107739529495, 4704.976475117637, 4708.7645617719145, 4618.937644341803, 4633.09654617348, 4648.697834640738, 4659.254519276917, 4670.439547254335, 4673.556012178855, 4674.509176536466, 4676.7193968361835, 4677.134730074913, 4680.7191568506, 3898.8245528951265, 3997.920041599152, 4011.0574015106426, 4021.034951611621, 4022.3363145081025, 5927.932972065027, 5930.55520835939, 5935.080062590306, 5999.13012613173, 6006.429389208019, 5432.91850622241, 5436.048691478975, 5442.2380866678695, 5445.455454454563, 5451.04606693827, 6625.436837868739, 6631.568948018364, 6632.43624291931, 6633.2703402625675, 6641.966790166019, 4304.870853874389, 4391.780410979471, 4396.20868243716, 4424.7123936944, 4440.000999775057, 6861.210960739479, 6897.068895699117, 6936.687849046754, 6939.757108501179, 6956.060931774196, 4494.685372023992, 4496.662750293741, 4506.571875671806, 4508.18852606534, 4519.864404067883, 4488.304312831517, 4573.885652858638, 4576.061907308994, 4579.6794224403975, 4581.037982023746, 5403.378611459654, 5408.242846001563, 5409.323834520685, 5412.91880621791, 5424.213489044067, 6732.057511948331, 6732.562383445158, 6743.662816859138, 6754.797356079325, 6755.49333799967, 1611.8629916457232, 1628.0557276779439, 1629.7310943694395, 1630.1767607358208, 1631.3963833381724, 1050.632278702448, 1052.8315469524991, 1053.0206907576028, 1061.9150467962545, 1080.821664425361, 6465.806025819233, 6476.190476190476, 6477.449416799531, 6517.478601711851, 6595.340465953463, 6994.860102797916, 7019.0875186226485, 7038.120234970629, 7048.259932706992, 7060.0, 6542.607443553402, 6546.279909209983, 6547.1161393211305, 6551.868962620721, 6585.539012269095, 3750.868719594801, 3784.2053168834304, 3821.9235615288094, 3822.560405553397, 3824.101336185994, 7248.927510724825, 7270.163931147986, 7284.417246620258, 7296.051513303237, 7326.816829579197, 7167.534110282815, 7172.426205903515, 7184.137903451619, 7207.783766487014, 7222.169450513258, 7131.857362852714, 7279.672414741243, 7293.124825021137, 7296.525725827911, 7326.157491888501, 6984.860302794017, 6986.336298051677, 6988.4059854910865, 6999.860002800017, 7001.194862590867, 6903.1716194055025, 6941.895871561829, 6957.130358705162, 6961.756338528127, 6962.338577835098, 7154.785356439315, 7287.526310789782, 7309.086364204474, 7325.706971721072, 7326.3406293433845, 4533.342665313577, 4568.0407114505, 4579.908401832011, 4584.15193189261, 4584.862454126382, 6936.826579335557, 6989.965050174716, 7029.156501219886, 7030.2969703029285, 7038.225795162426, 6645.800625981228, 6751.628660423724, 6753.3246675332075, 6753.459723222229, 6808.251092379735, 6754.2570317266045, 6760.966195168992, 6761.695723692544, 6768.390844823989, 6775.390214880685, 6527.0, 6589.0775291460195, 6639.203295604559, 6644.302348253364, 6653.933460665331, 6584.242812076673, 6703.363180497845, 6774.119364482585, 6789.0, 6834.487413443912, 6548.869022619616, 6565.934340656723, 6568.9014664781025, 6616.66916654175, 6633.170853643294], "unit": "ms", "name": "prototype.html", "value": 4990.075659747474}, {"lowerIsBetter": false, "replicates": [733.9082614673166, 814.9470284431511, 848.3298194426402, 864.0279685353977, 869.8434281829276, 13645.727085458286, 13717.588472345833, 13899.235542045184, 13947.581572552826, 13960.371783269751, 13230.27233502157, 13264.0, 13268.33658317085, 13301.0, 13310.667233319173, 789.6999140326668, 807.3944541593804, 809.4576633655436, 809.522381794741, 811.1685522339619, 795.5743677132735, 803.1727320859524, 805.5086397297644, 806.4838503357827, 806.8587997100514, 852.2925971443718, 852.6078004118082, 852.8336974290016, 853.3173461231021, 853.3343991686495, 840.3319361107934, 841.8190089130836, 841.9915800842005, 842.7935155886817, 848.2577744473585, 612.5038718637918, 616.3897741236188, 621.139721485741, 622.9688515574248, 624.2072567838865, 13440.0, 13456.058075934689, 13493.730125397535, 13557.796633050511, 13590.456381744718, 10065.748356291078, 10096.394216347042, 10160.59357625694, 10388.428636424993, 10526.684199474028, 9184.908150918473, 9238.149971265073, 9300.8604870927, 9383.296252781069, 9444.150026497648, 3110.377924415126, 3114.2986366867044, 3116.579261799651, 3159.510275907238, 3223.3069889973767, 44594.33108503375, 45184.322235166495, 45213.77393113046, 45229.54770452286, 45365.0, 7232.92767072333, 7293.416526677853, 7315.268473152642, 7337.003824671852, 7449.366803821736, 11904.583339583247, 11912.821307680391, 11949.163558550817, 11959.40202989847, 11976.0, 6031.758729650853, 6043.425874541911, 6110.511159107261, 6243.531735119885, 6299.622022678563, 3819.0834199792407, 3987.5813039631003, 4054.3107671695884, 4061.1268577256033, 4117.155983023473, 2668.21287720123, 2671.1852884869913, 2673.625692403065, 2683.141394753699, 2692.111603170949, 412.9050318426779, 421.6710965446956, 421.6879509163197, 426.28597099857615, 427.6749670250632, 447.1638036871007, 528.4292963599304, 572.7656899381839, 645.8837409266283, 663.0650782396893, 8642.30861531076, 8814.955925220462, 8926.152015558511, 8931.240844528287, 9079.977447248004, 696.3976160621027, 708.373089815517, 711.6904146696204, 714.370995343653, 718.9568625882464, 3920.4707364506, 3966.0, 4375.212461756851, 4472.1502914446155, 4567.1550763108935, 56276.0, 56385.43614563802, 56396.71801641048, 56985.715071424376, 57484.425155748744, 50712.49287507078, 51272.74363628233, 51643.64316319427, 52182.21726674103, 53714.731426342616, 50050.24924626134, 50572.747136264086, 50808.49191508112, 50866.491335086175, 51080.74459627678, 8827.117288271122, 9058.230050445785, 9309.0, 9380.822135014192, 9411.152996230372, 34939.30121397608, 35423.0, 35853.0, 35935.82032089823, 36655.08362290964, 40950.59049409587, 43720.78139609282, 44972.10055798931, 44984.32523512084, 45412.77293613643, 10263.819660739111, 10782.838257425989, 10878.40168790724, 11186.440677966231, 11615.477303521193, 12059.75880482403, 12195.817062743889, 12227.266364018187, 12731.363431828186, 12985.155964862253, 11462.484188211383, 11650.941745291218, 11839.881601183877, 12162.087843411957, 12206.816897746363, 11693.590724324607, 11926.940365298118, 12035.879641203475, 12279.324637145042, 12407.44166512491, 11587.768244634892, 11759.294442333487, 11900.0, 11924.284542927453, 11958.46186921608, 9958.15355694774, 10117.797644047225, 10246.282760206714, 10263.897361026295, 10301.896981030093, 9161.175494205554, 9659.565319560495, 10211.948940255252, 10217.33587316822, 10421.270511064153], "unit": "ms", "name": "yui.html", "value": 6064.903480808541}], "name": "dromaeo_css", "value": 6619.921740996172}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 106.0, 111.0, 109.0, 110.0, 124.0, 111.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 111.2}, {"lowerIsBetter": true, "replicates": [168.0, 135.0, 150.0, 165.0, 128.0, 152.0, 152.0, 127.0, 176.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 147.8}, {"lowerIsBetter": true, "replicates": [168.0, 163.0, 161.0, 162.0, 162.0, 162.0, 163.0, 162.0, 168.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 163.3}, {"lowerIsBetter": true, "replicates": [72.0, 82.0, 73.0, 74.0, 72.0, 148.0, 72.0, 73.0, 72.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 81.1}, {"lowerIsBetter": true, "replicates": [91.0, 92.0, 136.0, 90.0, 91.0, 91.0, 130.0, 90.0, 92.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 103.8}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.2}, {"lowerIsBetter": true, "replicates": [149.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [65.0, 64.0, 63.0, 64.0, 63.0, 65.0, 62.0, 63.0, 61.0, 63.0], "unit": "ms", "name": "json-parse-financial", "value": 63.3}, {"lowerIsBetter": true, "replicates": [69.0, 73.0, 71.0, 74.0, 70.0, 71.0, 69.0, 69.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.4}, {"lowerIsBetter": true, "replicates": [83.0, 86.0, 85.0, 86.0, 87.0, 86.0, 86.0, 87.0, 85.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.7}, {"lowerIsBetter": true, "replicates": [125.0, 122.0, 120.0, 136.0, 127.0, 128.0, 125.0, 132.0, 119.0, 126.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 126.0}, {"lowerIsBetter": true, "replicates": [149.0, 155.0, 155.0, 153.0, 155.0, 143.0, 155.0, 154.0, 161.0, 144.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 152.4}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 68.0, 69.0, 67.0, 68.0, 68.0, 68.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 68.0}], "name": "kraken", "value": 1526.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.50810989738497], "unit": "score", "name": "Richards", "value": 213.294684}, {"lowerIsBetter": false, "replicates": [193.23671497584542], "unit": "score", "name": "DeltaBlue", "value": 342.16064999999986}, {"lowerIsBetter": false, "replicates": [283.7684449489217], "unit": "score", "name": "Encrypt", "value": 938.0218440000002}, {"lowerIsBetter": false, "replicates": [5035.175879396985], "unit": "score", "name": "Decrypt", "value": 52.864290419161705}, {"lowerIsBetter": false, "replicates": [1066.0980810234541], "unit": "score", "name": "RayTrace", "value": 694.109682}, {"lowerIsBetter": false, "replicates": [718.9072609633357], "unit": "score", "name": "Earley", "value": 927.0500329999999}, {"lowerIsBetter": false, "replicates": [12240.963855421687], "unit": "score", "name": "Boyer", "value": 54.4453041338583}, {"lowerIsBetter": false, "replicates": [25048.780487804877], "unit": "score", "name": "RegExp", "value": 36.368437195715686}, {"lowerIsBetter": false, "replicates": [642.1725239616613], "unit": "score", "name": "Splay", "value": 126.89892039800993}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 21234.7021872948}]} 09:19:47 INFO - RETURN: dromaeo_css: 6619.92 09:19:47 INFO - RETURN: kraken: 1526.00 09:19:47 INFO - RETURN: v8_7: 21234.70 09:19:47 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "21234.70"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1526.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6619.92"}}} 09:19:47 INFO - Return code: 0 09:19:47 INFO - # TBPL SUCCESS # 09:19:47 INFO - Running post-action listener: _resource_record_post_action 09:19:47 INFO - Running post-run listener: _resource_record_post_run 09:19:48 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 4096; Write bytes: 512344064; Read time: 284; Write time: 615380 09:19:48 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 78430208; Read time: 0; Write time: 90560 09:19:48 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 4096; Write bytes: 351039488; Read time: 284; Write time: 422772 09:19:48 INFO - Running post-run listener: _upload_blobber_files 09:19:48 INFO - Blob upload gear active. 09:19:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:19:48 INFO - Copying logs to upload dir... 09:19:48 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1255.788688 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2015-11-19 09:19:48.535411) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 09:19:48.539351) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009929 build_url: 'https://queue.taskcluster.net/v1/task/gnIvwFcKT1SsSyF9VrugQA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 09:19:48.591481) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:19:48.591853) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1447952331.352351-1521520377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:19:48.641622) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 09:19:48.641967) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 09:19:48.642258) ========= ========= Total master_lag: 0.21 =========